[INFO] fetching crate cradle 0.0.17...
[INFO] testing cradle-0.0.17 against 1.54.0 for beta-1.55-1
[INFO] extracting crate cradle 0.0.17 into /workspace/builds/worker-54/source
[INFO] validating manifest of crates.io crate cradle 0.0.17 on toolchain 1.54.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.54.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate cradle 0.0.17
[INFO] finished tweaking crates.io crate cradle 0.0.17
[INFO] tweaked toml for crates.io crate cradle 0.0.17 written to /workspace/builds/worker-54/source/Cargo.toml
[INFO] crate crates.io crate cradle 0.0.17 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.54.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-54/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-54/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+1.54.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] c9aaf3d3abc5cdbf5d1cc7036962419c6e780c1648f509288d7b599772672a83
[INFO] running `Command { std: "docker" "start" "-a" "c9aaf3d3abc5cdbf5d1cc7036962419c6e780c1648f509288d7b599772672a83", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c9aaf3d3abc5cdbf5d1cc7036962419c6e780c1648f509288d7b599772672a83", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c9aaf3d3abc5cdbf5d1cc7036962419c6e780c1648f509288d7b599772672a83", kill_on_drop: false }`
[INFO] [stdout] c9aaf3d3abc5cdbf5d1cc7036962419c6e780c1648f509288d7b599772672a83
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-54/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-54/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+1.54.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9a6238135c900a8f9249f60384f37f48ce83ff6ad27b831a4fdfd76d54f6d91f
[INFO] running `Command { std: "docker" "start" "-a" "9a6238135c900a8f9249f60384f37f48ce83ff6ad27b831a4fdfd76d54f6d91f", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling rustversion v1.0.5
[INFO] [stderr]    Compiling cradle v0.0.17 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.84s
[INFO] running `Command { std: "docker" "inspect" "9a6238135c900a8f9249f60384f37f48ce83ff6ad27b831a4fdfd76d54f6d91f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9a6238135c900a8f9249f60384f37f48ce83ff6ad27b831a4fdfd76d54f6d91f", kill_on_drop: false }`
[INFO] [stdout] 9a6238135c900a8f9249f60384f37f48ce83ff6ad27b831a4fdfd76d54f6d91f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-54/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-54/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+1.54.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] db0047a4cfee905945aec6c9ca5e0234b7cfa7847dae2aea9c67cdce44664212
[INFO] running `Command { std: "docker" "start" "-a" "db0047a4cfee905945aec6c9ca5e0234b7cfa7847dae2aea9c67cdce44664212", kill_on_drop: false }`
[INFO] [stderr]    Compiling ppv-lite86 v0.2.10
[INFO] [stderr]    Compiling remove_dir_all v0.5.3
[INFO] [stderr]    Compiling ansi_term v0.12.1
[INFO] [stderr]    Compiling diff v0.1.12
[INFO] [stderr]    Compiling executable-path v1.0.0
[INFO] [stderr]    Compiling getrandom v0.2.3
[INFO] [stderr]    Compiling rand_core v0.6.3
[INFO] [stderr]    Compiling pretty_assertions v0.7.2
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.4
[INFO] [stderr]    Compiling tempfile v3.2.0
[INFO] [stderr]    Compiling cradle v0.0.17 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 4.59s
[INFO] running `Command { std: "docker" "inspect" "db0047a4cfee905945aec6c9ca5e0234b7cfa7847dae2aea9c67cdce44664212", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "db0047a4cfee905945aec6c9ca5e0234b7cfa7847dae2aea9c67cdce44664212", kill_on_drop: false }`
[INFO] [stdout] db0047a4cfee905945aec6c9ca5e0234b7cfa7847dae2aea9c67cdce44664212
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-54/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-54/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+1.54.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 5b0201f464452a8a1b9d2ad69ded35c37570acf75be665984013c8fcb2a339ef
[INFO] running `Command { std: "docker" "start" "-a" "5b0201f464452a8a1b9d2ad69ded35c37570acf75be665984013c8fcb2a339ef", kill_on_drop: false }`
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.03s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/cradle-86051ce6a18cb346)
[INFO] [stdout] 
[INFO] [stdout] running 128 tests
[INFO] [stderr] + echo foo
[INFO] [stderr] + cargo build --bin test_executables_helper --features test_executables
[INFO] [stdout] foo
[INFO] [stdout] foo
[INFO] [stdout] foo
[INFO] [stdout] foo
[INFO] [stdout] foo
[INFO] [stdout] test error::tests::invalid_utf8_to_stdout_has_source ... ok
[INFO] [stdout] test error::tests::invalid_utf8_to_stderr_has_source ... ok
[INFO] [stdout] test tests::bool_output::failure_exit_status_is_false ... ok
[INFO] [stdout] test tests::allows_to_execute_a_command ... ok
[INFO] [stdout] test tests::arguments_can_be_given_as_references ... ok
[INFO] [stdout] test tests::allows_to_retrieve_stdout ... ok
[INFO] [stdout] test tests::bool_output::success_exit_status_is_true ... ok
[INFO] [stdout] test tests::capturing_stdout::trimmed::trims_trailing_whitespace ... ok
[INFO] [stdout] test tests::capturing_stdout::untrimmed::does_not_relay_stdout ... ok
[INFO] [stdout] test tests::capturing_stdout::trimmed::does_not_modify_output_without_whitespace ... ok
[INFO] [stdout] test tests::capturing_stdout::untrimmed::does_not_trim_trailing_newline ... ok
[INFO] [stdout] test tests::capturing_stdout::trimmed::does_not_relay_stdout ... ok
[INFO] [stdout] test tests::capturing_stdout::trimmed::trims_leading_whitespace ... ok
[INFO] [stdout] test tests::capturing_stdout::untrimmed::does_not_trim_leading_whitespace ... ok
[INFO] [stdout] test tests::command_and_argument_as_separate_ref_str ... ok
[INFO] [stdout] test tests::capturing_stdout::trimmed::does_not_remove_whitespace_within_output ... ok
[INFO] [stdout] test tests::current_dir::sets_the_working_directory ... ok
[INFO] [stdout] test tests::errors::result_types::combine_err_with_other_outputs ... ok
[INFO] [stdout] test tests::errors::result_types::no_executable ... ok
[INFO] [stdout] test tests::errors::result_types::missing_executable_file_error_message ... ok
[INFO] [stdout] test tests::errors::result_types::missing_executable_file_error_can_be_matched_against ... ok
[INFO] [stdout] test tests::errors::result_types::no_errors ... ok
[INFO] [stdout] test tests::errors::result_types::missing_executable_file_error_can_be_caused_by_relative_paths ... ok
[INFO] [stdout] test tests::errors::result_types::combine_ok_with_other_outputs ... ok
[INFO] [stdout] test tests::errors::result_types::includes_full_command_on_non_zero_exit_codes ... ok
[INFO] [stdout] test tests::bool_output::io_error_panics - should panic ... ok
[INFO] [stdout] test tests::errors::panics_by_default::includes_full_command_on_non_zero_exit_codes - should panic ... ok
[INFO] [stdout] test tests::errors::panics_by_default::executable_cannot_be_found - should panic ... ok
[INFO] [stdout] test tests::errors::result_types::non_zero_exit_codes ... ok
[INFO] [stdout] test tests::exit_status::zero ... ok
[INFO] [stdout] test tests::log_commands::quotes_arguments_with_spaces ... ok
[INFO] [stdout] test tests::multiple_arguments_as_ref_str ... ok
[INFO] [stdout] test tests::errors::panics_by_default::no_executable - should panic ... ok
[INFO] [stdout] test tests::errors::panics_by_default::combine_panics_with_other_outputs - should panic ... ok
[INFO] [stdout] test tests::errors::panics_by_default::includes_full_command_on_io_errors - should panic ... ok
[INFO] [stdout] test tests::errors::panics_by_default::non_zero_exit_codes - should panic ... ok
[INFO] [stdout] test tests::log_commands::quotes_empty_arguments ... ok
[INFO] [stdout] test tests::os_strings::works_for_os_str ... ok
[INFO] [stdout] test tests::log_commands::logs_commands_with_arguments ... ok
[INFO] [stdout] test tests::log_commands::arguments_with_invalid_utf8_will_be_logged_with_lossy_conversion ... ok
[INFO] [stdout] test tests::exit_status::failing_commands_return_oks_when_exit_status_is_captured ... ok
[INFO] [stdout] test tests::log_commands::logs_simple_commands ... ok
[INFO] [stdout] test tests::exit_status::one ... ok
[INFO] [stdout] test tests::os_strings::works_for_os_string ... ok
[INFO] [stdout] test tests::current_dir::works_for_other_types ... ok
[INFO] [stdout] test tests::run_interface::allows_to_run_commands_with_dot_run ... ok
[INFO] [stdout] test tests::split::percent_sign::for_first_of_multiple_arguments ... ok
[INFO] [stdout] test tests::run_interface::works_for_different_output_types ... ok
[INFO] [stdout] test tests::split::percent_sign::in_cmd_result ... ok
[INFO] [stdout] test tests::run_interface::allows_to_bundle_arguments_up_in_tuples ... ok
[INFO] [stdout] test tests::sequences::arrays_as_arguments ... ok
[INFO] [stdout] test tests::sequences::slices_as_arguments ... ok
[INFO] [stdout] test tests::sequences::array_refs_as_arguments ... ok
[INFO] [stdout] test tests::sequences::arrays_of_non_strings ... ok
[INFO] [stdout] test tests::split::splits_owned_strings ... ok
[INFO] [stdout] test tests::split::percent_sign::works_for_later_arguments ... ok
[INFO] [stdout] test tests::invocation_syntax::trailing_comma_is_accepted_after_normal_argument ... ok
[INFO] [stdout] test tests::split::percent_sign::splits_words ... ok
[INFO] [stdout] test tests::sequences::vector_of_non_strings ... ok
[INFO] [stdout] test tests::sequences::vector_of_vectors ... ok
[INFO] [stdout] test tests::split::percent_sign::in_cmd_unit ... ok
[INFO] [stdout] test tests::run_interface::run_result ... ok
[INFO] [stdout] test tests::split::percent_sign::non_literals ... ok
[INFO] [stdout] test tests::sequences::slices_of_non_strings ... ok
[INFO] [stdout] test tests::sequences::allows_to_pass_in_arguments_as_a_vec_of_ref_str ... ok
[INFO] [stdout] test tests::split::skips_multiple_whitespace_characters ... ok
[INFO] [stdout] test tests::invocation_syntax::trailing_comma_is_accepted_after_split_argument ... ok
[INFO] [stdout] test tests::split::trims_leading_whitespace ... ok
[INFO] [stdout] test tests::split::splits_words_by_whitespace ... ok
[INFO] [stdout] test tests::split::trims_trailing_whitespace ... ok
[INFO] [stdout] test tests::splitting_with_library_functions::allow_to_use_split ... ok
[INFO] [stdout] test tests::splitting_with_library_functions::split_ascii_whitespace ... ok
[INFO] [stdout] test tests::splitting_with_library_functions::split_whitespace ... ok
[INFO] [stdout] test tests::errors::result_types::includes_full_command_on_io_errors ... ok
[INFO] [stdout] test tests::stdout::does_not_relay_stdout_when_collecting_into_result_of_string ... ok
[INFO] [stdout] test tests::stdout::does_not_relay_stdout_when_collecting_into_string ... ok
[INFO] [stdout] test tests::stdout::relays_stdout_by_default ... ok
[INFO] [stdout] test tests::stdin::writing_too_many_bytes_into_a_non_reading_child_may_error ... ok
[INFO] [stdout] test tests::strings::multiple_strings ... ok
[INFO] [stdout] test tests::tuple_inputs::two_tuple ... ok
[INFO] [stdout] test tests::tuple_inputs::nested_tuples ... ok
[INFO] [stdout] test tests::strings::works_for_string ... ok
[INFO] [stdout] test tests::tuple_inputs::three_tuples ... ok
[INFO] [stdout] test tests::strings::mix_ref_str_and_string ... ok
[INFO] [stdout] test tests::tuple_outputs::three_tuples ... ok
[INFO] [stdout] test tests::tuple_inputs::unit_input ... ok
[INFO] [stdout] test tests::tuple_outputs::result_of_tuple_when_erroring ... ok
[INFO] [stdout] test tests::paths::path_buf_as_executable ... ok
[INFO] [stdout] test tests::tuple_outputs::result_of_tuple ... ok
[INFO] [stdout] test tests::paths::path_buf_as_argument ... ok
[INFO] [stdout] test tests::paths::ref_path_as_argument ... ok
[INFO] [stdout] test tests::paths::ref_path_as_executable ... ok
[INFO] [stdout] test tests::run_interface::run_unit ... ok
[INFO] [stdout] test tests::sequences::elements_in_array_refs_are_not_split_by_whitespace ... ok
[INFO] [stdout] test tests::sequences::elements_in_arrays_are_not_split_by_whitespace ... ok
[INFO] [stderr]    Compiling libc v0.2.98
[INFO] [stdout] test tests::sequences::elements_in_slices_are_not_split_by_whitespace ... ok
[INFO] [stderr]    Compiling memoffset v0.6.4
[INFO] [stderr]    Compiling getrandom v0.2.3
[INFO] [stderr]    Compiling nix v0.21.0
[INFO] [stderr]    Compiling rand_core v0.6.3
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.4
[INFO] [stderr]    Compiling tempfile v3.2.0
[INFO] [stderr]    Compiling gag v0.1.10
[INFO] [stderr]    Compiling cradle v0.0.17 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 9.40s
[INFO] [stdout] test tests::environment_variables::child_processes_inherit_the_environment ... ok
[INFO] [stderr] + cargo build --bin test_executables_panic --features test_executables
[ERROR] error running command: stream did not contain valid UTF-8
[INFO] running `Command { std: "docker" "inspect" "5b0201f464452a8a1b9d2ad69ded35c37570acf75be665984013c8fcb2a339ef", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5b0201f464452a8a1b9d2ad69ded35c37570acf75be665984013c8fcb2a339ef", kill_on_drop: false }`
[INFO] [stdout] 5b0201f464452a8a1b9d2ad69ded35c37570acf75be665984013c8fcb2a339ef
