[INFO] cloning repository https://github.com/MacCracken/keystory
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/MacCracken/keystory" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FMacCracken%2Fkeystory", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FMacCracken%2Fkeystory'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 1a6c077eee16a31b9877749dad1ba7f401e67b72
[INFO] testing MacCracken/keystory against 1.100.0-beta.1+cargoflags=--release for beta-release-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FMacCracken%2Fkeystory" "/workspace/builds/worker-7-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc2/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-7-tc2/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/MacCracken/keystory
[INFO] removed 0 missing tests
[INFO] finished tweaking git repo https://github.com/MacCracken/keystory
[INFO] tweaked toml for git repo https://github.com/MacCracken/keystory written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/MacCracken/keystory 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 git repo https://github.com/MacCracken/keystory already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] cf9ae8c7cde3ffbe6a8ad17aa97c616a3e4c848d8f37ad8bdb536c35c3b618a4
[INFO] running `Command { std: "docker" "start" "cf9ae8c7cde3ffbe6a8ad17aa97c616a3e4c848d8f37ad8bdb536c35c3b618a4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "cf9ae8c7cde3ffbe6a8ad17aa97c616a3e4c848d8f37ad8bdb536c35c3b618a4", 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" "cf9ae8c7cde3ffbe6a8ad17aa97c616a3e4c848d8f37ad8bdb536c35c3b618a4" "/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" "cf9ae8c7cde3ffbe6a8ad17aa97c616a3e4c848d8f37ad8bdb536c35c3b618a4", 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" "cf9ae8c7cde3ffbe6a8ad17aa97c616a3e4c848d8f37ad8bdb536c35c3b618a4" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling keystory v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized + debuginfo] target(s) in 3.44s
[INFO] running `Command { std: "docker" "inspect" "cf9ae8c7cde3ffbe6a8ad17aa97c616a3e4c848d8f37ad8bdb536c35c3b618a4", 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" "cf9ae8c7cde3ffbe6a8ad17aa97c616a3e4c848d8f37ad8bdb536c35c3b618a4" "/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 keystory v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized + debuginfo] target(s) in 13.20s
[INFO] running `Command { std: "docker" "inspect" "cf9ae8c7cde3ffbe6a8ad17aa97c616a3e4c848d8f37ad8bdb536c35c3b618a4", 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" "cf9ae8c7cde3ffbe6a8ad17aa97c616a3e4c848d8f37ad8bdb536c35c3b618a4" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/build/keystory/0589640c1c3d5b3d/out/keystory-0589640c1c3d5b3d)
[INFO] [stdout] 
[INFO] [stdout] running 84 tests
[INFO] [stdout] test checker::tests::a_stale_but_valid_read_passes ... ok
[INFO] [stdout] test checker::tests::delete_then_reread_reflects_absence ... ok
[INFO] [stdout] test checkpoint::tests::load_missing_is_none ... ok
[INFO] [stdout] test crc::test::empty_input_is_zero ... ok
[INFO] [stdout] test crc::test::incremental_matches_oneshot ... ok
[INFO] [stdout] test checker::tests::an_inconsistent_read_is_caught ... ok
[INFO] [stdout] test checker::tests::consistent_history_passes ... ok
[INFO] [stdout] test checker::tests::out_of_order_recording_is_sorted_before_checking ... ok
[INFO] [stdout] test crc::test::random_ish_inputs_are_stable ... ok
[INFO] [stdout] test checkpoint::tests::entry_count_is_bounded_by_the_body_not_a_fixed_cap ... ok
[INFO] [stdout] test checkpoint::tests::trailing_bytes_and_duplicate_keys_are_rejected ... ok
[INFO] [stdout] test crc::test::canon_test_vector ... ok
[INFO] [stdout] test checkpoint::tests::corrupt_crc_rejected ... ok
[INFO] [stdout] test checkpoint::tests::atomicity_no_tmp_left_behind ... ok
[INFO] [stdout] test checkpoint::tests::roundtrip_empty ... ok
[INFO] [stdout] test checkpoint::tests::roundtrip_nonempty ... ok
[INFO] [stdout] test checkpoint::tests::another_format_version_is_refused_without_falling_back ... ok
[INFO] [stdout] test checkpoint::tests::discarding_a_damaged_latest_keeps_the_good_previous_generation ... ok
[INFO] [stdout] test checkpoint::tests::previous_generation_is_kept_and_used_as_fallback ... ok
[INFO] [stdout] test engine::tests::a_second_open_of_a_live_store_is_refused ... ok
[INFO] [stdout] test engine::tests::a_store_in_another_format_version_is_refused ... ok
[INFO] [stdout] test engine::tests::a_batch_is_one_commit ... ok
[INFO] [stdout] test engine::tests::a_snapshot_is_a_consistent_point_in_time_view ... ok
[INFO] [stdout] test engine::tests::a_failed_append_is_never_published_and_loses_nothing_acknowledged ... ok
[INFO] [stdout] test engine::tests::put_get_roundtrip ... ok
[INFO] [stdout] test engine::tests::delete_then_get_is_none ... ok
[INFO] [stdout] test engine::tests::creating_a_store_syncs_every_new_directory_entry ... ok
[INFO] [stdout] test engine::tests::range_scan_is_empty_for_inverted_or_equal_bounds ... ok
[INFO] [stdout] test engine::tests::open_repairs_torn_tail_then_keeps_appending ... ok
[INFO] [stdout] test engine::tests::scan_returns_prefix_in_order ... ok
[INFO] [stdout] test rcu::tests::load_returns_initial_value ... ok
[INFO] [stdout] test rcu::tests::no_leak_of_replaced_values ... ok
[INFO] [stdout] test engine::tests::torn_batch_record_applies_nothing ... ok
[INFO] [stdout] test rcu::tests::reader_snapshot_is_isolated_from_later_stores ... ok
[INFO] [stdout] test rcu::tests::store_updates_and_returns_previous ... ok
[INFO] [stdout] test types::tests::cloned_maps_share_bytes ... ok
[INFO] [stdout] test types::tests::delete_missing_is_noop ... ok
[INFO] [stdout] test types::tests::idempotent_put_same_value_is_noop ... ok
[INFO] [stdout] test types::tests::iterators_outlive_the_bounds_they_were_made_from ... ok
[INFO] [stdout] test types::tests::op_constructors_take_any_bytes ... ok
[INFO] [stdout] test types::tests::point_reads_borrow_the_value_and_report_its_version ... ok
[INFO] [stdout] test types::tests::put_overwrite_changes_state ... ok
[INFO] [stdout] test types::tests::range_is_half_open_and_ordered ... ok
[INFO] [stdout] test types::tests::scan_bounds_prefixes_that_end_in_ff ... ok
[INFO] [stdout] test types::tests::scan_is_ordered_and_stops_after_the_prefix ... ok
[INFO] [stdout] test types::tests::scan_matches_a_prefix_filter_on_edge_bytes ... ok
[INFO] [stdout] test wal::tests::a_bad_header_in_front_of_records_is_corruption ... ok
[INFO] [stdout] test wal::tests::a_checksummed_record_that_does_not_decode_is_corruption_not_a_torn_tail ... ok
[INFO] [stdout] test engine::tests::open_refuses_a_log_with_missing_or_reordered_records ... ok
[INFO] [stdout] test wal::tests::a_failed_rollback_poisons_the_log_until_reopen ... ok
[INFO] [stdout] test rcu::tests::parallel_readers_observe_no_torn_snapshots ... ok
[INFO] [stdout] test wal::tests::a_rotation_that_creates_nothing_keeps_appending_to_the_current_segment ... ok
[INFO] [stdout] test engine::tests::corrupt_latest_checkpoint_falls_back_to_previous_plus_wal ... ok
[INFO] [stdout] test engine::tests::a_checkpoint_after_a_fallback_keeps_the_good_generation ... ok
[INFO] [stdout] test wal::tests::a_segment_of_another_format_version_is_refused ... ok
[INFO] [stdout] test wal::tests::a_rotation_that_finds_its_segment_name_taken_poisons_the_log ... ok
[INFO] [stdout] test wal::tests::batch_record_round_trips ... ok
[INFO] [stdout] test wal::tests::a_rotation_whose_header_fails_removes_the_half_created_segment ... ok
[INFO] [stdout] test wal::tests::crc_detects_bit_flip ... ok
[INFO] [stdout] test wal::tests::every_segment_starts_with_a_versioned_header ... ok
[INFO] [stdout] test wal::tests::a_rotation_that_cannot_undo_or_finish_its_segment_poisons_the_log ... ok
[INFO] [stdout] test wal::tests::oversized_batch_count_is_rejected ... ok
[INFO] [stdout] test wal::tests::failed_append_is_rolled_back_so_a_retried_index_is_logged_once ... ok
[INFO] [stdout] test engine::tests::range_scan_is_ordered_and_half_open ... ok
[INFO] [stdout] test wal::tests::records_the_framing_cannot_hold_are_refused_before_writing ... ok
[INFO] [stdout] test wal::tests::open_repairs_torn_tail_and_resumes_the_segment ... ok
[INFO] [stdout] test wal::tests::roundtrip_put_and_delete ... ok
[INFO] [stdout] test wal::tests::replay_stops_at_the_first_record_the_sink_refuses ... ok
[INFO] [stdout] test wal::tests::stray_files_are_not_mistaken_for_segments ... ok
[INFO] [stdout] test wal::tests::torn_tail_is_dropped_cleanly ... ok
[INFO] [stdout] test wal::tests::corruption_before_a_later_segment_is_an_error ... ok
[INFO] [stdout] test engine::tests::checkpoints_reclaim_wal_one_generation_behind ... ok
[INFO] [stdout] test wal::tests::rotate_then_remove_segments_before_boundary ... ok
[INFO] [stdout] test checkpoint::tests::streaming_roundtrip_large ... ok
[INFO] [stdout] test wal::tests::an_interrupted_segment_creation_is_repaired_at_open ... ok
[INFO] [stdout] test engine::tests::crash_recovery_replays_wal ... ok
[INFO] [stdout] test engine::tests::reopen_without_checkpoint_keeps_wal_data ... ok
[INFO] [stdout] test engine::tests::range_scan_matches_prefix_scan_after_deletes ... ok
[INFO] [stdout] test engine::tests::concurrent_writers_are_group_committed ... ok
[INFO] [stdout] test wal::tests::rotation_and_replay_order ... ok
[INFO] [stdout] test engine::tests::crash_recovery_from_snapshot_then_wal_tail ... ok
[INFO] [stdout] test engine::tests::checkpoint_concurrent_with_writes_loses_nothing ... ok
[INFO] [stdout] test engine::tests::one_checkpoint_per_open_still_reclaims_the_log ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/build/keystory/fb58f1a60b999005/out/keystory_crash_runner-fb58f1a60b999005)
[INFO] [stdout] test engine::tests::store_is_sync_shareable_across_threads ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 84 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 37.98s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/crash_recover.rs (/opt/rustwide/target/release/build/keystory/3c6d00d6d75dd14e/out/crash_recover-3c6d00d6d75dd14e)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test sigkill_mid_write_recovers_a_consistent_prefix ... ok
[INFO] [stdout] test a_store_owned_by_another_process_is_refused_until_it_dies ... ok
[INFO] [stdout] test crash_without_checkpoint_recovers_purely_from_wal ... ok
[INFO] [stdout] test survives_sigkill_then_recovers_full_state ... ok
[INFO] [stderr]      Running tests/linearisability.rs (/opt/rustwide/target/release/build/keystory/5c54895fba5d2e16/out/linearisability-5c54895fba5d2e16)
[INFO] [stdout] test checkpoint_then_crash_recovers_from_snapshot_plus_tail ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.71s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test concurrent_writers_and_readers_are_sequentially_consistent ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 8.85s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/relative_path.rs (/opt/rustwide/target/release/build/keystory/fd0db328d16c72bf/out/relative_path-fd0db328d16c72bf)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]    Doc-tests keystory
[INFO] [stdout] test a_store_opened_by_a_relative_path_stays_where_it_was_opened ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.23s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test src/lib.rs - ReadmeDoctests (line 175) - compile ... ok
[INFO] [stdout] test src/engine.rs - engine::Options (line 76) ... ok
[INFO] [stdout] test src/engine.rs - engine::Store::snapshot (line 459) ... ok
[INFO] [stdout] test src/lib.rs - (line 9) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.66s; merged doctests compilation took 0.61s
[INFO] running `Command { std: "docker" "inspect" "cf9ae8c7cde3ffbe6a8ad17aa97c616a3e4c848d8f37ad8bdb536c35c3b618a4", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "cf9ae8c7cde3ffbe6a8ad17aa97c616a3e4c848d8f37ad8bdb536c35c3b618a4", kill_on_drop: false }`
[INFO] [stdout] cf9ae8c7cde3ffbe6a8ad17aa97c616a3e4c848d8f37ad8bdb536c35c3b618a4
