[INFO] fetching crate mnem-store 0.0.8...
[INFO] testing mnem-store-0.0.8 against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate mnem-store 0.0.8 into /workspace/builds/worker-6-tc1/source
[INFO] started tweaking crates.io crate mnem-store 0.0.8
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate mnem-store 0.0.8
[INFO] tweaked toml for crates.io crate mnem-store 0.0.8 written to /workspace/builds/worker-6-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate mnem-store 0.0.8 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 mnem-store 0.0.8 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] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded redb v2.6.3
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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] 6d2ef3a4d067768622c5d7371cacc9d74ce4b4c07edce6054e7b493f499460c1
[INFO] running `Command { std: "docker" "start" "6d2ef3a4d067768622c5d7371cacc9d74ce4b4c07edce6054e7b493f499460c1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "6d2ef3a4d067768622c5d7371cacc9d74ce4b4c07edce6054e7b493f499460c1", 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" "6d2ef3a4d067768622c5d7371cacc9d74ce4b4c07edce6054e7b493f499460c1" "/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" "6d2ef3a4d067768622c5d7371cacc9d74ce4b4c07edce6054e7b493f499460c1", 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" "6d2ef3a4d067768622c5d7371cacc9d74ce4b4c07edce6054e7b493f499460c1" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling zerocopy v0.8.56
[INFO] [stderr]    Compiling zerocopy-derive v0.8.56
[INFO] [stderr]    Compiling redb v2.6.3
[INFO] [stderr]    Compiling constant_time_eq v0.4.2
[INFO] [stderr]    Compiling cc v1.4.5
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling blake3 v1.8.7
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling thiserror-impl v2.0.20
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling mnem-store v0.0.8 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 32.73s
[INFO] running `Command { std: "docker" "inspect" "6d2ef3a4d067768622c5d7371cacc9d74ce4b4c07edce6054e7b493f499460c1", 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" "6d2ef3a4d067768622c5d7371cacc9d74ce4b4c07edce6054e7b493f499460c1" "/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 mnem-store v0.0.8 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 19.90s
[INFO] running `Command { std: "docker" "inspect" "6d2ef3a4d067768622c5d7371cacc9d74ce4b4c07edce6054e7b493f499460c1", 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" "6d2ef3a4d067768622c5d7371cacc9d74ce4b4c07edce6054e7b493f499460c1" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/mnem_store-a57a3ef82e075b4b)
[INFO] [stdout] 
[INFO] [stdout] running 91 tests
[INFO] [stdout] test blame::tests::blame_reads_provenance_and_event_time ... ok
[INFO] [stdout] test branch::tests::branch_creates_a_ref_at_head ... ok
[INFO] [stdout] test branch::tests::branch_refuses_a_duplicate_or_bad_name ... ok
[INFO] [stdout] test bisect::tests::bisect_rejects_a_non_monotonic_or_impossible_range ... ok
[INFO] [stdout] test codec::tests::a_commit_encodes_stably ... ok
[INFO] [stdout] test codec::tests::content_map_key_order_does_not_change_the_bytes ... ok
[INFO] [stdout] test blame::tests::blame_errors_when_the_node_is_absent ... ok
[INFO] [stdout] test codec::tests::encoding_is_idempotent ... ok
[INFO] [stdout] test codec::tests::garbage_bytes_are_corrupt_store ... ok
[INFO] [stdout] test codec::tests::round_trips ... ok
[INFO] [stdout] test branch::tests::resolve_commitish_covers_names_ids_and_errors ... ok
[INFO] [stdout] test bisect::tests::bisect_on_the_boundary_cases ... ok
[INFO] [stdout] test checkout::tests::checkout_refuses_a_dirty_index_unless_discarded ... ok
[INFO] [stdout] test blame::tests::blame_finds_the_commit_that_last_set_the_value ... ok
[INFO] [stdout] test config::tests::ignores_unknown_keys_and_comments ... ok
[INFO] [stdout] test config::tests::rejects_a_missing_required_key ... ok
[INFO] [stdout] test config::tests::rejects_a_future_format_version ... ok
[INFO] [stdout] test config::tests::round_trips ... ok
[INFO] [stdout] test config::tests::rejects_an_unknown_hash_algo ... ok
[INFO] [stdout] test branch::tests::delete_branch_drops_the_ref_but_not_the_current_one ... ok
[INFO] [stdout] test branch::tests::branch_refuses_when_head_is_unborn ... ok
[INFO] [stdout] test checkout::tests::checkout_switches_branches_and_detaches ... ok
[INFO] [stdout] test branch::tests::branch_takes_a_start_point ... ok
[INFO] [stdout] test head::tests::attached_round_trips ... ok
[INFO] [stdout] test head::tests::detached_round_trips ... ok
[INFO] [stdout] test head::tests::rejects_junk ... ok
[INFO] [stdout] test id::tests::hex_is_stable ... ok
[INFO] [stdout] test id::tests::hex_round_trips ... ok
[INFO] [stdout] test id::tests::parse_rejects_bad_input ... ok
[INFO] [stdout] test checkout::tests::rm_of_a_pending_add_just_unstages_and_rm_of_nothing_errors ... ok
[INFO] [stdout] test blame::tests::blame_follows_a_value_through_a_merge_to_its_real_origin ... ok
[INFO] [stdout] test bisect::tests::bisect_with_an_absence_predicate ... ok
[INFO] [stdout] test checkout::tests::staging_a_node_lifts_its_tombstone ... ok
[INFO] [stdout] test commit::tests::first_commit_creates_the_branch_with_no_parent ... ok
[INFO] [stdout] test bisect::tests::bisect_finds_the_flip_commit ... ok
[INFO] [stdout] test merge::tests::a_clean_merge_is_order_independent ... ok
[INFO] [stdout] test merge::tests::conflict_carries_all_three_sides ... ok
[INFO] [stdout] test merge::tests::every_row_of_the_table ... ok
[INFO] [stdout] test diff::tests::diff_against_working_memory_and_empty ... ok
[INFO] [stdout] test commit::tests::unstage_drops_a_node_before_it_is_committed ... ok
[INFO] [stdout] test commit::tests::commit_is_refused_from_a_detached_head ... ok
[INFO] [stdout] test commit::tests::a_second_commit_carries_the_first_ones_nodes_and_chains ... ok
[INFO] [stdout] test checkout::tests::working_memory_is_head_state_minus_tombstones_plus_staging ... ok
[INFO] [stdout] test diff::tests::diff_classifies_added_removed_and_modified ... ok
[INFO] [stdout] test index::tests::changed_by_recomputes_when_the_entry_is_missing ... ok
[INFO] [stdout] test object::tests::empty_provenance_is_skipped ... ok
[INFO] [stdout] test object::tests::commit_round_trips_through_cbor ... ok
[INFO] [stdout] test object::tests::node_round_trips_through_cbor ... ok
[INFO] [stdout] test object::tests::unknown_kind_is_rejected ... ok
[INFO] [stdout] test graph::tests::merge_base_is_the_ancestor_when_one_side_leads ... ok
[INFO] [stdout] test object::tests::object_kind_matches_the_tag ... ok
[INFO] [stdout] test objects::tests::put_is_idempotent_and_content_addressed ... ok
[INFO] [stdout] test objects::tests::two_objects_are_independent ... ok
[INFO] [stdout] test objects::tests::put_then_get_round_trips ... ok
[INFO] [stdout] test objects::tests::missing_object_is_none_on_a_fresh_store ... ok
[INFO] [stdout] test graph::tests::merge_base_of_two_branches_off_one_commit ... ok
[INFO] [stdout] test log::tests::log_head_is_empty_on_a_fresh_store ... ok
[INFO] [stdout] test graph::tests::criss_cross_history_has_two_merge_bases ... ok
[INFO] [stdout] test refs::tests::compare_and_set_enforces_the_expected_value ... ok
[INFO] [stdout] test index::tests::commit_records_its_change_set ... ok
[INFO] [stdout] test refs::tests::name_rules ... ok
[INFO] [stdout] test index::tests::a_merge_commit_is_indexed_against_its_first_parent ... ok
[INFO] [stdout] test refs::tests::list_is_empty_on_a_fresh_store ... ok
[INFO] [stdout] test refs::tests::set_get_list_delete ... ok
[INFO] [stdout] test log::tests::first_parent_walk_is_linear_and_newest_first ... ok
[INFO] [stdout] test log::tests::full_walk_visits_every_parent_once ... ok
[INFO] [stdout] test mergeflow::tests::merge_refuses_a_dirty_index_or_detached_head ... ok
[INFO] [stdout] test mergeflow::tests::a_conflict_writes_nothing_then_a_resolution_completes_it ... ok
[INFO] [stdout] test mergeflow::tests::a_clean_merge_writes_a_two_parent_commit ... ok
[INFO] [stdout] test mergeflow::tests::fast_forward_and_already_up_to_date ... ok
[INFO] [stdout] test mergeflow::tests::strategy_ours_resolves_every_conflict_one_way ... ok
[INFO] [stdout] test store::tests::open_fails_where_there_is_no_store ... ok
[INFO] [stdout] test portable::tests::import_refuses_an_existing_store ... ok
[INFO] [stdout] test tests::version_is_reported ... ok
[INFO] [stdout] test mergeflow::tests::strategy_set_and_stale_resolutions ... ok
[INFO] [stdout] test portable::tests::object_ids_are_hex_strings_not_byte_arrays ... ok
[INFO] [stdout] test store::tests::head_starts_attached_to_a_missing_main_then_resolves ... ok
[INFO] [stdout] test store::tests::init_creates_the_layout ... ok
[INFO] [stdout] test store::tests::init_refuses_a_store_inside_a_store ... ok
[INFO] [stdout] test portable::tests::a_tampered_object_id_is_a_corrupt_store_error ... ok
[INFO] [stdout] test store::tests::open_discovers_from_a_subdirectory ... ok
[INFO] [stdout] test semantic::tests::structural_only_leaves_every_conflict_for_the_caller ... ok
[INFO] [stdout] test semantic::tests::a_resolver_verdict_settles_the_merge ... ok
[INFO] [stdout] test store::tests::transactions_open ... ok
[INFO] [stdout] test portable::tests::export_then_reexport_is_byte_identical ... ok
[INFO] [stdout] test semantic::tests::era_1_treats_a_contradiction_verdict_as_unresolved ... ok
[INFO] [stdout] test timetravel::tests::state_at_rejects_a_non_commit ... ok
[INFO] [stdout] test semantic::tests::an_explicit_resolution_beats_the_resolver ... ok
[INFO] [stdout] test portable::tests::export_then_import_round_trips_working_memory_and_history ... ok
[INFO] [stdout] test timetravel::tests::state_at_reconstructs_each_commit_exactly ... ok
[INFO] [stdout] test semantic::tests::merge_is_merge_with_structural_only ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 91 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/benchmark.rs (/opt/rustwide/target/release/deps/benchmark-14bf6c5b78eeae1b)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/buggy_run.rs (/opt/rustwide/target/release/deps/buggy_run-efa1367f133da951)
[INFO] [stdout] test the_v1_benchmark_correctness_metrics_hold ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 8.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test bisect_lands_on_the_bad_commit_and_blame_explains_it ... ok
[INFO] [stderr]      Running tests/golden_vectors.rs (/opt/rustwide/target/release/deps/golden_vectors-7817e7df1b5862b7)
[INFO] [stdout] test bisect_finds_where_a_belief_first_appeared ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test print ... ignored, run with --ignored to print the current vectors
[INFO] [stdout] test the_format_doc_lists_the_same_vectors ... FAILED
[INFO] [stdout] test vectors_match_the_frozen_bytes ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- the_format_doc_lists_the_same_vectors stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'the_format_doc_lists_the_same_vectors' (937) panicked at tests/golden_vectors.rs:151:6:
[INFO] [stdout] read docs/format/golden-vectors.md: Os { code: 2, kind: NotFound, message: "No such file or directory" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5af858384211 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5af85839895a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5af8583886ac - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5af858360496 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5af85837c609 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5af8582e5de0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5af85837c7c2 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5af858360542 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5af858359139 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5af85836113d - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5af85839908c - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5af858398e62 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5af8582d42d6 - <golden_vectors[b1906ca7f287b57]::the_format_doc_lists_the_same_vectors::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x5af8582d90cb - 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]   14:     0x5af8582e6735 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   15:     0x5af8582dfff4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x5af8582e9892 - <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]   17:     0x5af858383779 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x7a91d24d3dfa - <unknown>
[INFO] [stdout]   19:     0x7a91d25673d4 - clone
[INFO] [stdout]   20:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     the_format_doc_lists_the_same_vectors
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 1 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test golden_vectors`
[INFO] running `Command { std: "docker" "inspect" "6d2ef3a4d067768622c5d7371cacc9d74ce4b4c07edce6054e7b493f499460c1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6d2ef3a4d067768622c5d7371cacc9d74ce4b4c07edce6054e7b493f499460c1", kill_on_drop: false }`
[INFO] [stdout] 6d2ef3a4d067768622c5d7371cacc9d74ce4b4c07edce6054e7b493f499460c1
