[INFO] cloning repository https://github.com/BhaveshThapar/Keel
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/BhaveshThapar/Keel" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBhaveshThapar%2FKeel", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBhaveshThapar%2FKeel'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] d30bffa05493b0e87749b476d6027f1616d3d3af
[INFO] testing BhaveshThapar/Keel 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%2FBhaveshThapar%2FKeel" "/workspace/builds/worker-2-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc1/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-2-tc1/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/BhaveshThapar/Keel
[INFO] finished tweaking git repo https://github.com/BhaveshThapar/Keel
[INFO] tweaked toml for git repo https://github.com/BhaveshThapar/Keel written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/BhaveshThapar/Keel 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/BhaveshThapar/Keel 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]     Updating crates.io index
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded unicode-ident v1.0.24
[INFO] [stderr]   Downloaded cfg-if v1.0.4
[INFO] [stderr]   Downloaded unarray v0.1.4
[INFO] [stderr]   Downloaded thiserror-impl v2.0.20
[INFO] [stderr]   Downloaded quick-error v1.2.3
[INFO] [stderr]   Downloaded clap_lex v1.1.0
[INFO] [stderr]   Downloaded atomic-polyfill v1.0.3
[INFO] [stderr]   Downloaded once_cell_polyfill v1.70.2
[INFO] [stderr]   Downloaded thread_local v1.1.10
[INFO] [stderr]   Downloaded windows-link v0.2.1
[INFO] [stderr]   Downloaded cobs v0.3.0
[INFO] [stderr]   Downloaded embedded-io v0.6.1
[INFO] [stderr]   Downloaded matchers v0.2.0
[INFO] [stderr]   Downloaded crc32c v0.6.8
[INFO] [stderr]   Downloaded rustc_version v0.4.1
[INFO] [stderr]   Downloaded anstyle-wincon v3.0.11
[INFO] [stderr]   Downloaded thiserror v2.0.20
[INFO] [stderr]   Downloaded rand_xorshift v0.4.0
[INFO] [stderr]   Downloaded wait-timeout v0.2.1
[INFO] [stderr]   Downloaded hash32 v0.2.1
[INFO] [stderr]   Downloaded embedded-io v0.4.0
[INFO] [stderr]   Downloaded xxhash-rust v0.8.18
[INFO] [stderr]   Downloaded bit-vec v0.8.0
[INFO] [stderr]   Downloaded zmij v1.0.23
[INFO] [stderr]   Downloaded rusty-fork v0.3.1
[INFO] [stderr]   Downloaded nu-ansi-term v0.50.3
[INFO] [stderr]   Downloaded redox_syscall v0.5.18
[INFO] [stderr]   Downloaded rand_core v0.9.5
[INFO] [stderr]   Downloaded rand_chacha v0.9.0
[INFO] [stderr]   Downloaded autocfg v1.5.1
[INFO] [stderr]   Downloaded lock_api v0.4.14
[INFO] [stderr]   Downloaded bit-set v0.8.0
[INFO] [stderr]   Downloaded lz4_flex v0.11.6
[INFO] [stderr]   Downloaded semver v1.0.28
[INFO] [stderr]   Downloaded spin v0.9.9
[INFO] [stderr]   Downloaded critical-section v1.2.0
[INFO] [stderr]   Downloaded clap_derive v4.6.4
[INFO] [stderr]   Downloaded valuable v0.1.1
[INFO] [stderr]   Downloaded serde_derive v1.0.229
[INFO] [stderr]   Downloaded getrandom v0.3.4
[INFO] [stderr]   Downloaded parking_lot v0.12.5
[INFO] [stderr]   Downloaded smallvec v1.15.2
[INFO] [stderr]   Downloaded postcard v1.1.3
[INFO] [stderr]   Downloaded parking_lot_core v0.9.12
[INFO] [stderr]   Downloaded twox-hash v2.1.3
[INFO] [stderr]   Downloaded tracing-attributes v0.1.31
[INFO] [stderr]   Downloaded r-efi v6.0.0
[INFO] [stderr]   Downloaded wit-bindgen v0.46.0
[INFO] [stderr]   Downloaded clap v4.6.6
[INFO] [stderr]   Downloaded crc32fast v1.5.1
[INFO] [stderr]   Downloaded heapless v0.7.17
[INFO] [stderr]   Downloaded bitflags v2.13.1
[INFO] [stderr]   Downloaded rand v0.9.5
[INFO] [stderr]   Downloaded zerocopy-derive v0.8.56
[INFO] [stderr]   Downloaded wasip2 v1.0.1+wasi-0.2.4
[INFO] [stderr]   Downloaded serde_json v1.0.151
[INFO] [stderr]   Downloaded syn v3.0.3
[INFO] [stderr]   Downloaded clap_builder v4.6.6
[INFO] [stderr]   Downloaded aho-corasick v1.1.5
[INFO] [stderr]   Downloaded proptest v1.11.0
[INFO] [stderr]   Downloaded tracing-subscriber v0.3.23
[INFO] [stderr]   Downloaded zerocopy v0.8.56
[INFO] [stderr]   Downloaded syn v2.0.119
[INFO] [stderr]   Downloaded regex-syntax v0.8.11
[INFO] [stderr]   Downloaded rustix v1.1.4
[INFO] [stderr]   Downloaded regex-automata v0.4.18
[INFO] [stderr]   Downloaded libc v0.2.189
[INFO] [stderr]   Downloaded windows-sys v0.61.2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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] 6dcc18568ed75de8b951c0d15ac45fded1863c0332b59be081842e92524f9efc
[INFO] running `Command { std: "docker" "start" "6dcc18568ed75de8b951c0d15ac45fded1863c0332b59be081842e92524f9efc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "6dcc18568ed75de8b951c0d15ac45fded1863c0332b59be081842e92524f9efc", 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" "6dcc18568ed75de8b951c0d15ac45fded1863c0332b59be081842e92524f9efc" "/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" "6dcc18568ed75de8b951c0d15ac45fded1863c0332b59be081842e92524f9efc", 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" "6dcc18568ed75de8b951c0d15ac45fded1863c0332b59be081842e92524f9efc" "/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 thiserror v2.0.20
[INFO] [stderr]    Compiling semver v1.0.28
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling libc v0.2.189
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling stable_deref_trait v1.2.1
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling hash32 v0.2.1
[INFO] [stderr]    Compiling bitflags v2.13.1
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling crc32fast v1.5.1
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling twox-hash v2.1.3
[INFO] [stderr]    Compiling rustc_version v0.4.1
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling lz4_flex v0.11.6
[INFO] [stderr]    Compiling xxhash-rust v0.8.18
[INFO] [stderr]    Compiling fastrand v2.5.0
[INFO] [stderr]    Compiling keel-rand v3.1.6 (/opt/rustwide/workdir/crates/keel-rand)
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling regex-syntax v0.8.11
[INFO] [stderr]    Compiling spin v0.9.9
[INFO] [stderr]    Compiling log v0.4.34
[INFO] [stderr]    Compiling clap_builder v4.6.6
[INFO] [stderr]    Compiling heapless v0.7.17
[INFO] [stderr]    Compiling crc32c v0.6.8
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling pin-project-lite v0.2.17
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling thread_local v1.1.10
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling zmij v1.0.23
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling itoa v1.0.18
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling regex-automata v0.4.18
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling thiserror-impl v2.0.20
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling clap_derive v4.6.4
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling cobs v0.3.0
[INFO] [stderr]    Compiling lsm_kv v0.1.0 (/opt/rustwide/workdir/crates/lsm-kv)
[INFO] [stderr]    Compiling keel-net v3.1.6 (/opt/rustwide/workdir/crates/keel-net)
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling clap v4.6.6
[INFO] [stderr]    Compiling keel-chaos v3.1.6 (/opt/rustwide/workdir/crates/keel-chaos)
[INFO] [stderr]    Compiling bytes v1.12.1
[INFO] [stderr]    Compiling keel-raft v3.1.6 (/opt/rustwide/workdir/crates/keel-raft)
[INFO] [stderr]    Compiling postcard v1.1.3
[INFO] [stderr]    Compiling keel-api v3.1.6 (/opt/rustwide/workdir/crates/keel-api)
[INFO] [stderr]    Compiling keel-log v3.1.6 (/opt/rustwide/workdir/crates/keel-log)
[INFO] [stderr]    Compiling keel-sm v3.1.6 (/opt/rustwide/workdir/crates/keel-sm)
[INFO] [stderr]    Compiling keel-client v3.1.6 (/opt/rustwide/workdir/crates/keel-client)
[INFO] [stderr]    Compiling keel-bench v3.1.6 (/opt/rustwide/workdir/crates/keel-bench)
[INFO] [stderr]    Compiling keel-node v3.1.6 (/opt/rustwide/workdir/crates/keel-node)
[INFO] [stderr]    Compiling keel-sim v3.1.6 (/opt/rustwide/workdir/crates/keel-sim)
[INFO] [stderr]    Compiling keel-fuzz v3.1.6 (/opt/rustwide/workdir/crates/keel-fuzz)
[INFO] [stderr]    Compiling keel-server v3.1.6 (/opt/rustwide/workdir/crates/keel-server)
[INFO] [stderr]    Compiling keel-maelstrom v3.1.6 (/opt/rustwide/workdir/crates/keel-maelstrom)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 1m 29s
[INFO] running `Command { std: "docker" "inspect" "6dcc18568ed75de8b951c0d15ac45fded1863c0332b59be081842e92524f9efc", 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" "6dcc18568ed75de8b951c0d15ac45fded1863c0332b59be081842e92524f9efc" "/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 regex-syntax v0.8.11
[INFO] [stderr]    Compiling keel-net v3.1.6 (/opt/rustwide/workdir/crates/keel-net)
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling zerocopy v0.8.56
[INFO] [stderr]    Compiling autocfg v1.5.1
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling keel-log v3.1.6 (/opt/rustwide/workdir/crates/keel-log)
[INFO] [stderr]    Compiling unarray v0.1.4
[INFO] [stderr]    Compiling keel-sm v3.1.6 (/opt/rustwide/workdir/crates/keel-sm)
[INFO] [stderr]    Compiling keel-raft v3.1.6 (/opt/rustwide/workdir/crates/keel-raft)
[INFO] [stderr]    Compiling keel-api v3.1.6 (/opt/rustwide/workdir/crates/keel-api)
[INFO] [stderr]    Compiling keel-rand v3.1.6 (/opt/rustwide/workdir/crates/keel-rand)
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling rand v0.9.5
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling keel-bench v3.1.6 (/opt/rustwide/workdir/crates/keel-bench)
[INFO] [stderr]    Compiling keel-node v3.1.6 (/opt/rustwide/workdir/crates/keel-node)
[INFO] [stderr]    Compiling keel-sim v3.1.6 (/opt/rustwide/workdir/crates/keel-sim)
[INFO] [stderr]    Compiling keel-fuzz v3.1.6 (/opt/rustwide/workdir/crates/keel-fuzz)
[INFO] [stderr]    Compiling regex-automata v0.4.18
[INFO] [stderr]    Compiling keel-maelstrom v3.1.6 (/opt/rustwide/workdir/crates/keel-maelstrom)
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling proptest v1.11.0
[INFO] [stderr]    Compiling keel-server v3.1.6 (/opt/rustwide/workdir/crates/keel-server)
[INFO] [stderr]    Compiling keel-chaos v3.1.6 (/opt/rustwide/workdir/crates/keel-chaos)
[INFO] [stderr]    Compiling keel-client v3.1.6 (/opt/rustwide/workdir/crates/keel-client)
[INFO] [stderr]    Compiling lsm_kv v0.1.0 (/opt/rustwide/workdir/crates/lsm-kv)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 1m 07s
[INFO] running `Command { std: "docker" "inspect" "6dcc18568ed75de8b951c0d15ac45fded1863c0332b59be081842e92524f9efc", 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" "6dcc18568ed75de8b951c0d15ac45fded1863c0332b59be081842e92524f9efc" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.13s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/keel_api-d326859f0d9a4829)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test tests::a_payload_with_bytes_appended_is_refused ... ok
[INFO] [stdout] test tests::distinct_requests_encode_distinctly ... ok
[INFO] [stdout] test tests::arbitrary_bytes_do_not_panic_the_decoder ... ok
[INFO] [stdout] test tests::an_envelope_round_trips_and_is_distinct_from_its_body ... ok
[INFO] [stdout] test tests::every_peer_message_round_trips ... ok
[INFO] [stdout] test tests::every_response_round_trips ... ok
[INFO] [stdout] test tests::every_request_round_trips ... ok
[INFO] [stdout] test tests::an_oversized_payload_is_refused_before_it_is_parsed ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/keel_bench-ace2380dbe51ee03)
[INFO] [stdout] test tests::envelopes_with_the_same_body_and_different_labels_are_distinct ... ok
[INFO] [stdout] test tests::every_proposal_round_trips ... ok
[INFO] [stdout] test tests::a_truncated_payload_is_refused_rather_than_guessed ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 45 tests
[INFO] [stdout] test failover::tests::a_report_with_too_few_trials_says_its_percentiles_describe_the_draw ... ok
[INFO] [stdout] test histogram::tests::a_weighted_sample_counts_for_every_request_it_stands_for ... ok
[INFO] [stdout] test environment::tests::a_path_that_does_not_exist_is_unknown_rather_than_durable ... ok
[INFO] [stdout] test plot::tests::a_single_point_is_refused_because_it_is_not_a_curve ... ok
[INFO] [stdout] test failover::tests::a_median_that_holds_across_the_halves_is_reported_as_stable ... ok
[INFO] [stdout] test histogram::tests::small_values_are_recorded_exactly ... ok
[INFO] [stdout] test plot::tests::a_series_recorded_out_of_order_draws_the_same_picture ... ok
[INFO] [stdout] test histogram::tests::merging_two_histograms_is_the_same_as_recording_into_one ... ok
[INFO] [stdout] test histogram::tests::a_percentile_is_never_optimistic ... ok
[INFO] [stdout] test histogram::tests::an_empty_histogram_answers_without_dividing_by_zero ... ok
[INFO] [stdout] test plot::tests::markup_in_a_series_name_is_escaped ... ok
[INFO] [stdout] test plot::tests::numbers_are_formatted_by_integer_arithmetic ... ok
[INFO] [stdout] test histogram::tests::the_relative_error_is_bounded_at_every_magnitude ... ok
[INFO] [stdout] test failover::tests::a_summary_counts_each_outcome_and_only_times_the_recoveries ... ok
[INFO] [stdout] test environment::tests::a_temporary_directory_is_on_a_named_filesystem ... ok
[INFO] [stdout] test plot::tests::axes_end_on_round_numbers ... ok
[INFO] [stdout] test plot::tests::the_caption_is_rendered_into_the_image ... ok
[INFO] [stdout] test publishable::tests::a_modified_tree_and_an_unknown_commit_are_both_refused ... ok
[INFO] [stdout] test publishable::tests::full_fsync_is_durable_and_publishable ... ok
[INFO] [stdout] test record::tests::an_admitted_result_is_written_and_says_why_it_is_not_publishable ... ok
[INFO] [stdout] test record::tests::an_unheadlineable_result_repeats_the_qualifier_above_the_numbers ... ok
[INFO] [stdout] test publishable::tests::a_run_without_fsync_is_refused ... ok
[INFO] [stdout] test publishable::tests::a_filesystem_nobody_could_identify_is_refused ... ok
[INFO] [stdout] test plot::tests::the_same_data_renders_the_same_bytes ... ok
[INFO] [stdout] test publishable::tests::a_host_nobody_described_is_refused ... ok
[INFO] [stdout] test publishable::tests::one_run_is_refused_and_three_is_the_floor ... ok
[INFO] [stdout] test publishable::tests::a_run_on_memory_is_refused ... ok
[INFO] [stdout] test publishable::tests::only_the_reference_tier_may_be_headlined ... ok
[INFO] [stdout] test publishable::tests::a_stated_durable_host_with_enough_runs_passes ... ok
[INFO] [stdout] test failover::tests::a_median_that_moves_between_the_halves_is_reported_as_unstable ... ok
[INFO] [stdout] test publishable::tests::an_admitted_run_carries_the_reason_it_cannot_be_published ... ok
[INFO] [stdout] test workload::tests::a_mix_draws_the_proportion_it_names ... ok
[INFO] [stdout] test workload::tests::a_plan_is_a_pure_function_of_its_seed ... ok
[INFO] [stdout] test workload::tests::a_run_that_fell_behind_its_schedule_says_so ... ok
[INFO] [stdout] test record::tests::anything_that_is_not_a_plain_file_name_is_refused ... ok
[INFO] [stdout] test workload::tests::node_addresses_parse_and_bad_ones_are_dropped ... ok
[INFO] [stdout] test workload::tests::keys_are_fixed_width_and_values_are_the_size_asked_for ... ok
[INFO] [stdout] test environment::tests::a_probed_environment_is_stated_and_an_unknown_one_is_not ... ok
[INFO] [stdout] test record::tests::a_publishable_result_is_written_with_its_header_first ... ok
[INFO] [stdout] test environment::tests::the_header_names_the_hardware_and_the_filesystem ... ok
[INFO] [stdout] test workload::tests::a_closed_loop_run_reports_throughput_and_a_latency_distribution ... ok
[INFO] [stdout] test workload::tests::an_open_loop_charges_a_stall_to_every_request_it_delayed ... ok
[INFO] [stdout] test workload::tests::a_deep_pipeline_charges_each_operation_its_own_latency ... ok
[INFO] [stdout] test workload::tests::depth_lifts_the_throughput_ceiling_a_closed_client_imposes ... ok
[INFO] [stdout] test failover::tests::a_trial_that_killed_nothing_is_discarded_rather_than_timed ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 45 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 10.16s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/keel_bench-cf1956aed2c8749a)
[INFO] [stderr]      Running tests/gate.rs (/opt/rustwide/target/release/deps/gate-5d7496822620785a)
[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] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test tmpfs_and_zero_fsync_are_refused_and_their_controls_are_admitted ... ok
[INFO] [stdout] test a_result_cannot_be_written_outside_the_bench_directory ... ok
[INFO] [stdout] test this_host_can_produce_an_exploratory_result ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/keel_chaos-766aa14960de0557)
[INFO] [stdout] test the_tier_is_a_claim_the_caller_makes_and_the_gate_records ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 24 tests
[INFO] [stdout] test clock::tests::the_monotonic_clock_does_not_go_backwards ... ok
[INFO] [stdout] test clock::tests::a_probe_that_merely_waited_does_not_count_as_a_jump ... ok
[INFO] [stdout] test clock::tests::availability_either_finds_a_library_or_says_why_not ... ok
[INFO] [stdout] test schedule::tests::a_hundred_seeds_reach_every_kind_of_fault ... ok
[INFO] [stdout] test nemesis::tests::signalling_something_that_is_not_running_is_an_error_rather_than_a_silent_no_op ... ok
[INFO] [stdout] test nemesis::tests::a_stopped_process_stays_alive_until_it_is_resumed ... ok
[INFO] [stdout] test schedule::tests::events_are_in_time_order ... ok
[INFO] [stdout] test cluster::tests::healing_restores_every_link ... ok
[INFO] [stdout] test schedule::tests::a_seed_determines_the_whole_schedule ... ok
[INFO] [stdout] test cluster::tests::every_ordered_pair_gets_its_own_link ... ok
[INFO] [stdout] test schedule::tests::the_plan_is_printed_before_it_is_injected ... ok
[INFO] [stdout] test cluster::tests::isolating_one_node_leaves_the_rest_of_the_cluster_connected ... ok
[INFO] [stdout] test cluster::tests::a_one_way_isolation_cuts_one_direction_only ... ok
[INFO] [stdout] test schedule::tests::a_split_never_puts_a_majority_on_the_wrong_side ... ok
[INFO] [stdout] test nemesis::tests::a_stopped_process_can_still_be_killed ... ok
[INFO] [stdout] test schedule::tests::every_node_named_is_a_node_that_exists ... ok
[INFO] [stdout] test nemesis::tests::a_killed_process_is_reaped_and_can_be_restarted ... ok
[INFO] [stdout] test cluster::tests::a_split_cuts_across_and_not_within ... ok
[INFO] [stdout] test schedule::tests::every_fault_is_repaired_and_the_cluster_ends_healthy ... ok
[INFO] [stdout] test schedule::tests::a_host_without_clock_control_gets_a_schedule_without_clock_jumps ... ok
[INFO] [stdout] test schedule::tests::clock_jumps_do_occur_when_the_host_allows_them ... ok
[INFO] [stdout] test proxy::tests::a_cut_blocks_the_direction_it_names_and_not_the_other ... ok
[INFO] [stdout] test nemesis::tests::a_process_that_dies_during_startup_is_noticed_immediately ... ok
[INFO] [stdout] test nemesis::tests::a_process_that_never_gets_ready_is_reported_rather_than_waited_on_forever ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 24 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/keel_chaos-6a11f2338b58fc61)
[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/real_cluster.rs (/opt/rustwide/target/release/deps/real_cluster-3726b09b94e75e4b)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test a_one_way_partition_does_not_stop_the_majority ... ok
[INFO] [stdout] test a_killed_node_rejoins_and_the_cluster_keeps_its_acknowledged_writes ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/keel_client-2b7e7ca9592826dc)
[INFO] [stdout] test a_cluster_survives_a_partition_a_pause_and_a_kill ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.23s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 18 tests
[INFO] [stdout] test history::tests::an_operation_has_an_invocation_and_a_completion ... ok
[INFO] [stdout] test history::tests::a_history_taken_mid_flight_says_how_many_are_pending ... ok
[INFO] [stdout] test history::tests::a_lost_answer_is_unknown_rather_than_refused ... ok
[INFO] [stdout] test history::tests::the_rendering_is_one_line_per_operation ... ok
[INFO] [stdout] test history::tests::keys_and_values_are_rendered_as_bytes_rather_than_text ... ok
[INFO] [stdout] test pipeline::tests::a_full_pipeline_refuses_the_next_submission ... ok
[INFO] [stdout] test history::tests::an_unanswered_operation_carries_no_result ... ok
[INFO] [stdout] test pipeline::tests::a_redirect_resends_the_same_session_pair_under_the_same_label ... ok
[INFO] [stdout] test pipeline::tests::a_registration_frees_its_slot_without_troubling_the_caller ... ok
[INFO] [stdout] test pipeline::tests::finishing_frees_the_slot_and_the_sequence_number_moves_on ... ok
[INFO] [stdout] test history::tests::merged_histories_share_an_origin_and_come_out_in_order ... ok
[INFO] [stdout] test transport::tests::a_frame_split_across_reads_survives_the_poll_that_found_half_of_it ... ok
[INFO] [stdout] test pipeline::tests::no_two_outstanding_requests_share_a_session ... ok
[INFO] [stdout] test pipeline::tests::a_request_past_its_deadline_is_reported_as_a_timeout ... ok
[INFO] [stdout] test history::tests::a_read_records_what_it_returned ... ok
[INFO] [stdout] test transport::tests::several_answers_in_one_read_come_out_one_at_a_time ... ok
[INFO] [stdout] test transport::tests::a_round_trip_keeps_waiting_after_a_nonblocking_empty_poll ... ok
[INFO] [stdout] test transport::tests::a_round_trip_skips_answers_that_are_not_its_own ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 18 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.21s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/kv.rs (/opt/rustwide/target/release/deps/kv-59e847b10d9ef9f2)
[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/cluster.rs (/opt/rustwide/target/release/deps/cluster-cbb4932fd2ea8b63)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test a_misconfigured_node_refuses_to_start ... ok
[INFO] [stdout] test writes_survive_a_leader_being_killed ... ok
[INFO] [stdout] test operator_commands_transfer_leadership_and_force_a_checkpoint ... ok
[INFO] [stdout] test a_client_records_a_history_a_checker_can_read ... ok
[INFO] [stdout] test a_retry_storm_across_forced_failover_applies_the_command_exactly_once ... ok
[INFO] [stdout] test a_three_node_cluster_serves_traffic ... ok
[INFO] [stdout] test two_nodes_join_under_load_and_the_removed_leader_is_replaced ... ok
[INFO] [stdout] test an_operator_adds_promotes_and_removes_a_routed_learner ... ok
[INFO] [stdout] test a_pipeline_keeps_many_requests_in_flight_and_applies_each_once ... ok
[INFO] [stdout] test a_real_follower_resumes_and_installs_a_snapshot_after_it_is_killed_mid_stream ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/keel_fuzz-2293a2ecc4031e25)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 12.33s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/smoke.rs (/opt/rustwide/target/release/deps/smoke-06111d673c174b2c)
[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 4 tests
[INFO] [stdout] test every_target_is_named_once ... ok
[INFO] [stdout] test a_corrupted_record_is_rejected ... ok
[INFO] [stdout] test a_smoke_run_is_a_pure_function_of_its_seed ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/keel_log-fff02eb5ab8632f8)
[INFO] [stdout] test every_target_survives_a_smoke_run ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.06s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 24 tests
[INFO] [stdout] test fold::tests::a_compacted_log_starts_at_its_floor_rather_than_at_index_one ... ok
[INFO] [stdout] test fold::tests::a_snapshot_at_the_existing_floor_is_not_a_conflict ... ok
[INFO] [stdout] test fold::tests::a_gap_in_the_stream_is_refused ... ok
[INFO] [stdout] test fold::tests::a_commit_index_the_tail_took_with_it_is_clamped ... ok
[INFO] [stdout] test fold::tests::a_snapshot_that_conflicts_with_the_log_discards_all_of_it ... ok
[INFO] [stdout] test fold::tests::the_last_hard_state_wins ... ok
[INFO] [stdout] test fold::tests::entries_accumulate_in_file_order ... ok
[INFO] [stdout] test record::tests::a_zero_length_is_the_end_of_the_written_region ... ok
[INFO] [stdout] test fold::tests::entries_that_do_not_continue_the_log_are_refused ... ok
[INFO] [stdout] test record::tests::an_implausible_length_is_not_trusted ... ok
[INFO] [stdout] test record::tests::a_flipped_byte_anywhere_in_the_frame_is_caught ... ok
[INFO] [stdout] test record::tests::a_half_written_header_degrades_either_way_round ... ok
[INFO] [stdout] test record::tests::a_partial_header_at_the_end_is_a_tear ... ok
[INFO] [stdout] test record::tests::a_truncated_body_stops_at_the_frame_that_does_not_fit ... ok
[INFO] [stdout] test record::tests::an_unknown_kind_that_checksums_is_an_error_not_a_torn_tail ... ok
[INFO] [stdout] test fold::tests::a_truncate_drops_the_suffix_and_the_replacement_follows_it ... ok
[INFO] [stdout] test fold::tests::a_snapshot_drops_the_prefix_it_covers ... ok
[INFO] [stdout] test fold::tests::a_snapshot_past_the_log_leaves_nothing_and_still_sets_the_floor ... ok
[INFO] [stdout] test fold::tests::a_segment_header_folds_to_nothing ... ok
[INFO] [stdout] test record::tests::every_record_kind_round_trips ... ok
[INFO] [stdout] test record::tests::slack_at_the_end_of_a_full_segment_is_not_a_tear ... ok
[INFO] [stdout] test fold::tests::a_snapshot_below_the_floor_already_folded_does_not_move_it_back ... ok
[INFO] [stdout] test fold::tests::a_snapshot_read_after_the_entries_it_covers_still_sets_the_floor ... ok
[INFO] [stdout] test fold::tests::a_snapshot_the_log_agrees_with_only_compacts ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 24 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/fs_conformance.rs (/opt/rustwide/target/release/deps/fs_conformance-3595d86abb71a779)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/recovery.rs (/opt/rustwide/target/release/deps/recovery-91d60ad907e5796e)
[INFO] [stdout] test the_real_filesystem_conforms ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 20 tests
[INFO] [stdout] test a_second_handle_on_a_live_directory_is_refused ... ok
[INFO] [stdout] test a_clean_shutdown_recovers_everything ... ok
[INFO] [stdout] test an_empty_directory_opens_clean ... ok
[INFO] [stdout] test a_sync_covers_exactly_what_was_written_before_it ... ok
[INFO] [stdout] test a_record_larger_than_the_limit_is_refused ... ok
[INFO] [stdout] test a_hole_a_crash_left_is_not_read_as_the_clean_end_it_looks_like ... ok
[INFO] [stdout] test a_truncation_survives_a_restart ... ok
[INFO] [stdout] test a_large_ready_is_split_into_recoverable_records ... ok
[INFO] [stdout] test a_snapshot_replaces_the_log_it_covers ... ok
[INFO] [stdout] test a_commit_index_the_tear_took_with_it_is_clamped_and_reported ... ok
[INFO] [stdout] test a_torn_trailing_record_is_discarded_and_the_prefix_survives ... ok
[INFO] [stdout] test a_segment_whose_header_never_landed_is_discarded ... ok
[INFO] [stdout] test preallocation_leaves_the_segment_at_its_full_size_from_the_start ... ok
[INFO] [stdout] test entries_that_do_not_continue_the_log_are_refused_at_the_door ... ok
[INFO] [stdout] test a_torn_record_cannot_be_resurrected_by_a_shorter_one_written_over_it ... ok
[INFO] [stdout] test a_headerless_segment_below_the_end_is_also_refused ... ok
[INFO] [stdout] test a_record_above_a_hole_is_erased_rather_than_read_on_the_next_open ... ok
[INFO] [stdout] test damage_below_the_end_of_the_log_is_refused_rather_than_guessed_past ... ok
[INFO] [stdout] test compaction_drops_only_segments_the_snapshot_covers ... ok
[INFO] [stdout] test the_log_rolls_segments_and_reads_back_across_them ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/keel_maelstrom-1ab23e1c7e46eedc)
[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] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/keel_net-3c4e5e72bafe1e10)
[INFO] [stdout] running 8 tests
[INFO] [stdout] test frame::tests::a_frame_split_across_arbitrary_reads_is_still_one_frame ... ok
[INFO] [stdout] test frame::tests::an_oversized_length_is_refused_before_anything_is_reserved ... ok
[INFO] [stdout] test frame::tests::several_frames_in_one_read_come_back_in_order ... ok
[INFO] [stdout] test frame::tests::encoding_refuses_what_the_reader_would_refuse ... ok
[INFO] [stdout] test frame::tests::a_frame_round_trips ... ok
[INFO] [stdout] test frame::tests::a_payload_at_exactly_the_limit_is_accepted ... ok
[INFO] [stdout] test tcp::tests::a_connection_lost_during_flush_is_reported_by_the_next_send ... ok
[INFO] [stderr]      Running tests/transport_conformance.rs (/opt/rustwide/target/release/deps/transport_conformance-e77af94a84af62c5)
[INFO] [stdout] test frame::tests::an_empty_frame_is_a_frame ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test an_oversized_frame_is_refused_rather_than_cut_down ... ok
[INFO] [stdout] test a_message_round_trips_identically_through_both_transports ... ok
[INFO] [stdout] test loopback_meets_the_transport_contract ... ok
[INFO] [stdout] test tcp_meets_the_transport_contract ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/keel_node-17dcfb08d34bd4ab)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test snapshots::tests::a_checkpoint_is_due_by_entries_applied_rather_than_by_time ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/group_commit.rs (/opt/rustwide/target/release/deps/group_commit-3e8d3f3482901270)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test a_single_proposal_is_not_held_waiting_for_company ... ok
[INFO] [stdout] test the_same_hundred_driven_one_at_a_time_cost_a_hundred_of_each ... ok
[INFO] [stdout] test a_hundred_queued_proposals_cost_one_append_and_one_sync ... ok
[INFO] [stdout] test batching_changes_the_cost_and_not_the_outcome ... ok
[INFO] [stderr]      Running tests/snapshot_end_to_end.rs (/opt/rustwide/target/release/deps/snapshot_end_to_end-62b3790aa7e484fc)
[INFO] [stdout] test a_write_replicates_to_a_peer_before_it_commits ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.67s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test checkpoints_are_due_by_entries_applied ... ok
[INFO] [stdout] test a_receiver_that_has_verified_nothing_gets_the_whole_snapshot ... ok
[INFO] [stdout] test a_transfer_interrupted_repeatedly_still_converges ... ok
[INFO] [stdout] test a_fresh_node_is_brought_up_by_a_snapshot_that_is_killed_mid_stream ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 11.51s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/keel_raft-427b8efca342dc9c)
[INFO] [stdout] 
[INFO] [stdout] running 58 tests
[INFO] [stdout] test audit::tests::a_ready_with_nothing_to_persist_may_send_immediately ... ok
[INFO] [stdout] test audit::tests::several_readys_may_be_outstanding_at_once ... ok
[INFO] [stdout] test confchange::tests::adding_a_voter_enters_joint ... ok
[INFO] [stdout] test audit::tests::acknowledging_a_ready_nobody_issued_is_caught ... ok
[INFO] [stdout] test audit::tests::applying_before_sending_is_caught ... ok
[INFO] [stdout] test audit::tests::acknowledging_before_sending_is_caught ... ok
[INFO] [stdout] test confchange::tests::cannot_remove_the_last_voter ... ok
[INFO] [stdout] test audit::tests::sending_before_persisting_is_caught ... ok
[INFO] [stdout] test confchange::tests::learner_only_change_skips_the_joint_phase ... ok
[INFO] [stdout] test confchange::tests::leaving_when_not_joint_is_rejected ... ok
[INFO] [stdout] test audit::tests::a_host_that_claims_to_be_sequential_is_held_to_it ... ok
[INFO] [stdout] test log::tests::a_conflict_hint_names_the_first_index_of_its_term ... ok
[INFO] [stdout] test log::tests::is_up_to_date_prefers_the_later_term_then_the_longer_log ... ok
[INFO] [stdout] test confchange::tests::promoting_a_learner_moves_it_to_voters ... ok
[INFO] [stdout] test audit::tests::an_applied_watermark_that_goes_backwards_is_caught ... ok
[INFO] [stdout] test log::tests::malformed_append_with_a_gap_is_rejected_without_mutating_the_log ... ok
[INFO] [stdout] test log::tests::a_log_that_is_merely_too_short_reports_no_conflict_term ... ok
[INFO] [stdout] test log::tests::compact_prefix_will_not_pass_applied ... ok
[INFO] [stdout] test audit::tests::one_ready_may_be_acknowledged_twice ... ok
[INFO] [stdout] test log::tests::everything_recovered_off_disk_is_already_durable ... ok
[INFO] [stdout] test log::tests::last_index_of_term_falls_back_to_the_snapshot ... ok
[INFO] [stdout] test log::tests::slice_is_capped_by_count_and_by_bytes ... ok
[INFO] [stdout] test log::tests::next_committed_stops_at_the_persist_watermark ... ok
[INFO] [stdout] test log::tests::a_truncation_pulls_both_watermarks_back ... ok
[INFO] [stdout] test log::tests::re_delivered_entries_leave_the_watermarks_alone ... ok
[INFO] [stdout] test log::tests::commit_to_never_moves_backwards_and_never_passes_the_log ... ok
[INFO] [stdout] test log::tests::restore_snapshot_discards_a_conflicting_log ... ok
[INFO] [stdout] test log::tests::slice_returns_one_entry_even_when_it_blows_the_byte_budget ... ok
[INFO] [stdout] test log::tests::the_persist_watermark_only_moves_forward ... ok
[INFO] [stdout] test quorum::tests::committed_index_is_majority_replicated ... ok
[INFO] [stdout] test quorum::tests::joint_requires_both_halves ... ok
[INFO] [stdout] test quorum::tests::quorums_of_both_halves_always_intersect ... ok
[INFO] [stdout] test rng::tests::different_seeds_diverge ... ok
[INFO] [stdout] test quorum::tests::joint_commit_takes_the_lower_half ... ok
[INFO] [stdout] test quorum::tests::majority_quorum_sizes ... ok
[INFO] [stdout] test quorum::tests::vote_result_transitions ... ok
[INFO] [stdout] test rng::tests::range_stays_in_bounds ... ok
[INFO] [stdout] test confchange::tests::leaving_joint_drops_the_outgoing_half ... ok
[INFO] [stdout] test audit::tests::the_documented_order_passes ... ok
[INFO] [stdout] test confchange::tests::cannot_nest_configuration_changes ... ok
[INFO] [stdout] test log::tests::restore_snapshot_keeps_a_log_that_already_contains_it ... ok
[INFO] [stdout] test log::tests::set_persisted_never_outruns_the_log ... ok
[INFO] [stdout] test log::tests::a_restored_commit_index_cannot_outrun_a_truncated_tail ... ok
[INFO] [stdout] test rng::tests::same_seed_same_stream ... ok
[INFO] [stdout] test tracker::tests::a_freed_window_stops_reporting_its_bytes ... ok
[INFO] [stdout] test log::tests::truncating_committed_history_is_a_bug_not_a_condition - should panic ... ok
[INFO] [stdout] test tracker::tests::a_probe_never_backs_off_below_what_is_already_matched ... ok
[INFO] [stdout] test tracker::tests::a_probe_rejection_backs_off_to_the_conflict_hint ... ok
[INFO] [stdout] test tracker::tests::an_acknowledgement_below_the_window_retires_nothing ... ok
[INFO] [stdout] test tracker::tests::an_acknowledgement_retires_every_message_at_or_below_it ... ok
[INFO] [stdout] test tracker::tests::the_window_caps_accumulated_bytes ... ok
[INFO] [stdout] test tracker::tests::the_window_caps_message_count ... ok
[INFO] [stdout] test tracker::tests::changing_state_empties_the_window ... ok
[INFO] [stdout] test tracker::tests::matched_never_moves_backwards ... ok
[INFO] [stdout] test tracker::tests::one_slot_can_be_released_without_an_acknowledgement ... ok
[INFO] [stdout] test tracker::tests::a_snapshot_in_flight_pauses_replication_outright ... ok
[INFO] [stdout] test tracker::tests::a_stale_rejection_in_replicate_is_ignored ... ok
[INFO] [stdout] test tracker::tests::probe_sends_one_message_at_a_time ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 58 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cluster_behaviour.rs (/opt/rustwide/target/release/deps/cluster_behaviour-92653132eb72f619)
[INFO] [stdout] 
[INFO] [stdout] running 22 tests
[INFO] [stdout] test a_checkpoint_bounds_what_the_core_holds_in_memory ... ok
[INFO] [stdout] test a_stale_installed_snapshot_is_refused ... ok
[INFO] [stdout] test a_fresh_leader_parks_reads_until_its_no_op_commits ... ok
[INFO] [stdout] test a_leader_that_removes_itself_steps_down ... ok
[INFO] [stdout] test a_lease_read_is_answered_without_a_round_trip ... ok
[INFO] [stdout] test a_proposal_to_a_follower_is_refused_with_a_redirect_hint ... ok
[INFO] [stdout] test adding_voters_goes_through_a_joint_configuration_and_leaves_it_automatically ... ok
[INFO] [stdout] test lease_configuration_does_not_bypass_the_no_op_park ... ok
[INFO] [stdout] test a_second_configuration_change_is_refused_while_one_is_in_flight ... ok
[INFO] [stdout] test read_index_returns_an_index_at_or_past_the_latest_commit ... ok
[INFO] [stdout] test a_snapshot_offer_carries_the_checkpointed_configuration ... ok
[INFO] [stdout] test lease_reads_are_only_valid_while_the_lease_holds ... ok
[INFO] [stdout] test stepping_down_a_follower_does_nothing ... ok
[INFO] [stdout] test a_stale_checkpoint_is_refused ... ok
[INFO] [stdout] test a_leader_told_to_step_down_stops_leading_without_moving_the_term ... ok
[INFO] [stdout] test writes_keep_flowing_during_a_membership_change ... ok
[INFO] [stdout] test writes_replicate_to_every_follower_in_order ... ok
[INFO] [stdout] test a_learner_catches_up_without_affecting_quorum ... ok
[INFO] [stdout] test a_checkpoint_above_what_was_applied_is_refused ... ok
[INFO] [stdout] test a_follower_forwards_reads_to_the_leader ... ok
[INFO] [stdout] test a_step_down_fails_the_reads_it_can_no_longer_confirm ... ok
[INFO] [stdout] test a_follower_that_missed_thousands_of_entries_catches_up_without_a_snapshot ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 22 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/determinism.rs (/opt/rustwide/target/release/deps/determinism-fb4075bd7150fa09)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test identical_inputs_produce_identical_outputs ... ok
[INFO] [stdout] test the_core_has_no_io_dependencies ... ok
[INFO] [stdout] test a_whole_cluster_replays_identically ... ok
[INFO] [stdout] test the_seed_is_the_only_source_of_variation ... 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/election.rs (/opt/rustwide/target/release/deps/election-655fadf0c5eb4342)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test leader_transfer_moves_leadership_within_one_election_timeout ... ok
[INFO] [stdout] test leader_without_a_quorum_steps_down ... ok
[INFO] [stdout] test three_nodes_elect_exactly_one_leader ... ok
[INFO] [stdout] test majority_partition_elects_a_new_leader_and_the_minority_does_not ... ok
[INFO] [stdout] test pre_vote_stops_a_rejoining_node_from_deposing_the_leader ... ok
[INFO] [stdout] test single_node_elects_itself ... ok
[INFO] [stdout] test a_node_with_a_stale_log_cannot_win ... ok
[INFO] [stderr]      Running tests/paper_scenarios.rs (/opt/rustwide/target/release/deps/paper_scenarios-c703d6d018570075)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test figure_7_conflict_hints_beat_one_index_at_a_time ... ok
[INFO] [stdout] test figure_8_leader_does_not_commit_an_old_term_entry_by_counting ... ok
[INFO] [stdout] test figure_7_all_divergent_followers_converge_on_the_leader ... ok
[INFO] [stdout] test a_restart_does_not_re_apply_what_the_state_machine_already_applied ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/keel_rand-b50044c97a9ddcf7)
[INFO] [stdout] test an_applied_index_above_the_commit_index_is_clamped ... ok
[INFO] [stdout] test vote_is_granted_only_to_an_up_to_date_candidate ... ok
[INFO] [stdout] test a_node_votes_at_most_once_per_term ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test tests::the_label_decides_the_stream_not_the_order ... ok
[INFO] [stdout] test tests::pick_returns_none_only_for_an_empty_slice ... ok
[INFO] [stdout] test tests::chance_is_roughly_its_percentage ... ok
[INFO] [stdout] test tests::an_empty_range_yields_its_low_bound ... ok
[INFO] [stdout] test tests::a_seed_determines_the_whole_sequence ... ok
[INFO] [stdout] test tests::range_stays_inside_its_bounds ... ok
[INFO] [stdout] test tests::a_new_split_does_not_disturb_an_existing_one ... ok
[INFO] [stdout] test tests::chance_covers_both_certainties ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/keel_server-d7a6ec1f684e2380)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 26 tests
[INFO] [stdout] test clients::tests::a_connection_that_has_been_answered_is_still_read_without_blocking ... ok
[INFO] [stdout] test clients::tests::the_outstanding_count_returns_to_zero_however_a_request_is_answered ... ok
[INFO] [stdout] test metrics::tests::the_parser_refuses_a_sample_with_no_type - should panic ... ok
[INFO] [stdout] test metrics::tests::the_output_parses_as_exposition ... ok
[INFO] [stdout] test status::tests::a_failure_message_with_quotes_and_newlines_is_escaped ... ok
[INFO] [stdout] test clients::tests::a_dropped_connection_leaves_nothing_behind_in_the_tables ... ok
[INFO] [stdout] test metrics::tests::the_body_ends_with_a_newline ... ok
[INFO] [stdout] test clients::tests::a_request_split_inside_its_prefix_is_not_lost ... ok
[INFO] [stdout] test clients::tests::a_read_is_answered_under_its_label_once_its_index_is_applied ... ok
[INFO] [stdout] test metrics::tests::whole_numbers_render_whole ... ok
[INFO] [stdout] test clients::tests::a_registration_answer_without_a_nonce_answers_nobody ... ok
[INFO] [stdout] test clients::tests::several_requests_on_one_connection_are_answered_by_label_in_any_order ... ok
[INFO] [stdout] test clients::tests::a_write_is_answered_by_its_session_pair ... ok
[INFO] [stdout] test clients::tests::a_registration_answer_for_an_unknown_nonce_answers_nobody ... ok
[INFO] [stdout] test clients::tests::two_concurrent_registrations_are_answered_by_nonce_and_not_by_arrival ... ok
[INFO] [stdout] test metrics::tests::nothing_to_export_is_an_empty_body_rather_than_a_malformed_one ... ok
[INFO] [stdout] test metrics::tests::a_histogram_has_cumulative_buckets_and_inf_sum_and_count ... ok
[INFO] [stdout] test clients::tests::sweeping_a_displaced_request_leaves_the_one_that_displaced_it_findable ... ok
[INFO] [stdout] test tests::every_operator_command_is_parsed_strictly ... ok
[INFO] [stdout] test tests::status_and_metrics_remain_get_only ... ok
[INFO] [stdout] test status::tests::a_healthy_status_is_well_formed ... ok
[INFO] [stdout] test status::tests::a_node_with_no_leader_says_null_rather_than_zero ... ok
[INFO] [stdout] test tests::a_response_carries_the_length_of_its_body ... ok
[INFO] [stdout] test status::tests::every_sync_mode_has_a_name_and_durable_modes_are_identified ... ok
[INFO] [stdout] test tests::the_two_endpoints_are_recognised_and_nothing_else_is ... ok
[INFO] [stdout] test status::tests::the_well_formed_check_rejects_what_it_should ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/keel-admin.rs (/opt/rustwide/target/release/deps/keel_admin-b8b6c010c46925d3)
[INFO] [stdout] test result: ok. 26 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/keel_server-e8e3aca101caf406)
[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] [stdout] 
[INFO] [stderr]      Running tests/admin_surface.rs (/opt/rustwide/target/release/deps/admin_surface-103cc333cddff274)
[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 3 tests
[INFO] [stdout] test a_node_that_is_not_durable_says_so ... ok
[INFO] [stdout] test a_node_reports_itself_over_a_real_socket ... ok
[INFO] [stdout] test the_ready_file_is_written_whole_or_not_at_all ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/keel_sim-21834d6fab08bedc)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test digest::rebase_tests::adopting_a_snapshot_that_replaces_history_reports_what_it_replaced ... ok
[INFO] [stdout] test digest::rebase_tests::a_rebased_digest_agrees_with_one_that_was_never_compacted ... ok
[INFO] [stdout] test digest::rebase_tests::adopting_a_snapshot_takes_the_digest_with_the_floor ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/keel_sim-2ec5b5f7f50e0b80)
[INFO] [stdout] test digest::rebase_tests::a_floor_nobody_carried_is_reported_rather_than_invented ... ok
[INFO] [stdout] test digest::rebase_tests::adopting_a_snapshot_the_log_already_agrees_with_discards_nothing ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/fault_fs.rs (/opt/rustwide/target/release/deps/fault_fs-3804556bbf58e9ec)
[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 25 tests
[INFO] [stdout] test a_crash_releases_every_lock_the_way_a_killed_process_does ... ok
[INFO] [stdout] test a_directory_entry_is_not_durable_until_its_directory_is_synced ... ok
[INFO] [stdout] test a_crash_with_nothing_pending_changes_nothing ... ok
[INFO] [stdout] test a_file_with_nothing_staged_does_not_shift_the_tear_stream ... ok
[INFO] [stdout] test a_four_kilobyte_sector_over_a_one_kilobyte_segment_can_never_tear ... ok
[INFO] [stdout] test a_visible_read_is_the_durable_bytes_with_the_pending_ones_folded_on_top ... ok
[INFO] [stdout] test a_write_inside_one_sector_lands_whole_or_not_at_all ... ok
[INFO] [stdout] test a_crash_takes_back_every_write_no_sync_covered ... ok
[INFO] [stdout] test a_barrier_sync_does_not_survive_a_crash ... ok
[INFO] [stdout] test a_crash_counts_the_shapes_it_produced ... ok
[INFO] [stdout] test a_crash_that_lands_no_sector_leaves_exactly_what_a_crash_always_left ... ok
[INFO] [stdout] test a_sync_of_one_file_does_not_make_another_durable ... ok
[INFO] [stdout] test a_pending_allocation_is_all_or_nothing ... ok
[INFO] [stdout] test a_sector_a_write_only_partly_covers_keeps_the_bytes_it_did_not_touch ... ok
[INFO] [stdout] test two_disks_with_the_same_seed_tear_identically ... ok
[INFO] [stdout] test the_fault_injecting_filesystem_conforms_while_tearing ... ok
[INFO] [stdout] test a_write_that_straddles_a_sector_boundary_can_land_tail_first ... ok
[INFO] [stdout] test an_earlier_write_can_be_lost_while_a_later_one_survives ... ok
[INFO] [stdout] test an_acknowledged_but_lost_fsync_is_exposed_by_the_next_crash ... ok
[INFO] [stdout] test an_unlink_the_directory_never_synced_comes_back ... ok
[INFO] [stdout] test the_fault_injecting_filesystem_conforms ... ok
[INFO] [stdout] test an_unlink_survives_once_the_directory_is_synced ... ok
[INFO] [stdout] test the_cut_falls_at_a_multiple_of_the_sector_size_from_the_start_of_the_file ... ok
[INFO] [stdout] test the_durable_digest_notices_a_byte ... ok
[INFO] [stdout] test a_write_that_straddles_a_sector_boundary_can_land_head_first ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 25 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/log_over_faultfs.rs (/opt/rustwide/target/release/deps/log_over_faultfs-9875635d00ffa7d4)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test a_second_handle_on_a_live_simulated_log_is_refused ... ok
[INFO] [stdout] test a_crash_loses_exactly_what_was_never_synced ... ok
[INFO] [stdout] test a_truncation_survives_a_crash_replacement_and_all ... ok
[INFO] [stdout] test the_simulated_disk_is_the_only_place_the_log_writes ... ok
[INFO] [stdout] test a_clean_shutdown_recovers_everything_through_the_real_parser ... ok
[INFO] [stdout] test a_crash_releases_the_lock_so_the_node_can_reopen_its_own_log ... ok
[INFO] [stdout] test a_log_opens_on_an_empty_simulated_disk ... ok
[INFO] [stdout] test the_log_rolls_segments_and_recovers_across_them ... ok
[INFO] [stdout] test a_log_that_crashed_can_always_be_reopened ... ok
[INFO] [stdout] test a_rollover_is_durable_before_the_log_continues_past_it ... ok
[INFO] [stdout] test a_crash_that_leaves_a_record_above_a_hole_still_reports_bytes_discarded ... ok
[INFO] [stdout] test a_log_whose_crash_left_a_hole_never_reads_the_leftover_as_a_record ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/simulation.rs (/opt/rustwide/target/release/deps/simulation-efd4656a785955d9)
[INFO] [stdout] 
[INFO] [stdout] running 29 tests
[INFO] [stdout] test every_roll_selects_an_action_whatever_the_weights_are ... ok
[INFO] [stdout] test clock_drift_is_off_unless_a_profile_asks_for_it ... ok
[INFO] [stdout] test the_cluster_makes_progress ... ok
[INFO] [stdout] test different_seeds_produce_different_runs ... ok
[INFO] [stdout] test the_default_weights_reproduce_the_ranges_they_replaced ... ok
[INFO] [stdout] test heavy_faults_actually_reach_the_interesting_states ... ok
[INFO] [stdout] test the_membership_profile_is_inert_at_three_nodes ... ok
[INFO] [stdout] test the_model_oracle_actually_applies_the_log ... ok
[INFO] [stdout] test the_profile_list_and_the_named_constructor_cannot_drift ... ok
[INFO] [stdout] test the_profiles_that_predate_membership_changes_propose_none ... ok
[INFO] [stdout] test no_leader_ever_commits_an_old_term_entry_by_counting ... ok
[INFO] [stdout] test seeds_pass_when_faults_target_the_figure_8_window ... ok
[INFO] [stdout] test the_profiles_that_predate_reads_still_issue_none ... ok
[INFO] [stdout] test the_simulator_cannot_reach_a_socket_or_a_storage_engine ... ok
[INFO] [stdout] test seeds_pass_under_heavy_faults ... ok
[INFO] [stdout] test the_random_client_workload_records_a_deterministic_history ... ok
[INFO] [stdout] test seeds_pass_under_the_default_profile ... ok
[INFO] [stdout] test a_seed_replays_exactly ... ok
[INFO] [stdout] test restarts_recover_across_more_than_one_segment ... ok
[INFO] [stdout] test membership_actually_changes_and_the_joint_configuration_is_actually_open ... ok
[INFO] [stdout] test snapshots_are_actually_taken_streamed_and_resumed ... ok
[INFO] [stdout] test the_committed_profiles_still_replay_to_their_pinned_fingerprints ... ok
[INFO] [stdout] test the_state_machine_is_actually_exercised ... ok
[INFO] [stdout] test reads_are_actually_issued_confirmed_and_answered ... ok
[INFO] [stdout] test a_tear_meets_a_partition ... ok
[INFO] [stdout] test heavy_disk_faults_actually_tear_the_log ... ok
[INFO] [stdout] test leases_are_only_safe_inside_their_clock_assumption ... ok
[INFO] [stdout] test pre_vote_stops_a_partitioned_node_from_burning_terms ... ok
[INFO] [stdout] test the_membership_profile_never_takes_the_voter_set_below_three ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 29 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 21.07s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/keel_sm-9e7e8d5a6095db32)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test session::tests::a_duplicate_with_no_cached_response_is_too_old ... ok
[INFO] [stdout] test session::tests::a_fresh_sequence_is_fresh_and_the_last_one_is_a_duplicate ... ok
[INFO] [stdout] test session::tests::session_keys_sort_by_client_id ... ok
[INFO] [stdout] test session::tests::the_upper_bound_of_a_prefix_excludes_everything_below_it ... ok
[INFO] [stdout] test store::tests::an_empty_store_round_trips ... ok
[INFO] [stdout] test store::tests::a_store_round_trips_through_its_bytes ... ok
[INFO] [stdout] test store::tests::trailing_bytes_after_a_snapshot_are_refused ... ok
[INFO] [stdout] test transfer::tests::a_chunk_verifies_against_its_own_bytes ... ok
[INFO] [stdout] test transfer::tests::a_name_that_could_escape_the_directory_is_refused ... ok
[INFO] [stdout] test store::tests::every_truncation_of_a_snapshot_is_refused ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/kill_during_apply.rs (/opt/rustwide/target/release/deps/kill_during_apply-3b1c4fb35272e1d4)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test a_kill_mid_apply_never_double_applies_or_regresses ... ok
[INFO] [stderr]      Running tests/snapshot_transfer.rs (/opt/rustwide/target/release/deps/snapshot_transfer-551741f203f668ed)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 4.57s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test a_chunk_that_names_an_escape_is_refused ... ok
[INFO] [stdout] test a_killed_receiver_recovers_its_verified_file_positions ... ok
[INFO] [stdout] test a_corrupt_chunk_is_rejected_and_the_position_does_not_advance ... ok
[INFO] [stdout] test an_incomplete_transfer_refuses_to_publish ... ok
[INFO] [stdout] test a_snapshot_whose_digest_disagrees_is_thrown_away_rather_than_published ... ok
[INFO] [stdout] test a_transfer_cut_at_any_chunk_resumes_and_completes ... ok
[INFO] [stdout] test a_transfer_installs_a_snapshot_that_matches_the_senders_digest ... ok
[INFO] [stdout] test publishing_over_an_existing_snapshot_replaces_it_whole ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 20.98s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/state_machine.rs (/opt/rustwide/target/release/deps/state_machine-efe6b8458c08747d)
[INFO] [stdout] running 23 tests
[INFO] [stdout] test a_client_registered_in_a_batch_can_be_used_later_in_the_same_batch ... ok
[INFO] [stdout] test incrementing_a_key_that_is_not_a_counter_is_a_client_error ... ok
[INFO] [stdout] test a_batch_leaves_the_applied_index_at_its_highest_entry ... ok
[INFO] [stdout] test replaying_the_log_below_the_watermark_applies_nothing ... ok
[INFO] [stdout] test re_registering_with_the_same_nonce_returns_the_same_identity ... ok
[INFO] [stdout] test a_batch_of_entries_means_what_applying_them_one_at_a_time_means ... ok
[INFO] [stdout] test a_scan_returns_user_keys_and_not_the_machines_own ... ok
[INFO] [stdout] test a_sequence_below_the_floor_is_refused_rather_than_guessed_at ... ok
[INFO] [stdout] test a_duplicate_returns_the_cached_response_and_writes_nothing ... ok
[INFO] [stdout] test identities_are_a_function_of_the_log ... ok
[INFO] [stdout] test a_retry_storm_applies_each_command_exactly_once ... ok
[INFO] [stdout] test a_session_expires_on_the_leaders_clock_and_only_on_it ... ok
[INFO] [stdout] test the_state_digest_covers_the_session_table ... ok
[INFO] [stdout] test every_idle_session_is_collected_even_when_the_table_is_far_wider_than_one_sweep ... ok
[INFO] [stdout] test two_machines_fed_the_same_entries_expire_the_same_sessions ... ok
[INFO] [stdout] test the_memory_store_meets_the_contract ... ok
[INFO] [stdout] test a_command_from_an_expired_session_is_refused ... ok
[INFO] [stdout] test compare_and_swap_takes_effect_only_when_it_matches ... ok
[INFO] [stdout] test a_retry_against_a_restored_checkpoint_still_applies_once ... ok
[INFO] [stdout] test a_restart_recovers_the_applied_index_from_the_store ... ok
[INFO] [stdout] test both_stores_apply_the_same_log_to_the_same_state ... ok
[INFO] [stdout] test a_checkpoint_carries_the_sessions_as_well_as_the_data ... ok
[INFO] [stdout] test the_lsm_store_meets_the_same_contract ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 23 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.40s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/lsm_kv-adb477142771f8ac)
[INFO] [stdout] 
[INFO] [stdout] running 104 tests
[INFO] [stdout] test bloom::tests::no_false_negatives ... ok
[INFO] [stdout] test bloom::tests::false_positive_rate_is_near_target ... ok
[INFO] [stdout] test bloom::tests::encode_decode_round_trip ... ok
[INFO] [stdout] test bloom::tests::empty_filter_is_valid ... ok
[INFO] [stdout] test bloom::tests::decode_rejects_short_buffer ... ok
[INFO] [stdout] test compaction::tests::tombstone_retained_when_not_oldest_run ... ok
[INFO] [stdout] test compaction::tests::snapshot_horizon_preserves_old_versions ... ok
[INFO] [stdout] test compaction::tests::no_snapshot_collapses_to_newest_version ... ok
[INFO] [stdout] test compaction::tests::disjoint_keys_all_survive ... ok
[INFO] [stdout] test compaction::tests::newest_input_wins_on_duplicate_key ... ok
[INFO] [stdout] test compaction::tests::tombstone_dropped_when_oldest_run ... ok
[INFO] [stdout] test db::tests::a_stale_wal_scratch_file_is_reclaimed_on_open ... ok
[INFO] [stdout] test db::tests::a_second_open_of_a_live_directory_is_refused ... ok
[INFO] [stdout] test db::tests::deleted_key_stays_deleted_after_reopen ... ok
[INFO] [stdout] test db::tests::a_failed_flush_poisons_the_handle ... ok
[INFO] [stdout] test db::tests::a_flush_publishes_a_new_wal_rather_than_truncating_the_live_one ... ok
[INFO] [stdout] test db::tests::parse_sst_id_matching ... ok
[INFO] [stdout] test db::tests::only_the_first_failure_is_kept ... ok
[INFO] [stdout] test db::tests::flush_persists_and_survives_reopen ... ok
[INFO] [stdout] test db::tests::put_get_delete ... ok
[INFO] [stdout] test db::tests::manual_maintenance_runs_nothing_until_asked ... ok
[INFO] [stdout] test db::tests::data_survives_repeated_reopen_without_flush ... ok
[INFO] [stdout] test db::tests::snapshot_sees_absence_of_later_keys ... ok
[INFO] [stdout] test db::tests::threads_remain_the_default ... ok
[INFO] [stdout] test db::tests::reopen_recovers_from_wal ... ok
[INFO] [stdout] test db::tests::migrates_phase2_directory_without_manifest ... ok
[INFO] [stdout] test fs::tests::a_hard_link_onto_an_existing_name_is_refused ... ok
[INFO] [stdout] test fs::tests::a_hard_link_outlives_the_name_it_was_made_from ... ok
[INFO] [stdout] test fs::tests::a_second_lock_is_refused_without_waiting ... ok
[INFO] [stdout] test fs::tests::an_unflushed_buffer_has_not_reached_the_file ... ok
[INFO] [stdout] test fs::tests::append_preserves_what_was_there ... ok
[INFO] [stdout] test fs::tests::a_file_round_trips_through_the_seam ... ok
[INFO] [stdout] test db::tests::flush_only_does_not_compact ... ok
[INFO] [stdout] test fs::tests::reading_past_the_end_is_an_error_rather_than_a_short_answer ... ok
[INFO] [stdout] test fs::tests::rename_moves_the_contents_and_removes_the_source ... ok
[INFO] [stdout] test fs::tests::listing_finds_what_was_created ... ok
[INFO] [stdout] test fs::tests::truncate_does_not_preserve_what_was_there ... ok
[INFO] [stdout] test fsutil::tests::a_second_lock_on_a_held_directory_is_refused ... ok
[INFO] [stdout] test db::tests::compaction_keeps_newest_value_and_drops_deleted_key ... ok
[INFO] [stdout] test fsutil::tests::dropping_the_handle_releases_the_lock ... ok
[INFO] [stdout] test header::tests::a_file_shorter_than_a_header_is_legacy_not_a_bad_header ... ok
[INFO] [stdout] test header::tests::a_headerless_file_is_read_as_legacy ... ok
[INFO] [stdout] test header::tests::a_version_from_the_future_is_refused_rather_than_guessed_at ... ok
[INFO] [stdout] test header::tests::a_written_header_classifies_as_this_version ... ok
[INFO] [stdout] test fs::tests::buffered_appends_produce_the_same_file_as_direct_ones ... ok
[INFO] [stdout] test fsutil::tests::syncing_a_directory_succeeds ... ok
[INFO] [stdout] test header::tests::an_empty_file_is_empty_rather_than_legacy ... ok
[INFO] [stdout] test header::tests::the_checksum_is_decided_by_the_version_not_by_this_build ... ok
[INFO] [stdout] test header::tests::the_two_checksums_are_actually_different ... ok
[INFO] [stdout] test db::tests::auto_flush_on_threshold ... ok
[INFO] [stdout] test header::tests::the_other_files_magic_is_not_accepted ... ok
[INFO] [stdout] test db::tests::snapshot_sees_value_at_capture_time ... ok
[INFO] [stdout] test manifest::tests::a_header_and_nothing_else_is_an_empty_manifest ... ok
[INFO] [stdout] test manifest::tests::a_headerless_manifest_still_replays_and_is_rewritten_with_a_header ... ok
[INFO] [stdout] test manifest::tests::a_manifest_with_no_readable_frame_is_refused_rather_than_read_as_empty ... ok
[INFO] [stdout] test memtable::tests::a_horizon_between_two_writes_sees_the_older_one ... ok
[INFO] [stdout] test memtable::tests::a_rewrite_is_a_new_version_and_the_newest_one_wins ... ok
[INFO] [stdout] test memtable::tests::insert_and_get ... ok
[INFO] [stdout] test memtable::tests::is_full_tracks_threshold ... ok
[INFO] [stdout] test memtable::tests::iter_is_key_ascending_and_sequence_descending ... ok
[INFO] [stdout] test memtable::tests::range_includes_every_version_of_the_start_key ... ok
[INFO] [stdout] test memtable::tests::range_respects_both_bounds ... ok
[INFO] [stdout] test memtable::tests::rewriting_one_key_still_fills_the_memtable ... ok
[INFO] [stdout] test memtable::tests::tombstone_shadows_the_value_beneath_it ... ok
[INFO] [stdout] test record::tests::decodes_consecutive_records ... ok
[INFO] [stdout] test record::tests::put_round_trip ... ok
[INFO] [stdout] test record::tests::tombstone_round_trip ... ok
[INFO] [stdout] test record::tests::truncated_input_errors ... ok
[INFO] [stdout] test scan::tests::a_horizon_below_a_tombstone_sees_what_it_covered ... ok
[INFO] [stdout] test scan::tests::a_tombstone_removes_the_key_rather_than_appearing_as_one ... ok
[INFO] [stdout] test manifest::tests::delete_table_removes_from_live_set ... ok
[INFO] [stdout] test scan::tests::an_error_in_a_source_is_reported_rather_than_skipped ... ok
[INFO] [stdout] test manifest::tests::torn_trailing_frame_is_ignored ... ok
[INFO] [stdout] test db::tests::compaction_reduces_table_count_and_preserves_data ... ok
[INFO] [stdout] test scan::tests::no_sources_is_an_empty_scan ... ok
[INFO] [stdout] test db::tests::newer_sstable_shadows_older ... ok
[INFO] [stdout] test scan::tests::the_end_bound_is_exclusive ... ok
[INFO] [stdout] test scan::tests::the_highest_sequence_wins_regardless_of_source_order ... ok
[INFO] [stdout] test db::tests::open_creates_manifest_and_reclaims_orphans ... ok
[INFO] [stdout] test scan::tests::sources_interleave_by_key ... ok
[INFO] [stdout] test sstable::tests::bad_magic_is_rejected ... ok
[INFO] [stdout] test db::tests::materialized_snapshot_is_complete_and_excludes_later_writes ... ok
[INFO] [stdout] test wal::tests::a_fresh_wal_starts_with_a_header ... ok
[INFO] [stdout] test wal::tests::a_headerless_wal_still_replays ... ok
[INFO] [stdout] test wal::tests::append_and_replay ... ok
[INFO] [stdout] test sstable::tests::empty_table_lookup ... ok
[INFO] [stdout] test sstable::tests::bloom_disabled_still_correct ... ok
[INFO] [stdout] test wal::tests::crc_mismatch_stops_replay ... ok
[INFO] [stdout] test wal::tests::appending_to_a_legacy_wal_leaves_it_headerless_and_readable ... ok
[INFO] [stdout] test fsutil::tests::the_lock_file_survives_a_release ... ok
[INFO] [stdout] test db::tests::the_lock_file_is_not_reclaimed_as_an_orphan ... ok
[INFO] [stdout] test sstable::tests::tombstone_survives_round_trip ... ok
[INFO] [stdout] test sstable::tests::iter_all_returns_sorted_records ... ok
[INFO] [stdout] test sstable::tests::write_then_point_lookup ... ok
[INFO] [stdout] test wal::tests::a_wal_whose_first_frame_is_torn_holds_no_records ... ok
[INFO] [stdout] test manifest::tests::create_open_round_trip ... ok
[INFO] [stdout] test wal::tests::torn_trailing_frame_is_ignored ... ok
[INFO] [stdout] test wal::tests::replay_missing_file_is_empty ... ok
[INFO] [stdout] test db::tests::snapshot_survives_compaction ... ok
[INFO] [stdout] test db::tests::concurrent_writers_and_readers ... ok
[INFO] [stdout] test manifest::tests::rollover_compacts_and_preserves_state ... ok
[INFO] [stdout] test db::tests::writes_across_reopens_accumulate ... ok
[INFO] [stdout] test db::tests::background_compaction_bounds_table_count ... ok
[INFO] [stdout] test db::tests::manual_maintenance_is_reproducible ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 104 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/lsm.rs (/opt/rustwide/target/release/deps/lsm-f6855c15e163c9cb)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/checkpoint.rs (/opt/rustwide/target/release/deps/checkpoint-f1c6bd7a37338667)
[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 6 tests
[INFO] [stdout] test a_checkpoint_over_an_existing_one_is_refused ... ok
[INFO] [stdout] test a_checkpoint_includes_what_was_still_in_memory ... ok
[INFO] [stdout] test writes_after_a_checkpoint_are_not_in_it ... ok
[INFO] [stdout] test the_source_is_unaffected_by_being_checkpointed ... ok
[INFO] [stdout] test a_checkpoint_shares_its_bytes_with_the_source ... ok
[INFO] [stdout] test a_checkpoint_survives_the_source_losing_the_names_it_linked ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.44s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/crash_phase3.rs (/opt/rustwide/target/release/deps/crash_phase3-b53dcf089da07143)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test partial_tmp_file_is_reclaimed_on_open ... ok
[INFO] [stdout] test acked_writes_survive_sigkill_during_background_work ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 21.82s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/crash_recovery.rs (/opt/rustwide/target/release/deps/crash_recovery-cd29497fe13dd0a6)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test acked_writes_survive_sigkill ... ok
[INFO] [stderr]      Running tests/format_versions.rs (/opt/rustwide/target/release/deps/format_versions-69632779bc91972a)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 7.82s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test a_database_from_before_the_checksum_changed_still_opens_with_its_data ... ok
[INFO] [stdout] test an_older_database_is_rewritten_at_the_current_version ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/injectable_filesystem.rs (/opt/rustwide/target/release/deps/injectable_filesystem-20c7a056eb7aa3d4)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test the_engine_runs_on_a_filesystem_that_is_neither_send_nor_sync ... ok
[INFO] [stdout] test a_refused_wal_append_fails_the_write_without_poisoning_the_handle ... ok
[INFO] [stdout] test an_injected_flush_failure_poisons_the_handle ... ok
[INFO] [stdout] test an_unreadable_manifest_refuses_the_open_instead_of_deleting_the_database ... ok
[INFO] [stdout] test every_operation_goes_through_the_seam ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/model.rs (/opt/rustwide/target/release/deps/model-69dc3bc2b8932158)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/scan.rs (/opt/rustwide/target/release/deps/scan-ff550c966c4aa69d)
[INFO] [stdout] test engine_matches_hashmap_oracle ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 40.06s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test a_scan_through_a_snapshot_sees_the_database_as_it_was ... ok
[INFO] [stdout] test a_limit_of_zero_returns_nothing ... ok
[INFO] [stdout] test an_empty_database_scans_to_nothing ... ok
[INFO] [stdout] test a_scan_matches_the_model_in_every_state ... ok
[INFO] [stdout] test a_bounded_scan_of_a_large_table_returns_only_what_was_asked_for ... ok
[INFO] [stderr]      Running tests/write_batch.rs (/opt/rustwide/target/release/deps/write_batch-d63d390933ef8297)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.59s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test an_empty_batch_is_a_no_op ... ok
[INFO] [stdout] test a_later_write_in_a_batch_shadows_an_earlier_one ... ok
[INFO] [stdout] test a_batch_survives_a_flush_and_a_reopen ... ok
[INFO] [stdout] test a_batch_costs_one_fsync_and_the_same_keys_written_singly_cost_one_each ... ok
[INFO] [stdout] test every_truncation_of_a_batch_leaves_all_of_it_or_none_of_it ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.36s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests keel_api
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test crates/keel-api/src/lib.rs - (line 27) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.46s; merged doctests compilation took 0.45s
[INFO] [stderr]    Doc-tests keel_bench
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test crates/keel-bench/src/lib.rs - (line 29) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.76s; merged doctests compilation took 0.72s
[INFO] [stderr]    Doc-tests keel_chaos
[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 keel_client
[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 keel_fuzz
[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 keel_log
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test crates/keel-log/src/lib.rs - (line 10) - compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.30s; merged doctests compilation took 0.29s
[INFO] [stderr]    Doc-tests keel_net
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test crates/keel-net/src/lib.rs - (line 17) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.34s; merged doctests compilation took 0.32s
[INFO] [stderr]    Doc-tests keel_node
[INFO] [stderr]    Doc-tests keel_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] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test crates/keel-raft/src/audit.rs - audit (line 27) ... ok
[INFO] [stdout] test crates/keel-raft/src/lib.rs - (line 13) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.50s; merged doctests compilation took 0.49s
[INFO] [stderr]    Doc-tests keel_rand
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test crates/keel-rand/src/lib.rs - (line 15) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.45s; merged doctests compilation took 0.44s
[INFO] [stderr]    Doc-tests keel_server
[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 keel_sim
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test crates/keel-sim/src/lib.rs - (line 19) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.72s; merged doctests compilation took 0.59s
[INFO] [stderr]    Doc-tests keel_sm
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test crates/keel-sm/src/lib.rs - (line 20) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.44s; merged doctests compilation took 0.43s
[INFO] [stderr]    Doc-tests lsm_kv
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test crates/lsm-kv/src/db.rs - db::Db<F>::maintain (line 529) - compile ... ok
[INFO] [stdout] test crates/lsm-kv/src/lib.rs - (line 8) - compile ... ok
[INFO] [stdout] test crates/lsm-kv/src/db.rs - db::Db<F>::write_batch (line 441) - compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 1.01s; merged doctests compilation took 1.00s
[INFO] running `Command { std: "docker" "inspect" "6dcc18568ed75de8b951c0d15ac45fded1863c0332b59be081842e92524f9efc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6dcc18568ed75de8b951c0d15ac45fded1863c0332b59be081842e92524f9efc", kill_on_drop: false }`
[INFO] [stdout] 6dcc18568ed75de8b951c0d15ac45fded1863c0332b59be081842e92524f9efc
