[INFO] fetching crate below-store 0.5.0...
[INFO] testing below-store-0.5.0 against beta-2022-04-10 for beta-1.61-1
[INFO] extracting crate below-store 0.5.0 into /workspace/builds/worker-18/source
[INFO] validating manifest of crates.io crate below-store 0.5.0 on toolchain beta-2022-04-10
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate below-store 0.5.0
[INFO] finished tweaking crates.io crate below-store 0.5.0
[INFO] tweaked toml for crates.io crate below-store 0.5.0 written to /workspace/builds/worker-18/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-04-10" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr] warning: version requirement `=0.8.0+zstd.1.4.9` for dependency `zstd` includes semver metadata which will be ignored, removing the metadata is recommended to avoid confusion
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f083f4226db54b50ea5931a220dbde3b6de635ed0ece38f8610e1f8349a8ccde
[INFO] running `Command { std: "docker" "start" "-a" "f083f4226db54b50ea5931a220dbde3b6de635ed0ece38f8610e1f8349a8ccde", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f083f4226db54b50ea5931a220dbde3b6de635ed0ece38f8610e1f8349a8ccde", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f083f4226db54b50ea5931a220dbde3b6de635ed0ece38f8610e1f8349a8ccde", kill_on_drop: false }`
[INFO] [stdout] f083f4226db54b50ea5931a220dbde3b6de635ed0ece38f8610e1f8349a8ccde
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 41af20237cdd283feec326282382b65dc4aaa0fce6d04a9a471603a47022cf11
[INFO] running `Command { std: "docker" "start" "-a" "41af20237cdd283feec326282382b65dc4aaa0fce6d04a9a471603a47022cf11", kill_on_drop: false }`
[INFO] [stderr] warning: version requirement `=0.8.0+zstd.1.4.9` for dependency `zstd` includes semver metadata which will be ignored, removing the metadata is recommended to avoid confusion
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling libc v0.2.123
[INFO] [stderr]    Compiling syn v1.0.91
[INFO] [stderr]    Compiling serde v1.0.136
[INFO] [stderr]    Compiling log v0.4.16
[INFO] [stderr]    Compiling ident_case v1.0.1
[INFO] [stderr]    Compiling strsim v0.9.3
[INFO] [stderr]    Compiling once_cell v1.10.0
[INFO] [stderr]    Compiling crossbeam-utils v0.8.8
[INFO] [stderr]    Compiling slog v2.7.0
[INFO] [stderr]    Compiling itoa v1.0.1
[INFO] [stderr]    Compiling anyhow v1.0.56
[INFO] [stderr]    Compiling signal-hook v0.3.13
[INFO] [stderr]    Compiling stable_deref_trait v1.2.0
[INFO] [stderr]    Compiling array-macro v1.0.5
[INFO] [stderr]    Compiling unicode-segmentation v1.9.0
[INFO] [stderr]    Compiling zstd-safe v4.0.0+zstd.1.4.9
[INFO] [stderr]    Compiling time-macros v0.2.4
[INFO] [stderr]    Compiling num_threads v0.1.5
[INFO] [stderr]    Compiling serde_json v1.0.79
[INFO] [stderr]    Compiling regex-syntax v0.6.25
[INFO] [stderr]    Compiling numtoa v0.1.0
[INFO] [stderr]    Compiling ryu v1.0.9
[INFO] [stderr]    Compiling match_cfg v0.1.0
[INFO] [stderr]    Compiling half v1.8.2
[INFO] [stderr]    Compiling humantime v2.1.0
[INFO] [stderr]    Compiling maplit v1.0.2
[INFO] [stderr]    Compiling static_assertions v1.1.0
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling memoffset v0.6.5
[INFO] [stderr]    Compiling num-iter v0.1.42
[INFO] [stderr]    Compiling num-rational v0.3.2
[INFO] [stderr]    Compiling ahash v0.6.3
[INFO] [stderr]    Compiling walkdir v2.3.2
[INFO] [stderr]    Compiling jobserver v0.1.24
[INFO] [stderr]    Compiling quote v1.0.18
[INFO] [stderr]    Compiling aho-corasick v0.7.18
[INFO] [stderr]    Compiling owning_ref v0.4.1
[INFO] [stderr]    Compiling cc v1.0.73
[INFO] [stderr]    Compiling thread_local v1.1.4
[INFO] [stderr]    Compiling crossbeam-channel v0.5.4
[INFO] [stderr]    Compiling num-complex v0.3.1
[INFO] [stderr]    Compiling zstd-sys v1.5.0+zstd.1.4.9
[INFO] [stderr]    Compiling regex v1.5.5
[INFO] [stderr]    Compiling num v0.3.1
[INFO] [stderr]    Compiling signal-hook-registry v1.4.0
[INFO] [stderr]    Compiling dirs-sys-next v0.1.2
[INFO] [stderr]    Compiling getrandom v0.2.6
[INFO] [stderr]    Compiling mio v0.7.14
[INFO] [stderr]    Compiling time v0.1.43
[INFO] [stderr]    Compiling parking_lot_core v0.8.5
[INFO] [stderr]    Compiling nix v0.22.3
[INFO] [stderr]    Compiling openat v0.1.21
[INFO] [stderr]    Compiling num_cpus v1.13.1
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling termion v1.5.6
[INFO] [stderr]    Compiling time v0.3.9
[INFO] [stderr]    Compiling hostname v0.3.1
[INFO] [stderr]    Compiling memmap v0.7.0
[INFO] [stderr]    Compiling dirs-next v2.0.0
[INFO] [stderr]    Compiling term v0.7.0
[INFO] [stderr]    Compiling threadpool v1.8.1
[INFO] [stderr]    Compiling signal-hook v0.1.17
[INFO] [stderr]    Compiling parking_lot v0.11.2
[INFO] [stderr]    Compiling crossterm v0.19.0
[INFO] [stderr]    Compiling darling_core v0.10.2
[INFO] [stderr]    Compiling serde_derive v1.0.136
[INFO] [stderr]    Compiling enum-map-derive v0.4.6
[INFO] [stderr]    Compiling thiserror-impl v1.0.30
[INFO] [stderr]    Compiling below_derive v0.5.0
[INFO] [stderr]    Compiling darling_macro v0.10.2
[INFO] [stderr]    Compiling enum-map v0.6.6
[INFO] [stderr]    Compiling darling v0.10.2
[INFO] [stderr]    Compiling wasmer_enumset_derive v0.5.0
[INFO] [stderr]    Compiling thiserror v1.0.30
[INFO] [stderr]    Compiling wasmer_enumset v1.0.1
[INFO] [stderr]    Compiling erased-serde v0.3.20
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling fb_procfs v0.5.0
[INFO] [stderr]    Compiling cgroupfs v0.5.0
[INFO] [stderr]    Compiling os_info v3.2.0
[INFO] [stderr]    Compiling serde_cbor v0.11.2
[INFO] [stderr]    Compiling bytes v1.1.0
[INFO] [stderr]    Compiling cursive_core v0.2.2
[INFO] [stderr]    Compiling slog-term v2.9.0
[INFO] [stderr]    Compiling below-model v0.5.0
[INFO] [stderr]    Compiling cursive v0.16.3
[INFO] [stderr]    Compiling below-common v0.5.0
[INFO] [stderr]    Compiling zstd v0.8.0+zstd.1.4.9
[INFO] [stderr]    Compiling below-store v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 29.53s
[INFO] running `Command { std: "docker" "inspect" "41af20237cdd283feec326282382b65dc4aaa0fce6d04a9a471603a47022cf11", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "41af20237cdd283feec326282382b65dc4aaa0fce6d04a9a471603a47022cf11", kill_on_drop: false }`
[INFO] [stdout] 41af20237cdd283feec326282382b65dc4aaa0fce6d04a9a471603a47022cf11
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0e7ed8398b5e858896b303869b0b23d9206fa885a232d1a1bc17e655ff7f6dd0
[INFO] running `Command { std: "docker" "start" "-a" "0e7ed8398b5e858896b303869b0b23d9206fa885a232d1a1bc17e655ff7f6dd0", kill_on_drop: false }`
[INFO] [stderr] warning: version requirement `=0.8.0+zstd.1.4.9` for dependency `zstd` includes semver metadata which will be ignored, removing the metadata is recommended to avoid confusion
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]    Compiling either v1.6.1
[INFO] [stderr]    Compiling remove_dir_all v0.5.3
[INFO] [stderr]    Compiling paste v1.0.7
[INFO] [stderr]    Compiling rand v0.4.6
[INFO] [stderr]    Compiling itertools v0.10.3
[INFO] [stderr]    Compiling tempdir v0.3.7
[INFO] [stderr]    Compiling below-store v0.5.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 22.27s
[INFO] [stderr]   Executable unittests src/lib.rs (/opt/rustwide/target/debug/deps/below_store-ee77074371608ce8)
[INFO] running `Command { std: "docker" "inspect" "0e7ed8398b5e858896b303869b0b23d9206fa885a232d1a1bc17e655ff7f6dd0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0e7ed8398b5e858896b303869b0b23d9206fa885a232d1a1bc17e655ff7f6dd0", kill_on_drop: false }`
[INFO] [stdout] 0e7ed8398b5e858896b303869b0b23d9206fa885a232d1a1bc17e655ff7f6dd0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-18/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=warn" "-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:b0c94ce3c1162fcb8e57cac5b65ec2f72eabb1eebea4fcc35e269e823f681646" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-04-10" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 6baa27ffb21f1741a33d8e0a76bd45becd70f0ca10011e4e7e9e7d6ecf50dc04
[INFO] running `Command { std: "docker" "start" "-a" "6baa27ffb21f1741a33d8e0a76bd45becd70f0ca10011e4e7e9e7d6ecf50dc04", kill_on_drop: false }`
[INFO] [stderr] warning: version requirement `=0.8.0+zstd.1.4.9` for dependency `zstd` includes semver metadata which will be ignored, removing the metadata is recommended to avoid confusion
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 1.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/below_store-ee77074371608ce8)
[INFO] [stdout] 
[INFO] [stdout] running 81 tests
[INFO] [stderr] Apr 13 09:39:17.940 WARN Expected file does not exist: /tmp/below_store_test.S8BgMfd8apxH/index_01649894400
[INFO] [stderr] Apr 13 09:39:17.940 WARN Expected file does not exist: /tmp/below_store_test.S8BgMfd8apxH/index_01650153600
[INFO] [stderr] Apr 13 09:39:17.944 WARN Corrupted index entry found: shard=1649808000 offset=0x0
[INFO] [stderr] Apr 13 09:39:17.944 WARN Corrupted index entry found: shard=1649808000 offset=0x0
[INFO] [stderr] Apr 13 09:39:17.944 WARN Failed to extract serialized data frame: Failed to get index entry at offset 0
[INFO] [stderr] Apr 13 09:39:17.944 WARN Expected file does not exist: /tmp/below_store_test.kgatgnk9ZlUh/index_01650067200
[INFO] [stderr] Apr 13 09:39:17.945 WARN Expected file does not exist: /tmp/below_store_test.F86mXNCecGtq/index_01649894400
[INFO] [stderr] Apr 13 09:39:17.954 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1649842762 offset=0x437
[INFO] [stderr] Apr 13 09:39:17.959 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1649842757 offset=0x0
[INFO] [stderr] Apr 13 09:39:18.004 WARN Expected file does not exist: /tmp/below_store_test.fJtFbiFQFI67/index_00000086400
[INFO] [stdout] test advance::tests::advance_test_advance_continous_move ... ok
[INFO] [stdout] test advance::tests::store_operation_test_get_adjacent_sample_at_timestamp ... ok
[INFO] [stdout] test advance::tests::advance_test_advance_direction_change ... ok
[INFO] [stdout] test advance::tests::advance_test_initialize ... ok
[INFO] [stdout] test advance::tests::advance_test_jump_util ... ok
[INFO] [stdout] test advance::tests::store_operation_test_with_fake_store ... ok
[INFO] [stdout] test advance::tests::advance_test_jump_sample_to ... ok
[INFO] [stdout] test advance::tests::advance_test_get_next_ts ... ok
[INFO] [stderr] Apr 13 09:39:18.025 WARN Index length not a multiple of fixed index entry size: 90. Padding to size: 96
[INFO] [stderr] Apr 13 09:39:18.025 WARN Corrupted index entry found: shard=86400 offset=0x20
[INFO] [stderr] Apr 13 09:39:18.025 WARN Corrupted index entry found: shard=86400 offset=0x40
[INFO] [stderr] Apr 13 09:39:18.034 WARN Data length mismatch: 568 (expect 567)
[INFO] [stdout] test cursor::tests::default_get_next ... ok
[INFO] [stdout] test cursor::tests::default_next ... ok
[INFO] [stdout] test cursor::tests::default_get_near ... ok
[INFO] [stdout] test cursor::tests::advance_retry ... ok
[INFO] [stdout] test cursor::tests::advance_simple ... ok
[INFO] [stdout] test cursor::tests::advance_at_boundries ... ok
[INFO] [stdout] test cursor::tests::default_jump_to_key ... ok
[INFO] [stdout] test cursor::tests::advance_from_invalid_offset ... ok
[INFO] [stdout] test test::test_cbor::test_removing_optional_field ... ok
[INFO] [stdout] test test::test_cbor::test_adding_field ... ok
[INFO] [stdout] test cursor::tests::get_corrupt ... ok
[INFO] [stdout] test cursor::tests::advance_when_empty ... ok
[INFO] [stdout] test cursor::tests::jump_to_key ... ok
[INFO] [stdout] test cursor::tests::manipulate_offset ... ok
[INFO] [stdout] test test::test_cbor::test_changing_optional_to_required ... ok
[INFO] [stdout] test test::test_cbor::test_changing_required_to_optional ... ok
[INFO] [stdout] test cursor::tests::read_cbor ... ok
[INFO] [stdout] test tests::create_writer_compressed_cbor ... ok
[INFO] [stdout] test test::test_cbor::test_removing_field ... ok
[INFO] [stdout] test test::test_cbor::test_adding_optional_field ... ok
[INFO] [stdout] test tests::put_and_read_work_across_shards_uncompressed_cbor ... ok
[INFO] [stdout] test cursor::tests::read_compressed_cbor ... ok
[INFO] [stdout] test tests::create_writer_dict_compressed_cbor ... ok
[INFO] [stdout] test tests::read_reverse_uncompressed_cbor ... ok
[INFO] [stdout] test tests::read_reverse_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.080 WARN Expected file does not exist: /tmp/below_store_test.2xqihdBs9GDh/index_00000086400
[INFO] [stdout] test tests::put_new_shard_compressed_cbor ... ok
[INFO] [stdout] test tests::read_iterates_appropriately_uncompressed_cbor ... ok
[INFO] [stdout] test tests::read_reverse_across_shards_dict_compressed_cbor ... ok
[INFO] [stdout] test tests::read_reverse_dict_compressed_cbor ... ok
[INFO] [stdout] test tests::read_skips_over_corrupt_index_entry_uncompressed_cbor ... ok
[INFO] [stdout] test tests::read_reverse_across_shards_uncompressed_cbor ... ok
[INFO] [stdout] test tests::simple_put_read_10_uncompressed_cbor ... ok
[INFO] [stdout] test tests::writer_creates_directory_compressed_cbor ... ok
[INFO] [stdout] test tests::simple_put_read_uncompressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.098 WARN Expected file does not exist: /tmp/below_store_test.2xqihdBs9GDh/index_00000086400
[INFO] [stderr] Apr 13 09:39:18.099 WARN Expected file does not exist: /tmp/below_store_test.2xqihdBs9GDh/index_00000086400
[INFO] [stderr] Apr 13 09:39:18.100 WARN Expected file does not exist: /tmp/below_store_test.2xqihdBs9GDh/index_00000086400
[INFO] [stderr] Apr 13 09:39:18.101 INFO Padding index so that first entry of block is aligned. Current len: 224. New len: 256
[INFO] [stderr] Apr 13 09:39:18.101 INFO Padding index so that first entry of block is aligned. Current len: 320. New len: 384
[INFO] [stderr] Apr 13 09:39:18.102 INFO Padding index so that first entry of block is aligned. Current len: 448. New len: 512
[INFO] [stderr] Apr 13 09:39:18.104 INFO Padding index so that first entry of block is aligned. Current len: 832. New len: 896
[INFO] [stderr] Apr 13 09:39:18.104 INFO Padding index so that first entry of block is aligned. Current len: 960. New len: 1024
[INFO] [stderr] Apr 13 09:39:18.106 INFO Padding index so that first entry of block is aligned. Current len: 1344. New len: 1408
[INFO] [stderr] Apr 13 09:39:18.107 INFO Padding index so that first entry of block is aligned. Current len: 1472. New len: 1536
[INFO] [stderr] Apr 13 09:39:18.107 INFO Padding index so that first entry of block is aligned. Current len: 1568. New len: 1600
[INFO] [stderr] Apr 13 09:39:18.108 INFO Padding index so that first entry of block is aligned. Current len: 1824. New len: 1856
[INFO] [stderr] Apr 13 09:39:18.109 INFO Padding index so that first entry of block is aligned. Current len: 1888. New len: 1920
[INFO] [stderr] Apr 13 09:39:18.109 INFO Padding index so that first entry of block is aligned. Current len: 1952. New len: 2048
[INFO] [stderr] Apr 13 09:39:18.109 INFO Padding index so that first entry of block is aligned. Current len: 2080. New len: 2112
[INFO] [stderr] Apr 13 09:39:18.109 INFO Padding index so that first entry of block is aligned. Current len: 2144. New len: 2304
[INFO] [stderr] Apr 13 09:39:18.110 INFO Padding index so that first entry of block is aligned. Current len: 2336. New len: 2432
[INFO] [stderr] Apr 13 09:39:18.110 INFO Padding index so that first entry of block is aligned. Current len: 2496. New len: 2560
[INFO] [stderr] Apr 13 09:39:18.110 INFO Padding index so that first entry of block is aligned. Current len: 2624. New len: 2688
[INFO] [stderr] Apr 13 09:39:18.111 INFO Padding index so that first entry of block is aligned. Current len: 2752. New len: 2816
[INFO] [stderr] Apr 13 09:39:18.111 INFO Padding index so that first entry of block is aligned. Current len: 2848. New len: 2880
[INFO] [stderr] Apr 13 09:39:18.111 INFO Padding index so that first entry of block is aligned. Current len: 2912. New len: 2944
[INFO] [stderr] Apr 13 09:39:18.111 INFO Padding index so that first entry of block is aligned. Current len: 2976. New len: 3072
[INFO] [stderr] Apr 13 09:39:18.111 INFO Padding index so that first entry of block is aligned. Current len: 3104. New len: 3200
[INFO] [stderr] Apr 13 09:39:18.112 INFO Padding index so that first entry of block is aligned. Current len: 3232. New len: 3328
[INFO] [stderr] Apr 13 09:39:18.112 INFO Padding index so that first entry of block is aligned. Current len: 3360. New len: 3584
[INFO] [stderr] Apr 13 09:39:18.112 INFO Padding index so that first entry of block is aligned. Current len: 3648. New len: 3840
[INFO] [stderr] Apr 13 09:39:18.113 INFO Padding index so that first entry of block is aligned. Current len: 3904. New len: 4096
[INFO] [stdout] test tests::put_and_read_work_across_shards_dict_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.113 INFO Padding index so that first entry of block is aligned. Current len: 4160. New len: 4352
[INFO] [stdout] test tests::simple_put_read_10_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.113 INFO Padding index so that first entry of block is aligned. Current len: 4384. New len: 4416
[INFO] [stderr] Apr 13 09:39:18.114 INFO Padding index so that first entry of block is aligned. Current len: 4448. New len: 4608
[INFO] [stderr] Apr 13 09:39:18.114 INFO Padding index so that first entry of block is aligned. Current len: 4640. New len: 4736
[INFO] [stderr] Apr 13 09:39:18.114 INFO Padding index so that first entry of block is aligned. Current len: 4768. New len: 4864
[INFO] [stderr] Apr 13 09:39:18.114 INFO Padding index so that first entry of block is aligned. Current len: 4896. New len: 5120
[INFO] [stderr] Apr 13 09:39:18.121 WARN Expected file does not exist: /tmp/below_store_test.IZmKMMp84E9f/index_00000086400
[INFO] [stderr] Apr 13 09:39:18.122 WARN Expected file does not exist: /tmp/below_store_test.IZmKMMp84E9f/index_00000086400
[INFO] [stderr] Apr 13 09:39:18.123 WARN Expected file does not exist: /tmp/below_store_test.IZmKMMp84E9f/index_00000086400
[INFO] [stderr] Apr 13 09:39:18.123 WARN Expected file does not exist: /tmp/below_store_test.IZmKMMp84E9f/index_00000086400
[INFO] [stdout] test test::test_cbor::test_serialize_deserialize ... ok
[INFO] [stdout] test tests::try_discard_until_size_dict_compressed_cbor ... ok
[INFO] [stdout] test tests::try_discard_until_size_uncompressed_cbor ... ok
[INFO] [stdout] test tests::writer_creates_directory_uncompressed_cbor ... ok
[INFO] [stdout] test tests::simple_put_read_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.166 WARN Data length mismatch: 568 (expect 567)
[INFO] [stdout] test tests::flock_protects_dict_compressed_cbor ... ok
[INFO] [stdout] test tests::create_writer_uncompressed_cbor ... ok
[INFO] [stdout] test tests::read_past_the_end_returns_none_dict_compressed_cbor ... ok
[INFO] [stdout] test tests::read_past_the_end_returns_none_compressed_cbor ... ok
[INFO] [stdout] test tests::put_new_shard_uncompressed_cbor ... ok
[INFO] [stdout] test tests::read_past_the_end_returns_none_uncompressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.179 INFO Padding index so that first entry of block is aligned. Current len: 160. New len: 256
[INFO] [stdout] test tests::put_read_corrupt_data_dict_compressed_cbor ... ok
[INFO] [stdout] test tests::flock_protects_uncompressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.182 WARN Expected file does not exist: /tmp/below_store_test.9bJGeX6aZ6ED/index_00000086400
[INFO] [stdout] test tests::flock_protects_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.185 WARN Expected file does not exist: /tmp/below_store_test.9zeXMEpieUEv/index_00000086400
[INFO] [stdout] test tests::put_read_corrupt_data_uncompressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.185 WARN Data length mismatch: 1082 (expect 1081)
[INFO] [stdout] test tests::writer_creates_directory_dict_compressed_cbor ... ok
[INFO] [stdout] test tests::discard_earlier_uncompressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.194 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1649842767 offset=0x86e
[INFO] [stderr] Apr 13 09:39:18.194 WARN Corrupted index entry found: shard=1649808000 offset=0x60
[INFO] [stderr] Apr 13 09:39:18.194 WARN Failed to extract serialized data frame: Failed to get index entry at offset 96
[INFO] [stderr] Apr 13 09:39:18.194 WARN Index length not a multiple of fixed index entry size: 90. Padding to size: 96
[INFO] [stderr] Apr 13 09:39:18.195 INFO Padding index so that first entry of block is aligned. Current len: 96. New len: 128
[INFO] [stderr] Apr 13 09:39:18.196 WARN Index length not a multiple of fixed index entry size: 90. Padding to size: 96
[INFO] [stdout] test tests::put_and_read_work_across_shards_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.202 WARN Corrupted index entry found: shard=86400 offset=0x20
[INFO] [stderr] Apr 13 09:39:18.204 WARN Corrupted index entry found: shard=86400 offset=0x40
[INFO] [stdout] test tests::discard_earlier_dict_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.209 WARN Corrupted index entry found: shard=86400 offset=0x20
[INFO] [stderr] Apr 13 09:39:18.209 WARN Corrupted index entry found: shard=86400 offset=0x40
[INFO] [stdout] test tests::read_iterates_appropriately_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.210 WARN Expected file does not exist: /tmp/below_store_test.Oly7h2k37gnE/index_00000086400
[INFO] [stderr] Apr 13 09:39:18.226 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1649929157 offset=0x0
[INFO] [stdout] test cursor::tests::read_dict_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.227 WARN Corrupted index entry found: shard=1649980800 offset=0x0
[INFO] [stdout] test tests::read_skips_over_corrupt_index_entry_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.227 WARN Failed to extract serialized data frame: Failed to get index entry at offset 0
[INFO] [stdout] test tests::simple_put_read_dict_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.228 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1650101957 offset=0x0
[INFO] [stdout] test tests::read_reverse_across_shards_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.237 INFO Padding index so that first entry of block is aligned. Current len: 32. New len: 128
[INFO] [stdout] test tests::discard_earlier_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.244 WARN Corrupted index entry found: shard=1649980800 offset=0x0
[INFO] [stdout] test tests::writing_to_already_written_index_with_different_compression_format_works ... ok
[INFO] [stderr] Apr 13 09:39:18.244 WARN Failed to extract serialized data frame: Failed to get index entry at offset 0
[INFO] [stdout] test tests::read_skips_over_corrupt_index_entry_dict_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.244 INFO Padding index so that first entry of block is aligned. Current len: 32. New len: 128
[INFO] [stdout] test tests::write_index_padding ... ok
[INFO] [stderr] Apr 13 09:39:18.245 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1649929157 offset=0x0
[INFO] [stdout] test tests::simple_put_read_10_dict_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.245 WARN Corrupted index entry found: shard=1649808000 offset=0x60
[INFO] [stdout] test tests::writing_to_already_written_index_works_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.245 WARN Failed to extract serialized data frame: Failed to get index entry at offset 96
[INFO] [stdout] test tests::read_iterates_appropriately_dict_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.245 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1649842767 offset=0x86e
[INFO] [stdout] test tests::put_read_corrupt_data_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.246 WARN Expected file does not exist: /tmp/below_store_test.Oly7h2k37gnE/index_00000086400
[INFO] [stdout] test tests::writing_to_already_written_index_works_uncompressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.249 WARN Expected file does not exist: /tmp/below_store_test.Oly7h2k37gnE/index_00000086400
[INFO] [stdout] test cursor::tests::skip_corrupt ... ok
[INFO] [stdout] test tests::writing_to_already_written_index_works_dict_compressed_cbor ... ok
[INFO] [stderr] Apr 13 09:39:18.250 WARN Expected file does not exist: /tmp/below_store_test.Oly7h2k37gnE/index_00000086400
[INFO] [stdout] test tests::put_new_shard_dict_compressed_cbor ... ok
[INFO] [stdout] test tests::try_discard_until_size_compressed_cbor ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 81 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.34s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests below-store
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "6baa27ffb21f1741a33d8e0a76bd45becd70f0ca10011e4e7e9e7d6ecf50dc04", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6baa27ffb21f1741a33d8e0a76bd45becd70f0ca10011e4e7e9e7d6ecf50dc04", kill_on_drop: false }`
[INFO] [stdout] 6baa27ffb21f1741a33d8e0a76bd45becd70f0ca10011e4e7e9e7d6ecf50dc04