[INFO] cloning repository https://github.com/junroh/myLedger
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/junroh/myLedger" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjunroh%2FmyLedger", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjunroh%2FmyLedger'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 2bf99efba081e4e58903828fc8cd694d5eb9c051
[INFO] testing junroh/myLedger against 1.100.0-beta.1 for beta-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fjunroh%2FmyLedger" "/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/.cargo/config.toml
[INFO] started tweaking git repo https://github.com/junroh/myLedger
[INFO] finished tweaking git repo https://github.com/junroh/myLedger
[INFO] tweaked toml for git repo https://github.com/junroh/myLedger written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/junroh/myLedger 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/junroh/myLedger 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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 969e6e0d536c80f368ad16ebed7dd8c0346f22af5efa43f37f0aa336f4c2a74c
[INFO] running `Command { std: "docker" "start" "969e6e0d536c80f368ad16ebed7dd8c0346f22af5efa43f37f0aa336f4c2a74c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "969e6e0d536c80f368ad16ebed7dd8c0346f22af5efa43f37f0aa336f4c2a74c", 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" "969e6e0d536c80f368ad16ebed7dd8c0346f22af5efa43f37f0aa336f4c2a74c" "/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" "969e6e0d536c80f368ad16ebed7dd8c0346f22af5efa43f37f0aa336f4c2a74c", 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" "969e6e0d536c80f368ad16ebed7dd8c0346f22af5efa43f37f0aa336f4c2a74c" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling windows_x86_64_gnu v0.52.6
[INFO] [stderr]    Compiling signal-hook v0.3.18
[INFO] [stderr]    Compiling ledger-base v0.1.0 (/opt/rustwide/workdir/base)
[INFO] [stderr]    Compiling rustc-hash v2.1.3
[INFO] [stderr]    Compiling semver v1.0.28
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling lexopt v0.3.2
[INFO] [stderr]    Compiling hdrhistogram v7.6.0
[INFO] [stderr]    Compiling windows-targets v0.52.6
[INFO] [stderr]    Compiling windows-sys v0.52.0
[INFO] [stderr]    Compiling rustc_version v0.4.1
[INFO] [stderr]    Compiling crc32c v0.6.8
[INFO] [stderr]    Compiling ledger-stubkit v0.1.0 (/opt/rustwide/workdir/stubkit)
[INFO] [stderr]    Compiling ledger-sequencer v0.1.0 (/opt/rustwide/workdir/sequencer)
[INFO] [stderr]    Compiling ledger-account v0.1.0 (/opt/rustwide/workdir/account)
[INFO] [stderr]    Compiling ledger-benchkit v0.1.0 (/opt/rustwide/workdir/benchkit)
[INFO] [stderr]    Compiling ledger-pending v0.1.0 (/opt/rustwide/workdir/pending)
[INFO] [stderr]    Compiling ledger-raft v0.1.0 (/opt/rustwide/workdir/raft)
[INFO] [stderr]    Compiling ledger-idempotency v0.1.0 (/opt/rustwide/workdir/idempotency)
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling ledger-service v0.1.0 (/opt/rustwide/workdir/service)
[INFO] [stderr]    Compiling ledgersim v0.1.0 (/opt/rustwide/workdir/ledgersim)
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling ledgerfio v0.1.0 (/opt/rustwide/workdir/ledgerfio)
[INFO] [stderr]    Compiling ledgerd v0.1.0 (/opt/rustwide/workdir/ledgerd)
[INFO] [stderr] warning: unused dependency `windows-sys`
[INFO] [stderr]   --> base/Cargo.toml:22:1
[INFO] [stderr]    |
[INFO] [stderr] 22 | windows-sys = { version = "0.52", features = ["Win32_Foundation", "Win32_System_Threading"], default-features = false }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `windows-sys`
[INFO] [stderr] warning: `ledger-base` (manifest) generated 1 warning
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 19.21s
[INFO] running `Command { std: "docker" "inspect" "969e6e0d536c80f368ad16ebed7dd8c0346f22af5efa43f37f0aa336f4c2a74c", 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" "969e6e0d536c80f368ad16ebed7dd8c0346f22af5efa43f37f0aa336f4c2a74c" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling rtrb v0.3.4
[INFO] [stderr]    Compiling ledger-account v0.1.0 (/opt/rustwide/workdir/account)
[INFO] [stderr]    Compiling ledger-idempotency v0.1.0 (/opt/rustwide/workdir/idempotency)
[INFO] [stderr]    Compiling ledgerfio v0.1.0 (/opt/rustwide/workdir/ledgerfio)
[INFO] [stderr]    Compiling ledger-sequencer v0.1.0 (/opt/rustwide/workdir/sequencer)
[INFO] [stderr]    Compiling ledger-base v0.1.0 (/opt/rustwide/workdir/base)
[INFO] [stderr]    Compiling ledgersim v0.1.0 (/opt/rustwide/workdir/ledgersim)
[INFO] [stderr]    Compiling ledgerd v0.1.0 (/opt/rustwide/workdir/ledgerd)
[INFO] [stderr]    Compiling ledger-service v0.1.0 (/opt/rustwide/workdir/service)
[INFO] [stderr]    Compiling ledger-pending v0.1.0 (/opt/rustwide/workdir/pending)
[INFO] [stderr]    Compiling ledger-raft v0.1.0 (/opt/rustwide/workdir/raft)
[INFO] [stderr]    Compiling ledger-stubkit v0.1.0 (/opt/rustwide/workdir/stubkit)
[INFO] [stderr]    Compiling ledger-benchkit v0.1.0 (/opt/rustwide/workdir/benchkit)
[INFO] [stderr] warning: unused dependency `windows-sys`
[INFO] [stderr]   --> base/Cargo.toml:22:1
[INFO] [stderr]    |
[INFO] [stderr] 22 | windows-sys = { version = "0.52", features = ["Win32_Foundation", "Win32_System_Threading"], default-features = false }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `windows-sys`
[INFO] [stderr] warning: `ledger-base` (manifest) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 16.95s
[INFO] running `Command { std: "docker" "inspect" "969e6e0d536c80f368ad16ebed7dd8c0346f22af5efa43f37f0aa336f4c2a74c", 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" "969e6e0d536c80f368ad16ebed7dd8c0346f22af5efa43f37f0aa336f4c2a74c" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] warning: unused dependency `windows-sys`
[INFO] [stderr]   --> base/Cargo.toml:22:1
[INFO] [stderr]    |
[INFO] [stderr] 22 | windows-sys = { version = "0.52", features = ["Win32_Foundation", "Win32_System_Threading"], default-features = false }
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `windows-sys`
[INFO] [stderr] warning: `ledger-base` (manifest) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.12s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/ledger-account/b86b387ea0b8592c/out/ledger_account-b86b387ea0b8592c)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test store::tests::an_effect_that_cannot_land_on_both_sides_lands_on_neither ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.86s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/ledger-base/6b2f6de3e61eabf5/out/ledger_base-6b2f6de3e61eabf5)
[INFO] [stdout] running 19 tests
[INFO] [stdout] test footprint::tests::a_buffer_is_priced_by_its_capacity ... ok
[INFO] [stdout] test footprint::tests::a_peak_remembers_the_largest_it_was_shown ... ok
[INFO] [stdout] test footprint::tests::a_hash_table_is_priced_by_its_buckets_and_says_it_is_approximate ... ok
[INFO] [stdout] test affinity::tests::a_placement_is_always_one_of_the_named_ones ... ok
[INFO] [stdout] test hash::tests::a_wide_key_is_mixed_in_full ... ok
[INFO] [stdout] test sizing::tests::a_bucket_costs_its_pair_and_a_control_byte ... ok
[INFO] [stdout] test layout::tests::a_declared_size_that_no_longer_matches_is_caught ... ok
[INFO] [stdout] test prng::tests::floats_stay_in_the_unit_interval ... ok
[INFO] [stdout] test spsc::tests::a_batch_takes_what_fits_and_reports_it ... ok
[INFO] [stdout] test layout::tests::whole_lines_means_whole_lines_on_the_selected_target ... ok
[INFO] [stdout] test layout::tests::a_fit_holds_only_when_it_holds_on_every_target_line_size ... ok
[INFO] [stdout] test sizing::tests::a_list_with_two_parts_of_one_name_is_refused ... ok
[INFO] [stdout] test sizing::tests::a_table_rounds_its_buckets_up_to_a_power_of_two ... ok
[INFO] [stdout] test hash::tests::the_map_keeps_every_key_and_orders_itself_the_same_way_every_time ... ok
[INFO] [stdout] test spsc::tests::dropping_the_ring_drops_what_is_still_in_it ... ok
[INFO] [stdout] test signals::tests::a_terminate_signal_asks_the_process_to_stop ... ok
[INFO] [stdout] test spsc::tests::a_full_ring_refuses_and_wraps_in_order ... ok
[INFO] [stdout] test prng::tests::an_exponential_draw_has_the_mean_it_was_asked_for_and_a_tail ... ok
[INFO] [stdout] test spsc::tests::a_producer_thread_and_a_consumer_thread_agree_on_every_value ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/ledger-benchkit/a0647783fc1cee9f/out/ledger_benchkit-a0647783fc1cee9f)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.14s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/ledger-idempotency/bcb33cf6ba8d2c51/out/ledger_idempotency-bcb33cf6ba8d2c51)
[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] [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 unittests src/lib.rs (/opt/rustwide/target/debug/build/ledger-pending/1beee5839e913a00/out/ledger_pending-1beee5839e913a00)
[INFO] [stdout] 
[INFO] [stdout] running 100 tests
[INFO] [stdout] test block::tests::an_address_carries_its_three_parts ... ok
[INFO] [stdout] test block::tests::a_record_does_not_carry_its_group_totals ... ok
[INFO] [stdout] test block::tests::a_record_survives_the_round_trip_field_for_field ... ok
[INFO] [stdout] test cache::tests::a_block_read_twice_reaches_the_store_once ... ok
[INFO] [stdout] test cache::tests::a_read_of_another_block_is_its_own ... ok
[INFO] [stdout] test cache::tests::reads_for_a_block_already_on_its_way_join_it ... ok
[INFO] [stdout] test cache::tests::a_cache_of_no_blocks_is_no_cache ... ok
[INFO] [stdout] test cache::tests::an_object_written_again_drops_what_was_held_for_it ... ok
[INFO] [stdout] test block::tests::every_generation_of_every_day_names_a_file_of_its_own ... ok
[INFO] [stdout] test device::tests::a_modelled_read_waits_for_the_later_of_the_two_times ... ok
[INFO] [stdout] test block::tests::an_address_names_its_own_file ... ok
[INFO] [stdout] test engine::apply_tests::a_decision_without_the_record_falls_back_to_reading_it ... ok
[INFO] [stdout] test engine::apply_tests::a_written_record_is_still_answered_from_memory_until_residency_ends ... ok
[INFO] [stdout] test engine::apply_tests::applying_a_decision_never_reads_the_store ... ok
[INFO] [stdout] test engine::buffer_tests::a_repacking_block_does_not_drag_coverage_backwards ... ok
[INFO] [stdout] test engine::buffer_tests::a_repacking_stopped_between_its_read_and_its_stage_has_moved_nothing ... ok
[INFO] [stdout] test engine::buffer_tests::a_superseded_version_is_dropped_without_being_tracked ... ok
[INFO] [stdout] test engine::expiry_tests::a_days_blocks_go_back_once_nothing_points_into_it ... ok
[INFO] [stdout] test engine::expiry_tests::a_days_holds_are_released_a_bounded_number_at_a_time ... ok
[INFO] [stdout] test engine::expiry_tests::a_new_day_is_a_new_segment_and_records_stay_findable_across_it ... ok
[INFO] [stdout] test engine::buffer_tests::a_repacked_days_own_file_goes_and_its_holds_still_answer ... ok
[INFO] [stdout] test engine::buffer_tests::a_day_repacked_at_an_age_loses_its_first_repackings_file_too ... ok
[INFO] [stdout] test engine::expiry_tests::a_freed_day_leaves_no_slot_behind_so_the_index_needs_no_epoch ... ok
[INFO] [stdout] test engine::buffer_tests::a_hold_resolved_before_its_block_is_compacted_never_reaches_the_store ... ok
[INFO] [stdout] test engine::expiry_tests::a_day_with_a_hold_left_is_never_finished_or_skipped ... ok
[INFO] [stdout] test engine::expiry_tests::a_void_nobody_took_is_offered_again ... ok
[INFO] [stdout] test engine::expiry_tests::an_outlived_hold_is_offered_as_an_expiry_void_with_a_derived_id ... ok
[INFO] [stdout] test engine::expiry_tests::a_resolved_hold_is_never_offered ... ok
[INFO] [stdout] test engine::expiry_tests::a_new_leader_resumes_on_the_oldest_day_the_counts_say_is_unfinished ... ok
[INFO] [stdout] test engine::buffer_tests::a_survivor_is_carried_on_and_still_found_at_its_new_address ... ok
[INFO] [stdout] test engine::expiry_tests::nothing_is_released_before_the_promise_and_its_grace_have_passed ... ok
[INFO] [stdout] test engine::tests::a_group_survives_its_records_spilling_into_later_blocks ... ok
[INFO] [stdout] test engine::fetch_tests::a_buffered_hold_is_answered_without_a_fetch ... ok
[INFO] [stdout] test engine::fetch_tests::a_hold_that_was_never_created_is_answered_absent ... ok
[INFO] [stdout] test engine::fetch_tests::a_cold_hold_is_fetched_and_answered_when_it_completes ... ok
[INFO] [stdout] test engine::buffer_tests::the_walk_at_an_age_takes_one_block_per_interval ... ok
[INFO] [stdout] test engine::tests::a_hold_the_index_cannot_take_is_named_back_to_the_caller ... ok
[INFO] [stdout] test files::tests::a_freed_segment_leaves_no_file_behind ... ok
[INFO] [stdout] test engine::expiry_tests::the_calendar_stops_before_two_live_days_share_a_segment ... ok
[INFO] [stdout] test files::tests::a_segment_file_left_behind_is_refused_rather_than_written_over ... ok
[INFO] [stdout] test files::tests::every_days_file_begins_at_its_own_block_zero ... ok
[INFO] [stdout] test index::tests::a_hold_comes_back_by_its_own_key_and_a_repoint_moves_it ... ok
[INFO] [stdout] test engine::tests::a_partial_resolution_appends_a_version_rather_than_rewriting_one ... ok
[INFO] [stdout] test log::tests::a_block_that_came_back_changed_is_refused_rather_than_decoded ... ok
[INFO] [stdout] test log::tests::a_block_whose_write_is_outstanding_does_not_leave_memory ... ok
[INFO] [stdout] test log::tests::a_write_answered_out_of_order_does_not_shift_the_blocks_beside_it ... ok
[INFO] [stdout] test files::tests::two_directories_declared_one_volume_write_to_both_and_share_one_queue ... ok
[INFO] [stdout] test files::tests::a_write_lane_keeps_the_order_writes_need_and_the_records_come_back ... ok
[INFO] [stdout] test memory::tests::a_memory_budget_is_answered_with_the_largest_rate_that_fits ... ok
[INFO] [stdout] test memory::tests::an_index_budget_is_met_by_what_the_table_takes_not_by_what_was_asked_for ... ok
[INFO] [stdout] test memory::tests::every_size_follows_the_lifetime_the_grace_extends ... ok
[INFO] [stdout] test memory::tests::no_grace_at_all_is_refused ... ok
[INFO] [stdout] test memory::tests::the_dials_come_out_of_the_budget_before_the_windows_do ... ok
[INFO] [stdout] test log::tests::records_come_back_from_open_and_sealed_blocks_alike ... ok
[INFO] [stdout] test memory::tests::a_lifetime_longer_than_the_segments_available_is_refused ... ok
[INFO] [stdout] test files::tests::a_read_pool_answers_the_same_records_and_not_in_the_order_asked ... ok
[INFO] [stdout] test files::tests::a_second_store_over_the_same_directory_reads_what_the_first_wrote ... ok
[INFO] [stdout] test memory::worker_tests::a_day_that_runs_out_arrives_as_notices_on_the_port ... ok
[INFO] [stdout] test orderer::tests::a_reply_waits_for_its_own_work_as_well_as_its_turn ... ok
[INFO] [stdout] test files::tests::every_record_comes_back_from_the_files_it_was_written_to ... ok
[INFO] [stdout] test orderer::tests::a_run_begins_at_whatever_seq_arrives_after_the_lane_falls_quiet ... ok
[INFO] [stdout] test orderer::tests::the_fault_releases_a_reply_out_of_its_turn ... ok
[INFO] [stdout] test orderer::tests::unordered_replies_leave_without_waiting_and_do_not_overwrite_each_other ... ok
[INFO] [stdout] test orderer::tests::one_lane_waiting_does_not_hold_another_up ... ok
[INFO] [stdout] test orderer::tests::work_that_finishes_out_of_order_leaves_in_the_order_it_arrived ... ok
[INFO] [stdout] test overlay::tests::a_decision_outranks_an_answer_that_was_already_in_flight ... ok
[INFO] [stdout] test overlay::tests::a_removal_is_remembered_until_the_engine_has_applied_it ... ok
[INFO] [stdout] test overlay::tests::a_removal_with_no_pin_is_remembered_too ... ok
[INFO] [stdout] test snapshot::tests::a_create_arriving_again_repoints_rather_than_inserting_twice ... ok
[INFO] [stdout] test snapshot::tests::a_hold_still_in_the_buffer_is_not_carried ... ok
[INFO] [stdout] test memory::worker_tests::a_snapshot_reaches_the_directory_and_its_numbers_reach_the_port ... ok
[INFO] [stdout] test memory::worker_tests::blocks_with_no_snapshot_to_name_them_are_refused_rather_than_deleted ... ok
[INFO] [stdout] test snapshot::tests::a_days_blocks_come_back_from_the_volume_and_not_from_its_slots ... ok
[INFO] [stdout] test snapshot::tests::a_restore_takes_its_day_from_the_stream_rather_than_from_a_clock ... ok
[INFO] [stdout] test snapshot::tests::a_snapshot_reads_the_table_it_began_with_while_the_engine_writes ... ok
[INFO] [stdout] test snapshot::tests::bytes_that_are_not_a_snapshot_are_refused ... ok
[INFO] [stdout] test snapshot::tests::a_table_of_a_different_size_refuses_the_stream ... ok
[INFO] [stdout] test snapshot::tests::coverage_stops_where_the_buffer_begins_and_moves_as_it_flushes ... ok
[INFO] [stdout] test memory::worker_tests::a_snapshot_directory_on_the_blocks_volume_shares_their_store ... ok
[INFO] [stdout] test snapshot::tests::a_restored_engine_answers_every_carried_hold_the_same ... ok
[INFO] [stdout] test snapshots::tests::a_current_object_that_is_not_a_snapshot_is_refused ... ok
[INFO] [stdout] test snapshot::tests::restore_and_replay_reproduce_the_engine ... ok
[INFO] [stdout] test snapshots::tests::a_declared_volume_carries_the_blocks_and_the_snapshot_on_one_store ... ok
[INFO] [stdout] test snapshot::tests::a_sealed_block_is_carried_only_once_a_sync_has_covered_it ... ok
[INFO] [stdout] test snapshots::tests::a_dump_holds_only_its_share_of_the_volume ... ok
[INFO] [stdout] test snapshots::tests::a_dump_is_given_up_on_when_the_store_breaks_under_it ... ok
[INFO] [stdout] test snapshots::tests::a_dump_publishes_nothing_until_its_writes_are_answered_for ... ok
[INFO] [stdout] test snapshots::tests::a_dump_that_never_finished_is_not_the_current_snapshot ... ok
[INFO] [stdout] test snapshots::tests::a_restore_frees_the_days_nothing_points_into_and_keeps_the_rest ... ok
[INFO] [stdout] test snapshots::tests::a_shadow_past_its_budget_ends_the_dump_rather_than_growing ... ok
[INFO] [stdout] test snapshots::tests::a_node_stopped_between_the_write_and_the_barrier_keeps_every_hold ... ok
[INFO] [stdout] test snapshots::tests::a_node_stopped_between_the_barrier_and_the_repoint_keeps_every_hold ... ok
[INFO] [stdout] test snapshots::tests::a_node_stopped_between_the_repoint_and_the_release_keeps_every_hold ... ok
[INFO] [stdout] test index::tests::a_shared_fingerprint_is_detected_and_disambiguated ... ok
[INFO] [stdout] test snapshots::tests::a_volume_with_no_snapshot_on_it_is_not_a_broken_one ... ok
[INFO] [stdout] test snapshots::tests::a_snapshot_written_to_a_volume_restores_into_another_engine ... ok
[INFO] [stdout] test index::tests::a_table_filled_to_its_target_keeps_every_key ... ok
[INFO] [stdout] test snapshots::tests::a_restored_engine_writes_past_the_blocks_the_last_one_left ... ok
[INFO] [stdout] test snapshots::tests::the_cadence_follows_the_log_and_not_the_rounds ... ok
[INFO] [stdout] test memory::worker_tests::a_node_started_on_a_volume_it_has_written_comes_back_with_its_holds ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- memory::worker_tests::a_node_started_on_a_volume_it_has_written_comes_back_with_its_holds stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'memory::worker_tests::a_node_started_on_a_volume_it_has_written_comes_back_with_its_holds' (1213) panicked at pending/src/memory.rs:2088:13:
[INFO] [stdout] the first life published no snapshot after its records were durable (SnapshotStats { written: 0, abandoned: 0, bytes: 20480, shadow_peak: 386, last_rounds: 0, covered: 0 })
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x58914693bcc1 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x58914693bcc1 - std[617be2ca0a6fd877]::backtrace_rs::backtrace::trace_unsynchronized::<std[617be2ca0a6fd877]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x58914693bcc1 - std[617be2ca0a6fd877]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x58914693bcc1 - <<std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[a373ad73a504971]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x589146950f6a - <core[a373ad73a504971]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x589146950f6a - core[a373ad73a504971]::fmt::write
[INFO] [stdout]    6:     0x5891469401ac - core[a373ad73a504971]::io::write::default_write_fmt::<alloc[2e1b2a31b91974c5]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:402:11
[INFO] [stdout]    7:     0x5891469401ac - <alloc[2e1b2a31b91974c5]::vec::Vec<u8> as core[a373ad73a504971]::io::write::Write>::write_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/io/write.rs:335:13
[INFO] [stdout]    8:     0x589146916386 - <std[617be2ca0a6fd877]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x589146916386 - std[617be2ca0a6fd877]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:289:27
[INFO] [stdout]   10:     0x5891469332c9 - std[617be2ca0a6fd877]::panicking::default_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:313:9
[INFO] [stdout]   11:     0x5891468c8500 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   12:     0x5891468c8500 - test[aada8e7e76ba119b]::test_main_inner::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:137:21
[INFO] [stdout]   13:     0x589146933482 - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn for<'a, 'b> core[a373ad73a504971]::ops::function::Fn<(&'a std[617be2ca0a6fd877]::panic::PanicHookInfo<'b>,), Output = ()> + core[a373ad73a504971]::marker::Sync + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::Fn<(&std[617be2ca0a6fd877]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2328:9
[INFO] [stdout]   14:     0x589146933482 - std[617be2ca0a6fd877]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:819:13
[INFO] [stdout]   15:     0x589146916432 - std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:685:13
[INFO] [stdout]   16:     0x58914690ef29 - std[617be2ca0a6fd877]::sys::backtrace::__rust_end_short_backtrace::<std[617be2ca0a6fd877]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x58914691712d - __rustc[ea4f61d10b84890b]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:676:5
[INFO] [stdout]   18:     0x58914695178c - core[a373ad73a504971]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x58914682cabd - ledger_pending[22f3d1a4d41f0011]::memory::worker_tests::a_node_started_on_a_volume_it_has_written_comes_back_with_its_holds
[INFO] [stdout]                                at /opt/rustwide/workdir/pending/src/memory.rs:2088:13
[INFO] [stdout]   20:     0x5891468229b7 - ledger_pending[22f3d1a4d41f0011]::memory::worker_tests::a_node_started_on_a_volume_it_has_written_comes_back_with_its_holds::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/pending/src/memory.rs:2064:77
[INFO] [stdout]   21:     0x58914683c806 - <ledger_pending[22f3d1a4d41f0011]::memory::worker_tests::a_node_started_on_a_volume_it_has_written_comes_back_with_its_holds::{closure#0} as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   22:     0x5891468bb94b - <fn() -> core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   23:     0x5891468bb94b - 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]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:701:18
[INFO] [stdout]   24:     0x5891468c8b25 - test[aada8e7e76ba119b]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:74
[INFO] [stdout]   25:     0x5891468c8b25 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   26:     0x5891468c8b25 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   27:     0x5891468c8b25 - std[617be2ca0a6fd877]::panicking::catch_unwind::<core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>, core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   28:     0x5891468c8b25 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<test[aada8e7e76ba119b]::run_test_in_process::{closure#0}>, core[a373ad73a504971]::result::Result<(), alloc[2e1b2a31b91974c5]::string::String>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   29:     0x5891468c8b25 - test[aada8e7e76ba119b]::run_test_in_process
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:724:27
[INFO] [stdout]   30:     0x5891468c8b25 - test[aada8e7e76ba119b]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:645:43
[INFO] [stdout]   31:     0x5891468c2414 - test[aada8e7e76ba119b]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/test/src/lib.rs:675:41
[INFO] [stdout]   32:     0x5891468c2414 - std[617be2ca0a6fd877]::sys::backtrace::__rust_begin_short_backtrace::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   33:     0x5891468cbbb2 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   34:     0x5891468cbbb2 - <core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   35:     0x5891468cbbb2 - std[617be2ca0a6fd877]::panicking::catch_unwind::do_call::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:573:43
[INFO] [stdout]   36:     0x5891468cbbb2 - std[617be2ca0a6fd877]::panicking::catch_unwind::<(), core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panicking.rs:541:19
[INFO] [stdout]   37:     0x5891468cbbb2 - std[617be2ca0a6fd877]::panic::catch_unwind::<core[a373ad73a504971]::panic::unwind_safe::AssertUnwindSafe<std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/panic.rs:359:14
[INFO] [stdout]   38:     0x5891468cbbb2 - std[617be2ca0a6fd877]::thread::lifecycle::spawn_unchecked::<test[aada8e7e76ba119b]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   39:     0x5891468cbbb2 - <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]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x58914693b22f - <alloc[2e1b2a31b91974c5]::boxed::Box<dyn core[a373ad73a504971]::ops::function::FnOnce<(), Output = ()> + core[a373ad73a504971]::marker::Send> as core[a373ad73a504971]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/alloc/src/boxed.rs:2314:9
[INFO] [stdout]   41:     0x58914693b22f - <std[617be2ca0a6fd877]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/e3feeb59cd1bdd011a2bfcc0747b55125d9b8527/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   42:     0x7b3e6883fdfa - <unknown>
[INFO] [stdout]   43:     0x7b3e688d33d4 - clone
[INFO] [stdout]   44:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     memory::worker_tests::a_node_started_on_a_volume_it_has_written_comes_back_with_its_holds
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 99 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.54s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `-p ledger-pending --lib`
[INFO] running `Command { std: "docker" "inspect" "969e6e0d536c80f368ad16ebed7dd8c0346f22af5efa43f37f0aa336f4c2a74c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "969e6e0d536c80f368ad16ebed7dd8c0346f22af5efa43f37f0aa336f4c2a74c", kill_on_drop: false }`
[INFO] [stdout] 969e6e0d536c80f368ad16ebed7dd8c0346f22af5efa43f37f0aa336f4c2a74c
