[INFO] fetching crate orcs-hook 0.1.1...
[INFO] testing orcs-hook-0.1.1 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-2
[INFO] extracting crate orcs-hook 0.1.1 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate orcs-hook 0.1.1
[INFO] finished tweaking crates.io crate orcs-hook 0.1.1
[INFO] tweaked toml for crates.io crate orcs-hook 0.1.1 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate orcs-hook 0.1.1 on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate orcs-hook 0.1.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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 9eaaf249deb525c53b6e438f728590c33a89f459ecd42ece1154dbde523c896a
[INFO] running `Command { std: "docker" "start" "-a" "9eaaf249deb525c53b6e438f728590c33a89f459ecd42ece1154dbde523c896a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "9eaaf249deb525c53b6e438f728590c33a89f459ecd42ece1154dbde523c896a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9eaaf249deb525c53b6e438f728590c33a89f459ecd42ece1154dbde523c896a", kill_on_drop: false }`
[INFO] [stdout] 9eaaf249deb525c53b6e438f728590c33a89f459ecd42ece1154dbde523c896a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d8e1c4dbfc10ccdaba08530b9d6d656b1cc06e4822d48bf8a165113a378e60bf
[INFO] running `Command { std: "docker" "start" "-a" "d8e1c4dbfc10ccdaba08530b9d6d656b1cc06e4822d48bf8a165113a378e60bf", kill_on_drop: false }`
[INFO] [stderr]    Compiling zmij v1.0.20
[INFO] [stderr]    Compiling sha1_smol v1.0.1
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling syn v2.0.114
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling uuid v1.20.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[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 tracing v0.1.44
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling orcs-types v0.1.1
[INFO] [stderr]    Compiling orcs-hook v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 12.95s
[INFO] running `Command { std: "docker" "inspect" "d8e1c4dbfc10ccdaba08530b9d6d656b1cc06e4822d48bf8a165113a378e60bf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d8e1c4dbfc10ccdaba08530b9d6d656b1cc06e4822d48bf8a165113a378e60bf", kill_on_drop: false }`
[INFO] [stdout] d8e1c4dbfc10ccdaba08530b9d6d656b1cc06e4822d48bf8a165113a378e60bf
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9d1e66ef98f3299074ce141c29b1f269eff11934d6cc1f6e9cb4012308d2e56f
[INFO] running `Command { std: "docker" "start" "-a" "9d1e66ef98f3299074ce141c29b1f269eff11934d6cc1f6e9cb4012308d2e56f", kill_on_drop: false }`
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling socket2 v0.6.2
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling serde_spanned v0.6.9
[INFO] [stderr]    Compiling toml_datetime v0.6.11
[INFO] [stderr]    Compiling uuid v1.20.0
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling toml_edit v0.22.27
[INFO] [stderr]    Compiling orcs-types v0.1.1
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling orcs-hook v0.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling toml v0.8.23
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 12.28s
[INFO] running `Command { std: "docker" "inspect" "9d1e66ef98f3299074ce141c29b1f269eff11934d6cc1f6e9cb4012308d2e56f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9d1e66ef98f3299074ce141c29b1f269eff11934d6cc1f6e9cb4012308d2e56f", kill_on_drop: false }`
[INFO] [stdout] 9d1e66ef98f3299074ce141c29b1f269eff11934d6cc1f6e9cb4012308d2e56f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] cf08a88713495fd71d5b17746784a8d3aad533cb92886b4a30e6dfc0426814fb
[INFO] running `Command { std: "docker" "start" "-a" "cf08a88713495fd71d5b17746784a8d3aad533cb92886b4a30e6dfc0426814fb", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/orcs_hook-1ce8fdbec2860914)
[INFO] [stdout] 
[INFO] [stdout] running 99 tests
[INFO] [stdout] test action::tests::abort_variant ... ok
[INFO] [stdout] test action::tests::replace_variant ... ok
[INFO] [stdout] test config::tests::default_enabled_is_true ... ok
[INFO] [stdout] test action::tests::skip_variant ... ok
[INFO] [stdout] test config::tests::default_priority_is_100 ... ok
[INFO] [stdout] test config::tests::merge_anonymous_hooks_always_append ... ok
[INFO] [stdout] test config::tests::merge_appends_new_hooks ... ok
[INFO] [stdout] test config::tests::merge_mixed_override_and_append ... ok
[INFO] [stdout] test config::tests::serde_json_defaults_applied ... ok
[INFO] [stdout] test config::tests::merge_overrides_same_id ... ok
[INFO] [stdout] test config::tests::serde_json_roundtrip ... ok
[INFO] [stdout] test config::tests::validate_all_collects_all_errors ... ok
[INFO] [stdout] test config::tests::toml_empty_hooks ... ok
[INFO] [stdout] test config::tests::validate_anonymous_hook ... ok
[INFO] [stdout] test config::tests::validate_valid_script_hook ... ok
[INFO] [stdout] test context::tests::clone_is_independent ... ok
[INFO] [stdout] test context::tests::depth_exceeded ... ok
[INFO] [stdout] test config::tests::toml_roundtrip ... ok
[INFO] [stdout] test config::tests::validate_both_handlers_error ... ok
[INFO] [stdout] test config::tests::validate_valid_inline_hook ... ok
[INFO] [stdout] test context::tests::depth_increment ... ok
[INFO] [stdout] test context::tests::depth_saturation ... ok
[INFO] [stdout] test config::tests::hooks_config_default_is_empty ... ok
[INFO] [stdout] test config::tests::toml_minimal_with_defaults ... ok
[INFO] [stdout] test config::tests::validate_invalid_fql ... ok
[INFO] [stdout] test config::tests::validate_invalid_point ... ok
[INFO] [stdout] test context::tests::new_has_correct_defaults ... ok
[INFO] [stdout] test context::tests::with_max_depth ... ok
[INFO] [stdout] test context::tests::with_metadata ... ok
[INFO] [stdout] test error::tests::display_depth_exceeded ... ok
[INFO] [stdout] test error::tests::display_execution_failed ... ok
[INFO] [stdout] test context::tests::serde_roundtrip ... ok
[INFO] [stdout] test config::tests::validate_anonymous_error_display ... ok
[INFO] [stdout] test error::tests::error_is_clone_and_eq ... ok
[INFO] [stdout] test fql::tests::display_roundtrip ... ok
[INFO] [stdout] test error::tests::display_not_found ... ok
[INFO] [stdout] test fql::tests::match_exact ... ok
[INFO] [stdout] test error::tests::display_invalid_fql ... ok
[INFO] [stdout] test fql::tests::match_exact_no_match ... ok
[INFO] [stdout] test fql::tests::match_full_wildcard ... ok
[INFO] [stdout] test fql::tests::match_wildcard_scope ... ok
[INFO] [stdout] test fql::tests::match_no_child_pattern_accepts_any_child ... ok
[INFO] [stdout] test error::tests::display_unknown_hook_point ... ok
[INFO] [stdout] test fql::tests::parse_empty_child_path ... ok
[INFO] [stdout] test fql::tests::match_wildcard_target ... ok
[INFO] [stdout] test fql::tests::parse_empty_instance ... ok
[INFO] [stdout] test fql::tests::match_child_exact ... ok
[INFO] [stdout] test fql::tests::parse_empty_scope ... ok
[INFO] [stdout] test fql::tests::parse_missing_separator ... ok
[INFO] [stdout] test fql::tests::parse_empty_string ... ok
[INFO] [stdout] test fql::tests::match_plugin_namespace ... ok
[INFO] [stdout] test fql::tests::parse_scope_wildcard ... ok
[INFO] [stdout] test fql::tests::parse_with_child_wildcard ... ok
[INFO] [stdout] test fql::tests::parse_target_wildcard ... ok
[INFO] [stdout] test fql::tests::parse_exact_match ... ok
[INFO] [stdout] test fql::tests::parse_full_wildcard ... ok
[INFO] [stdout] test fql::tests::parse_with_child_path ... ok
[INFO] [stdout] test fql::tests::parse_empty_target ... ok
[INFO] [stdout] test fql::tests::serde_roundtrip ... ok
[INFO] [stdout] test fql::tests::parse_with_instance ... ok
[INFO] [stdout] test hook::tests::hook_default_priority ... ok
[INFO] [stdout] test fql::tests::parse_full_pattern ... ok
[INFO] [stdout] test hook::tests::mock_aborter ... ok
[INFO] [stdout] test hook::tests::mock_call_count_increments ... ok
[INFO] [stdout] test hook::tests::mock_modifier ... ok
[INFO] [stdout] test hook::tests::mock_pass_through ... ok
[INFO] [stdout] test action::tests::continue_variant ... ok
[INFO] [stdout] test hook::tests::mock_priority ... ok
[INFO] [stdout] test point::tests::all_variants_count ... ok
[INFO] [stdout] test point::tests::event_hooks_are_neither_pre_nor_post ... ok
[INFO] [stdout] test point::tests::every_variant_is_exactly_one_category ... ok
[INFO] [stdout] test config::tests::validate_no_handler_error ... ok
[INFO] [stdout] test fql::tests::match_child_wildcard ... ok
[INFO] [stdout] test point::tests::from_str_empty ... ok
[INFO] [stdout] test point::tests::from_str_roundtrip_all ... ok
[INFO] [stdout] test point::tests::from_str_unknown ... ok
[INFO] [stdout] test point::tests::is_post_correct ... ok
[INFO] [stdout] test point::tests::is_pre_correct ... ok
[INFO] [stdout] test point::tests::serde_roundtrip ... ok
[INFO] [stdout] test registry::tests::chain_hooks_modify_sequentially ... ok
[INFO] [stdout] test registry::tests::depth_exceeded_breaks_chain ... ok
[INFO] [stdout] test registry::tests::disabled_hook_skipped ... ok
[INFO] [stdout] test registry::tests::dispatch_abort_stops_chain ... ok
[INFO] [stdout] test registry::tests::dispatch_modifying_hook ... ok
[INFO] [stdout] test registry::tests::dispatch_no_hooks_returns_continue ... ok
[INFO] [stdout] test registry::tests::dispatch_pass_through_hook ... ok
[INFO] [stdout] test registry::tests::dispatch_skip_stops_chain ... ok
[INFO] [stdout] test registry::tests::empty_registry ... ok
[INFO] [stdout] test registry::tests::fql_filtering ... ok
[INFO] [stdout] test registry::tests::len_counts_across_points ... ok
[INFO] [stdout] test registry::tests::post_hook_replace_updates_payload_and_continues_chain ... ok
[INFO] [stdout] test registry::tests::pre_hook_replace_is_ignored ... ok
[INFO] [stdout] test registry::tests::priority_ordering ... ok
[INFO] [stdout] test registry::tests::unregister_by_id ... ok
[INFO] [stdout] test tests::shared_registry_clone_shares_state ... ok
[INFO] [stdout] test registry::tests::re_enable_hook ... ok
[INFO] [stdout] test registry::tests::unregister_by_owner ... ok
[INFO] [stdout] test tests::shared_registry_register_and_dispatch ... ok
[INFO] [stdout] test tests::shared_registry_creation ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 99 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests orcs_hook
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test src/lib.rs - (line 61) ... ignored
[INFO] [stdout] test src/lib.rs - (line 99) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.22s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "cf08a88713495fd71d5b17746784a8d3aad533cb92886b4a30e6dfc0426814fb", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cf08a88713495fd71d5b17746784a8d3aad533cb92886b4a30e6dfc0426814fb", kill_on_drop: false }`
[INFO] [stdout] cf08a88713495fd71d5b17746784a8d3aad533cb92886b4a30e6dfc0426814fb