[INFO] fetching crate orchestrator-scheduler 0.3.0...
[INFO] testing orchestrator-scheduler-0.3.0 against try#33835004928d3bf65db4d4712e1330766263b0bd for pr-155739
[INFO] extracting crate orchestrator-scheduler 0.3.0 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate orchestrator-scheduler 0.3.0
[INFO] finished tweaking crates.io crate orchestrator-scheduler 0.3.0
[INFO] tweaked toml for crates.io crate orchestrator-scheduler 0.3.0 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate orchestrator-scheduler 0.3.0 on toolchain 33835004928d3bf65db4d4712e1330766263b0bd
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate orchestrator-scheduler 0.3.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" "+33835004928d3bf65db4d4712e1330766263b0bd" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/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" "+33835004928d3bf65db4d4712e1330766263b0bd" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 4ea7990733ed339e854eb9cbfc64b855f9ef5b17d5f40eb54dfcb7783a3c13e7
[INFO] running `Command { std: "docker" "start" "-a" "4ea7990733ed339e854eb9cbfc64b855f9ef5b17d5f40eb54dfcb7783a3c13e7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4ea7990733ed339e854eb9cbfc64b855f9ef5b17d5f40eb54dfcb7783a3c13e7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4ea7990733ed339e854eb9cbfc64b855f9ef5b17d5f40eb54dfcb7783a3c13e7", kill_on_drop: false }`
[INFO] [stdout] 4ea7990733ed339e854eb9cbfc64b855f9ef5b17d5f40eb54dfcb7783a3c13e7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+33835004928d3bf65db4d4712e1330766263b0bd" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c2c9196dd194b6c4761b0b1f34548eecf6f178dec065e4556266e8b8bd75f9a8
[INFO] running `Command { std: "docker" "start" "-a" "c2c9196dd194b6c4761b0b1f34548eecf6f178dec065e4556266e8b8bd75f9a8", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.183
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling anyhow v1.0.102
[INFO] [stderr]    Compiling zerocopy v0.8.47
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling generic-array v0.14.7
[INFO] [stderr]    Compiling siphasher v1.0.2
[INFO] [stderr]    Compiling futures-task v0.3.32
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling prettyplease v0.2.37
[INFO] [stderr]    Compiling pulldown-cmark v0.13.3
[INFO] [stderr]    Compiling foldhash v0.1.5
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling unicase v2.9.0
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling cc v1.2.58
[INFO] [stderr]    Compiling hashbrown v0.15.5
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling fixedbitset v0.5.7
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling inout v0.1.4
[INFO] [stderr]    Compiling hybrid-array v0.4.10
[INFO] [stderr]    Compiling multimap v0.10.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling axum-core v0.5.6
[INFO] [stderr]    Compiling phf_shared v0.11.3
[INFO] [stderr]    Compiling protoc-bin-vendored-linux-x86_64 v3.2.0
[INFO] [stderr]    Compiling protoc-bin-vendored-macos-aarch_64 v3.2.0
[INFO] [stderr]    Compiling pulldown-cmark-to-cmark v22.0.0
[INFO] [stderr]    Compiling cfg_aliases v0.2.1
[INFO] [stderr]    Compiling petgraph v0.8.3
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling protoc-bin-vendored-linux-x86_32 v3.2.0
[INFO] [stderr]    Compiling protoc-bin-vendored-macos-x86_64 v3.2.0
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling protoc-bin-vendored-linux-aarch_64 v3.2.0
[INFO] [stderr]    Compiling protoc-bin-vendored-linux-s390_64 v3.2.0
[INFO] [stderr]    Compiling protoc-bin-vendored-win32 v3.2.0
[INFO] [stderr]    Compiling protoc-bin-vendored-linux-ppcle_64 v3.2.0
[INFO] [stderr]    Compiling matchit v0.8.4
[INFO] [stderr]    Compiling crypto-common v0.2.1
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling phf_generator v0.11.3
[INFO] [stderr]    Compiling protoc-bin-vendored v3.2.0
[INFO] [stderr]    Compiling block-buffer v0.12.0
[INFO] [stderr]    Compiling murmur3 v0.4.1
[INFO] [stderr]    Compiling nix v0.31.2
[INFO] [stderr]    Compiling fallible-iterator v0.3.0
[INFO] [stderr]    Compiling better_any v0.2.1
[INFO] [stderr]    Compiling typed-arena v2.0.2
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling phf_shared v0.12.1
[INFO] [stderr]    Compiling num-conv v0.2.1
[INFO] [stderr]    Compiling time-core v0.1.8
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling uuid v1.23.0
[INFO] [stderr]    Compiling libsqlite3-sys v0.28.0
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling cipher v0.4.4
[INFO] [stderr]    Compiling universal-hash v0.5.1
[INFO] [stderr]    Compiling aead v0.5.2
[INFO] [stderr]    Compiling digest v0.11.2
[INFO] [stderr]    Compiling chrono-tz v0.10.4
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling polyval v0.6.2
[INFO] [stderr]    Compiling ctr v0.9.2
[INFO] [stderr]    Compiling aes v0.8.4
[INFO] [stderr]    Compiling zeroize v1.8.2
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling dirs-sys v0.5.0
[INFO] [stderr]    Compiling antlr4rust v0.3.0-rc2
[INFO] [stderr]    Compiling sha2 v0.11.0
[INFO] [stderr]    Compiling time v0.3.47
[INFO] [stderr]    Compiling phf v0.12.1
[INFO] [stderr]    Compiling aes-gcm-siv v0.11.1
[INFO] [stderr]    Compiling winnow v0.7.15
[INFO] [stderr]    Compiling agent-orchestrator v0.3.0
[INFO] [stderr]    Compiling arc-swap v1.9.0
[INFO] [stderr]    Compiling dirs v6.0.0
[INFO] [stderr]    Compiling pathdiff v0.2.3
[INFO] [stderr]    Compiling orchestrator-scheduler v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling tonic-build v0.14.5
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling prost-derive v0.14.3
[INFO] [stderr]    Compiling pin-project-internal v1.1.11
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling phf_macros v0.11.3
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling cel-parser v0.10.1
[INFO] [stderr]    Compiling pin-project v1.1.11
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling phf v0.11.3
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling prost v0.14.3
[INFO] [stderr]    Compiling hashlink v0.9.1
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling prost-types v0.14.3
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling prost-build v0.14.3
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling serde_yaml v0.9.34+deprecated
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling tonic-prost-build v0.14.5
[INFO] [stderr]    Compiling orchestrator-proto v0.3.0
[INFO] [stderr]    Compiling orchestrator-config v0.3.0
[INFO] [stderr]    Compiling cron v0.16.0
[INFO] [stderr]    Compiling cel-interpreter v0.10.0
[INFO] [stderr]    Compiling tracing-appender v0.2.4
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling tokio-stream v0.1.18
[INFO] [stderr]    Compiling h2 v0.4.13
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling axum v0.8.8
[INFO] [stderr]    Compiling rusqlite v0.31.0
[INFO] [stderr]    Compiling tokio-rusqlite v0.5.1
[INFO] [stderr]    Compiling hyper v1.8.1
[INFO] [stderr]    Compiling orchestrator-runner v0.3.0
[INFO] [stderr]    Compiling orchestrator-security v0.3.0
[INFO] [stderr]    Compiling orchestrator-collab v0.3.0
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling hyper-timeout v0.5.2
[INFO] [stderr]    Compiling tonic v0.14.5
[INFO] [stderr]    Compiling tonic-prost v0.14.5
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 50s
[INFO] running `Command { std: "docker" "inspect" "c2c9196dd194b6c4761b0b1f34548eecf6f178dec065e4556266e8b8bd75f9a8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c2c9196dd194b6c4761b0b1f34548eecf6f178dec065e4556266e8b8bd75f9a8", kill_on_drop: false }`
[INFO] [stdout] c2c9196dd194b6c4761b0b1f34548eecf6f178dec065e4556266e8b8bd75f9a8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 45f1321c75d5552f5a9aee39eadf802d407d4d8fe395f69d6c249f3652e74a42
[INFO] running `Command { std: "docker" "start" "-a" "45f1321c75d5552f5a9aee39eadf802d407d4d8fe395f69d6c249f3652e74a42", kill_on_drop: false }`
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stderr]    Compiling agent-orchestrator v0.3.0
[INFO] [stderr]    Compiling orchestrator-scheduler v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling pulldown-cmark v0.13.3
[INFO] [stderr]    Compiling rusqlite v0.31.0
[INFO] [stderr]    Compiling nix v0.31.2
[INFO] [stderr]    Compiling orchestrator-runner v0.3.0
[INFO] [stderr]    Compiling pulldown-cmark-to-cmark v22.0.0
[INFO] [stderr]    Compiling orchestrator-security v0.3.0
[INFO] [stderr]    Compiling tokio-rusqlite v0.5.1
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling prost-build v0.14.3
[INFO] [stderr]    Compiling tonic-prost-build v0.14.5
[INFO] [stderr]    Compiling orchestrator-proto v0.3.0
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 49.53s
[INFO] running `Command { std: "docker" "inspect" "45f1321c75d5552f5a9aee39eadf802d407d4d8fe395f69d6c249f3652e74a42", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "45f1321c75d5552f5a9aee39eadf802d407d4d8fe395f69d6c249f3652e74a42", kill_on_drop: false }`
[INFO] [stdout] 45f1321c75d5552f5a9aee39eadf802d407d4d8fe395f69d6c249f3652e74a42
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/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=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-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" "+33835004928d3bf65db4d4712e1330766263b0bd" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] bd4cce172f86defe470ac4e296a36cfbe377ceee6a6a0807740a9f7a6835c627
[INFO] running `Command { std: "docker" "start" "-a" "bd4cce172f86defe470ac4e296a36cfbe377ceee6a6a0807740a9f7a6835c627", kill_on_drop: false }`
[INFO] [stderr]    Compiling orchestrator-scheduler v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 22.32s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/orchestrator_scheduler-ca69120c0401afae)
[INFO] [stdout] 
[INFO] [stdout] running 436 tests
[INFO] [stdout] test scheduler::check::tests::json_roundtrip ... ok
[INFO] [stdout] test scheduler::check::tests::pipe_to_unknown ... ok
[INFO] [stdout] test scheduler::check::tests::prompt_delivery_arg_no_warning ... ok
[INFO] [stdout] test scheduler::check::tests::template_pipeline_var_ok ... ok
[INFO] [stdout] test scheduler::check::tests::builtin_unknown ... ok
[INFO] [stdout] test scheduler::check::tests::prompt_delivery_stdin_warns_on_prompt_placeholder ... ok
[INFO] [stdout] test scheduler::check::tests::workspace_root_missing ... ok
[INFO] [stdout] test scheduler::check::tests::chain_steps_checked ... ok
[INFO] [stdout] test scheduler::check::tests::command_steps_skip_capability_requirement ... ok
[INFO] [stdout] test scheduler::check::tests::empty_workflow ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_evaluate_assertion_eq ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_evaluate_assertion_invalid_number ... ok
[INFO] [stdout] test scheduler::check::tests::prompt_delivery_file_warns_missing_prompt_file_placeholder ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_evaluate_assertion_neq ... ok
[INFO] [stdout] test scheduler::check::tests::template_unknown_var ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_evaluate_assertion_whitespace_handling ... ok
[INFO] [stdout] test scheduler::check::tests::execution_mode_mismatch ... ok
[INFO] [stdout] test scheduler::check::tests::step_semantic_conflict ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_file_matches_pattern_exact ... ok
[INFO] [stdout] test scheduler::check::tests::qa_targets_missing ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_file_matches_pattern_no_wildcard_exact ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_evaluate_assertion_unsupported_expression ... ok
[INFO] [stdout] test scheduler::check::tests::clean_config_no_errors ... ok
[INFO] [stdout] test scheduler::check::tests::template_system_var_ok ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_evaluate_invariants_empty_list ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_file_matches_pattern_prefix_glob ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_has_halting_violation ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_has_halting_violation_empty_results ... ok
[INFO] [stdout] test scheduler::check::tests::agent_missing_command ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_file_matches_pattern_suffix_glob ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_invariant_on_violation_preserved ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_invariant_with_assert_expr_passing ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_invariant_with_assert_expr_failing ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_failing_invariant ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_has_rollback_violation_empty_results ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_invariant_command_error_reported ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_evaluate_invariants_filters_by_checkpoint ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_invariant_with_expected_exit ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_evaluate_invariants_all_matching_checkpoints ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_no_halting_violation_when_warn ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_no_rollback_violation_when_all_pass ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_no_rollback_violation_when_halt ... ok
[INFO] [stdout] test scheduler::item_executor::dispatch::step_vars_tests::overlay_adds_new_key ... ok
[INFO] [stdout] test scheduler::item_executor::dispatch::step_vars_tests::overlay_overrides_existing_key ... ok
[INFO] [stdout] test scheduler::item_executor::dispatch::step_vars_tests::full_overlay_restore_roundtrip ... ok
[INFO] [stdout] test scheduler::item_executor::dispatch::step_vars_tests::restore_reverts_overridden_keys ... ok
[INFO] [stdout] test scheduler::item_executor::dispatch::step_vars_tests::restore_removes_new_keys ... ok
[INFO] [stdout] test scheduler::item_executor::tests::apply_captures_exit_code ... ok
[INFO] [stdout] test scheduler::item_executor::tests::apply_captures_failed_flag ... ok
[INFO] [stdout] test scheduler::item_executor::tests::apply_captures_stdout_json_path_extracts_stream_json_score ... ok
[INFO] [stdout] test scheduler::item_executor::tests::apply_captures_multiple ... ok
[INFO] [stdout] test scheduler::item_executor::tests::accumulator_new_initializes_with_pending_status ... ok
[INFO] [stdout] test scheduler::item_executor::tests::apply_captures_stderr ... ok
[INFO] [stdout] test scheduler::item_executor::tests::apply_captures_stdout_json_path_extracts_score ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_passing_invariant ... ok
[INFO] [stdout] test scheduler::item_executor::dispatch::step_vars_tests::overlay_none_returns_clone ... ok
[INFO] [stdout] test scheduler::item_executor::tests::apply_captures_stderr_no_output_is_noop ... ok
[INFO] [stdout] test scheduler::check::tests::capability_no_agent ... ok
[INFO] [stdout] test scheduler::item_executor::tests::apply_captures_stdout_json_path_falls_back_to_empty_string_on_missing_field ... ok
[INFO] [stdout] test scheduler::item_executor::tests::apply_captures_stdout_no_output_is_noop ... ok
[INFO] [stdout] test scheduler::item_executor::tests::evaluate_builtin_loop_guard_continues_when_flag_disabled ... ok
[INFO] [stdout] test scheduler::item_executor::tests::evaluate_builtin_loop_guard_continues_when_flag_disabled_and_has_items ... ok
[INFO] [stdout] test scheduler::item_executor::tests::apply_captures_success_flag ... ok
[INFO] [stdout] test scheduler::item_executor::tests::builtin_guard_noop_for_agent_step ... ok
[INFO] [stdout] test scheduler::item_executor::tests::evaluate_builtin_loop_guard_continues_when_has_unresolved ... ok
[INFO] [stdout] test scheduler::item_executor::tests::auto_capture_falls_back_to_raw_stdout_for_non_stream_json ... ok
[INFO] [stdout] test scheduler::item_executor::tests::apply_captures_stdout_spills_under_task_logs_dir ... ok
[INFO] [stdout] test scheduler::item_executor::tests::evaluate_builtin_loop_guard_stops_when_no_unresolved ... ok
[INFO] [stdout] test scheduler::item_executor::tests::auto_capture_extracts_stream_json_result_for_spill ... ok
[INFO] [stdout] test scheduler::item_executor::tests::execution_hard_failure_detects_failed_validation_status ... ok
[INFO] [stdout] test scheduler::item_executor::tests::apply_captures_success_flag_on_failure ... ok
[INFO] [stdout] test scheduler::item_executor::tests::builtin_guard_noop_when_already_correct ... ok
[INFO] [stdout] test scheduler::item_executor::tests::execution_hard_failure_ignores_non_validation_failures ... ok
[INFO] [stdout] test scheduler::item_executor::tests::builtin_guard_routes_self_test_regardless_of_execution_mode ... ok
[INFO] [stdout] test scheduler::item_executor::tests::merge_task_pipeline_vars_copies_build_errors_when_empty ... ok
[INFO] [stdout] test scheduler::item_executor::tests::benchmark_score_capture_can_drive_item_select_max ... ok
[INFO] [stdout] test scheduler::item_executor::tests::auto_capture_stream_json_large_result_spills_only_extracted_text ... ok
[INFO] [stdout] test scheduler::item_executor::tests::merge_task_pipeline_vars_does_not_overwrite_existing ... ok
[INFO] [stdout] test scheduler::item_executor::tests::parse_guard_output_continue_false_means_stop ... ok
[INFO] [stdout] test scheduler::item_executor::tests::parse_guard_output_continue_true_means_dont_stop ... ok
[INFO] [stdout] test scheduler::item_executor::tests::parse_guard_output_invalid_json_defaults_to_continue ... ok
[INFO] [stdout] test scheduler::item_executor::tests::parse_guard_output_missing_fields_defaults_to_continue ... ok
[INFO] [stdout] test scheduler::item_executor::tests::parse_guard_output_should_stop_takes_precedence_over_continue ... ok
[INFO] [stdout] test scheduler::item_executor::tests::parse_guard_output_should_stop_false ... ok
[INFO] [stdout] test scheduler::item_executor::tests::parse_guard_output_should_stop_true ... ok
[INFO] [stdout] test scheduler::item_executor::tests::merge_task_pipeline_vars_preserves_existing_build_errors ... ok
[INFO] [stdout] test scheduler::item_executor::tests::parse_guard_output_empty_string_defaults_to_continue ... ok
[INFO] [stdout] test scheduler::item_executor::tests::spill_large_var_large_value_sets_correct_path_key ... ok
[INFO] [stdout] test scheduler::item_executor::tests::spill_large_var_multibyte_boundary ... ok
[INFO] [stdout] test scheduler::item_executor::tests::spill_large_var_exactly_at_limit_inserts_inline ... ok
[INFO] [stdout] test scheduler::item_executor::tests::spill_to_file_large_value_truncated_format ... ok
[INFO] [stdout] test scheduler::item_executor::tests::spill_to_file_exactly_at_limit_returns_none ... ok
[INFO] [stdout] test scheduler::item_executor::tests::spill_large_var_small_value_inserts_inline ... ok
[INFO] [stdout] test scheduler::item_executor::tests::spill_large_var_one_byte_over_limit_spills_to_file ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_finalize_context_basic_fields ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_finalize_context_disabled_step_not_configured ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_finalize_context_confidence_and_quality ... ok
[INFO] [stdout] test scheduler::item_executor::tests::spill_to_file_small_value_returns_none ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_finalize_context_is_last_cycle ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_finalize_context_not_repeatable_in_cycle_2 ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_finalize_context_qa_ran_and_configured ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_finalize_context_qa_skipped ... ok
[INFO] [stdout] test scheduler::item_executor::tests::spill_to_file_multibyte_fully_within_limit ... ok
[INFO] [stdout] test scheduler::item_executor::tests::step_ids_for_capability_includes_canonical_and_custom ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_finalize_context_fix_skipped ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_finalize_context_artifacts ... ok
[INFO] [stdout] test scheduler::item_executor::tests::spill_to_file_multibyte_at_boundary ... ok
[INFO] [stdout] test scheduler::item_executor::tests::spill_to_file_one_byte_over_returns_some ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_finalize_context_retest ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_prehook_context_build_test_counts ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_finalize_context_tickets_set_fix_required ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_prehook_context_exit_codes_from_canonical_step_ids ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_prehook_context_basic_fields ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_prehook_context_max_cycles_defaults_to_1 ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_prehook_context_is_last_cycle_when_current_equals_max ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_extract_dynamic_items ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_prehook_context_exit_codes_use_first_alias_match ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_prehook_context_qa_failed_and_fix_required ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_prehook_context_self_test_vars ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_extract_dynamic_items_missing_var ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_extract_stream_json_result_no_result ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_prehook_context_fix_required_from_tickets_even_without_qa_failed ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_extract_dynamic_items_from_array_json_string ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_prehook_context_capability_based_step_ids ... ok
[INFO] [stdout] test scheduler::item_executor::tests::to_finalize_context_fix_ran_and_success ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_extract_dynamic_items_unquoted_json ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_extract_dynamic_items_skips_missing_id ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_resolve_pipeline_var_content_not_truncated ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_resolve_pipeline_var_content_truncated_missing_path ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_extract_stream_json_result ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_resolve_pipeline_var_content_truncated ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_extract_stream_json_result_redacted ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_extract_stream_json_result_whitespace_lines ... ok
[INFO] [stdout] test scheduler::item_select::tests::test_select_min ... ok
[INFO] [stdout] test scheduler::item_select::tests::test_select_max_picks_highest_score ... ok
[INFO] [stdout] test scheduler::item_select::tests::test_empty_items_fails ... ok
[INFO] [stdout] test scheduler::item_select::tests::test_select_threshold ... ok
[INFO] [stdout] test scheduler::item_select::tests::test_single_item ... ok
[INFO] [stdout] test scheduler::loop_engine::isolation::tests::parse_numstat_basic ... ok
[INFO] [stdout] test scheduler::item_select::tests::test_unparseable_metric_var_fails ... ok
[INFO] [stdout] test scheduler::item_select::tests::test_select_weighted ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_resolve_pipeline_var_content_missing_var ... ok
[INFO] [stdout] test scheduler::loop_engine::isolation::tests::parse_numstat_binary_files ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_resolve_pipeline_var_content_truncated_stream_json ... ok
[INFO] [stdout] test scheduler::loop_engine::isolation::tests::parse_numstat_empty ... ok
[INFO] [stdout] test scheduler::loop_engine::isolation::tests::sanitize_ref_component_replaces_invalid_chars ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::check_invariants_returns_none_for_empty_invariants ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::collect_item_eval_states_maps_pipeline_vars ... ok
[INFO] [stdout] test scheduler::item_select::tests::test_tie_break_last ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::build_segments_skips_disabled_steps ... ok
[INFO] [stdout] test scheduler::item_select::tests::test_select_max ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::build_segments_empty_when_no_steps ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::collect_remaining_item_step_steps_from_start_index_2 ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::collect_remaining_item_step_steps_returns_only_item_steps_after_segment ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::collect_remaining_item_step_steps_skips_non_repeatable_steps_after_first_cycle ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::compute_rollback_tag_saturates_to_one ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::build_segments_groups_contiguous_scopes ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::build_segments_item_select_is_task_scoped ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::build_segments_skips_guards ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::compute_rollback_tag_exact_cycle_one ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::compute_rollback_tag_zero_failures ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::compute_rollback_tag_normal ... ok
[INFO] [stdout] test scheduler::item_generate::tests::test_extract_dynamic_items_from_full_object_json_string ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::infinite_mode_with_max_cycles_before_limit_returns_none ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::fixed_mode_stops_at_max_cycles ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::infinite_mode_no_max_cycles_with_guard_enabled_returns_none ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::last_item_segment_detected_when_no_later_item_segments_exist ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::last_item_segment_rejects_item_segment_with_later_item_work_remaining ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::once_mode_always_stops ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::proactive_max_cycles_fixed_mode ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::proactive_max_cycles_fixed_mode_default ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::proactive_max_cycles_infinite_mode_no_cap ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::proactive_max_cycles_infinite_mode_with_cap ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::proactive_max_cycles_once_mode_passthrough ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::promote_winner_vars_inserts_into_pipeline ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::propagate_preserves_existing_item_state ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::propagate_task_segment_terminal_state_marks_all_items_terminal ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::propagate_task_segment_terminal_state_no_execution_failed_flag ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::resolved_scope_uses_explicit_override ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::should_auto_rollback_false_when_below_threshold ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::should_auto_rollback_false_when_disabled ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::should_auto_rollback_false_when_no_checkpoint_strategy ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::infinite_mode_with_disabled_guard_continues_immediately ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::infinite_mode_respects_max_cycles ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::fixed_mode_defaults_to_one_cycle ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::should_auto_rollback_true_when_failures_exceed_threshold ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::should_snapshot_false_when_binary_snapshot_disabled ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::should_snapshot_false_when_both_disabled ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::should_snapshot_true_when_both_enabled ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::should_snapshot_false_when_not_self_referential ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::apply_prompt_delivery_arg_shell_escapes_full_prompt ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_classifies_allowlist_network_denial ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_detects_dns_block_as_network_event ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_detects_network_block ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::should_auto_rollback_true_when_all_conditions_met ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_detects_operation_not_permitted ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_detects_open_files_limit ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_detects_probe_memory_marker ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_detects_probe_network_marker ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_handles_missing_stderr ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_detects_probe_process_marker ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_detects_cpu_signal ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_ignores_other_stderr ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_has_rollback_violation ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::effective_exit_code_preserves_nonzero_codes ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::effective_exit_code_maps_validation_failure_to_nonzero ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::heartbeat_reaches_stall_auto_kill_threshold ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_keeps_network_target_empty_for_traceback_noise ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::heartbeat_sample_delta_exactly_at_threshold_counts_as_stagnant ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::heartbeat_sample_low_output_after_three_quiet_heartbeats ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::heartbeat_sample_marks_quiet_when_process_is_not_alive ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::heartbeat_sample_not_alive_overrides_low_output_detection ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::heartbeat_sample_quiet_before_threshold ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::heartbeat_sample_resets_quiet_counter_after_output_resumes ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::heartbeat_sample_tracks_stderr_delta ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::read_output_with_limit_empty_file ... ok
[INFO] [stdout] test scheduler::item_executor::tests::step_ids_for_capability_no_duplicates_for_canonical_names ... ok
[INFO] [stdout] test scheduler::invariant::tests::test_invariant_no_command_passes ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::read_output_with_limit_missing_file_returns_error ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::read_output_with_limit_no_truncation_when_file_smaller_than_limit ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::render_step_template_prompt_keeps_rel_path_human_readable ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_returns_false_for_host_mode ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::effective_exit_code_with_various_validation_statuses ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::read_output_with_limit_exact_size_match ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::heartbeat_sample_active_when_output_grows ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::resolve_command_no_rules_returns_default ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::resolved_step_timeout_defaults ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::shell_escape_multiple_single_quotes ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::shell_escape_empty_string ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::shell_escape_only_single_quote ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::read_output_with_limit_returns_only_tail_bytes ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::shell_escape_simple_string ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::detect_sandbox_violation_preserves_probe_network_reason_code ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::shell_escape_special_chars_preserved ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::shell_escape_string_with_single_quotes ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::step_scope_label_matches_both_variants ... ok
[INFO] [stdout] test scheduler::query::format::tests::colorize_status_completed ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::resolve_command_no_match_falls_back ... ok
[INFO] [stdout] test scheduler::query::format::tests::format_bytes_bytes ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::resolve_command_matching_rule ... ok
[INFO] [stdout] test scheduler::query::format::tests::colorize_status_unknown_passes_through ... ok
[INFO] [stdout] test scheduler::query::format::tests::format_bytes_kilobytes ... ok
[INFO] [stdout] test scheduler::query::format::tests::format_bytes_megabytes ... ok
[INFO] [stdout] test scheduler::phase_runner::tests::cases::resolve_command_first_matching_rule_wins ... ok
[INFO] [stdout] test scheduler::query::format::tests::colorize_status_failed ... ok
[INFO] [stdout] test scheduler::query::format::tests::colorize_status_paused ... ok
[INFO] [stdout] test scheduler::query::format::tests::colorize_status_running ... ok
[INFO] [stdout] test scheduler::query::format::tests::format_duration_milliseconds ... ok
[INFO] [stdout] test scheduler::query::format::tests::format_duration_minutes ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::follow_one_stream_callback_incremental_read ... ok
[INFO] [stdout] test scheduler::query::format::tests::format_duration_seconds ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::follow_one_stream_uses_callback_for_stderr ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::follow_one_stream_uses_callback_for_stdout ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::emit_skipped_item_step_events_empty_items_emits_nothing ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::stream_task_logs_impl_no_runs_returns_empty ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::emit_skipped_item_step_events_empty_steps_emits_nothing ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::emit_skipped_item_step_events_writes_event_rows ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::tail_lines_empty_file_returns_empty ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::stream_task_logs_impl_tail_count_limits_output ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::tail_lines_missing_file_returns_error ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::tail_lines_returns_all_when_limit_exceeds_file ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::tail_lines_large_file ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::tail_lines_returns_last_n_lines ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::tail_lines_zero_limit_returns_empty ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::execute_cycle_graph_returns_static_segment_fallback_on_fail_closed_planner_error ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::stream_task_logs_impl_returns_log_chunks ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::stream_task_logs_impl_returns_placeholder_when_logs_missing ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::stream_task_logs_impl_redacts_secret_store_values ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::stream_task_logs_impl_redacts_non_default_project_secret_store_values ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::stream_task_logs_impl_returns_partial_results_when_one_run_is_unavailable ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::execute_cycle_graph_persists_replay_and_skips_prehook_false_nodes ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::execute_cycle_graph_uses_deterministic_dag_fallback_graph_on_fail_closed_planner_error ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::stream_task_logs_impl_with_timestamps ... ok
[INFO] [stdout] test scheduler::query::task_queries::tests::delete_task_impl_nonexistent_returns_error ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::stream_task_logs_impl_with_stderr ... ok
[INFO] [stdout] test scheduler::query::log_stream::tests::stream_task_logs_impl_works_when_active_config_is_not_runnable ... ok
[INFO] [stdout] test scheduler::query::tests::emit_anomaly_warning_updates_throttle_timestamp ... ok
[INFO] [stdout] test scheduler::query::tests::is_transient_query_error_matches_known_messages ... ok
[INFO] [stdout] test scheduler::query::watch::tests::render_watch_frame_keeps_active_state_for_active_heartbeat ... ok
[INFO] [stdout] test scheduler::query::watch::tests::render_watch_frame_includes_running_step_and_cycle ... ok
[INFO] [stdout] test scheduler::query::watch::tests::render_watch_frame_shows_low_output_details_for_heartbeat ... ok
[INFO] [stdout] test scheduler::query::watch::tests::render_watch_frame_shows_unspecified_scope_marker_for_missing_scope_event ... ok
[INFO] [stdout] test scheduler::query::task_queries::tests::list_tasks_impl_empty_when_no_tasks ... ok
[INFO] [stdout] test scheduler::query::task_queries::tests::get_task_details_impl_returns_items_and_empty_runs ... ok
[INFO] [stdout] test scheduler::query::task_queries::tests::get_task_details_impl_with_command_run ... ok
[INFO] [stdout] test scheduler::query::task_queries::tests::load_task_summary_returns_counts ... ok
[INFO] [stdout] test scheduler::query::task_queries::tests::list_tasks_impl_returns_seeded_task ... ok
[INFO] [stdout] test scheduler::query::task_queries::tests::delete_task_impl_removes_task_and_log_files ... ok
[INFO] [stdout] test scheduler::query::task_queries::tests::list_tasks_impl_multiple_tasks_ordered_desc ... ok
[INFO] [stdout] test scheduler::query::task_queries::tests::load_task_summary_with_prefix ... ok
[INFO] [stdout] test scheduler::query::task_queries::tests::resolve_task_id_not_found ... ok
[INFO] [stdout] test scheduler::query::task_queries::tests::resolve_task_id_exact_match ... ok
[INFO] [stderr] hint: Using 'master' as the name for the initial branch. This default branch name
[INFO] [stderr] hint: is subject to change. To configure the initial branch name to use in all
[INFO] [stderr] hint: of your new repositories, which will suppress this warning, call:
[INFO] [stderr] hint: 
[INFO] [stderr] hint: 	git config --global init.defaultBranch <name>
[INFO] [stderr] hint: 
[INFO] [stderr] hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and
[INFO] [stderr] hint: 'development'. The just-created branch can be renamed via this command:
[INFO] [stderr] hint: 
[INFO] [stderr] hint: 	git branch -m <name>
[INFO] [stdout] Initialized empty Git repository in /tmp/safety-test-gitPuWniW/.git/
[INFO] [stdout] test scheduler::query::task_queries::tests::resolve_task_id_prefix_match ... ok
[INFO] [stdout] test scheduler::safety::tests::test_create_checkpoint_fails_outside_git_repo ... ok
[INFO] [stdout] test scheduler::runtime::tests::load_task_runtime_context_clone_shares_heavy_fields ... ok
[INFO] [stdout] [master (root-commit) 0760a71] base
[INFO] [stdout]  1 file changed, 1 insertion(+)
[INFO] [stdout]  create mode 100644 tracked.txt
[INFO] [stdout] test scheduler::runtime::tests::load_task_runtime_context_errors_when_workspace_root_is_missing ... ok
[INFO] [stdout] [master d9ad734] checkpoint
[INFO] [stdout]  1 file changed, 1 insertion(+), 1 deletion(-)
[INFO] [stdout] [master e04ab22] after
[INFO] [stdout]  1 file changed, 1 insertion(+), 1 deletion(-)
[INFO] [stdout] test scheduler::safety::tests::test_create_checkpoint_and_rollback_success ... ok
[INFO] [stdout] test scheduler::runtime::tests::shutdown_running_tasks_is_noop_when_empty ... ok
[INFO] [stdout] test scheduler::runtime::tests::load_task_runtime_context_renormalizes_stale_self_test_steps ... ok
[INFO] [stdout] test scheduler::runtime::tests::stop_task_runtime_for_delete_removes_running_handle ... ok
[INFO] [stdout] test scheduler::runtime::tests::spawn_task_runner_returns_early_for_duplicate_task ... ok
[INFO] [stdout] test scheduler::runtime::tests::load_task_runtime_context_validates_probe_profile ... ok
[INFO] [stdout] test scheduler::runtime::tests::load_task_runtime_context_normalizes_fields ... ok
[INFO] [stdout] test scheduler::runtime::tests::load_task_runtime_context_skips_safety_check_when_unsafe_mode ... ok
[INFO] [stdout] test scheduler::runtime::tests::shutdown_running_tasks_pauses_and_clears_runtime_map ... ok
[INFO] [stdout] test scheduler::safety::tests::test_exit_restart_constant ... ok
[INFO] [stdout] test scheduler::safety::tests::test_restore_binary_creates_parent_dirs ... ok
[INFO] [stdout] test scheduler::safety::tests::test_restore_binary_snapshot_missing_stable ... ok
[INFO] [stdout] test scheduler::safety::tests::test_restore_binary_snapshot_success ... ok
[INFO] [stdout] test scheduler::safety::tests::test_restore_rejects_corrupt_stable ... ok
[INFO] [stdout] test scheduler::safety::tests::test_restore_with_manifest_integrity_check ... ok
[INFO] [stdout] test scheduler::safety::tests::test_restore_without_manifest_backward_compat ... ok
[INFO] [stderr] hint: Using 'master' as the name for the initial branch. This default branch name
[INFO] [stderr] hint: is subject to change. To configure the initial branch name to use in all
[INFO] [stderr] hint: of your new repositories, which will suppress this warning, call:
[INFO] [stderr] hint: 
[INFO] [stderr] hint: 	git config --global init.defaultBranch <name>
[INFO] [stderr] hint: 
[INFO] [stderr] hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and
[INFO] [stderr] hint: 'development'. The just-created branch can be renamed via this command:
[INFO] [stderr] hint: 
[INFO] [stderr] hint: 	git branch -m <name>
[INFO] [stdout] Initialized empty Git repository in /tmp/safety-test-missing-tag7mGpzV/.git/
[INFO] [stdout] test scheduler::runtime::tests::stop_task_runtime_marks_task_and_stop_flag ... ok
[INFO] [stdout] test scheduler::safety::tests::test_snapshot_atomic_leaves_no_tmp ... ok
[INFO] [stdout] test scheduler::safety::tests::test_snapshot_binary_missing_release ... ok
[INFO] [stdout] [master (root-commit) 0760a71] base
[INFO] [stdout]  1 file changed, 1 insertion(+)
[INFO] [stdout]  create mode 100644 tracked.txt
[INFO] [stdout] test scheduler::safety::tests::test_rollback_to_checkpoint_fails_for_missing_tag ... ok
[INFO] [stdout] test scheduler::safety::tests::test_snapshot_binary_permission_error ... FAILED
[INFO] [stdout] test scheduler::safety::tests::test_snapshot_creates_manifest ... ok
[INFO] [stdout] test scheduler::safety::tests::test_snapshot_binary_success ... ok
[INFO] [stdout] test scheduler::safety::tests::test_snapshot_manifest_round_trip ... ok
[INFO] [stdout] test scheduler::safety::tests::test_snapshot_empty_binary ... ok
[INFO] [stdout] test scheduler::safety::tests::test_snapshot_manifest_size_matches_content ... ok
[INFO] [stdout] test scheduler::safety::tests::test_snapshot_restore_content_integrity ... ok
[INFO] [stdout] test scheduler::safety::tests::test_snapshot_overwrites_existing_stable ... ok
[INFO] [stdout] test scheduler::safety::tests::test_verify_binary_snapshot_matches ... ok
[INFO] [stdout] test scheduler::safety::tests::test_snapshot_verify_sha256 ... ok
[INFO] [stdout] test scheduler::safety::tests::test_verify_binary_snapshot_mismatch ... ok
[INFO] [stdout] test scheduler::safety::tests::test_verify_binary_snapshot_missing_binary ... ok
[INFO] [stdout] test scheduler::safety::tests::test_verify_binary_snapshot_missing_stable ... ok
[INFO] [stdout] test scheduler::safety::tests::test_verify_includes_manifest_metadata ... ok
[INFO] [stdout] test scheduler::safety::tests::test_verify_post_restart_binary_no_event_returns_true ... ok
[INFO] [stdout] test scheduler::safety::tests::test_verify_post_restart_binary_unknown_hash_skips ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::inflight_wait_timeout_diagnostic_fields ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::inflight_wait_timeout_without_heartbeat ... ok
[INFO] [stdout] test scheduler::safety::tests::test_verify_with_corrupt_manifest_json ... ok
[INFO] [stdout] test scheduler::safety::tests::test_verify_without_manifest ... ok
[INFO] [stdout] test scheduler::spawn::tests::execute_spawn_task_creates_child_task_and_increments_depth ... ok
[INFO] [stdout] test scheduler::spawn::tests::execute_spawn_task_without_workspace_inheritance_uses_default_workspace ... ok
[INFO] [stdout] test scheduler::spawn::tests::execute_spawn_tasks_creates_batch_children_skips_missing_goal_and_honors_limit ... ok
[INFO] [stdout] test scheduler::loop_engine::tests::inflight_wait_heartbeat_resets_timeout ... ok
[INFO] [stdout] test scheduler::spawn::tests::test_resolve_template ... ok
[INFO] [stdout] test scheduler::spawn::tests::test_resolve_template_no_vars ... ok
[INFO] [stdout] test scheduler::spawn::tests::test_truncate_goal ... ok
[INFO] [stdout] test scheduler::spawn::tests::test_validate_spawn_depth_at_limit ... ok
[INFO] [stdout] test scheduler::spawn::tests::test_validate_spawn_depth_no_limit ... ok
[INFO] [stdout] test scheduler::spawn::tests::test_validate_spawn_depth_within_limit ... ok
[INFO] [stdout] test scheduler::spawn::tests::execute_spawn_tasks_errors_when_source_variable_is_missing ... ok
[INFO] [stdout] test scheduler::trace::anomaly::tests::degenerate_loop_emits_anomaly_on_three_consecutive_failures ... ok
[INFO] [stdout] test scheduler::trace::anomaly::tests::degenerate_loop_no_anomaly_when_failures_are_non_consecutive ... ok
[INFO] [stdout] test scheduler::trace::anomaly::tests::degenerate_loop_no_anomaly_when_fewer_than_three_consecutive_failures ... ok
[INFO] [stdout] test scheduler::trace::anomaly::tests::orphan_command_detected_without_truncation ... ok
[INFO] [stdout] test scheduler::trace::anomaly::tests::orphan_command_not_skipped_for_known_item_even_when_truncated ... ok
[INFO] [stdout] test scheduler::trace::anomaly::tests::orphan_command_skipped_when_events_truncated_and_item_unknown ... ok
[INFO] [stdout] test scheduler::trace::anomaly::tests::sandbox_denied_emits_anomaly_per_step ... ok
[INFO] [stdout] test scheduler::trace::anomaly::tests::sandbox_denied_no_anomaly_when_no_denial_events ... ok
[INFO] [stdout] test scheduler::trace::tests::build_trace_includes_build_version ... ok
[INFO] [stdout] test scheduler::trace::tests::build_trace_includes_dynamic_graph_events ... ok
[INFO] [stdout] test scheduler::trace::tests::build_trace_marks_missing_step_scope_as_unspecified ... ok
[INFO] [stdout] test scheduler::trace::tests::build_trace_marks_task_scoped_step_with_anchor_item ... ok
[INFO] [stdout] test scheduler::trace::tests::build_version_fields_populated ... ok
[INFO] [stdout] test scheduler::trace::tests::build_version_optional_backward_compat ... ok
[INFO] [stdout] test scheduler::trace::tests::chain_and_dynamic_step_events_handled ... ok
[INFO] [stdout] test scheduler::trace::tests::clean_sequence_no_anomalies ... ok
[INFO] [stdout] test scheduler::trace::tests::colorize_status_known_values ... ok
[INFO] [stdout] test scheduler::trace::tests::colorize_status_unknown_returns_plain ... ok
[INFO] [stdout] test scheduler::trace::tests::command_run_enriches_step ... ok
[INFO] [stdout] test scheduler::trace::tests::completed_task_backfills_last_cycle_end_from_completed_at ... ok
[INFO] [stdout] test scheduler::trace::tests::completed_task_wall_time_uses_task_meta_when_events_are_sparse ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_duplicate_runner_anomaly ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_empty_cycle_anomaly ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_empty_cycle_between_two_cycles ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_long_running_step_anomaly ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_low_output_step_anomaly ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_missing_step_end_anomaly ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_missing_step_end_when_no_terminal_event ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_nonzero_exit_anomaly ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_orphan_command_anomaly ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_overlapping_cycles_anomaly ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_overlapping_steps_anomaly ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_unexpanded_template_var_anomaly ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_unexpanded_template_var_ignores_user_content ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_unexpanded_template_var_legacy_row_skipped ... ok
[INFO] [stdout] test scheduler::trace::tests::detect_unexpanded_template_var_true_positive_only ... ok
[INFO] [stdout] test scheduler::trace::tests::empty_events_produces_empty_trace ... ok
[INFO] [stdout] test scheduler::trace::tests::extract_time_from_no_separator ... ok
[INFO] [stdout] test scheduler::trace::tests::extract_time_from_rfc3339 ... ok
[INFO] [stdout] test scheduler::trace::tests::extract_time_from_space_separated ... ok
[INFO] [stdout] test scheduler::trace::tests::find_template_vars_basic ... ok
[INFO] [stdout] test scheduler::trace::tests::find_template_vars_empty_braces_ignored ... ok
[INFO] [stdout] test scheduler::trace::tests::find_template_vars_ignores_uppercase_and_numbers ... ok
[INFO] [stdout] test scheduler::trace::tests::find_template_vars_no_matches ... ok
[INFO] [stdout] test scheduler::trace::tests::format_duration_hours ... ok
[INFO] [stdout] test scheduler::trace::tests::format_duration_minutes_and_seconds ... ok
[INFO] [stdout] test scheduler::trace::tests::format_duration_seconds_only ... ok
[INFO] [stdout] test scheduler::trace::tests::json_serialization_includes_build_version ... ok
[INFO] [stdout] test scheduler::trace::tests::json_serialization_roundtrip ... ok
[INFO] [stdout] test scheduler::trace::tests::low_output_heartbeat_with_dead_process_not_flagged ... ok
[INFO] [stdout] test scheduler::trace::tests::multi_cycle_trace ... ok
[INFO] [stdout] test scheduler::trace::tests::multiple_low_output_heartbeats_for_same_step_deduplicate ... ok
[INFO] [stdout] test scheduler::trace::tests::nonzero_exit_code_minus_one_not_flagged ... ok
[INFO] [stdout] test scheduler::trace::tests::parse_trace_timestamp_accepts_rfc3339_offset ... ok
[INFO] [stdout] test scheduler::trace::tests::parse_trace_timestamp_garbage_returns_none ... ok
[INFO] [stdout] test scheduler::trace::tests::parse_trace_timestamp_iso_without_offset ... ok
[INFO] [stdout] test scheduler::trace::tests::parse_trace_timestamp_naive_datetime_format ... ok
[INFO] [stdout] test scheduler::trace::tests::parse_trace_timestamp_with_fractional_seconds ... ok
[INFO] [stdout] test scheduler::trace::tests::quiet_heartbeat_does_not_create_low_output_anomaly ... ok
[INFO] [stdout] test scheduler::trace::tests::render_trace_terminal_does_not_panic ... ok
[INFO] [stdout] test scheduler::trace::tests::render_trace_terminal_shows_build_version ... ok
[INFO] [stdout] test scheduler::task_state::tests::task_state_wrappers_delegate_to_repository_and_writer ... ok
[INFO] [stdout] test scheduler::trace::tests::single_cycle_with_steps ... ok
[INFO] [stdout] test scheduler::trace::tests::skipped_step_recorded ... ok
[INFO] [stdout] test scheduler::trace::tests::split_observed_item_binding_all_variants ... ok
[INFO] [stdout] test scheduler::trace::tests::step_started_before_cycle_started_auto_creates_cycle_zero ... ok
[INFO] [stdout] test scheduler::trace::tests::summary_counts_failed_commands ... ok
[INFO] [stdout] test scheduler::trace::tests::two_cycle_completed_task_closes_first_cycle_without_overlap ... ok
[INFO] [stdout] test scheduler::trace::tests::wall_time_calculated ... ok
[INFO] [stdout] test scheduler::trace::tests::wall_time_uses_started_at_from_meta ... ok
[INFO] [stdout] test service::task::tests::create_task_and_query_wrappers_round_trip ... ok
[INFO] [stdout] test service::task::tests::get_task_logs_reads_existing_command_output ... ok
[INFO] [stdout] test service::task::tests::get_task_trace_filters_non_verbose_events ... ok
[INFO] [stdout] test service::task::tests::resolve_start_id_supports_explicit_latest_and_error ... ok
[INFO] [stdout] test service::task::tests::retry_task_item_resets_item_and_delete_task_removes_parent ... ok
[INFO] [stdout] test scheduler::safety::tests::test_execute_self_restart_step_binary_read_fails_uses_unknown ... ok
[INFO] [stdout] test scheduler::safety::tests::test_execute_self_restart_step_build_fails ... ok
[INFO] [stdout] test scheduler::safety::tests::test_verify_post_restart_binary_with_mismatch ... ok
[INFO] [stdout] test scheduler::safety::tests::test_verify_post_restart_binary_includes_old_sha256 ... ok
[INFO] [stdout] test scheduler::safety::tests::test_execute_self_restart_step_records_old_binary_sha256 ... ok
[INFO] [stdout] test scheduler::safety::tests::test_execute_self_restart_step_snapshot_fails ... ok
[INFO] [stdout] test scheduler::safety::tests::test_verify_post_restart_binary_with_matching_event ... ok
[INFO] [stdout] test scheduler::safety::tests::test_execute_self_restart_step_success_returns_exit_restart ... ok
[INFO] [stdout] test scheduler::safety::tests::test_execute_self_restart_step_verify_timeout ... ok
[INFO] [stdout] test scheduler::safety::tests::test_execute_self_test_step_cargo_test_fails ... ok
[INFO] [stdout] test scheduler::safety::tests::test_execute_self_test_step_no_manifest_script ... ok
[INFO] [stdout] test scheduler::safety::tests::test_execute_self_test_step_manifest_validate_fails ... ok
[INFO] [stdout] test scheduler::safety::tests::test_execute_self_test_step_returns_nonzero_when_cargo_check_fails ... ok
[INFO] [stdout] test scheduler::safety::tests::test_execute_self_test_step_success_with_manifest_validate ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- scheduler::safety::tests::test_snapshot_binary_permission_error stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'scheduler::safety::tests::test_snapshot_binary_permission_error' (1331) panicked at src/scheduler/safety/tests.rs:1134:5:
[INFO] [stdout] snapshot should fail on read-only workspace
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x62ff8c26df7a - std[ec419fa8110287d5]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x62ff8c26df7a - std[ec419fa8110287d5]::backtrace_rs::backtrace::trace_unsynchronized::<std[ec419fa8110287d5]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x62ff8c26df7a - std[ec419fa8110287d5]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x62ff8c26df7a - <<std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[35159d6ffb30e017]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x62ff8c28762a - <core[35159d6ffb30e017]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x62ff8c28762a - core[35159d6ffb30e017]::fmt::write
[INFO] [stdout]    6:     0x62ff8c273702 - std[ec419fa8110287d5]::io::default_write_fmt::<alloc[a8a7aba520129d98]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x62ff8c273702 - <alloc[a8a7aba520129d98]::vec::Vec<u8> as std[ec419fa8110287d5]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x62ff8c24737f - <std[ec419fa8110287d5]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x62ff8c24737f - std[ec419fa8110287d5]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x62ff8c263a79 - std[ec419fa8110287d5]::panicking::default_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x62ff8b38ce8c - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x62ff8b38ce8c - test[a8007afcd3effadc]::test_main_inner::<test[a8007afcd3effadc]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x62ff8c263cf2 - <alloc[a8a7aba520129d98]::boxed::Box<dyn for<'a, 'b> core[35159d6ffb30e017]::ops::function::Fn<(&'a std[ec419fa8110287d5]::panic::PanicHookInfo<'b>,), Output = ()> + core[35159d6ffb30e017]::marker::Sync + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::Fn<(&std[ec419fa8110287d5]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x62ff8c263cf2 - std[ec419fa8110287d5]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x62ff8c24746a - std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x62ff8c23e379 - std[ec419fa8110287d5]::sys::backtrace::__rust_end_short_backtrace::<std[ec419fa8110287d5]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x62ff8c24870d - __rustc[cfb3622dd870db93]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x62ff8c287e6c - core[35159d6ffb30e017]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x62ff8b02cf09 - orchestrator_scheduler[da5032a9ce575ceb]::scheduler::safety::tests::test_snapshot_binary_permission_error::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/scheduler/safety/tests.rs:1134:5
[INFO] [stdout]   20:     0x62ff8b08e712 - <core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>> as core[35159d6ffb30e017]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/future/future.rs:133:9
[INFO] [stdout]   21:     0x62ff8b08e78d - <core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>> as core[35159d6ffb30e017]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/future/future.rs:133:9
[INFO] [stdout]   22:     0x62ff8b06988d - <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:769:70
[INFO] [stdout]   23:     0x62ff8b06978b - tokio[e65a009f55513092]::task::coop::with_budget::<core[35159d6ffb30e017]::task::poll::Poll<()>, <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]   24:     0x62ff8b06978b - tokio[e65a009f55513092]::task::coop::budget::<core[35159d6ffb30e017]::task::poll::Poll<()>, <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]   25:     0x62ff8b06978b - <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:769:25
[INFO] [stdout]   26:     0x62ff8b059420 - <tokio[e65a009f55513092]::runtime::scheduler::current_thread::Context>::enter::<core[35159d6ffb30e017]::task::poll::Poll<()>, <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:446:19
[INFO] [stdout]   27:     0x62ff8b065b5d - <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:768:44
[INFO] [stdout]   28:     0x62ff8b064df4 - <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>::{closure#0}, core[35159d6ffb30e017]::option::Option<()>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:856:68
[INFO] [stdout]   29:     0x62ff8affdc0b - <tokio[e65a009f55513092]::runtime::context::scoped::Scoped<tokio[e65a009f55513092]::runtime::scheduler::Context>>::set::<<tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>::{closure#0}, core[35159d6ffb30e017]::option::Option<()>>::{closure#0}, (alloc[a8a7aba520129d98]::boxed::Box<tokio[e65a009f55513092]::runtime::scheduler::current_thread::Core>, core[35159d6ffb30e017]::option::Option<()>)>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   30:     0x62ff8b0a03c9 - tokio[e65a009f55513092]::runtime::context::set_scheduler::<(alloc[a8a7aba520129d98]::boxed::Box<tokio[e65a009f55513092]::runtime::scheduler::current_thread::Core>, core[35159d6ffb30e017]::option::Option<()>), <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>::{closure#0}, core[35159d6ffb30e017]::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/context.rs:176:38
[INFO] [stdout]   31:     0x62ff8afd0242 - <std[ec419fa8110287d5]::thread::local::LocalKey<tokio[e65a009f55513092]::runtime::context::Context>>::try_with::<tokio[e65a009f55513092]::runtime::context::set_scheduler<(alloc[a8a7aba520129d98]::boxed::Box<tokio[e65a009f55513092]::runtime::scheduler::current_thread::Core>, core[35159d6ffb30e017]::option::Option<()>), <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>::{closure#0}, core[35159d6ffb30e017]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[a8a7aba520129d98]::boxed::Box<tokio[e65a009f55513092]::runtime::scheduler::current_thread::Core>, core[35159d6ffb30e017]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   32:     0x62ff8afcfdce - <std[ec419fa8110287d5]::thread::local::LocalKey<tokio[e65a009f55513092]::runtime::context::Context>>::with::<tokio[e65a009f55513092]::runtime::context::set_scheduler<(alloc[a8a7aba520129d98]::boxed::Box<tokio[e65a009f55513092]::runtime::scheduler::current_thread::Core>, core[35159d6ffb30e017]::option::Option<()>), <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>::{closure#0}, core[35159d6ffb30e017]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[a8a7aba520129d98]::boxed::Box<tokio[e65a009f55513092]::runtime::scheduler::current_thread::Core>, core[35159d6ffb30e017]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   33:     0x62ff8b09625d - tokio[e65a009f55513092]::runtime::context::set_scheduler::<(alloc[a8a7aba520129d98]::boxed::Box<tokio[e65a009f55513092]::runtime::scheduler::current_thread::Core>, core[35159d6ffb30e017]::option::Option<()>), <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>::{closure#0}, core[35159d6ffb30e017]::option::Option<()>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/context.rs:176:17
[INFO] [stdout]   34:     0x62ff8b05c520 - <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>::{closure#0}, core[35159d6ffb30e017]::option::Option<()>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:856:27
[INFO] [stdout]   35:     0x62ff8b05c816 - <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[35159d6ffb30e017]::pin::Pin<&mut core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:756:24
[INFO] [stdout]   36:     0x62ff8b064900 - <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:200:33
[INFO] [stdout]   37:     0x62ff8b292643 - tokio[e65a009f55513092]::runtime::context::runtime::enter_runtime::<<tokio[e65a009f55513092]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   38:     0x62ff8b058f51 - <tokio[e65a009f55513092]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/scheduler/current_thread/mod.rs:188:9
[INFO] [stdout]   39:     0x62ff8b140624 - <tokio[e65a009f55513092]::runtime::runtime::Runtime>::block_on_inner::<core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/runtime.rs:371:52
[INFO] [stdout]   40:     0x62ff8b1407ef - <tokio[e65a009f55513092]::runtime::runtime::Runtime>::block_on::<core[35159d6ffb30e017]::pin::Pin<&mut dyn core[35159d6ffb30e017]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.50.0/src/runtime/runtime.rs:345:18
[INFO] [stdout]   41:     0x62ff8b054297 - orchestrator_scheduler[da5032a9ce575ceb]::scheduler::safety::tests::test_snapshot_binary_permission_error
[INFO] [stdout]                                at /opt/rustwide/workdir/src/scheduler/safety/tests.rs:1137:6
[INFO] [stdout]   42:     0x62ff8b025627 - orchestrator_scheduler[da5032a9ce575ceb]::scheduler::safety::tests::test_snapshot_binary_permission_error::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/scheduler/safety/tests.rs:1115:49
[INFO] [stdout]   43:     0x62ff8af413d6 - <orchestrator_scheduler[da5032a9ce575ceb]::scheduler::safety::tests::test_snapshot_binary_permission_error::{closure#0} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   44:     0x62ff8b38016b - <fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   45:     0x62ff8b38016b - test[a8007afcd3effadc]::__rust_begin_short_backtrace::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, fn() -> core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:724:18
[INFO] [stdout]   46:     0x62ff8b38d95b - test[a8007afcd3effadc]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:74
[INFO] [stdout]   47:     0x62ff8b38d95b - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   48:     0x62ff8b38d95b - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   49:     0x62ff8b38d95b - std[ec419fa8110287d5]::panicking::catch_unwind::<core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>, core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   50:     0x62ff8b38d95b - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<test[a8007afcd3effadc]::run_test_in_process::{closure#0}>, core[35159d6ffb30e017]::result::Result<(), alloc[a8a7aba520129d98]::string::String>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   51:     0x62ff8b38d95b - test[a8007afcd3effadc]::run_test_in_process
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:747:27
[INFO] [stdout]   52:     0x62ff8b38d95b - test[a8007afcd3effadc]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:668:43
[INFO] [stdout]   53:     0x62ff8b388334 - test[a8007afcd3effadc]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/test/src/lib.rs:698:41
[INFO] [stdout]   54:     0x62ff8b388334 - std[ec419fa8110287d5]::sys::backtrace::__rust_begin_short_backtrace::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   55:     0x62ff8b390562 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   56:     0x62ff8b390562 - <core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   57:     0x62ff8b390562 - std[ec419fa8110287d5]::panicking::catch_unwind::do_call::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:581:40
[INFO] [stdout]   58:     0x62ff8b390562 - std[ec419fa8110287d5]::panicking::catch_unwind::<(), core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panicking.rs:544:19
[INFO] [stdout]   59:     0x62ff8b390562 - std[ec419fa8110287d5]::panic::catch_unwind::<core[35159d6ffb30e017]::panic::unwind_safe::AssertUnwindSafe<std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/panic.rs:359:14
[INFO] [stdout]   60:     0x62ff8b390562 - std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked::<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   61:     0x62ff8b390562 - <std[ec419fa8110287d5]::thread::lifecycle::spawn_unchecked<test[a8007afcd3effadc]::run_test::{closure#1}, ()>::{closure#1} as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   62:     0x62ff8c26cdbf - <alloc[a8a7aba520129d98]::boxed::Box<dyn core[35159d6ffb30e017]::ops::function::FnOnce<(), Output = ()> + core[35159d6ffb30e017]::marker::Send> as core[35159d6ffb30e017]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   63:     0x62ff8c26cdbf - <std[ec419fa8110287d5]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/33835004928d3bf65db4d4712e1330766263b0bd/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   64:     0x7f13dd0d0aa4 - <unknown>
[INFO] [stdout]   65:     0x7f13dd15da64 - clone
[INFO] [stdout]   66:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     scheduler::safety::tests::test_snapshot_binary_permission_error
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 435 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 36.61s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "bd4cce172f86defe470ac4e296a36cfbe377ceee6a6a0807740a9f7a6835c627", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bd4cce172f86defe470ac4e296a36cfbe377ceee6a6a0807740a9f7a6835c627", kill_on_drop: false }`
[INFO] [stdout] bd4cce172f86defe470ac4e296a36cfbe377ceee6a6a0807740a9f7a6835c627
