[INFO] fetching crate below-store 0.5.0... [INFO] testing below-store-0.5.0 against beta-2022-02-22 for beta-1.60-1 [INFO] extracting crate below-store 0.5.0 into /workspace/builds/worker-0/source [INFO] validating manifest of crates.io crate below-store 0.5.0 on toolchain beta-2022-02-22 [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "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-0/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+beta-2022-02-22" "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] [stderr] Downloading crates ... [INFO] [stderr] Downloaded paste v1.0.6 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 278e590219bba17ec448285f045fb8d0b461f580294dc89e61a28be26d25ed8b [INFO] running `Command { std: "docker" "start" "-a" "278e590219bba17ec448285f045fb8d0b461f580294dc89e61a28be26d25ed8b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "278e590219bba17ec448285f045fb8d0b461f580294dc89e61a28be26d25ed8b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "278e590219bba17ec448285f045fb8d0b461f580294dc89e61a28be26d25ed8b", kill_on_drop: false }` [INFO] [stdout] 278e590219bba17ec448285f045fb8d0b461f580294dc89e61a28be26d25ed8b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 3926541140f39cd62a3f73cc63613c77b6a636fc01de53bc7ce051f97a12892f [INFO] running `Command { std: "docker" "start" "-a" "3926541140f39cd62a3f73cc63613c77b6a636fc01de53bc7ce051f97a12892f", 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] Compiling syn v1.0.86 [INFO] [stderr] Compiling serde v1.0.136 [INFO] [stderr] Compiling strsim v0.9.3 [INFO] [stderr] Compiling proc-macro2 v1.0.36 [INFO] [stderr] Compiling crossbeam-utils v0.8.7 [INFO] [stderr] Compiling once_cell v1.9.0 [INFO] [stderr] Compiling erased-serde v0.3.18 [INFO] [stderr] Compiling slog v2.7.0 [INFO] [stderr] Compiling anyhow v1.0.55 [INFO] [stderr] Compiling array-macro v1.0.5 [INFO] [stderr] Compiling signal-hook v0.3.13 [INFO] [stderr] Compiling serde_json v1.0.79 [INFO] [stderr] Compiling num_threads v0.1.3 [INFO] [stderr] Compiling time-macros v0.2.3 [INFO] [stderr] Compiling unicode-segmentation v1.9.0 [INFO] [stderr] Compiling zstd-safe v4.0.0+zstd.1.4.9 [INFO] [stderr] Compiling numtoa v0.1.0 [INFO] [stderr] Compiling xi-unicode v0.3.0 [INFO] [stderr] Compiling match_cfg v0.1.0 [INFO] [stderr] Compiling maplit v1.0.2 [INFO] [stderr] Compiling num-traits v0.2.14 [INFO] [stderr] Compiling num-integer v0.1.44 [INFO] [stderr] Compiling num-rational v0.3.2 [INFO] [stderr] Compiling num-iter v0.1.42 [INFO] [stderr] Compiling ahash v0.6.3 [INFO] [stderr] Compiling signal-hook-registry v1.4.0 [INFO] [stderr] Compiling time v0.1.43 [INFO] [stderr] Compiling dirs-sys-next v0.1.2 [INFO] [stderr] Compiling getrandom v0.2.5 [INFO] [stderr] Compiling openat v0.1.21 [INFO] [stderr] Compiling memmap v0.7.0 [INFO] [stderr] Compiling jobserver v0.1.24 [INFO] [stderr] Compiling mio v0.7.14 [INFO] [stderr] Compiling threadpool v1.8.1 [INFO] [stderr] Compiling nix v0.22.3 [INFO] [stderr] Compiling hostname v0.3.1 [INFO] [stderr] Compiling thread_local v1.1.4 [INFO] [stderr] Compiling dirs-next v2.0.0 [INFO] [stderr] Compiling cc v1.0.73 [INFO] [stderr] Compiling termion v1.5.6 [INFO] [stderr] Compiling term v0.7.0 [INFO] [stderr] Compiling signal-hook v0.1.17 [INFO] [stderr] Compiling crossterm v0.19.0 [INFO] [stderr] Compiling crossbeam-channel v0.5.2 [INFO] [stderr] Compiling quote v1.0.15 [INFO] [stderr] Compiling time v0.3.7 [INFO] [stderr] Compiling zstd-sys v1.5.0+zstd.1.4.9 [INFO] [stderr] Compiling num-complex v0.3.1 [INFO] [stderr] Compiling num v0.3.1 [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 chrono v0.4.19 [INFO] [stderr] Compiling os_info v3.2.0 [INFO] [stderr] Compiling cgroupfs v0.5.0 [INFO] [stderr] Compiling fb_procfs v0.5.0 [INFO] [stderr] Compiling bytes v1.1.0 [INFO] [stderr] Compiling serde_cbor v0.11.2 [INFO] [stderr] Compiling slog-term v2.9.0 [INFO] [stderr] Compiling cursive_core v0.2.2 [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 35.27s [INFO] running `Command { std: "docker" "inspect" "3926541140f39cd62a3f73cc63613c77b6a636fc01de53bc7ce051f97a12892f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3926541140f39cd62a3f73cc63613c77b6a636fc01de53bc7ce051f97a12892f", kill_on_drop: false }` [INFO] [stdout] 3926541140f39cd62a3f73cc63613c77b6a636fc01de53bc7ce051f97a12892f [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 239ca1a118f5d1e7779a10b9b51377a53ff56b711a43c8110c72e00636a45cb8 [INFO] running `Command { std: "docker" "start" "-a" "239ca1a118f5d1e7779a10b9b51377a53ff56b711a43c8110c72e00636a45cb8", 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] Compiling paste v1.0.6 [INFO] [stderr] Compiling rand v0.4.6 [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 10.79s [INFO] running `Command { std: "docker" "inspect" "239ca1a118f5d1e7779a10b9b51377a53ff56b711a43c8110c72e00636a45cb8", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "239ca1a118f5d1e7779a10b9b51377a53ff56b711a43c8110c72e00636a45cb8", kill_on_drop: false }` [INFO] [stdout] 239ca1a118f5d1e7779a10b9b51377a53ff56b711a43c8110c72e00636a45cb8 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0/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:0cd99ca24d8e8c98e67c542213511d985b8778b5bdcbb160e038429496686047" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2022-02-22" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 937c379fdbefeb64ec098fc70d28a21fb13db17d8fc80871e312ca982e17aee7 [INFO] running `Command { std: "docker" "start" "-a" "937c379fdbefeb64ec098fc70d28a21fb13db17d8fc80871e312ca982e17aee7", 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] Finished test [unoptimized + debuginfo] target(s) in 0.06s [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/below_store-28a92b550d6ffa0e) [INFO] [stdout] [INFO] [stdout] running 81 tests [INFO] [stderr] Feb 26 13:43:28.370 WARN Expected file does not exist: /tmp/below_store_test.AfLGLkd6yB1N/index_01645920000 [INFO] [stderr] Feb 26 13:43:28.371 WARN Expected file does not exist: /tmp/below_store_test.AfLGLkd6yB1N/index_01646179200 [INFO] [stderr] Feb 26 13:43:28.371 WARN Corrupted index entry found: shard=1645833600 offset=0x0 [INFO] [stderr] Feb 26 13:43:28.371 WARN Corrupted index entry found: shard=1645833600 offset=0x0 [INFO] [stderr] Feb 26 13:43:28.371 WARN Failed to extract serialized data frame: Failed to get index entry at offset 0 [INFO] [stderr] Feb 26 13:43:28.372 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1645883013 offset=0x437 [INFO] [stderr] Feb 26 13:43:28.376 WARN Expected file does not exist: /tmp/below_store_test.1BsAMG4XWtMz/index_01645920000 [INFO] [stderr] Feb 26 13:43:28.377 WARN Expected file does not exist: /tmp/below_store_test.EBZCpx0DX29q/index_01646092800 [INFO] [stderr] Feb 26 13:43:28.385 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1645883008 offset=0x0 [INFO] [stderr] Feb 26 13:43:28.387 WARN Expected file does not exist: /tmp/below_store_test.pBaPtaAo75jA/index_00000086400 [INFO] [stderr] Feb 26 13:43:28.388 WARN Expected file does not exist: /tmp/below_store_test.MCmB3HUZxkfB/index_00000086400 [INFO] [stdout] test advance::tests::advance_test_advance_direction_change ... ok [INFO] [stdout] test advance::tests::advance_test_advance_continous_move ... ok [INFO] [stdout] test advance::tests::advance_test_initialize ... ok [INFO] [stdout] test advance::tests::advance_test_get_next_ts ... ok [INFO] [stdout] test advance::tests::advance_test_jump_sample_to ... ok [INFO] [stdout] test advance::tests::store_operation_test_get_adjacent_sample_at_timestamp ... ok [INFO] [stdout] test advance::tests::store_operation_test_with_fake_store ... ok [INFO] [stdout] test advance::tests::advance_test_jump_util ... ok [INFO] [stderr] Feb 26 13:43:28.391 WARN Data length mismatch: 568 (expect 567) [INFO] [stdout] test cursor::tests::default_get_next ... ok [INFO] [stdout] test cursor::tests::default_get_near ... ok [INFO] [stdout] test cursor::tests::default_jump_to_key ... ok [INFO] [stdout] test cursor::tests::default_next ... ok [INFO] [stdout] test cursor::tests::advance_when_empty ... ok [INFO] [stdout] test cursor::tests::advance_at_boundries ... ok [INFO] [stdout] test cursor::tests::advance_from_invalid_offset ... ok [INFO] [stdout] test cursor::tests::get_corrupt ... ok [INFO] [stdout] test test::test_cbor::test_adding_optional_field ... ok [INFO] [stdout] test cursor::tests::advance_simple ... ok [INFO] [stdout] test test::test_cbor::test_adding_field ... ok [INFO] [stdout] test test::test_cbor::test_changing_optional_to_required ... ok [INFO] [stdout] test cursor::tests::advance_retry ... ok [INFO] [stdout] test test::test_cbor::test_serialize_deserialize ... ok [INFO] [stdout] test test::test_cbor::test_changing_required_to_optional ... ok [INFO] [stdout] test test::test_cbor::test_removing_field ... ok [INFO] [stdout] test test::test_cbor::test_removing_optional_field ... ok [INFO] [stdout] test cursor::tests::manipulate_offset ... ok [INFO] [stdout] test tests::create_writer_compressed_cbor ... ok [INFO] [stdout] test tests::create_writer_uncompressed_cbor ... ok [INFO] [stdout] test tests::create_writer_dict_compressed_cbor ... ok [INFO] [stdout] test tests::flock_protects_compressed_cbor ... ok [INFO] [stdout] test tests::flock_protects_dict_compressed_cbor ... ok [INFO] [stdout] test cursor::tests::read_cbor ... ok [INFO] [stdout] test tests::flock_protects_uncompressed_cbor ... ok [INFO] [stdout] test cursor::tests::jump_to_key ... ok [INFO] [stderr] Feb 26 13:43:28.399 WARN Expected file does not exist: /tmp/below_store_test.W5plbpl3ScnH/index_00000086400 [INFO] [stderr] Feb 26 13:43:28.401 WARN Index length not a multiple of fixed index entry size: 90. Padding to size: 96 [INFO] [stderr] Feb 26 13:43:28.399 WARN Data length mismatch: 1082 (expect 1081) [INFO] [stderr] Feb 26 13:43:28.403 WARN Data length mismatch: 568 (expect 567) [INFO] [stdout] test tests::read_past_the_end_returns_none_dict_compressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.408 WARN Corrupted index entry found: shard=86400 offset=0x20 [INFO] [stderr] Feb 26 13:43:28.408 WARN Corrupted index entry found: shard=86400 offset=0x40 [INFO] [stdout] test cursor::tests::read_compressed_cbor ... ok [INFO] [stdout] test tests::put_new_shard_compressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.416 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1645883018 offset=0x86e [INFO] [stderr] Feb 26 13:43:28.416 WARN Corrupted index entry found: shard=1645833600 offset=0x60 [INFO] [stderr] Feb 26 13:43:28.416 WARN Failed to extract serialized data frame: Failed to get index entry at offset 96 [INFO] [stderr] Feb 26 13:43:28.420 INFO Padding index so that first entry of block is aligned. Current len: 32. New len: 128 [INFO] [stderr] Feb 26 13:43:28.420 WARN Index length not a multiple of fixed index entry size: 90. Padding to size: 96 [INFO] [stdout] test tests::read_past_the_end_returns_none_uncompressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.424 WARN Corrupted index entry found: shard=86400 offset=0x20 [INFO] [stderr] Feb 26 13:43:28.424 WARN Corrupted index entry found: shard=86400 offset=0x40 [INFO] [stderr] Feb 26 13:43:28.425 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1645969408 offset=0x0 [INFO] [stdout] test tests::read_reverse_uncompressed_cbor ... ok [INFO] [stdout] test tests::read_past_the_end_returns_none_compressed_cbor ... ok [INFO] [stdout] test tests::simple_put_read_dict_compressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.437 INFO Padding index so that first entry of block is aligned. Current len: 160. New len: 256 [INFO] [stderr] Feb 26 13:43:28.438 INFO Padding index so that first entry of block is aligned. Current len: 224. New len: 256 [INFO] [stderr] Feb 26 13:43:28.438 INFO Padding index so that first entry of block is aligned. Current len: 320. New len: 384 [INFO] [stderr] Feb 26 13:43:28.439 INFO Padding index so that first entry of block is aligned. Current len: 448. New len: 512 [INFO] [stderr] Feb 26 13:43:28.447 WARN Corrupted index entry found: shard=1646006400 offset=0x0 [INFO] [stderr] Feb 26 13:43:28.447 WARN Failed to extract serialized data frame: Failed to get index entry at offset 0 [INFO] [stdout] test cursor::tests::read_dict_compressed_cbor ... ok [INFO] [stdout] test tests::writing_to_already_written_index_works_uncompressed_cbor ... ok [INFO] [stdout] test tests::writing_to_already_written_index_works_compressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.449 INFO Padding index so that first entry of block is aligned. Current len: 832. New len: 896 [INFO] [stderr] Feb 26 13:43:28.449 INFO Padding index so that first entry of block is aligned. Current len: 960. New len: 1024 [INFO] [stderr] Feb 26 13:43:28.449 INFO Padding index so that first entry of block is aligned. Current len: 32. New len: 128 [INFO] [stderr] Feb 26 13:43:28.453 INFO Padding index so that first entry of block is aligned. Current len: 1344. New len: 1408 [INFO] [stderr] Feb 26 13:43:28.453 INFO Padding index so that first entry of block is aligned. Current len: 1472. New len: 1536 [INFO] [stderr] Feb 26 13:43:28.454 INFO Padding index so that first entry of block is aligned. Current len: 1568. New len: 1600 [INFO] [stderr] Feb 26 13:43:28.455 INFO Padding index so that first entry of block is aligned. Current len: 1824. New len: 1856 [INFO] [stderr] Feb 26 13:43:28.455 INFO Padding index so that first entry of block is aligned. Current len: 1888. New len: 1920 [INFO] [stderr] Feb 26 13:43:28.455 INFO Padding index so that first entry of block is aligned. Current len: 1952. New len: 2048 [INFO] [stderr] Feb 26 13:43:28.456 INFO Padding index so that first entry of block is aligned. Current len: 2080. New len: 2112 [INFO] [stderr] Feb 26 13:43:28.456 INFO Padding index so that first entry of block is aligned. Current len: 2144. New len: 2304 [INFO] [stderr] Feb 26 13:43:28.456 INFO Padding index so that first entry of block is aligned. Current len: 2336. New len: 2432 [INFO] [stderr] Feb 26 13:43:28.456 INFO Padding index so that first entry of block is aligned. Current len: 2496. New len: 2560 [INFO] [stderr] Feb 26 13:43:28.457 INFO Padding index so that first entry of block is aligned. Current len: 2624. New len: 2688 [INFO] [stderr] Feb 26 13:43:28.458 INFO Padding index so that first entry of block is aligned. Current len: 2752. New len: 2816 [INFO] [stderr] Feb 26 13:43:28.458 INFO Padding index so that first entry of block is aligned. Current len: 2848. New len: 2880 [INFO] [stderr] Feb 26 13:43:28.458 INFO Padding index so that first entry of block is aligned. Current len: 2912. New len: 2944 [INFO] [stderr] Feb 26 13:43:28.458 INFO Padding index so that first entry of block is aligned. Current len: 2976. New len: 3072 [INFO] [stderr] Feb 26 13:43:28.459 INFO Padding index so that first entry of block is aligned. Current len: 3104. New len: 3200 [INFO] [stderr] Feb 26 13:43:28.459 INFO Padding index so that first entry of block is aligned. Current len: 3232. New len: 3328 [INFO] [stderr] Feb 26 13:43:28.459 INFO Padding index so that first entry of block is aligned. Current len: 3360. New len: 3584 [INFO] [stderr] Feb 26 13:43:28.459 INFO Padding index so that first entry of block is aligned. Current len: 3648. New len: 3840 [INFO] [stderr] Feb 26 13:43:28.460 INFO Padding index so that first entry of block is aligned. Current len: 3904. New len: 4096 [INFO] [stderr] Feb 26 13:43:28.460 INFO Padding index so that first entry of block is aligned. Current len: 4160. New len: 4352 [INFO] [stderr] Feb 26 13:43:28.460 INFO Padding index so that first entry of block is aligned. Current len: 4384. New len: 4416 [INFO] [stderr] Feb 26 13:43:28.461 WARN Index length not a multiple of fixed index entry size: 90. Padding to size: 96 [INFO] [stdout] test tests::read_iterates_appropriately_uncompressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.461 INFO Padding index so that first entry of block is aligned. Current len: 96. New len: 128 [INFO] [stderr] Feb 26 13:43:28.463 INFO Padding index so that first entry of block is aligned. Current len: 4448. New len: 4608 [INFO] [stderr] Feb 26 13:43:28.463 INFO Padding index so that first entry of block is aligned. Current len: 4640. New len: 4736 [INFO] [stderr] Feb 26 13:43:28.463 INFO Padding index so that first entry of block is aligned. Current len: 4768. New len: 4864 [INFO] [stderr] Feb 26 13:43:28.464 WARN Corrupted index entry found: shard=86400 offset=0x20 [INFO] [stderr] Feb 26 13:43:28.464 WARN Corrupted index entry found: shard=86400 offset=0x40 [INFO] [stderr] Feb 26 13:43:28.464 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1646142208 offset=0x0 [INFO] [stderr] Feb 26 13:43:28.465 INFO Padding index so that first entry of block is aligned. Current len: 4896. New len: 5120 [INFO] [stderr] Feb 26 13:43:28.468 WARN Corrupted index entry found: shard=1646006400 offset=0x0 [INFO] [stderr] Feb 26 13:43:28.468 WARN Failed to extract serialized data frame: Failed to get index entry at offset 0 [INFO] [stdout] test tests::put_read_corrupt_data_uncompressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.478 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1645969408 offset=0x0 [INFO] [stdout] test tests::discard_earlier_uncompressed_cbor ... ok [INFO] [stdout] test tests::put_and_read_work_across_shards_uncompressed_cbor ... ok [INFO] [stdout] test tests::read_reverse_across_shards_uncompressed_cbor ... ok [INFO] [stdout] test tests::discard_earlier_compressed_cbor ... ok [INFO] [stdout] test tests::read_iterates_appropriately_dict_compressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.482 WARN Corrupted index entry found: shard=1645833600 offset=0x60 [INFO] [stdout] test tests::discard_earlier_dict_compressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.482 WARN Failed to extract serialized data frame: Failed to get index entry at offset 96 [INFO] [stdout] test tests::simple_put_read_uncompressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.483 WARN Failed to extract serialized data frame: Corrupted data entry found: ts=1645883018 offset=0x86e [INFO] [stdout] test tests::read_skips_over_corrupt_index_entry_uncompressed_cbor ... ok [INFO] [stdout] test tests::read_reverse_compressed_cbor ... ok [INFO] [stdout] test tests::read_skips_over_corrupt_index_entry_dict_compressed_cbor ... ok [INFO] [stdout] test tests::put_new_shard_uncompressed_cbor ... ok [INFO] [stdout] test tests::read_reverse_across_shards_dict_compressed_cbor ... ok [INFO] [stdout] test cursor::tests::skip_corrupt ... ok [INFO] [stdout] test tests::writer_creates_directory_dict_compressed_cbor ... ok [INFO] [stdout] test tests::simple_put_read_10_uncompressed_cbor ... ok [INFO] [stdout] test tests::put_read_corrupt_data_dict_compressed_cbor ... ok [INFO] [stdout] test tests::simple_put_read_compressed_cbor ... ok [INFO] [stdout] test tests::put_new_shard_dict_compressed_cbor ... ok [INFO] [stdout] test tests::put_read_corrupt_data_compressed_cbor ... ok [INFO] [stdout] test tests::put_and_read_work_across_shards_dict_compressed_cbor ... ok [INFO] [stdout] test tests::simple_put_read_10_dict_compressed_cbor ... ok [INFO] [stdout] test tests::put_and_read_work_across_shards_compressed_cbor ... ok [INFO] [stdout] test tests::writer_creates_directory_uncompressed_cbor ... ok [INFO] [stdout] test tests::read_iterates_appropriately_compressed_cbor ... ok [INFO] [stdout] test tests::read_skips_over_corrupt_index_entry_compressed_cbor ... ok [INFO] [stdout] test tests::writing_to_already_written_index_works_dict_compressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.501 WARN Expected file does not exist: /tmp/below_store_test.Doa7GsP4AHOh/index_00000086400 [INFO] [stdout] test tests::read_reverse_dict_compressed_cbor ... ok [INFO] [stdout] test tests::writer_creates_directory_compressed_cbor ... ok [INFO] [stdout] test tests::simple_put_read_10_compressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.502 WARN Expected file does not exist: /tmp/below_store_test.Doa7GsP4AHOh/index_00000086400 [INFO] [stderr] Feb 26 13:43:28.504 WARN Expected file does not exist: /tmp/below_store_test.NzOjTaxOxoDK/index_00000086400 [INFO] [stdout] test tests::read_reverse_across_shards_compressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.504 WARN Expected file does not exist: /tmp/below_store_test.Doa7GsP4AHOh/index_00000086400 [INFO] [stderr] Feb 26 13:43:28.505 WARN Expected file does not exist: /tmp/below_store_test.tRvhsDHcHNgS/index_00000086400 [INFO] [stderr] Feb 26 13:43:28.506 WARN Expected file does not exist: /tmp/below_store_test.NzOjTaxOxoDK/index_00000086400 [INFO] [stderr] Feb 26 13:43:28.506 WARN Expected file does not exist: /tmp/below_store_test.tRvhsDHcHNgS/index_00000086400 [INFO] [stderr] Feb 26 13:43:28.507 WARN Expected file does not exist: /tmp/below_store_test.Doa7GsP4AHOh/index_00000086400 [INFO] [stderr] Feb 26 13:43:28.507 WARN Expected file does not exist: /tmp/below_store_test.NzOjTaxOxoDK/index_00000086400 [INFO] [stdout] test tests::try_discard_until_size_dict_compressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.508 WARN Expected file does not exist: /tmp/below_store_test.tRvhsDHcHNgS/index_00000086400 [INFO] [stderr] Feb 26 13:43:28.508 WARN Expected file does not exist: /tmp/below_store_test.NzOjTaxOxoDK/index_00000086400 [INFO] [stdout] test tests::try_discard_until_size_uncompressed_cbor ... ok [INFO] [stderr] Feb 26 13:43:28.509 WARN Expected file does not exist: /tmp/below_store_test.tRvhsDHcHNgS/index_00000086400 [INFO] [stdout] test tests::try_discard_until_size_compressed_cbor ... ok [INFO] [stdout] test tests::write_index_padding ... ok [INFO] [stdout] test tests::writing_to_already_written_index_with_different_compression_format_works ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 81 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s [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" "937c379fdbefeb64ec098fc70d28a21fb13db17d8fc80871e312ca982e17aee7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "937c379fdbefeb64ec098fc70d28a21fb13db17d8fc80871e312ca982e17aee7", kill_on_drop: false }` [INFO] [stdout] 937c379fdbefeb64ec098fc70d28a21fb13db17d8fc80871e312ca982e17aee7