[INFO] cloning repository https://github.com/emirror-de/pork
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/emirror-de/pork" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Femirror-de%2Fpork", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Femirror-de%2Fpork'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 987017ae5d9b5b038df30a2d2b4d6bcfab5a340f
[INFO] testing emirror-de/pork against 1.100.0-beta.1 for beta-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Femirror-de%2Fpork" "/workspace/builds/worker-3-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-3-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/emirror-de/pork
[INFO] finished tweaking git repo https://github.com/emirror-de/pork
[INFO] tweaked toml for git repo https://github.com/emirror-de/pork written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/emirror-de/pork 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 git repo https://github.com/emirror-de/pork 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] [stderr] warning: unused workspace dependency `serde_json`
[INFO] [stderr]   --> Cargo.toml:16:25
[INFO] [stderr]    |
[INFO] [stderr] 16 | [workspace.dependencies.serde_json]
[INFO] [stderr]    |                         ^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_workspace_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the workspace dependency `serde_json`
[INFO] [stderr] warning: workspace (manifest) generated 1 warning
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> pork/Cargo.toml:11:12
[INFO] [stderr]    |
[INFO] [stderr]  8 | repository = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |              ------------------------------------
[INFO] [stderr] ...
[INFO] [stderr] 11 | homepage = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `pork` (manifest) generated 1 warning
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> pork-proto/Cargo.toml:10:12
[INFO] [stderr]    |
[INFO] [stderr]  8 | repository = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |              ------------------------------------
[INFO] [stderr]  9 | description = "Shared protocol types and codecs for pork control messages, typed IPC envelopes, and dual-channel workflows"
[INFO] [stderr] 10 | homepage = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `pork-proto` (manifest) generated 1 warning
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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] a865d07fb87ef192aebff20d8962220a61b455c918c9bd718c0dca7cc5c70125
[INFO] running `Command { std: "docker" "start" "a865d07fb87ef192aebff20d8962220a61b455c918c9bd718c0dca7cc5c70125", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a865d07fb87ef192aebff20d8962220a61b455c918c9bd718c0dca7cc5c70125", 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" "a865d07fb87ef192aebff20d8962220a61b455c918c9bd718c0dca7cc5c70125" "/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" "a865d07fb87ef192aebff20d8962220a61b455c918c9bd718c0dca7cc5c70125", 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" "a865d07fb87ef192aebff20d8962220a61b455c918c9bd718c0dca7cc5c70125" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr] warning: unused workspace dependency `serde_json`
[INFO] [stderr]   --> Cargo.toml:16:25
[INFO] [stderr]    |
[INFO] [stderr] 16 | [workspace.dependencies.serde_json]
[INFO] [stderr]    |                         ^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_workspace_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the workspace dependency `serde_json`
[INFO] [stderr] warning: workspace (manifest) generated 1 warning
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> pork-proto/Cargo.toml:10:12
[INFO] [stderr]    |
[INFO] [stderr]  8 | repository = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |              ------------------------------------
[INFO] [stderr]  9 | description = "Shared protocol types and codecs for pork control messages, typed IPC envelopes, and dual-channel workflows"
[INFO] [stderr] 10 | homepage = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `pork-proto` (manifest) generated 1 warning
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> pork/Cargo.toml:11:12
[INFO] [stderr]    |
[INFO] [stderr]  8 | repository = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |              ------------------------------------
[INFO] [stderr] ...
[INFO] [stderr] 11 | homepage = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `pork` (manifest) generated 1 warning
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling libc v0.2.189
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling semver v1.0.28
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling crossbeam-utils v0.8.22
[INFO] [stderr]    Compiling zmij v1.0.23
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling futures-core v0.3.34
[INFO] [stderr]    Compiling spin v0.9.9
[INFO] [stderr]    Compiling bitflags v2.13.1
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling pin-project-lite v0.2.17
[INFO] [stderr]    Compiling fastrand v2.5.0
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling itoa v1.0.18
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling hash32 v0.2.1
[INFO] [stderr]    Compiling futures-channel v0.3.34
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling futures-task v0.3.34
[INFO] [stderr]    Compiling rustc_version v0.4.1
[INFO] [stderr]    Compiling bytes v1.12.1
[INFO] [stderr]    Compiling rustc-hash v2.1.3
[INFO] [stderr]    Compiling heapless v0.7.17
[INFO] [stderr]    Compiling crossbeam-channel v0.5.16
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling mio v1.2.2
[INFO] [stderr]    Compiling socket2 v0.6.5
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling uuid v1.24.0
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling thiserror-impl v2.0.20
[INFO] [stderr]    Compiling futures-macro v0.3.34
[INFO] [stderr]    Compiling tokio-macros v2.7.2
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling futures-util v0.3.34
[INFO] [stderr]    Compiling cobs v0.3.0
[INFO] [stderr]    Compiling postcard v1.1.3
[INFO] [stderr]    Compiling pork-proto v2.0.0 (/opt/rustwide/workdir/pork-proto)
[INFO] [stderr]    Compiling ipc-channel v0.22.0
[INFO] [stderr]    Compiling pork v2.0.0 (/opt/rustwide/workdir/pork)
[INFO] [stderr]    Compiling pork-comms v2.0.0 (/opt/rustwide/workdir/examples/pork-comms)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 28.32s
[INFO] running `Command { std: "docker" "inspect" "a865d07fb87ef192aebff20d8962220a61b455c918c9bd718c0dca7cc5c70125", 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" "a865d07fb87ef192aebff20d8962220a61b455c918c9bd718c0dca7cc5c70125" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr] warning: unused workspace dependency `serde_json`
[INFO] [stderr]   --> Cargo.toml:16:25
[INFO] [stderr]    |
[INFO] [stderr] 16 | [workspace.dependencies.serde_json]
[INFO] [stderr]    |                         ^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_workspace_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the workspace dependency `serde_json`
[INFO] [stderr] warning: workspace (manifest) generated 1 warning
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> pork-proto/Cargo.toml:10:12
[INFO] [stderr]    |
[INFO] [stderr]  8 | repository = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |              ------------------------------------
[INFO] [stderr]  9 | description = "Shared protocol types and codecs for pork control messages, typed IPC envelopes, and dual-channel workflows"
[INFO] [stderr] 10 | homepage = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `pork-proto` (manifest) generated 1 warning
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> pork/Cargo.toml:11:12
[INFO] [stderr]    |
[INFO] [stderr]  8 | repository = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |              ------------------------------------
[INFO] [stderr] ...
[INFO] [stderr] 11 | homepage = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `pork` (manifest) generated 1 warning
[INFO] [stderr]    Compiling pork-proto v2.0.0 (/opt/rustwide/workdir/pork-proto)
[INFO] [stderr]    Compiling pork v2.0.0 (/opt/rustwide/workdir/pork)
[INFO] [stderr]    Compiling pork-comms v2.0.0 (/opt/rustwide/workdir/examples/pork-comms)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 8.92s
[INFO] running `Command { std: "docker" "inspect" "a865d07fb87ef192aebff20d8962220a61b455c918c9bd718c0dca7cc5c70125", 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" "a865d07fb87ef192aebff20d8962220a61b455c918c9bd718c0dca7cc5c70125" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] warning: unused workspace dependency `serde_json`
[INFO] [stderr]   --> Cargo.toml:16:25
[INFO] [stderr]    |
[INFO] [stderr] 16 | [workspace.dependencies.serde_json]
[INFO] [stderr]    |                         ^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_workspace_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the workspace dependency `serde_json`
[INFO] [stderr] warning: workspace (manifest) generated 1 warning
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> pork-proto/Cargo.toml:10:12
[INFO] [stderr]    |
[INFO] [stderr]  8 | repository = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |              ------------------------------------
[INFO] [stderr]  9 | description = "Shared protocol types and codecs for pork control messages, typed IPC envelopes, and dual-channel workflows"
[INFO] [stderr] 10 | homepage = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `pork-proto` (manifest) generated 1 warning
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> pork/Cargo.toml:11:12
[INFO] [stderr]    |
[INFO] [stderr]  8 | repository = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |              ------------------------------------
[INFO] [stderr] ...
[INFO] [stderr] 11 | homepage = "https://github.com/emirror-de/pork"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `pork` (manifest) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/pork/34b15d09c1efb70c/out/pork-34b15d09c1efb70c)
[INFO] [stdout] 
[INFO] [stdout] running 24 tests
[INFO] [stdout] test error::tests::control_codec_forwards_its_source ... ok
[INFO] [stdout] test child::bootstrap::tests::child_bootstrap_channels_debug_format ... ok
[INFO] [stdout] test error::tests::display_output_is_stable ... ok
[INFO] [stdout] test error::tests::error_is_send_sync_static ... ok
[INFO] [stdout] test child::bootstrap::tests::child_bootstrap_from_env_with_missing_data_var ... ok
[INFO] [stdout] test child::bootstrap::tests::child_bootstrap_from_default_env_constructor_is_available ... ok
[INFO] [stdout] test child::bootstrap::tests::control_and_data_sender_types_exist ... ok
[INFO] [stdout] test child::bootstrap::tests::child_bootstrap_new_creates_instance ... ok
[INFO] [stdout] test error::tests::leaf_variants_have_no_source ... ok
[INFO] [stdout] test error::tests::ipc_forwards_its_source ... ok
[INFO] [stdout] test host::tests::bootstrap_env_applies_to_command ... ok
[INFO] [stdout] test child::status_reporter::tests::current_time_ms_is_monotonic ... ok
[INFO] [stdout] test host::tests::bootstrap_env_debug_format ... ok
[INFO] [stdout] test host::tests::host_bootstrap_new_creates_instance ... ok
[INFO] [stdout] test host::tests::bootstrap_env_stores_server_names ... ok
[INFO] [stdout] test orchestrator::tests::cleanup_reserved_name_after_failed_start_keeps_other_owner_registration ... ok
[INFO] [stdout] test error::tests::io_forwards_its_source ... ok
[INFO] [stdout] test host::tests::host_bootstrap_channels_debug_format ... ok
[INFO] [stdout] test types::tests::map_stream_item_distinguishes_message_close_and_ipc_error ... ok
[INFO] [stdout] test child::bootstrap::tests::control_worker_progresses_while_data_queue_is_full ... ok
[INFO] [stdout] test orchestrator::tests::cleanup_reserved_name_after_failed_start_waits_for_lock_and_removes_name ... ok
[INFO] [stdout] test child::status_reporter::tests::current_time_ms_returns_positive_value ... ok
[INFO] [stdout] test host::tests::bootstrap_env_can_be_cloned ... ok
[INFO] [stdout] test child::bootstrap::tests::control_worker_surfaces_invalid_payload_and_keeps_receiving ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 24 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/control_events.rs (/opt/rustwide/target/debug/build/pork/0bc56e88ba2eaae5/out/control_events-0bc56e88ba2eaae5)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test control_events_test_child_entrypoint ... ok
[INFO] [stdout] test subscribe_control_events_rejects_unknown_process ... ok
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test control_events_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 2 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stdout] test observes_child_control_messages_including_ignored_lifecycle_messages ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/dependencies.rs (/opt/rustwide/target/debug/build/pork/52cbd447a7909e50/out/dependencies-52cbd447a7909e50)
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test delayed_dep_test_child_entrypoint ... ok
[INFO] [stdout] test process_spec_depends_on_all_and_accessor ... ok
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test non_running_dep_test_child_entrypoint ... ok
[INFO] [stdout] test process_spec_depends_on_defaults_to_empty ... ok
[INFO] [stdout] test start_process_returns_dependency_not_found_for_unknown_name ... ok
[INFO] [stdout] test dep_test_child_entrypoint ... ok
[INFO] [stdout] test start_process_returns_dependency_cycle_for_self_dependency ... ok
[INFO] [stdout] test heartbeat_only_dep_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test dep_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 13 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] test process_without_dependencies_starts_normally ... ok
[INFO] [stdout] test dep_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 13 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stdout] test dep_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 13 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] test duplicate_start_keeps_original_name_registration ... ok
[INFO] [stdout] test dep_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 13 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stdout] test process_starts_when_dependency_is_already_running ... ok
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test dep_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 13 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] test delayed_dep_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 13 filtered out; finished in 0.16s
[INFO] [stdout] 
[INFO] [stdout] test dependent_waits_for_child_reported_running ... ok
[INFO] [stdout] test heartbeat_only_dep_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 13 filtered out; finished in 0.23s
[INFO] [stdout] 
[INFO] [stdout] test heartbeat_only_dependency_times_out ... ok
[INFO] [stdout] test non_running_dep_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 13 filtered out; finished in 0.35s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/heartbeat.rs (/opt/rustwide/target/debug/build/pork/dc984da1fd2d1e56/out/heartbeat-dc984da1fd2d1e56)
[INFO] [stdout] test start_process_returns_dependency_timeout_when_dep_not_ready_in_time ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.36s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test heartbeat_only_test_child_entrypoint ... ok
[INFO] [stdout] test heartbeat_status_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test heartbeat_only_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 3 filtered out; finished in 0.36s
[INFO] [stdout] 
[INFO] [stdout] test heartbeat_without_status_update_leaves_child_status_none ... ok
[INFO] [stdout] test heartbeat_status_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 3 filtered out; finished in 0.66s
[INFO] [stdout] 
[INFO] [stdout] test heartbeat_preserves_lifecycle_status_and_refreshes_timestamp ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.67s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/public_api.rs (/opt/rustwide/target/debug/build/pork/cffd312bf4d4e6d9/out/public_api-cffd312bf4d4e6d9)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test process_spec_defaults_are_predictable_and_documented ... ok
[INFO] [stdout] test process_spec_log_output_uses_one_append_target_for_both_streams ... ok
[INFO] [stdout] test process_spec_without_managed_name_clears_previous_value ... ok
[INFO] [stdout] test child_status_by_name_reports_unknown_process ... ok
[INFO] [stdout] test process_spec_exposes_configured_fields_through_accessors ... ok
[INFO] [stdout] test managed_child_identity_accessors_are_predictable ... ok
[INFO] [stdout] test child_status_reports_unknown_process ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/restart.rs (/opt/rustwide/target/debug/build/pork/11e7f288ca12cc1c/out/restart-11e7f288ca12cc1c)
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test process_id_by_name_returns_not_found_shape_for_unknown_name ... ok
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test logfile_output_is_inspectable_while_child_is_running ... ok
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test bootstrap_cleanup_subprocess_entrypoint ... ok
[INFO] [stdout] test failed_start_process_cleans_up_name_registration ... ok
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] test orchestrator_restart_process_after_name_lookup_restarts_named_child ... ok
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test orchestrator_restart_process_replaces_child_and_preserves_managed_name_via_process_id ... ok
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] test orchestrator_reports_running_status_for_started_child ... ok
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] test handshake_failure_releases_name_and_preserves_error_context ... ok
[INFO] [stdout] test graceful_shutdown_timeout_force_kills_and_cleans_up_stubborn_child ... ok
[INFO] [stdout] test orchestrator_reports_stopping_status_after_graceful_shutdown_request ... ok
[INFO] [stdout] test unexpected_child_exit_is_reconciled_on_lookup ... ok
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] test startup_timeout_releases_name_after_partial_bootstrap ... ok
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] test startup_timeout_releases_name_after_no_bootstrap_connection ... ok
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] test bootstrap_cleanup_subprocess_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.57s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] test bootstrap_cleanup_subprocess_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.54s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stdout] test bootstrap_cleanup_subprocess_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.18s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test restart_test_child_entrypoint ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr] 
[INFO] [stderr] thread 'bootstrap_cleanup_subprocess_entrypoint' (2235) panicked at pork/tests/restart.rs:133:23:
[INFO] [stderr] healthy child should start after cleanup: timed out waiting for process 2 to finish startup
[INFO] [stderr] stack backtrace:
[INFO] [stderr]    0:     0x5cd55dd525c1 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stderr]    1:     0x5cd55dd525c1 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::trace_unsynchronized::<std[617be2ca0a6fd877]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stderr]    2:     0x5cd55dd525c1 - std[617be2ca0a6fd877]::sys::backtrace::_print_fmt
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:74:9
[INFO] [stderr]    3:     0x5cd55dd525c1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:44:26
[INFO] [stderr]    4:     0x5cd55dd67aba - <core[a373ad73a504971]::fmt::rt::Argument>::fmt
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/fmt/rt.rs:152:76
[INFO] [stderr]    5:     0x5cd55dd67aba - core[a373ad73a504971]::fmt::write
[INFO] [stderr]    6:     0x5cd55dd57ccc - core[a373ad73a504971]::io::write::default_write_fmt::<std[617be2ca0a6fd877]::sys::stdio::unix::Stderr>
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:402:11
[INFO] [stderr]    7:     0x5cd55dd57ccc - <std[617be2ca0a6fd877]::sys::stdio::unix::Stderr as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:335:13
[INFO] [stderr]    8:     0x5cd55dd2c476 - <std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:47:9
[INFO] [stderr]    9:     0x5cd55dd2c476 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:289:27
[INFO] [stderr]   10:     0x5cd55dd498e1 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:316:9
[INFO] [stderr]   11:     0x5cd55dd49c0b - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:811:13
[INFO] [stderr]   12:     0x5cd55dd2c522 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:685:13
[INFO] [stderr]   13:     0x5cd55dd24f89 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:182:18
[INFO] [stderr]   14:     0x5cd55dd2d12d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:676:5
[INFO] [stderr]   15:     0x5cd55dd682dc - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:80:14
[INFO] [stderr]   16:     0x5cd55db0131a - restart[f235880ba9cff242]::start_and_shutdown_healthy_child::{closure#0}
[INFO] [stderr]                                at /opt/rustwide/workdir/pork/tests/restart.rs:133:23
[INFO] [stderr]   17:     0x5cd55db00e51 - restart[f235880ba9cff242]::run_early_exit_cleanup_scenario::{closure#0}
[INFO] [stderr]                                at /opt/rustwide/workdir/pork/tests/restart.rs:684:59
[INFO] [stderr]   18:     0x5cd55db003c5 - restart[f235880ba9cff242]::run_handshake_failure_scenario::{closure#0}
[INFO] [stderr]                                at /opt/rustwide/workdir/pork/tests/restart.rs:689:10
[INFO] [stderr]   19:     0x5cd55db0485d - restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0}
[INFO] [stderr]                                at /opt/rustwide/workdir/pork/tests/restart.rs:840:50
[INFO] [stderr]   20:     0x5cd55db36a6d - <core[a373ad73a504971]::pin::Pin<alloc[2e1b2a31b91974c5]::boxed::Box<restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0}>> as core[a373ad73a504971]::future::future::Future>::poll
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/future/future.rs:133:9
[INFO] [stderr]   21:     0x5cd55db4c17d - <tokio[1bce2bec9dfe6751]::runtime::park::CachedParkThread>::block_on::<core[a373ad73a504971]::pin::Pin<alloc[2e1b2a31b91974c5]::boxed::Box<restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0}>>>::{closure#0}
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/park.rs:284:71
[INFO] [stderr]   22:     0x5cd55db490b2 - tokio[1bce2bec9dfe6751]::task::coop::with_budget::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1bce2bec9dfe6751]::runtime::park::CachedParkThread>::block_on<core[a373ad73a504971]::pin::Pin<alloc[2e1b2a31b91974c5]::boxed::Box<restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0}>>>::{closure#0}>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/task/coop/mod.rs:167:5
[INFO] [stderr]   23:     0x5cd55db490b2 - tokio[1bce2bec9dfe6751]::task::coop::budget::<core[a373ad73a504971]::task::poll::Poll<()>, <tokio[1bce2bec9dfe6751]::runtime::park::CachedParkThread>::block_on<core[a373ad73a504971]::pin::Pin<alloc[2e1b2a31b91974c5]::boxed::Box<restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0}>>>::{closure#0}>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/task/coop/mod.rs:133:5
[INFO] [stderr]   24:     0x5cd55db490b2 - <tokio[1bce2bec9dfe6751]::runtime::park::CachedParkThread>::block_on::<core[a373ad73a504971]::pin::Pin<alloc[2e1b2a31b91974c5]::boxed::Box<restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0}>>>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/park.rs:284:31
[INFO] [stderr]   25:     0x5cd55db31570 - <tokio[1bce2bec9dfe6751]::runtime::context::blocking::BlockingRegionGuard>::block_on::<core[a373ad73a504971]::pin::Pin<alloc[2e1b2a31b91974c5]::boxed::Box<restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0}>>>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/context/blocking.rs:66:14
[INFO] [stderr]   26:     0x5cd55db5f5de - <tokio[1bce2bec9dfe6751]::runtime::scheduler::multi_thread::MultiThread>::block_on::<core[a373ad73a504971]::pin::Pin<alloc[2e1b2a31b91974c5]::boxed::Box<restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0}>>>::{closure#0}
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/scheduler/multi_thread/mod.rs:92:22
[INFO] [stderr]   27:     0x5cd55db72f7e - tokio[1bce2bec9dfe6751]::runtime::context::runtime::enter_runtime::<<tokio[1bce2bec9dfe6751]::runtime::scheduler::multi_thread::MultiThread>::block_on<core[a373ad73a504971]::pin::Pin<alloc[2e1b2a31b91974c5]::boxed::Box<restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0}>>>::{closure#0}, ()>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/context/runtime.rs:65:16
[INFO] [stderr]   28:     0x5cd55db59021 - <tokio[1bce2bec9dfe6751]::runtime::scheduler::multi_thread::MultiThread>::block_on::<core[a373ad73a504971]::pin::Pin<alloc[2e1b2a31b91974c5]::boxed::Box<restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0}>>>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/scheduler/multi_thread/mod.rs:91:9
[INFO] [stderr]   29:     0x5cd55db3e8f7 - <tokio[1bce2bec9dfe6751]::runtime::runtime::Runtime>::block_on_inner::<core[a373ad73a504971]::pin::Pin<alloc[2e1b2a31b91974c5]::boxed::Box<restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0}>>>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/runtime.rs:376:50
[INFO] [stderr]   30:     0x5cd55db3f9bc - <tokio[1bce2bec9dfe6751]::runtime::runtime::Runtime>::block_on::<restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0}>
[INFO] [stderr]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.53.1/src/runtime/runtime.rs:341:18
[INFO] [stderr]   31:     0x5cd55db12129 - restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint
[INFO] [stderr]                                at /opt/rustwide/workdir/pork/tests/restart.rs:821:13
[INFO] [stderr]   32:     0x5cd55db02477 - restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0}
[INFO] [stderr]                                at /opt/rustwide/workdir/pork/tests/restart.rs:811:45
[INFO] [stderr]   33:     0x5cd55dafe0a6 - <restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_entrypoint::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stderr]   34:     0x5cd55db83d0b - <fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stderr]   35:     0x5cd55db83d0b - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:701:18
[INFO] [stderr]   36:     0x5cd55db90ee5 - test[aada8e7e76ba119b]::run_test_in_process::{closure#0}
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:74
[INFO] [stderr]   37:     0x5cd55db90ee5 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stderr]   38:     0x5cd55db90ee5 - 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] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stderr]   39:     0x5cd55db90ee5 - 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] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stderr]   40:     0x5cd55db90ee5 - 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] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stderr]   41:     0x5cd55db90ee5 - test[aada8e7e76ba119b]::run_test_in_process
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:27
[INFO] [stderr]   42:     0x5cd55db90ee5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:645:43
[INFO] [stderr]   43:     0x5cd55db8a7d4 - test[aada8e7e76ba119b]::run_test::{closure#1}
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:675:41
[INFO] [stderr]   44:     0x5cd55db8a7d4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:166:18
[INFO] [stderr]   45:     0x5cd55db93f72 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stderr]   46:     0x5cd55db93f72 - <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] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stderr]   47:     0x5cd55db93f72 - 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] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stderr]   48:     0x5cd55db93f72 - 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] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stderr]   49:     0x5cd55db93f72 - 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] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stderr]   50:     0x5cd55db93f72 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stderr]   51:     0x5cd55db93f72 - <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] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stderr]   52:     0x5cd55dd51aaf - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn core[a373ad73a504971]::ops::function::FnOnce<(), Output = ()> + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2314:9
[INFO] [stderr]   53:     0x5cd55dd51aaf - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stderr]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stderr]   54:     0x715ef2846dfa - <unknown>
[INFO] [stderr]   55:     0x715ef28da3d4 - clone
[INFO] [stderr]   56:                0x0 - <unknown>
[INFO] [stdout] test bootstrap_cleanup_subprocess_entrypoint ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     bootstrap_cleanup_subprocess_entrypoint
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 14 filtered out; finished in 0.40s
[INFO] [stdout] 
[INFO] [stdout] test bootstrap_cleanup_subprocess_scenarios_exit_before_watchdog ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- bootstrap_cleanup_subprocess_scenarios_exit_before_watchdog stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'bootstrap_cleanup_subprocess_scenarios_exit_before_watchdog' (1776) panicked at pork/tests/restart.rs:590:17:
[INFO] [stdout] bootstrap cleanup scenario handshake-failure failed with status ExitStatus(unix_wait_status(25856))
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5e25dce2c5c1 - 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:     0x5e25dce2c5c1 - 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:     0x5e25dce2c5c1 - std[617be2ca0a6fd877]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5e25dce2c5c1 - <<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:     0x5e25dce41aba - <core[a373ad73a504971]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5e25dce41aba - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    6:     0x5e25dce30ebc - 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:     0x5e25dce30ebc - <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:     0x5e25dce06476 - <std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5e25dce06476 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:289:27
[INFO] [stdout]   10:     0x5e25dce23919 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:313:9
[INFO] [stdout]   11:     0x5e25dcc6a8c0 - <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:     0x5e25dcc6a8c0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:137:21
[INFO] [stdout]   13:     0x5e25dce23c42 - <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:     0x5e25dce23c42 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:819:13
[INFO] [stdout]   15:     0x5e25dce06522 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:685:13
[INFO] [stdout]   16:     0x5e25dcdfef89 - 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:     0x5e25dce0712d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:676:5
[INFO] [stdout]   18:     0x5e25dce422dc - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5e25dcbeabe9 - restart[f235880ba9cff242]::run_bootstrap_cleanup_subprocess
[INFO] [stdout]                                at /opt/rustwide/workdir/pork/tests/restart.rs:590:17
[INFO] [stdout]   20:     0x5e25dcbecca1 - restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_scenarios_exit_before_watchdog
[INFO] [stdout]                                at /opt/rustwide/workdir/pork/tests/restart.rs:567:9
[INFO] [stdout]   21:     0x5e25dcbdc597 - restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_scenarios_exit_before_watchdog::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/pork/tests/restart.rs:558:65
[INFO] [stdout]   22:     0x5e25dcbd8166 - <restart[f235880ba9cff242]::bootstrap_cleanup_subprocess_scenarios_exit_before_watchdog::{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]   23:     0x5e25dcc5dd0b - <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]   24:     0x5e25dcc5dd0b - 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]   25:     0x5e25dcc6aee5 - test[aada8e7e76ba119b]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:74
[INFO] [stdout]   26:     0x5e25dcc6aee5 - <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]   27:     0x5e25dcc6aee5 - 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]   28:     0x5e25dcc6aee5 - 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]   29:     0x5e25dcc6aee5 - 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]   30:     0x5e25dcc6aee5 - test[aada8e7e76ba119b]::run_test_in_process
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:27
[INFO] [stdout]   31:     0x5e25dcc6aee5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:645:43
[INFO] [stdout]   32:     0x5e25dcc647d4 - test[aada8e7e76ba119b]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:675:41
[INFO] [stdout]   33:     0x5e25dcc647d4 - 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]   34:     0x5e25dcc6df72 - 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]   35:     0x5e25dcc6df72 - <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]   36:     0x5e25dcc6df72 - 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]   37:     0x5e25dcc6df72 - 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]   38:     0x5e25dcc6df72 - 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]   39:     0x5e25dcc6df72 - 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]   40:     0x5e25dcc6df72 - <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]   41:     0x5e25dce2baaf - <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]   42:     0x5e25dce2baaf - <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]   43:     0x76e50ab07dfa - <unknown>
[INFO] [stdout]   44:     0x76e50ab9b3d4 - clone
[INFO] [stdout]   45:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     bootstrap_cleanup_subprocess_scenarios_exit_before_watchdog
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 14 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.78s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `-p pork --test restart`
[INFO] running `Command { std: "docker" "inspect" "a865d07fb87ef192aebff20d8962220a61b455c918c9bd718c0dca7cc5c70125", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a865d07fb87ef192aebff20d8962220a61b455c918c9bd718c0dca7cc5c70125", kill_on_drop: false }`
[INFO] [stdout] a865d07fb87ef192aebff20d8962220a61b455c918c9bd718c0dca7cc5c70125
