[INFO] cloning repository https://github.com/ethanniser/keermat-kv
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/ethanniser/keermat-kv" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fethanniser%2Fkeermat-kv", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fethanniser%2Fkeermat-kv'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] bd65e474aa37cf5cbda40fd17b812c5282003f2a
[INFO] testing ethanniser/keermat-kv against 1.99.0-beta.8 for beta-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fethanniser%2Fkeermat-kv" "/workspace/builds/worker-1-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc1/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-1-tc1/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/ethanniser/keermat-kv
[INFO] finished tweaking git repo https://github.com/ethanniser/keermat-kv
[INFO] tweaked toml for git repo https://github.com/ethanniser/keermat-kv written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/ethanniser/keermat-kv 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 git repo https://github.com/ethanniser/keermat-kv 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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 837fb9b31a2fb965f9c081cd09226f5db5d5c8fa8ce2a7813095710581d8aab5
[INFO] running `Command { std: "docker" "start" "837fb9b31a2fb965f9c081cd09226f5db5d5c8fa8ce2a7813095710581d8aab5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "837fb9b31a2fb965f9c081cd09226f5db5d5c8fa8ce2a7813095710581d8aab5", 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" "837fb9b31a2fb965f9c081cd09226f5db5d5c8fa8ce2a7813095710581d8aab5" "/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" "837fb9b31a2fb965f9c081cd09226f5db5d5c8fa8ce2a7813095710581d8aab5", 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" "837fb9b31a2fb965f9c081cd09226f5db5d5c8fa8ce2a7813095710581d8aab5" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling libc v0.2.186
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling wasm-bindgen-shared v0.2.126
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling quote v1.0.46
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling rustversion v1.0.23
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling bumpalo v3.20.3
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling regex-syntax v0.8.11
[INFO] [stderr]    Compiling log v0.4.33
[INFO] [stderr]    Compiling winnow v1.0.3
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling toml_writer v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling wasm-bindgen v0.2.126
[INFO] [stderr]    Compiling toml_parser v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling syn v2.0.118
[INFO] [stderr]    Compiling regex-automata v0.4.15
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling socket2 v0.6.4
[INFO] [stderr]    Compiling mio v1.2.1
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling toml_datetime v1.1.1+spec-1.1.0
[INFO] [stderr]    Compiling serde_spanned v1.1.1
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling toml v1.1.2+spec-1.1.0
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.6
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling wasm-bindgen-macro-support v0.2.126
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio v1.52.3
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling keermat-core v0.1.0 (/opt/rustwide/workdir/crates/core)
[INFO] [stderr]    Compiling bincode v1.3.3
[INFO] [stderr]    Compiling wasm-bindgen-macro v0.2.126
[INFO] [stderr]    Compiling keermat-sim v0.1.0 (/opt/rustwide/workdir/crates/sim)
[INFO] [stderr]    Compiling console_error_panic_hook v0.1.7
[INFO] [stderr]    Compiling keermat-wasm v0.1.0 (/opt/rustwide/workdir/crates/wasm)
[INFO] [stderr]    Compiling keermat-client v0.1.0 (/opt/rustwide/workdir/crates/client)
[INFO] [stderr]    Compiling keermat-node v0.1.0 (/opt/rustwide/workdir/crates/node)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 42.35s
[INFO] running `Command { std: "docker" "inspect" "837fb9b31a2fb965f9c081cd09226f5db5d5c8fa8ce2a7813095710581d8aab5", 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" "837fb9b31a2fb965f9c081cd09226f5db5d5c8fa8ce2a7813095710581d8aab5" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling keermat-sim v0.1.0 (/opt/rustwide/workdir/crates/sim)
[INFO] [stderr]    Compiling keermat-core v0.1.0 (/opt/rustwide/workdir/crates/core)
[INFO] [stderr]    Compiling keermat-wasm v0.1.0 (/opt/rustwide/workdir/crates/wasm)
[INFO] [stderr]    Compiling keermat-client v0.1.0 (/opt/rustwide/workdir/crates/client)
[INFO] [stderr]    Compiling keermat-node v0.1.0 (/opt/rustwide/workdir/crates/node)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 9.92s
[INFO] running `Command { std: "docker" "inspect" "837fb9b31a2fb965f9c081cd09226f5db5d5c8fa8ce2a7813095710581d8aab5", 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" "837fb9b31a2fb965f9c081cd09226f5db5d5c8fa8ce2a7813095710581d8aab5" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.15s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/keermat_client-2bcbcf9e7d8f90bd)
[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/bin/keermat-cli.rs (/opt/rustwide/target/debug/deps/keermat_cli-5a37b6fc696e022f)
[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/deps/keermat_core-dc0f61545ccc93c3)
[INFO] [stdout] 
[INFO] [stdout] running 79 tests
[INFO] [stdout] test config::tests::leader_rotation_round_robins_by_epoch ... ok
[INFO] [stdout] test config::tests::validate_accepts_even_n ... ok
[INFO] [stdout] test config::tests::validate_rejects_zero_epoch_len ... ok
[INFO] [stdout] test config::tests::validate_accepts_a_well_formed_config ... ok
[INFO] [stdout] test config::tests::validate_accepts_zero_hedge_delay ... ok
[INFO] [stdout] test config::tests::leader_rotation_single_node_is_always_zero ... ok
[INFO] [stdout] test config::tests::validate_error_messages_name_the_field_and_value ... ok
[INFO] [stdout] test config::tests::leader_rotation_with_small_epoch_len ... ok
[INFO] [stdout] test config::tests::quorum_math ... ok
[INFO] [stdout] test config::tests::validate_rejects_zero_max_batch ... ok
[INFO] [stdout] test effect::tests::timer_id_ordering_is_deterministic ... ok
[INFO] [stdout] test config::tests::validate_rejects_zero_n ... ok
[INFO] [stdout] test config::tests::validate_rejects_zero_resend_ns ... ok
[INFO] [stdout] test config::tests::validate_rejects_zero_sync_ns ... ok
[INFO] [stdout] test effect::tests::persist_then_nests_effects ... ok
[INFO] [stdout] test isr::tests::first_call_at_a_step_above_initial_sets_fc_ac_and_no_ap ... ok
[INFO] [stdout] test proposer::tests::catch_up_ignores_req_step_and_fires_on_any_ahead_reply ... ok
[INFO] [stdout] test msg::tests::node_id_serde_round_trip ... ok
[INFO] [stdout] test proposer::tests::fast_path_decides_when_all_firsts_are_identical_h ... ok
[INFO] [stdout] test proposer::tests::catch_up_to_a_step_at_the_threshold_poisons_instead_of_adopting ... ok
[INFO] [stdout] test isr::tests::first_value_never_overwritten_within_a_step ... ok
[INFO] [stdout] test isr::tests::initial_step_zero_edge_case_leaves_fc_none ... ok
[INFO] [stdout] test msg::tests::msg_serde_round_trip_covers_every_variant ... ok
[INFO] [stdout] test proposer::tests::leader_round1_phase0_sends_identical_h_to_all_recorders ... ok
[INFO] [stdout] test proposer::tests::catch_up_adopts_step_and_first_and_rebroadcasts ... ok
[INFO] [stdout] test proposer::tests::nonleader_round1_phase0_draws_per_recorder_priorities ... ok
[INFO] [stdout] test proposer::tests::phase1_no_action_just_advances_and_spreads_p ... ok
[INFO] [stdout] test proposer::tests::phase2_decides_when_p_equals_max_agg_prev ... ok
[INFO] [stdout] test isr::tests::skip_ahead_zeroes_ap ... ok
[INFO] [stdout] test proposer::tests::phase2_no_decide_then_phase3_carries_max_agg_prev ... ok
[INFO] [stdout] test proposer::tests::reply_with_stale_recorder_step_is_not_counted ... ok
[INFO] [stdout] test proposer::tests::fast_path_refused_when_one_first_differs_restarted_leader_duplicate_h ... ok
[INFO] [stdout] test proposer::tests::duplicate_recorder_reply_counts_once ... ok
[INFO] [stdout] test recorder::tests::from_recovered_preserves_decided_slots ... ok
[INFO] [stdout] test recorder::tests::mark_decided_is_idempotent_first_write_wins ... ok
[INFO] [stdout] test isr::tests::same_step_aggregates_max_regardless_of_arrival_order ... ok
[INFO] [stdout] test proposer::tests::reply_with_wrong_req_step_is_not_counted ... ok
[INFO] [stdout] test proposer::tests::suppressed_leader_round1_phase0_uses_random_priority_not_h ... ok
[INFO] [stdout] test recorder::tests::mark_decided_prunes_the_slots_isr ... ok
[INFO] [stdout] test proposer::tests::ignores_replies_after_decision ... ok
[INFO] [stdout] test isr::tests::stale_step_leaves_state_untouched_but_replies_current ... ok
[INFO] [stdout] test isr::tests::step_advance_by_one_preserves_ac_as_ap ... ok
[INFO] [stdout] test rng::tests::priority_draws_different_rounds_get_independent_draws ... ok
[INFO] [stdout] test recorder::tests::persistence_payload_round_trips_through_recovered_state ... ok
[INFO] [stdout] test proposer::tests::resend_reuses_cached_phase0_priorities_byte_identical ... ok
[INFO] [stdout] test proposer::tests::step_overflow_guard_halts_instead_of_wrapping ... ok
[INFO] [stdout] test recorder::tests::from_recovered_drops_isrs_of_decided_slots ... ok
[INFO] [stdout] test rng::tests::accepts_h_minus_one_the_range_is_1_to_h_minus_1_inclusive ... ok
[INFO] [stdout] test recorder::tests::already_decided_short_circuits_without_touching_isr ... ok
[INFO] [stdout] test rng::tests::priority_draws_different_recorders_get_independent_draws ... ok
[INFO] [stdout] test rng::tests::priority_draws_rejection_still_applies_through_get ... ok
[INFO] [stdout] test rng::tests::priority_draws_same_key_returns_same_value_without_redrawing ... ok
[INFO] [stdout] test rng::tests::rejects_nil_then_h_then_returns_first_valid_value ... ok
[INFO] [stdout] test smr::tests::stale_seq_is_dropped_no_reply_no_execute ... ok
[INFO] [stdout] test smr::tests::cas_success_and_failure ... ok
[INFO] [stdout] test recorder::tests::on_record_creates_and_reuses_isr_per_slot ... ok
[INFO] [stdout] test storage::tests::decode_isr_rejects_garbage ... ok
[INFO] [stdout] test smr::tests::put_get_delete_round_trip ... ok
[INFO] [stdout] test smr::tests::exact_retry_returns_cached_reply_without_reexecuting ... ok
[INFO] [stdout] test storage::tests::recovered_state_from_kv_collects_epoch_markers ... ok
[INFO] [stdout] test smr::tests::sessions_are_per_client_independent ... ok
[INFO] [stdout] test storage::tests::recovered_state_from_kv_empty_is_empty ... ok
[INFO] [stdout] test types::tests::fnv1a64_known_vectors_pin_the_algorithm ... ok
[INFO] [stdout] test storage::tests::key_formatting ... ok
[INFO] [stdout] test storage::tests::isr_encode_decode_round_trip ... ok
[INFO] [stdout] test types::tests::h_and_nil_constants ... ok
[INFO] [stdout] test types::tests::priority_dominates_proposer ... ok
[INFO] [stdout] test types::tests::different_values_under_same_priority_proposer_are_not_equal ... ok
[INFO] [stdout] test types::tests::proposer_breaks_ties_on_equal_priority ... ok
[INFO] [stdout] test types::tests::same_priority_proposer_and_value_are_equal ... ok
[INFO] [stdout] test types::tests::value_bytes_are_excluded_beyond_the_hash ... ok
[INFO] [stdout] test storage::tests::decode_value_round_trip ... ok
[INFO] [stdout] test types::tests::value_hash_matches_manual_fnv_of_canonical_encoding ... ok
[INFO] [stdout] test types::tests::max_picks_by_order_key_and_keeps_that_sides_value ... ok
[INFO] [stdout] test storage::tests::recovered_state_from_kv_ignores_unknown_and_applied_upto_keys ... ok
[INFO] [stdout] test rng::tests::stays_within_bounds_across_many_deterministic_draws ... ok
[INFO] [stdout] test types::tests::proposal_serde_round_trip ... ok
[INFO] [stdout] test storage::tests::recovered_state_from_kv_panics_on_corrupt_recognized_key - should panic ... ok
[INFO] [stdout] test isr::tests::property_random_sequences_preserve_isr_invariants ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 79 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cluster.rs (/opt/rustwide/target/debug/deps/cluster-130d363c1819271f)
[INFO] [stdout] 
[INFO] [stdout] running 24 tests
[INFO] [stdout] test repair_continues_while_behind_then_exits_when_caught_up ... ok
[INFO] [stdout] test duplicate_client_request_is_exactly_once ... ok
[INFO] [stdout] test repair_entry_is_idempotent_no_second_chain ... ok
[INFO] [stdout] test epoch_marker_persisted_once_per_epoch_not_per_slot ... ok
[INFO] [stdout] test healthy_leader_decides_in_one_round_trip_fast_path ... ok
[INFO] [stdout] test epoch_marker_persist_completing_marks_durable_and_skips_gating_later ... ok
[INFO] [stdout] test message_drop_then_resend_recovers ... ok
[INFO] [stdout] test recovery_in_marked_epoch_forfeits_h_uses_random_priority ... ok
[INFO] [stdout] test happy_path_put_then_linearizable_get_n3_fast_path ... ok
[INFO] [stdout] test recovery_in_unmarked_epoch_still_uses_h ... ok
[INFO] [stdout] test gap_fill_commit_out_of_order_drives_missing_slot ... ok
[INFO] [stdout] test sync_request_from_ahead_peer_is_behind_evidence_for_responder ... ok
[INFO] [stdout] test leader_silent_hedged_proposer_drives_slow_path ... ok
[INFO] [stdout] test repair_enters_on_gap_evidence_and_polls_at_resend_cadence ... ok
[INFO] [stdout] test multi_slot_sequence_converges_and_applies_in_order ... ok
[INFO] [stdout] test restart_from_recovered_state_before_learning_via_sync ... ok
[INFO] [stdout] test epoch_marker_persisted_before_first_h_send_and_gates_it ... ok
[INFO] [stdout] test restart_midprotocol_rejoins_and_converges ... ok
[INFO] [stdout] test epoch_marker_gates_h_by_durability_not_issuance_across_slot_boundary ... ok
[INFO] [stdout] test delta_zero_all_proposers_active_still_safe ... ok
[INFO] [stdout] test cas_success_then_failure_round_trip ... ok
[INFO] [stdout] test two_proposers_race_same_slot_agree ... ok
[INFO] [stdout] test restarted_leader_duplicate_h_racing_still_agrees ... ok
[INFO] [stdout] test sync_request_at_or_below_own_frontier_is_not_behind_evidence ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 24 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/keermat_node-974ca44236d7ccfb)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test wal::tests::empty_wal_replays_empty ... ok
[INFO] [stdout] test timers::tests::first_arm_is_current ... ok
[INFO] [stdout] test timers::tests::unknown_id_or_generation_is_not_current ... ok
[INFO] [stdout] test timers::tests::distinct_timer_ids_are_independent ... ok
[INFO] [stdout] test timers::tests::later_arm_supersedes_earlier_one ... ok
[INFO] [stdout] test wal::tests::write_replay_round_trip ... ok
[INFO] [stdout] test wal::tests::torn_payload_is_discarded_and_file_truncated ... ok
[INFO] [stdout] test wal::tests::truncated_length_prefix_is_discarded_not_a_crash ... ok
[INFO] [stdout] test wal::tests::reopening_preserves_and_extends_log ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/smoke.rs (/opt/rustwide/target/debug/deps/smoke-cd265d3cd0256a72)
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/keermat_sim-0fc431f09e3fb0cf)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test three_node_cluster_survives_a_kill_and_restart ... ignored
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 119 tests
[INFO] [stdout] test checkers::tests::check_agreement_accepts_repeated_identical_decisions ... ok
[INFO] [stdout] test checkers::tests::check_durability_accepts_intact_confirmed_slot ... ok
[INFO] [stdout] test checkers::tests::apply_envelope_dedups_by_request_id ... ok
[INFO] [stdout] test checkers::tests::check_no_stuck_slot_accepts_decided_or_recent_slots ... ok
[INFO] [stdout] test checkers::tests::check_prefix_consistency_accepts_correct_fold ... ok
[INFO] [stdout] test checkers::tests::convergence_outcome_reports_converged_without_panicking ... ok
[INFO] [stdout] test checkers::tests::check_stale_read_accepts_value_matching_prefix ... ok
[INFO] [stdout] test checkers::tests::observations_mark_confirmed_then_durability_check_passes ... ok
[INFO] [stdout] test checkers::tests::observations_tracks_agreement_and_prefix_from_persisted_batches ... ok
[INFO] [stdout] test checkers::tests::check_exactly_once_accepts_distinct_request_ids ... ok
[INFO] [stdout] test checkers::tests::check_convergence_accepts_identical_snapshots ... ok
[INFO] [stdout] test disk::tests::complete_on_unknown_token_returns_none ... ok
[INFO] [stdout] test disk::tests::crash_never_affects_other_nodes_inflight_persists ... ok
[INFO] [stdout] test disk::tests::crash_with_full_torn_probability_always_commits_some_prefix ... ok
[INFO] [stdout] test disk::tests::crash_with_zero_torn_probability_always_fully_loses_inflight ... ok
[INFO] [stdout] test disk::tests::kv_snapshot_persists_across_crash_once_committed ... ok
[INFO] [stdout] test disk::tests::crash_with_no_inflight_persists_is_a_no_op ... ok
[INFO] [stdout] test disk::tests::submit_then_complete_commits_batch_and_returns_then ... ok
[INFO] [stdout] test explore::tests::different_seeds_usually_derive_different_params ... ok
[INFO] [stdout] test explore::tests::fault_config_max_concurrent_crashes_tracks_drawn_n_not_a_constant ... ok
[INFO] [stdout] test explore::tests::grace_ns_is_a_pure_function_of_seed ... ok
[INFO] [stdout] test explore::tests::derive_is_a_pure_function_of_seed ... ok
[INFO] [stdout] test explore::tests::clients_is_always_in_range ... ok
[INFO] [stdout] test explore::tests::hedge_zero_is_reachable_and_resolves_to_zero_ns ... ok
[INFO] [stdout] test explore::tests::scenario_config_carries_partition_style_and_weights ... ok
[INFO] [stdout] test explore::tests::grace_ns_is_never_smaller_than_the_quiescence_floor_or_32_slot_costs ... ok
[INFO] [stdout] test explore::tests::core_config_is_always_valid ... ok
[INFO] [stdout] test explore::tests::n_is_always_a_valid_odd_replica_count ... ok
[INFO] [stdout] test explore::tests::total_ns_still_matches_quiescence_floor_after_the_refactor ... ok
[INFO] [stdout] test explore::tests::total_ns_leaves_a_quiescence_window_of_several_sync_intervals ... ok
[INFO] [stdout] test history::tests::events_for_key_filters ... ok
[INFO] [stdout] test explore::tests::total_ns_is_never_shorter_than_the_drawn_minutes ... ok
[INFO] [stdout] test history::tests::invoke_then_complete_round_trips ... ok
[INFO] [stdout] test linearize::tests::distinct_keys_do_not_interfere ... ok
[INFO] [stdout] test linearize::tests::duplicate_apply_observed_via_cas ... ok
[INFO] [stdout] test linearize::tests::lost_update_via_cas ... ok
[INFO] [stdout] test linearize::tests::concurrent_cas_one_wins ... ok
[INFO] [stdout] test linearize::tests::overlapping_put_get_reads_new ... ok
[INFO] [stdout] test linearize::tests::overlapping_put_get_reads_old ... ok
[INFO] [stdout] test linearize::tests::pending_cas_both_branches ... ok
[INFO] [stdout] test checkers::tests::check_prefix_consistency_catches_wrong_applied_upto - should panic ... ok
[INFO] [stdout] test linearize::tests::pending_put_observed_by_later_get ... ok
[INFO] [stdout] test linearize::tests::pending_put_omitted_get_reads_absent ... ok
[INFO] [stdout] test linearize::tests::perf_5000_events_8_keys ... ignored, stress: 5000 events over 8 keys; run with --ignored
[INFO] [stdout] test checkers::tests::check_durability_catches_slot_content_swapped - should panic ... ok
[INFO] [stdout] test checkers::tests::check_agreement_catches_two_replicas_deciding_different_values - should panic ... ok
[INFO] [stdout] test checkers::tests::check_durability_catches_vanished_slot - should panic ... ok
[INFO] [stdout] test linearize::tests::value_from_nowhere ... ok
[INFO] [stdout] test checkers::tests::check_no_stuck_slot_catches_slot_stuck_past_bound - should panic ... ok
[INFO] [stdout] test checkers::tests::check_exactly_once_catches_duplicate_request_id_across_slots - should panic ... ok
[INFO] [stdout] test linearize::tests::read_your_writes_violation ... ok
[INFO] [stdout] test checkers::tests::check_prefix_consistency_catches_wrong_kv_state - should panic ... ok
[INFO] [stdout] test linearize::tests::per_key_isolation_reports_small_bad_key ... ok
[INFO] [stdout] test checkers::tests::check_convergence_catches_diverged_replica - should panic ... ok
[INFO] [stdout] test net::tests::core_config_for_preset_scales_with_rtt ... ok
[INFO] [stdout] test net::tests::heal_clears_all_blocks ... ok
[INFO] [stdout] test linearize::tests::stale_read_no_concurrency ... ok
[INFO] [stdout] test net::tests::partition_blocks_delivery_deterministically ... ok
[INFO] [stdout] test checkers::tests::observations_catches_disagreement_across_replicas - should panic ... ok
[INFO] [stdout] test checkers::tests::check_stale_read_catches_value_from_beyond_applied_point - should panic ... ok
[INFO] [stdout] test net::tests::per_link_override_wins_over_default ... ok
[INFO] [stdout] test net::tests::scale_default_link_multiplies_and_caps ... ok
[INFO] [stdout] test net::tests::slow_node_multiplies_outgoing_latency ... ok
[INFO] [stdout] test net::tests::symmetric_partition_blocks_both_directions ... ok
[INFO] [stdout] test net::tests::validate_core_config_accepts_every_explore_n ... ok
[INFO] [stdout] test linearize::tests::windowed_path_detects_violation_in_large_key ... ok
[INFO] [stdout] test checkers::tests::convergence_outcome_reports_diverged_with_the_same_detail_check_convergence_panics_with ... ok
[INFO] [stdout] test linearize::tests::windowed_path_accepts_large_clean_key ... ok
[INFO] [stdout] test net::tests::validate_core_config_accepts_zero_hedge_delay ... ok
[INFO] [stdout] test net::tests::same_seed_produces_identical_roll_sequence ... ok
[INFO] [stdout] test net::tests::typical_rtt_ns_for_preset_matches_core_config_derivation ... ok
[INFO] [stdout] test node::tests::test_node_answers_put_then_get_with_persist_then_send ... ok
[INFO] [stdout] test node::tests::factory_produces_independent_fresh_nodes ... ok
[INFO] [stdout] test node::tests::test_node_dedups_by_request_id ... ok
[INFO] [stdout] test history::tests::complete_unknown_op_id_panics - should panic ... ok
[INFO] [stdout] test node::tests::test_node_ignores_non_client_messages ... ok
[INFO] [stdout] test process::tests::crash_tracker_respects_concurrency_budget ... ok
[INFO] [stdout] test process::tests::draw_restart_delay_full_permanent_probability_always_none ... ok
[INFO] [stdout] test history::tests::double_complete_panics - should panic ... ok
[INFO] [stdout] test process::tests::draw_restart_delay_zero_permanent_probability_never_returns_none ... ok
[INFO] [stdout] test scenario::tests::asymmetric_style_only_blocks_one_direction ... ok
[INFO] [stdout] test scenario::tests::island_style_always_isolates_exactly_one_node ... ok
[INFO] [stdout] test scenario::tests::different_seeds_produce_different_schedules ... ok
[INFO] [stdout] test scenario::tests::pick_event_kind_weighted_favors_higher_weight_index ... ok
[INFO] [stdout] test process::tests::draw_pause_duration_within_bounds ... ok
[INFO] [stdout] test scenario::tests::schedule_always_heals_and_restarts_everyone_before_quiescence ... ok
[INFO] [stdout] test scenario::tests::pick_event_kind_with_uniform_weights_matches_original_unweighted_draw ... ok
[INFO] [stdout] test scenario::tests::schedule_ends_with_quiescence_check_at_total_ns ... ok
[INFO] [stdout] test scenario::tests::schedule_is_deterministic_for_same_seed ... ok
[INFO] [stdout] test scenario::tests::flapping_style_produces_multiple_toggle_events ... ok
[INFO] [stdout] test net::tests::core_config_for_preset_rejects_unknown_name - should panic ... ok
[INFO] [stdout] test net::tests::typical_rtt_ns_for_preset_rejects_unknown_name - should panic ... ok
[INFO] [stdout] test net::tests::validate_core_config_rejects_zero_epoch_len - should panic ... ok
[INFO] [stdout] test sim::tests::crash_then_restart_creates_new_incarnation_and_fresh_node ... ok
[INFO] [stdout] test sim::tests::pause_queues_messages_and_resume_delivers_them ... ok
[INFO] [stdout] test sim::tests::crash_respects_concurrency_budget ... ok
[INFO] [stdout] test sim::tests::timer_from_stale_incarnation_never_reaches_restarted_node ... ok
[INFO] [stdout] test sim::tests::heap_pops_equal_times_in_push_seq_order ... ok
[INFO] [stdout] test net::tests::validate_core_config_rejects_zero_resend - should panic ... ok
[INFO] [stdout] test workload::tests::retry_request_rebuilds_identical_command_and_request_id ... ok
[INFO] [stdout] test net::tests::validate_core_config_rejects_zero_n - should panic ... ok
[INFO] [stdout] test workload::tests::serve_get_stale_on_unstarted_replica_yields_none ... ok
[INFO] [stdout] test workload::tests::serve_get_stale_reads_observations_and_records_side_log ... ok
[INFO] [stdout] test workload::tests::start_log_op_sets_outstanding_and_records_invocation ... ok
[INFO] [stdout] test workload::tests::on_client_reply_ignores_reply_for_stale_request_id ... ok
[INFO] [stdout] test workload::tests::retry_request_none_once_completed ... ok
[INFO] [stdout] test sim::tests::run_completes_and_produces_history ... ok
[INFO] [stdout] test sim::tests::same_time_partition_events_resolve_in_schedule_push_order ... ok
[INFO] [stdout] test net::tests::validate_core_config_rejects_zero_sync - should panic ... ok
[INFO] [stdout] test workload::tests::start_log_op_panics_if_already_outstanding - should panic ... ok
[INFO] [stdout] test sim::tests::different_seeds_diverge ... ok
[INFO] [stdout] test sim::tests::same_seed_same_config_gives_identical_trace_hash ... ok
[INFO] [stdout] test linearize::tests::randomized_soundness_always_accepts ... ok
[INFO] [stdout] test workload::tests::on_client_reply_completes_matching_outstanding_and_marks_confirmed ... ok
[INFO] [stdout] test linearize::tests::mutation_fuzz_never_panics_usually_rejects ... ok
[INFO] [stdout] test net::tests::drop_probability_is_approximately_configured_over_many_rolls ... ok
[INFO] [stdout] test net::tests::dup_probability_is_approximately_configured_over_many_rolls ... ok
[INFO] [stdout] test workload::tests::choose_op_weighted_distribution_matches_configured_weights_approximately ... ok
[INFO] [stdout] test explore::tests::minutes_drawn_is_biased_short ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 118 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.26s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/vopr.rs (/opt/rustwide/target/debug/deps/vopr-cae364cafb54133f)
[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/checkers_red.rs (/opt/rustwide/target/debug/deps/checkers_red-00a905705b7fe5d5)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test observations_public_api_round_trips_agreement_and_applied_value ... ok
[INFO] [stdout] test convergence_checker_catches_replicas_disagreeing_after_quiescence - should panic ... ok
[INFO] [stdout] test exactly_once_checker_catches_duplicate_application - should panic ... ok
[INFO] [stdout] test prefix_consistency_checker_catches_a_wrong_fold - should panic ... ok
[INFO] [stdout] test live_sim_panics_when_replicas_disagree_on_a_decided_slot - should panic ... ok
[INFO] [stdout] test agreement_checker_catches_conflicting_decisions - should panic ... ok
[INFO] [stdout] test durability_checker_catches_a_confirmed_write_vanishing - should panic ... ok
[INFO] [stdout] test no_stuck_slot_checker_catches_a_slot_stuck_past_its_bound - should panic ... ok
[INFO] [stdout] test stale_read_checker_catches_a_read_beyond_its_claimed_prefix - should panic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/determinism.rs (/opt/rustwide/target/debug/deps/determinism-0a1c0b69eb2dc855)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test different_seed_diverges ... ok
[INFO] [stdout] test same_seed_twice_is_byte_identical ... ok
[INFO] [stderr]      Running tests/disk_torn_write.rs (/opt/rustwide/target/debug/deps/disk_torn_write-5a87408fe83fad93)
[INFO] [stdout] test many_seeds_all_reproduce_themselves ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.71s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test completed_persists_survive_a_later_crash ... ok
[INFO] [stdout] test empty_batch_crash_never_panics_and_commits_nothing ... ok
[INFO] [stdout] test zero_torn_probability_never_commits_partial_batches ... ok
[INFO] [stdout] test torn_write_prefix_is_a_true_prefix_of_submission_order ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/explore_mode.rs (/opt/rustwide/target/debug/deps/explore_mode-17ef1e57711b11f4)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test non_explore_cli_still_rejects_unknown_config ... ok
[INFO] [stderr] seed=2 config=chaos virtual=600s wall=2.48s ratio=242x ops=1146 stale_reads=377
[INFO] [stdout] test non_explore_cli_is_unaffected_seed_2_chaos_still_passes ... ok
[INFO] [stdout] test explore_seed_reprints_byte_identical_reproduction_line ... ok
[INFO] [stdout] test explore_seed_reproduces_identical_run_outcome ... FAILED
[INFO] [stdout] test different_explore_seeds_print_different_reproduction_lines has been running for over 60 seconds
[INFO] [stdout] test explore_params_and_sim_outcome_are_byte_identical_across_derivations has been running for over 60 seconds
[INFO] [stdout] test explore_params_and_sim_outcome_are_byte_identical_across_derivations ... ok
[INFO] [stdout] test different_explore_seeds_print_different_reproduction_lines ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- explore_seed_reproduces_identical_run_outcome stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'explore_seed_reproduces_identical_run_outcome' (2115) panicked at crates/sim/tests/explore_mode.rs:65:9:
[INFO] [stdout] assertion `left == right` failed: summary field "virtual" differed between runs: "[vopr-heartbeat] seed=3 wall=14s virtual=1198.0/2880.0s (41.6%) events=524288 (~438 ev/virtual-s)\nseed=3 explore virtual=2880.0s wall=27.33s ratio=105x ops=3566 stale_reads=1198\n" vs "[vopr-heartbeat] seed=3 wall=13s virtual=1844.8/2880.0s (64.1%) events=786432 (~426 ev/virtual-s)\nseed=3 explore virtual=2880.0s wall=18.37s ratio=157x ops=3566 stale_reads=1198\n"
[INFO] [stdout]   left: "1198.0/2880.0s"
[INFO] [stdout]  right: "1844.8/2880.0s"
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5a9c6d86b4f1 - std[70759c8f55707aa4]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5a9c6d86b4f1 - std[70759c8f55707aa4]::backtrace_rs::backtrace::trace_unsynchronized::<std[70759c8f55707aa4]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5a9c6d86b4f1 - std[70759c8f55707aa4]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5a9c6d86b4f1 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5a9c6d880c0a - <core[df12db4294e9bfd3]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5a9c6d880c0a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    6:     0x5a9c6d86f9dc - core[df12db4294e9bfd3]::io::write::default_write_fmt::<alloc[2182bb758b4b3781]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/io/write.rs:402:11
[INFO] [stdout]    7:     0x5a9c6d86f9dc - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/io/write.rs:335:13
[INFO] [stdout]    8:     0x5a9c6d8478a6 - <std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5a9c6d8478a6 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5a9c6d863c59 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5a9c6d6890e0 - <alloc[2182bb758b4b3781]::boxed::Box<dyn for<'a, 'b> core[df12db4294e9bfd3]::ops::function::Fn<(&'a std[70759c8f55707aa4]::panic::PanicHookInfo<'b>,), Output = ()> + core[df12db4294e9bfd3]::marker::Send + core[df12db4294e9bfd3]::marker::Sync> as core[df12db4294e9bfd3]::ops::function::Fn<(&std[70759c8f55707aa4]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/alloc/src/boxed.rs:2334:9
[INFO] [stdout]   12:     0x5a9c6d6890e0 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5a9c6d863e12 - <alloc[2182bb758b4b3781]::boxed::Box<dyn for<'a, 'b> core[df12db4294e9bfd3]::ops::function::Fn<(&'a std[70759c8f55707aa4]::panic::PanicHookInfo<'b>,), Output = ()> + core[df12db4294e9bfd3]::marker::Send + core[df12db4294e9bfd3]::marker::Sync> as core[df12db4294e9bfd3]::ops::function::Fn<(&std[70759c8f55707aa4]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/alloc/src/boxed.rs:2334:9
[INFO] [stdout]   14:     0x5a9c6d863e12 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:823:13
[INFO] [stdout]   15:     0x5a9c6d847952 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:688:13
[INFO] [stderr] error: test failed, to rerun pass `-p keermat-sim --test explore_mode`
[INFO] [stdout]   16:     0x5a9c6d8406d9 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5a9c6d84854d - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:679:5
[INFO] [stdout]   18:     0x5a9c6d8813fc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5a9c6d8812f3 - core[df12db4294e9bfd3]::panicking::assert_failed_inner
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/panicking.rs:447:23
[INFO] [stdout]   20:     0x5a9c6d6763a4 - core[df12db4294e9bfd3]::panicking::assert_failed::<alloc[2182bb758b4b3781]::string::String, alloc[2182bb758b4b3781]::string::String>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/panicking.rs:407:5
[INFO] [stdout]   21:     0x5a9c6d6778b0 - explore_mode[caff1730da31b07a]::explore_seed_reproduces_identical_run_outcome
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/sim/tests/explore_mode.rs:65:9
[INFO] [stdout]   22:     0x5a9c6d6767f7 - explore_mode[caff1730da31b07a]::explore_seed_reproduces_identical_run_outcome::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/sim/tests/explore_mode.rs:58:51
[INFO] [stdout]   23:     0x5a9c6d67c2f6 - <explore_mode[caff1730da31b07a]::explore_seed_reproduces_identical_run_outcome::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5a9c6d67c3cb - <fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String> as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5a9c6d67c3cb - 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]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:733:18
[INFO] [stdout]   26:     0x5a9c6d689a35 - test[9d35eded1c95d3be]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:756:74
[INFO] [stdout]   27:     0x5a9c6d689a35 - <core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<test[9d35eded1c95d3be]::run_test_in_process::{closure#0}> as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x5a9c6d689a35 - std[70759c8f55707aa4]::panicking::catch_unwind::do_call::<core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<test[9d35eded1c95d3be]::run_test_in_process::{closure#0}>, core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:576:43
[INFO] [stdout]   29:     0x5a9c6d689a35 - std[70759c8f55707aa4]::panicking::catch_unwind::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<test[9d35eded1c95d3be]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x5a9c6d689a35 - std[70759c8f55707aa4]::panic::catch_unwind::<core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<test[9d35eded1c95d3be]::run_test_in_process::{closure#0}>, core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x5a9c6d689a35 - test[9d35eded1c95d3be]::run_test_in_process
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:756:27
[INFO] [stdout]   32:     0x5a9c6d689a35 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:677:43
[INFO] [stdout]   33:     0x5a9c6d6832f4 - test[9d35eded1c95d3be]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/test/src/lib.rs:707:41
[INFO] [stdout]   34:     0x5a9c6d6832f4 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x5a9c6d68cb92 - std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x5a9c6d68cb92 - <core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x5a9c6d68cb92 - std[70759c8f55707aa4]::panicking::catch_unwind::do_call::<core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:576:43
[INFO] [stdout]   38:     0x5a9c6d68cb92 - std[70759c8f55707aa4]::panicking::catch_unwind::<(), core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x5a9c6d68cb92 - std[70759c8f55707aa4]::panic::catch_unwind::<core[df12db4294e9bfd3]::panic::unwind_safe::AssertUnwindSafe<std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x5a9c6d68cb92 - std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x5a9c6d68cb92 - <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]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x5a9c6d86adc9 - <alloc[2182bb758b4b3781]::boxed::Box<dyn core[df12db4294e9bfd3]::ops::function::FnOnce<(), Output = ()> + core[df12db4294e9bfd3]::marker::Send> as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/alloc/src/boxed.rs:2320:9
[INFO] [stdout]   43:     0x5a9c6d86adc9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/dfb0accb7f508ae8be965d8629557221c889dd98/library/std/src/sys/thread/unix.rs:123:17
[INFO] [stdout]   44:     0x7a2999617dfa - <unknown>
[INFO] [stdout]   45:     0x7a29996ab3d4 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     explore_seed_reproduces_identical_run_outcome
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 5 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 184.64s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "837fb9b31a2fb965f9c081cd09226f5db5d5c8fa8ce2a7813095710581d8aab5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "837fb9b31a2fb965f9c081cd09226f5db5d5c8fa8ce2a7813095710581d8aab5", kill_on_drop: false }`
[INFO] [stdout] 837fb9b31a2fb965f9c081cd09226f5db5d5c8fa8ce2a7813095710581d8aab5
