[INFO] fetching crate starpod-hooks 0.3.1...
[INFO] testing starpod-hooks-0.3.1 against try#33835004928d3bf65db4d4712e1330766263b0bd for pr-155739-1
[INFO] extracting crate starpod-hooks 0.3.1 into /workspace/builds/worker-0-tc2/source
[INFO] started tweaking crates.io crate starpod-hooks 0.3.1
[INFO] finished tweaking crates.io crate starpod-hooks 0.3.1
[INFO] tweaked toml for crates.io crate starpod-hooks 0.3.1 written to /workspace/builds/worker-0-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate starpod-hooks 0.3.1 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 starpod-hooks 0.3.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" "+33835004928d3bf65db4d4712e1330766263b0bd" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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] 715d8e1200f2aeab2f0c79de0ecd33295c4f1c190d47dbc3b45a61d817d1a2fe
[INFO] running `Command { std: "docker" "start" "-a" "715d8e1200f2aeab2f0c79de0ecd33295c4f1c190d47dbc3b45a61d817d1a2fe", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "715d8e1200f2aeab2f0c79de0ecd33295c4f1c190d47dbc3b45a61d817d1a2fe", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "715d8e1200f2aeab2f0c79de0ecd33295c4f1c190d47dbc3b45a61d817d1a2fe", kill_on_drop: false }`
[INFO] [stdout] 715d8e1200f2aeab2f0c79de0ecd33295c4f1c190d47dbc3b45a61d817d1a2fe
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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] 526f1bdecfc319e5ea7278c8af484539c59737313832f1522ab1cecd0f3bc91f
[INFO] running `Command { std: "docker" "start" "-a" "526f1bdecfc319e5ea7278c8af484539c59737313832f1522ab1cecd0f3bc91f", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.183
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling pin-project-lite v0.2.17
[INFO] [stderr]    Compiling hashbrown v0.16.1
[INFO] [stderr]    Compiling winnow v0.7.15
[INFO] [stderr]    Compiling bytes v1.11.1
[INFO] [stderr]    Compiling bitflags v2.11.0
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling toml_write v0.1.2
[INFO] [stderr]    Compiling regex-syntax v0.8.10
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling env_home v0.1.0
[INFO] [stderr]    Compiling itoa v1.0.17
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling glob v0.3.3
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling indexmap v2.13.0
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling which v7.0.3
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling serde_spanned v0.6.9
[INFO] [stderr]    Compiling toml_datetime v0.6.11
[INFO] [stderr]    Compiling toml_edit v0.22.27
[INFO] [stderr]    Compiling toml v0.8.23
[INFO] [stderr]    Compiling starpod-hooks v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 24.96s
[INFO] running `Command { std: "docker" "inspect" "526f1bdecfc319e5ea7278c8af484539c59737313832f1522ab1cecd0f3bc91f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "526f1bdecfc319e5ea7278c8af484539c59737313832f1522ab1cecd0f3bc91f", kill_on_drop: false }`
[INFO] [stdout] 526f1bdecfc319e5ea7278c8af484539c59737313832f1522ab1cecd0f3bc91f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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] 94ad59cbf2a782cf2893d892d953319255655c6dcfd5402088ae5474d2f27d91
[INFO] running `Command { std: "docker" "start" "-a" "94ad59cbf2a782cf2893d892d953319255655c6dcfd5402088ae5474d2f27d91", kill_on_drop: false }`
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling yansi v1.0.1
[INFO] [stderr]    Compiling diff v0.1.13
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling pretty_assertions v1.4.1
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling starpod-hooks v0.3.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 15.58s
[INFO] running `Command { std: "docker" "inspect" "94ad59cbf2a782cf2893d892d953319255655c6dcfd5402088ae5474d2f27d91", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "94ad59cbf2a782cf2893d892d953319255655c6dcfd5402088ae5474d2f27d91", kill_on_drop: false }`
[INFO] [stdout] 94ad59cbf2a782cf2893d892d953319255655c6dcfd5402088ae5474d2f27d91
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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] 9d069628f163c51a2915483426cd68eba05ccb306ab7e4242ce3d9708341de0a
[INFO] running `Command { std: "docker" "start" "-a" "9d069628f163c51a2915483426cd68eba05ccb306ab7e4242ce3d9708341de0a", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/starpod_hooks-0cd8a2bb48b82105)
[INFO] [stdout] 
[INFO] [stdout] running 104 tests
[INFO] [stdout] test callback::tests::matcher_debug_shows_hook_count ... ok
[INFO] [stdout] test callback::tests::hook_fn_creates_callable_callback ... ok
[INFO] [stdout] test callback::tests::matcher_builder_chaining ... ok
[INFO] [stdout] test callback::tests::matcher_with_name ... ok
[INFO] [stdout] test callback::tests::matcher_with_requirements ... ok
[INFO] [stdout] test callback::tests::matcher_debug_includes_name_and_requires ... ok
[INFO] [stdout] test callback::tests::matcher_with_timeout ... ok
[INFO] [stdout] test callback::tests::matcher_no_pattern_matches_everything ... ok
[INFO] [stdout] test circuit_breaker::tests::cooldown_expires ... ok
[INFO] [stdout] test circuit_breaker::tests::default_config ... ok
[INFO] [stdout] test circuit_breaker::tests::independent_hooks_tracked_separately ... ok
[INFO] [stdout] test circuit_breaker::tests::not_tripped_initially ... ok
[INFO] [stdout] test circuit_breaker::tests::opens_after_max_failures ... ok
[INFO] [stdout] test circuit_breaker::tests::re_opens_on_failure_after_half_open ... ok
[INFO] [stdout] test circuit_breaker::tests::success_resets_breaker ... ok
[INFO] [stdout] test circuit_breaker::tests::unnamed_hooks_bypass ... ok
[INFO] [stdout] test callback::tests::matcher_regex_filters ... ok
[INFO] [stdout] test discovery::tests::discover_multiple_hooks_same_event ... ok
[INFO] [stdout] test callback::tests::matcher_invalid_regex_returns_error ... ok
[INFO] [stdout] test discovery::tests::discover_builds_registry ... ok
[INFO] [stdout] test discovery::tests::discover_nonexistent_dir_is_ok ... ok
[INFO] [stdout] test discovery::tests::parse_invalid_toml_returns_error ... ok
[INFO] [stdout] test discovery::tests::parse_minimal_manifest ... ok
[INFO] [stdout] test discovery::tests::parse_missing_closing_delimiter ... ok
[INFO] [stdout] test discovery::tests::parse_missing_frontmatter_delimiters ... ok
[INFO] [stdout] test discovery::tests::parse_missing_required_fields ... ok
[INFO] [stdout] test discovery::tests::parse_valid_manifest ... ok
[INFO] [stdout] test discovery::tests::scan_empty_dir ... ok
[INFO] [stdout] test eligibility::tests::current_os_passes ... ok
[INFO] [stdout] test eligibility::tests::any_bins_passes_if_one_exists ... ok
[INFO] [stdout] test eligibility::tests::empty_requirements_always_pass ... ok
[INFO] [stdout] test eligibility::tests::missing_env_var_fails ... ok
[INFO] [stdout] test eligibility::tests::existing_binary_passes ... ok
[INFO] [stdout] test eligibility::tests::any_bins_at_least_one_must_exist ... ok
[INFO] [stdout] test eligibility::tests::os_mismatch_fails ... ok
[INFO] [stdout] test discovery::tests::command_callback_executes_subprocess ... ok
[INFO] [stdout] test error::tests::display_callback_failed ... ok
[INFO] [stdout] test error::tests::display_circuit_breaker_open ... ok
[INFO] [stdout] test error::tests::display_command_execution ... ok
[INFO] [stdout] test error::tests::display_eligibility ... ok
[INFO] [stdout] test eligibility::tests::existing_env_var_passes ... ok
[INFO] [stdout] test eligibility::tests::missing_binary_fails ... ok
[INFO] [stdout] test discovery::tests::command_callback_empty_stdout_returns_default ... ok
[INFO] [stdout] test discovery::tests::scan_dir_propagates_timeout_and_requires ... ok
[INFO] [stdout] test discovery::tests::scan_dir_skips_hooks_without_command ... ok
[INFO] [stdout] test error::tests::display_discovery ... ok
[INFO] [stdout] test eligibility::tests::serde_roundtrip ... ok
[INFO] [stdout] test error::tests::display_invalid_regex ... ok
[INFO] [stdout] test error::tests::display_manifest_parse ... ok
[INFO] [stdout] test error::tests::display_timeout ... ok
[INFO] [stdout] test error::tests::from_io_error ... ok
[INFO] [stdout] test error::tests::from_regex_error ... ok
[INFO] [stdout] test error::tests::from_serde_error ... ok
[INFO] [stdout] test event::tests::display_matches_variant_name ... ok
[INFO] [stdout] test event::tests::hook_event_equality ... ok
[INFO] [stdout] test event::tests::hook_event_hash_works_in_hashmap ... ok
[INFO] [stdout] test event::tests::serde_roundtrip ... ok
[INFO] [stdout] test discovery::tests::command_callback_invalid_json_returns_error ... ok
[INFO] [stdout] test input::tests::all_event_names_covered ... ok
[INFO] [stdout] test input::tests::base_accessor_returns_correct_fields ... ok
[INFO] [stdout] test input::tests::config_change_source_serde ... ok
[INFO] [stdout] test input::tests::event_name_matches_variant ... ok
[INFO] [stdout] test input::tests::serde_roundtrip_tagged ... ok
[INFO] [stdout] test input::tests::session_start_source_serde ... ok
[INFO] [stdout] test input::tests::tool_name_returns_none_for_non_tool_events ... ok
[INFO] [stdout] test input::tests::tool_name_returns_some_for_tool_events ... ok
[INFO] [stdout] test output::tests::async_output_roundtrip ... ok
[INFO] [stdout] test output::tests::default_output_is_sync ... ok
[INFO] [stdout] test output::tests::hook_decision_serde ... ok
[INFO] [stdout] test output::tests::permission_request_decision_allow ... ok
[INFO] [stdout] test output::tests::permission_request_decision_deny_with_message ... ok
[INFO] [stdout] test output::tests::sync_output_default_has_no_fields_set ... ok
[INFO] [stdout] test output::tests::sync_output_with_decision_roundtrip ... ok
[INFO] [stdout] test permissions::tests::permission_decision_all_variants ... ok
[INFO] [stdout] test permissions::tests::permission_level_all_variants ... ok
[INFO] [stdout] test permissions::tests::permission_update_serde ... ok
[INFO] [stdout] test output::tests::pre_tool_use_specific_output ... ok
[INFO] [stdout] test runner::tests::circuit_breaker_resets_on_success ... ok
[INFO] [stdout] test runner::tests::circuit_breaker_skips_tripped_hook ... ok
[INFO] [stdout] test runner::tests::eligibility_os_mismatch_skips ... ok
[INFO] [stdout] test runner::tests::eligibility_passes_eligible_hook ... ok
[INFO] [stdout] test runner::tests::has_hooks_false_for_empty_matchers_vec ... ok
[INFO] [stdout] test runner::tests::eligibility_skips_ineligible_hook ... ok
[INFO] [stdout] test runner::tests::hook_error_is_logged_not_propagated ... ok
[INFO] [stdout] test runner::tests::merge_combines_registries ... ok
[INFO] [stdout] test runner::tests::merge_into_empty_registry ... ok
[INFO] [stdout] test runner::tests::hook_receives_correct_input_fields ... ok
[INFO] [stdout] test discovery::tests::scan_dir_finds_hooks ... ok
[INFO] [stdout] test runner::tests::pre_tool_use_non_matching_returns_none ... ok
[INFO] [stdout] test runner::tests::registry_from_map_and_into_map ... ok
[INFO] [stdout] test runner::tests::multiple_matchers_all_fire ... ok
[INFO] [stdout] test runner::tests::registry_get_returns_matchers ... ok
[INFO] [stdout] test runner::tests::registry_new_is_empty ... ok
[INFO] [stdout] test runner::tests::registry_register_and_has_hooks ... ok
[INFO] [stdout] test runner::tests::run_event_fires_generic_hooks ... ok
[INFO] [stdout] test runner::tests::run_event_noop_when_no_hooks_registered ... ok
[INFO] [stdout] test runner::tests::run_post_tool_use_fires_matching_hooks ... ok
[INFO] [stdout] test runner::tests::run_post_tool_use_no_matcher_fires_for_all ... ok
[INFO] [stdout] test runner::tests::run_post_tool_use_noop_when_no_hooks_registered ... ok
[INFO] [stdout] test runner::tests::run_pre_tool_use_returns_output ... ok
[INFO] [stdout] test runner::tests::run_pre_tool_use_returns_none_when_no_hooks ... ok
[INFO] [stdout] test runner::tests::unnamed_hook_bypasses_circuit_breaker ... ok
[INFO] [stdout] test runner::tests::with_circuit_breaker_builder ... ok
[INFO] [stdout] test runner::tests::hook_timeout_is_enforced ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 104 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.04s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests starpod_hooks
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test src/discovery.rs - discovery::HookDiscovery (line 84) - compile ... ok
[INFO] [stdout] test src/runner.rs - runner::HookRegistry (line 21) ... ok
[INFO] [stdout] test src/output.rs - output::HookOutput (line 11) ... ok
[INFO] [stdout] test src/event.rs - event::HookEvent (line 11) ... ok
[INFO] [stdout] test src/circuit_breaker.rs - circuit_breaker::CircuitBreaker (line 55) ... ok
[INFO] [stdout] test src/lib.rs - (line 51) ... ok
[INFO] [stdout] test src/callback.rs - callback::hook_fn (line 45) ... ok
[INFO] [stdout] test src/input.rs - input::HookInput (line 26) ... ok
[INFO] [stdout] test src/eligibility.rs - eligibility::HookRequirements (line 17) ... ok
[INFO] [stdout] test src/callback.rs - callback::HookCallback (line 23) ... ok
[INFO] [stdout] test src/callback.rs - callback::HookCallbackMatcher (line 72) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.89s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "9d069628f163c51a2915483426cd68eba05ccb306ab7e4242ce3d9708341de0a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9d069628f163c51a2915483426cd68eba05ccb306ab7e4242ce3d9708341de0a", kill_on_drop: false }`
[INFO] [stdout] 9d069628f163c51a2915483426cd68eba05ccb306ab7e4242ce3d9708341de0a
