[INFO] fetching crate mnem-store 0.0.8...
[INFO] testing mnem-store-0.0.8 against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate mnem-store 0.0.8 into /workspace/builds/worker-6-tc2/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-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate mnem-store 0.0.8 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate 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.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 2264fc44dbe918a6a389b2a622d4f1996cba66ddfe5e665d4777865df236474a
[INFO] running `Command { std: "docker" "start" "2264fc44dbe918a6a389b2a622d4f1996cba66ddfe5e665d4777865df236474a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "2264fc44dbe918a6a389b2a622d4f1996cba66ddfe5e665d4777865df236474a", 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" "2264fc44dbe918a6a389b2a622d4f1996cba66ddfe5e665d4777865df236474a" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "2264fc44dbe918a6a389b2a622d4f1996cba66ddfe5e665d4777865df236474a", 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" "2264fc44dbe918a6a389b2a622d4f1996cba66ddfe5e665d4777865df236474a" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling zerocopy 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 syn v3.0.5
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling zerocopy-derive v0.8.56
[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 15.63s
[INFO] running `Command { std: "docker" "inspect" "2264fc44dbe918a6a389b2a622d4f1996cba66ddfe5e665d4777865df236474a", 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" "2264fc44dbe918a6a389b2a622d4f1996cba66ddfe5e665d4777865df236474a" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling mnem-store v0.0.8 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 5.31s
[INFO] running `Command { std: "docker" "inspect" "2264fc44dbe918a6a389b2a622d4f1996cba66ddfe5e665d4777865df236474a", 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" "2264fc44dbe918a6a389b2a622d4f1996cba66ddfe5e665d4777865df236474a" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/mnem-store/a57a3ef82e075b4b/out/mnem_store-a57a3ef82e075b4b)
[INFO] [stdout] 
[INFO] [stdout] running 91 tests
[INFO] [stdout] test branch::tests::resolve_commitish_covers_names_ids_and_errors ... 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_refuses_a_duplicate_or_bad_name ... ok
[INFO] [stdout] test blame::tests::blame_errors_when_the_node_is_absent ... ok
[INFO] [stdout] test blame::tests::blame_reads_provenance_and_event_time ... 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 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::branch_creates_a_ref_at_head ... ok
[INFO] [stdout] test branch::tests::delete_branch_drops_the_ref_but_not_the_current_one ... ok
[INFO] [stdout] test config::tests::ignores_unknown_keys_and_comments ... ok
[INFO] [stdout] test config::tests::rejects_a_future_format_version ... ok
[INFO] [stdout] test config::tests::rejects_a_missing_required_key ... ok
[INFO] [stdout] test config::tests::rejects_an_unknown_hash_algo ... ok
[INFO] [stdout] test config::tests::round_trips ... ok
[INFO] [stdout] test checkout::tests::checkout_refuses_a_dirty_index_unless_discarded ... ok
[INFO] [stdout] test branch::tests::branch_takes_a_start_point ... ok
[INFO] [stdout] test bisect::tests::bisect_on_the_boundary_cases ... ok
[INFO] [stdout] test bisect::tests::bisect_rejects_a_non_monotonic_or_impossible_range ... ok
[INFO] [stdout] test blame::tests::blame_finds_the_commit_that_last_set_the_value ... 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 blame::tests::blame_follows_a_value_through_a_merge_to_its_real_origin ... ok
[INFO] [stdout] test bisect::tests::bisect_finds_the_flip_commit ... ok
[INFO] [stdout] test commit::tests::first_commit_creates_the_branch_with_no_parent ... ok
[INFO] [stdout] test checkout::tests::working_memory_is_head_state_minus_tombstones_plus_staging ... ok
[INFO] [stdout] test checkout::tests::staging_a_node_lifts_its_tombstone ... ok
[INFO] [stdout] test checkout::tests::rm_of_a_pending_add_just_unstages_and_rm_of_nothing_errors ... 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::a_second_commit_carries_the_first_ones_nodes_and_chains ... 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 bisect::tests::bisect_with_an_absence_predicate ... ok
[INFO] [stdout] test graph::tests::merge_base_is_the_ancestor_when_one_side_leads ... ok
[INFO] [stdout] test object::tests::commit_round_trips_through_cbor ... ok
[INFO] [stdout] test object::tests::empty_provenance_is_skipped ... ok
[INFO] [stdout] test object::tests::node_round_trips_through_cbor ... ok
[INFO] [stdout] test object::tests::object_kind_matches_the_tag ... ok
[INFO] [stdout] test object::tests::unknown_kind_is_rejected ... ok
[INFO] [stdout] test diff::tests::diff_classifies_added_removed_and_modified ... ok
[INFO] [stdout] test objects::tests::missing_object_is_none_on_a_fresh_store ... ok
[INFO] [stdout] test objects::tests::put_is_idempotent_and_content_addressed ... ok
[INFO] [stdout] test objects::tests::put_then_get_round_trips ... ok
[INFO] [stdout] test objects::tests::two_objects_are_independent ... ok
[INFO] [stdout] test graph::tests::criss_cross_history_has_two_merge_bases ... ok
[INFO] [stdout] test log::tests::log_head_is_empty_on_a_fresh_store ... ok
[INFO] [stdout] test graph::tests::merge_base_of_two_branches_off_one_commit ... ok
[INFO] [stdout] test index::tests::changed_by_recomputes_when_the_entry_is_missing ... 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 log::tests::full_walk_visits_every_parent_once ... ok
[INFO] [stdout] test refs::tests::name_rules ... 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 index::tests::a_merge_commit_is_indexed_against_its_first_parent ... ok
[INFO] [stdout] test log::tests::first_parent_walk_is_linear_and_newest_first ... ok
[INFO] [stdout] test mergeflow::tests::merge_refuses_a_dirty_index_or_detached_head ... ok
[INFO] [stdout] test mergeflow::tests::strategy_ours_resolves_every_conflict_one_way ... 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::a_conflict_writes_nothing_then_a_resolution_completes_it ... 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::init_creates_the_layout ... ok
[INFO] [stdout] test store::tests::init_refuses_a_store_inside_a_store ... ok
[INFO] [stdout] test store::tests::open_discovers_from_a_subdirectory ... ok
[INFO] [stdout] test store::tests::head_starts_attached_to_a_missing_main_then_resolves ... ok
[INFO] [stdout] test portable::tests::a_tampered_object_id_is_a_corrupt_store_error ... ok
[INFO] [stdout] test store::tests::transactions_open ... ok
[INFO] [stdout] test semantic::tests::era_1_treats_a_contradiction_verdict_as_unresolved ... 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 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 portable::tests::export_then_reexport_is_byte_identical ... ok
[INFO] [stdout] test timetravel::tests::state_at_rejects_a_non_commit ... 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 0.86s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/benchmark.rs (/opt/rustwide/target/release/build/mnem-store/14bf6c5b78eeae1b/out/benchmark-14bf6c5b78eeae1b)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[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 5.20s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/buggy_run.rs (/opt/rustwide/target/release/build/mnem-store/efa1367f133da951/out/buggy_run-efa1367f133da951)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test bisect_lands_on_the_bad_commit_and_blame_explains_it ... ok
[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.05s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/golden_vectors.rs (/opt/rustwide/target/release/build/mnem-store/7817e7df1b5862b7/out/golden_vectors-7817e7df1b5862b7)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test print ... ignored, run with --ignored to print the current vectors
[INFO] [stdout] test vectors_match_the_frozen_bytes ... ok
[INFO] [stdout] test the_format_doc_lists_the_same_vectors ... FAILED
[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' (936) 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:     0x6027b1c75221 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x6027b1c8968a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    2:     0x6027b1c7943c - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x6027b1c51326 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x6027b1c6d559 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]    5:     0x6027b1bd6e70 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]    6:     0x6027b1c6d712 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x6027b1c513d2 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x6027b1c49f59 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x6027b1c51fdd - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]   10:     0x6027b1c89dbc - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]   11:     0x6027b1c89b92 - core[a373ad73a504971]::result::unwrap_failed
[INFO] [stdout]   12:     0x6027b1bc54c6 - <golden_vectors[d60b3c161473b4de]::the_format_doc_lists_the_same_vectors::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   13:     0x6027b1bca2bb - test[aada8e7e76ba119b]::__rust_begin_short_backtrace::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]   14:     0x6027b1bd7495 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]   15:     0x6027b1bd0d84 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]   16:     0x6027b1bda522 - <std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   17:     0x6027b1c7471f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   18:     0x77b5a476cdfa - <unknown>
[INFO] [stdout]   19:     0x77b5a48003d4 - 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" "2264fc44dbe918a6a389b2a622d4f1996cba66ddfe5e665d4777865df236474a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2264fc44dbe918a6a389b2a622d4f1996cba66ddfe5e665d4777865df236474a", kill_on_drop: false }`
[INFO] [stdout] 2264fc44dbe918a6a389b2a622d4f1996cba66ddfe5e665d4777865df236474a
