[INFO] cloning repository https://github.com/chimpanzcr/ClockWork
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/chimpanzcr/ClockWork" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchimpanzcr%2FClockWork", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchimpanzcr%2FClockWork'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 6e2a7447c8073de5b2b6c2bd3c9dad5fe06d102f
[INFO] testing chimpanzcr/ClockWork against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-3
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fchimpanzcr%2FClockWork" "/workspace/builds/worker-7-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/chimpanzcr/ClockWork
[INFO] finished tweaking git repo https://github.com/chimpanzcr/ClockWork
[INFO] tweaked toml for git repo https://github.com/chimpanzcr/ClockWork written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/chimpanzcr/ClockWork 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/chimpanzcr/ClockWork already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] c37dc09b25428903382c2a0518ff505c4ce0d2ef54590385ed166dcdfdcb2c8f
[INFO] running `Command { std: "docker" "start" "c37dc09b25428903382c2a0518ff505c4ce0d2ef54590385ed166dcdfdcb2c8f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c37dc09b25428903382c2a0518ff505c4ce0d2ef54590385ed166dcdfdcb2c8f", 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" "c37dc09b25428903382c2a0518ff505c4ce0d2ef54590385ed166dcdfdcb2c8f" "/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" "c37dc09b25428903382c2a0518ff505c4ce0d2ef54590385ed166dcdfdcb2c8f", 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" "c37dc09b25428903382c2a0518ff505c4ce0d2ef54590385ed166dcdfdcb2c8f" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling zmij v1.0.23
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling itoa v1.0.18
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling clap_builder v4.6.6
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling clap_derive v4.6.4
[INFO] [stderr]    Compiling clap v4.6.6
[INFO] [stderr]    Compiling clockwork-raft v0.1.0 (/opt/rustwide/workdir/crates/clockwork-raft)
[INFO] [stderr]    Compiling clockwork-check v0.1.0 (/opt/rustwide/workdir/crates/clockwork-check)
[INFO] [stderr]    Compiling clockwork-sim v0.1.0 (/opt/rustwide/workdir/crates/clockwork-sim)
[INFO] [stderr]    Compiling clockwork-cli v0.1.0 (/opt/rustwide/workdir/crates/clockwork-cli)
[INFO] [stderr]     Finished `release` profile [optimized + debuginfo] target(s) in 1m 22s
[INFO] running `Command { std: "docker" "inspect" "c37dc09b25428903382c2a0518ff505c4ce0d2ef54590385ed166dcdfdcb2c8f", 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" "c37dc09b25428903382c2a0518ff505c4ce0d2ef54590385ed166dcdfdcb2c8f" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling clockwork-raft v0.1.0 (/opt/rustwide/workdir/crates/clockwork-raft)
[INFO] [stderr]    Compiling clockwork-check v0.1.0 (/opt/rustwide/workdir/crates/clockwork-check)
[INFO] [stderr]    Compiling clockwork-sim v0.1.0 (/opt/rustwide/workdir/crates/clockwork-sim)
[INFO] [stderr]    Compiling clockwork-cli v0.1.0 (/opt/rustwide/workdir/crates/clockwork-cli)
[INFO] [stderr]     Finished `release` profile [optimized + debuginfo] target(s) in 59.04s
[INFO] running `Command { std: "docker" "inspect" "c37dc09b25428903382c2a0518ff505c4ce0d2ef54590385ed166dcdfdcb2c8f", 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" "c37dc09b25428903382c2a0518ff505c4ce0d2ef54590385ed166dcdfdcb2c8f" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/clockwork_check-8697cc018c15a0c3)
[INFO] [stdout] 
[INFO] [stdout] running 47 tests
[INFO] [stdout] test history::tests::timeout_has_unbounded_completion ... ok
[INFO] [stdout] test history::tests::validate_catches_malformed_records ... ok
[INFO] [stdout] test history::tests::validate_accepts_well_formed ... ok
[INFO] [stdout] test invariants::tests::a_crashed_ex_leader_is_not_counted_as_a_leader ... ok
[INFO] [stdout] test invariants::tests::applied_beyond_commit_is_caught ... ok
[INFO] [stdout] test invariants::tests::clean_execution_has_no_violations ... ok
[INFO] [stdout] test invariants::tests::commit_going_backwards_is_caught ... ok
[INFO] [stdout] test invariants::tests::every_kind_documents_itself ... ok
[INFO] [stdout] test history::tests::partitions_are_independent_and_complete ... ok
[INFO] [stdout] test history::tests::serde_round_trip ... ok
[INFO] [stdout] test invariants::tests::a_crashed_nodes_log_still_counts_as_evidence ... ok
[INFO] [stdout] test history::tests::counters ... ok
[INFO] [stdout] test invariants::tests::identical_application_is_fine ... ok
[INFO] [stdout] test invariants::tests::leader_missing_committed_entry_is_caught ... ok
[INFO] [stdout] test invariants::tests::leaders_in_different_terms_are_fine ... ok
[INFO] [stdout] test invariants::tests::log_matching_violation_is_caught ... ok
[INFO] [stdout] test invariants::tests::only_first_violation_of_a_kind_is_kept ... ok
[INFO] [stdout] test invariants::tests::two_leaders_in_one_term_is_caught ... ok
[INFO] [stdout] test invariants::tests::same_index_different_term_is_not_a_log_matching_violation ... ok
[INFO] [stdout] test invariants::tests::leader_need_not_hold_entries_committed_in_its_own_term ... ok
[INFO] [stdout] test invariants::tests::state_machine_divergence_is_caught ... ok
[INFO] [stdout] test invariants::tests::leader_with_snapshot_covering_entry_is_fine ... ok
[INFO] [stdout] test linearizability::tests::blame_names_the_offending_operation ... ok
[INFO] [stdout] test invariants::tests::two_commands_committed_at_one_index_is_caught ... ok
[INFO] [stdout] test linearizability::tests::cas_that_should_have_failed_is_rejected ... ok
[INFO] [stdout] test linearizability::tests::empty_history_is_linearizable ... ok
[INFO] [stdout] test linearizability::tests::double_applied_append_is_rejected ... ok
[INFO] [stdout] test linearizability::tests::lost_write_is_rejected ... ok
[INFO] [stdout] test linearizability::tests::concurrent_writes_resolved_by_a_later_read ... ok
[INFO] [stdout] test linearizability::tests::read_of_a_value_never_written_is_rejected ... ok
[INFO] [stdout] test linearizability::tests::repeated_checks_give_identical_verdicts ... ok
[INFO] [stdout] test linearizability::tests::failed_operations_are_treated_as_never_having_happened ... ok
[INFO] [stdout] test linearizability::tests::timeout_does_not_excuse_an_impossible_read ... ok
[INFO] [stdout] test linearizability::tests::tiny_budget_yields_unknown_not_a_pass ... ok
[INFO] [stdout] test linearizability::tests::witness_covers_every_determinate_operation ... ok
[INFO] [stdout] test model::tests::append_returns_cumulative_length ... ok
[INFO] [stdout] test model::tests::apply_is_pure ... ok
[INFO] [stdout] test model::tests::cas_semantics ... ok
[INFO] [stdout] test linearizability::tests::sequential_history_is_linearizable ... ok
[INFO] [stdout] test linearizability::tests::timed_out_write_that_never_took_effect_is_accepted ... ok
[INFO] [stdout] test linearizability::tests::stale_read_after_completed_write_is_rejected ... ok
[INFO] [stdout] test linearizability::tests::timed_out_write_that_took_effect_is_accepted ... ok
[INFO] [stdout] test linearizability::tests::verdict_labels_are_distinct ... ok
[INFO] [stdout] test model::tests::read_of_absent_key_is_none ... ok
[INFO] [stdout] test model::tests::digest_distinguishes_states ... ok
[INFO] [stdout] test linearizability::tests::operations_on_different_keys_do_not_interfere ... ok
[INFO] [stdout] test model::tests::digest_separates_field_boundaries ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 47 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/clockwork-43cebe6b177a9864)
[INFO] [stdout] 
[INFO] [stdout] running 22 tests
[INFO] [stdout] test diagram::tests::long_gaps_are_elided ... ok
[INFO] [stdout] test diagram::tests::outcome_shortening_handles_all_shapes ... ok
[INFO] [stdout] test diagram::tests::empty_trace_still_produces_a_header ... ok
[INFO] [stdout] test diagram::tests::faults_render_as_banners ... ok
[INFO] [stdout] test diagram::tests::role_change_appears_in_the_right_lane ... ok
[INFO] [stdout] test diagram::tests::header_lists_every_node ... ok
[INFO] [stdout] test diagram::tests::row_cap_is_respected ... ok
[INFO] [stdout] test diagram::tests::heartbeats_are_hidden_by_default_and_shown_on_request ... ok
[INFO] [stdout] test html::tests::escaping_neutralises_markup ... ok
[INFO] [stdout] test diagram::tests::arrow_points_the_right_way ... ok
[INFO] [stdout] test shrink::tests::blocks_terminates_for_many_sizes ... ok
[INFO] [stdout] test diagram::tests::op_shortening_is_compact_and_lossless_enough ... ok
[INFO] [stdout] test diagram::tests::rendering_never_panics_on_out_of_range_nodes ... ok
[INFO] [stdout] test shrink::tests::blocks_handles_degenerate_lengths ... ok
[INFO] [stdout] test shrink::tests::duration_truncation_drops_out_of_range_content ... ok
[INFO] [stdout] test shrink::tests::cluster_shrink_allows_sizes_that_keep_faults_valid ... ok
[INFO] [stdout] test shrink::tests::reduction_permille_is_sane ... ok
[INFO] [stdout] test shrink::tests::cluster_shrink_rejects_sizes_that_orphan_faults ... ok
[INFO] [stdout] test shrink::tests::every_candidate_strategy_strictly_shrinks_or_yields_nothing ... ok
[INFO] [stdout] test shrink::tests::blocks_covers_halves_then_finer ... ok
[INFO] [stdout] test html::tests::report_states_when_defects_are_active ... ok
[INFO] [stdout] test html::tests::report_is_self_contained_and_well_formed ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.33s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/flags.rs (/opt/rustwide/target/release/deps/flags-ed1418dfc127a8cc)
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test a_missing_scenario_file_reports_the_path ... ok
[INFO] [stdout] test bugs_reports_how_each_defect_is_actually_caught ... ok
[INFO] [stdout] test neither_seed_nor_scenario_is_an_error ... ok
[INFO] [stdout] test diagram_control_is_opt_in_for_simulate_and_opt_out_for_inspection ... ok
[INFO] [stdout] test profiles_lists_every_named_profile ... ok
[INFO] [stdout] test out_of_range_values_are_rejected ... ok
[INFO] [stdout] test generation_flags_are_accepted_without_a_scenario ... ok
[INFO] [stdout] test no_subcommand_declares_a_flag_twice ... ok
[INFO] [stdout] test strict_is_available_wherever_a_verdict_is_reached ... ok
[INFO] [stdout] test unknown_defect_is_rejected_and_lists_the_valid_names ... ok
[INFO] [stdout] test unknown_profile_is_rejected_helpfully ... ok
[INFO] [stdout] test verify_determinism_does_not_offer_checker_flags ... ok
[INFO] [stdout] test simulate_omits_the_diagram_unless_asked ... ok
[INFO] [stdout] test scenario_conflicts_with_every_generation_flag ... ok
[INFO] [stdout] test short_flags_match_their_long_forms ... ok
[INFO] [stdout] test every_listed_defect_is_accepted ... ok
[INFO] [stdout] test every_listed_profile_is_accepted ... ok
[INFO] [stdout] test a_saved_scenario_replays_identically ... ok
[INFO] [stdout] test strict_turns_an_undecided_verdict_into_a_failure ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.72s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/clockwork_raft-c64d7186e6e91a9d)
[INFO] [stdout] 
[INFO] [stdout] running 73 tests
[INFO] [stdout] test bugs::tests::default_is_correct_implementation ... ok
[INFO] [stdout] test bugs::tests::describe_is_readable ... ok
[INFO] [stdout] test bugs::tests::every_bug_documents_itself ... ok
[INFO] [stdout] test bugs::tests::names_round_trip_and_are_unique ... ok
[INFO] [stdout] test bugs::tests::bits_are_independent ... ok
[INFO] [stdout] test bugs::tests::combinations_accumulate ... ok
[INFO] [stdout] test bugs::tests::reachability_matches_the_documented_split ... ok
[INFO] [stdout] test config::tests::quorum_sizes ... ok
[INFO] [stdout] test config::tests::peers_excludes_self ... ok
[INFO] [stdout] test bugs::tests::bitset_has_room_for_all_bugs ... ok
[INFO] [stdout] test config::tests::single_node_may_have_fixed_timeout ... ok
[INFO] [stdout] test config::tests::two_quorums_always_intersect ... ok
[INFO] [stdout] test config::tests::validate_accepts_defaults ... ok
[INFO] [stdout] test config::tests::validate_rejects_bad_configs ... ok
[INFO] [stdout] test log::tests::append_continues_after_compaction ... ok
[INFO] [stdout] test log::tests::append_rejects_gaps ... ok
[INFO] [stdout] test log::tests::compact_is_monotonic ... ok
[INFO] [stdout] test log::tests::conflict_beyond_end_points_at_log_end ... ok
[INFO] [stdout] test log::tests::conflict_reports_first_index_of_term ... ok
[INFO] [stdout] test log::tests::conflict_search_stops_at_snapshot_base ... ok
[INFO] [stdout] test log::tests::conversions_survive_compaction ... ok
[INFO] [stdout] test log::tests::divergence_found_at_first_term_mismatch ... ok
[INFO] [stdout] test log::tests::divergence_past_end_is_the_new_index ... ok
[INFO] [stdout] test log::tests::duplicate_append_reports_no_divergence ... ok
[INFO] [stdout] test log::tests::empty_log_accepts_anything ... ok
[INFO] [stdout] test log::tests::empty_log_boundaries ... ok
[INFO] [stdout] test log::tests::entries_from_respects_limit_and_bounds ... ok
[INFO] [stdout] test log::tests::index_conversions_round_trip ... ok
[INFO] [stdout] test log::tests::install_snapshot_covering_everything_clears_log ... ok
[INFO] [stdout] test log::tests::install_snapshot_retains_newer_contiguous_suffix ... ok
[INFO] [stdout] test log::tests::single_entry_boundaries ... ok
[INFO] [stdout] test log::tests::truncate_beyond_end_is_a_noop ... ok
[INFO] [stdout] test log::tests::truncate_refuses_committed ... ok
[INFO] [stdout] test log::tests::truncate_at_commit_boundary_is_refused ... ok
[INFO] [stdout] test log::tests::truncate_refuses_inside_snapshot ... ok
[INFO] [stdout] test log::tests::truncate_removes_suffix ... ok
[INFO] [stdout] test log::tests::up_to_date_prefers_term_over_index ... ok
[INFO] [stdout] test message::tests::heartbeats_are_labelled_separately ... ok
[INFO] [stdout] test log::tests::install_snapshot_discards_non_contiguous_suffix ... ok
[INFO] [stdout] test message::tests::pre_vote_requests_do_not_bump_terms ... ok
[INFO] [stdout] test message::tests::pre_vote_responses_do_bump_terms ... ok
[INFO] [stdout] test prng::tests::chance_endpoints_are_absolute ... ok
[INFO] [stdout] test prng::tests::chance_is_roughly_calibrated ... ok
[INFO] [stdout] test prng::tests::degenerate_range_is_a_constant ... ok
[INFO] [stdout] test prng::tests::derive_is_stable ... ok
[INFO] [stdout] test prng::tests::derive_separates_streams ... ok
[INFO] [stdout] test prng::tests::gen_range_respects_bounds_and_covers_them ... ok
[INFO] [stdout] test prng::tests::distinct_seeds_give_distinct_streams ... ok
[INFO] [stdout] test prng::tests::pinned_known_answers ... ok
[INFO] [stdout] test prng::tests::shuffle_handles_tiny_slices ... ok
[INFO] [stdout] test prng::tests::gen_range_is_roughly_uniform ... ok
[INFO] [stdout] test state::tests::leader_state_initialises_optimistically ... ok
[INFO] [stdout] test state::tests::only_invariant_violations_are_implementation_bugs ... ok
[INFO] [stdout] test state::tests::role_names_and_hints ... ok
[INFO] [stdout] test statemachine::tests::aged_out_duplicate_returns_none_rather_than_a_wrong_answer ... ok
[INFO] [stdout] test statemachine::tests::append_concatenates ... ok
[INFO] [stdout] test statemachine::tests::cas_respects_expectation ... ok
[INFO] [stdout] test statemachine::tests::dedup_is_per_client ... ok
[INFO] [stdout] test statemachine::tests::dedup_suppresses_double_apply ... ok
[INFO] [stdout] test prng::tests::shuffle_permutes_without_loss ... ok
[INFO] [stdout] test statemachine::tests::digest_detects_divergence ... ok
[INFO] [stdout] test statemachine::tests::digest_agrees_on_identical_application_order ... ok
[INFO] [stdout] test statemachine::tests::snapshot_round_trip_preserves_everything ... ok
[INFO] [stdout] test statemachine::tests::stale_retry_never_returns_another_operations_result ... ok
[INFO] [stdout] test statemachine::tests::write_then_read ... ok
[INFO] [stdout] test storage::tests::append_then_truncate ... ok
[INFO] [stdout] test statemachine::tests::without_dedup_a_retry_double_applies ... ok
[INFO] [stdout] test storage::tests::append_overwrites_existing_index ... ok
[INFO] [stdout] test storage::tests::snapshot_discards_covered_prefix ... ok
[INFO] [stdout] test storage::tests::round_trips_hard_state ... ok
[INFO] [stdout] test types::tests::only_reads_are_non_mutating ... ok
[INFO] [stdout] test types::tests::checksum_distinguishes_payload_values ... ok
[INFO] [stdout] test types::tests::checksum_is_content_sensitive ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 73 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/clockwork_sim-3597c6d370fb92f6)
[INFO] [stdout] 
[INFO] [stdout] running 103 tests
[INFO] [stdout] test client::tests::a_timeout_followed_by_refusals_is_indeterminate_not_failed ... ok
[INFO] [stdout] test client::tests::finished_operations_cannot_be_reattempted ... ok
[INFO] [stdout] test client::tests::history_validates ... ok
[INFO] [stdout] test client::tests::a_second_operation_for_the_same_client_is_deferred ... ok
[INFO] [stdout] test client::tests::a_retry_of_the_owning_operation_is_not_deferred ... ok
[INFO] [stdout] test client::tests::different_operations_get_different_sequence_numbers ... ok
[INFO] [stdout] test client::tests::exhausted_not_leader_retries_record_a_definite_failure ... ok
[INFO] [stdout] test client::tests::identical_seeds_choose_identical_targets ... ok
[INFO] [stdout] test client::tests::response_to_unknown_request_is_ignored ... ok
[INFO] [stdout] test client::tests::not_leader_updates_the_hint_and_retries ... ok
[INFO] [stdout] test client::tests::first_attempt_allocates_a_sequence_number ... ok
[INFO] [stdout] test client::tests::outstanding_operations_are_finalised_as_indeterminate ... ok
[INFO] [stdout] test client::tests::refusals_with_no_timeout_are_a_definite_failure ... ok
[INFO] [stdout] test client::tests::stale_timeout_is_ignored ... ok
[INFO] [stdout] test client::tests::retries_reuse_the_same_sequence_number ... ok
[INFO] [stdout] test client::tests::sequence_numbers_are_per_client ... ok
[INFO] [stdout] test client::tests::stale_response_is_ignored ... ok
[INFO] [stdout] test client::tests::success_records_ok_and_spans_from_first_attempt ... ok
[INFO] [stdout] test client::tests::timeout_after_max_attempts_is_indeterminate ... ok
[INFO] [stdout] test client::tests::timeout_before_max_attempts_retries ... ok
[INFO] [stdout] test cluster::tests::a_quiet_cluster_elects_a_leader_and_commits ... ok
[INFO] [stdout] test cluster::tests::different_seeds_produce_different_executions ... ok
[INFO] [stdout] test cluster::tests::single_node_cluster_makes_progress ... ok
[INFO] [stdout] test cluster::tests::large_cluster_makes_progress ... ok
[INFO] [stdout] test cluster::tests::faults_actually_fire ... ok
[INFO] [stdout] test disk::tests::append_at_existing_index_overwrites_suffix ... ok
[INFO] [stdout] test disk::tests::corrupting_a_missing_index_reports_failure ... ok
[INFO] [stdout] test disk::tests::corruption_is_detected_by_checksum_on_load ... ok
[INFO] [stdout] test disk::tests::full_disk_rejects_writes ... ok
[INFO] [stdout] test disk::tests::handle_is_shared_so_the_disk_outlives_the_node ... ok
[INFO] [stdout] test disk::tests::snapshot_discards_covered_entries ... ok
[INFO] [stdout] test disk::tests::stall_accumulates_and_drains ... ok
[INFO] [stdout] test disk::tests::sync_failure_leaves_data_unsynced ... ok
[INFO] [stdout] test disk::tests::synced_hard_state_survives_but_unsynced_does_not ... ok
[INFO] [stdout] test disk::tests::synced_writes_always_survive_a_crash ... ok
[INFO] [stdout] test disk::tests::torn_write_of_a_single_entry_degrades_to_total_loss ... ok
[INFO] [stdout] test disk::tests::torn_write_persists_a_prefix ... ok
[INFO] [stdout] test disk::tests::truncate_then_sync_removes_entries ... ok
[INFO] [stdout] test disk::tests::unsynced_writes_are_lost_on_crash ... ok
[INFO] [stdout] test faults::tests::active_window_is_half_open ... ok
[INFO] [stdout] test faults::tests::descriptions_are_non_empty_and_readable ... ok
[INFO] [stdout] test faults::tests::isolate_blocks_both_directions ... ok
[INFO] [stdout] test faults::tests::latency_and_loss_weaken_toward_zero ... ok
[INFO] [stdout] test faults::tests::mentions_covers_all_referenced_nodes ... ok
[INFO] [stdout] test faults::tests::one_way_blocks_only_one_direction ... ok
[INFO] [stdout] test faults::tests::pairs_block_exactly_what_is_listed ... ok
[INFO] [stdout] test faults::tests::serde_round_trip ... ok
[INFO] [stdout] test faults::tests::split_blocks_across_but_not_within ... ok
[INFO] [stdout] test faults::tests::weaken_reduces_partitions_toward_simpler_forms ... ok
[INFO] [stdout] test faults::tests::weaken_terminates ... ok
[INFO] [stdout] test network::tests::asymmetric_partition_blocks_one_direction_only ... ok
[INFO] [stdout] test network::tests::clean_network_always_delivers_once ... ok
[INFO] [stdout] test network::tests::different_seeds_diverge ... ok
[INFO] [stdout] test network::tests::drop_rate_is_roughly_honoured ... ok
[INFO] [stdout] test network::tests::duplication_produces_two_deliveries ... ok
[INFO] [stdout] test network::tests::extra_drop_adds_to_baseline ... ok
[INFO] [stdout] test network::tests::extra_latency_delays_delivery ... ok
[INFO] [stdout] test network::tests::healing_a_partition_restores_delivery ... ok
[INFO] [stdout] test network::tests::identical_seeds_produce_identical_outcomes ... ok
[INFO] [stdout] test network::tests::independent_latencies_cause_reordering ... ok
[INFO] [stdout] test network::tests::links_are_independent_streams ... ok
[INFO] [stdout] test network::tests::overlapping_partitions_both_apply ... ok
[INFO] [stdout] test network::tests::partition_blocks_at_send_time ... ok
[INFO] [stdout] test scenario::tests::cluster_sizes_are_odd ... ok
[INFO] [stdout] test scenario::tests::config_matches_tunables ... ok
[INFO] [stdout] test scenario::tests::different_seeds_give_different_scenarios ... ok
[INFO] [stdout] test scenario::tests::faults_are_sorted_by_start ... ok
[INFO] [stdout] test scenario::tests::faults_stay_within_duration ... ok
[INFO] [stdout] test scenario::tests::generated_scenarios_are_valid ... ok
[INFO] [stdout] test scenario::tests::generated_scenarios_only_reference_existing_nodes ... ok
[INFO] [stdout] test scenario::tests::generation_is_deterministic ... ok
[INFO] [stdout] test scenario::tests::higher_intensity_produces_more_faults ... ok
[INFO] [stdout] test scenario::tests::json_round_trip ... ok
[INFO] [stdout] test scenario::tests::ops_are_sorted_and_within_duration ... ok
[INFO] [stdout] test scenario::tests::preserve_quorum_never_disables_a_majority ... ok
[INFO] [stdout] test scenario::tests::quiet_params_generate_no_faults ... ok
[INFO] [stdout] test scenario::tests::size_shrinks_when_content_is_removed ... ok
[INFO] [stdout] test scenario::tests::validate_rejects_out_of_range_references ... ok
[INFO] [stdout] test scenario::tests::zero_intensity_means_no_faults_at_any_seed ... ok
[INFO] [stdout] test scheduler::tests::empty_scheduler_yields_nothing ... ok
[INFO] [stdout] test scheduler::tests::ordering_is_stable_across_construction_order ... ok
[INFO] [stdout] test scheduler::tests::past_events_are_clamped_to_now ... ok
[INFO] [stdout] test scheduler::tests::pending_count_tracks_heap ... ok
[INFO] [stdout] test scheduler::tests::pops_in_time_order ... ok
[INFO] [stdout] test scheduler::tests::schedule_after_is_relative_to_now ... ok
[INFO] [stdout] test scheduler::tests::simultaneous_events_keep_insertion_order ... ok
[INFO] [stdout] test scheduler::tests::time_jumps_rather_than_ticking ... ok
[INFO] [stdout] test trace::tests::client_outcome_is_included_in_fingerprint ... ok
[INFO] [stdout] test trace::tests::divergence_index_is_found ... ok
[INFO] [stdout] test trace::tests::empty_trace_has_the_offset_basis ... ok
[INFO] [stdout] test trace::tests::fingerprint_is_order_sensitive ... ok
[INFO] [stdout] test trace::tests::heartbeats_are_not_notable ... ok
[INFO] [stdout] test trace::tests::identical_sequences_agree ... ok
[INFO] [stdout] test trace::tests::jsonl_has_one_line_per_event ... ok
[INFO] [stdout] test trace::tests::recording_does_not_affect_fingerprint ... ok
[INFO] [stdout] test trace::tests::summary_text_is_excluded_from_fingerprint ... ok
[INFO] [stdout] test cluster::tests::most_operations_complete_on_a_quiet_cluster ... ok
[INFO] [stdout] test cluster::tests::identical_seeds_produce_identical_fingerprints ... ok
[INFO] [stdout] test cluster::tests::history_accounts_for_exactly_the_issued_operations ... ok
[INFO] [stdout] test cluster::tests::client_history_is_well_formed ... ok
[INFO] [stdout] test cluster::tests::tracing_does_not_change_behaviour ... ok
[INFO] [stdout] test cluster::tests::the_correct_implementation_survives_hostile_scenarios ... ok
[INFO] [stdout] test cluster::tests::crashes_and_restarts_happen_and_are_survived ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 103 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 11.08s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/determinism.rs (/opt/rustwide/target/release/deps/determinism-b4292ce76927d4db)
[INFO] [stdout] running 11 tests
[INFO] [stdout] test continuing_past_a_failure_does_not_change_the_prefix ... ok
[INFO] [stdout] test parallelism_does_not_affect_results ... ok
[INFO] [stdout] test scenarios_round_trip_and_replay_identically ... ok
[INFO] [stdout] test the_checker_is_deterministic ... ok
[INFO] [stdout] test every_profile_is_deterministic ... ok
[INFO] [stdout] test the_same_scenario_value_replays_identically ... ok
[INFO] [stdout] test distinct_seeds_explore_distinct_executions ... ok
[INFO] [stdout] test a_seed_reproduces_its_execution_exactly ... ok
[INFO] [stdout] test runs_with_injected_defects_are_deterministic ... ok
[INFO] [stdout] test tracing_cannot_change_behaviour ... ok
[INFO] [stdout] test parallel_campaigns_find_the_same_failures ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 50.31s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/planted_bugs.rs (/opt/rustwide/target/release/deps/planted_bugs-d6681190e9f32bbf)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test detects_commit_without_majority ... ok
[INFO] [stdout] test detects_double_vote ... ok
[INFO] [stdout] test detects_no_dedup ... ok
[INFO] [stdout] test detects_vote_without_log_check ... ok
[INFO] [stdout] test detects_stale_local_read ... ok
[INFO] [stdout] test detects_truncate_on_every_append ... ok
[INFO] [stdout] test ignore_higher_term_is_benign_in_isolation has been running for over 60 seconds
[INFO] [stdout] test the_correct_implementation_is_not_flagged has been running for over 60 seconds
[INFO] [stdout] test ignore_higher_term_is_benign_in_isolation ... ok
[INFO] [stdout] test the_correct_implementation_is_not_flagged ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 101.63s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/properties.rs (/opt/rustwide/target/release/deps/properties-6c12cec2f538e137)
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test a_crash_never_produces_entries_that_were_never_written ... ok
[INFO] [stdout] test synced_data_always_survives_a_crash ... ok
[INFO] [stdout] test sequential_executions_are_always_linearizable ... ok
[INFO] [stdout] test corrupting_a_sequential_history_makes_it_unlinearizable ... ok
[INFO] [stdout] test a_quiet_cluster_always_makes_progress ... ok
[INFO] [stdout] test no_node_ever_applies_beyond_its_commit_index ... ok
[INFO] [stdout] test hostile_profiles_actually_inject_faults ... ok
[INFO] [stdout] test recorded_histories_are_always_well_formed ... ok
[INFO] [stdout] test the_correct_implementation_is_always_safe has been running for over 60 seconds
[INFO] [stderr]      Running tests/targeted.rs (/opt/rustwide/target/release/deps/targeted-71ebd486f029cd72)
[INFO] [stdout] test the_correct_implementation_is_always_safe ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 81.96s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test a_vote_lost_to_a_crash_produces_two_leaders_in_one_term ... ok
[INFO] [stdout] test figure_8_with_the_correct_commit_rule_is_safe ... ok
[INFO] [stderr]    Doc-tests clockwork_check
[INFO] [stdout] test a_durable_vote_survives_the_same_crash ... ok
[INFO] [stdout] test figure_8_with_the_faulty_commit_rule_loses_committed_data ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[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]    Doc-tests clockwork_raft
[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]    Doc-tests clockwork_sim
[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] running `Command { std: "docker" "inspect" "c37dc09b25428903382c2a0518ff505c4ce0d2ef54590385ed166dcdfdcb2c8f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c37dc09b25428903382c2a0518ff505c4ce0d2ef54590385ed166dcdfdcb2c8f", kill_on_drop: false }`
[INFO] [stdout] c37dc09b25428903382c2a0518ff505c4ce0d2ef54590385ed166dcdfdcb2c8f
