[INFO] fetching crate hayson-engine 0.19.0...
[INFO] testing hayson-engine-0.19.0 against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate hayson-engine 0.19.0 into /workspace/builds/worker-1-tc1/source
[INFO] started tweaking crates.io crate hayson-engine 0.19.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate hayson-engine 0.19.0
[INFO] tweaked toml for crates.io crate hayson-engine 0.19.0 written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate hayson-engine 0.19.0 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate hayson-engine 0.19.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.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/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] 1e31ca407e51b4d4ed93e10f01c0fb80d528a0aef9184101a10fe74713ce5b59
[INFO] running `Command { std: "docker" "start" "1e31ca407e51b4d4ed93e10f01c0fb80d528a0aef9184101a10fe74713ce5b59", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1e31ca407e51b4d4ed93e10f01c0fb80d528a0aef9184101a10fe74713ce5b59", 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" "1e31ca407e51b4d4ed93e10f01c0fb80d528a0aef9184101a10fe74713ce5b59" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1e31ca407e51b4d4ed93e10f01c0fb80d528a0aef9184101a10fe74713ce5b59", 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" "1e31ca407e51b4d4ed93e10f01c0fb80d528a0aef9184101a10fe74713ce5b59" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling crypto-common v0.2.2
[INFO] [stderr]    Compiling inout v0.2.2
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling cpubits v0.1.1
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling indexmap v2.14.1
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling universal-hash v0.6.1
[INFO] [stderr]    Compiling cipher v0.5.2
[INFO] [stderr]    Compiling aead v0.6.1
[INFO] [stderr]    Compiling polyval v0.7.3
[INFO] [stderr]    Compiling syn v3.0.4
[INFO] [stderr]    Compiling ghash v0.6.0
[INFO] [stderr]    Compiling ctr v0.10.1
[INFO] [stderr]    Compiling aes v0.9.3
[INFO] [stderr]    Compiling aes-gcm v0.11.1
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling hayson-engine v0.19.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]    --> src/database/verify.rs:351:21
[INFO] [stdout]     |
[INFO] [stdout] 351 |                 let mut report = |checker: &mut Self, severity, code, message: String| {
[INFO] [stdout]     |                     ----^^^^^^
[INFO] [stdout]     |                     |
[INFO] [stdout]     |                     help: remove this `mut`
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_mut)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 36.18s
[INFO] running `Command { std: "docker" "inspect" "1e31ca407e51b4d4ed93e10f01c0fb80d528a0aef9184101a10fe74713ce5b59", 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" "1e31ca407e51b4d4ed93e10f01c0fb80d528a0aef9184101a10fe74713ce5b59" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling fastrand v2.5.0
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]    --> src/database/verify.rs:351:21
[INFO] [stdout]     |
[INFO] [stdout] 351 |                 let mut report = |checker: &mut Self, severity, code, message: String| {
[INFO] [stdout]     |                     ----^^^^^^
[INFO] [stdout]     |                     |
[INFO] [stdout]     |                     help: remove this `mut`
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_mut)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling hayson-engine v0.19.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]    --> src/database/verify.rs:351:21
[INFO] [stdout]     |
[INFO] [stdout] 351 |                 let mut report = |checker: &mut Self, severity, code, message: String| {
[INFO] [stdout]     |                     ----^^^^^^
[INFO] [stdout]     |                     |
[INFO] [stdout]     |                     help: remove this `mut`
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_mut)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 1m 10s
[INFO] running `Command { std: "docker" "inspect" "1e31ca407e51b4d4ed93e10f01c0fb80d528a0aef9184101a10fe74713ce5b59", 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" "1e31ca407e51b4d4ed93e10f01c0fb80d528a0aef9184101a10fe74713ce5b59" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: variable does not need to be mutable
[INFO] [stderr]    --> src/database/verify.rs:351:21
[INFO] [stderr]     |
[INFO] [stderr] 351 |                 let mut report = |checker: &mut Self, severity, code, message: String| {
[INFO] [stderr]     |                     ----^^^^^^
[INFO] [stderr]     |                     |
[INFO] [stderr]     |                     help: remove this `mut`
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_mut)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `hayson-engine` (lib) generated 1 warning (run `cargo fix --lib -p hayson-engine` to apply 1 suggestion)
[INFO] [stderr] warning: `hayson-engine` (lib test) generated 1 warning (1 duplicate)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/hayson_engine-2adad655275df09b)
[INFO] [stdout] 
[INFO] [stdout] running 754 tests
[INFO] [stdout] test atomic::temp_file::tests::refuses_a_planted_symlink_and_leaves_the_victim_untouched ... ok
[INFO] [stdout] test buffer::entry::tests::round_trips_a_delete_without_a_payload ... ok
[INFO] [stdout] test buffer::entry::tests::round_trips_a_put_with_its_payload ... ok
[INFO] [stdout] test buffer::entry::tests::carries_no_final_seq_field ... ok
[INFO] [stdout] test atomic::temp_file::tests::replaces_plain_residue_left_by_a_killed_process ... ok
[INFO] [stdout] test buffer::merge::tests::the_same_key_changed_differently_in_two_buffers_is_a_conflict_not_clean ... ok
[INFO] [stdout] test atomic::temp_file::tests::a_residue_that_cannot_be_cleared_surfaces_as_the_domain_error ... ok
[INFO] [stdout] test buffer::entry::tests::refuses_lines_that_are_not_usable ... ok
[INFO] [stdout] test buffer::resolve::tests::custom_builds_a_fresh_put_from_the_given_value ... ok
[INFO] [stdout] test buffer::session::tests::format_conflict_markers_adds_a_base_section_when_a_base_is_given ... ok
[INFO] [stdout] test atomic::temp_file::tests::write_atomic_is_not_written_through_a_planted_symlink_on_the_temporary_path ... ok
[INFO] [stdout] test buffer::parser::tests::reads_two_folders_and_keeps_each_entrys_own_source ... ok
[INFO] [stdout] test buffer::merge::tests::two_folders_without_conflicts_merge_in_buffered_at_order ... ok
[INFO] [stdout] test buffer::session::tests::format_conflict_markers_shows_every_version_between_markers ... ok
[INFO] [stdout] test catalog::build::tests::create_table_keeps_documentation_and_created_at_of_a_previous_entry ... ok
[INFO] [stdout] test buffer::resolve::tests::theirs_picks_the_named_version ... ok
[INFO] [stdout] test atomic::temp_file::tests::creates_with_the_given_mode_without_a_later_chmod ... ok
[INFO] [stdout] test catalog::build::tests::drop_field_refuses_the_primary_key ... ok
[INFO] [stdout] test atomic::temp_file::tests::write_atomic_creates_the_target_and_leaves_no_temporary_behind ... ok
[INFO] [stdout] test catalog::build::tests::drop_field_succeeds_when_nothing_depends_on_the_field ... ok
[INFO] [stdout] test buffer::session::tests::continue_merge_refuses_and_writes_nothing_before_full_resolution ... ok
[INFO] [stdout] test catalog::build::tests::drop_field_refuses_when_a_named_index_still_uses_the_field ... ok
[INFO] [stdout] test atomic::temp_file::tests::creates_and_writes_a_fresh_temporary_file ... ok
[INFO] [stdout] test buffer::resolve::tests::ours_picks_the_named_version ... ok
[INFO] [stdout] test buffer::session::tests::resolving_one_of_two_conflicts_leaves_the_session_unresolved ... ok
[INFO] [stdout] test buffer::merge::tests::a_write_clean_across_buffers_still_conflicts_on_unique_against_current_db_state ... ok
[INFO] [stdout] test buffer::merge::tests::merging_a_buffer_never_fires_a_trigger_the_live_write_already_fired ... ok
[INFO] [stdout] test catalog::declare_index::tests::refuses_a_duplicate_index_name ... ok
[INFO] [stdout] test catalog::build::tests::create_table_adds_a_fresh_table_to_an_empty_catalog ... ok
[INFO] [stdout] test catalog::integrity::tests::relations_touching_filters_by_either_end ... ok
[INFO] [stdout] test catalog::declare_index::tests::refuses_when_the_table_is_not_in_the_catalog ... ok
[INFO] [stdout] test catalog::build::tests::drop_field_refuses_when_a_check_mentions_the_field_by_name ... ok
[INFO] [stdout] test catalog::declare_relation::tests::declares_a_relation_with_no_orphans_to_check ... ok
[INFO] [stdout] test catalog::migrate::tests::is_format_one_directory_is_false_on_an_empty_directory ... ok
[INFO] [stdout] test catalog::migrate::tests::is_format_one_directory_is_true_where_migrate_format1_would_find_something_to_rewrite ... ok
[INFO] [stdout] test catalog::migrate::tests::is_format_one_directory_refuses_a_path_that_is_not_a_directory ... ok
[INFO] [stdout] test buffer::parser::tests::ignores_files_without_the_jsonl_extension ... ok
[INFO] [stdout] test catalog::declare_relation::tests::refuses_a_duplicate_relation_name ... ok
[INFO] [stdout] test catalog::migrate::tests::migrate_format1_refuses_a_directory_holding_no_schema_files_without_writing_a_catalog ... ok
[INFO] [stdout] test catalog::declare_relation::tests::force_declares_enforce_true_over_existing_orphans ... ok
[INFO] [stdout] test catalog::declare_relation::tests::refuses_enforce_true_when_a_live_record_already_orphans_it ... ok
[INFO] [stdout] test buffer::merge::tests::a_buffer_entry_earlier_than_an_already_committed_write_never_jumps_ahead_of_it ... ok
[INFO] [stdout] test catalog::integrity::tests::a_relation_naming_a_table_not_supplied_reads_as_empty ... ok
[INFO] [stdout] test catalog::migrate::tests::migrate_format1_refuses_a_marker_that_is_neither_1_nor_2_and_keeps_the_schema_files ... ok
[INFO] [stdout] test catalog::migrate::tests::migrate_format1_refuses_a_path_that_is_not_a_directory ... ok
[INFO] [stdout] test catalog::migrate::tests::is_format_one_directory_refuses_an_unknown_marker_the_same_way_migrate_format1_does ... ok
[INFO] [stdout] test catalog::migrate::tests::migrate_format1_leaves_the_log_byte_identical ... ok
[INFO] [stdout] test catalog::migrate::tests::migrate_format1_refuses_a_schema_file_naming_a_table_outside_the_directory_before_writing_anything ... ok
[INFO] [stdout] test catalog::migrate::tests::migrates_a_unique_field_that_has_no_live_duplicate ... ok
[INFO] [stdout] test catalog::declare_relation::tests::enforce_true_declares_cleanly_when_every_reference_resolves ... ok
[INFO] [stdout] test catalog::declare_index::tests::a_non_unique_index_is_declared_without_scanning_for_clashes ... ok
[INFO] [stdout] test catalog::migrate::tests::migrate_format1_refuses_while_an_unfinished_unit_of_work_is_on_disk ... ok
[INFO] [stdout] test catalog::integrity::tests::a_record_with_no_value_on_the_from_field_is_unlinked_not_orphaned ... ok
[INFO] [stdout] test catalog::migrate::tests::migrates_every_schema_file_of_the_directory_into_one_catalog ... ok
[INFO] [stdout] test catalog::migrate::tests::is_format_one_directory_is_false_on_a_directory_already_at_format_2 ... ok
[INFO] [stdout] test catalog::declare_index::tests::declares_a_unique_index_when_live_records_already_hold_it ... ok
[INFO] [stdout] test catalog::migrate::tests::migrates_a_partitioned_table_whose_partitions_hold_no_duplicate ... ok
[INFO] [stdout] test catalog::migrate::tests::refuses_a_nested_layout_table_whose_log_holds_a_unique_duplicate ... ok
[INFO] [stdout] test buffer::session::tests::continue_merge_applies_clean_and_resolved_entries_once_fully_resolved ... ok
[INFO] [stdout] test catalog::declare_index::tests::refuses_a_unique_index_when_live_records_already_clash_and_changes_nothing ... ok
[INFO] [stdout] test catalog::migrate::tests::migrate_format1_writes_the_catalog_no_looser_than_the_manifest_it_replaces ... ok
[INFO] [stdout] test catalog::migrate::tests::migrates_when_no_table_declares_a_unique_field ... ok
[INFO] [stdout] test catalog::migrate::tests::migrates_a_nested_layout_table_whose_log_holds_no_duplicate ... ok
[INFO] [stdout] test catalog::parse::tests::parses_a_table_declared_lazy_link_parsing_option ... ok
[INFO] [stdout] test catalog::migrate::tests::migrate_format1_a_second_run_reports_a_no_op_and_rewrites_nothing ... ok
[INFO] [stdout] test catalog::migrate::tests::refuses_a_partitioned_table_whose_partitions_together_hold_a_unique_duplicate ... ok
[INFO] [stdout] test catalog::migrate::tests::refuses_the_whole_migration_when_one_table_has_a_live_unique_duplicate ... ok
[INFO] [stdout] test catalog::parse::tests::a_non_addressable_markdown_field_survives_the_catalog_it_is_written_into ... ok
[INFO] [stdout] test catalog::parse::tests::parses_a_catalog_that_still_carries_the_marker_under_the_old_key ... ok
[INFO] [stdout] test catalog::parse::tests::parses_a_catalog_that_carries_the_marker_under_the_current_key_alone ... ok
[INFO] [stdout] test catalog::parse::tests::parses_the_format_md_reference_document ... ok
[INFO] [stdout] test catalog::parse::tests::parses_a_declared_trigger_and_round_trips_it ... ok
[INFO] [stdout] test catalog::parse::tests::rejects_a_catalog_with_marker_1 ... ok
[INFO] [stdout] test catalog::migrate::tests::refuses_while_a_journal_is_on_disk ... ok
[INFO] [stdout] test catalog::parse::tests::rejects_an_unknown_top_level_option ... ok
[INFO] [stdout] test catalog::parse::tests::rejects_a_formatversion_newer_than_this_build_reads ... ok
[INFO] [stdout] test catalog::parse::tests::parses_a_table_declared_image_dir_option ... ok
[INFO] [stdout] test catalog::migrate::tests::round_trips_through_serialize_and_parse ... ok
[INFO] [stdout] test catalog::parse::tests::rejects_a_declared_index_whose_name_collides_with_field_modifier_sugar_under_different_content ... ok
[INFO] [stdout] test catalog::parse::tests::rejects_a_marker_that_is_not_format_1_without_naming_the_migration_tool ... ok
[INFO] [stdout] test catalog::parse::tests::rejects_a_trigger_naming_a_source_table_the_catalog_does_not_declare ... ok
[INFO] [stdout] test catalog::parse::tests::rejects_a_where_predicate_naming_an_unknown_field ... ok
[INFO] [stdout] test catalog::parse::tests::rejects_an_unsupported_link_parsing_mode ... ok
[INFO] [stdout] test catalog::parse::tests::rejects_a_formatversion_older_than_the_catalog_and_names_the_migration_tool ... ok
[INFO] [stdout] test catalog::parse::tests::rejects_a_trigger_whose_effect_names_a_table_the_catalog_does_not_declare ... ok
[INFO] [stdout] test catalog::parse::tests::rejects_a_relation_pointing_at_a_non_unique_field ... ok
[INFO] [stdout] test catalog::integrity::tests::reports_a_record_whose_reference_has_no_counterpart ... ok
[INFO] [stdout] test catalog::parse::tests::rejects_markdown_and_image_in_a_sorted_index_of_any_width ... ok
[INFO] [stdout] test catalog::parse::tests::rewrites_an_old_keyed_catalog_under_the_current_key_alone ... ok
[INFO] [stdout] test catalog::parse::tests::round_trips_parse_serialize_parse_to_an_identical_catalog ... ok
[INFO] [stdout] test catalog::parse::tests::serializes_to_the_same_bytes_whatever_order_the_keys_arrived_in ... ok
[INFO] [stdout] test catalog::parse::tests::rejects_two_triggers_sharing_a_name ... ok
[INFO] [stdout] test catalog::write::tests::refuses_before_touching_disk_when_the_catalog_cannot_be_read_back ... ok
[INFO] [stdout] test checkpoint::restore::tests::falls_back_without_erroring_when_no_snapshot_file_exists ... ok
[INFO] [stdout] test checkpoint::restore::tests::falls_back_without_erroring_when_the_snapshot_file_is_truncated_garbage ... ok
[INFO] [stdout] test checkpoint::restore::tests::rejects_a_snapshot_whose_schema_hash_no_longer_matches ... ok
[INFO] [stdout] test checkpoint::restore::tests::rejects_a_paired_snapshot_whose_content_fingerprint_was_tampered_with ... ok
[INFO] [stdout] test catalog::migrate::tests::migrate_format1_rewrites_a_format_1_directory_into_a_catalog_the_engine_opens ... ok
[INFO] [stdout] test checkpoint::restore::tests::rebuilds_from_the_log_when_the_snapshot_carries_the_pre_rename_marker ... ok
[INFO] [stdout] test checkpoint::restore::tests::rejects_a_partitioned_snapshot_of_a_partition_that_was_rewritten_and_then_grown_past_it ... ok
[INFO] [stdout] test checkpoint::restore::tests::falls_back_to_a_full_scan_when_the_log_grew_after_the_snapshot_was_taken ... ok
[INFO] [stdout] test checkpoint::restore::tests::rejects_a_snapshot_of_a_log_generation_that_was_rewritten_and_then_grown_past_it ... ok
[INFO] [stdout] test catalog::write::tests::writes_the_catalog_atomically ... ok
[INFO] [stdout] test compaction::compact::tests::compacts_an_all_tombstones_log_down_to_empty ... ok
[INFO] [stdout] test checkpoint::restore::tests::write_snapshot_leaves_no_temporary_file_behind ... ok
[INFO] [stdout] test checkpoint::restore::tests::write_snapshot_is_atomic_and_does_not_write_through_a_symlink_at_the_target_path ... ok
[INFO] [stdout] test checkpoint::restore::tests::a_rejected_snapshot_names_the_index_the_log_and_the_watermark_it_claimed ... ok
[INFO] [stdout] test checkpoint::restore::tests::a_rejected_partitioned_snapshot_names_the_partition_whose_fingerprint_disagreed ... ok
[INFO] [stdout] test crypto::field_cipher::tests::a_carried_envelope_sealed_for_another_record_is_refused ... ok
[INFO] [stdout] test crypto::field_cipher::tests::a_carried_envelope_under_a_key_that_is_not_on_hand_is_taken_on_trust ... ok
[INFO] [stdout] test crypto::field_cipher::tests::a_carried_value_that_is_not_an_envelope_is_refused ... ok
[INFO] [stdout] test crypto::field_cipher::tests::decryption_fails_when_the_ciphertext_is_tampered_with ... ok
[INFO] [stdout] test crypto::field_cipher::tests::decryption_fails_with_the_wrong_key ... ok
[INFO] [stdout] test crypto::field_cipher::tests::insists_on_a_key_id_when_several_keys_are_given ... ok
[INFO] [stdout] test crypto::field_cipher::tests::missing_key_policy_raw_returns_the_envelope_unchanged ... ok
[INFO] [stdout] test crypto::field_cipher::tests::missing_key_policy_throw_is_the_default ... ok
[INFO] [stdout] test checkpoint::restore::tests::restores_a_registry_that_answers_queries_identically_to_the_one_it_was_snapshotted_from ... ok
[INFO] [stdout] test crypto::field_cipher::tests::moving_a_value_to_another_field_fails_authentication ... ok
[INFO] [stdout] test crypto::field_cipher::tests::missing_key_policy_omit_drops_the_field ... ok
[INFO] [stdout] test crypto::field_cipher::tests::open_record_with_omit_policy_drops_the_field ... ok
[INFO] [stdout] test crypto::field_cipher::tests::moving_a_value_to_another_record_fails_authentication ... ok
[INFO] [stdout] test crypto::field_cipher::tests::rejects_an_unknown_key_id ... ok
[INFO] [stdout] test crypto::field_cipher::tests::moving_a_value_to_another_table_fails_authentication ... ok
[INFO] [stdout] test crypto::field_cipher::tests::round_trips_a_value_with_the_correct_key ... ok
[INFO] [stdout] test crypto::field_cipher::tests::seal_record_carries_a_verified_envelope_byte_for_byte ... ok
[INFO] [stdout] test crypto::field_cipher::tests::seal_record_seals_a_genuine_envelope_again_when_it_is_not_carried ... ok
[INFO] [stdout] test crypto::field_cipher::tests::seal_record_without_a_cipher_refuses_to_write_a_cypher_field ... ok
[INFO] [stdout] test crypto::field_cipher::tests::seal_record_leaves_a_null_value_alone ... ok
[INFO] [stdout] test crypto::field_cipher::tests::seal_record_without_a_cipher_still_refuses_a_carried_value_that_is_not_an_envelope ... ok
[INFO] [stdout] test crypto::field_cipher::tests::seal_record_seals_a_plaintext_that_only_looks_like_an_envelope ... ok
[INFO] [stdout] test crypto::field_cipher::tests::two_equal_values_get_two_different_envelopes ... ok
[INFO] [stdout] test crypto::field_cipher::tests::rejects_a_key_that_is_not_32_bytes ... ok
[INFO] [stdout] test database::database::tests::a_catalog_write_that_fails_for_any_other_reason_is_still_a_catalog_refusal ... ok
[INFO] [stdout] test crypto::field_cipher::tests::seal_then_open_round_trips_every_cypher_field ... ok
[INFO] [stdout] test database::database::tests::a_database_and_its_tables_can_be_shared_across_threads ... ok
[INFO] [stdout] test compaction::compact::tests::registry_remap_after_compaction_still_answers_queries_correctly ... ok
[INFO] [stdout] test compaction::compact::tests::keeps_only_the_last_version_of_an_overwritten_key ... ok
[INFO] [stdout] test compaction::compact::tests::reclaims_the_space_freed_by_dead_entries_and_reports_it ... ok
[INFO] [stdout] test compaction::compact::tests::resulting_file_is_a_usable_log_file_that_still_accepts_appends ... ok
[INFO] [stdout] test compaction::compact::tests::drops_a_key_that_was_put_then_deleted ... ok
[INFO] [stdout] test checkpoint::restore::tests::rejects_a_snapshot_laid_over_a_shorter_log_it_was_never_taken_of ... ok
[INFO] [stdout] test compaction::compact::tests::original_log_is_untouched_if_the_process_dies_before_the_rename ... ok
[INFO] [stdout] test checkpoint::restore::tests::keeps_rebuilding_quietly_when_the_log_vanished_and_reopened_empty_under_its_snapshot ... ok
[INFO] [stdout] test checkpoint::restore::tests::keeps_rebuilding_quietly_when_an_unflushed_tail_was_lost_but_the_leading_window_agrees ... ok
[INFO] [stdout] test checkpoint::restore::tests::a_snapshot_written_without_a_prefix_fingerprint_still_rebuilds_quietly_under_a_short_log ... ok
[INFO] [stdout] test database::database::tests::a_catalog_naming_no_sync_anywhere_leaves_every_table_committing ... ok
[INFO] [stdout] test database::database::tests::a_catalog_marking_no_field_deprecated_leaves_every_table_without_the_warning ... ok
[INFO] [stdout] test database::database::tests::a_database_wide_sync_default_reaches_a_table_declaring_none_of_its_own ... ok
[INFO] [stdout] test database::database::tests::a_database_wide_default_names_the_link_parsing_mode_a_table_declaring_none_opens_with ... ok
[INFO] [stdout] test database::database::tests::a_declared_table_whose_log_is_not_on_disk::a_readonly_open_accepts_a_partitioned_table_that_was_never_written_to ... ok
[INFO] [stdout] test database::database::tests::a_database_wide_default_names_the_image_dir_a_table_declaring_none_writes_under ... ok
[INFO] [stdout] test database::database::tests::a_close_refused_by_a_swapped_log_still_releases_the_directory_lock ... ok
[INFO] [stdout] test database::database::tests::a_declared_table_whose_log_is_not_on_disk::a_readonly_open_refuses_naming_the_table_and_the_log_without_advising_create_table ... ok
[INFO] [stdout] test database::database::tests::a_close_after_a_swapped_log_leaves_no_snapshot_so_the_reopen_reads_the_new_file ... ok
[INFO] [stdout] test database::database::tests::a_declared_table_whose_log_is_not_on_disk::a_readonly_open_accepts_a_log_that_is_present_and_empty ... ok
[INFO] [stdout] test database::database::tests::a_declared_table_whose_log_is_not_on_disk::a_readonly_open_reports_a_partition_that_cannot_be_opened_against_its_own_path ... ok
[INFO] [stdout] test database::database::tests::a_declared_table_whose_log_is_not_on_disk::a_readonly_open_refuses_a_partitioned_table_whose_recorded_partition_is_gone ... ok
[INFO] [stdout] test database::database::tests::a_declared_composite_index_still_serves_queries_after_closing_and_reopening_the_database ... ok
[INFO] [stdout] test database::database::tests::a_second_open_of_the_same_directory_is_denied_while_the_first_still_holds_the_lock ... ok
[INFO] [stdout] test database::database::tests::a_directory_refused_for_a_bad_cipher_key_is_left_unlocked_too ... ok
[INFO] [stdout] test database::database::tests::a_declared_table_whose_log_is_not_on_disk::a_writable_open_of_the_same_directory_creates_the_log_and_serves_an_empty_table ... ok
[INFO] [stdout] test database::database::tests::a_directory_opens_normally_after_an_open_that_was_refused_a_broken_catalog ... ok
[INFO] [stdout] test database::database::tests::a_composite_unique_index_declared_after_a_snapshot_still_holds_across_partitions ... ok
[INFO] [stdout] test database::database::tests::a_declared_trigger_fires_its_effect_immediately_on_the_next_unit_of_work ... ok
[INFO] [stdout] test database::database::tests::a_directory_refused_at_open_is_left_unlocked_whichever_way_the_catalog_was_broken ... ok
[INFO] [stdout] test database::database::tests::a_directory_with_logs_but_no_catalog_is_refused_instead_of_opening_empty ... ok
[INFO] [stdout] test database::database::tests::a_low_snapshot_every_writes_the_index_without_waiting_for_close ... ok
[INFO] [stdout] test database::database::tests::a_declared_trigger_still_fires_after_create_table_migrated_its_source_table ... ok
[INFO] [stdout] test database::database::tests::a_table_declaring_no_link_parsing_option_defaults_to_eager ... ok
[INFO] [stdout] test database::database::tests::a_declared_trigger_still_fires_after_closing_and_reopening_the_database ... ok
[INFO] [stdout] test database::database::tests::a_declared_trigger_still_fires_after_declare_index_reopened_its_source_table ... ok
[INFO] [stdout] test database::database::tests::a_table_declaring_its_own_link_parsing_mode_beats_the_database_wide_default ... ok
[INFO] [stdout] test database::database::tests::a_table_declaring_a_lazy_link_parsing_option_opens_with_it_instead_of_the_eager_default ... ok
[INFO] [stdout] test database::database::tests::a_declared_trigger_still_fires_after_drop_field_reopened_its_source_table ... ok
[INFO] [stdout] test database::database::tests::a_table_declaring_an_image_dir_option_writes_bytes_under_it_instead_of_the_default ... ok
[INFO] [stdout] test database::database::tests::a_sync_never_table_survives_a_close_and_reopen_with_its_writes_and_its_mode ... ok
[INFO] [stdout] test database::database::tests::a_table_declaring_no_validation_refuses_a_write_with_a_stray_field ... ok
[INFO] [stdout] test database::database::tests::a_table_declaring_its_own_sync_mode_beats_the_database_wide_default ... ok
[INFO] [stdout] test database::database::tests::a_snapshot_taken_mid_stream_does_not_disturb_writes_that_follow_it ... ok
[INFO] [stdout] test database::database::tests::a_lock_skipping_handle_that_refreshes_first_keeps_both_declarations ... ok
[INFO] [stdout] test database::database::tests::a_lock_skipping_handle_that_never_refreshed_loses_the_declaration_of_the_locked_one ... ok
[INFO] [stdout] test database::database::tests::an_open_that_succeeded_still_holds_the_lock_until_the_handle_closes ... ok
[INFO] [stdout] test database::database::tests::an_empty_directory_and_one_holding_no_logs_still_open_as_a_new_database ... ok
[INFO] [stdout] test database::database::tests::a_table_declaring_validation_warn_takes_a_write_with_a_stray_field_and_stores_it_without_that_field ... ok
[INFO] [stdout] test database::database::tests::a_write_touching_a_field_the_catalog_marks_deprecated_warns_with_the_field_and_the_note ... ok
[INFO] [stdout] test database::database::tests::a_second_writer_that_skips_the_lock_opens_and_writes_beside_the_one_holding_it ... ok
[INFO] [stdout] test database::database::tests::automatic_compaction::an_open_call_naming_nothing_falls_back_to_the_engine_default_thresholds ... ok
[INFO] [stdout] test database::database::tests::a_where_scoped_index_answers_only_a_query_that_repeats_its_predicate ... ok
[INFO] [stdout] test database::database::tests::a_snapshot_written_before_an_index_was_declared_is_rebuilt_rather_than_restored_into_it ... ok
[INFO] [stdout] test database::database::tests::automatic_compaction::a_tables_own_entry_beats_the_catalog_defaults_and_an_open_call_beats_them_both ... ok
[INFO] [stdout] test database::database::tests::cascading_delete::a_missing_key_answers_false_without_staging_anything ... ok
[INFO] [stdout] test database::database::tests::cascading_delete::delete_checked_refuses_on_a_readonly_handle ... ok
[INFO] [stdout] test database::database::tests::cascading_delete::a_table_no_relation_names_as_to_goes_straight_through_plain_delete ... ok
[INFO] [stdout] test database::database::tests::automatic_compaction::garbage_below_the_ratio_leaves_the_log_alone_however_many_bytes_it_is ... ok
[INFO] [stdout] test database::database::tests::cascading_delete::restrict_allows_the_delete_once_nothing_references_it ... ok
[INFO] [stdout] test database::database::tests::cascading_delete::a_restrict_deep_in_the_cascade_aborts_the_whole_delete_atomically ... ok
[INFO] [stdout] test database::database::tests::cascading_delete::no_action_and_undeclared_leave_referencing_records_untouched ... ok
[INFO] [stdout] test database::database::tests::close_commits_a_staged_write_so_a_reopen_still_finds_it ... ok
[INFO] [stdout] test database::database::tests::cascading_delete::restrict_refuses_when_a_referencing_record_exists_and_leaves_everything_untouched ... ok
[INFO] [stdout] test database::database::tests::create_table_refused_by_a_directory_on_the_catalog_temporary_path_keeps_the_typed_refusal ... ok
[INFO] [stdout] test database::database::tests::automatic_compaction::a_catalog_declaring_compaction_false_keeps_the_garbage_an_open_call_named_nothing_about ... ok
[INFO] [stdout] test database::database::tests::cascading_delete::cascade_deletes_every_referencing_record ... ok
[INFO] [stdout] test database::database::tests::close_snapshots_dirty_tables_so_a_reopen_restores_instead_of_rebuilding ... ok
[INFO] [stdout] test database::database::tests::cascading_delete::a_cycle_of_cascading_relations_does_not_recurse_forever ... ok
[INFO] [stdout] test database::database::tests::automatic_compaction::garbage_under_the_absolute_floor_leaves_the_log_alone_however_large_its_share ... ok
[INFO] [stdout] test database::database::tests::create_table_refuses_a_schema_naming_a_table_other_than_the_one_being_created ... ok
[INFO] [stdout] test database::database::tests::cypher::open_refuses_a_key_that_is_not_32_bytes ... ok
[INFO] [stdout] test database::database::tests::cascading_delete::cascade_recurses_through_a_chain_of_relations ... ok
[INFO] [stdout] test database::database::tests::automatic_compaction::a_partitioned_table_is_measured_and_rewritten_one_partition_at_a_time ... ok
[INFO] [stdout] test database::database::tests::cascading_delete::set_null_clears_the_referencing_field_instead_of_deleting_the_row ... ok
[INFO] [stdout] test database::database::tests::create_table_refuses_on_a_readonly_database_and_changes_nothing ... ok
[INFO] [stdout] test database::database::tests::create_table_on_an_open_database_adds_a_new_working_table_immediately ... ok
[INFO] [stdout] test database::database::tests::create_table_still_takes_a_schema_naming_exactly_the_table_it_creates ... ok
[INFO] [stdout] test database::database::tests::cypher::keeps_the_keyring_across_drop_field_reopening_the_table ... ok
[INFO] [stdout] test database::database::tests::cypher::a_cypher_field_round_trips_through_open_created_table_and_reopen ... ok
[INFO] [stdout] test database::database::tests::cypher::missing_key_policy_raw_hands_back_the_envelope_through_the_database ... ok
[INFO] [stdout] test database::database::tests::declare_trigger_refuses_an_unknown_effect_table_and_changes_nothing_on_disk ... ok
[INFO] [stdout] test database::database::tests::declare_index_with_a_conflicting_unique_value_refuses_and_changes_nothing ... ok
[INFO] [stdout] test database::database::tests::automatic_compaction::a_table_created_under_a_handle_is_armed_on_the_same_terms_as_one_opened_with_it ... ok
[INFO] [stdout] test database::database::tests::automatic_compaction::crossing_both_thresholds_rewrites_the_log_and_moves_the_generation_on ... ok
[INFO] [stdout] test database::database::tests::declare_relation_adds_a_relation_to_the_catalog_and_persists_it ... ok
[INFO] [stdout] test database::database::tests::describing_one_table::refuses_a_table_this_handle_holds_nowhere ... ok
[INFO] [stdout] test database::database::tests::describing_one_table::describes_the_index_set_the_handle_serves_and_leaves_the_named_ones_out ... ok
[INFO] [stdout] test database::database::tests::declare_trigger_writes_the_catalog_and_refreshes_both_tables_live_trigger_set ... ok
[INFO] [stdout] test database::database::tests::describing_one_table::describes_a_table_of_its_own_while_another_one_has_drifted ... ok
[INFO] [stdout] test database::database::tests::declare_relation_with_enforce_true_refuses_over_a_live_orphan_and_force_overrides ... ok
[INFO] [stdout] test database::database::tests::declare_index_without_a_conflict_covers_records_written_before_the_declaration ... ok
[INFO] [stdout] test database::database::tests::describing_one_table::refuses_the_table_that_drifted_itself ... ok
[INFO] [stdout] test database::database::tests::declare_index_of_a_composite_field_pair_serves_a_query_matching_both_fields ... ok
[INFO] [stdout] test database::database::tests::automatic_compaction::a_log_the_thresholds_rewrote_reopens_at_the_generation_and_the_records_it_left ... ok
[INFO] [stdout] test database::database::tests::cypher::reads_with_an_old_key_and_writes_with_the_new_one_after_rotation ... ok
[INFO] [stdout] test database::database::tests::declare_index_snapshots_the_reopened_table_and_keeps_snapshotting_it_afterwards ... ok
[INFO] [stdout] test database::database::tests::drop_field_names_the_declaration_when_it_is_the_only_thing_blocking_it ... ok
[INFO] [stdout] test database::database::tests::drop_field_names_the_declaration_when_residual_records_also_block_it ... ok
[INFO] [stdout] test database::database::tests::drop_field_keeps_refusing_a_declared_dependency_even_with_force ... ok
[INFO] [stdout] test database::database::tests::drop_field_names_the_residual_records_when_no_declaration_blocks_it ... ok
[INFO] [stdout] test database::database::tests::following_the_log::a_readonly_open_follows_by_default_and_a_writable_one_does_not ... ok
[INFO] [stdout] test database::database::tests::drop_field_refuses_a_live_residual_field_without_force ... ok
[INFO] [stdout] test database::database::tests::open_restores_the_registry_from_a_paired_snapshot_instead_of_scanning_the_log ... ok
[INFO] [stdout] test database::database::tests::following_the_log::a_plain_query_on_a_following_reader_counts_what_the_writer_committed ... ok
[INFO] [stdout] test database::database::tests::drop_field_without_dependents_removes_it_from_disk_and_from_memory ... ok
[INFO] [stdout] test database::database::tests::following_the_log::a_reader_with_follow_off_stands_still_until_refresh_is_called ... ok
[INFO] [stdout] test database::database::tests::open_builds_a_database_with_every_declared_table_available ... ok
[INFO] [stdout] test database::database::tests::following_the_log::get_many_and_the_aggregates_follow_the_log_the_same_way_a_single_get_does ... ok
[INFO] [stdout] test database::database::tests::following_the_log::a_plain_get_on_a_following_reader_sees_what_the_writer_committed_after_it_opened ... ok
[INFO] [stdout] test database::database::tests::partitions::dropping_the_field_the_table_partitions_by_is_refused ... ok
[INFO] [stdout] test database::database::tests::open_finishes_an_unfinished_journal_before_returning ... ok
[INFO] [stdout] test database::database::tests::partitions::dropping_an_unrelated_field_keeps_every_partition_readable ... ok
[INFO] [stdout] test database::database::tests::partitions::a_partitioned_table_survives_the_catalog_round_trip ... ok
[INFO] [stdout] test database::database::tests::following_the_log::a_following_reader_reloads_instead_of_reading_the_offsets_a_foreign_compaction_moved ... ok
[INFO] [stdout] test database::database::tests::partitions::a_unit_of_work_lands_in_two_partitions_of_one_table ... ok
[INFO] [stdout] test database::database::tests::put_checked_refuses_a_value_with_no_counterpart_on_an_enforced_relation ... ok
[INFO] [stdout] test database::database::tests::read_cache::a_cached_table_read_through_the_database_answers_the_same_document_twice ... ok
[INFO] [stdout] test database::database::tests::put_checked_ignores_a_relation_that_does_not_declare_enforce ... ok
[INFO] [stdout] test database::database::tests::patch_checked_refuses_changing_the_field_to_a_value_with_no_counterpart ... ok
[INFO] [stdout] test database::database::tests::patch_checked_leaves_the_field_alone_when_changes_does_not_touch_it ... ok
[INFO] [stdout] test database::database::tests::read_cache::an_open_that_names_no_cache_leaves_every_table_caching_nothing ... ok
[INFO] [stdout] test database::database::tests::put_checked_allows_a_value_with_a_live_counterpart ... ok
[INFO] [stdout] test database::database::tests::the_logs_of_a_partitioned_table_are_found_one_level_down_too ... ok
[INFO] [stdout] test database::database::tests::read_cache::the_policy_an_open_call_names_arms_the_tables_it_opens_and_the_ones_it_creates ... ok
[INFO] [stdout] test database::database::tests::where_the_files_land::the_two_names_for_the_metadata_document_are_refused_when_they_disagree_touching_nothing ... ok
[INFO] [stdout] test database::layout::tests::a_custom_metadata_file_moves_only_that_document ... ok
[INFO] [stdout] test database::layout::tests::reopening_a_stored_database_under_the_other_kind_is_refused_naming_both ... ok
[INFO] [stdout] test database::layout::tests::the_deprecated_alias_names_the_document_on_its_own_and_alongside_an_agreeing_new_name ... ok
[INFO] [stdout] test database::database::tests::partitions::declaring_partitions_on_an_existing_table_moves_its_records_into_the_new_layout ... ok
[INFO] [stdout] test database::database::tests::refresh_with_nothing_new_on_disk_reports_no_change ... ok
[INFO] [stdout] test database::layout::tests::the_kind_on_disk_wins_over_an_absent_request_and_the_request_wins_over_nothing_on_disk ... ok
[INFO] [stdout] test database::layout::tests::two_disagreeing_names_for_the_metadata_document_are_refused_naming_both ... ok
[INFO] [stdout] test database::layout::tests::the_flat_layout_keeps_every_role_in_the_database_directory ... ok
[INFO] [stdout] test database::layout::tests::the_nested_layout_sorts_the_roles_into_subdirectories_but_keeps_the_metadata_at_the_root ... ok
[INFO] [stdout] test database::migrate_layout::tests::a_directory_with_no_metadata_document_is_refused_and_left_without_a_lock_file ... ok
[INFO] [stdout] test database::database::tests::refresh_picks_up_both_a_table_and_the_records_another_process_wrote ... ok
[INFO] [stdout] test database::database::tests::refresh_after_a_swapped_log_takes_the_new_file_and_writes_land_in_it_again ... ok
[INFO] [stdout] test database::database::tests::where_the_files_land::a_partitioned_table_under_the_nested_layout_lands_under_tables_slash_its_own_name ... ok
[INFO] [stdout] test database::database::tests::unit_of_work_commits_across_two_tables_and_the_database_sees_both ... ok
[INFO] [stdout] test database::database::tests::where_the_files_land::a_custom_metadata_file_holds_the_catalog_and_nothing_is_written_under_the_default_name ... ok
[INFO] [stdout] test database::database::tests::refresh_survives_a_compaction_run_by_the_writer_process ... ok
[INFO] [stdout] test database::database::tests::where_the_files_land::the_nested_layout_sorts_the_logs_the_snapshots_and_the_lock_into_their_subdirectories ... ok
[INFO] [stdout] test database::database::tests::where_the_files_land::the_deprecated_manifest_name_opens_what_the_new_one_wrote_and_the_other_way_round ... ok
[INFO] [stdout] test database::database::tests::where_the_files_land::a_nested_database_reopens_as_nested_without_being_told_and_is_refused_as_flat ... ok
[INFO] [stdout] test database::migrate_layout::tests::a_format_1_database_moves_with_its_schema_files_and_stays_on_format_1 ... ok
[INFO] [stdout] test database::database::tests::where_the_files_land::the_default_open_writes_exactly_what_it_wrote_before_layouts_existed ... ok
[INFO] [stdout] test database::migrate_layout::tests::a_truncated_metadata_document_is_refused_naming_the_file ... ok
[INFO] [stdout] test database::database::tests::where_the_files_land::a_unit_of_work_under_the_nested_layout_journals_into_runtime_and_reopens_whole ... ok
[INFO] [stdout] test database::migrate_layout::tests::a_file_the_move_does_not_understand_is_reported_rather_than_carried ... ok
[INFO] [stdout] test database::migrate_layout::tests::a_dry_run_plans_the_move_without_taking_the_lock_or_touching_the_disk ... ok
[INFO] [stdout] test database::migrate_layout::tests::moving_a_format_1_database_back_drops_the_layout_member_instead_of_writing_flat ... ok
[INFO] [stdout] test database::migrate_layout::tests::a_catalog_names_its_arrangement_out_loud_after_a_move_back_to_flat ... ok
[INFO] [stdout] test database::database::tests::the_deprecated_mark_reaches_a_table_in_either_validation_mode_and_on_every_write ... ok
[INFO] [stdout] test lock::dir_lock::tests::a_served_grace_is_only_read_off_an_owner_token_this_engine_minted ... ok
[INFO] [stdout] test lock::dir_lock::tests::a_clean_release_frees_the_directory_at_once_without_waiting_out_the_lease ... ok
[INFO] [stdout] test database::migrate_layout::tests::an_unfinished_unit_of_work_stops_the_move_before_it_starts ... ok
[INFO] [stdout] test database::migrate_layout::tests::moving_a_database_that_is_already_there_changes_nothing ... ok
[INFO] [stdout] test database::migrate_layout::tests::a_move_is_refused_while_an_engine_writer_holds_the_directory_and_moves_nothing ... ok
[INFO] [stdout] test database::migrate_layout::tests::a_taken_target_name_is_refused_before_anything_moves ... ok
[INFO] [stdout] test database::migrate_layout::tests::two_disagreeing_names_for_one_document_are_refused_naming_both_options ... ok
[INFO] [stdout] test lock::dir_lock::tests::challenge_takes_a_free_lock_outright_and_reports_a_live_holder_as_blocked ... ok
[INFO] [stdout] test database::migrate_layout::tests::the_lock_a_move_takes_is_the_one_an_engine_writer_is_refused_by ... ok
[INFO] [stdout] test lock::dir_lock::tests::inspect_reads_a_directory_nobody_holds_as_free_without_creating_a_lock_file ... ok
[INFO] [stdout] test lock::dir_lock::tests::inspect_reads_a_lock_file_it_cannot_parse_as_unreadable_and_refuses_a_planted_link ... ok
[INFO] [stdout] test lock::dir_lock::tests::inspect_names_the_live_holder_and_leaves_the_lock_where_it_is ... ok
[INFO] [stdout] test database::migrate_layout::tests::a_move_and_a_move_back_leave_the_records_and_the_arrangement_where_they_started ... ok
[INFO] [stdout] test database::migrate_layout::tests::renaming_the_document_alone_moves_it_and_nothing_else ... ok
[INFO] [stdout] test database::migrate_layout::tests::a_partitioned_table_carries_its_whole_directory_across ... ok
[INFO] [stdout] test lock::dir_lock::tests::reclaims_a_lock_whose_heartbeat_went_stale ... ok
[INFO] [stdout] test database::database::tests::following_the_log::a_custom_interval_coalesces_the_freshness_check_and_lets_it_through_once_it_elapses ... ok
[INFO] [stdout] test lock::dir_lock::tests::an_old_format_pid_only_lock_file_is_unparsable_and_reclaimed_after_the_lease ... ok
[INFO] [stdout] test lock::dir_lock::tests::reclaims_an_unparsable_lock_file_nobody_touched_for_longer_than_stale_after_ms ... ok
[INFO] [stdout] test lock::dir_lock::tests::refuses_a_second_writer_while_the_first_is_alive ... ok
[INFO] [stdout] test lock::dir_lock::tests::refuses_to_touch_a_lock_path_somebody_replaced_with_a_symlink ... ok
[INFO] [stdout] test lock::dir_lock::tests::leaves_a_freshly_written_unparsable_lock_file_alone ... ok
[INFO] [stdout] test lock::dir_lock::tests::the_background_heartbeat_keeps_the_lease_fresh_on_its_own ... ok
[INFO] [stdout] test lock::dir_lock::tests::the_heartbeat_refuses_every_planted_temporary_and_beats_on_a_free_name ... ok
[INFO] [stdout] test lock::dir_lock::tests::a_dispossessed_holder_learns_it_lost_the_lock_on_its_next_tick ... ok
[INFO] [stdout] test database::migrate_layout::tests::a_move_waits_for_an_engine_writer_to_leave_and_then_lands ... ok
[INFO] [stdout] test query::aggregate::tests::a_field_with_no_numeric_values_at_all_behaves_like_an_empty_aggregate ... ok
[INFO] [stdout] test query::aggregate::tests::an_empty_match_reports_zero_count_and_none_for_the_rest ... ok
[INFO] [stdout] test query::aggregate::tests::builder_wires_the_aggregate_methods_into_a_spec_pair ... ok
[INFO] [stdout] test lock::dir_lock::tests::writes_the_holder_and_removes_the_file_on_release ... ok
[INFO] [stdout] test query::aggregate::tests::grouped_output_is_sorted_deterministically_by_group_key ... ok
[INFO] [stdout] test query::aggregate::tests::counts_every_matching_record ... ok
[INFO] [stdout] test query::aggregate::tests::non_numeric_values_are_skipped_without_panicking ... ok
[INFO] [stdout] test query::aggregate::tests::groups_count_and_sum_independently_per_distinct_field_value ... ok
[INFO] [stdout] test query::aggregate::tests::sum_avg_min_max_over_a_numeric_field ... ok
[INFO] [stdout] test query::aggregate::tests::records_missing_the_group_field_fall_into_a_null_group ... ok
[INFO] [stdout] test query::builder::tests::builds_a_flat_conjunction ... ok
[INFO] [stdout] test query::aggregate::tests::ungrouped_aggregation_is_unaffected_by_the_group_by_field_existing ... ok
[INFO] [stdout] test query::builder::tests::drops_an_empty_bracket_instead_of_keeping_a_hollow_node ... ok
[INFO] [stdout] test query::builder::tests::nests_an_and_bracket_inside_an_or_bracket ... ok
[INFO] [stdout] test query::parser::tests::a_field_named_like_a_clause_keyword_still_works_in_a_predicate ... ok
[INFO] [stdout] test query::parser::tests::and_inside_or_bracket_matches_where_any_where_all ... ok
[INFO] [stdout] test query::parser::tests::clause_keywords_ignore_case_like_and_and_or ... ok
[INFO] [stdout] test query::parser::tests::clauses_without_a_where_fragment_leave_the_conjunction_empty ... ok
[INFO] [stdout] test query::parser::tests::error_display_carries_position_and_reason ... ok
[INFO] [stdout] test query::parser::tests::each_clause_stands_on_its_own ... ok
[INFO] [stdout] test query::parser::tests::or_bracket_inside_flat_and_matches_where_then_where_any ... ok
[INFO] [stdout] test query::parser::tests::order_by_defaults_to_ascending ... ok
[INFO] [stdout] test query::parser::tests::flat_and_matches_chained_where ... ok
[INFO] [stdout] test query::parser::tests::order_by_limit_offset_match_the_builder_chain ... ok
[INFO] [stdout] test query::parser::tests::parses_a_single_comparison ... ok
[INFO] [stdout] test query::parser::tests::rejects_a_clause_given_twice ... ok
[INFO] [stdout] test query::parser::tests::parses_every_comparison_operator ... ok
[INFO] [stdout] test query::parser::tests::rejects_a_malformed_order_by ... ok
[INFO] [stdout] test query::parser::tests::rejects_clauses_out_of_sql_order ... ok
[INFO] [stdout] test query::parser::tests::rejects_limit_and_offset_that_are_not_non_negative_integers ... ok
[INFO] [stdout] test query::planner::tests::a_contains_predicate_on_a_string_field_with_an_exact_index_falls_back_to_a_scan ... ok
[INFO] [stdout] test query::parser::tests::rejects_garbage_with_a_positioned_error ... ok
[INFO] [stdout] test query::planner::tests::a_count_only_query_answers_with_the_match_count_and_no_page ... ok
[INFO] [stdout] test query::planner::tests::a_count_only_query_counts_every_match_even_where_a_page_would_be_cut ... ok
[INFO] [stdout] test query::planner::tests::a_fulltext_search_ranks_by_score_before_the_page_is_cut ... ok
[INFO] [stdout] test query::planner::tests::a_page_is_cut_out_of_the_ordered_result_only_after_the_predicate_narrowed_it ... ok
[INFO] [stdout] test query::planner::tests::a_contains_predicate_on_an_array_field_keeps_using_the_exact_index ... ok
[INFO] [stdout] test query::planner::tests::a_page_window_over_an_empty_result_stays_empty ... ok
[INFO] [stdout] test query::planner::tests::a_query_whose_every_field_is_projected_answers_without_reading_a_record ... ok
[INFO] [stdout] test query::planner::tests::a_query_touching_a_field_outside_the_projection_reads_every_candidate ... ok
[INFO] [stdout] test query::planner::tests::a_query_without_a_search_reports_an_unranked_plan ... ok
[INFO] [stdout] test query::planner::tests::a_record_written_after_an_overwrite_still_lands_at_the_end_of_the_page ... ok
[INFO] [stdout] test query::planner::tests::a_search_left_to_its_own_order_reports_a_ranked_plan ... ok
[INFO] [stdout] test query::planner::tests::a_sort_on_a_field_outside_the_projection_takes_the_query_off_the_projections ... ok
[INFO] [stdout] test query::planner::tests::a_where_scoped_index_answers_a_query_that_repeats_its_predicate ... ok
[INFO] [stdout] test query::planner::tests::a_tie_on_the_sort_field_keeps_every_matched_key_exactly_once ... ok
[INFO] [stdout] test query::planner::tests::a_where_scoped_index_never_answers_a_query_that_omits_its_predicate ... ok
[INFO] [stdout] test query::planner::tests::an_aggregate_keeps_its_keys_when_the_spec_it_unpages_was_counting ... ok
[INFO] [stdout] test query::planner::tests::an_aggregate_folds_every_match_and_not_just_the_page ... ok
[INFO] [stdout] test query::planner::tests::an_empty_fulltext_result_never_falls_back_to_a_scan ... ok
[INFO] [stdout] test query::planner::tests::an_equality_against_an_array_value_falls_back_to_a_scan ... ok
[INFO] [stdout] test query::planner::tests::an_empty_index_bucket_never_falls_back_to_a_scan ... ok
[INFO] [stdout] test query::planner::tests::an_explicit_order_replaces_the_fulltext_ranking ... ok
[INFO] [stdout] test query::planner::tests::an_explicit_order_beside_a_search_reports_an_unranked_plan ... ok
[INFO] [stdout] test query::planner::tests::an_offset_and_a_limit_together_select_a_middle_window_of_the_order ... ok
[INFO] [stdout] test lock::dir_lock::tests::reports_how_long_it_waited_before_giving_up ... ok
[INFO] [stdout] test query::planner::tests::an_offset_past_the_end_and_a_limit_of_zero_both_answer_an_empty_page ... ok
[INFO] [stdout] test query::planner::tests::an_overwritten_record_keeps_its_place_on_an_unordered_page ... ok
[INFO] [stdout] test query::planner::tests::candidates_of_an_index_and_of_a_scan_alike_come_out_in_write_order ... ok
[INFO] [stdout] test query::planner::tests::degrades_the_whole_bracket_to_a_scan_when_one_branch_has_no_index ... ok
[INFO] [stdout] test query::planner::tests::falls_back_to_a_scan_when_no_registry_can_help ... ok
[INFO] [stdout] test query::planner::tests::narrows_a_fulltext_search_with_an_in_memory_predicate ... ok
[INFO] [stdout] test query::planner::tests::matches_a_direct_registry_search_call ... ok
[INFO] [stdout] test query::planner::tests::narrows_through_the_index_then_filters_the_rest_in_memory ... ok
[INFO] [stdout] test query::planner::tests::orders_the_result_by_a_field_ascending_and_descending ... ok
[INFO] [stdout] test query::planner::tests::picks_the_more_selective_index_when_more_than_one_applies ... ok
[INFO] [stdout] test query::planner::tests::nests_an_and_bracket_inside_an_or_bracket ... ok
[INFO] [stdout] test query::planner::tests::reaches_the_primary_registry_from_a_top_level_equality ... ok
[INFO] [stdout] test query::planner::tests::uses_the_fulltext_index_for_a_search_query ... ok
[INFO] [stdout] test query::planner::tests::unions_the_branches_of_an_or_bracket ... ok
[INFO] [stdout] test query::planner::tests::uses_the_exact_index_for_a_single_predicate ... ok
[INFO] [stdout] test query::predicates::tests::treats_an_empty_or_group_as_no_predicate_at_all ... ok
[INFO] [stdout] test query::predicates::tests::tests_every_comparison_operator ... ok
[INFO] [stdout] test queue::pending_map::tests::a_claim_on_all_fields_conflicts_with_any_field ... ok
[INFO] [stdout] test queue::pending_map::tests::a_claim_requesting_all_fields_conflicts_with_anything_held ... ok
[INFO] [stdout] test queue::pending_map::tests::release_clears_the_key_once_every_field_is_released ... ok
[INFO] [stdout] test queue::pending_map::tests::reports_no_conflict_for_an_unclaimed_key ... ok
[INFO] [stdout] test queue::pending_map::tests::reports_the_overlapping_fields_as_a_conflict ... ok
[INFO] [stdout] test query::predicates::tests::combines_and_and_or_with_arbitrary_nesting ... ok
[INFO] [stdout] test queue::pending_map::tests::stacked_claims_on_the_same_field_need_the_same_number_of_releases ... ok
[INFO] [stdout] test queue::write_queue::tests::a_delete_claims_every_field_and_conflicts_with_a_write_on_any_of_them ... ok
[INFO] [stdout] test queue::write_queue::tests::a_refused_append_leaves_nothing_queued_and_nothing_claimed ... ok
[INFO] [stdout] test queue::write_queue::tests::a_refusal_mid_way_keeps_the_batches_that_landed_and_abandons_the_rest ... ok
[INFO] [stdout] test queue::write_queue::tests::dead_bytes_grow_when_an_existing_key_is_overwritten_or_removed ... ok
[INFO] [stdout] test queue::write_queue::tests::rejects_a_second_record_claiming_a_unique_field_already_held ... ok
[INFO] [stdout] test queue::write_queue::tests::commits_every_entry_queued_since_the_last_call_in_one_append ... ok
[INFO] [stdout] test queue::write_queue::tests::rejects_a_second_record_claiming_an_already_taken_markdown_virtual_path ... ok
[INFO] [stdout] test queue::write_queue::tests::a_second_write_to_the_same_field_conflicts_unless_forced ... ok
[INFO] [stdout] test queue::write_queue::tests::releases_the_claim_only_after_the_batch_commits ... ok
[INFO] [stdout] test registry::composite_index::tests::answers_a_lookup_on_the_leading_field_alone_from_the_prefix_of_the_key ... ok
[INFO] [stdout] test registry::composite_index::tests::holds_a_unique_key_only_when_every_field_of_it_repeats ... ok
[INFO] [stdout] test queue::write_queue::tests::splits_a_batch_larger_than_max_batch_into_several_appends ... ok
[INFO] [stdout] test registry::composite_index::tests::holds_an_array_field_of_the_key_per_element ... ok
[INFO] [stdout] test registry::composite_index::tests::reads_its_fields_in_the_order_they_were_declared ... ok
[INFO] [stdout] test registry::composite_index::tests::answers_a_range_bounded_on_the_whole_vector ... ok
[INFO] [stdout] test lock::dir_lock::tests::waits_up_to_the_timeout_and_takes_the_lock_once_released ... ok
[INFO] [stdout] test registry::exact_index::tests::adds_and_removes_a_key_from_its_bucket ... ok
[INFO] [stdout] test registry::exact_index::tests::dedupes_array_values_into_one_key_each ... ok
[INFO] [stdout] test registry::exact_index::tests::treats_a_missing_field_and_an_explicit_null_the_same ... ok
[INFO] [stdout] test registry::exact_index::tests::does_not_confuse_a_number_with_its_string_form ... ok
[INFO] [stdout] test registry::fulltext_index::tests::forgets_a_removed_document ... ok
[INFO] [stdout] test registry::fulltext_index::tests::forgets_a_token_from_the_vocabulary_when_its_last_document_goes ... ok
[INFO] [stdout] test registry::fulltext_index::tests::judges_an_unindexed_document_by_the_same_membership_rule_as_the_postings ... ok
[INFO] [stdout] test registry::fulltext_index::tests::requires_every_query_token_by_default_and_ranks_the_closer_document_higher ... ok
[INFO] [stdout] test registry::fulltext_index::tests::scores_a_staged_document_against_the_corpus_it_is_added_to ... ok
[INFO] [stdout] test registry::sorted_index::tests::keeps_entries_ordered_and_answers_ranges ... ok
[INFO] [stdout] test registry::fulltext_index::tests::matches_a_token_that_only_begins_with_the_query_term_when_marked ... ok
[INFO] [stdout] test registry::fulltext_index::tests::leaves_a_removed_document_out_of_the_scores_and_statistics ... ok
[INFO] [stdout] test registry::fulltext_index::tests::scores_each_expanded_token_by_its_own_document_frequency ... ok
[INFO] [stdout] test registry::sorted_index::tests::orders_a_shorter_entry_before_every_longer_one_that_shares_its_prefix ... ok
[INFO] [stdout] test registry::table_registry::tests::a_key_written_again_after_a_delete_arrives_afresh ... ok
[INFO] [stdout] test registry::table_registry::tests::a_key_first_seen_after_an_overwrite_arrives_last ... ok
[INFO] [stdout] test registry::table_registry::tests::an_overwrite_leaves_a_key_where_it_first_arrived ... ok
[INFO] [stdout] test registry::table_registry::tests::answers_the_unique_holder_of_a_value ... ok
[INFO] [stdout] test registry::table_registry::tests::compaction_leaves_every_surviving_key_where_it_arrived ... ok
[INFO] [stdout] test registry::table_registry::tests::does_not_confuse_a_number_with_its_string_form ... ok
[INFO] [stdout] test registry::table_registry::tests::drops_a_deleted_record_from_every_registry_and_counts_the_tombstone ... ok
[INFO] [stdout] test registry::table_registry::tests::holds_the_virtual_path_of_a_markdown_field_uniquely ... ok
[INFO] [stdout] test registry::table_registry::tests::counts_an_overwritten_record_as_garbage_and_reindexes_it ... ok
[INFO] [stdout] test registry::table_registry::tests::remaps_slots_after_compaction_and_forgets_the_garbage ... ok
[INFO] [stdout] test registry::table_registry::tests::serves_exact_array_member_sorted_and_fulltext_lookups ... ok
[INFO] [stdout] test registry::tokenize::tests::folds_case_and_diacritics_so_lodz_meets_lodz ... ok
[INFO] [stdout] test registry::table_registry::tests::frees_a_virtual_path_once_its_holder_is_deleted_or_overwritten ... ok
[INFO] [stdout] test registry::table_registry::tests::keeps_a_non_addressable_markdown_field_out_of_the_virtual_path_index ... ok
[INFO] [stdout] test registry::tokenize::tests::reads_a_trailing_asterisk_as_a_prefix_marker ... ok
[INFO] [stdout] test registry::table_registry::tests::matches_the_ts_fixture_search_results_after_a_log_scan ... FAILED
[INFO] [stdout] test schema::content_hash::tests::recognises_its_own_output_and_nothing_else_as_a_physical_path ... ok
[INFO] [stdout] test schema::markdown_links::tests::ignores_a_link_that_is_not_an_absolute_known_extension ... ok
[INFO] [stdout] test schema::markdown_links::tests::extracts_a_link_and_an_embed_pointing_at_a_virtual_path ... ok
[INFO] [stdout] test schema::content_hash::tests::hashes_different_bytes_to_different_paths ... ok
[INFO] [stdout] test schema::content_hash::tests::hashes_identical_bytes_to_the_same_path ... ok
[INFO] [stdout] test schema::markdown_links::tests::reports_only_the_links_that_do_not_exist ... ok
[INFO] [stdout] test schema::parse::tests::a_non_addressable_markdown_field_is_refused_the_same_five_options_but_for_its_own_reason ... ok
[INFO] [stdout] test schema::markdown_links::tests::strips_an_anchor_or_query_before_matching_the_extension ... ok
[INFO] [stdout] test schema::parse::tests::addressable_has_to_be_a_boolean ... ok
[INFO] [stdout] test schema::parse::tests::a_markdown_field_is_addressable_unless_it_says_otherwise ... ok
[INFO] [stdout] test schema::parse::tests::only_a_markdown_field_may_declare_addressable ... ok
[INFO] [stdout] test schema::parse::tests::parses_a_partitioned_schema ... ok
[INFO] [stdout] test schema::parse::tests::moves_a_pre_rename_marker_onto_the_current_key_and_leaves_a_current_one_alone ... ok
[INFO] [stdout] test schema::parse::tests::accepts_markdown_and_image_field_declarations ... ok
[INFO] [stdout] test schema::parse::tests::opting_out_survives_the_schema_document_it_is_rendered_into ... ok
[INFO] [stdout] test schema::parse::tests::rejects_an_index_declared_directly_on_an_image_field ... ok
[INFO] [stdout] test schema::parse::tests::rejects_a_cypher_field_carrying_an_index ... ok
[INFO] [stdout] test schema::parse::tests::parses_the_ts_reference_fixture_and_normalizes_the_primary_key ... FAILED
[INFO] [stdout] test schema::parse::tests::rejects_fulltext_declared_directly_on_a_markdown_field ... ok
[INFO] [stdout] test schema::parse::tests::rejects_an_unknown_field_option ... ok
[INFO] [stdout] test schema::parse::tests::rejects_a_schema_document_that_declares_the_pre_rename_marker ... ok
[INFO] [stdout] test schema::parse::tests::rejects_partition_by_naming_the_primary_key ... ok
[INFO] [stdout] test schema::parse::tests::rejects_an_unknown_schema_option ... ok
[INFO] [stdout] test schema::partition::tests::a_partitioned_table_reads_the_partition_off_the_named_field ... ok
[INFO] [stdout] test schema::parse::tests::rejects_unique_declared_directly_on_a_markdown_field ... ok
[INFO] [stdout] test schema::partition::tests::an_unpartitioned_table_puts_every_record_in_the_empty_partition ... ok
[INFO] [stdout] test schema::partition::tests::refuses_a_value_longer_than_sixty_four_characters_and_takes_one_of_exactly_that_length ... ok
[INFO] [stdout] test schema::parse::tests::rejects_sorted_index_on_a_non_sortable_type ... ok
[INFO] [stdout] test schema::serialize::tests::keeps_the_key_order_the_ts_document_has ... ok
[INFO] [stdout] test schema::partition::tests::refuses_a_value_that_cannot_name_a_file ... ok
[INFO] [stdout] test schema::serialize::tests::renders_a_cypher_field_as_the_bare_fact_of_it ... ok
[INFO] [stdout] test schema::validate::tests::a_document_stripped_of_its_stray_key_validates_while_the_original_does_not ... ok
[INFO] [stdout] test schema::validate::tests::a_non_addressable_markdown_field_still_shape_checks_a_path_it_was_handed ... ok
[INFO] [stdout] test schema::validate::tests::a_non_addressable_markdown_field_validates_on_content_alone ... ok
[INFO] [stdout] test schema::serialize::tests::renders_only_the_modifiers_a_field_actually_carries ... ok
[INFO] [stdout] test schema::validate::tests::accepts_a_typed_array_field ... ok
[INFO] [stdout] test schema::validate::tests::accepts_an_iso_timestamp_and_converts_it_to_epoch_millis ... ok
[INFO] [stdout] test schema::validate::tests::coerces_a_markdown_field_into_content_and_virtual_path ... ok
[INFO] [stdout] test schema::serialize::tests::round_trips_through_parse_schema ... ok
[INFO] [stdout] test schema::validate::tests::coerces_an_image_field_into_a_content_addressed_physical_path ... ok
[INFO] [stdout] test schema::validate::tests::fills_in_defaults_and_the_now_sentinel ... ok
[INFO] [stdout] test schema::validate::tests::hashes_identical_image_content_to_the_same_physical_path ... ok
[INFO] [stdout] test schema::validate::tests::partial_mode_skips_absent_fields_instead_of_defaulting_or_requiring ... ok
[INFO] [stdout] test schema::validate::tests::refuses_a_caller_supplied_physical_path_that_is_not_a_content_hash_file_name ... ok
[INFO] [stdout] test schema::validate::tests::rejects_a_field_not_declared_in_the_schema ... ok
[INFO] [stdout] test schema::validate::tests::rejects_a_field_using_the_reserved_meta_prefix ... ok
[INFO] [stdout] test schema::validate::tests::rejects_a_markdown_value_missing_its_virtual_path ... ok
[INFO] [stdout] test schema::validate::tests::rejects_a_required_field_missing_without_a_default ... ok
[INFO] [stdout] test schema::serialize::tests::leaves_partition_by_out_of_a_table_that_declares_none ... ok
[INFO] [stdout] test schema::validate::tests::rejects_a_non_integer_for_an_int_field ... ok
[INFO] [stdout] test schema::validate::tests::rejects_an_image_content_that_is_not_valid_base64 ... ok
[INFO] [stdout] test schema::validate::tests::rejects_an_enum_value_outside_its_declared_values ... ok
[INFO] [stdout] test schema::validate::tests::without_undeclared_keeps_a_clean_document_borrowed_and_copies_only_a_dirty_one ... ok
[INFO] [stdout] test storage::log_file::tests::a_never_fsync_log_still_reads_back_everything_it_was_given ... ok
[INFO] [stdout] test schema::validate::tests::undeclared_fields_names_the_stray_keys_and_leaves_a_reserved_one_alone ... ok
[INFO] [stdout] test schema::validate::tests::projects_out_fields_the_schema_no_longer_declares ... ok
[INFO] [stdout] test storage::log_file::tests::a_read_between_two_appends_does_not_disturb_where_the_next_append_lands ... ok
[INFO] [stdout] test storage::log_file::tests::a_scan_after_a_truncate_reads_only_what_is_left ... ok
[INFO] [stdout] test lock::dir_lock::tests::a_fail_fast_acquire_carrying_a_served_grace_reclaims_the_lease_that_token_names ... ok
[INFO] [stdout] test storage::log_file::tests::a_readonly_handle_forgets_the_torn_tail_without_touching_the_file ... ok
[INFO] [stdout] test storage::log_file::tests::cutting_a_torn_tail_off_a_never_fsync_log_still_cuts_it_without_an_fsync ... ok
[INFO] [stdout] test schema::validate::tests::round_trips_an_image_field_it_coerced_itself ... ok
[INFO] [stdout] test storage::log_file::tests::probe_reflects_size_and_inode_from_the_path_not_the_handle ... ok
[INFO] [stdout] test storage::log_file::tests::reads_to_the_last_full_line_when_the_tail_is_truncated_mid_write ... ok
[INFO] [stdout] test storage::log_file::tests::refuses_to_append_once_the_file_at_its_path_is_shorter_than_what_it_wrote ... ok
[INFO] [stdout] test storage::log_file::tests::refuses_to_append_once_the_file_is_gone_from_its_path ... ok
[INFO] [stdout] test storage::log_file::tests::a_writable_handle_cuts_the_torn_tail_so_the_next_append_lands_on_its_own_line ... ok
[INFO] [stdout] test storage::log_file::tests::reopening_keeps_the_durability_the_handle_was_configured_with ... ok
[INFO] [stdout] test storage::log_file::tests::refuses_to_append_once_the_file_at_its_path_is_a_different_one ... ok
[INFO] [stdout] test storage::log_file::tests::returns_byte_offsets_that_survive_multibyte_text ... ok
[INFO] [stdout] test storage::log_file::tests::reconstructs_state_after_reopening_the_file ... ok
[INFO] [stdout] test storage::log_set::tests::a_new_partition_value_creates_its_own_file_on_first_append ... ok
[INFO] [stdout] test storage::log_set::tests::a_file_that_is_not_a_log_never_becomes_a_partition ... ok
[INFO] [stdout] test storage::log_file::tests::concurrent_readers_sharing_one_handle_each_get_their_own_offset ... ok
[INFO] [stdout] test storage::log_set::tests::a_flat_set_holds_exactly_the_file_it_was_given ... ok
[INFO] [stdout] test storage::log_set::tests::appending_to_a_known_value_reuses_its_file_instead_of_making_another ... ok
[INFO] [stdout] test storage::log_set::tests::reads_a_slice_back_from_the_partition_it_was_written_to ... ok
[INFO] [stdout] test storage::log_set::tests::discover_opens_a_partition_file_another_writer_created_since_the_last_look ... ok
[INFO] [stdout] test storage::log_file::tests::sync_never_issues_no_fsync_while_the_default_commit_still_does ... ok
[INFO] [stdout] test storage::record::tests::parses_a_tombstone_without_a_payload ... ok
[INFO] [stdout] test storage::record::tests::puts_meta_under_the_reserved_prefix_next_to_the_payload ... ok
[INFO] [stdout] test storage::record::tests::matches_the_ts_fixture_byte_for_byte ... FAILED
[INFO] [stdout] test storage::log_set::tests::turning_fsync_off_reaches_partitions_opened_before_and_after_the_call ... ok
[INFO] [stdout] test storage::record::tests::reads_a_line_written_before_the_field_order_was_canonical ... ok
[INFO] [stdout] test storage::record::tests::refuses_lines_that_are_not_usable ... ok
[INFO] [stdout] test storage::log_set::tests::a_set_left_alone_keeps_fsyncing_every_partition_it_holds ... ok
[INFO] [stdout] test storage::record::tests::writes_payload_fields_alphabetically_whatever_order_they_arrived_in ... ok
[INFO] [stdout] test storage::repartition::tests::a_table_with_no_flat_log_and_no_partitions_has_nothing_to_move ... ok
[INFO] [stdout] test storage::log_set::tests::reopening_finds_every_partition_file_already_on_disk ... ok
[INFO] [stdout] test storage::repartition::tests::refuses_a_flat_log_holding_a_value_that_cannot_name_a_file ... ok
[INFO] [stdout] test table::cache::tests::a_disabled_cache_answers_nothing_and_keeps_nothing ... ok
[INFO] [stdout] test table::cache::tests::a_document_one_byte_under_the_line_is_still_a_small_one ... ok
[INFO] [stdout] test table::cache::tests::a_full_tier_evicts_the_least_recently_read_key_and_keeps_the_one_just_read ... ok
[INFO] [stdout] test storage::repartition::tests::an_unpartitioned_schema_is_left_exactly_as_it_is ... ok
[INFO] [stdout] test table::cache::tests::a_second_insert_on_one_key_replaces_the_document_without_taking_a_second_slot ... ok
[INFO] [stdout] test table::cache::tests::a_tier_keeps_its_capacity_across_eviction_and_reinsertion ... ok
[INFO] [stdout] test table::cache::tests::a_large_document_lands_in_its_own_tier_and_evicts_only_large_ones ... ok
[INFO] [stdout] test table::cache::tests::approx_bytes_counts_strings_for_real_and_nested_values_too ... ok
[INFO] [stdout] test table::cache::tests::invalidating_a_key_nobody_cached_changes_nothing ... ok
[INFO] [stdout] test table::cache::tests::what_was_inserted_reads_back_until_it_is_invalidated ... ok
[INFO] [stdout] test table::cache::tests::clear_empties_both_tiers ... ok
[INFO] [stdout] test table::cache::tests::with_entries_derives_a_smaller_large_tier_and_reads_zero_as_disabled ... ok
[INFO] [stdout] test table::describe::tests::folds_a_served_sugar_index_back_into_the_field_modifier ... ok
[INFO] [stdout] test table::describe::tests::leaves_a_partial_or_ttl_index_out_even_under_a_sugar_name ... ok
[INFO] [stdout] test table::describe::tests::keeps_the_kind_the_schema_declares_where_the_served_sugar_disagrees ... ok
[INFO] [stdout] test table::describe::tests::leaves_an_index_named_by_hand_out_of_the_modifiers ... ok
[INFO] [stdout] test table::handle::tests::a_broken_link_in_a_non_addressable_field_is_still_reported_against_that_field ... ok
[INFO] [stdout] test storage::repartition::tests::drops_the_flat_log_an_interrupted_move_left_beside_the_partitions ... ok
[INFO] [stdout] test table::handle::tests::a_field_handed_over_with_an_empty_note_warns_with_the_field_alone ... ok
[INFO] [stdout] test storage::repartition::tests::throws_away_a_staging_directory_an_interrupted_move_left_behind ... ok
[INFO] [stdout] test storage::repartition::tests::moves_the_live_records_into_one_file_per_partition_value ... ok
[INFO] [stdout] test table::handle::tests::a_non_addressable_field_links_to_an_addressable_one_and_the_link_resolves ... ok
[INFO] [stdout] test table::handle::tests::a_non_addressable_field_needs_no_path_of_its_own_at_all ... ok
[INFO] [stdout] test table::handle::tests::a_compaction_after_the_log_was_swapped_out_is_refused_and_leaves_the_new_file_alone ... ok
[INFO] [stdout] test table::handle::tests::a_live_put_fires_a_same_table_trigger_right_after_the_write_it_fired_on ... ok
[INFO] [stdout] test table::handle::tests::a_put_with_an_image_field_writes_its_bytes_under_the_default_images_directory ... ok
[INFO] [stdout] test table::handle::tests::a_patch_that_does_not_touch_the_image_field_still_validates ... ok
[INFO] [stdout] test table::handle::tests::a_search_against_a_table_with_no_fulltext_field_is_refused_rather_than_answered_empty ... ok
[INFO] [stdout] test table::handle::tests::a_refused_write_leaves_no_ghost_in_the_staging_window ... ok
[INFO] [stdout] test table::handle::tests::a_table_nobody_configured_refuses_a_write_naming_an_undeclared_field ... ok
[INFO] [stdout] test table::handle::tests::a_reload_keeps_serving_queries_from_the_declared_indexes_it_was_built_with ... ok
[INFO] [stdout] test table::handle::tests::a_table_nobody_marked_answers_no_deprecation_warning_at_all ... ok
[INFO] [stdout] test table::handle::tests::a_table_opened_with_no_link_parsing_option_defaults_to_eager ... ok
[INFO] [stdout] test table::handle::tests::a_second_record_with_the_same_image_content_does_not_rewrite_the_existing_file ... ok
[INFO] [stdout] test table::handle::tests::a_table_nobody_configured_still_fsyncs_every_flush ... ok
[INFO] [stdout] test table::handle::tests::a_table_set_to_sync_never_flushes_its_writes_without_a_single_fsync ... ok
[INFO] [stdout] test table::handle::tests::a_table_opened_without_a_snapshot_path_never_writes_one ... ok
[INFO] [stdout] test table::handle::tests::a_warn_table_prepares_a_unit_of_work_line_without_the_undeclared_field ... ok
[INFO] [stdout] test table::handle::tests::a_warn_table_still_refuses_a_bad_type_a_missing_required_field_and_a_reserved_key ... ok
[INFO] [stdout] test table::handle::tests::a_warn_patch_merges_the_declared_changes_and_drops_the_undeclared_one ... ok
[INFO] [stdout] test table::handle::tests::a_trigger_only_fires_for_the_event_it_declares ... ok
[INFO] [stdout] test table::handle::tests::a_warn_table_takes_the_write_drops_the_undeclared_field_and_says_which ... ok
[INFO] [stdout] test table::handle::tests::a_snapshot_written_mid_stream_does_not_disturb_later_reads_and_writes ... ok
[INFO] [stdout] test table::handle::tests::a_snapshot_written_before_the_header_carried_a_generation_still_restores_and_starts_at_zero ... ok
[INFO] [stdout] test table::handle::tests::a_write_after_the_log_was_swapped_out_under_the_table_is_refused_rather_than_lost ... ok
[INFO] [stdout] test table::handle::tests::asking_after_the_write_gives_an_eager_table_the_same_warnings_put_with_warnings_carried ... ok
[INFO] [stdout] test table::handle::tests::aggregate_delegates_to_the_planner_and_matches_it_directly ... ok
[INFO] [stdout] test table::handle::tests::cypher::a_carried_field_is_refused_when_it_is_not_an_envelope ... ok
[INFO] [stdout] test table::handle::tests::asking_after_the_write_stays_silent_on_a_lazy_table_that_broken_links_of_would_answer_for ... ok
[INFO] [stdout] test table::handle::tests::cypher::a_carried_field_is_refused_when_the_envelope_belongs_to_another_record ... ok
[INFO] [stdout] test table::handle::tests::cypher::a_field_that_is_not_declared_cypher_cannot_be_carried ... ok
[INFO] [stdout] test table::handle::tests::cypher::a_carried_field_does_not_spare_another_write_in_the_same_window ... ok
[INFO] [stdout] test table::handle::tests::cypher::a_copy_between_databases_carries_the_envelope_byte_for_byte ... ok
[INFO] [stdout] test table::handle::tests::cypher::a_missing_key_is_skipped_and_counted_when_the_policy_says_so ... ok
[INFO] [stdout] test table::handle::tests::cypher::a_missing_key_refuses_the_run_under_the_default_policy ... ok
[INFO] [stdout] test table::handle::tests::cypher::a_rotated_record_reads_back_with_the_new_key_alone ... ok
[INFO] [stdout] test table::handle::tests::cypher::a_staged_value_reads_back_plain_and_still_lands_sealed ... ok
[INFO] [stdout] test table::handle::tests::compact_drops_dead_versions_but_leaves_the_table_fully_usable ... ok
[INFO] [stdout] test table::handle::tests::cypher::a_table_with_no_cypher_field_answers_zero_without_reading_anything ... ok
[INFO] [stdout] test table::handle::tests::cypher::a_value_that_only_looks_like_an_envelope_is_sealed_like_any_other ... ok
[INFO] [stdout] test table::handle::tests::cypher::a_second_rotation_reports_nothing_left_to_do ... ok
[INFO] [stdout] test table::handle::tests::cypher::hands_back_the_envelope_when_the_policy_says_raw ... ok
[INFO] [stdout] test table::handle::tests::cypher::hands_the_plaintext_back_on_read_with_its_type_intact ... ok
[INFO] [stdout] test table::handle::tests::cypher::leaves_null_alone ... ok
[INFO] [stdout] test table::handle::tests::compaction_replaces_the_snapshot_with_one_that_pairs_with_the_rewritten_log ... ok
[INFO] [stdout] test table::handle::tests::cypher::every_value_still_reads_back_as_itself_after_a_rotation ... ok
[INFO] [stdout] test table::handle::tests::cypher::never_writes_the_plaintext_into_the_log ... ok
[INFO] [stdout] test table::handle::tests::cypher::matches_a_predicate_on_an_encrypted_field_by_scanning ... ok
[INFO] [stdout] test table::handle::tests::cypher::omits_the_field_when_the_policy_says_so ... ok
[INFO] [stdout] test table::handle::tests::cypher::refuses_to_read_with_no_key_at_all ... ok
[INFO] [stdout] test table::handle::tests::cypher::refuses_to_write_an_encrypted_field_with_no_key ... ok
[INFO] [stdout] test table::handle::tests::cypher::refuses_to_read_without_the_key_that_wrote_the_value ... ok
[INFO] [stdout] test table::handle::tests::cypher::refuses_a_value_moved_to_another_record ... ok
[INFO] [stdout] test table::handle::tests::cypher::rotation_is_refused_on_a_readonly_handle ... ok
[INFO] [stdout] test table::handle::tests::cypher::survives_a_patch_of_an_encrypted_field ... ok
[INFO] [stdout] test table::handle::tests::cypher::rotation_re_encrypts_only_the_record_still_on_the_retired_key ... ok
[INFO] [stdout] test table::handle::tests::cypher::two_equal_values_get_two_different_envelopes ... ok
[INFO] [stdout] test table::handle::tests::cypher_txn::prepare_txn_ops_seals_the_line_but_keeps_the_prepared_record_plain ... ok
[INFO] [stdout] test table::handle::tests::cypher::rotation_reaches_every_partition_of_a_partitioned_table ... ok
[INFO] [stdout] test table::handle::tests::cypher::survives_compaction_without_a_key ... ok
[INFO] [stdout] test table::handle::tests::delete_of_an_unknown_key_reports_it_did_not_exist ... ok
[INFO] [stdout] test table::handle::tests::deprecated_field_warnings_stay_quiet_for_a_document_touching_no_marked_field ... ok
[INFO] [stdout] test table::handle::tests::deprecated_field_warnings_name_the_marked_field_the_document_touches_and_quote_its_note ... ok
[INFO] [stdout] test table::handle::tests::dropped_field_warnings_answer_nothing_on_a_strict_table_or_a_document_with_no_stray_field ... ok
[INFO] [stdout] test table::handle::tests::eager_put_of_a_link_to_a_cover_images_virtual_path_in_the_same_record_carries_no_warnings ... ok
[INFO] [stdout] test table::handle::tests::eager_put_of_a_broken_link_still_succeeds_but_carries_a_warning ... ok
[INFO] [stdout] test table::handle::tests::deprecated_field_warnings_answer_the_same_in_either_validation_mode_and_on_every_write ... ok
[INFO] [stdout] test table::handle::tests::get_of_an_unknown_key_is_none ... ok
[INFO] [stdout] test table::handle::tests::eager_put_of_a_link_to_a_cover_images_virtual_path_in_another_record_carries_no_warnings ... ok
[INFO] [stdout] test table::handle::tests::eager_put_of_a_link_to_a_path_no_field_holds_still_carries_a_warning ... ok
[INFO] [stdout] test table::handle::tests::follow_is_due_only_where_follow_if_due_would_reach_for_the_files ... ok
[INFO] [stdout] test table::handle::tests::eager_put_of_a_link_to_an_existing_page_carries_no_warnings ... ok
[INFO] [stdout] test table::handle::tests::half_written_tail::a_readonly_open_moves_its_own_end_marker_and_leaves_the_torn_file_alone ... ok
[INFO] [stdout] test table::handle::tests::history_does_not_see_a_write_that_is_still_staged ... ok
[INFO] [stdout] test table::handle::tests::has_staged_writes_answers_whether_a_flush_would_do_anything ... ok
[INFO] [stdout] test table::handle::tests::history_reads_the_line_the_offsets_it_reports_point_at ... ok
[INFO] [stdout] test table::handle::tests::half_written_tail::a_writable_open_cuts_the_torn_tail_off_the_log_so_the_next_append_stays_readable ... ok
[INFO] [stdout] test table::handle::tests::lazy_link_parsing_reports_no_automatic_warning_but_broken_links_of_finds_it_on_request ... ok
[INFO] [stdout] test table::handle::tests::nothing_can_link_to_a_non_addressable_field_because_it_holds_no_virtual_path ... ok
[INFO] [stdout] test table::handle::tests::history_hands_back_every_version_of_one_key_oldest_first ... ok
[INFO] [stdout] test table::handle::tests::keys_has_len_and_stats_report_the_live_records_not_the_bytes_behind_them ... ok
[INFO] [stdout] test table::handle::tests::partitions::a_readonly_open_reads_the_flat_log_the_move_has_not_touched_yet ... ok
[INFO] [stdout] test table::handle::tests::partitions::a_readonly_open_of_the_unmoved_flat_log_writes_no_index_snapshot ... ok
[INFO] [stdout] test table::handle::tests::partitions::an_unpartitioned_table_keeps_writing_the_single_flat_log ... ok
[INFO] [stdout] test table::handle::tests::partitions::history_of_a_declared_but_unsplit_table_reads_the_flat_log ... ok
[INFO] [stdout] test table::handle::tests::partitions::a_readonly_open_reads_the_partitions_once_the_move_has_run ... ok
[INFO] [stdout] test table::handle::tests::partitions::a_writer_still_finishes_the_move_a_readonly_open_left_undone ... ok
[INFO] [stdout] test table::handle::tests::partitions::holds_uniqueness_across_partitions_not_just_inside_one ... ok
[INFO] [stdout] test table::handle::tests::partitions::history_of_a_key_that_moved_partitions_reads_as_one_list_across_both_files ... ok
[INFO] [stdout] test table::handle::tests::partitions::lands_a_unit_of_work_that_spans_two_partitions_of_one_table ... ok
[INFO] [stdout] test table::handle::tests::partitions::keeps_one_log_file_per_partition_value ... ok
[INFO] [stdout] test table::handle::tests::partitions::compacts_one_partition_and_leaves_the_others_byte_for_byte ... ok
[INFO] [stdout] test table::handle::tests::partitions::compaction_repairs_every_partition_watermark_in_the_snapshot ... ok
[INFO] [stdout] test table::handle::tests::partitions::keeps_full_text_results_identical_to_the_unpartitioned_table ... ok
[INFO] [stdout] test table::handle::tests::partitions::pairs_the_index_snapshot_with_every_partition_it_covers ... ok
[INFO] [stdout] test table::handle::tests::partitions::refuses_a_partition_value_that_cannot_name_a_file ... ok
[INFO] [stdout] test table::handle::tests::partitions::refuses_a_staged_write_that_would_move_a_key_the_window_already_placed ... ok
[INFO] [stdout] test table::handle::tests::partitions::puts_a_tombstone_in_the_partition_the_record_lived_in ... ok
[INFO] [stdout] test table::handle::tests::partitions::refuses_to_move_a_record_between_partitions ... ok
[INFO] [stdout] test table::handle::tests::partitions::refuses_a_unit_of_work_that_would_move_a_record_between_partitions ... ok
[INFO] [stdout] test table::handle::tests::partitions::reads_every_partition_back_after_a_restart ... ok
[INFO] [stdout] test table::handle::tests::partitions::moves_the_live_records_into_one_file_per_partition_when_the_schema_starts_partitioning ... ok
[INFO] [stdout] test table::handle::tests::patch_cannot_change_the_primary_key ... ok
[INFO] [stdout] test table::handle::tests::partitions::rewrites_a_record_inside_its_own_partition ... ok
[INFO] [stdout] test table::handle::tests::partitions::rebuilds_when_one_partition_grew_after_the_snapshot_was_taken ... ok
[INFO] [stdout] test table::handle::tests::patch_of_an_unknown_key_is_an_error ... ok
[INFO] [stdout] test table::handle::tests::pending_is_empty_between_calls_because_every_write_commits_inside_its_own_call ... ok
[INFO] [stdout] test table::handle::tests::patch_merges_only_the_given_fields ... ok
[INFO] [stdout] test table::handle::tests::plain_put_and_patch_still_return_a_bare_record_alongside_the_warning_variants ... ok
[INFO] [stdout] test table::handle::tests::partitions::restores_the_registry_from_a_snapshot_that_covers_every_partition ... ok
[INFO] [stdout] test table::handle::tests::put_then_get_round_trips_the_record ... ok
[INFO] [stdout] test table::handle::tests::put_delete_get_leaves_nothing_behind ... ok
[INFO] [stdout] test table::handle::tests::read_cache::a_delete_of_a_cached_key_leaves_nothing_for_the_next_get ... ok
[INFO] [stdout] test lock::dir_lock::tests::a_holder_that_resumes_beating_inside_the_grace_window_keeps_its_lock ... ok
[INFO] [stdout] test lock::dir_lock::tests::a_served_grace_naming_another_lease_reclaims_nothing ... ok
[INFO] [stdout] test table::handle::tests::read_cache::a_patch_over_a_cached_key_is_what_the_next_get_answers ... ok
[INFO] [stdout] test table::handle::tests::read_cache::a_following_read_of_a_cached_key_sees_the_foreign_write_it_caught_up_with ... ok
[INFO] [stdout] test table::handle::tests::query_delegates_to_the_planner_and_matches_it_directly ... ok
[INFO] [stdout] test table::handle::tests::read_cache::a_put_over_a_cached_key_is_what_the_next_get_answers ... ok
[INFO] [stdout] test table::handle::tests::read_cache::a_second_get_of_a_cached_key_answers_without_touching_the_log_again ... ok
[INFO] [stdout] test table::handle::tests::read_cache::a_recovered_journal_line_over_a_cached_key_invalidates_it ... ok
[INFO] [stdout] test table::handle::tests::read_cache::a_refresh_that_catches_a_foreign_write_drops_the_document_it_cached ... ok
[INFO] [stdout] test table::handle::tests::read_cache::a_table_nobody_configured_caches_nothing_and_reads_exactly_as_before ... ok
[INFO] [stdout] test table::handle::tests::read_cache::a_unit_of_work_entry_applied_over_a_cached_key_invalidates_it ... ok
[INFO] [stdout] test table::handle::tests::read_cache::a_reload_after_a_foreign_compaction_throws_the_whole_cache_away ... ok
[INFO] [stdout] test table::handle::tests::partitions::writes_and_reads_further_records_after_the_move ... ok
[INFO] [stdout] test table::handle::tests::read_cache::an_evicted_key_reaches_for_the_log_while_the_two_kept_ones_do_not ... ok
[INFO] [stdout] test table::handle::tests::read_cache::re_arming_the_cache_starts_it_cold ... ok
[INFO] [stdout] test table::handle::tests::read_cache::without_a_cache_the_same_second_get_goes_back_to_the_log ... ok
[INFO] [stdout] test table::handle::tests::refresh_applies_the_records_another_handle_appended_to_the_same_log ... ok
[INFO] [stdout] test table::handle::tests::read_cache::compacting_this_handles_own_log_leaves_nothing_cached_behind ... ok
[INFO] [stdout] test table::handle::tests::read_cache::large_documents_share_their_own_smaller_tier_and_leave_the_small_ones_alone ... ok
[INFO] [stdout] test table::handle::tests::read_cache::the_limit_evicts_the_least_recently_read_key_and_it_still_reads_off_the_log ... ok
[INFO] [stdout] test table::handle::tests::refresh_on_a_log_nobody_touched_reports_no_change_and_leaves_the_registry_alone ... ok
[INFO] [stdout] test table::handle::tests::refresh_opens_a_partition_file_another_handle_created_and_reads_it_whole ... ok
[INFO] [stdout] test table::handle::tests::refresh_applies_a_foreign_delete_as_well_as_a_foreign_write ... ok
[INFO] [stdout] test table::handle::tests::repeated_puts_on_the_same_key_leave_only_the_latest_version ... ok
[INFO] [stdout] test table::handle::tests::staging::a_staged_delete_answers_gone_before_its_tombstone_is_written ... ok
[INFO] [stdout] test table::handle::tests::staging::a_staged_patch_claims_the_fields_it_changes_and_no_others ... ok
[INFO] [stdout] test table::handle::tests::staging::a_staged_put_is_read_back_before_it_is_a_record ... ok
[INFO] [stdout] test table::handle::tests::staging::a_read_of_committed_state_flushes_the_window_first ... ok
[INFO] [stdout] test table::handle::tests::refresh_rebuilds_after_another_handle_compacted_the_log_under_it ... ok
[INFO] [stdout] test table::handle::tests::the_images_directory_is_created_automatically_on_first_write ... ok
[INFO] [stdout] test table::handle::tests::staging::a_staged_write_outranks_a_peers_committed_one_on_the_same_key ... ok
[INFO] [stdout] test table::handle::tests::stats_counts_a_rebuild_as_a_generation ... ok
[INFO] [stdout] test table::handle::tests::what_a_sync_never_table_wrote_is_still_there_after_reopening_it ... ok
[INFO] [stdout] test table::handle::tests::staging::a_trigger_fires_on_the_flush_rather_than_on_the_staging ... ok
[INFO] [stdout] test table::trigger::tests::a_field_missing_on_the_named_side_is_skipped ... ok
[INFO] [stdout] test table::trigger::tests::copies_new_and_old_fields_and_fills_constants ... ok
[INFO] [stdout] test txn::journal::tests::a_missing_journal_reads_as_nothing_to_recover ... ok
[INFO] [stdout] test txn::journal::tests::reads_the_pre_rename_marker_key_an_older_release_left_behind ... ok
[INFO] [stdout] test table::handle::tests::two_records_may_share_the_path_of_a_non_addressable_field ... ok
[INFO] [stdout] test txn::journal::tests::reads_the_snake_case_header_an_older_release_left_behind ... ok
[INFO] [stdout] test txn::journal::tests::writes_the_header_key_the_format_documents ... ok
[INFO] [stdout] test txn::journal::tests::round_trips_header_and_entries ... ok
[INFO] [stdout] test table::handle::tests::reads_agree_before_and_after_a_restart_that_follows_a_compaction ... ok
[INFO] [stdout] test table::handle::tests::writes_a_snapshot_once_the_operation_threshold_is_crossed ... ok
[INFO] [stdout] test table::handle::tests::refresh_catches_a_foreign_compaction_even_when_the_log_ends_up_longer_than_before ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_cross_table_insert_trigger_effect_lands_in_the_same_unit_of_work ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_patch_of_a_key_the_same_unit_deleted_is_refused_as_absent ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_delete_trigger_sees_the_record_the_same_unit_put_moments_earlier ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_page_through_the_unit_is_cut_after_the_staged_writes_joined_the_order ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_patch_merges_over_the_put_the_same_unit_made_of_a_key_the_log_does_not_hold_yet ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_patch_that_duplicates_a_put_made_earlier_in_the_same_unit_is_refused_too ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_read_through_the_unit_sees_its_own_uncommitted_put ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_query_through_the_unit_excludes_a_patch_that_makes_the_record_stop_matching ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_query_through_the_unit_excludes_an_uncommitted_delete_the_table_itself_still_finds ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_query_through_the_unit_finds_an_uncommitted_put_the_table_itself_does_not_see_yet ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_search_through_a_unit_is_rescored_over_the_corpus_the_unit_will_leave ... ok
[INFO] [stdout] test table::handle::tests::the_generation_counts_compactions_and_survives_a_restart_through_the_snapshot_header ... ok
[INFO] [stdout] test table::handle::tests::zero_disables_periodic_snapshotting ... ok
[INFO] [stdout] test lock::dir_lock::tests::challenge_reports_the_grace_an_expired_lease_is_owed_without_waiting_any_of_it ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_search_through_the_unit_leaves_out_a_staged_record_the_term_is_not_in ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_search_through_the_unit_drops_a_record_a_staged_patch_takes_the_term_out_of ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_unit_answers_a_prefix_search_with_a_record_only_it_has_staged ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_put_over_a_key_the_same_unit_deleted_fires_insert_not_update ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_refused_unit_keeps_its_staging_and_lands_once_the_cause_is_fixed ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_unit_refused_for_a_bad_document_is_refused_again_because_staging_is_append_only ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_search_without_an_overlay_index_falls_back_to_checking_every_staged_record ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_staged_put_that_matches_counts_as_one_staged_candidate_and_suppresses_nothing ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_staged_patch_suppresses_the_committed_candidate_and_re_stages_it_in_its_new_state ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_staged_delete_suppresses_the_committed_candidate_the_index_still_holds ... ok
[INFO] [stdout] test txn::unit_of_work::tests::an_invalid_op_in_one_table_rejects_the_whole_unit_with_nothing_written_anywhere ... ok
[INFO] [stdout] test txn::unit_of_work::tests::every_unit_id_is_its_own_even_when_two_are_minted_in_the_same_nanosecond ... ok
[INFO] [stdout] test txn::unit_of_work::tests::an_unknown_table_fails_the_whole_unit_before_anything_is_staged_to_disk ... ok
[INFO] [stdout] test txn::unit_of_work::tests::nothing_to_recover_when_no_journal_was_ever_written ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_unit_with_nothing_staged_still_reports_an_empty_overlay ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_unit_reading_and_committing_into_a_warn_table_never_sees_the_stray_field ... ok
[INFO] [stdout] test txn::unit_of_work::tests::two_puts_of_one_email_in_the_same_unit_are_refused_before_either_lands ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_unit_that_landed_refuses_a_second_commit_instead_of_writing_twice ... ok
[INFO] [stdout] test txn::unit_of_work::tests::a_unit_may_hand_an_email_from_the_record_it_deletes_to_the_one_it_writes ... ok
[INFO] [stdout] test txn::unit_of_work::tests::commits_across_two_tables_and_both_see_the_change ... ok
[INFO] [stdout] test txn::unit_of_work::tests::recovering_twice_is_a_no_op_the_second_time ... ok
[INFO] [stdout] test txn::unit_of_work::tests::recover_incomplete_finishes_a_journal_left_by_a_crash_between_journal_and_write ... ok
[INFO] [stdout] test txn::unit_of_work::tests::an_update_trigger_sees_old_and_new_of_the_same_patch_at_once ... ok
[INFO] [stdout] test lock::dir_lock::tests::concurrent_finishers_of_one_challenge_leave_the_lock_file_naming_exactly_one_of_them ... ok
[INFO] [stdout] test lock::dir_lock::tests::exactly_one_of_many_concurrent_challengers_takes_an_expired_lock ... ok
[INFO] [stdout] test lock::dir_lock::tests::finishing_a_challenge_a_resumed_holder_has_moved_past_reclaims_nothing ... ok
[INFO] [stdout] test lock::dir_lock::tests::never_steals_from_a_holder_that_keeps_beating_across_several_lease_periods ... ok
[INFO] [stdout] test lock::dir_lock::tests::of_two_callers_finishing_one_challenge_exactly_one_takes_the_lock_and_the_other_is_refused ... ok
[INFO] [stdout] test lock::dir_lock::tests::reclaims_an_expired_lease_on_the_lease_alone_with_no_pid_liveness_to_help ... ok
[INFO] [stdout] test lock::dir_lock::tests::inspect_tells_an_expired_lease_from_a_holder_that_is_provably_gone ... ok
[INFO] [stdout] test lock::dir_lock::tests::reclaims_a_lock_whose_owner_is_gone ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- registry::table_registry::tests::matches_the_ts_fixture_search_results_after_a_log_scan stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'registry::table_registry::tests::matches_the_ts_fixture_search_results_after_a_log_scan' (1455) panicked at src/registry/table_registry.rs:988:91:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x6413d13ee8a1 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x6413d1405a9a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x6413d13f32dc - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x6413d13c5d46 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6413d13e4109 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x6413d1368850 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x6413d13e4432 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x6413d13c5df2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x6413d13be769 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x6413d13c6c8d - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x6413d14061cc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x6413d1405fa2 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x6413d102a5b9 - hayson_engine[673ceba897d13d02]::registry::table_registry::tests::matches_the_ts_fixture_search_results_after_a_log_scan
[INFO] [stdout]   13:     0x6413d0eb5ab9 - <hayson_engine[673ceba897d13d02]::registry::table_registry::tests::matches_the_ts_fixture_search_results_after_a_log_scan::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6413d135bb3b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   15:     0x6413d13691a5 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   16:     0x6413d1362a64 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   17:     0x6413d136c302 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   18:     0x6413d13ed829 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   19:     0x78ecd8e20dfa - <unknown>
[INFO] [stdout]   20:     0x78ecd8eb43d4 - clone
[INFO] [stdout]   21:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- schema::parse::tests::parses_the_ts_reference_fixture_and_normalizes_the_primary_key stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'schema::parse::tests::parses_the_ts_reference_fixture_and_normalizes_the_primary_key' (1475) panicked at src/schema/parse.rs:504:65:
[INFO] [stdout] "/opt/rustwide/workdir/../../packages/hayson/test/fixtures/v1/unique-field/users.schema.json": No such file or directory (os error 2)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x6413d13ee8a1 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x6413d1405a9a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] [stdout]    2:     0x6413d13f32dc - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x6413d13c5d46 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6413d13e4109 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x6413d1368850 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x6413d13e4432 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x6413d13c5df2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x6413d13be769 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x6413d13c6c8d - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x6413d14061cc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x6413d0fdda7a - hayson_engine[673ceba897d13d02]::schema::parse::tests::fixture::{closure#0}
[INFO] [stdout]   12:     0x6413d1005e0d - hayson_engine[673ceba897d13d02]::schema::parse::tests::parses_the_ts_reference_fixture_and_normalizes_the_primary_key
[INFO] [stdout]   13:     0x6413d0eb0809 - <hayson_engine[673ceba897d13d02]::schema::parse::tests::parses_the_ts_reference_fixture_and_normalizes_the_primary_key::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6413d135bb3b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   15:     0x6413d13691a5 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   16:     0x6413d1362a64 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   17:     0x6413d136c302 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   18:     0x6413d13ed829 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   19:     0x78ecd8e20dfa - <unknown>
[INFO] [stdout]   20:     0x78ecd8eb43d4 - clone
[INFO] [stdout]   21:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- storage::record::tests::matches_the_ts_fixture_byte_for_byte stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'storage::record::tests::matches_the_ts_fixture_byte_for_byte' (1551) panicked at src/storage/record.rs:182:49:
[INFO] [stdout] TS fixture must exist: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x6413d13ee8a1 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x6413d1405a9a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x6413d13f32dc - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x6413d13c5d46 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6413d13e4109 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x6413d1368850 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x6413d13e4432 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x6413d13c5df2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x6413d13be769 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x6413d13c6c8d - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x6413d14061cc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x6413d1405fa2 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x6413d116b165 - hayson_engine[673ceba897d13d02]::storage::record::tests::matches_the_ts_fixture_byte_for_byte
[INFO] [stdout]   13:     0x6413d0eb47d9 - <hayson_engine[673ceba897d13d02]::storage::record::tests::matches_the_ts_fixture_byte_for_byte::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x6413d135bb3b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   15:     0x6413d13691a5 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   16:     0x6413d1362a64 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   17:     0x6413d136c302 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   18:     0x6413d13ed829 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   19:     0x78ecd8e20dfa - <unknown>
[INFO] [stdout]   20:     0x78ecd8eb43d4 - clone
[INFO] [stdout]   21:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     registry::table_registry::tests::matches_the_ts_fixture_search_results_after_a_log_scan
[INFO] [stdout]     schema::parse::tests::parses_the_ts_reference_fixture_and_normalizes_the_primary_key
[INFO] [stdout]     storage::record::tests::matches_the_ts_fixture_byte_for_byte
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 751 passed; 3 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.82s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "1e31ca407e51b4d4ed93e10f01c0fb80d528a0aef9184101a10fe74713ce5b59", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1e31ca407e51b4d4ed93e10f01c0fb80d528a0aef9184101a10fe74713ce5b59", kill_on_drop: false }`
[INFO] [stdout] 1e31ca407e51b4d4ed93e10f01c0fb80d528a0aef9184101a10fe74713ce5b59
