[INFO] fetching crate vellaveto-approval 6.1.1...
[INFO] testing vellaveto-approval-6.1.1 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-1
[INFO] extracting crate vellaveto-approval 6.1.1 into /workspace/builds/worker-3-tc1/source
[INFO] started tweaking crates.io crate vellaveto-approval 6.1.1
[INFO] finished tweaking crates.io crate vellaveto-approval 6.1.1
[INFO] tweaked toml for crates.io crate vellaveto-approval 6.1.1 written to /workspace/builds/worker-3-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate vellaveto-approval 6.1.1 on toolchain 562dee4820c458d823175268e41601d4c060588a
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate vellaveto-approval 6.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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded vellaveto-types v6.1.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 1ca9c8de424ca13949187c5a767aa31e81079c98000664d0b58c6c8b39467404
[INFO] running `Command { std: "docker" "start" "-a" "1ca9c8de424ca13949187c5a767aa31e81079c98000664d0b58c6c8b39467404", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1ca9c8de424ca13949187c5a767aa31e81079c98000664d0b58c6c8b39467404", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1ca9c8de424ca13949187c5a767aa31e81079c98000664d0b58c6c8b39467404", kill_on_drop: false }`
[INFO] [stdout] 1ca9c8de424ca13949187c5a767aa31e81079c98000664d0b58c6c8b39467404
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e3f7a59b34bc749f1b3b46edad8137179dc891a74a68fd1350d37228399caeb8
[INFO] running `Command { std: "docker" "start" "-a" "e3f7a59b34bc749f1b3b46edad8137179dc891a74a68fd1350d37228399caeb8", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling tinyvec v1.10.0
[INFO] [stderr]    Compiling mio v1.1.1
[INFO] [stderr]    Compiling uuid v1.22.0
[INFO] [stderr]    Compiling unicode-normalization v0.1.25
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling tokio-macros v2.6.1
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling chrono v0.4.44
[INFO] [stderr]    Compiling vellaveto-types v6.1.1
[INFO] [stderr]    Compiling vellaveto-approval v6.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 22.52s
[INFO] running `Command { std: "docker" "inspect" "e3f7a59b34bc749f1b3b46edad8137179dc891a74a68fd1350d37228399caeb8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e3f7a59b34bc749f1b3b46edad8137179dc891a74a68fd1350d37228399caeb8", kill_on_drop: false }`
[INFO] [stdout] e3f7a59b34bc749f1b3b46edad8137179dc891a74a68fd1350d37228399caeb8
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c98a39c726fa4361080e200e7e7691b1fd50269d902b10a040957f8d9295c5b5
[INFO] running `Command { std: "docker" "start" "-a" "c98a39c726fa4361080e200e7e7691b1fd50269d902b10a040957f8d9295c5b5", kill_on_drop: false }`
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling vellaveto-approval v6.1.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 5.05s
[INFO] running `Command { std: "docker" "inspect" "c98a39c726fa4361080e200e7e7691b1fd50269d902b10a040957f8d9295c5b5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c98a39c726fa4361080e200e7e7691b1fd50269d902b10a040957f8d9295c5b5", kill_on_drop: false }`
[INFO] [stdout] c98a39c726fa4361080e200e7e7691b1fd50269d902b10a040957f8d9295c5b5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] ef6898b24a0c54c0182860c1dfe9d4021cccf9e02090d8c22854243ec942ce70
[INFO] running `Command { std: "docker" "start" "-a" "ef6898b24a0c54c0182860c1dfe9d4021cccf9e02090d8c22854243ec942ce70", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.12s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/vellaveto_approval-5e9d714dd2883c64)
[INFO] [stdout] 
[INFO] [stdout] running 115 tests
[INFO] [stdout] test tests::test_compute_dedup_key_different_reasons_differ ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_case_normalization ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_different_requested_by_differ ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_none_vs_some_requested_by_differ ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_none_vs_some_session_id_differ ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_sorted_ips ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_deterministic ... ok
[INFO] [stdout] test tests::test_containment_context_to_runtime_security_context_preserves_fields ... ok
[INFO] [stdout] test tests::test_create_rejects_reason_exceeding_max_length ... ok
[INFO] [stdout] test tests::test_create_rejects_control_chars_in_session_id ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_homoglyph_normalization ... ok
[INFO] [stdout] test tests::test_create_scoped_approval_persists_session_and_fingerprint ... ok
[INFO] [stdout] test tests::test_approve_rejects_control_chars_in_by ... ok
[INFO] [stdout] test tests::test_dedup_concurrent_safety ... ok
[INFO] [stdout] test tests::test_consume_approved_rejects_scope_mismatch_without_consuming ... ok
[INFO] [stdout] test tests::test_approve ... ok
[INFO] [stdout] test tests::test_create_rejects_non_hex_action_fingerprint ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_different_session_ids_differ ... ok
[INFO] [stdout] test tests::test_create_rejects_empty_session_id ... ok
[INFO] [stdout] test tests::test_create_approval ... ok
[INFO] [stdout] test tests::test_create_accepts_reason_at_max_length ... ok
[INFO] [stdout] test tests::test_consume_approved_rejects_legacy_unbound_approval_without_consuming ... ok
[INFO] [stdout] test tests::test_dedup_same_action_same_session_returns_existing_id ... ok
[INFO] [stdout] test tests::test_approve_expired_returns_expired_error ... ok
[INFO] [stdout] test tests::test_extract_presented_approval_id_from_rpc_meta_missing_field_returns_none ... ok
[INFO] [stdout] test tests::test_extract_presented_approval_id_from_rpc_meta_nested_in_params ... ok
[INFO] [stdout] test tests::test_extract_presented_approval_id_from_rpc_meta_prefers_top_level_meta ... ok
[INFO] [stdout] test tests::test_extract_presented_approval_id_from_rpc_meta_rejects_control_chars ... ok
[INFO] [stdout] test tests::test_extract_presented_approval_id_from_rpc_meta_rejects_too_long ... ok
[INFO] [stdout] test tests::test_extract_presented_approval_id_from_rpc_meta_top_level ... ok
[INFO] [stdout] test tests::test_fact_summary_basic ... ok
[INFO] [stdout] test tests::test_fact_summary_with_containment_context ... ok
[INFO] [stdout] test tests::test_lineage_drift_no_change_valid ... ok
[INFO] [stdout] test tests::test_lineage_drift_taint_accumulation_invalidates ... ok
[INFO] [stdout] test tests::test_lineage_drift_trust_downgrade_invalidates ... ok
[INFO] [stdout] test tests::test_dedup_different_actions_get_separate_ids ... ok
[INFO] [stdout] test tests::test_dedup_same_action_different_sessions_create_distinct_ids ... ok
[INFO] [stdout] test tests::test_consume_approved_succeeds_once_and_sets_consumed_state ... ok
[INFO] [stdout] test tests::test_pending_approval_scope_matches_fail_closed_on_mismatched_bound_values ... ok
[INFO] [stdout] test tests::test_pending_approval_scope_matches_opaque_session_binding ... ok
[INFO] [stdout] test tests::test_double_approve_fails ... ok
[INFO] [stdout] test tests::test_dedup_distinguishes_materially_different_containment_context ... ok
[INFO] [stdout] test tests::test_not_found ... ok
[INFO] [stdout] test tests::test_pending_count_empty_store ... ok
[INFO] [stdout] test tests::test_dedup_same_action_returns_existing_id ... ok
[INFO] [stdout] test tests::test_dedup_distinguishes_provenance_summary_differences ... ok
[INFO] [stdout] test tests::test_create_approval_with_containment_context_persists ... ok
[INFO] [stdout] test tests::test_deny ... ok
[INFO] [stdout] test tests::test_r116_ca_001_create_rejects_reason_with_null_byte ... ok
[INFO] [stdout] test tests::test_r116_ca_001_create_rejects_reason_with_control_chars ... ok
[INFO] [stdout] test tests::test_dedup_resolved_action_creates_fresh ... ok
[INFO] [stdout] test tests::test_r116_ca_001_create_rejects_reason_with_unicode_format_chars ... ok
[INFO] [stdout] test tests::test_r116_ca_001_create_rejects_reason_with_bidi_override ... ok
[INFO] [stdout] test tests::test_list_pending ... ok
[INFO] [stdout] test tests::test_dedup_normalizes_equivalent_containment_context ... ok
[INFO] [stdout] test tests::test_deny_rejects_control_chars_in_by ... ok
[INFO] [stdout] test tests::test_r245_with_max_pending_path_traversal_rejected ... ok
[INFO] [stdout] test tests::test_r143_create_empty_requested_by_rejected ... ok
[INFO] [stdout] test tests::test_persistence ... ok
[INFO] [stdout] test tests::test_r245_with_max_pending_clean_path_accepted ... ok
[INFO] [stdout] test tests::test_r253_create_rejects_short_fingerprint ... ok
[INFO] [stdout] test tests::test_load_from_file_evicts_stale_resolved ... ok
[INFO] [stdout] test tests::test_r36_sup_2_ascii_truncation_still_works ... ok
[INFO] [stdout] test tests::test_r116_ca_001_create_accepts_clean_reason ... ok
[INFO] [stdout] test tests::test_persistence_roundtrip_restores_session_scoped_dedup ... ok
[INFO] [stdout] test tests::test_pending_count_excludes_resolved ... ok
[INFO] [stdout] test tests::test_expire_stale_idempotent ... ok
[INFO] [stdout] test tests::test_expire_stale ... ok
[INFO] [stdout] test tests::test_r143_approve_empty_by_rejected ... ok
[INFO] [stdout] test tests::test_deny_expired_returns_expired_error ... ok
[INFO] [stdout] test tests::test_r253_create_rejects_unicode_format_chars_in_session_id ... ok
[INFO] [stdout] test tests::test_persistence_roundtrip ... ok
[INFO] [stdout] test tests::test_r39_sup_6_create_rejects_long_requested_by ... ok
[INFO] [stdout] test tests::test_r116_ca_002_with_max_pending_zero_clamps_to_one ... ok
[INFO] [stdout] test tests::test_r143_deny_empty_by_rejected ... ok
[INFO] [stdout] test tests::test_r36_sup_4_unicode_confusable_self_approval_blocked_roman_numeral ... ok
[INFO] [stdout] test tests::test_r40_sup_4_dedup_key_different_ips_still_differ ... ok
[INFO] [stdout] test tests::test_r38_sup_3_greek_sigma_self_approval_blocked ... ok
[INFO] [stdout] test tests::test_r40_sup_4_dedup_key_same_domains_different_order ... ok
[INFO] [stdout] test tests::test_r39_sup_6_approve_rejects_long_identity ... ok
[INFO] [stdout] test tests::test_r253_consume_cleans_dedup_index_allows_recreate ... ok
[INFO] [stdout] test tests::test_r40_sup_4_dedup_key_same_ips_different_order ... ok
[INFO] [stdout] test tests::test_r40_sup_4_dedup_key_same_paths_different_order ... ok
[INFO] [stdout] test tests::test_r36_sup_2_multibyte_utf8_truncation_no_panic ... ok
[INFO] [stdout] test tests::test_r40_sup_4_dedup_integration_same_ips_different_order ... ok
[INFO] [stdout] test tests::test_r39_sup_6_approve_accepts_identity_at_limit ... ok
[INFO] [stdout] test tests::test_r39_sup_6_deny_accepts_identity_at_limit ... ok
[INFO] [stdout] test tests::test_r39_sup_6_create_accepts_none_requested_by ... ok
[INFO] [stdout] test tests::test_r39_sup_6_deny_rejects_long_identity ... ok
[INFO] [stdout] test tests::test_review_safe_provenance_summary_fingerprints_sensitive_fields ... ok
[INFO] [stdout] test tests::test_stable_provenance_satisfied_by_matching_runtime_security_context ... ok
[INFO] [stdout] test tests::test_r38_sup_3_anonymous_exclusion_preserved ... ok
[INFO] [stdout] test tests::test_stable_provenance_satisfied_by_rejects_key_drift ... ok
[INFO] [stdout] test tests::test_r38_sup_3_ascii_case_folding_still_works ... ok
[INFO] [stdout] test verified_approval_consumption::tests::test_binding_guard_requires_fingerprint_and_scope_match ... ok
[INFO] [stdout] test verified_approval_consumption::tests::test_only_approved_status_allows_consumption ... ok
[INFO] [stdout] test verified_approval_consumption::tests::test_consumption_permitted_requires_approved_bound_matching_scope ... ok
[INFO] [stdout] test verified_approval_scope::tests::test_bound_session_binding_requires_present_match ... ok
[INFO] [stdout] test verified_approval_scope::tests::test_combined_scope_binding_requires_all_bound_dimensions ... ok
[INFO] [stdout] test verified_approval_scope::tests::test_unbound_fingerprint_binding_always_succeeds ... ok
[INFO] [stdout] test verified_presented_approval_id::tests::test_presented_approval_id_value_accepted_requires_safe_bounded_value ... ok
[INFO] [stdout] test verified_approval_scope::tests::test_unbound_session_binding_always_succeeds ... ok
[INFO] [stdout] test verified_presented_approval_id::tests::test_presented_approval_id_length_valid_accepts_within_cap ... ok
[INFO] [stdout] test verified_presented_approval_id::tests::test_presented_approval_id_length_valid_rejects_above_cap ... ok
[INFO] [stdout] test tests::test_r36_sup_4_legitimate_different_users_allowed ... ok
[INFO] [stdout] test tests::test_r42_sup_1_cyrillic_homoglyph_self_approval_blocked ... ok
[INFO] [stdout] test tests::test_r39_sup_6_create_accepts_requested_by_at_limit ... ok
[INFO] [stdout] test tests::test_r42_sup_1_greek_homoglyph_self_approval_blocked ... ok
[INFO] [stdout] test verified_approval_scope::tests::test_bound_fingerprint_binding_requires_present_match ... ok
[INFO] [stdout] test tests::test_store_scope_matches_uses_persisted_scope_bindings ... ok
[INFO] [stdout] test tests::test_r42_sup_1_cyrillic_mixed_homoglyphs_self_approval_blocked ... ok
[INFO] [stdout] test tests::test_r116_ca_002_with_max_pending_normal_value_unchanged ... ok
[INFO] [stdout] test tests::test_with_max_pending_capacity_exceeded ... ok
[INFO] [stdout] test tests::test_r36_sup_4_unicode_confusable_self_approval_blocked_fullwidth ... ok
[INFO] [stderr]    Doc-tests vellaveto_approval
[INFO] [stdout] test tests::test_with_max_pending_one_allows_single_approval ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 115 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "ef6898b24a0c54c0182860c1dfe9d4021cccf9e02090d8c22854243ec942ce70", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ef6898b24a0c54c0182860c1dfe9d4021cccf9e02090d8c22854243ec942ce70", kill_on_drop: false }`
[INFO] [stdout] ef6898b24a0c54c0182860c1dfe9d4021cccf9e02090d8c22854243ec942ce70