[INFO] fetching crate sst 0.19.0...
[INFO] testing sst-0.19.0 against master#ec6f9a5b4413f74386267ef8efc93712c2ce6db6 for pr-155739-1
[INFO] extracting crate sst 0.19.0 into /workspace/builds/worker-0-tc1/source
[INFO] started tweaking crates.io crate sst 0.19.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate sst 0.19.0
[INFO] tweaked toml for crates.io crate sst 0.19.0 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate sst 0.19.0 on toolchain ec6f9a5b4413f74386267ef8efc93712c2ce6db6
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate sst 0.19.0 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" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded tatl v0.14.0
[INFO] [stderr]   Downloaded prototk v0.14.0
[INFO] [stderr]   Downloaded zerror v0.9.0
[INFO] [stderr]   Downloaded utilz v0.8.0
[INFO] [stderr]   Downloaded prototk_derive v0.14.0
[INFO] [stderr]   Downloaded derive_util v0.6.0
[INFO] [stderr]   Downloaded handled v0.7.0
[INFO] [stderr]   Downloaded one_two_eight v0.11.0
[INFO] [stderr]   Downloaded arrrg_derive v0.9.0
[INFO] [stderr]   Downloaded setsum v0.8.0
[INFO] [stderr]   Downloaded arrrg v0.9.0
[INFO] [stderr]   Downloaded indicio v0.13.0
[INFO] [stderr]   Downloaded armnod v0.12.0
[INFO] [stderr]   Downloaded buffertk v0.14.0
[INFO] [stderr]   Downloaded biometrics v0.13.0
[INFO] [stderr]   Downloaded sig_fig_histogram v0.7.0
[INFO] [stderr]   Downloaded sync42 v0.16.0
[INFO] [stderr]   Downloaded guacamole v0.15.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 68b235abf4803775512d239eb403704dc629dad9ce76d1c7b63ee137e9a45255
[INFO] running `Command { std: "docker" "start" "-a" "68b235abf4803775512d239eb403704dc629dad9ce76d1c7b63ee137e9a45255", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "68b235abf4803775512d239eb403704dc629dad9ce76d1c7b63ee137e9a45255", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "68b235abf4803775512d239eb403704dc629dad9ce76d1c7b63ee137e9a45255", kill_on_drop: false }`
[INFO] [stdout] 68b235abf4803775512d239eb403704dc629dad9ce76d1c7b63ee137e9a45255
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0772d99cc905ff9ff390994aa9a6fddea3dba7374af3cd1f7a8793696b9cdcb7
[INFO] running `Command { std: "docker" "start" "-a" "0772d99cc905ff9ff390994aa9a6fddea3dba7374af3cd1f7a8793696b9cdcb7", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling sig_fig_histogram v0.7.0
[INFO] [stderr]    Compiling buffertk v0.14.0
[INFO] [stderr]    Compiling zerror v0.9.0
[INFO] [stderr]    Compiling minimal-lexical v0.2.1
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling crc32c v0.6.8
[INFO] [stderr]    Compiling utilz v0.8.0
[INFO] [stderr]    Compiling crypto-common v0.1.6
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling biometrics v0.13.0
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling sync42 v0.16.0
[INFO] [stderr]    Compiling handled v0.7.0
[INFO] [stderr]    Compiling sha3 v0.10.8
[INFO] [stderr]    Compiling setsum v0.8.0
[INFO] [stderr]    Compiling derive_util v0.6.0
[INFO] [stderr]    Compiling prototk_derive v0.14.0
[INFO] [stderr]    Compiling arrrg_derive v0.9.0
[INFO] [stderr]    Compiling arrrg v0.9.0
[INFO] [stderr]    Compiling guacamole v0.15.0
[INFO] [stderr]    Compiling prototk v0.14.0
[INFO] [stderr]    Compiling armnod v0.12.0
[INFO] [stderr]    Compiling one_two_eight v0.11.0
[INFO] [stderr]    Compiling tatl v0.14.0
[INFO] [stderr]    Compiling indicio v0.13.0
[INFO] [stderr]    Compiling sst v0.19.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 11.23s
[INFO] running `Command { std: "docker" "inspect" "0772d99cc905ff9ff390994aa9a6fddea3dba7374af3cd1f7a8793696b9cdcb7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0772d99cc905ff9ff390994aa9a6fddea3dba7374af3cd1f7a8793696b9cdcb7", kill_on_drop: false }`
[INFO] [stdout] 0772d99cc905ff9ff390994aa9a6fddea3dba7374af3cd1f7a8793696b9cdcb7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c01f6f19487b2cdf09d187f8b5a7d81565b9dd62a61f19c70aa62260e4686dcc
[INFO] running `Command { std: "docker" "start" "-a" "c01f6f19487b2cdf09d187f8b5a7d81565b9dd62a61f19c70aa62260e4686dcc", kill_on_drop: false }`
[INFO] [stderr]    Compiling getrandom v0.3.3
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling rustix v1.1.2
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling unarray v0.1.4
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling tempfile v3.23.0
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling proptest v1.8.0
[INFO] [stderr]    Compiling sst v0.19.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 13.59s
[INFO] running `Command { std: "docker" "inspect" "c01f6f19487b2cdf09d187f8b5a7d81565b9dd62a61f19c70aa62260e4686dcc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c01f6f19487b2cdf09d187f8b5a7d81565b9dd62a61f19c70aa62260e4686dcc", kill_on_drop: false }`
[INFO] [stdout] c01f6f19487b2cdf09d187f8b5a7d81565b9dd62a61f19c70aa62260e4686dcc
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,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" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+ec6f9a5b4413f74386267ef8efc93712c2ce6db6" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 65d778308fd7f0ab0df9f771222dc748898348a0349b16727276ae187fa88c4b
[INFO] running `Command { std: "docker" "start" "-a" "65d778308fd7f0ab0df9f771222dc748898348a0349b16727276ae187fa88c4b", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.13s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sst-843fa97e7f3ed766)
[INFO] [stdout] 
[INFO] [stdout] running 50 tests
[INFO] [stdout] test block::tests::build_prefix_compression ... ok
[INFO] [stdout] test block::tests::build_empty_block ... ok
[INFO] [stdout] test block::tests::corruption_bug_gone ... ok
[INFO] [stdout] test block::tests::build_single_item_block ... ok
[INFO] [stdout] test block::tests::extract_key ... ok
[INFO] [stdout] test block::tests::seek_bug_gone ... ok
[INFO] [stdout] test block::tests::cursor_equals ... ok
[INFO] [stdout] test block::tests::load_restart_points ... ok
[INFO] [stdout] test gc::tests::expires_example1 ... ok
[INFO] [stdout] test gc::tests::expires_example2 ... ok
[INFO] [stdout] test gc::tests::policy::any ... ok
[INFO] [stdout] test gc::tests::policy::all ... ok
[INFO] [stdout] test gc::tests::policy::expires0 ... ok
[INFO] [stdout] test gc::tests::policy::expires42 ... ok
[INFO] [stdout] test gc::tests::policy::expires1 ... ok
[INFO] [stdout] test gc::tests::policy::versions42 ... ok
[INFO] [stdout] test gc::tests::versions_example2 ... ok
[INFO] [stdout] test log::builder::crc32c_one ... ok
[INFO] [stdout] test log::offsets::boundaries ... ok
[INFO] [stdout] test tests::crc32c::extends ... ok
[INFO] [stdout] test log::offsets::true_ups ... ok
[INFO] [stdout] test log::builder::header_one ... ok
[INFO] [stdout] test sbbf::tests::filter ... ok
[INFO] [stdout] test log::builder::insert_one ... ok
[INFO] [stdout] test tests::divide_keys::bug_1 ... ok
[INFO] [stdout] test tests::divide_keys::empty_one ... ok
[INFO] [stdout] test tests::divide_keys::empty_timestamp ... ok
[INFO] [stdout] test reference::tables::empty ... ok
[INFO] [stdout] test log::offsets::offsets ... ok
[INFO] [stdout] test tests::divide_keys::max_timestamp ... ok
[INFO] [stdout] test tests::divide_keys::shared_prefix_0xaa ... ok
[INFO] [stdout] test log::builder::empty ... ok
[INFO] [stdout] test tests::divide_keys::adjacent_shared ... ok
[INFO] [stdout] test tests::divide_keys::empty_timestamp_adjacent ... ok
[INFO] [stdout] test tests::divide_keys::shared_prefix_no_diff ... ok
[INFO] [stdout] test tests::key_ref_ordering ... ok
[INFO] [stdout] test tests::minimal_successor_key::empty_zero_timestamp ... ok
[INFO] [stdout] test tests::divide_keys::shared_prefix_0xff ... ok
[INFO] [stdout] test tests::crc32c::values ... ok
[INFO] [stdout] test tests::crc32c::standard_results ... ok
[INFO] [stdout] test gc::tests::versions_example1 ... ok
[INFO] [stdout] test gc::tests::policy::versions0 ... ok
[INFO] [stdout] test tests::minimal_successor_key::ffffff_nonzero_timestamp ... ok
[INFO] [stdout] test gc::tests::policy::versions1 ... ok
[INFO] [stdout] test tests::minimal_successor_key::empty_nonzero_timestamp ... ok
[INFO] [stdout] test tests::minimal_successor_key::nonempty_nonzero_timestamp ... ok
[INFO] [stdout] test tests::minimal_successor_key::nonempty_zero_timestamp ... ok
[INFO] [stdout] test tests::u64_is_usize ... ok
[INFO] [stdout] test tests::minimal_successor_key::ffffff_zero_timestamp ... ok
[INFO] [stdout] test log::builder::insert_across_boundary ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 50 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.40s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/jester-from-plaintext.rs (/opt/rustwide/target/debug/deps/jester_from_plaintext-9b13345b4dca0c82)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/log-checksum.rs (/opt/rustwide/target/debug/deps/log_checksum-32d57b53a6719915)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/log-dump.rs (/opt/rustwide/target/debug/deps/log_dump-fb97b12c2f951838)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/log-from-plaintext.rs (/opt/rustwide/target/debug/deps/log_from_plaintext-1a2743e50e5d2f31)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/log-options.rs (/opt/rustwide/target/debug/deps/log_options-9c92bf989f790ba0)
[INFO] [stderr]      Running unittests src/bin/log-truncate-final-partial-frame.rs (/opt/rustwide/target/debug/deps/log_truncate_final_partial_frame-94891e004abcf8b8)
[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 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/sst-checksum.rs (/opt/rustwide/target/debug/deps/sst_checksum-17eb8c08963051d1)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/sst-dump.rs (/opt/rustwide/target/debug/deps/sst_dump-45f93c57c8aa8010)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/sst-from-log.rs (/opt/rustwide/target/debug/deps/sst_from_log-89da79ec0d62a9f1)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stderr]      Running unittests src/bin/sst-from-plaintext.rs (/opt/rustwide/target/debug/deps/sst_from_plaintext-756a0f82bccc7b91)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/sst-inspect.rs (/opt/rustwide/target/debug/deps/sst_inspect-1cbc72a6cbdaed4d)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/sst-options.rs (/opt/rustwide/target/debug/deps/sst_options-6453f67b99df0ad5)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/sst-stat.rs (/opt/rustwide/target/debug/deps/sst_stat-e9a0a060812fda57)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stderr]      Running tests/alphabet.rs (/opt/rustwide/target/debug/deps/alphabet-5b79a861c370ad17)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/block.rs (/opt/rustwide/target/debug/deps/block-6c004321db7c88b2)
[INFO] [stdout] 
[INFO] [stdout] running 160 tests
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_1_key_value_pairs_restart_interval_1::seek_to_at ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_1_key_value_pairs_restart_interval_1::seek_to_first ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_1_key_value_pairs_restart_interval_1::seek_to_z ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_1_key_value_pairs_restart_interval_1::step_the_alphabet_reverse ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_1_key_value_pairs_restart_interval_1::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_1_key_value_pairs_restart_interval_1::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_1_key_value_pairs_restart_interval_1::seek_to_last ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_512_key_value_pairs_restart_interval_16::seek_to_first ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_512_key_value_pairs_restart_interval_16::seek_to_at ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_512_key_value_pairs_restart_interval_16::step_the_alphabet_reverse ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_1_key_value_pairs_restart_interval_1::step_the_alphabet_forward ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_512_key_value_pairs_restart_interval_16::step_the_alphabet_forward ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_512_key_value_pairs_restart_interval_16::seek_to_last ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_512_key_value_pairs_restart_interval_16::seek_to_z ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_512_key_value_pairs_restart_interval_16::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test alphabet_opts_bytes_restart_interval_512_key_value_pairs_restart_interval_16::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_16_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_16_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_16_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_16_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_16_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_1_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_16_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_1_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_1_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_1_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_256_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_16_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_1_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_1_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_16_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16384_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_1_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16384_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_256_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_256_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_256_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16384_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16384_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_256_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16384_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16384_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_1_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16384_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16384_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16384_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16384_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_256_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_1_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_1_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_256_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16384_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10000_key_bytes_256_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_1_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_1_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16384_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_1_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_1_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_256_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_1_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_1_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_256_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_1_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_16_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_256_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_1_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_256_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_1_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_256_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_256_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_1_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_256_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_256_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_256_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_256_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_256_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_1_key_value_pairs_restart_interval_1::num_keys_10_key_bytes_256_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_16_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_16_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_16_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_1_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_16_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_1_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_16_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_16_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_1_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_1_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_16_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_1_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16384_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_1_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_1_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_1_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_256_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_16_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_256_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_256_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16384_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16384_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16384_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_256_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16384_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16384_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16384_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16384_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_256_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16384_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_256_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_256_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_1_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16384_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16384_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_1_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_1_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_16384_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_1_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_256_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_1_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_256_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_256_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_1_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10000_key_bytes_256_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_256_value_bytes_0_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_1_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_256_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_1_value_bytes_16_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_1_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_256_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_256_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_256_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_1_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_1_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_256_value_bytes_256_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_1_value_bytes_1_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_256_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_0 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_256_value_bytes_4096_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] test guacamole_bytes_restart_interval_512_key_value_pairs_restart_interval_16::num_keys_10_key_bytes_256_value_bytes_32768_num_seeks_1000_seek_distance_10_prev_probability_0_125 ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 160 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 15.89s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/bounds_cursor.rs (/opt/rustwide/target/debug/deps/bounds_cursor-4c0dbac701a8ff90)
[INFO] [stdout] 
[INFO] [stdout] running 56 tests
[INFO] [stdout] test bounds_cursor_both_bound_excluded::seek_to_last ... ok
[INFO] [stdout] test bounds_cursor_both_bound_excluded::step_the_alphabet_forward ... ok
[INFO] [stdout] test bounds_cursor_both_bound_excluded::seek_to_at ... ok
[INFO] [stdout] test bounds_cursor_both_bound_excluded::seek_to_first ... ok
[INFO] [stdout] test bounds_cursor_both_bound_included::seek_to_first ... ok
[INFO] [stdout] test bounds_cursor_both_bound_included::seek_to_last ... ok
[INFO] [stdout] test bounds_cursor_both_bound_included::seek_to_z ... ok
[INFO] [stdout] test bounds_cursor_both_bound_included::step_the_alphabet_reverse ... ok
[INFO] [stdout] test bounds_cursor_both_bound_excluded::step_the_alphabet_reverse ... ok
[INFO] [stdout] test bounds_cursor_both_bound_included::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test bounds_cursor_both_bound_included::step_the_alphabet_forward ... ok
[INFO] [stdout] test bounds_cursor_both_bound_included::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test bounds_cursor_end_bound_excluded::seek_to_at ... ok
[INFO] [stdout] test bounds_cursor_end_bound_excluded::seek_to_first ... ok
[INFO] [stdout] test bounds_cursor_both_bound_excluded::seek_to_z ... ok
[INFO] [stdout] test bounds_cursor_end_bound_excluded::seek_to_z ... ok
[INFO] [stdout] test bounds_cursor_both_bound_included::seek_to_at ... ok
[INFO] [stdout] test bounds_cursor_end_bound_excluded::step_the_alphabet_forward ... ok
[INFO] [stdout] test bounds_cursor_end_bound_excluded::step_the_alphabet_reverse ... ok
[INFO] [stdout] test bounds_cursor_end_bound_excluded::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test bounds_cursor_end_bound_excluded::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test bounds_cursor_end_bound_included::seek_to_at ... ok
[INFO] [stdout] test bounds_cursor_end_bound_included::seek_to_first ... ok
[INFO] [stdout] test bounds_cursor_end_bound_included::seek_to_last ... ok
[INFO] [stdout] test bounds_cursor_end_bound_included::seek_to_z ... ok
[INFO] [stdout] test bounds_cursor_end_bound_included::step_the_alphabet_forward ... ok
[INFO] [stdout] test bounds_cursor_end_bound_included::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test bounds_cursor_end_bound_included::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test bounds_cursor_both_bound_excluded::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test bounds_cursor_no_bounds::seek_to_first ... ok
[INFO] [stdout] test bounds_cursor_no_bounds::seek_to_last ... ok
[INFO] [stdout] test bounds_cursor_no_bounds::seek_to_z ... ok
[INFO] [stdout] test bounds_cursor_start_bound_excluded::step_the_alphabet_forward ... ok
[INFO] [stdout] test bounds_cursor_no_bounds::step_the_alphabet_forward ... ok
[INFO] [stdout] test bounds_cursor_no_bounds::step_the_alphabet_reverse ... ok
[INFO] [stdout] test bounds_cursor_no_bounds::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test bounds_cursor_no_bounds::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test bounds_cursor_start_bound_excluded::seek_to_z ... ok
[INFO] [stdout] test bounds_cursor_start_bound_excluded::seek_to_last ... ok
[INFO] [stdout] test bounds_cursor_end_bound_included::step_the_alphabet_reverse ... ok
[INFO] [stdout] test bounds_cursor_start_bound_included::seek_to_first ... ok
[INFO] [stdout] test bounds_cursor_start_bound_included::seek_to_z ... ok
[INFO] [stdout] test bounds_cursor_start_bound_included::step_the_alphabet_forward ... ok
[INFO] [stdout] test bounds_cursor_end_bound_excluded::seek_to_last ... ok
[INFO] [stdout] test bounds_cursor_start_bound_excluded::seek_to_at ... ok
[INFO] [stdout] test bounds_cursor_start_bound_excluded::seek_to_first ... ok
[INFO] [stdout] test bounds_cursor_no_bounds::seek_to_at ... ok
[INFO] [stdout] test bounds_cursor_start_bound_excluded::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test bounds_cursor_start_bound_excluded::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test bounds_cursor_start_bound_included::step_the_alphabet_reverse ... ok
[INFO] [stdout] test bounds_cursor_start_bound_excluded::step_the_alphabet_reverse ... ok
[INFO] [stdout] test bounds_cursor_start_bound_included::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test bounds_cursor_start_bound_included::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test bounds_cursor_both_bound_excluded::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test bounds_cursor_start_bound_included::seek_to_at ... ok
[INFO] [stdout] test bounds_cursor_start_bound_included::seek_to_last ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 56 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.15s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/concat_cursor.rs (/opt/rustwide/target/debug/deps/concat_cursor-0d94a5b0f2e5fbcf)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test sequence_cursor::seek_to_at ... ok
[INFO] [stdout] test sequence_cursor::seek_to_last ... ok
[INFO] [stdout] test sequence_cursor::step_the_alphabet_forward ... ok
[INFO] [stdout] test sequence_cursor::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test sequence_cursor::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test sequence_cursor::seek_to_first ... ok
[INFO] [stdout] test sequence_cursor::seek_to_z ... ok
[INFO] [stdout] test sequence_cursor::step_the_alphabet_reverse ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/guacamole.rs (/opt/rustwide/target/debug/deps/guacamole-ccedb5cf3388c0f5)
[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 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/merging_cursor.rs (/opt/rustwide/target/debug/deps/merging_cursor-c0b597c2a005d242)
[INFO] [stdout] 
[INFO] [stdout] running 16 tests
[INFO] [stdout] test merging_cursor_no_overlap::seek_to_at ... ok
[INFO] [stdout] test merging_cursor_no_overlap::seek_to_first ... ok
[INFO] [stdout] test merging_cursor_no_overlap::seek_to_last ... ok
[INFO] [stdout] test merging_cursor_no_overlap::step_the_alphabet_forward ... ok
[INFO] [stdout] test merging_cursor_no_overlap::seek_to_z ... ok
[INFO] [stdout] test merging_cursor_total_overlap::seek_to_at ... ok
[INFO] [stdout] test merging_cursor_total_overlap::seek_to_first ... ok
[INFO] [stdout] test merging_cursor_no_overlap::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test merging_cursor_no_overlap::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test merging_cursor_total_overlap::seek_to_last ... ok
[INFO] [stdout] test merging_cursor_total_overlap::seek_to_z ... ok
[INFO] [stderr]      Running tests/properties.rs (/opt/rustwide/target/debug/deps/properties-903bcdf6b80a9cbe)
[INFO] [stdout] test merging_cursor_no_overlap::step_the_alphabet_reverse ... ok
[INFO] [stdout] test merging_cursor_total_overlap::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test merging_cursor_total_overlap::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test merging_cursor_total_overlap::step_the_alphabet_forward ... ok
[INFO] [stdout] test merging_cursor_total_overlap::step_the_alphabet_reverse ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 16 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running tests/pruning_cursor.rs (/opt/rustwide/target/debug/deps/pruning_cursor-2ae8a4ac7c947d6e)
[INFO] [stdout] test log ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 11.20s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 32 tests
[INFO] [stdout] test pruning_cursor_deleted_extras::seek_to_first ... ok
[INFO] [stdout] test pruning_cursor_deleted_extras::seek_to_last ... ok
[INFO] [stdout] test pruning_cursor_deleted_extras::seek_to_at ... ok
[INFO] [stdout] test pruning_cursor_deleted_extras::seek_to_z ... ok
[INFO] [stdout] test pruning_cursor_deleted_extras::step_the_alphabet_reverse ... ok
[INFO] [stdout] test pruning_cursor_deleted_extras::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test pruning_cursor_no_pruning::seek_to_first ... ok
[INFO] [stdout] test pruning_cursor_no_pruning::seek_to_at ... ok
[INFO] [stdout] test pruning_cursor_deleted_extras::step_the_alphabet_forward ... ok
[INFO] [stdout] test pruning_cursor_no_pruning::seek_to_z ... ok
[INFO] [stdout] test pruning_cursor_no_pruning::seek_to_last ... ok
[INFO] [stdout] test pruning_cursor_no_pruning::step_the_alphabet_forward ... ok
[INFO] [stdout] test pruning_cursor_deleted_extras::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test pruning_cursor_no_pruning::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test pruning_cursor_snapshot_cutoff::seek_to_first ... ok
[INFO] [stdout] test pruning_cursor_snapshot_cutoff::seek_to_last ... ok
[INFO] [stdout] test pruning_cursor_snapshot_cutoff::seek_to_at ... ok
[INFO] [stdout] test pruning_cursor_snapshot_cutoff::seek_to_z ... ok
[INFO] [stdout] test pruning_cursor_snapshot_cutoff::step_the_alphabet_forward ... ok
[INFO] [stdout] test pruning_cursor_snapshot_cutoff::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test pruning_cursor_snapshot_cutoff::step_the_alphabet_reverse ... ok
[INFO] [stdout] test pruning_cursor_snapshot_cutoff::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test pruning_cursor_tombstone_above_snapshot::seek_to_at ... ok
[INFO] [stdout] test pruning_cursor_tombstone_above_snapshot::seek_to_first ... ok
[INFO] [stdout] test pruning_cursor_tombstone_above_snapshot::seek_to_last ... ok
[INFO] [stdout] test pruning_cursor_tombstone_above_snapshot::seek_to_z ... ok
[INFO] [stdout] test pruning_cursor_tombstone_above_snapshot::step_the_alphabet_forward ... ok
[INFO] [stdout] test pruning_cursor_tombstone_above_snapshot::step_the_alphabet_reverse ... ok
[INFO] [stdout] test pruning_cursor_tombstone_above_snapshot::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] test pruning_cursor_tombstone_above_snapshot::two_steps_reverse_one_step_forward ... ok
[INFO] [stdout] test pruning_cursor_no_pruning::step_the_alphabet_reverse ... ok
[INFO] [stdout] test pruning_cursor_no_pruning::two_steps_forward_one_step_reverse ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 32 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/read-while-write-log.rs (/opt/rustwide/target/debug/deps/read_while_write_log-018f09cd9792d616)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test read_while_writing_conclog ... FAILED
[INFO] [stderr] error: test failed, to rerun pass `--test read-while-write-log`
[INFO] [stdout] test read_while_writing_log ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- read_while_writing_conclog stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'read_while_writing_conclog' (363) panicked at tests/read-while-write-log.rs:44:85:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: SError { detail: List([Atom("error"), List([Atom("phase"), Atom("sst")]), List([Atom("code"), Atom("system-error")]), List([Atom("kind"), Atom("ReadOnlyFilesystem")]), List([Atom("message"), Atom("\"Read-only file system (os error 30)\"")]), List([Atom("path"), Atom("\"read_while_writing_concurrent.log\"")])]) }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d818602fc6a - std[29689e6404d28ef9]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5d818602fc6a - std[29689e6404d28ef9]::backtrace_rs::backtrace::trace_unsynchronized::<std[29689e6404d28ef9]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5d818602fc6a - std[29689e6404d28ef9]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5d818602fc6a - <<std[29689e6404d28ef9]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[e929cb53b82a81ca]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5d81860443da - <core[e929cb53b82a81ca]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5d81860443da - core[e929cb53b82a81ca]::fmt::write
[INFO] [stdout]    6:     0x5d81860345a2 - std[29689e6404d28ef9]::io::default_write_fmt::<alloc[9d7caffeb3b5d2c6]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5d81860345a2 - <alloc[9d7caffeb3b5d2c6]::vec::Vec<u8> as std[29689e6404d28ef9]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5d818600eaff - <std[29689e6404d28ef9]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5d818600eaff - std[29689e6404d28ef9]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5d8186028019 - std[29689e6404d28ef9]::panicking::default_hook
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5d8185f929bc - <alloc[9d7caffeb3b5d2c6]::boxed::Box<dyn for<'a, 'b> core[e929cb53b82a81ca]::ops::function::Fn<(&'a std[29689e6404d28ef9]::panic::PanicHookInfo<'b>,), Output = ()> + core[e929cb53b82a81ca]::marker::Send + core[e929cb53b82a81ca]::marker::Sync> as core[e929cb53b82a81ca]::ops::function::Fn<(&std[29689e6404d28ef9]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5d8185f929bc - test[a24b3028667022f7]::test_main_inner::<test[a24b3028667022f7]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5d81860281d2 - <alloc[9d7caffeb3b5d2c6]::boxed::Box<dyn for<'a, 'b> core[e929cb53b82a81ca]::ops::function::Fn<(&'a std[29689e6404d28ef9]::panic::PanicHookInfo<'b>,), Output = ()> + core[e929cb53b82a81ca]::marker::Send + core[e929cb53b82a81ca]::marker::Sync> as core[e929cb53b82a81ca]::ops::function::Fn<(&std[29689e6404d28ef9]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5d81860281d2 - std[29689e6404d28ef9]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5d818600ebb8 - std[29689e6404d28ef9]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5d8186003d19 - std[29689e6404d28ef9]::sys::backtrace::__rust_end_short_backtrace::<std[29689e6404d28ef9]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5d818600f8cd - __rustc[3aed6af316653e63]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5d8186044b2c - core[e929cb53b82a81ca]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5d81860448a2 - core[e929cb53b82a81ca]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x5d8185f83f31 - <core[e929cb53b82a81ca]::result::Result<sst[6c59437b1853bb6]::log::LogBuilder<std[29689e6404d28ef9]::fs::File>, handled[a67a71c1c84f22ab]::SError>>::unwrap
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/result.rs:1233:23
[INFO] [stdout]   21:     0x5d8185f83f31 - read_while_write_log[446d5d41a257a50]::read_while_writing_conclog
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/read-while-write-log.rs:44:85
[INFO] [stdout]   22:     0x5d8185f82837 - read_while_write_log[446d5d41a257a50]::read_while_writing_conclog::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/read-while-write-log.rs:41:32
[INFO] [stdout]   23:     0x5d8185f85bf6 - <read_while_write_log[446d5d41a257a50]::read_while_writing_conclog::{closure#0} as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5d8185f85c9b - <fn() -> core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5d8185f85c9b - test[a24b3028667022f7]::__rust_begin_short_backtrace::<core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>, fn() -> core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x5d8185f9348b - test[a24b3028667022f7]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x5d8185f9348b - <core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x5d8185f9348b - std[29689e6404d28ef9]::panicking::catch_unwind::do_call::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}>, core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x5d8185f9348b - std[29689e6404d28ef9]::panicking::catch_unwind::<core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>, core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x5d8185f9348b - std[29689e6404d28ef9]::panic::catch_unwind::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}>, core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x5d8185f9348b - test[a24b3028667022f7]::run_test_in_process
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x5d8185f9348b - test[a24b3028667022f7]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x5d8185f8cba4 - test[a24b3028667022f7]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x5d8185f8cba4 - std[29689e6404d28ef9]::sys::backtrace::__rust_begin_short_backtrace::<test[a24b3028667022f7]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x5d8185f96092 - std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked::<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x5d8185f96092 - <core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x5d8185f96092 - std[29689e6404d28ef9]::panicking::catch_unwind::do_call::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x5d8185f96092 - std[29689e6404d28ef9]::panicking::catch_unwind::<(), core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x5d8185f96092 - std[29689e6404d28ef9]::panic::catch_unwind::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x5d8185f96092 - std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked::<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x5d8185f96092 - <std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1} as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x5d818602f57f - <alloc[9d7caffeb3b5d2c6]::boxed::Box<dyn core[e929cb53b82a81ca]::ops::function::FnOnce<(), Output = ()> + core[e929cb53b82a81ca]::marker::Send> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x5d818602f57f - <std[29689e6404d28ef9]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x72e8bc901aa4 - <unknown>
[INFO] [stdout]   45:     0x72e8bc98ea64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- read_while_writing_log stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'read_while_writing_log' (364) panicked at tests/read-while-write-log.rs:9:92:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: SError { detail: List([Atom("error"), List([Atom("phase"), Atom("sst")]), List([Atom("code"), Atom("system-error")]), List([Atom("kind"), Atom("ReadOnlyFilesystem")]), List([Atom("message"), Atom("\"Read-only file system (os error 30)\"")]), List([Atom("path"), Atom("\"read_while_writing.log\"")])]) }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5d818602fc6a - std[29689e6404d28ef9]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5d818602fc6a - std[29689e6404d28ef9]::backtrace_rs::backtrace::trace_unsynchronized::<std[29689e6404d28ef9]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5d818602fc6a - std[29689e6404d28ef9]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5d818602fc6a - <<std[29689e6404d28ef9]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[e929cb53b82a81ca]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5d81860443da - <core[e929cb53b82a81ca]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5d81860443da - core[e929cb53b82a81ca]::fmt::write
[INFO] [stdout]    6:     0x5d81860345a2 - std[29689e6404d28ef9]::io::default_write_fmt::<alloc[9d7caffeb3b5d2c6]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5d81860345a2 - <alloc[9d7caffeb3b5d2c6]::vec::Vec<u8> as std[29689e6404d28ef9]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5d818600eaff - <std[29689e6404d28ef9]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5d818600eaff - std[29689e6404d28ef9]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5d8186028019 - std[29689e6404d28ef9]::panicking::default_hook
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5d8185f929bc - <alloc[9d7caffeb3b5d2c6]::boxed::Box<dyn for<'a, 'b> core[e929cb53b82a81ca]::ops::function::Fn<(&'a std[29689e6404d28ef9]::panic::PanicHookInfo<'b>,), Output = ()> + core[e929cb53b82a81ca]::marker::Send + core[e929cb53b82a81ca]::marker::Sync> as core[e929cb53b82a81ca]::ops::function::Fn<(&std[29689e6404d28ef9]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5d8185f929bc - test[a24b3028667022f7]::test_main_inner::<test[a24b3028667022f7]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5d81860281d2 - <alloc[9d7caffeb3b5d2c6]::boxed::Box<dyn for<'a, 'b> core[e929cb53b82a81ca]::ops::function::Fn<(&'a std[29689e6404d28ef9]::panic::PanicHookInfo<'b>,), Output = ()> + core[e929cb53b82a81ca]::marker::Send + core[e929cb53b82a81ca]::marker::Sync> as core[e929cb53b82a81ca]::ops::function::Fn<(&std[29689e6404d28ef9]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5d81860281d2 - std[29689e6404d28ef9]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5d818600ebb8 - std[29689e6404d28ef9]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5d8186003d19 - std[29689e6404d28ef9]::sys::backtrace::__rust_end_short_backtrace::<std[29689e6404d28ef9]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5d818600f8cd - __rustc[3aed6af316653e63]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5d8186044b2c - core[e929cb53b82a81ca]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5d81860448a2 - core[e929cb53b82a81ca]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x5d8185f82961 - <core[e929cb53b82a81ca]::result::Result<sst[6c59437b1853bb6]::log::LogBuilder<std[29689e6404d28ef9]::fs::File>, handled[a67a71c1c84f22ab]::SError>>::unwrap
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/result.rs:1233:23
[INFO] [stdout]   21:     0x5d8185f82961 - read_while_write_log[446d5d41a257a50]::read_while_writing_log
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/read-while-write-log.rs:9:92
[INFO] [stdout]   22:     0x5d8185f82807 - read_while_write_log[446d5d41a257a50]::read_while_writing_log::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/read-while-write-log.rs:7:28
[INFO] [stdout]   23:     0x5d8185f85bb6 - <read_while_write_log[446d5d41a257a50]::read_while_writing_log::{closure#0} as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5d8185f85c9b - <fn() -> core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5d8185f85c9b - test[a24b3028667022f7]::__rust_begin_short_backtrace::<core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>, fn() -> core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:724:18
[INFO] [stdout]   26:     0x5d8185f9348b - test[a24b3028667022f7]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:747:74
[INFO] [stdout]   27:     0x5d8185f9348b - <core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x5d8185f9348b - std[29689e6404d28ef9]::panicking::catch_unwind::do_call::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}>, core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x5d8185f9348b - std[29689e6404d28ef9]::panicking::catch_unwind::<core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>, core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x5d8185f9348b - std[29689e6404d28ef9]::panic::catch_unwind::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<test[a24b3028667022f7]::run_test_in_process::{closure#0}>, core[e929cb53b82a81ca]::result::Result<(), alloc[9d7caffeb3b5d2c6]::string::String>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x5d8185f9348b - test[a24b3028667022f7]::run_test_in_process
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:747:27
[INFO] [stdout]   32:     0x5d8185f9348b - test[a24b3028667022f7]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:668:43
[INFO] [stdout]   33:     0x5d8185f8cba4 - test[a24b3028667022f7]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/test/src/lib.rs:698:41
[INFO] [stdout]   34:     0x5d8185f8cba4 - std[29689e6404d28ef9]::sys::backtrace::__rust_begin_short_backtrace::<test[a24b3028667022f7]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x5d8185f96092 - std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked::<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   36:     0x5d8185f96092 - <core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x5d8185f96092 - std[29689e6404d28ef9]::panicking::catch_unwind::do_call::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x5d8185f96092 - std[29689e6404d28ef9]::panicking::catch_unwind::<(), core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x5d8185f96092 - std[29689e6404d28ef9]::panic::catch_unwind::<core[e929cb53b82a81ca]::panic::unwind_safe::AssertUnwindSafe<std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x5d8185f96092 - std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked::<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   41:     0x5d8185f96092 - <std[29689e6404d28ef9]::thread::lifecycle::spawn_unchecked<test[a24b3028667022f7]::run_test::{closure#1}, ()>::{closure#1} as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x5d818602f57f - <alloc[9d7caffeb3b5d2c6]::boxed::Box<dyn core[e929cb53b82a81ca]::ops::function::FnOnce<(), Output = ()> + core[e929cb53b82a81ca]::marker::Send> as core[e929cb53b82a81ca]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   43:     0x5d818602f57f - <std[29689e6404d28ef9]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/ec6f9a5b4413f74386267ef8efc93712c2ce6db6/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x72e8bc901aa4 - <unknown>
[INFO] [stdout]   45:     0x72e8bc98ea64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     read_while_writing_conclog
[INFO] [stdout]     read_while_writing_log
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "65d778308fd7f0ab0df9f771222dc748898348a0349b16727276ae187fa88c4b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "65d778308fd7f0ab0df9f771222dc748898348a0349b16727276ae187fa88c4b", kill_on_drop: false }`
[INFO] [stdout] 65d778308fd7f0ab0df9f771222dc748898348a0349b16727276ae187fa88c4b
