[INFO] fetching crate zfskit 0.2.2...
[INFO] testing zfskit-0.2.2 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate zfskit 0.2.2 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate zfskit 0.2.2
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate zfskit 0.2.2
[INFO] tweaked toml for crates.io crate zfskit 0.2.2 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate zfskit 0.2.2 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate zfskit 0.2.2 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] ae27eafe1356c9bd069f649d3941737f10ffd7109c19764d5bd155e01af74412
[INFO] running `Command { std: "docker" "start" "ae27eafe1356c9bd069f649d3941737f10ffd7109c19764d5bd155e01af74412", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ae27eafe1356c9bd069f649d3941737f10ffd7109c19764d5bd155e01af74412", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "ae27eafe1356c9bd069f649d3941737f10ffd7109c19764d5bd155e01af74412" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ae27eafe1356c9bd069f649d3941737f10ffd7109c19764d5bd155e01af74412", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "ae27eafe1356c9bd069f649d3941737f10ffd7109c19764d5bd155e01af74412" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling zeroize v1.9.0
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling syn v2.0.118
[INFO] [stderr]    Compiling mio v1.2.1
[INFO] [stderr]    Compiling regex-automata v0.4.15
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling regex v1.13.0
[INFO] [stderr]    Compiling tokio v1.52.3
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling zfskit v0.2.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 6.90s
[INFO] running `Command { std: "docker" "inspect" "ae27eafe1356c9bd069f649d3941737f10ffd7109c19764d5bd155e01af74412", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "ae27eafe1356c9bd069f649d3941737f10ffd7109c19764d5bd155e01af74412" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling tokio v1.52.3
[INFO] [stderr]    Compiling zfskit v0.2.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 8.47s
[INFO] running `Command { std: "docker" "inspect" "ae27eafe1356c9bd069f649d3941737f10ffd7109c19764d5bd155e01af74412", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "ae27eafe1356c9bd069f649d3941737f10ffd7109c19764d5bd155e01af74412" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/zfskit/802f2c4393fbe331/out/zfskit-802f2c4393fbe331)
[INFO] [stdout] 
[INFO] [stdout] running 192 tests
[INFO] [stdout] test dataset::create::tests::build_args_no_mount_with_mountpoint_property ... ok
[INFO] [stdout] test bookmark::tests::create_succeeds ... ok
[INFO] [stdout] test bookmark::tests::destroy_bookmark_succeeds ... ok
[INFO] [stdout] test dataset::create::tests::build_args_no_properties ... ok
[INFO] [stdout] test bookmark::tests::create_rejects_existing_bookmark_for_other_snapshot ... ok
[INFO] [stdout] test bookmark::tests::create_is_idempotent_on_bookmark_exists ... ok
[INFO] [stdout] test dataset::create::tests::build_args_with_create_parents ... ok
[INFO] [stdout] test dataset::create::tests::build_args_with_no_mount ... ok
[INFO] [stdout] test dataset::create::tests::build_args_with_properties ... ok
[INFO] [stdout] test dataset::destroy::tests::build_args_default ... ok
[INFO] [stdout] test dataset::destroy::tests::build_args_defer_holds ... ok
[INFO] [stdout] test dataset::destroy::tests::build_args_recursive_force ... ok
[INFO] [stdout] test dataset::destroy::tests::build_args_recursive_with_clones_supersedes_r ... ok
[INFO] [stdout] test dataset::get::tests::build_args_minimal ... ok
[INFO] [stdout] test dataset::destroy::tests::destroy_success ... ok
[INFO] [stdout] test dataset::get::tests::build_args_full ... ok
[INFO] [stdout] test dataset::get::tests::build_args_rejects_empty_properties ... ok
[INFO] [stdout] test dataset::list::tests::build_args_default ... ok
[INFO] [stdout] test dataset::list::tests::build_args_full ... ok
[INFO] [stdout] test dataset::mount::tests::mount_args_default ... ok
[INFO] [stdout] test dataset::mount::tests::mount_args_recursive ... ok
[INFO] [stdout] test dataset::mount::tests::unmount_args_default ... ok
[INFO] [stdout] test dataset::mount::tests::unmount_args_force ... ok
[INFO] [stdout] test dataset::rollback::tests::build_args_default ... ok
[INFO] [stdout] test dataset::rollback::tests::build_args_destroy_newer ... ok
[INFO] [stdout] test dataset::rollback::tests::build_args_destroy_newer_with_clones_supersedes_r ... ok
[INFO] [stdout] test dataset::set::tests::build_args_multiple_properties ... ok
[INFO] [stdout] test dataset::rollback::tests::rollback_success ... ok
[INFO] [stdout] test dataset::set::tests::build_args_no_mount ... ok
[INFO] [stdout] test dataset::set::tests::build_args_single_property ... ok
[INFO] [stdout] test dataset::snapshot::tests::build_args_atomic_many ... ok
[INFO] [stdout] test dataset::snapshot::tests::build_args_basic ... ok
[INFO] [stdout] test dataset::snapshot::tests::build_args_recursive_with_properties ... ok
[INFO] [stdout] test dataset::snapshot::tests::snapshot_recursive_propagates_args ... ok
[INFO] [stdout] test dataset::snapshot::tests::snapshot_success ... ok
[INFO] [stdout] test engine::tests::dataset_load_key_with_passphrase_via_handle ... ok
[INFO] [stdout] test dataset::rollback::tests::rollback_dataset_not_found ... ok
[INFO] [stdout] test engine::tests::dataset_get_property_via_handle ... ok
[INFO] [stdout] test engine::tests::dataset_mount_unmount_via_handle ... ok
[INFO] [stdout] test engine::tests::dataset_set_properties_empty_is_noop ... ok
[INFO] [stdout] test engine::tests::dataset_set_property_via_handle ... ok
[INFO] [stdout] test dataset::destroy::tests::destroy_held_snapshot_returns_typed_error ... ok
[INFO] [stdout] test engine::tests::dataset_snapshot_rollback_destroy_via_handle ... ok
[INFO] [stdout] test engine::tests::dataset_set_properties_no_mount_via_handle ... ok
[INFO] [stdout] test engine::tests::pool_create_dataset_returns_handle_with_full_name ... ok
[INFO] [stdout] test bookmark::tests::destroy_not_found_returns_error ... ok
[INFO] [stdout] test dataset::snapshot::tests::snapshot_dataset_not_found ... ok
[INFO] [stdout] test engine::tests::nested_create_dataset_joins_path ... ok
[INFO] [stdout] test engine::tests::pool_dataset_lookup_joins_path ... ok
[INFO] [stdout] test engine::tests::pool_import_export_via_handle ... ok
[INFO] [stdout] test engine::tests::pool_root_dataset_uses_pool_name ... ok
[INFO] [stdout] test engine::tests::zfs_create_dataset_takes_absolute_name ... ok
[INFO] [stdout] test engine::tests::zfs_unmount_all_force ... ok
[INFO] [stdout] test error::tests::classifies_pool_not_found_from_zpool_list ... ok
[INFO] [stdout] test error::tests::classifies_pool_not_found ... ok
[INFO] [stdout] test error::tests::classifies_permission_denied ... ok
[INFO] [stdout] test error::tests::classifies_busy_pool ... ok
[INFO] [stdout] test error::tests::classifies_busy ... ok
[INFO] [stdout] test dataset::destroy::tests::destroy_dataset_busy_routes_to_busy ... ok
[INFO] [stdout] test error::tests::classifies_key_not_loaded_unnamed_falls_back ... ok
[INFO] [stdout] test error::tests::classifies_out_of_space ... ok
[INFO] [stdout] test hold::tests::hold_is_idempotent_on_tag_already_exists ... ok
[INFO] [stdout] test error::tests::falls_back_to_other_with_trimmed_stderr ... ok
[INFO] [stdout] test error::tests::classifies_snapshot_held ... ok
[INFO] [stdout] test error::tests::classifies_dataset_not_found_pool_form ... ok
[INFO] [stdout] test hold::tests::list_holds_many_empty_input_skips_spawn ... ok
[INFO] [stdout] test error::tests::classifies_snapshot_held_when_name_contains_colon ... ok
[INFO] [stdout] test error::tests::classifies_dataset_not_found_dataset_form ... ok
[INFO] [stdout] test hold::tests::list_holds_many_parses_multiple_snapshots ... ok
[INFO] [stdout] test hold::tests::list_holds_many_partial_failure_returns_survivors ... ok
[INFO] [stdout] test error::tests::classifies_snapshot_exists ... ok
[INFO] [stdout] test error::tests::classifies_key_not_loaded_named ... ok
[INFO] [stdout] test hold::tests::list_holds_many_total_failure_errors ... ok
[INFO] [stdout] test hold::tests::list_holds_parses_fixture ... ok
[INFO] [stdout] test hold::tests::parse_empty_output ... ok
[INFO] [stdout] test hold::tests::parse_rejects_malformed_line ... ok
[INFO] [stdout] test hold::tests::parse_single_hold ... ok
[INFO] [stdout] test models::common::tests::unknown_wire_enums_are_preserved ... ok
[INFO] [stdout] test models::common::tests::unsupported_output_major_is_explicit ... ok
[INFO] [stdout] test names::tests::accepts_openzfs_component_characters ... ok
[INFO] [stdout] test names::tests::enforces_entity_length_in_bytes ... ok
[INFO] [stdout] test names::tests::names_distinguish_entity_kinds ... ok
[INFO] [stdout] test names::tests::rejects_names_openzfs_rejects ... ok
[INFO] [stdout] test pool::create::tests::build_args_archinstall_shape ... ok
[INFO] [stdout] test pool::create::tests::build_args_minimal_single_disk ... ok
[INFO] [stdout] test pool::create::tests::build_args_mirror_pair ... ok
[INFO] [stdout] test pool::create::tests::build_args_raidz2_quad ... ok
[INFO] [stdout] test pool::create::tests::build_args_rejects_invalid_pool_name ... ok
[INFO] [stdout] test pool::create::tests::build_args_rejects_empty_vdev ... ok
[INFO] [stdout] test pool::create::tests::build_args_stripe_of_two_mirrors ... ok
[INFO] [stdout] test pool::create::tests::build_args_rejects_no_vdevs ... ok
[INFO] [stdout] test pool::destroy::tests::build_args_default ... ok
[INFO] [stdout] test pool::destroy::tests::build_args_force ... ok
[INFO] [stdout] test pool::discover::tests::parse_captures_status_for_degraded ... ok
[INFO] [stdout] test pool::discover::tests::parse_empty_input_yields_empty_vec ... ok
[INFO] [stdout] test pool::discover::tests::parse_ignores_lines_before_first_pool ... ok
[INFO] [stdout] test pool::discover::tests::parse_ignores_unknown_keys ... ok
[INFO] [stdout] test pool::discover::tests::parse_single_online_pool ... ok
[INFO] [stdout] test pool::export::tests::build_args_default ... ok
[INFO] [stdout] test pool::export::tests::build_args_force ... ok
[INFO] [stdout] test pool::import::tests::build_args_default ... ok
[INFO] [stdout] test pool::import::tests::build_args_force_no_mount ... ok
[INFO] [stdout] test pool::import::tests::build_args_with_altroot ... ok
[INFO] [stdout] test pool::list::tests::build_args_default ... ok
[INFO] [stdout] test pool::list::tests::build_args_full ... ok
[INFO] [stdout] test pool::discover::tests::parse_multiple_pools_separated_by_blank_lines ... ok
[INFO] [stdout] test pool::list::tests::no_imported_pools_is_an_empty_list ... ok
[INFO] [stdout] test pool::scrub::tests::pause_uses_dash_p ... ok
[INFO] [stdout] test pool::scrub::tests::resume_is_bare_scrub_not_dash_p ... ok
[INFO] [stdout] test pool::scrub::tests::start_is_bare_scrub ... ok
[INFO] [stdout] test pool::scrub::tests::stop_uses_dash_s ... ok
[INFO] [stdout] test recv::tests::build_args_property_override_and_inherit ... ok
[INFO] [stdout] test recv::tests::abort_partial_runs_recv_dash_a ... ok
[INFO] [stdout] test recv::tests::build_args_defaults ... ok
[INFO] [stdout] test recv::tests::abort_partial_treats_no_partial_as_success ... ok
[INFO] [stdout] test recv::tests::build_args_flags ... ok
[INFO] [stdout] test recv::tests::build_args_rejects_conflicting_shape_and_property_flags ... ok
[INFO] [stdout] test recv::tests::build_args_resumable_flag ... ok
[INFO] [stdout] test recv::tests::build_args_validates_target_shape ... ok
[INFO] [stdout] test recv::tests::check_recv_stderr_clean ... ok
[INFO] [stdout] test recv::tests::check_recv_stderr_needs_resume_token ... ok
[INFO] [stdout] test recv::tests::receive_resume_token_returns_some_for_real_token ... ok
[INFO] [stdout] test recv::tests::receive_resume_token_returns_none_for_dash ... ok
[INFO] [stdout] test recv::tests::recv_spawns_correct_args ... ok
[INFO] [stdout] test recv::tests::recv_interrupted_stderr_yields_resume_token ... ok
[INFO] [stdout] test resume_token::tests::decode_from_fixture ... ok
[INFO] [stdout] test resume_token::tests::parse_hex_invalid ... ok
[INFO] [stdout] test resume_token::tests::parse_hex_valid ... ok
[INFO] [stdout] test resume_token::tests::parse_missing_field ... ok
[INFO] [stdout] test resume_token::tests::parse_nvlist_fixture ... ok
[INFO] [stdout] test resume_token::tests::parse_nvlist_with_fromguid ... ok
[INFO] [stdout] test runner::tests::cmd_eq_hash_includes_stdin ... ok
[INFO] [stdout] test runner::tests::cmd_builder_basic ... ok
[INFO] [stdout] test runner::tests::cmd_secret_stdin_redacted ... ok
[INFO] [stdout] test runner::tests::cmd_stdin_visible_in_display ... ok
[INFO] [stdout] test runner::tests::mock_handle_start_kill_is_noop ... ok
[INFO] [stdout] test runner::tests::recording_runner_returns_fixture ... ok
[INFO] [stdout] test runner::tests::recording_runner_distinguishes_stdin ... ok
[INFO] [stdout] test runner::tests::spawn_fixture_streams_stdout_and_waits ... ok
[INFO] [stdout] test runner::tests::recording_runner_unmatched_returns_not_found ... ok
[INFO] [stdout] test runner::tests::shell_quote_with_spaces_and_metachars ... ok
[INFO] [stdout] test runner::tests::shell_quote_with_single_quote ... ok
[INFO] [stdout] test runner::tests::spawn_mock_stdin_accepts_writes ... ok
[INFO] [stdout] test runner::tests::ssh_runner_argv_pubkey_mode ... ok
[INFO] [stdout] test runner::tests::shell_quote_simple ... ok
[INFO] [stdout] test runner::tests::ssh_runner_argv_password_mode_uses_sshpass ... ok
[INFO] [stdout] test runner::tests::spawn_unmatched_returns_not_found ... ok
[INFO] [stdout] test runner::tests::real_runner_forces_stable_c_locale ... ok
[INFO] [stdout] test runner::tests::ssh_target_parse_bare_ipv6_takes_whole_as_host ... ok
[INFO] [stdout] test runner::tests::ssh_runner_rejects_non_utf8_arguments ... ok
[INFO] [stdout] test runner::tests::ssh_target_parse_bracketed_ipv6_with_port ... ok
[INFO] [stdout] test runner::tests::ssh_target_parse_host_port_only ... ok
[INFO] [stdout] test runner::tests::ssh_runner_argv_quotes_arg_with_single_quote ... ok
[INFO] [stdout] test runner::tests::real_runner_spawn_start_kill_aborts_long_running_child ... ok
[INFO] [stdout] test runner::tests::ssh_target_parse_defaults ... ok
[INFO] [stdout] test runner::tests::ssh_target_parse_full ... ok
[INFO] [stdout] test runner::tests::ssh_target_parse_rejects_malformed_ipv6_brackets ... ok
[INFO] [stdout] test send::args::tests::all_replication_flags ... ok
[INFO] [stdout] test send::args::tests::full_dry_run_args ... ok
[INFO] [stdout] test send::args::tests::incremental_send_args ... ok
[INFO] [stdout] test runner::tests::ssh_target_parse_user_only ... ok
[INFO] [stdout] test runner::tests::ssh_target_parse_rejects_empty_host ... ok
[INFO] [stdout] test send::args::tests::resume_rejects_normal_send_flags ... ok
[INFO] [stdout] test send::args::tests::resume_token_must_not_be_empty ... ok
[INFO] [stdout] test send::dry_run::tests::dry_run_incremental_from_fixture ... ok
[INFO] [stdout] test runner::tests::ssh_target_parse_rejects_invalid_user_and_port ... ok
[INFO] [stdout] test send::args::tests::dataset_head_send_is_supported ... ok
[INFO] [stdout] test send::args::tests::dry_run_with_resume_token_is_error ... ok
[INFO] [stdout] test send::args::tests::incremental_dry_run_args ... ok
[INFO] [stdout] test send::dry_run::tests::parse_empty_fails ... ok
[INFO] [stdout] test send::args::tests::full_send_args ... ok
[INFO] [stdout] test send::args::tests::validates_send_target_and_incremental_sources ... ok
[INFO] [stdout] test send::dry_run::tests::dry_run_full_from_fixture ... ok
[INFO] [stdout] test send::tests::send_full_returns_handle_with_stream ... ok
[INFO] [stdout] test send::dry_run::tests::parse_full_send ... ok
[INFO] [stdout] test system::arc::tests::one_line_input_errors ... ok
[INFO] [stdout] test send::args::tests::resume_token_send_args ... ok
[INFO] [stdout] test send::args::tests::snapshot_only_modes_reject_dataset_heads_and_bookmarks ... ok
[INFO] [stdout] test send::tests::send_resume_token_spawns_correct_args ... ok
[INFO] [stdout] test system::arc::tests::hit_ratio_is_sensible ... ok
[INFO] [stdout] test send::tests::send_replication_flags_passed_through ... ok
[INFO] [stdout] test send::dry_run::tests::parse_incremental_send ... ok
[INFO] [stdout] test system::arc::tests::empty_input_errors ... ok
[INFO] [stdout] test send::tests::send_incremental_spawns_correct_args ... ok
[INFO] [stdout] test system::arc::tests::parses_typed_fields_from_real_kernel_output ... ok
[INFO] [stdout] test system::arc::tests::raw_map_captures_fields_not_in_struct ... ok
[INFO] [stdout] test system::arc::tests::unparseable_rows_are_skipped_not_fatal ... ok
[INFO] [stdout] test runner::tests::wait_closes_untaken_stdin_before_waiting ... FAILED
[INFO] [stdout] test runner::tests::real_runner_child_ignoring_stdin_still_reports_output ... ok
[INFO] [stdout] test runner::tests::real_runner_stdin_larger_than_pipe_buffer_does_not_deadlock ... ok
[INFO] [stdout] test runner::tests::real_runner_kill_on_drop_terminates_child ... ok
[INFO] [stdout] test runner::tests::real_runner_run_is_killed_when_future_is_cancelled ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- runner::tests::wait_closes_untaken_stdin_before_waiting stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'runner::tests::wait_closes_untaken_stdin_before_waiting' (1084) panicked at src/runner.rs:1012:9:
[INFO] [stdout] assertion failed: status.success()
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5eb9b4e63761 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5eb9b4e63761 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::trace_unsynchronized::<std[617be2ca0a6fd877]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5eb9b4e63761 - std[617be2ca0a6fd877]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5eb9b4e63761 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5eb9b4e7969a - <core[a373ad73a504971]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5eb9b4e7969a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    6:     0x5eb9b4e67dfc - core[a373ad73a504971]::io::write::default_write_fmt::<alloc[2e1b2a31b91974c5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:402:11
[INFO] [stdout]    7:     0x5eb9b4e67dfc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:335:13
[INFO] [stdout]    8:     0x5eb9b4e3e766 - <std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5eb9b4e3e766 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:289:27
[INFO] [stdout]   10:     0x5eb9b4e5b4e9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:313:9
[INFO] [stdout]   11:     0x5eb9b4b1b600 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   12:     0x5eb9b4b1b600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:137:21
[INFO] [stdout]   13:     0x5eb9b4e5b812 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   14:     0x5eb9b4e5b812 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:819:13
[INFO] [stdout]   15:     0x5eb9b4e3e844 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:678:13
[INFO] [stdout]   16:     0x5eb9b4e372e9 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5eb9b4e3f41d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:676:5
[INFO] [stdout]   18:     0x5eb9b4e79f3c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5eb9b4e79f02 - core[a373ad73a504971]::panicking::panic
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:150:5
[INFO] [stdout]   20:     0x5eb9b4a6c81c - zfskit[dfb1b12d7c3ec4a4]::runner::tests::wait_closes_untaken_stdin_before_waiting::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/runner.rs:1012:9
[INFO] [stdout]   21:     0x5eb9b4a14492 - <core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>> as core[a373ad73a504971]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/future/future.rs:133:9
[INFO] [stdout]   22:     0x5eb9b4a1450d - <core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>> as core[a373ad73a504971]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/future/future.rs:133:9
[INFO] [stdout]   23:     0x5eb9b4a8d15d - <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/scheduler/current_thread/mod.rs:778:70
[INFO] [stdout]   24:     0x5eb9b4a8d085 - tokio[4375b2b12cc6222b]::task::coop::with_budget::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/task/coop/mod.rs:167:5
[INFO] [stdout]   25:     0x5eb9b4a8d085 - tokio[4375b2b12cc6222b]::task::coop::budget::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/task/coop/mod.rs:133:5
[INFO] [stdout]   26:     0x5eb9b4a8d085 - <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/scheduler/current_thread/mod.rs:778:25
[INFO] [stdout]   27:     0x5eb9b4a87b02 - <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::Context>::enter::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/scheduler/current_thread/mod.rs:451:19
[INFO] [stdout]   28:     0x5eb9b4a8c53d - <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/scheduler/current_thread/mod.rs:777:44
[INFO] [stdout]   29:     0x5eb9b4a8b7e4 - <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/scheduler/current_thread/mod.rs:865:68
[INFO] [stdout]   30:     0x5eb9b4ac528b - <tokio[4375b2b12cc6222b]::runtime::context::scoped::Scoped<tokio[4375b2b12cc6222b]::runtime::scheduler::Context>>::set::<<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   31:     0x5eb9b4aca0a9 - tokio[4375b2b12cc6222b]::runtime::context::set_scheduler::<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/context.rs:181:38
[INFO] [stdout]   32:     0x5eb9b4aa87bf - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[4375b2b12cc6222b]::runtime::context::Context>>::try_with::<tokio[4375b2b12cc6222b]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/local.rs:463:12
[INFO] [stdout]   33:     0x5eb9b4aa836e - <std[617be2ca0a6fd877]::thread::local::LocalKey<tokio[4375b2b12cc6222b]::runtime::context::Context>>::with::<tokio[4375b2b12cc6222b]::runtime::context::set_scheduler<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[2e1b2a31b91974c5]::boxed::Box<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/local.rs:427:20
[INFO] [stdout]   34:     0x5eb9b4ac94ad - tokio[4375b2b12cc6222b]::runtime::context::set_scheduler::<(alloc[2e1b2a31b91974c5]::boxed::Box<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::Core>, core[a373ad73a504971]::option::Option<()>), <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/context.rs:181:17
[INFO] [stdout]   35:     0x5eb9b4a8a360 - <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>::{closure#0}, core[a373ad73a504971]::option::Option<()>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/scheduler/current_thread/mod.rs:865:27
[INFO] [stdout]   36:     0x5eb9b4a8a656 - <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[a373ad73a504971]::pin::Pin<&mut core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/scheduler/current_thread/mod.rs:765:24
[INFO] [stdout]   37:     0x5eb9b4a8b2f0 - <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/scheduler/current_thread/mod.rs:205:33
[INFO] [stdout]   38:     0x5eb9b4a4a828 - tokio[4375b2b12cc6222b]::runtime::context::runtime::enter_runtime::<<tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   39:     0x5eb9b4a876c4 - <tokio[4375b2b12cc6222b]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/scheduler/current_thread/mod.rs:193:9
[INFO] [stdout]   40:     0x5eb9b4a2534f - <tokio[4375b2b12cc6222b]::runtime::runtime::Runtime>::block_on_inner::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/runtime.rs:371:52
[INFO] [stdout]   41:     0x5eb9b4a2549b - <tokio[4375b2b12cc6222b]::runtime::runtime::Runtime>::block_on::<core[a373ad73a504971]::pin::Pin<&mut dyn core[a373ad73a504971]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.52.3/src/runtime/runtime.rs:345:18
[INFO] [stdout]   42:     0x5eb9b4a75e6e - zfskit[dfb1b12d7c3ec4a4]::runner::tests::wait_closes_untaken_stdin_before_waiting
[INFO] [stdout]                                at /opt/rustwide/workdir/src/runner.rs:1012:34
[INFO] [stdout]   43:     0x5eb9b4a67da7 - zfskit[dfb1b12d7c3ec4a4]::runner::tests::wait_closes_untaken_stdin_before_waiting::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/runner.rs:1006:56
[INFO] [stdout]   44:     0x5eb9b4ae90c6 - <zfskit[dfb1b12d7c3ec4a4]::runner::tests::wait_closes_untaken_stdin_before_waiting::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45:     0x5eb9b4b0ea4b - <fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x5eb9b4b0ea4b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:701:18
[INFO] [stdout]   47:     0x5eb9b4b1bc25 - test[aada8e7e76ba119b]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:74
[INFO] [stdout]   48:     0x5eb9b4b1bc25 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   49:     0x5eb9b4b1bc25 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   50:     0x5eb9b4b1bc25 - std[617be2ca0a6fd877]::panicking::catch_unwind::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   51:     0x5eb9b4b1bc25 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   52:     0x5eb9b4b1bc25 - test[aada8e7e76ba119b]::run_test_in_process
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:27
[INFO] [stdout]   53:     0x5eb9b4b1bc25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:645:43
[INFO] [stdout]   54:     0x5eb9b4b15514 - test[aada8e7e76ba119b]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:675:41
[INFO] [stdout]   55:     0x5eb9b4b15514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   56:     0x5eb9b4b1ecb2 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   57:     0x5eb9b4b1ecb2 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   58:     0x5eb9b4b1ecb2 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   59:     0x5eb9b4b1ecb2 - std[617be2ca0a6fd877]::panicking::catch_unwind::<(), core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   60:     0x5eb9b4b1ecb2 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   61:     0x5eb9b4b1ecb2 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   62:     0x5eb9b4b1ecb2 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   63:     0x5eb9b4e6293f - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn core[a373ad73a504971]::ops::function::FnOnce<(), Output = ()> + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2314:9
[INFO] [stdout]   64:     0x5eb9b4e6293f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   65:     0x7f96e1506dfa - <unknown>
[INFO] [stdout]   66:     0x7f96e159a3d4 - clone
[INFO] [stdout]   67:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     runner::tests::wait_closes_untaken_stdin_before_waiting
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 191 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.24s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "ae27eafe1356c9bd069f649d3941737f10ffd7109c19764d5bd155e01af74412", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ae27eafe1356c9bd069f649d3941737f10ffd7109c19764d5bd155e01af74412", kill_on_drop: false }`
[INFO] [stdout] ae27eafe1356c9bd069f649d3941737f10ffd7109c19764d5bd155e01af74412
