[INFO] fetching crate cradle 0.0.9...
[INFO] testing cradle-0.0.9 against try#ecabaf78506b7a4668d42dc20268c086b93f0fad for pr-87041
[INFO] extracting crate cradle 0.0.9 into /workspace/builds/worker-8/source
[INFO] validating manifest of crates.io crate cradle 0.0.9 on toolchain ecabaf78506b7a4668d42dc20268c086b93f0fad
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate cradle 0.0.9
[INFO] finished tweaking crates.io crate cradle 0.0.9
[INFO] tweaked toml for crates.io crate cradle 0.0.9 written to /workspace/builds/worker-8/source/Cargo.toml
[INFO] crate crates.io crate cradle 0.0.9 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded executable-path v1.0.0
[INFO] [stderr]   Downloaded ansi_term v0.12.1
[INFO] [stderr]   Downloaded nix v0.21.0
[INFO] [stderr]   Downloaded pretty_assertions v0.7.2
[INFO] [stderr]   Downloaded diff v0.1.12
[INFO] [stderr]   Downloaded gag v0.1.10
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 8fa46db20aba4f305cc5063c9634eba61082a868533540cc281d6d0c23844c37
[INFO] running `Command { std: "docker" "start" "-a" "8fa46db20aba4f305cc5063c9634eba61082a868533540cc281d6d0c23844c37", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "8fa46db20aba4f305cc5063c9634eba61082a868533540cc281d6d0c23844c37", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8fa46db20aba4f305cc5063c9634eba61082a868533540cc281d6d0c23844c37", kill_on_drop: false }`
[INFO] [stdout] 8fa46db20aba4f305cc5063c9634eba61082a868533540cc281d6d0c23844c37
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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=forbid" "-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" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f4d253de0e693db210d5c785504f14ba83a2a097e1c6bb9f79f5af1eea1751e5
[INFO] running `Command { std: "docker" "start" "-a" "f4d253de0e693db210d5c785504f14ba83a2a097e1c6bb9f79f5af1eea1751e5", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustversion v1.0.5
[INFO] [stderr]    Compiling cradle v0.0.9 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 2.44s
[INFO] running `Command { std: "docker" "inspect" "f4d253de0e693db210d5c785504f14ba83a2a097e1c6bb9f79f5af1eea1751e5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f4d253de0e693db210d5c785504f14ba83a2a097e1c6bb9f79f5af1eea1751e5", kill_on_drop: false }`
[INFO] [stdout] f4d253de0e693db210d5c785504f14ba83a2a097e1c6bb9f79f5af1eea1751e5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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=forbid" "-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" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e2963c328861d56692ebf6bf2397f9db83ddfeae4a49c5a98563349d2994d8e0
[INFO] running `Command { std: "docker" "start" "-a" "e2963c328861d56692ebf6bf2397f9db83ddfeae4a49c5a98563349d2994d8e0", kill_on_drop: false }`
[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.9 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 4.18s
[INFO] running `Command { std: "docker" "inspect" "e2963c328861d56692ebf6bf2397f9db83ddfeae4a49c5a98563349d2994d8e0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e2963c328861d56692ebf6bf2397f9db83ddfeae4a49c5a98563349d2994d8e0", kill_on_drop: false }`
[INFO] [stdout] e2963c328861d56692ebf6bf2397f9db83ddfeae4a49c5a98563349d2994d8e0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/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=forbid" "-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" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] c0ad8dd72ac863a2349c4dbf025de024240f47a3c7f037a3766c78dc5b0258a3
[INFO] running `Command { std: "docker" "start" "-a" "c0ad8dd72ac863a2349c4dbf025de024240f47a3c7f037a3766c78dc5b0258a3", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1.20s
[INFO] [stdout] running 106 tests
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/cradle-ebfc72a62e739c32)
[INFO] [stderr] + echo foo
[INFO] [stdout] test error::tests::invalid_utf8_to_stderr_has_source ... ok
[INFO] [stdout] test tests::errors::result_types::no_executable ... ok
[INFO] [stdout] test tests::command_and_argument_as_separate_ref_str ... ok
[INFO] [stdout] test tests::errors::result_types::non_zero_exit_codes ... ok
[INFO] [stdout] test tests::capturing_stdout::untrimmed::does_not_trim_trailing_newline ... ok
[INFO] [stdout] test tests::capturing_stdout::untrimmed::does_not_trim_leading_whitespace ... ok
[INFO] [stdout] test tests::exit_status::failing_commands_return_oks_when_exit_status_is_captured ... ok
[INFO] [stdout] test tests::capturing_stdout::trimmed::does_not_modify_output_without_whitespace ... ok
[INFO] [stdout] foo
[INFO] [stdout] test tests::capturing_stdout::trimmed::does_not_relay_stdout ... ok
[INFO] [stdout] foo
[INFO] [stdout] test tests::arguments_can_be_given_as_references ... ok
[INFO] [stdout] test error::tests::invalid_utf8_to_stdout_has_source ... ok
[INFO] [stdout] test tests::exit_status::one ... ok
[INFO] [stdout] test tests::capturing_stdout::trimmed::trims_trailing_whitespace ... ok
[INFO] [stdout] test tests::capturing_stdout::trimmed::does_not_remove_whitespace_within_output ... ok
[INFO] [stdout] test tests::capturing_stdout::untrimmed::does_not_relay_stdout ... ok
[INFO] [stdout] test tests::errors::result_types::combine_ok_with_other_outputs ... ok
[INFO] [stdout] foo
[INFO] [stdout] foo
[INFO] [stdout] test tests::allows_to_retrieve_stdout ... ok
[INFO] [stdout] test tests::current_dir::sets_the_working_directory ... ok
[INFO] [stdout] test tests::capturing_stdout::trimmed::trims_leading_whitespace ... ok
[INFO] [stdout] test tests::errors::result_types::executable_cannot_be_found ... ok
[INFO] [stdout] test tests::errors::result_types::includes_full_command_on_non_zero_exit_codes ... ok
[INFO] [stdout] test tests::invocation_syntax::trailing_comma_is_accepted_after_split_argument ... ok
[INFO] [stdout] test tests::errors::result_types::includes_full_command_on_missing_executables ... ok
[INFO] [stdout] test tests::errors::result_types::combine_err_with_other_outputs ... ok
[INFO] [stdout] test tests::exit_status::zero ... ok
[INFO] [stdout] test tests::errors::result_types::no_errors ... ok
[INFO] [stdout] test tests::log_commands::logs_commands_with_arguments ... ok
[INFO] [stdout] test tests::os_strings::works_for_os_string ... ok
[INFO] [stdout] test tests::invocation_syntax::trailing_comma_is_accepted_after_normal_argument ... ok
[INFO] [stdout] test tests::paths::path_buf_as_argument ... ok
[INFO] [stdout] test tests::log_commands::quotes_arguments_with_spaces ... ok
[INFO] [stdout] test tests::os_strings::works_for_os_str ... ok
[INFO] [stdout] test tests::log_commands::arguments_with_invalid_utf8_will_be_logged_with_lossy_conversion ... ok
[INFO] [stdout] test tests::log_commands::logs_simple_commands ... ok
[INFO] [stdout] test tests::sequences::slices_of_non_strings ... ok
[INFO] [stdout] test tests::sequences::slices_as_arguments ... ok
[INFO] [stdout] test tests::sequences::allows_to_pass_in_arguments_as_a_vec_of_ref_str ... ok
[INFO] [stdout] test tests::sequences::arrays_of_non_strings ... ok
[INFO] [stdout] test tests::sequences::vector_of_non_strings ... ok
[INFO] [stdout] test tests::paths::ref_path_as_argument ... ok
[INFO] [stdout] test tests::sequences::arrays_as_arguments ... ok
[INFO] [stdout] test tests::sequences::array_refs_as_arguments ... ok
[INFO] [stdout] foo
[INFO] [stdout] test tests::multiple_arguments_as_ref_str ... ok
[INFO] [stdout] test tests::sequences::vector_of_vectors ... ok
[INFO] [stdout] test tests::split::splits_owned_strings ... ok
[INFO] [stdout] test tests::split::splits_words_by_whitespace ... ok
[INFO] [stdout] test tests::split::percent_sign::in_cmd_unit ... ok
[INFO] [stdout] test tests::split::percent_sign::non_literals ... ok
[INFO] [stdout] test tests::split::percent_sign::for_first_of_multiple_arguments ... ok
[INFO] [stdout] test tests::split::trims_trailing_whitespace ... ok
[INFO] [stdout] test tests::split::percent_sign::in_cmd_result ... ok
[INFO] [stdout] test tests::splitting_with_library_functions::split_whitespace ... ok
[INFO] [stdout] test tests::splitting_with_library_functions::split_ascii_whitespace ... ok
[INFO] [stdout] test tests::splitting_with_library_functions::allow_to_use_split ... ok
[INFO] [stdout] test tests::split::trims_leading_whitespace ... ok
[INFO] [stdout] test tests::split::skips_multiple_whitespace_characters ... ok
[INFO] [stdout] test tests::split::percent_sign::works_for_later_arguments ... ok
[INFO] [stdout] test tests::sequences::elements_in_slices_are_not_split_by_whitespace ... ok
[INFO] [stdout] test tests::errors::panics_by_default::executable_cannot_be_found - should panic ... ok
[INFO] [stdout] test tests::errors::panics_by_default::no_executable - should panic ... ok
[INFO] [stdout] test tests::split::percent_sign::splits_words ... ok
[INFO] [stdout] test tests::errors::result_types::other_exit_codes ... FAILED
[INFO] [stdout] test tests::errors::panics_by_default::invalid_utf8_to_stdout_is_allowed_when_not_captured ... FAILED
[INFO] [stdout] test tests::errors::panics_by_default::non_zero_exit_codes - should panic ... ok
[INFO] [stdout] test tests::errors::panics_by_default::other_exit_codes - should panic ... FAILED
[INFO] [stdout] test tests::stdout::relays_stdout_by_default ... ok
[INFO] [stdout] test tests::stdout::does_not_relay_stdout_when_collecting_into_result_of_string ... ok
[INFO] [stdout] test tests::strings::mix_ref_str_and_string ... ok
[INFO] [stdout] test tests::stdin::multiple_stdin_arguments_are_all_passed_into_the_child_process ... FAILED
[INFO] [stdout] test tests::allows_to_execute_a_command ... FAILED
[INFO] [stdout] test tests::errors::panics_by_default::combine_panics_with_other_outputs - should panic ... ok
[INFO] [stdout] test tests::stdin::writing_too_many_bytes_into_a_non_reading_child_may_error ... ok
[INFO] [stdout] test tests::current_dir::works_for_other_types ... FAILED
[INFO] [stdout] test tests::stdout::does_not_relay_stdout_when_collecting_into_string ... ok
[INFO] [stdout] test tests::strings::multiple_strings ... ok
[INFO] [stdout] test tests::strings::does_not_split_strings_in_vectors ... FAILED
[INFO] [stdout] test tests::tuple_outputs::result_of_tuple_when_erroring ... ok
[INFO] [stdout] test tests::tuple_outputs::result_of_tuple ... ok
[INFO] [stdout] test tests::errors::panics_by_default::includes_full_command_on_non_zero_exit_codes - should panic ... ok
[INFO] [stdout] test tests::sequences::elements_in_array_refs_are_not_split_by_whitespace ... FAILED
[INFO] [stdout] test tests::tuple_outputs::three_tuples ... ok
[INFO] [stdout] test tests::stderr::relays_stderr_by_default ... FAILED
[INFO] [stdout] test tests::strings::works_for_string ... ok
[INFO] [stdout] test tests::stderr::relays_stderr_for_non_zero_exit_codes ... FAILED
[INFO] [stdout] test tests::stderr::does_not_relay_stderr_when_catpuring ... FAILED
[INFO] [stdout] test tests::sequences::elements_in_arrays_are_not_split_by_whitespace ... FAILED
[INFO] [stdout] test tests::stdin::allows_passing_in_u8_slices_as_stdin ... FAILED
[INFO] [stdout] test tests::stderr::capture_stderr ... FAILED
[INFO] [stdout] test tests::errors::panics_by_default::invalid_utf8_stdout - should panic ... FAILED
[INFO] [stdout] test tests::exit_status::forty_two ... FAILED
[INFO] [stdout] test tests::errors::result_types::invalid_utf8_stdout ... FAILED
[INFO] [stdout] test tests::stdin::allows_to_pass_in_strings_as_stdin ... FAILED
[INFO] [stdout] test tests::stdin::works_for_owned_strings ... FAILED
[INFO] [stdout] test tests::paths::path_buf_as_executable ... FAILED
[INFO] [stdout] test tests::paths::ref_path_as_executable ... FAILED
[INFO] [stdout] test tests::tuple_outputs::capturing_stderr_on_errors ... FAILED
[INFO] [stdout] test tests::errors::panics_by_default::includes_full_command_on_missing_executables - should panic ... ok
[INFO] [stdout] test tests::stdin::stdin_is_closed_by_default ... FAILED
[INFO] [stdout] test tests::tuple_outputs::capturing_stdout_on_errors ... FAILED
[INFO] [stdout] test tests::stderr::assumes_stderr_is_utf_8 ... FAILED
[INFO] [stdout] test tests::stdout::relays_stdout_for_non_zero_exit_codes ... FAILED
[INFO] [stdout] test tests::tuple_outputs::two_tuple_1 ... FAILED
[INFO] [stdout] test tests::tuple_outputs::two_tuple_2 ... FAILED
[INFO] [stdout] test tests::stderr::does_allow_invalid_utf_8_to_stderr_when_not_capturing ... FAILED
[INFO] [stdout] test tests::stderr::streams_stderr has been running for over 60 seconds
[INFO] [stdout] test tests::stdout::streams_stdout has been running for over 60 seconds
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "c0ad8dd72ac863a2349c4dbf025de024240f47a3c7f037a3766c78dc5b0258a3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c0ad8dd72ac863a2349c4dbf025de024240f47a3c7f037a3766c78dc5b0258a3", kill_on_drop: false }`
[INFO] [stdout] c0ad8dd72ac863a2349c4dbf025de024240f47a3c7f037a3766c78dc5b0258a3