[INFO] fetching crate vellaveto-approval 6.1.1...
[INFO] testing vellaveto-approval-6.1.1 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-1
[INFO] extracting crate vellaveto-approval 6.1.1 into /workspace/builds/worker-3-tc2/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-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate vellaveto-approval 6.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 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" "+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-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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 792f31c31f59a0afe400c837cf3abd3057254b12af702a08aac48ce10fe514bf
[INFO] running `Command { std: "docker" "start" "-a" "792f31c31f59a0afe400c837cf3abd3057254b12af702a08aac48ce10fe514bf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "792f31c31f59a0afe400c837cf3abd3057254b12af702a08aac48ce10fe514bf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "792f31c31f59a0afe400c837cf3abd3057254b12af702a08aac48ce10fe514bf", kill_on_drop: false }`
[INFO] [stdout] 792f31c31f59a0afe400c837cf3abd3057254b12af702a08aac48ce10fe514bf
[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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d7b5b110a1cd1c463d0c953c7354215d0fc9c8828bb5922fd0f3d2e2e0423953
[INFO] running `Command { std: "docker" "start" "-a" "d7b5b110a1cd1c463d0c953c7354215d0fc9c8828bb5922fd0f3d2e2e0423953", kill_on_drop: false }`
[INFO] [stderr]    Compiling tinyvec v1.10.0
[INFO] [stderr]    Compiling syn v2.0.117
[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 tokio-macros v2.6.1
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling tokio v1.50.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling thiserror v2.0.18
[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 20.80s
[INFO] running `Command { std: "docker" "inspect" "d7b5b110a1cd1c463d0c953c7354215d0fc9c8828bb5922fd0f3d2e2e0423953", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d7b5b110a1cd1c463d0c953c7354215d0fc9c8828bb5922fd0f3d2e2e0423953", kill_on_drop: false }`
[INFO] [stdout] d7b5b110a1cd1c463d0c953c7354215d0fc9c8828bb5922fd0f3d2e2e0423953
[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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7b95134974522babf43e6729157fd5aa2a832ce75a14ba5d8a5abc9e2dab97b0
[INFO] running `Command { std: "docker" "start" "-a" "7b95134974522babf43e6729157fd5aa2a832ce75a14ba5d8a5abc9e2dab97b0", 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 4.57s
[INFO] running `Command { std: "docker" "inspect" "7b95134974522babf43e6729157fd5aa2a832ce75a14ba5d8a5abc9e2dab97b0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7b95134974522babf43e6729157fd5aa2a832ce75a14ba5d8a5abc9e2dab97b0", kill_on_drop: false }`
[INFO] [stdout] 7b95134974522babf43e6729157fd5aa2a832ce75a14ba5d8a5abc9e2dab97b0
[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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 98ee9473630399b16586182e2c2e11cbf85555a72105d14e76af06472541c151
[INFO] running `Command { std: "docker" "start" "-a" "98ee9473630399b16586182e2c2e11cbf85555a72105d14e76af06472541c151", 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/vellaveto_approval-5e9d714dd2883c64)
[INFO] [stdout] 
[INFO] [stdout] running 115 tests
[INFO] [stdout] test tests::test_compute_dedup_key_case_normalization ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_deterministic ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_different_session_ids_differ ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_different_requested_by_differ ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_homoglyph_normalization ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_different_reasons_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_none_vs_some_requested_by_differ ... ok
[INFO] [stdout] test tests::test_compute_dedup_key_sorted_ips ... ok
[INFO] [stdout] test tests::test_containment_context_to_runtime_security_context_preserves_fields ... ok
[INFO] [stdout] test tests::test_approve_rejects_control_chars_in_by ... ok
[INFO] [stdout] test tests::test_create_rejects_control_chars_in_session_id ... ok
[INFO] [stdout] test tests::test_create_rejects_non_hex_action_fingerprint ... ok
[INFO] [stdout] test tests::test_approve ... ok
[INFO] [stdout] test tests::test_create_approval_with_containment_context_persists ... ok
[INFO] [stdout] test tests::test_consume_approved_succeeds_once_and_sets_consumed_state ... ok
[INFO] [stdout] test tests::test_create_approval ... ok
[INFO] [stdout] test tests::test_consume_approved_rejects_legacy_unbound_approval_without_consuming ... ok
[INFO] [stdout] test tests::test_dedup_normalizes_equivalent_containment_context ... ok
[INFO] [stdout] test tests::test_dedup_distinguishes_provenance_summary_differences ... ok
[INFO] [stdout] test tests::test_create_rejects_reason_exceeding_max_length ... ok
[INFO] [stdout] test tests::test_create_rejects_empty_session_id ... ok
[INFO] [stdout] test tests::test_create_scoped_approval_persists_session_and_fingerprint ... ok
[INFO] [stdout] test tests::test_dedup_same_action_same_session_returns_existing_id ... 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_dedup_same_action_returns_existing_id ... 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_dedup_concurrent_safety ... ok
[INFO] [stdout] test tests::test_approve_expired_returns_expired_error ... ok
[INFO] [stdout] test tests::test_dedup_different_actions_get_separate_ids ... ok
[INFO] [stdout] test tests::test_deny_rejects_control_chars_in_by ... ok
[INFO] [stdout] test tests::test_consume_approved_rejects_scope_mismatch_without_consuming ... ok
[INFO] [stdout] test tests::test_extract_presented_approval_id_from_rpc_meta_top_level ... ok
[INFO] [stdout] test tests::test_create_accepts_reason_at_max_length ... ok
[INFO] [stdout] test tests::test_fact_summary_basic ... ok
[INFO] [stdout] test tests::test_dedup_resolved_action_creates_fresh ... 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_not_found ... 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_deny ... ok
[INFO] [stdout] test tests::test_dedup_distinguishes_materially_different_containment_context ... ok
[INFO] [stdout] test tests::test_pending_count_empty_store ... ok
[INFO] [stdout] test tests::test_r116_ca_001_create_rejects_reason_with_control_chars ... ok
[INFO] [stdout] test tests::test_r116_ca_001_create_rejects_reason_with_bidi_override ... ok
[INFO] [stdout] test tests::test_deny_expired_returns_expired_error ... ok
[INFO] [stdout] test tests::test_dedup_same_action_different_sessions_create_distinct_ids ... ok
[INFO] [stdout] test tests::test_list_pending ... ok
[INFO] [stdout] test tests::test_load_from_file_evicts_stale_resolved ... ok
[INFO] [stdout] test tests::test_r116_ca_001_create_rejects_reason_with_null_byte ... ok
[INFO] [stdout] test tests::test_r245_with_max_pending_path_traversal_rejected ... ok
[INFO] [stdout] test tests::test_r116_ca_001_create_rejects_reason_with_unicode_format_chars ... ok
[INFO] [stdout] test tests::test_expire_stale_idempotent ... ok
[INFO] [stdout] test tests::test_persistence_roundtrip_restores_session_scoped_dedup ... ok
[INFO] [stdout] test tests::test_double_approve_fails ... ok
[INFO] [stdout] test tests::test_persistence ... ok
[INFO] [stdout] test tests::test_r116_ca_002_with_max_pending_normal_value_unchanged ... ok
[INFO] [stdout] test tests::test_r253_create_rejects_unicode_format_chars_in_session_id ... ok
[INFO] [stdout] test tests::test_r36_sup_4_unicode_confusable_self_approval_blocked_fullwidth ... ok
[INFO] [stdout] test tests::test_r36_sup_4_unicode_confusable_self_approval_blocked_roman_numeral ... ok
[INFO] [stdout] test tests::test_r143_create_empty_requested_by_rejected ... ok
[INFO] [stdout] test tests::test_r143_approve_empty_by_rejected ... ok
[INFO] [stdout] test tests::test_r38_sup_3_ascii_case_folding_still_works ... ok
[INFO] [stdout] test tests::test_r245_with_max_pending_clean_path_accepted ... ok
[INFO] [stdout] test tests::test_expire_stale ... ok
[INFO] [stdout] test tests::test_r116_ca_001_create_accepts_clean_reason ... ok
[INFO] [stdout] test tests::test_r36_sup_2_ascii_truncation_still_works ... ok
[INFO] [stdout] test tests::test_r36_sup_2_multibyte_utf8_truncation_no_panic ... ok
[INFO] [stdout] test tests::test_r143_deny_empty_by_rejected ... ok
[INFO] [stdout] test tests::test_pending_count_excludes_resolved ... ok
[INFO] [stdout] test tests::test_r253_create_rejects_short_fingerprint ... ok
[INFO] [stdout] test tests::test_r40_sup_4_dedup_key_different_ips_still_differ ... ok
[INFO] [stdout] test tests::test_r39_sup_6_approve_accepts_identity_at_limit ... ok
[INFO] [stdout] test tests::test_persistence_roundtrip ... ok
[INFO] [stdout] test tests::test_r38_sup_3_anonymous_exclusion_preserved ... ok
[INFO] [stdout] test tests::test_r36_sup_4_legitimate_different_users_allowed ... 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_domains_different_order ... ok
[INFO] [stdout] test tests::test_r39_sup_6_create_rejects_long_requested_by ... ok
[INFO] [stdout] test tests::test_r38_sup_3_greek_sigma_self_approval_blocked ... ok
[INFO] [stdout] test tests::test_r116_ca_002_with_max_pending_zero_clamps_to_one ... ok
[INFO] [stdout] test tests::test_review_safe_provenance_summary_fingerprints_sensitive_fields ... ok
[INFO] [stdout] test tests::test_stable_provenance_satisfied_by_rejects_key_drift ... ok
[INFO] [stdout] test tests::test_r40_sup_4_dedup_key_same_paths_different_order ... ok
[INFO] [stdout] test verified_approval_consumption::tests::test_consumption_permitted_requires_approved_bound_matching_scope ... ok
[INFO] [stdout] test verified_approval_consumption::tests::test_binding_guard_requires_fingerprint_and_scope_match ... ok
[INFO] [stdout] test tests::test_stable_provenance_satisfied_by_matching_runtime_security_context ... ok
[INFO] [stdout] test verified_approval_consumption::tests::test_only_approved_status_allows_consumption ... ok
[INFO] [stdout] test verified_approval_scope::tests::test_bound_fingerprint_binding_requires_present_match ... 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_presented_approval_id::tests::test_presented_approval_id_length_valid_accepts_within_cap ... 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_rejects_above_cap ... 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_fingerprint_binding_always_succeeds ... ok
[INFO] [stdout] test tests::test_r39_sup_6_approve_rejects_long_identity ... ok
[INFO] [stdout] test tests::test_r39_sup_6_create_accepts_none_requested_by ... ok
[INFO] [stdout] test tests::test_r40_sup_4_dedup_integration_same_ips_different_order ... ok
[INFO] [stdout] test tests::test_r253_consume_cleans_dedup_index_allows_recreate ... ok
[INFO] [stdout] test tests::test_r42_sup_1_greek_homoglyph_self_approval_blocked ... ok
[INFO] [stdout] test tests::test_r42_sup_1_cyrillic_mixed_homoglyphs_self_approval_blocked ... ok
[INFO] [stdout] test tests::test_r39_sup_6_create_accepts_requested_by_at_limit ... ok
[INFO] [stdout] test tests::test_r39_sup_6_deny_accepts_identity_at_limit ... ok
[INFO] [stdout] test tests::test_store_scope_matches_uses_persisted_scope_bindings ... ok
[INFO] [stdout] test tests::test_r42_sup_1_cyrillic_homoglyph_self_approval_blocked ... ok
[INFO] [stdout] test tests::test_r39_sup_6_deny_rejects_long_identity ... ok
[INFO] [stdout] test tests::test_with_max_pending_one_allows_single_approval ... ok
[INFO] [stdout] test tests::test_with_max_pending_capacity_exceeded ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 115 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests vellaveto_approval
[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" "98ee9473630399b16586182e2c2e11cbf85555a72105d14e76af06472541c151", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "98ee9473630399b16586182e2c2e11cbf85555a72105d14e76af06472541c151", kill_on_drop: false }`
[INFO] [stdout] 98ee9473630399b16586182e2c2e11cbf85555a72105d14e76af06472541c151