[INFO] fetching crate polyforge-toolrunner 0.4.1...
[INFO] testing polyforge-toolrunner-0.4.1 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate polyforge-toolrunner 0.4.1 into /workspace/builds/worker-0-tc2/source
[INFO] started tweaking crates.io crate polyforge-toolrunner 0.4.1
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate polyforge-toolrunner 0.4.1
[INFO] tweaked toml for crates.io crate polyforge-toolrunner 0.4.1 written to /workspace/builds/worker-0-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate polyforge-toolrunner 0.4.1 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 polyforge-toolrunner 0.4.1 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: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:34:12
[INFO] [stderr]    |
[INFO] [stderr] 34 | homepage = "https://github.com/tensorov/polyforge"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 39 | repository = "https://github.com/tensorov/polyforge"
[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: `polyforge-toolrunner` (manifest) generated 1 warning
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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] 1ef4fdd9fdcc41c490469fa1d9f079977698d10bf08de532240c898878e65f52
[INFO] running `Command { std: "docker" "start" "1ef4fdd9fdcc41c490469fa1d9f079977698d10bf08de532240c898878e65f52", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1ef4fdd9fdcc41c490469fa1d9f079977698d10bf08de532240c898878e65f52", 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" "1ef4fdd9fdcc41c490469fa1d9f079977698d10bf08de532240c898878e65f52" "/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" "1ef4fdd9fdcc41c490469fa1d9f079977698d10bf08de532240c898878e65f52", 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" "1ef4fdd9fdcc41c490469fa1d9f079977698d10bf08de532240c898878e65f52" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:34:12
[INFO] [stderr]    |
[INFO] [stderr] 34 | homepage = "https://github.com/tensorov/polyforge"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 39 | repository = "https://github.com/tensorov/polyforge"
[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: `polyforge-toolrunner` (manifest) generated 1 warning
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling fs2 v0.4.0
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling polyforge-core v0.4.0
[INFO] [stderr]    Compiling polyforge-toolrunner v0.4.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 19.83s
[INFO] running `Command { std: "docker" "inspect" "1ef4fdd9fdcc41c490469fa1d9f079977698d10bf08de532240c898878e65f52", 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" "1ef4fdd9fdcc41c490469fa1d9f079977698d10bf08de532240c898878e65f52" "/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] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:34:12
[INFO] [stderr]    |
[INFO] [stderr] 34 | homepage = "https://github.com/tensorov/polyforge"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 39 | repository = "https://github.com/tensorov/polyforge"
[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: `polyforge-toolrunner` (manifest) generated 1 warning
[INFO] [stderr]    Compiling polyforge-toolrunner v0.4.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 7.66s
[INFO] running `Command { std: "docker" "inspect" "1ef4fdd9fdcc41c490469fa1d9f079977698d10bf08de532240c898878e65f52", 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" "1ef4fdd9fdcc41c490469fa1d9f079977698d10bf08de532240c898878e65f52" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:34:12
[INFO] [stderr]    |
[INFO] [stderr] 34 | homepage = "https://github.com/tensorov/polyforge"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 39 | repository = "https://github.com/tensorov/polyforge"
[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: `polyforge-toolrunner` (manifest) generated 1 warning
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/polyforge-toolrunner/813e4899f2bb7ce3/out/polyforge_toolrunner-813e4899f2bb7ce3)
[INFO] [stdout] 
[INFO] [stdout] running 89 tests
[INFO] [stdout] test prober::tests::auto_prefers_firecracker_when_kvm_and_binaries_present ... ok
[INFO] [stdout] test prober::tests::error_display_is_actionable ... ok
[INFO] [stdout] test prober::tests::labels_match_attestation_metadata_prefixes ... ok
[INFO] [stdout] test prober::tests::auto_container_when_kvm_absent_docker_without_runsc ... ok
[INFO] [stdout] test prober::tests::auto_gvisor_happy_when_kvm_absent_docker_runsc ... ok
[INFO] [stdout] test prober::tests::auto_fails_closed_when_nothing_available ... ok
[INFO] [stdout] test prober::tests::auto_skips_kvm_without_fc_binaries ... ok
[INFO] [stdout] test prober::tests::auto_skips_fc_binaries_without_kvm ... ok
[INFO] [stdout] test prober::tests::override_firecracker_without_kvm_names_kvm ... ok
[INFO] [stdout] test prober::tests::override_container_happy_even_without_kvm_or_runsc ... ok
[INFO] [stdout] test prober::tests::override_gvisor_without_runsc_names_runsc ... ok
[INFO] [stdout] test prober::tests::override_gvisor_happy_returns_gvisor ... ok
[INFO] [stdout] test prober::tests::override_never_falls_back_to_weaker_tier ... ok
[INFO] [stdout] test prober::tests::override_gvisor_without_runtime_names_runtime ... ok
[INFO] [stdout] test prober::tests::override_container_without_runtime_names_runtime ... ok
[INFO] [stdout] test runner::tests::format_value_rule_is_eslint_scoped ... ok
[INFO] [stdout] test prober::tests::override_firecracker_with_kvm_but_no_binaries_names_binaries ... ok
[INFO] [stdout] test runner::tests::init_executor_process_is_idempotent ... ok
[INFO] [stdout] test runner::tests::arg_denylist_allows_benign_typed_args ... ok
[INFO] [stdout] test runner::tests::allowlist_v2_tools_are_lookupable ... ok
[INFO] [stdout] test runner::tests::arg_denylist_rejects_mutating_and_code_loading_args ... ok
[INFO] [stdout] test runner::tests::init_executor_sandbox_requires_feature ... ok
[INFO] [stdout] test runner::tests::kind_code_names_render_for_conflict_message ... ok
[INFO] [stdout] test runner::tests::process_backend_label_pinned_without_feature ... ok
[INFO] [stdout] test runner::tests::selection_defaults_to_process_without_init ... ok
[INFO] [stdout] test runner::tests::process_backend_yields_no_executor_digest ... ok
[INFO] [stdout] test runner::tests::pytest_single_token_no_plugin_arg_is_allowed ... ok
[INFO] [stdout] test runner::tests::test_absent_nix_devbox_render_none_token_and_stay_byte_stable ... ok
[INFO] [stdout] test runner::tests::test_allowlist_rejects_unknown_tool ... ok
[INFO] [stdout] test runner::tests::test_candidate_roots_walk_base_and_ancestors ... ok
[INFO] [stdout] test runner::tests::test_cargo_mutants_accepts_version_arg ... ok
[INFO] [stdout] test runner::tests::test_cargo_mutants_rejects_shell_interpolation ... ok
[INFO] [stdout] test runner::tests::test_env_fingerprint_allowlist_values_deterministic ... ok
[INFO] [stdout] test runner::tests::test_candidate_roots_prepends_git_root ... ok
[INFO] [stdout] test runner::tests::test_env_fingerprint_folds_allowlist_values ... ok
[INFO] [stdout] test runner::tests::spawn_uses_canonical_allowlist_entry_not_caller_bin ... ok
[INFO] [stdout] test runner::tests::test_attestation_has_tool_version_and_fingerprint ... ok
[INFO] [stdout] test runner::tests::test_args_are_passed_verbatim ... ok
[INFO] [stdout] test runner::tests::test_fingerprint_changes_when_nix_devbox_cargo_added ... ok
[INFO] [stdout] test runner::tests::test_fingerprint_identical_for_identical_inputs ... ok
[INFO] [stdout] test runner::tests::test_attestation_carries_longer_composite_fingerprint ... ok
[INFO] [stdout] test runner::tests::test_env_fingerprint_discovers_uv_lock_in_non_cargo_repo ... ok
[INFO] [stdout] test runner::tests::test_env_fingerprint_changes_with_tool_version ... ok
[INFO] [stdout] test runner::tests::test_env_fingerprint_discovers_uv_lock_under_workspace_root ... ok
[INFO] [stdout] test runner::tests::test_nix_digest_order_independent ... ok
[INFO] [stdout] test runner::tests::test_nix_segment_parser_validates_nix32_shape ... ok
[INFO] [stdout] test runner::tests::test_parse_timeout_defaults_and_overrides ... ok
[INFO] [stdout] test runner::tests::test_lookup_cargo_mutants ... ok
[INFO] [stdout] test runner::tests::test_env_fingerprint_no_lockfiles_up_tree_renders_none ... ok
[INFO] [stdout] test runner::tests::test_env_fingerprint_reads_real_workspace_lockfile ... ok
[INFO] [stdout] test runner::tests::test_env_fingerprint_stable_across_runs ... ok
[INFO] [stdout] test runner::tests::test_signal_killed_child_reports_minus_one ... ok
[INFO] [stderr] kill: (622): No such process
[INFO] [stdout] test runner::tests::test_hanging_tool_times_out ... ok
[INFO] [stdout] test runner::tests::test_kill_process_group_fallback_kills_child ... ok
[INFO] [stdout] test runner::tests::validate_arg_rejects_tab_metachar ... ok
[INFO] [stdout] test runner::tests::test_no_shell_used ... ok
[INFO] [stdout] test runner::tests::test_tool_version_is_real_output ... ok
[INFO] [stdout] test verify::tests::test_claim_git_mismatch_boolean_combos ... ok
[INFO] [stdout] test runner::tests::test_tool_version_falls_back_for_failing_tool ... ok
[INFO] [stderr] kill: (676): No such process
[INFO] [stdout] test runner::tests::test_timeout_kills_process_tree ... FAILED
[INFO] [stdout] test runner::tests::test_tool_version_reports_successful_tool_version ... FAILED
[INFO] [stdout] test runner::tests::test_wait_with_timeout_normal_completion ... FAILED
[INFO] [stdout] test verify::tests::test_appended_entry_is_verified_state ... FAILED
[INFO] [stdout] test verify::tests::test_git_state_from_marks_dirty_worktree ... FAILED
[INFO] [stdout] test runner::tests::test_run_with_timeout_allowlisted_tool ... FAILED
[INFO] [stdout] test verify::tests::test_discrepancy_ts_is_wallclock_epoch_millis ... FAILED
[INFO] [stdout] test verify::tests::test_legacy_path_without_tier_stays_untouched ... ok
[INFO] [stdout] test verify::tests::test_failed_run_without_claim_appends_nothing ... ok
[INFO] [stdout] test verify::tests::test_git_state_from_non_repo_fails_open ... ok
[INFO] [stdout] test verify::tests::test_tier_set_with_missing_digest_fails_closed ... ok
[INFO] [stdout] test verify::tests::test_failed_run_appends_discrepancy_entry ... FAILED
[INFO] [stdout] test verify::tests::test_tri_state_from_ledger_keeps_claim_state ... ok
[INFO] [stdout] test verify::tests::test_tier_set_with_present_digest_passes_through ... ok
[INFO] [stdout] test verify::tests::test_git_state_from_reports_real_head ... FAILED
[INFO] [stdout] test verify::tests::test_truncate_ascii_caps_at_limit ... ok
[INFO] [stdout] test verify::tests::test_truncate_ascii_never_returns_longer_than_limit ... ok
[INFO] [stdout] test verify::tests::test_attestation_ts_is_wallclock_epoch_millis ... FAILED
[INFO] [stdout] test verify::tests::test_tri_state_from_ledger_round_trips_verified ... ok
[INFO] [stdout] test verify::tests::test_verify_fails_on_tool_error ... FAILED
[INFO] [stdout] test verify::tests::test_tri_state_from_ledger_round_trips_validated ... ok
[INFO] [stdout] test verify::tests::test_verify_fails_without_claim ... ok
[INFO] [stdout] test verify::tests::test_tri_state_from_ledger_never_fabricates_validated ... ok
[INFO] [stdout] test verify::tests::test_truncate_utf8_loop_terminates ... ok
[INFO] [stdout] test verify::tests::test_verify_payload_carries_actual_git_state ... FAILED
[INFO] [stdout] test verify::tests::test_truncate_utf8_terminates_with_prefix ... ok
[INFO] [stdout] test verify::tests::test_verify_promotes_claim_with_tool_attestation ... FAILED
[INFO] [stdout] test verify::tests::test_verify_rejects_non_claim_entry ... ok
[INFO] [stdout] test verify::tests::test_verify_rejects_wrong_task_id ... ok
[INFO] [stdout] test verify::tests::test_truncate_utf8_preserves_codepoints ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- runner::tests::test_timeout_kills_process_tree stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'runner::tests::test_timeout_kills_process_tree' (619) panicked at src/runner.rs:1883:13:
[INFO] [stdout] grandchild 678 survived the process-group kill
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58410a7fc7e1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x58410a81262a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x58410a80135c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x58410a7d6106 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x58410a7f39d9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x58410a770600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x58410a7f3d02 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x58410a7d61b2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x58410a7ceb29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x58410a7d6ead - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x58410a812d5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x58410a72a4ac - <polyforge_toolrunner[3f20212ad3609a12]::runner::tests::test_timeout_kills_process_tree::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   12:     0x58410a763a4b - 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]   13:     0x58410a770c25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   14:     0x58410a76a514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   15:     0x58410a773cb2 - <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]   16:     0x58410a7fb7df - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   17:     0x7ab0bf255dfa - <unknown>
[INFO] [stdout]   18:     0x7ab0bf2e93d4 - clone
[INFO] [stdout]   19:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- runner::tests::test_tool_version_reports_successful_tool_version stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'runner::tests::test_tool_version_reports_successful_tool_version' (624) panicked at src/runner.rs:2000:51:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58410a7fc7e1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x58410a81262a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x58410a80135c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x58410a7d6106 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x58410a7f39d9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x58410a770600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x58410a7f3d02 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x58410a7d61b2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x58410a7ceb29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x58410a7d6ead - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x58410a812d5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x58410a812b32 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x58410a730cf3 - <polyforge_toolrunner[3f20212ad3609a12]::runner::tests::test_tool_version_reports_successful_tool_version::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x58410a763a4b - 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:     0x58410a770c25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x58410a76a514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x58410a773cb2 - <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:     0x58410a7fb7df - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7ab0bf255dfa - <unknown>
[INFO] [stdout]   19:     0x7ab0bf2e93d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- runner::tests::test_wait_with_timeout_normal_completion stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'runner::tests::test_wait_with_timeout_normal_completion' (627) panicked at src/runner.rs:1927:51:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58410a7fc7e1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x58410a81262a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x58410a80135c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x58410a7d6106 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x58410a7f39d9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x58410a770600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x58410a7f3d02 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x58410a7d61b2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x58410a7ceb29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x58410a7d6ead - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x58410a812d5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x58410a812b32 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x58410a72de9a - <polyforge_toolrunner[3f20212ad3609a12]::runner::tests::test_wait_with_timeout_normal_completion::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x58410a763a4b - 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:     0x58410a770c25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x58410a76a514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x58410a773cb2 - <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:     0x58410a7fb7df - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7ab0bf255dfa - <unknown>
[INFO] [stdout]   19:     0x7ab0bf2e93d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- verify::tests::test_appended_entry_is_verified_state stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'verify::tests::test_appended_entry_is_verified_state' (633) panicked at src/verify.rs:594:48:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58410a7fc7e1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x58410a81262a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x58410a80135c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x58410a7d6106 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x58410a7f39d9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x58410a770600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x58410a7f3d02 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x58410a7d61b2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x58410a7ceb29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x58410a7d6ead - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x58410a812d5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x58410a812b32 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x58410a7404c7 - <polyforge_toolrunner[3f20212ad3609a12]::verify::tests::test_appended_entry_is_verified_state::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x58410a763a4b - 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:     0x58410a770c25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x58410a76a514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x58410a773cb2 - <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:     0x58410a7fb7df - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7ab0bf255dfa - <unknown>
[INFO] [stdout]   19:     0x7ab0bf2e93d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- verify::tests::test_git_state_from_marks_dirty_worktree stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'verify::tests::test_git_state_from_marks_dirty_worktree' (682) panicked at src/verify.rs:838:48:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58410a7fc7e1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x58410a81262a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x58410a80135c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x58410a7d6106 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x58410a7f39d9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x58410a770600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x58410a7f3d02 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x58410a7d61b2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x58410a7ceb29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x58410a7d6ead - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x58410a812d5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x58410a812b32 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x58410a7421cd - <polyforge_toolrunner[3f20212ad3609a12]::verify::tests::test_git_state_from_marks_dirty_worktree::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x58410a763a4b - 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:     0x58410a770c25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x58410a76a514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x58410a773cb2 - <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:     0x58410a7fb7df - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7ab0bf255dfa - <unknown>
[INFO] [stdout]   19:     0x7ab0bf2e93d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- runner::tests::test_run_with_timeout_allowlisted_tool stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'runner::tests::test_run_with_timeout_allowlisted_tool' (617) panicked at src/runner.rs:1936:51:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58410a7fc7e1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x58410a81262a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x58410a80135c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x58410a7d6106 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x58410a7f39d9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x58410a770600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x58410a7f3d02 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x58410a7d61b2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x58410a7ceb29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x58410a7d6ead - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x58410a812d5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x58410a812b32 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x58410a72d8ff - <polyforge_toolrunner[3f20212ad3609a12]::runner::tests::test_run_with_timeout_allowlisted_tool::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x58410a763a4b - 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:     0x58410a770c25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x58410a76a514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x58410a773cb2 - <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:     0x58410a7fb7df - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7ab0bf255dfa - <unknown>
[INFO] [stdout]   19:     0x7ab0bf2e93d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- verify::tests::test_discrepancy_ts_is_wallclock_epoch_millis stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'verify::tests::test_discrepancy_ts_is_wallclock_epoch_millis' (675) panicked at src/verify.rs:529:48:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58410a7fc7e1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x58410a81262a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x58410a80135c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x58410a7d6106 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x58410a7f39d9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x58410a770600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x58410a7f3d02 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x58410a7d61b2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x58410a7ceb29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x58410a7d6ead - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x58410a812d5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x58410a812b32 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x58410a745736 - <polyforge_toolrunner[3f20212ad3609a12]::verify::tests::test_discrepancy_ts_is_wallclock_epoch_millis::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x58410a763a4b - 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:     0x58410a770c25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x58410a76a514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x58410a773cb2 - <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:     0x58410a7fb7df - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7ab0bf255dfa - <unknown>
[INFO] [stdout]   19:     0x7ab0bf2e93d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- verify::tests::test_failed_run_appends_discrepancy_entry stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'verify::tests::test_failed_run_appends_discrepancy_entry' (677) panicked at src/verify.rs:431:48:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58410a7fc7e1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x58410a81262a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x58410a80135c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x58410a7d6106 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x58410a7f39d9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x58410a770600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x58410a7f3d02 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x58410a7d61b2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x58410a7ceb29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x58410a7d6ead - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x58410a812d5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x58410a812b32 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x58410a74292d - <polyforge_toolrunner[3f20212ad3609a12]::verify::tests::test_failed_run_appends_discrepancy_entry::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x58410a763a4b - 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:     0x58410a770c25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x58410a76a514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x58410a773cb2 - <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:     0x58410a7fb7df - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7ab0bf255dfa - <unknown>
[INFO] [stdout]   19:     0x7ab0bf2e93d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- verify::tests::test_git_state_from_reports_real_head stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'verify::tests::test_git_state_from_reports_real_head' (684) panicked at src/verify.rs:824:48:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58410a7fc7e1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x58410a81262a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x58410a80135c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x58410a7d6106 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x58410a7f39d9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x58410a770600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x58410a7f3d02 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x58410a7d61b2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x58410a7ceb29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x58410a7d6ead - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x58410a812d5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x58410a812b32 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x58410a740b2a - <polyforge_toolrunner[3f20212ad3609a12]::verify::tests::test_git_state_from_reports_real_head::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x58410a763a4b - 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:     0x58410a770c25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x58410a76a514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x58410a773cb2 - <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:     0x58410a7fb7df - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7ab0bf255dfa - <unknown>
[INFO] [stdout]   19:     0x7ab0bf2e93d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- verify::tests::test_attestation_ts_is_wallclock_epoch_millis stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'verify::tests::test_attestation_ts_is_wallclock_epoch_millis' (651) panicked at src/verify.rs:504:48:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58410a7fc7e1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x58410a81262a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x58410a80135c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x58410a7d6106 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x58410a7f39d9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x58410a770600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x58410a7f3d02 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x58410a7d61b2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x58410a7ceb29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x58410a7d6ead - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x58410a812d5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x58410a812b32 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x58410a744f51 - <polyforge_toolrunner[3f20212ad3609a12]::verify::tests::test_attestation_ts_is_wallclock_epoch_millis::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x58410a763a4b - 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:     0x58410a770c25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x58410a76a514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x58410a773cb2 - <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:     0x58410a7fb7df - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7ab0bf255dfa - <unknown>
[INFO] [stdout]   19:     0x7ab0bf2e93d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- verify::tests::test_verify_fails_on_tool_error stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'verify::tests::test_verify_fails_on_tool_error' (697) panicked at src/verify.rs:572:48:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58410a7fc7e1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x58410a81262a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x58410a80135c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x58410a7d6106 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x58410a7f39d9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x58410a770600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x58410a7f3d02 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x58410a7d61b2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x58410a7ceb29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x58410a7d6ead - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x58410a812d5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x58410a812b32 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x58410a73ed81 - <polyforge_toolrunner[3f20212ad3609a12]::verify::tests::test_verify_fails_on_tool_error::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x58410a763a4b - 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:     0x58410a770c25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x58410a76a514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x58410a773cb2 - <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:     0x58410a7fb7df - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7ab0bf255dfa - <unknown>
[INFO] [stdout]   19:     0x7ab0bf2e93d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- verify::tests::test_verify_payload_carries_actual_git_state stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'verify::tests::test_verify_payload_carries_actual_git_state' (699) panicked at src/verify.rs:366:42:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58410a7fc7e1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x58410a81262a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x58410a80135c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x58410a7d6106 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x58410a7f39d9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x58410a770600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x58410a7f3d02 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x58410a7d61b2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x58410a7ceb29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x58410a7d6ead - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x58410a812d5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x58410a812b32 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x58410a7440bd - <polyforge_toolrunner[3f20212ad3609a12]::verify::tests::test_verify_payload_carries_actual_git_state::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x58410a763a4b - 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:     0x58410a770c25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x58410a76a514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x58410a773cb2 - <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:     0x58410a7fb7df - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7ab0bf255dfa - <unknown>
[INFO] [stdout]   19:     0x7ab0bf2e93d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- verify::tests::test_verify_promotes_claim_with_tool_attestation stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'verify::tests::test_verify_promotes_claim_with_tool_attestation' (700) panicked at src/verify.rs:476:48:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: PoisonError { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58410a7fc7e1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x58410a81262a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x58410a80135c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x58410a7d6106 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x58410a7f39d9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x58410a770600 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x58410a7f3d02 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x58410a7d61b2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x58410a7ceb29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x58410a7d6ead - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x58410a812d5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x58410a812b32 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x58410a746db4 - <polyforge_toolrunner[3f20212ad3609a12]::verify::tests::test_verify_promotes_claim_with_tool_attestation::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x58410a763a4b - 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:     0x58410a770c25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x58410a76a514 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x58410a773cb2 - <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:     0x58410a7fb7df - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7ab0bf255dfa - <unknown>
[INFO] [stdout]   19:     0x7ab0bf2e93d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     runner::tests::test_run_with_timeout_allowlisted_tool
[INFO] [stdout]     runner::tests::test_timeout_kills_process_tree
[INFO] [stdout]     runner::tests::test_tool_version_reports_successful_tool_version
[INFO] [stdout]     runner::tests::test_wait_with_timeout_normal_completion
[INFO] [stdout]     verify::tests::test_appended_entry_is_verified_state
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] [stdout]     verify::tests::test_attestation_ts_is_wallclock_epoch_millis
[INFO] [stdout]     verify::tests::test_discrepancy_ts_is_wallclock_epoch_millis
[INFO] [stdout]     verify::tests::test_failed_run_appends_discrepancy_entry
[INFO] [stdout]     verify::tests::test_git_state_from_marks_dirty_worktree
[INFO] [stdout]     verify::tests::test_git_state_from_reports_real_head
[INFO] [stdout]     verify::tests::test_verify_fails_on_tool_error
[INFO] [stdout]     verify::tests::test_verify_payload_carries_actual_git_state
[INFO] [stdout]     verify::tests::test_verify_promotes_claim_with_tool_attestation
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 76 passed; 13 failed; 0 ignored; 0 measured; 0 filtered out; finished in 6.58s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "1ef4fdd9fdcc41c490469fa1d9f079977698d10bf08de532240c898878e65f52", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1ef4fdd9fdcc41c490469fa1d9f079977698d10bf08de532240c898878e65f52", kill_on_drop: false }`
[INFO] [stdout] 1ef4fdd9fdcc41c490469fa1d9f079977698d10bf08de532240c898878e65f52
