[INFO] fetching crate procpilot 0.7.0...
[INFO] testing procpilot-0.7.0 against 1.95.0 for beta-1.96-2
[INFO] extracting crate procpilot 0.7.0 into /workspace/builds/worker-0-tc1/source
[INFO] started tweaking crates.io crate procpilot 0.7.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate procpilot 0.7.0
[INFO] tweaked toml for crates.io crate procpilot 0.7.0 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate procpilot 0.7.0 on toolchain 1.95.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.95.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate procpilot 0.7.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.95.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded os_pipe v1.2.3
[INFO] [stderr]   Downloaded shared_child v1.1.1
[INFO] [stderr]   Downloaded backon v1.6.0
[INFO] [stderr]   Downloaded sigchld v0.2.4
[INFO] [stderr]   Downloaded tokio v1.52.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 5feab730ec554ed9e7d1afa6dc1a5dc8e19ea80b4fd4c271ca157f66857c7ade
[INFO] running `Command { std: "docker" "start" "-a" "5feab730ec554ed9e7d1afa6dc1a5dc8e19ea80b4fd4c271ca157f66857c7ade", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "5feab730ec554ed9e7d1afa6dc1a5dc8e19ea80b4fd4c271ca157f66857c7ade", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5feab730ec554ed9e7d1afa6dc1a5dc8e19ea80b4fd4c271ca157f66857c7ade", kill_on_drop: false }`
[INFO] [stdout] 5feab730ec554ed9e7d1afa6dc1a5dc8e19ea80b4fd4c271ca157f66857c7ade
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 589288ee4b0cb2ccb17ac7825336094eba772ab06ff27e8f5ba794890975c7f2
[INFO] running `Command { std: "docker" "start" "-a" "589288ee4b0cb2ccb17ac7825336094eba772ab06ff27e8f5ba794890975c7f2", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.185
[INFO] [stderr]    Compiling signal-hook v0.3.18
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling backon v1.6.0
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling os_pipe v1.2.3
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling sigchld v0.2.4
[INFO] [stderr]    Compiling shared_child v1.1.1
[INFO] [stderr]    Compiling procpilot v0.7.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 3.14s
[INFO] running `Command { std: "docker" "inspect" "589288ee4b0cb2ccb17ac7825336094eba772ab06ff27e8f5ba794890975c7f2", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "589288ee4b0cb2ccb17ac7825336094eba772ab06ff27e8f5ba794890975c7f2", kill_on_drop: false }`
[INFO] [stdout] 589288ee4b0cb2ccb17ac7825336094eba772ab06ff27e8f5ba794890975c7f2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 27c9da78cd4ca4ded2d19587b0b05db7188dce24c2d4341fda39832bbb61e181
[INFO] running `Command { std: "docker" "start" "-a" "27c9da78cd4ca4ded2d19587b0b05db7188dce24c2d4341fda39832bbb61e181", kill_on_drop: false }`
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling backon v1.6.0
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling procpilot v0.7.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling tokio v1.52.0
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 7.36s
[INFO] running `Command { std: "docker" "inspect" "27c9da78cd4ca4ded2d19587b0b05db7188dce24c2d4341fda39832bbb61e181", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "27c9da78cd4ca4ded2d19587b0b05db7188dce24c2d4341fda39832bbb61e181", kill_on_drop: false }`
[INFO] [stdout] 27c9da78cd4ca4ded2d19587b0b05db7188dce24c2d4341fda39832bbb61e181
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.95.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] a9dc4a41e69cbf9020cd1b411afe91e6d98eeeab00ef956265ba736edfccd46c
[INFO] running `Command { std: "docker" "start" "-a" "a9dc4a41e69cbf9020cd1b411afe91e6d98eeeab00ef956265ba736edfccd46c", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/procpilot-898101c4d5b66c1f)
[INFO] [stdout] 
[INFO] [stdout] running 69 tests
[INFO] [stdout] test cmd::tests::display_respects_secret_via_cmd_display ... ok
[INFO] [stdout] test cmd::tests::display_on_cmd_matches_cmd_display ... ok
[INFO] [stdout] test cmd::tests::bitor_builds_pipeline ... ok
[INFO] [stdout] test cmd::tests::clone_shares_bytes_stdin_cheaply ... ok
[INFO] [stdout] test cmd::tests::flatten_owned_preserves_order ... ok
[INFO] [stdout] test cmd::tests::flatten_preserves_left_to_right_order_on_deep_pipeline ... ok
[INFO] [stdout] test cmd::tests::must_use_annotation_present ... ok
[INFO] [stdout] test cmd::tests::per_attempt_timeout_respects_both_bounds ... ok
[INFO] [stdout] test cmd::tests::pipe_builds_tree_and_args_target_rightmost ... ok
[INFO] [stdout] test cmd::tests::reader_stdin_shared_across_clones_is_one_shot ... ok
[INFO] [stdout] test cmd::tests::to_commands_returns_all_stages_left_to_right ... ok
[INFO] [stdout] test cmd::tests::to_rightmost_command_returns_rightmost_for_pipeline ... ok
[INFO] [stdout] test cmd_display::tests::args_with_spaces_are_quoted ... ok
[INFO] [stdout] test cmd_display::tests::embedded_single_quote_escaped ... ok
[INFO] [stdout] test cmd_display::tests::empty_arg_quoted ... ok
[INFO] [stdout] test cmd_display::tests::pipeline_renders_with_separator ... ok
[INFO] [stdout] test cmd_display::tests::pipeline_with_secret_redacts_every_stage ... ok
[INFO] [stdout] test cmd_display::tests::secret_preserves_program ... ok
[INFO] [stdout] test cmd_display::tests::simple_command ... ok
[INFO] [stdout] test error::tests::display_non_zero_exit_with_stderr ... ok
[INFO] [stdout] test error::tests::display_spawn_failure ... ok
[INFO] [stdout] test cmd_display::tests::secret_redacts_args ... ok
[INFO] [stdout] test error::tests::display_timeout ... ok
[INFO] [stdout] test cmd_display::tests::special_chars_quoted ... ok
[INFO] [stdout] test error::tests::error_source_none_for_non_spawn ... ok
[INFO] [stdout] test error::tests::exit_status_only_for_non_zero_exit ... ok
[INFO] [stdout] test error::tests::predicates ... ok
[INFO] [stdout] test error::tests::stderr_only_for_completed_or_timed_out ... ok
[INFO] [stdout] test error::tests::program_returns_name ... ok
[INFO] [stdout] test error::tests::secret_command_in_display ... ok
[INFO] [stdout] test cmd_display::tests::paths_unquoted ... ok
[INFO] [stdout] test error::tests::stdout_only_for_completed_or_timed_out ... ok
[INFO] [stdout] test error::tests::truncate_suffix_keeps_short_buffers ... ok
[INFO] [stdout] test error::tests::truncate_suffix_string_drops_from_front ... ok
[INFO] [stdout] test error::tests::wraps_into_anyhow ... ok
[INFO] [stdout] test redirection::tests::default_is_capture ... ok
[INFO] [stdout] test error::tests::truncate_suffix_string_keeps_short ... ok
[INFO] [stdout] test retry::tests::debug_does_not_panic ... ok
[INFO] [stdout] test retry::tests::default_transient_retries_lock ... ok
[INFO] [stdout] test retry::tests::default_transient_retries_stale ... ok
[INFO] [stdout] test retry::tests::default_transient_skips_other_nonzero ... ok
[INFO] [stdout] test retry::tests::default_transient_skips_spawn ... ok
[INFO] [stdout] test error::tests::truncate_suffix_drops_from_front ... ok
[INFO] [stdout] test error::tests::truncate_suffix_string_preserves_utf8 ... ok
[INFO] [stdout] test retry::tests::default_transient_skips_timeout ... ok
[INFO] [stdout] test cmd::tests::builder_accumulates_args_on_single ... ok
[INFO] [stdout] test cmd::tests::combine_outcomes_prefers_rightmost_failure ... ok
[INFO] [stdout] test cmd::tests::display_renders_pipeline ... ok
[INFO] [stdout] test cmd::tests::secret_flag_propagates_through_pipe ... ok
[INFO] [stdout] test cmd_display::tests::debug_impl_works ... ok
[INFO] [stdout] test cmd_display::tests::debug_respects_secret_flag ... ok
[INFO] [stdout] test cmd_display::tests::no_args ... ok
[INFO] [stdout] test redirection::tests::variants_are_constructible ... ok
[INFO] [stdout] test retry::tests::custom_predicate ... ok
[INFO] [stdout] test cmd::tests::cmd_is_clone_and_divergent_after_clone ... ok
[INFO] [stdout] test cmd::tests::env_builder_targets_rightmost ... ok
[INFO] [stdout] test cmd_display::tests::safe_chars_unquoted ... ok
[INFO] [stdout] test error::tests::error_source_for_spawn ... ok
[INFO] [stdout] test stdin::tests::from_reader_produces_reader_variant ... ok
[INFO] [stdout] test stdin::tests::from_str ... ok
[INFO] [stdout] test stdin::tests::from_vec_u8 ... ok
[INFO] [stdout] test stdin::tests::debug_impl_redacts_reader_contents ... ok
[INFO] [stdout] test spawned::tests::pipefail_all_success ... ok
[INFO] [stdout] test stdin::tests::from_byte_slice ... ok
[INFO] [stdout] test spawned::tests::pipefail_only_failure_wins_over_later_success ... ok
[INFO] [stdout] test runner::tests::binary_version_missing_returns_none ... ok
[INFO] [stdout] test runner::tests::binary_available_missing_returns_false ... ok
[INFO] [stdout] test spawned::tests::pipefail_rightmost_failure_wins ... ok
[INFO] [stdout] test stdin::tests::from_string ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 69 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/non_exhaustive.rs (/opt/rustwide/target/debug/deps/non_exhaustive-f954324e8b209ada)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/prelude.rs (/opt/rustwide/target/debug/deps/prelude-f8b3e2bb075d2434)
[INFO] [stdout] test run_error_stays_non_exhaustive ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test prelude_compiles ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stderr]    Doc-tests procpilot
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test src/cmd.rs - cmd (line 18) - compile ... ok
[INFO] [stdout] test src/cmd.rs - cmd (line 3) - compile ... ok
[INFO] [stdout] test src/cmd.rs - cmd::Cmd::pipe (line 343) - compile ... ok
[INFO] [stdout] test src/cmd.rs - cmd::Cmd::run (line 708) - compile ... ok
[INFO] [stdout] test src/cmd.rs - cmd::Cmd::run (line 729) - compile ... ok
[INFO] [stdout] test src/cmd.rs - cmd::Cmd::run (line 717) - compile ... ok
[INFO] [stdout] test src/error.rs - error::RunError (line 32) - compile ... ok
[INFO] [stdout] test src/lib.rs - (line 11) - compile ... ok
[INFO] [stdout] test src/cmd.rs - cmd::Cmd::spawn_and_collect_lines (line 650) - compile ... ok
[INFO] [stdout] test src/runner_trait.rs - runner_trait (line 12) - compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.57s; merged doctests compilation took 0.55s
[INFO] running `Command { std: "docker" "inspect" "a9dc4a41e69cbf9020cd1b411afe91e6d98eeeab00ef956265ba736edfccd46c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a9dc4a41e69cbf9020cd1b411afe91e6d98eeeab00ef956265ba736edfccd46c", kill_on_drop: false }`
[INFO] [stdout] a9dc4a41e69cbf9020cd1b411afe91e6d98eeeab00ef956265ba736edfccd46c
