[INFO] fetching crate rimap-audit 0.2.0...
[INFO] testing rimap-audit-0.2.0 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate rimap-audit 0.2.0 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate rimap-audit 0.2.0
[INFO] removed 0 missing examples
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate rimap-audit 0.2.0
[INFO] tweaked toml for crates.io crate rimap-audit 0.2.0 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate rimap-audit 0.2.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate rimap-audit 0.2.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" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] f50467bbd2098a1660611d76f453219f165476504e191b5a662437e03f45ee7d
[INFO] running `Command { std: "docker" "start" "f50467bbd2098a1660611d76f453219f165476504e191b5a662437e03f45ee7d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f50467bbd2098a1660611d76f453219f165476504e191b5a662437e03f45ee7d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "f50467bbd2098a1660611d76f453219f165476504e191b5a662437e03f45ee7d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f50467bbd2098a1660611d76f453219f165476504e191b5a662437e03f45ee7d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "f50467bbd2098a1660611d76f453219f165476504e191b5a662437e03f45ee7d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling typenum v1.20.0
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling cpufeatures v0.3.0
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling thiserror v2.0.19
[INFO] [stderr]    Compiling zeroize v1.8.2
[INFO] [stderr]    Compiling rimap-core v0.2.0
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling time-core v0.1.9
[INFO] [stderr]    Compiling num-conv v0.2.2
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling chacha20 v0.10.0
[INFO] [stderr]    Compiling deranged v0.5.8
[INFO] [stderr]    Compiling secrecy v0.10.3
[INFO] [stderr]    Compiling hex v0.4.3
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling time-macros v0.2.32
[INFO] [stderr]    Compiling rand v0.10.1
[INFO] [stderr]    Compiling concurrent-queue v2.5.0
[INFO] [stderr]    Compiling event-listener v5.4.1
[INFO] [stderr]    Compiling hybrid-array v0.4.11
[INFO] [stderr]    Compiling crypto-common v0.2.1
[INFO] [stderr]    Compiling block-buffer v0.12.0
[INFO] [stderr]    Compiling event-listener-strategy v0.5.4
[INFO] [stderr]    Compiling serde_derive_internals v0.30.0
[INFO] [stderr]    Compiling async-channel v2.5.0
[INFO] [stderr]    Compiling thiserror-impl v2.0.19
[INFO] [stderr]    Compiling fs4 v1.1.0
[INFO] [stderr]    Compiling digest v0.11.2
[INFO] [stderr]    Compiling schemars_derive v1.2.2
[INFO] [stderr]    Compiling time v0.3.54
[INFO] [stderr]    Compiling sha2 v0.11.0
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling ref-cast-impl v1.0.25
[INFO] [stderr]    Compiling strum_macros v0.28.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling ref-cast v1.0.25
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling strum v0.28.0
[INFO] [stderr]    Compiling schemars v1.2.2
[INFO] [stderr]    Compiling ulid v3.0.0
[INFO] [stderr]    Compiling rimap-audit v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 35.98s
[INFO] running `Command { std: "docker" "inspect" "f50467bbd2098a1660611d76f453219f165476504e191b5a662437e03f45ee7d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "f50467bbd2098a1660611d76f453219f165476504e191b5a662437e03f45ee7d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling zerocopy v0.8.48
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling unarray v0.1.4
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling rand v0.9.4
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling proptest v1.11.0
[INFO] [stderr]    Compiling rimap-audit v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 38.01s
[INFO] running `Command { std: "docker" "inspect" "f50467bbd2098a1660611d76f453219f165476504e191b5a662437e03f45ee7d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "f50467bbd2098a1660611d76f453219f165476504e191b5a662437e03f45ee7d" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.13s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/rimap-audit/b4e082ce1864063c/out/rimap_audit-b4e082ce1864063c)
[INFO] [stdout] 
[INFO] [stdout] running 173 tests
[INFO] [stdout] test error::tests::locked_message_includes_docs_anchor ... ok
[INFO] [stdout] test error::tests::rimap_error_conversion_preserves_code_and_source ... ok
[INFO] [stdout] test error::tests::runtime_errors_map_to_internal ... ok
[INFO] [stdout] test error::tests::read_error_with_line_number_renders_line_marker ... ok
[INFO] [stdout] test error::tests::read_error_without_line_number_omits_line_marker ... ok
[INFO] [stdout] test reader::backup_exclude::tests::exclude_from_backup_handles_tempdir ... ok
[INFO] [stdout] test reader::tests::empty_file_streams_zero_records ... ok
[INFO] [stdout] test reader::tests::filter_by_kind_matches_exact_string ... ok
[INFO] [stdout] test error::tests::open_time_errors_map_to_config ... ok
[INFO] [stdout] test error::tests::locked_message_names_the_path ... ok
[INFO] [stdout] test reader::tests::filter_by_process_id_matches ... ok
[INFO] [stdout] test error::tests::parse_variant_maps_to_internal ... ok
[INFO] [stdout] test reader::backup_exclude::tests::exclude_from_backup_does_not_panic ... ok
[INFO] [stdout] test fs::tests::writer_open_options_rejects_symlinks_via_o_nofollow ... ok
[INFO] [stdout] test reader::tests::account_filter_excludes_record_with_different_account ... ok
[INFO] [stdout] test reader::tests::filter_by_since_and_until_restricts_range ... ok
[INFO] [stdout] test fs::tests::writer_open_options_creates_file_with_mode_0600 ... ok
[INFO] [stdout] test reader::tests::known_kinds_are_never_read_as_unrecognized ... ok
[INFO] [stdout] test reader::tests::parse_line_display_is_human_readable_without_empty_backticks ... ok
[INFO] [stdout] test reader::tests::parse_line_returns_err_on_empty_input ... ok
[INFO] [stdout] test reader::tests::parse_line_returns_parse_variant_on_malformed_json ... ok
[INFO] [stdout] test reader::tests::parse_line_round_trips_a_valid_record ... ok
[INFO] [stdout] test reader::tests::known_kind_with_a_malformed_payload_still_aborts ... ok
[INFO] [stdout] test reader::tests::parse_line_does_not_panic_on_garbage_bytes ... ok
[INFO] [stdout] test cancellation::tests::drainer_writes_records_to_audit_writer ... ok
[INFO] [stdout] test reader::tests::malformed_non_trailing_line_error_carries_one_based_line_number ... ok
[INFO] [stdout] test reader::tests::malformed_non_trailing_line_is_an_error ... ok
[INFO] [stdout] test reader::tests::malformed_trailing_line_is_skipped_with_warning ... ok
[INFO] [stdout] test reader::tests::line_without_a_string_kind_still_aborts ... ok
[INFO] [stdout] test cancellation::tests::try_send_and_receive_round_trip ... ok
[INFO] [stdout] test fs::tests::reader_open_options_rejects_symlinks_via_o_nofollow ... ok
[INFO] [stdout] test reader::tests::since_bound_is_inclusive_at_record_timestamp ... ok
[INFO] [stdout] test reader::tests::tool_filter_excludes_non_tool_records ... ok
[INFO] [stdout] test reader::tests::unknown_kind_is_counted_even_when_the_filter_would_exclude_it ... ok
[INFO] [stdout] test reader::tests::until_bound_is_inclusive_at_record_timestamp ... ok
[INFO] [stdout] test reader::tests::unknown_kind_line_is_skipped_and_counted ... ok
[INFO] [stdout] test record::ids::tests::process_id_embeds_current_time_in_its_prefix ... ok
[INFO] [stdout] test record::ids::tests::seq_starts_at_one_and_increments ... ok
[INFO] [stdout] test record::ids::tests::from_offset_clamps_leap_second_nanosecond ... ok
[INFO] [stdout] test record::ids::tests::process_id_display_is_ulid_encoded ... ok
[INFO] [stdout] test record::ids::tests::timestamp_deserialize_rejects_malformed_input ... ok
[INFO] [stdout] test record::ids::tests::seq_next_saturates ... ok
[INFO] [stdout] test record::ids::tests::process_id_is_unique_per_call ... ok
[INFO] [stdout] test record::ids::tests::seq_display_uses_integer ... ok
[INFO] [stdout] test record::ids::tests::timestamp_serializes_as_rfc3339_millis ... ok
[INFO] [stdout] test reader::tests::unknown_kind_on_the_trailing_line_is_counted_not_read_as_a_torn_write ... ok
[INFO] [stdout] test record::ids::tests::timestamp_round_trips_through_serde ... ok
[INFO] [stdout] test record::tests::process_start_serializes_with_flat_kind_discriminator ... ok
[INFO] [stdout] test record::tests::process_start_tool_matrix_serializes_verdicts_with_provenance ... ok
[INFO] [stdout] test record::tests::an_empty_protected_list_is_not_the_same_line_as_an_unprobed_one ... ok
[INFO] [stdout] test record::tests::auth_result_serializes_snake_case ... ok
[INFO] [stdout] test record::tests::config_event_serializes_as_config_kind ... ok
[INFO] [stdout] test record::tests::process_end_round_trips ... ok
[INFO] [stdout] test record::tests::auth_record_round_trips_and_uses_snake_case_kind ... ok
[INFO] [stdout] test record::tests::tool_matrix_entry_without_folder_lists_parses_as_empty ... ok
[INFO] [stdout] test record::tests::process_start_without_tool_matrix_parses_as_empty ... ok
[INFO] [stdout] test record::tests::process_end_reason_serializes_snake_case ... ok
[INFO] [stdout] test record::tests::process_start_tool_matrix_serializes_folder_lists_with_provenance ... ok
[INFO] [stdout] test record::tests::tool_start_round_trips_with_snake_case_kind ... ok
[INFO] [stdout] test record::tests::process_start_round_trips_through_serde ... ok
[INFO] [stdout] test reader::tests::streams_all_records_with_empty_filter ... ok
[INFO] [stdout] test record::tests::tool_end_records_artifact_provenance_and_round_trips ... ok
[INFO] [stdout] test record::tests::tool_end_round_trips_with_provenance_and_summary ... ok
[INFO] [stdout] test record::tests::tool_status_cancelled_serializes_as_snake_case ... ok
[INFO] [stdout] test redact::tests::non_object_input_produces_placeholder ... ok
[INFO] [stdout] test redact::redact_record_tests::redact_passes_through_non_tool_start_records ... ok
[INFO] [stdout] test record::tests::tool_status_serializes_snake_case ... ok
[INFO] [stdout] test redact::tests::expunge_schema_has_result_fields ... ok
[INFO] [stdout] test redact::tests::create_draft_schema_hashes_recipients_and_redacts_body ... ok
[INFO] [stdout] test redact::tests::delete_folder_schema_has_result_fields ... ok
[INFO] [stdout] test redact::tests::every_v1_tool_has_a_schema ... ok
[INFO] [stdout] test redact::tests::export_messages_schema_caps_oversize_uid_array ... ok
[INFO] [stdout] test redact::tests::non_string_redactable_fields_get_question_mark ... ok
[INFO] [stdout] test redact::tests::export_messages_schema_redacts_paths_keeps_identifiers ... ok
[INFO] [stdout] test redact::tests::delete_message_schema_has_result_fields ... ok
[INFO] [stdout] test redact::tests::hash_arguments_is_stable_and_hex_encoded ... ok
[INFO] [stdout] test redact::redact_record_tests::redact_re_redacts_tool_start_arguments ... ok
[INFO] [stdout] test redact::tests::random_salt_is_not_all_zeros ... ok
[INFO] [stdout] test redact::tests::salted_hash_differs_across_processes ... ok
[INFO] [stdout] test redact::tests::salted_hash_is_deterministic_for_same_process ... ok
[INFO] [stdout] test redact::tests::search_schema_drops_password_and_token ... ok
[INFO] [stdout] test redact::tests::search_advanced_schema_matches_search_policy ... ok
[INFO] [stdout] test redact::tests::search_canary_never_leaks_through_any_verbatim_field ... ok
[INFO] [stdout] test redact::tests::schemas_do_not_have_duplicate_tools ... ok
[INFO] [stdout] test redact::tests::search_schema_hashes_headers_array ... ok
[INFO] [stdout] test reader::tests::tool_filter_excludes_record_whose_tool_does_not_match ... ok
[INFO] [stdout] test redact::tests::verbatim_string_array_rejects_mixed_element_types ... ok
[INFO] [stdout] test redact::tests::verbatim_string_rejects_non_string ... ok
[INFO] [stdout] test redact::tests::verbatim_u64_array_rejects_mixed_element_types ... ok
[INFO] [stdout] test redact::tests::verbatim_date_string_accepts_iso_dates ... ok
[INFO] [stdout] test redact::tests::send_email_schema_matches_spec_field_names ... ok
[INFO] [stdout] test redact::tests::search_schema_redacts_all_content_fields ... ok
[INFO] [stdout] test redact::tests::strings_are_replaced_with_length_markers ... ok
[INFO] [stdout] test redact::tests::unknown_fields_default_to_string_redaction ... ok
[INFO] [stdout] test redact::tests::verbatim_fields_pass_through ... ok
[INFO] [stdout] test redact::tests::use_account_schema_redacts_account_field ... ok
[INFO] [stdout] test redact::tests::verbatim_date_string_rejects_oversized_value ... ok
[INFO] [stdout] test redact::tests::search_schema_keeps_structural_fields_verbatim ... ok
[INFO] [stdout] test redact::tests::search_schema_preserves_structural_fields ... ok
[INFO] [stdout] test redact::tests::verbatim_bool_rejects_string_payload ... ok
[INFO] [stdout] test redact::tests::verbatim_u64_array_rejects_non_array ... ok
[INFO] [stdout] test writer::core::tests::drop_releases_the_lock ... ok
[INFO] [stdout] test writer::core::tests::force_next_write_failure_is_observed_by_fail_open_counter ... ok
[INFO] [stdout] test writer::core::tests::open_creates_parent_dir_with_mode_0700 ... ok
[INFO] [stdout] test writer::core::tests::open_sets_file_mode_to_0600 ... ok
[INFO] [stdout] test writer::core::tests::open_creates_file_and_acquires_lock ... ok
[INFO] [stdout] test writer::core::tests::open_creates_missing_parent_directory ... ok
[INFO] [stdout] test writer::core::tests::log_process_start_populates_chain_of_history_fields ... ok
[INFO] [stdout] test redact::tests::forbidden_fields_are_dropped ... ok
[INFO] [stdout] test writer::core::tests::force_next_write_failure_makes_next_write_record_fail ... ok
[INFO] [stdout] test redact::tests::verbatim_u64_rejects_string_payload ... ok
[INFO] [stdout] test writer::core::tests::log_auth_uses_writer_process_id_for_every_record ... ok
[INFO] [stdout] test writer::core::tests::second_open_against_same_path_fails_with_locked ... ok
[INFO] [stdout] test writer::core::tests::rotate_bytes_accessor_returns_configured_threshold ... ok
[INFO] [stdout] test writer::core::tests::log_process_start_marks_inode_unchanged_when_matching ... ok
[INFO] [stdout] test writer::core::tests::writer_holds_a_stable_process_id ... ok
[INFO] [stdout] test writer::core::tests::total_tool_calls_counts_each_tool_start ... ok
[INFO] [stdout] test writer::core::tests::writer_resumes_seq_from_initial_seq_option ... ok
[INFO] [stdout] test writer::core::tests::log_auth_writes_one_record_with_allocated_seq ... ok
[INFO] [stdout] test writer::core::tests::log_process_end_writes_valid_record ... ok
[INFO] [stdout] test writer::core::tests::writer_allocates_sequential_seqs ... ok
[INFO] [stdout] test writer::provenance::tests::count_cap_evicts_oldest_beyond_max_entries ... ok
[INFO] [stdout] test writer::provenance::tests::empty_buffer_snapshots_to_empty_vec ... ok
[INFO] [stdout] test writer::emit::tests::auth_process_and_config_records_are_fsynced ... ok
[INFO] [stdout] test writer::emit::tests::tool_start_and_tool_end_records_are_not_fsynced ... ok
[INFO] [stdout] test writer::core::tests::write_record_appends_one_jsonl_line ... ok
[INFO] [stdout] test writer::provenance::tests::is_empty_distinguishes_empty_from_populated ... ok
[INFO] [stdout] test writer::provenance::tests::entries_older_than_window_are_evicted_on_snapshot ... ok
[INFO] [stdout] test writer::provenance::tests::eviction_runs_before_new_inserts ... ok
[INFO] [stdout] test writer::core::tests::log_process_end_uses_writer_process_id ... ok
[INFO] [stdout] test writer::provenance::tests::len_returns_actual_entry_count ... ok
[INFO] [stdout] test writer::provenance::tests::message_id_at_exactly_max_len_is_not_truncated ... ok
[INFO] [stdout] test writer::deadline::tests::reopen_after_drop_succeeds_while_worker_idle ... ok
[INFO] [stdout] test writer::log::tests::note_auth_write_lost_increments_the_lost_record_counter ... ok
[INFO] [stdout] test writer::provenance::tests::oversize_message_id_is_truncated_with_suffix ... ok
[INFO] [stdout] test writer::provenance::tests::oversize_multibyte_message_id_truncates_at_grapheme_boundary ... ok
[INFO] [stdout] test writer::provenance::tests::records_preserve_insertion_order ... ok
[INFO] [stdout] test writer::provenance::tests::record_observably_appends_to_buffer ... ok
[INFO] [stdout] test writer::provenance::tests::snapshot_returns_actual_recorded_ids ... ok
[INFO] [stdout] test writer::provenance::tests::truncate_at_grapheme_boundary_fills_to_exact_max_bytes_for_ascii ... ok
[INFO] [stdout] test writer::provenance::tests::window_of_zero_drops_everything_immediately ... ok
[INFO] [stdout] test writer::rotation::tests::rotate_file_keep_zero_also_removes_base_rotation_just_created ... ok
[INFO] [stdout] test writer::rotation::tests::rotate_file_preserves_content_in_rotated_sibling ... ok
[INFO] [stdout] test writer::rotation::tests::rotate_file_new_active_file_is_writable_and_lockable ... ok
[INFO] [stdout] test writer::rotation::tests::rotated_path_appends_utc_stamp ... ok
[INFO] [stdout] test writer::rotation::tests::rotate_file_with_keep_zero_deletes_all_siblings ... ok
[INFO] [stdout] test writer::self_check::tests::completely_unparsable_trailing_line_returns_default ... ok
[INFO] [stdout] test writer::rotation::tests::unique_rotated_path_appends_counter_when_base_exists ... ok
[INFO] [stdout] test writer::self_check::tests::current_inode_returns_actual_file_inode ... ok
[INFO] [stdout] test writer::rotation::tests::rotate_file_skips_symlinked_siblings_during_prune ... ok
[INFO] [stdout] test writer::log::tests::auth_event_sink_emit_auth_writes_a_record_to_disk ... ok
[INFO] [stdout] test writer::self_check::tests::extracts_last_seq_and_process_id_from_trailing_line ... ok
[INFO] [stdout] test writer::self_check::tests::file_exceeding_size_cap_returns_default_state ... ok
[INFO] [stdout] test writer::self_check::tests::empty_file_returns_default_state ... ok
[INFO] [stdout] test writer::self_check::tests::metadata_error_other_than_not_found_is_propagated ... ok
[INFO] [stdout] test writer::self_check::tests::nonexistent_file_returns_default_state ... ok
[INFO] [stdout] test writer::self_check::tests::partial_trailing_line_is_ignored_in_favor_of_prior_line ... ok
[INFO] [stdout] test writer::core::tests::after_rotation_the_lock_still_blocks_new_writers ... ok
[INFO] [stdout] test writer::self_check::tests::records_most_recent_process_start_inode_after_process_end ... ok
[INFO] [stdout] test writer::rotation::tests::retention_none_preserves_count_only_behavior ... ok
[INFO] [stdout] test writer::core::tests::rotation_creates_new_file_and_preserves_contents ... ok
[INFO] [stdout] test writer::core::tests::write_record_tracks_bytes_written ... ok
[INFO] [stdout] test writer::self_check::tests::line_at_exactly_per_line_cap_is_still_parsed ... ok
[INFO] [stdout] test writer::self_check::tests::line_just_under_per_line_cap_is_parsed_normally ... ok
[INFO] [stdout] test writer::self_check::tests::records_inode_when_last_line_is_process_start ... ok
[INFO] [stdout] test writer::rotation::tests::prune_respects_retention_seconds ... ok
[INFO] [stdout] test writer::rotation::tests::rotate_file_prunes_to_keep_newest_siblings ... ok
[INFO] [stdout] test writer::self_check::tests::oversize_line_is_skipped_but_prior_record_still_parsed ... ok
[INFO] [stdout] test writer::self_check::tests::file_at_exactly_size_cap_is_still_parsed ... ok
[INFO] [stdout] test writer::deadline::tests::zero_deadline_waits_for_a_slow_write_to_complete ... ok
[INFO] [stdout] test writer::deadline::tests::write_deadline_fires_when_io_stalls ... ok
[INFO] [stdout] test writer::deadline::tests::fail_open_counts_a_timed_out_write ... ok
[INFO] [stdout] test writer::deadline::tests::seq_order_survives_a_timed_out_stall ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 173 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.08s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/concurrent_lock.rs (/opt/rustwide/target/release/build/rimap-audit/25f7608235ebb61f/out/concurrent_lock-25f7608235ebb61f)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test concurrent_open_fails_fast_with_locked ... ok
[INFO] [stderr]      Running tests/docs_anchor.rs (/opt/rustwide/target/release/build/rimap-audit/d67470173834394f/out/docs_anchor-d67470173834394f)
[INFO] [stdout] test lock_released_after_drop_allows_reopen ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test audit_log_md_defines_compatibility_contract_heading ... FAILED
[INFO] [stderr] error: test failed, to rerun pass `--test docs_anchor`
[INFO] [stdout] test audit_log_md_defines_running_multiple_mcp_clients_heading ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- audit_log_md_defines_compatibility_contract_heading stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'audit_log_md_defines_compatibility_contract_heading' (1889) panicked at tests/docs_anchor.rs:24:9:
[INFO] [stdout] failed to read /opt/docs/audit-log.md: No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x6289451da7a1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x6289451ee72a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x6289451de9dc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x6289451b6616 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6289451d2ad9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x62894516e9a0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x6289451d2c92 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x6289451b66c2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x6289451af249 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x6289451b72cd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6289451eee5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x628945161049 - docs_anchor[757e3195598342bf]::audit_log_md::{closure#0}
[INFO] [stdout]   12:     0x628945161350 - docs_anchor[757e3195598342bf]::audit_log_md
[INFO] [stdout]   13:     0x628945161c42 - <docs_anchor[757e3195598342bf]::audit_log_md_defines_compatibility_contract_heading::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x628945161deb - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   15:     0x62894516efc5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   16:     0x6289451688b4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   17:     0x628945172052 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   18:     0x6289451d9c9f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   19:     0x7be2cb484dfa - <unknown>
[INFO] [stdout]   20:     0x7be2cb5183d4 - clone
[INFO] [stdout]   21:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- audit_log_md_defines_running_multiple_mcp_clients_heading stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'audit_log_md_defines_running_multiple_mcp_clients_heading' (1890) panicked at tests/docs_anchor.rs:24:9:
[INFO] [stdout] failed to read /opt/docs/audit-log.md: No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x6289451da7a1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x6289451ee72a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x6289451de9dc - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x6289451b6616 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6289451d2ad9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x62894516e9a0 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x6289451d2c92 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x6289451b66c2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x6289451af249 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x6289451b72cd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6289451eee5c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x628945161049 - docs_anchor[757e3195598342bf]::audit_log_md::{closure#0}
[INFO] [stdout]   12:     0x628945161350 - docs_anchor[757e3195598342bf]::audit_log_md
[INFO] [stdout]   13:     0x628945161cf2 - <docs_anchor[757e3195598342bf]::audit_log_md_defines_running_multiple_mcp_clients_heading::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x628945161deb - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   15:     0x62894516efc5 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   16:     0x6289451688b4 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   17:     0x628945172052 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   18:     0x6289451d9c9f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   19:     0x7be2cb484dfa - <unknown>
[INFO] [stdout]   20:     0x7be2cb5183d4 - clone
[INFO] [stdout]   21:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     audit_log_md_defines_compatibility_contract_heading
[INFO] [stdout]     audit_log_md_defines_running_multiple_mcp_clients_heading
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "f50467bbd2098a1660611d76f453219f165476504e191b5a662437e03f45ee7d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f50467bbd2098a1660611d76f453219f165476504e191b5a662437e03f45ee7d", kill_on_drop: false }`
[INFO] [stdout] f50467bbd2098a1660611d76f453219f165476504e191b5a662437e03f45ee7d
