[INFO] fetching crate rightkit-process 0.4.0...
[INFO] testing rightkit-process-0.4.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate rightkit-process 0.4.0 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate rightkit-process 0.4.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate rightkit-process 0.4.0
[INFO] tweaked toml for crates.io crate rightkit-process 0.4.0 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate rightkit-process 0.4.0 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 rightkit-process 0.4.0 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-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 0080d57d8559ef70982b33078a113d7f2d56f9e526748b1ceeebbd2548c02f3d
[INFO] running `Command { std: "docker" "start" "0080d57d8559ef70982b33078a113d7f2d56f9e526748b1ceeebbd2548c02f3d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "0080d57d8559ef70982b33078a113d7f2d56f9e526748b1ceeebbd2548c02f3d", 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" "0080d57d8559ef70982b33078a113d7f2d56f9e526748b1ceeebbd2548c02f3d" "/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" "0080d57d8559ef70982b33078a113d7f2d56f9e526748b1ceeebbd2548c02f3d", 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" "0080d57d8559ef70982b33078a113d7f2d56f9e526748b1ceeebbd2548c02f3d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling rightkit-process v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 2.15s
[INFO] running `Command { std: "docker" "inspect" "0080d57d8559ef70982b33078a113d7f2d56f9e526748b1ceeebbd2548c02f3d", 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" "0080d57d8559ef70982b33078a113d7f2d56f9e526748b1ceeebbd2548c02f3d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling quote v1.0.46
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling target-triple v1.0.0
[INFO] [stderr]    Compiling toml_datetime v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling serde_spanned v1.1.1
[INFO] [stderr]    Compiling toml_writer v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling toml_parser v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling syn v2.0.118
[INFO] [stderr]    Compiling toml v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling trybuild v1.0.116
[INFO] [stderr]    Compiling rightkit-process v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 26.83s
[INFO] running `Command { std: "docker" "inspect" "0080d57d8559ef70982b33078a113d7f2d56f9e526748b1ceeebbd2548c02f3d", 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" "0080d57d8559ef70982b33078a113d7f2d56f9e526748b1ceeebbd2548c02f3d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.05s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/rightkit-process/77c004a0355c5e51/out/rightkit_process-77c004a0355c5e51)
[INFO] [stdout] 
[INFO] [stdout] running 69 tests
[INFO] [stdout] test exit_watch::tests::a_deadline_already_passed_looks_once_and_returns ... ok
[INFO] [stdout] test exit_watch::tests::a_failing_look_ends_the_wait_with_its_error ... ok
[INFO] [stdout] test exit_watch::tests::a_watch_without_an_event_source_pauses_one_interval_not_the_limit ... ok
[INFO] [stdout] test exit_watch::tests::an_unbounded_wait_returns_as_soon_as_a_look_reports ... ok
[INFO] [stdout] test identity_tests::a_detached_child_is_signalled_by_pid_only_while_unreaped ... ok
[INFO] [stdout] test identity_tests::an_exited_unreaped_leader_is_still_described_as_the_same_process ... ok
[INFO] [stdout] test identity_tests::a_parked_wait_exited_never_names_the_pid_after_another_reader_reaped_it ... ok
[INFO] [stdout] test identity_tests::a_parked_wait_never_names_the_pid_after_another_reader_reaped_it ... ok
[INFO] [stdout] test identity_tests::a_monitor_that_reaps_retires_the_owner_and_the_straggler_survives ... ok
[INFO] [stdout] test identity_tests::an_exit_monitor_that_does_not_reap_leaves_the_owner_able_to_kill_a_term_resistant_straggler ... ok
[INFO] [stdout] test identity_tests::after_a_reap_by_any_path_no_signal_of_any_kind_is_sent ... ok
[INFO] [stdout] test identity_tests::a_recycled_id_is_never_signalled_after_a_reap_by_any_path ... ok
[INFO] [stdout] test identity_tests::bounded_waits::wait_or_kill_returns_at_the_exit_not_at_the_end_of_the_grace ... ok
[INFO] [stdout] test identity_tests::bounded_waits::a_blocked_wait_is_released_when_another_thread_kills_the_child ... ok
[INFO] [stdout] test exit_watch::tests::a_pause_on_a_running_child_ends_at_its_limit_not_after_it ... ok
[INFO] [stdout] test identity_tests::replaced_pid::probes_and_kills_do_not_name_a_pid_that_changed_hands ... ok
[INFO] [stdout] test identity_tests::terminating_before_the_reap_signals_exactly_the_group_then_reaps ... ok
[INFO] [stdout] test exit_watch::tests::the_fallback_loop_honours_the_deadline_and_does_not_spin ... ok
[INFO] [stdout] test identity_tests::a_leader_reaped_behind_our_back_is_never_signalled ... ok
[INFO] [stdout] test identity_tests::wait_or_kill_signals_term_then_kill_before_the_reap_and_nothing_after ... ok
[INFO] [stdout] test identity_tests::while_unreaped_runs_the_action_only_until_the_leader_is_reaped ... ok
[INFO] [stdout] test owned::tests::a_detached_spawn_rejects_kill_on_parent_exit ... ok
[INFO] [stdout] test identity_tests::bounded_waits::a_blocked_wait_is_released_by_a_plain_kill_from_another_thread ... ok
[INFO] [stdout] test identity_tests::the_recycled_id_hook_redirects_signals_while_the_leader_is_unreaped ... ok
[INFO] [stdout] test owned::tests::a_failure_after_spawn_cleans_up_the_partially_wrapped_child ... ok
[INFO] [stdout] test owned::tests::new_and_from_command_share_spawn_settings ... ok
[INFO] [stdout] test owned::tests::option_defaults_preserve_existing_behavior ... ok
[INFO] [stdout] test owned::tests::option_builders_keep_every_requested_value ... ok
[INFO] [stdout] test owned::tests::owned_commands_hide_their_console_window_by_default ... ok
[INFO] [stdout] test owned::tests::kill_on_parent_exit_is_opt_in ... ok
[INFO] [stdout] test reveal::tests::a_dotdot_escape_is_refused ... ok
[INFO] [stdout] test reveal::tests::a_relative_path_gets_no_plan ... ok
[INFO] [stdout] test reveal::tests::a_missing_path_is_refused ... ok
[INFO] [stdout] test reveal::tests::a_sibling_sharing_the_root_prefix_is_refused ... ok
[INFO] [stdout] test reveal::tests::an_empty_scope_is_refused ... ok
[INFO] [stdout] test reveal::tests::a_symlink_out_of_the_root_is_refused ... ok
[INFO] [stdout] test reveal::tests::launch_command_refuses_an_outside_path_before_building_anything ... ok
[INFO] [stdout] test reveal::tests::linux_reveal_of_the_filesystem_root_opens_the_root ... ok
[INFO] [stdout] test reveal::tests::open_refuses_runnable_files_and_reveal_does_not ... ok
[INFO] [stdout] test reveal::tests::open_refuses_a_file_with_an_execute_bit ... ok
[INFO] [stdout] test owned::tests::zero_bound_partial_spawn_failure_still_reaps_the_child ... ok
[INFO] [stdout] test reveal::tests::plans_per_platform ... ok
[INFO] [stdout] test identity_tests::wait_exited_observes_without_reaping_and_its_timeout_form_reports_both_outcomes ... ok
[INFO] [stdout] test reveal::tests::paths_inside_the_root_resolve_and_the_root_itself_does ... ok
[INFO] [stdout] test reveal::tests::the_windows_reveal_argument_is_quoted_verbatim ... ok
[INFO] [stdout] test stderr_tail::tests::a_cut_never_splits_a_character ... ok
[INFO] [stdout] test identity_tests::bounded_waits::an_exit_monitor_in_progress_does_not_block_termination ... ok
[INFO] [stdout] test stderr_tail::tests::an_oversized_line_that_ends_the_stream_is_dropped_without_hanging ... ok
[INFO] [stdout] test identity_tests::bounded_waits::a_cancelled_bounded_wait_returns_and_the_cancel_is_not_blocked ... ok
[INFO] [stdout] test stderr_tail::tests::a_zero_budget_retains_nothing_and_still_drains ... ok
[INFO] [stdout] test stderr_tail::tests::the_total_stays_within_the_budget_over_many_lines ... ok
[INFO] [stdout] test stderr_tail::tests::the_continuation_of_an_oversized_line_never_reaches_the_redactor_or_the_sinks ... ok
[INFO] [stdout] test stderr_tail::tests::try_capture_surfaces_a_spawn_failure_and_capture_degrades_to_an_empty_tail ... ok
[INFO] [stdout] test stderr_tail::tests::finish_within_returns_while_the_callback_is_still_being_dropped ... ok
[INFO] [stdout] test stderr_tail::tests::a_line_of_exactly_the_cap_is_inspected_and_one_byte_more_is_not ... ok
[INFO] [stdout] test stderr_tail::tests::one_entry_larger_than_the_budget_is_cut_to_its_last_bytes ... ok
[INFO] [stdout] test stderr_tail::tests::an_oversized_line_is_drained_across_many_reads_and_the_next_line_survives ... ok
[INFO] [stdout] test usage::tests::rates_are_none_without_counters_and_zero_for_no_time ... ok
[INFO] [stdout] test usage::tests::sample_reports_resident_memory_and_cpu ... ok
[INFO] [stdout] test identity_tests::bounded_waits::the_tree_grace_ends_early_when_every_member_exits_on_term ... ok
[INFO] [stdout] test exit_watch::tests::a_wait_on_a_real_child_returns_its_status_when_it_exits ... ok
[INFO] [stdout] test exit_watch::tests::a_watch_opened_after_the_exit_still_pauses_for_a_bounded_time ... ok
[INFO] [stdout] test exit_watch::tests::an_exit_wakes_a_long_pause_early ... ok
[INFO] [stdout] test identity_tests::bounded_waits::an_unbounded_wait_returns_the_status_of_a_child_that_runs_300_ms ... ok
[INFO] [stdout] test identity_tests::replaced_pid::waiting_does_not_name_a_pid_that_changed_hands ... ok
[INFO] [stdout] test identity_tests::bounded_waits::a_bounded_wait_returns_by_its_deadline_while_the_child_is_still_running ... ok
[INFO] [stdout] test identity_tests::replaced_pid::terminating_does_not_signal_a_pid_that_changed_hands ... ok
[INFO] [stdout] test identity_tests::bounded_waits::the_tree_grace_waits_for_a_term_resistant_member_and_no_longer ... ok
[INFO] [stderr]      Running tests/adopted_identity.rs (/opt/rustwide/target/release/build/rightkit-process/ef1d7f90bf9083ff/out/adopted_identity-ef1d7f90bf9083ff)
[INFO] [stdout] test identity_tests::clones_reaping_and_owners_terminating_never_interleave_a_signal_after_the_reap ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 69 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.80s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test a_changed_executable_with_the_same_start_time_is_still_the_same_process ... ok
[INFO] [stdout] test capture_of_a_pid_nobody_has_is_none ... ok
[INFO] [stdout] test a_captured_identity_verifies_and_reports_its_parts ... ok
[INFO] [stdout] test a_different_start_time_is_refused_and_the_process_keeps_running ... ok
[INFO] [stdout] test this_process_is_never_signalled ... ok
[INFO] [stdout] test a_matching_identity_is_signalled_and_the_process_ends ... ok
[INFO] [stdout] test a_sweep_refuses_a_stale_target_and_stops_a_genuine_one ... ok
[INFO] [stdout] test descendants::a_stale_root_has_no_descendants ... ok
[INFO] [stdout] test descendants::descendants_are_found_with_their_own_identities ... ok
[INFO] [stdout] test descendants::the_sweep_stops_every_descendant_and_leaves_the_root ... ok
[INFO] [stdout] test descendants::an_owned_child_lists_its_descendants_and_none_once_reaped ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/api.rs (/opt/rustwide/target/release/build/rightkit-process/1f7cb941be961f48/out/api-1f7cb941be961f48)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]     Checking rightkit-process v0.4.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Checking rightkit-process-tests v0.0.0 (/opt/rustwide/target/tests/trybuild/rightkit-process)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2.30s
[INFO] [stderr] 
[INFO] [stderr] 
[INFO] [stderr] test tests/ui/adopted_cannot_terminate.rs ... ok
[INFO] [stdout] test adopted_process_is_observation_only_at_compile_time ... ok
[INFO] [stderr] 
[INFO] [stdout] 
[INFO] [stderr] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.22s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/drop_bounded.rs (/opt/rustwide/target/release/build/rightkit-process/51779f29ff4f87a8/out/drop_bounded-51779f29ff4f87a8)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test the_default_bound_is_two_seconds_and_can_be_changed ... ok
[INFO] [stdout] test drop_kills_the_child_and_returns_inside_the_bound ... ok
[INFO] [stdout] test a_zero_bound_does_not_wait_and_the_child_is_still_reaped ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test allowlist_with_defaults_clears_and_keeps_only_named_variables ... ok
[INFO] [stderr]      Running tests/env_policy.rs (/opt/rustwide/target/release/build/rightkit-process/f616870eef7f87c0/out/env_policy-f616870eef7f87c0)
[INFO] [stdout] test a_fresh_command_does_not_report_a_cleared_environment ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/exit_observation.rs (/opt/rustwide/target/release/build/rightkit-process/5aaeb30c43408569/out/exit_observation-5aaeb30c43408569)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test wait_exited_alone_has_the_same_property ... ok
[INFO] [stdout] test the_group_is_still_killable_after_the_exit_was_observed_so_the_pipe_reaches_eof ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/handle_inheritance.rs (/opt/rustwide/target/release/build/rightkit-process/e8b9dfe26b8a0dcd/out/handle_inheritance-e8b9dfe26b8a0dcd)
[INFO] [stdout] running 7 tests
[INFO] [stdout] test inheritance_child ... ignored
[INFO] [stdout] test invalid_allowlisted_fd_fails_before_spawn ... ok
[INFO] [stdout] test inheritance_filter_preserves_exec_failure_reporting ... ok
[INFO] [stdout] test unlisted_inheritable_pipe_is_not_inherited ... ok
[INFO] [stdout] test independent_blocking_wait_keeps_stdio_and_timeout_accessible ... ok
[INFO] [stdout] test listed_inheritable_pipe_is_inherited ... ok
[INFO] [stdout] test listed_noninheritable_pipe_is_inherited_without_changing_parent_flags ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/membrane_process.rs (/opt/rustwide/target/release/build/rightkit-process/e2062fec3d97f6de/out/membrane_process-e2062fec3d97f6de)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test creation_timestamp_is_present_on_native_hosts ... ok
[INFO] [stdout] test zero_grace_supports_immediate_escalation_and_bounded_reap ... ok
[INFO] [stdout] test std_child_borrow_and_independent_exit_reader_share_status ... ok
[INFO] [stdout] test term_then_kill_escalates_at_configured_grace ... ok
[INFO] [stdout] test session_mode_creates_session_and_group_led_by_child ... ok
[INFO] [stdout] test tree_termination_reaches_descendant_while_the_exited_parent_is_unreaped ... ok
[INFO] [stdout] test detached_child_survives_owner_drop_and_exit_reader_remains_usable ... ok
[INFO] [stderr]      Running tests/package_metadata.rs (/opt/rustwide/target/release/build/rightkit-process/1b04b75375082563/out/package_metadata-1b04b75375082563)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/parent_death.rs (/opt/rustwide/target/release/build/rightkit-process/bca0cc5208d3f32f/out/parent_death-bca0cc5208d3f32f)
[INFO] [stdout] test package_contains_both_declared_license_texts ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test parent_death_role ... ok
[INFO] [stdout] test tied_child_dies_when_its_parent_is_sigkilled ... ok
[INFO] [stdout] test tied_child_dies_when_its_parent_is_killed_right_after_spawn ... ok
[INFO] [stdout] test untied_child_survives_its_parent_being_sigkilled ... ok
[INFO] [stderr]      Running tests/restart_health.rs (/opt/rustwide/target/release/build/rightkit-process/7687eb9fe24e004a/out/restart_health-7687eb9fe24e004a)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test consecutive_cap_applies_the_cooldown_then_starts_a_new_count ... ok
[INFO] [stdout] test automatic_restart_uses_bounded_backoff_then_exhausts_the_window ... ok
[INFO] [stdout] test consecutive_cap_with_zero_cooldown_ends_the_run_and_ready_prevents_it ... ok
[INFO] [stdout] test shutdown_invalidates_a_pending_restart_permit ... ok
[INFO] [stdout] test a_zero_cap_is_rejected ... ok
[INFO] [stdout] test on_demand_and_disabled_modes_never_restart_automatically ... ok
[INFO] [stdout] test ready_of_a_stale_generation_changes_nothing_and_budget_reset_is_opt_in ... ok
[INFO] [stdout] test stale_generation_exit_is_ignored ... ok
[INFO] [stdout] test a_new_restart_permit_invalidates_the_previous_permit ... ok
[INFO] [stderr]      Running tests/reveal_scope.rs (/opt/rustwide/target/release/build/rightkit-process/d874238846d1f8a6/out/reveal_scope-d874238846d1f8a6)
[INFO] [stdout] test health_threshold_and_startup_timeout_are_deterministic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.18s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test reveal_and_open_refuse_a_path_outside_every_root ... ok
[INFO] [stdout] test a_parent_traversal_out_of_the_root_is_refused ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/supervision_primitives.rs (/opt/rustwide/target/release/build/rightkit-process/059ea2fc35b5eb10/out/supervision_primitives-059ea2fc35b5eb10)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test capture_cooldown_rate_limits_expensive_samples ... ok
[INFO] [stdout] test a_dead_target_stops_the_watchdog_with_a_reason ... ok
[INFO] [stdout] test child::env_allowlist_and_strip_reach_the_real_child ... ok
[INFO] [stdout] test child::bounded_reap_kills_a_child_that_ignores_sigterm_within_the_bound ... ok
[INFO] [stdout] test child::stderr_tail_is_bounded_redacted_forwarded_and_drains_a_chatty_child ... ok
[INFO] [stdout] test a_descheduled_observer_is_not_mistaken_for_a_stalled_target ... ok
[INFO] [stdout] test stall_is_reported_once_per_episode_then_recovers_and_rearms ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.44s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/tree_identity.rs (/opt/rustwide/target/release/build/rightkit-process/a2503b47c3cc59fe/out/tree_identity-a2503b47c3cc59fe)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test grace_ends_early_when_nothing_but_the_exited_leader_remains ... ok
[INFO] [stdout] test wait_or_kill_removes_a_straggler_that_ignores_term_after_the_leader_exits ... ok
[INFO] [stdout] test terminating_before_the_reap_kills_the_survivor_even_after_the_leader_exited ... ok
[INFO] [stdout] test tree_grace_escalates_to_kill_for_a_straggler_after_the_leader_exits ... ok
[INFO] [stdout] test a_plain_wait_followed_by_drop_signals_nothing ... ok
[INFO] [stdout] test a_try_wait_reap_followed_by_terminate_tree_signals_nothing ... ok
[INFO] [stdout] test a_clone_that_reaps_retires_the_owner ... ok
[INFO] [stdout] test reaping_through_the_child_accessor_retires_group_signalling ... ok
[INFO] [stdout] test a_zero_grace_kill_never_fails_while_the_child_exits_during_it ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 9.92s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/unix_process_group.rs (/opt/rustwide/target/release/build/rightkit-process/0389e7c80f365ad8/out/unix_process_group-0389e7c80f365ad8)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test graceful_exit_does_not_report_a_forced_kill ... ok
[INFO] [stdout] test terminating_owned_tree_kills_parent_and_grandchild_but_not_sibling ... FAILED
[INFO] [stdout] test dropping_owned_child_leaves_no_orphan ... FAILED
[INFO] [stdout] test sigterm_shutdown_takes_the_grandchild_down_too ... FAILED
[INFO] [stdout] test grace_expiry_terminates_the_whole_tree ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- terminating_owned_tree_kills_parent_and_grandchild_but_not_sibling stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'terminating_owned_tree_kills_parent_and_grandchild_but_not_sibling' (2523) panicked at tests/unix_process_group.rs:62:9:
[INFO] [stdout] process 2535 remained alive
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56e937208d41 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56e93721d09a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56e93720d2bc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56e9371e4226 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56e937200cc9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56e9371937f0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56e937200e82 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56e9371e42d2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56e9371dcd09 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56e9371e4edd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56e93721d7cc - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56e937185762 - unix_process_group[81f7b932a8dfb3cc]::wait_until_stopped
[INFO] [stdout]   12:     0x56e93718680f - <unix_process_group[81f7b932a8dfb3cc]::terminating_owned_tree_kills_parent_and_grandchild_but_not_sibling::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x56e937186c3b - 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]   14:     0x56e937193e15 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x56e93718d704 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x56e937196ea2 - <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]   17:     0x56e93720823f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7bf57bbcadfa - <unknown>
[INFO] [stdout]   19:     0x7bf57bc5e3d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- dropping_owned_child_leaves_no_orphan stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'dropping_owned_child_leaves_no_orphan' (2519) panicked at tests/unix_process_group.rs:62:9:
[INFO] [stdout] process 2532 remained alive
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56e937208d41 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56e93721d09a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56e93720d2bc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56e9371e4226 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56e937200cc9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56e9371937f0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56e937200e82 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56e9371e42d2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56e9371dcd09 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56e9371e4edd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56e93721d7cc - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56e937185762 - unix_process_group[81f7b932a8dfb3cc]::wait_until_stopped
[INFO] [stdout]   12:     0x56e937185b9b - <unix_process_group[81f7b932a8dfb3cc]::dropping_owned_child_leaves_no_orphan::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x56e937186c3b - 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]   14:     0x56e937193e15 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x56e93718d704 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x56e937196ea2 - <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]   17:     0x56e93720823f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7bf57bbcadfa - <unknown>
[INFO] [stdout]   19:     0x7bf57bc5e3d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- sigterm_shutdown_takes_the_grandchild_down_too stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'sigterm_shutdown_takes_the_grandchild_down_too' (2522) panicked at tests/unix_process_group.rs:62:9:
[INFO] [stdout] process 2531 remained alive
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56e937208d41 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56e93721d09a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56e93720d2bc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56e9371e4226 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56e937200cc9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56e9371937f0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56e937200e82 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56e9371e42d2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56e9371dcd09 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56e9371e4edd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56e93721d7cc - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56e937185762 - unix_process_group[81f7b932a8dfb3cc]::wait_until_stopped
[INFO] [stdout]   12:     0x56e9371864a6 - <unix_process_group[81f7b932a8dfb3cc]::sigterm_shutdown_takes_the_grandchild_down_too::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x56e937186c3b - 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]   14:     0x56e937193e15 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x56e93718d704 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x56e937196ea2 - <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]   17:     0x56e93720823f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7bf57bbcadfa - <unknown>
[INFO] [stdout]   19:     0x7bf57bc5e3d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- grace_expiry_terminates_the_whole_tree stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'grace_expiry_terminates_the_whole_tree' (2520) panicked at tests/unix_process_group.rs:62:9:
[INFO] [stdout] process 2529 remained alive
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x56e937208d41 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x56e93721d09a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x56e93720d2bc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x56e9371e4226 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x56e937200cc9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x56e9371937f0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x56e937200e82 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x56e9371e42d2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x56e9371dcd09 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x56e9371e4edd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x56e93721d7cc - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x56e937185762 - unix_process_group[81f7b932a8dfb3cc]::wait_until_stopped
[INFO] [stdout]   12:     0x56e937185f68 - <unix_process_group[81f7b932a8dfb3cc]::grace_expiry_terminates_the_whole_tree::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x56e937186c3b - 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]   14:     0x56e937193e15 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x56e93718d704 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x56e937196ea2 - <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]   17:     0x56e93720823f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7bf57bbcadfa - <unknown>
[INFO] [stdout]   19:     0x7bf57bc5e3d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     dropping_owned_child_leaves_no_orphan
[INFO] [stdout]     grace_expiry_terminates_the_whole_tree
[INFO] [stdout]     sigterm_shutdown_takes_the_grandchild_down_too
[INFO] [stdout]     terminating_owned_tree_kills_parent_and_grandchild_but_not_sibling
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 1 passed; 4 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.09s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test unix_process_group`
[INFO] running `Command { std: "docker" "inspect" "0080d57d8559ef70982b33078a113d7f2d56f9e526748b1ceeebbd2548c02f3d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0080d57d8559ef70982b33078a113d7f2d56f9e526748b1ceeebbd2548c02f3d", kill_on_drop: false }`
[INFO] [stdout] 0080d57d8559ef70982b33078a113d7f2d56f9e526748b1ceeebbd2548c02f3d
