[INFO] fetching crate cassadilia 0.4.2... [INFO] testing cassadilia-0.4.2 against 1.90.0 for beta-1.91-3 [INFO] extracting crate cassadilia 0.4.2 into /workspace/builds/worker-4-tc1/source [INFO] removed /workspace/builds/worker-4-tc1/source/.cargo/config.toml [INFO] started tweaking crates.io crate cassadilia 0.4.2 [INFO] finished tweaking crates.io crate cassadilia 0.4.2 [INFO] tweaked toml for crates.io crate cassadilia 0.4.2 written to /workspace/builds/worker-4-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate cassadilia 0.4.2 on toolchain 1.90.0 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.90.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate cassadilia 0.4.2 already has a lockfile, it will not be regenerated [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.90.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 42ccc74c14b5179d17e0f2db12e88ec91987ecead37183fb88c624fdef3d67bc [INFO] running `Command { std: "docker" "start" "-a" "42ccc74c14b5179d17e0f2db12e88ec91987ecead37183fb88c624fdef3d67bc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "42ccc74c14b5179d17e0f2db12e88ec91987ecead37183fb88c624fdef3d67bc", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "42ccc74c14b5179d17e0f2db12e88ec91987ecead37183fb88c624fdef3d67bc", kill_on_drop: false }` [INFO] [stdout] 42ccc74c14b5179d17e0f2db12e88ec91987ecead37183fb88c624fdef3d67bc [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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=warn" "-e" "RUSTDOCFLAGS=--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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] ddbb823efc017a9aae482610ada7519d4efd6832c5e905954303babbe6829e7e [INFO] running `Command { std: "docker" "start" "-a" "ddbb823efc017a9aae482610ada7519d4efd6832c5e905954303babbe6829e7e", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.101 [INFO] [stderr] Compiling quote v1.0.41 [INFO] [stderr] Compiling libc v0.2.176 [INFO] [stderr] Compiling unicode-ident v1.0.19 [INFO] [stderr] Compiling cfg-if v1.0.3 [INFO] [stderr] Compiling crossbeam-utils v0.8.21 [INFO] [stderr] Compiling once_cell v1.21.3 [INFO] [stderr] Compiling autocfg v1.5.0 [INFO] [stderr] Compiling rayon-core v1.13.0 [INFO] [stderr] Compiling serde_core v1.0.228 [INFO] [stderr] Compiling shlex v1.3.0 [INFO] [stderr] Compiling find-msvc-tools v0.1.2 [INFO] [stderr] Compiling getrandom v0.3.3 [INFO] [stderr] Compiling cc v1.2.39 [INFO] [stderr] Compiling rustix v1.1.2 [INFO] [stderr] Compiling lock_api v0.4.13 [INFO] [stderr] Compiling parking_lot_core v0.9.11 [INFO] [stderr] Compiling zerocopy v0.8.27 [INFO] [stderr] Compiling crossbeam-epoch v0.9.18 [INFO] [stderr] Compiling ahash v0.8.12 [INFO] [stderr] Compiling linux-raw-sys v0.11.0 [INFO] [stderr] Compiling crossbeam-deque v0.8.6 [INFO] [stderr] Compiling smallvec v1.15.1 [INFO] [stderr] Compiling syn v2.0.106 [INFO] [stderr] Compiling serde_json v1.0.145 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling bitflags v2.9.4 [INFO] [stderr] Compiling thiserror v2.0.17 [INFO] [stderr] Compiling memmap2 v0.9.8 [INFO] [stderr] Compiling tracing-core v0.1.34 [INFO] [stderr] Compiling memchr v2.7.6 [INFO] [stderr] Compiling arrayref v0.3.9 [INFO] [stderr] Compiling fastrand v2.3.0 [INFO] [stderr] Compiling blake3 v1.8.2 [INFO] [stderr] Compiling arrayvec v0.7.6 [INFO] [stderr] Compiling constant_time_eq v0.3.1 [INFO] [stderr] Compiling pin-project-lite v0.2.16 [INFO] [stderr] Compiling ryu v1.0.20 [INFO] [stderr] Compiling itoa v1.0.15 [INFO] [stderr] Compiling tempfile v3.23.0 [INFO] [stderr] Compiling parking_lot v0.12.4 [INFO] [stderr] Compiling bytes v1.10.1 [INFO] [stderr] Compiling tracing-attributes v0.1.30 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling thiserror-impl v2.0.17 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling cassadilia v0.4.2 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 35.92s [INFO] running `Command { std: "docker" "inspect" "ddbb823efc017a9aae482610ada7519d4efd6832c5e905954303babbe6829e7e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ddbb823efc017a9aae482610ada7519d4efd6832c5e905954303babbe6829e7e", kill_on_drop: false }` [INFO] [stdout] ddbb823efc017a9aae482610ada7519d4efd6832c5e905954303babbe6829e7e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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=warn" "-e" "RUSTDOCFLAGS=--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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] b87aacadcf209f389b34e52623fb7772bc3676a71bf4d08b8c34d9ce103c2ab5 [INFO] running `Command { std: "docker" "start" "-a" "b87aacadcf209f389b34e52623fb7772bc3676a71bf4d08b8c34d9ce103c2ab5", kill_on_drop: false }` [INFO] [stderr] Compiling cfg-if v1.0.3 [INFO] [stderr] Compiling libc v0.2.176 [INFO] [stderr] Compiling once_cell v1.21.3 [INFO] [stderr] Compiling memchr v2.7.6 [INFO] [stderr] Compiling crossbeam-utils v0.8.21 [INFO] [stderr] Compiling object v0.37.3 [INFO] [stderr] Compiling smallvec v1.15.1 [INFO] [stderr] Compiling serde_core v1.0.228 [INFO] [stderr] Compiling adler2 v2.0.1 [INFO] [stderr] Compiling bitflags v2.9.4 [INFO] [stderr] Compiling scopeguard v1.2.0 [INFO] [stderr] Compiling gimli v0.32.3 [INFO] [stderr] Compiling tracing-core v0.1.34 [INFO] [stderr] Compiling linux-raw-sys v0.11.0 [INFO] [stderr] Compiling pin-project-lite v0.2.16 [INFO] [stderr] Compiling regex-syntax v0.8.6 [INFO] [stderr] Compiling crossbeam-epoch v0.9.18 [INFO] [stderr] Compiling rustix v1.1.2 [INFO] [stderr] Compiling crossbeam-deque v0.8.6 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling lock_api v0.4.13 [INFO] [stderr] Compiling rayon-core v1.13.0 [INFO] [stderr] Compiling getrandom v0.3.3 [INFO] [stderr] Compiling parking_lot_core v0.9.11 [INFO] [stderr] Compiling memmap2 v0.9.8 [INFO] [stderr] Compiling miniz_oxide v0.8.9 [INFO] [stderr] Compiling zerocopy v0.8.27 [INFO] [stderr] Compiling regex-automata v0.4.11 [INFO] [stderr] Compiling log v0.4.28 [INFO] [stderr] Compiling fastrand v2.3.0 [INFO] [stderr] Compiling rustc-demangle v0.1.26 [INFO] [stderr] Compiling arrayvec v0.7.6 [INFO] [stderr] Compiling ryu v1.0.20 [INFO] [stderr] Compiling itoa v1.0.15 [INFO] [stderr] Compiling addr2line v0.25.1 [INFO] [stderr] Compiling arrayref v0.3.9 [INFO] [stderr] Compiling constant_time_eq v0.3.1 [INFO] [stderr] Compiling anyhow v1.0.100 [INFO] [stderr] Compiling lazy_static v1.5.0 [INFO] [stderr] Compiling sharded-slab v0.1.7 [INFO] [stderr] Compiling blake3 v1.8.2 [INFO] [stderr] Compiling serde_json v1.0.145 [INFO] [stderr] Compiling tempfile v3.23.0 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling ahash v0.8.12 [INFO] [stderr] Compiling tracing-log v0.2.0 [INFO] [stderr] Compiling thiserror v2.0.17 [INFO] [stderr] Compiling parking_lot v0.12.4 [INFO] [stderr] Compiling thread_local v1.1.9 [INFO] [stderr] Compiling hex v0.4.3 [INFO] [stderr] Compiling bytes v1.10.1 [INFO] [stderr] Compiling nu-ansi-term v0.50.1 [INFO] [stderr] Compiling matchers v0.2.0 [INFO] [stderr] Compiling tracing-subscriber v0.3.20 [INFO] [stderr] Compiling cassadilia v0.4.2 (/opt/rustwide/workdir) [INFO] [stderr] Compiling backtrace v0.3.76 [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 51.28s [INFO] running `Command { std: "docker" "inspect" "b87aacadcf209f389b34e52623fb7772bc3676a71bf4d08b8c34d9ce103c2ab5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b87aacadcf209f389b34e52623fb7772bc3676a71bf4d08b8c34d9ce103c2ab5", kill_on_drop: false }` [INFO] [stdout] b87aacadcf209f389b34e52623fb7772bc3676a71bf4d08b8c34d9ce103c2ab5 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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=warn" "-e" "RUSTDOCFLAGS=--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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] e6ecf4e89c295e5253ce411bfa14547c3b5e571c574080bc012800159e479734 [INFO] running `Command { std: "docker" "start" "-a" "e6ecf4e89c295e5253ce411bfa14547c3b5e571c574080bc012800159e479734", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.10s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/cassadilia-8d5449d956ecb474) [INFO] [stdout] [INFO] [stdout] running 68 tests [INFO] [stdout] test index::tests::test_intent_guard_multiple_intents_same_key_direct_state ... ok [INFO] [stdout] test index::tests::test_intent_guard_reference_counting_direct_state ... ok [INFO] [stdout] test serialization::tests::test_btreemap_serialization_last_version_none ... ok [INFO] [stdout] test index::tests::test_intent_guard_cleanup_direct_state ... ok [INFO] [stdout] test serialization::tests::test_btreemap_serialization_multiple_keys ... ok [INFO] [stdout] test serialization::tests::test_btreemap_serialization_single_empty_key ... ok [INFO] [stdout] test io::tests::same_fs_linux ... ok [INFO] [stdout] test index::tests::test_intent_guard_commit_removes_intent ... ok [INFO] [stdout] test serialization::tests::test_wal_op_invalid_data ... ok [INFO] [stdout] test serialization::tests::test_helper_functions ... ok [INFO] [stdout] test serialization::tests::test_helper_functions_errors ... ok [INFO] [stdout] test serialization::tests::test_wal_op_put_empty_key ... ok [INFO] [stdout] test serialization::tests::test_wal_op_put_large_key ... ok [INFO] [stdout] test serialization::tests::test_wal_op_remove_empty ... ok [INFO] [stdout] test serialization::tests::test_wal_op_remove_single_empty_key ... ok [INFO] [stdout] test settings::tests::test_future_version_rejected ... ok [INFO] [stdout] test settings::tests::test_past_version_rejected ... ok [INFO] [stdout] test serialization::tests::test_btreemap_invalid_data ... ok [INFO] [stdout] test serialization::tests::test_wal_op_remove_mixed_keys ... ok [INFO] [stdout] 2025-10-01T07:42:27.987036Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00388109 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:27.987841Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=5.302e-5 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:27.988366Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmp7yrarT/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:27.988454Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:27.988472Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:27.988496Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmp7yrarT/0_index.wal [INFO] [stdout] test settings::tests::test_immutable_settings_validation ... ok [INFO] [stdout] test serialization::tests::test_btreemap_serialization_empty ... ok [INFO] [stdout] test serialization::tests::test_btreemap_serialization_last_version_some ... ok [INFO] [stdout] 2025-10-01T07:42:27.992705Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=5.535e-5 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:27.996180Z DEBUG cassadilia::transaction: Starting transaction for key '"key00"' using staging file '/tmp/.tmp7yrarT/staging/.tmprJZNOK' [INFO] [stdout] 2025-10-01T07:42:27.996230Z DEBUG cassadilia::transaction: Finishing transaction for key '"key00"' [INFO] [stdout] test index::tests::test_index_read_guard_len ... ok [INFO] [stdout] 2025-10-01T07:42:27.998167Z DEBUG cassadilia::transaction: Committing transaction blob_hash=e5c858cc2b45f0c423a741c0785b4ec244c7618b7b73d855a1e9b8147eae434c key="key00" [INFO] [stdout] 2025-10-01T07:42:27.999710Z DEBUG cassadilia::cas_manager: Moved blob e5c858cc2b45f0c423a741c0785b4ec244c7618b7b73d855a1e9b8147eae434c to CAS path '/tmp/.tmp7yrarT/cas/e5/c8/58cc2b45f0c423a741c0785b4ec244c7618b7b73d855a1e9b8147eae434c' [INFO] [stdout] 2025-10-01T07:42:27.999770Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmp7yrarT/0_index.wal [INFO] [stdout] 2025-10-01T07:42:27.999799Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] test io::tests::file_lock ... ok [INFO] [stdout] 2025-10-01T07:42:28.002920Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpHnFyts/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.003026Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.003047Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.003072Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpHnFyts/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.003182Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpubvqas/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.004802Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=5.2451e-5 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.004819Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpFyoD4m/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.004913Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.004932Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.004955Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpFyoD4m/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.004979Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpHnFyts/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.005271Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.005295Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.005320Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpubvqas/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.005473Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpHnFyts/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.005495Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.005514Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpHnFyts/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.005956Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpHnFyts/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.005986Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=0 [INFO] [stdout] 2025-10-01T07:42:28.005999Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=0 [INFO] [stdout] 2025-10-01T07:42:28.006023Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpW4vIv0/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.006074Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=3.7849e-5 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.006099Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.006113Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.006135Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpW4vIv0/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.006204Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpHnFyts/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.006264Z DEBUG cassadilia::transaction: Starting transaction for key '"key01"' using staging file '/tmp/.tmp7yrarT/staging/.tmpXfeWHV' [INFO] [stdout] 2025-10-01T07:42:28.006275Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpHnFyts/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.006292Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.006295Z DEBUG cassadilia::transaction: Finishing transaction for key '"key01"' [INFO] [stdout] 2025-10-01T07:42:28.006302Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpHnFyts/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.006326Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpHnFyts/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.006340Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=0 [INFO] [stdout] 2025-10-01T07:42:28.006350Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=0 [INFO] [stdout] 2025-10-01T07:42:28.006421Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=3.576e-5 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.006504Z DEBUG cassadilia::transaction: Starting transaction for key '"key00"' using staging file '/tmp/.tmpHnFyts/staging/.tmpxc7HTK' [INFO] [stdout] 2025-10-01T07:42:28.006524Z DEBUG cassadilia::transaction: Finishing transaction for key '"key00"' [INFO] [stdout] 2025-10-01T07:42:28.006938Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpHc0IH2/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.007895Z DEBUG cassadilia::transaction: Committing transaction blob_hash=7daf4870c31069d381520201dc445fabbd623dd0254163806fda08617a3546ca key="key01" [INFO] [stdout] 2025-10-01T07:42:28.008123Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.008147Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.008171Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpHc0IH2/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.008513Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.000798149 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.008604Z DEBUG cassadilia::transaction: Starting transaction for key '"key00"' using staging file '/tmp/.tmpubvqas/staging/.tmpYcFOqv' [INFO] [stdout] 2025-10-01T07:42:28.008626Z DEBUG cassadilia::transaction: Finishing transaction for key '"key00"' [INFO] [stdout] 2025-10-01T07:42:28.008704Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00210306 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.008806Z DEBUG cassadilia::transaction: Committing transaction blob_hash=c4986a4567cde92203892070ccc59ae96e88100c4ee650c12bee3eb09324d574 key="key00" [INFO] [stdout] 2025-10-01T07:42:28.008827Z DEBUG cassadilia::transaction: Starting transaction for key '"key1"' using staging file '/tmp/.tmpFyoD4m/staging/.tmphj1hGC' [INFO] [stdout] 2025-10-01T07:42:28.008849Z DEBUG cassadilia::transaction: Finishing transaction for key '"key1"' [INFO] [stdout] 2025-10-01T07:42:28.009813Z DEBUG cassadilia::cas_manager: Moved blob c4986a4567cde92203892070ccc59ae96e88100c4ee650c12bee3eb09324d574 to CAS path '/tmp/.tmpHnFyts/cas/c4/98/6a4567cde92203892070ccc59ae96e88100c4ee650c12bee3eb09324d574' [INFO] [stdout] 2025-10-01T07:42:28.009860Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpHnFyts/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.009916Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.010202Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=5.186e-5 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.010286Z DEBUG cassadilia::transaction: Committing transaction blob_hash=cd5d9b6e45fc2098572aefc35b48abf6c81505edcba343900721dfee610e5340 key="key1" [INFO] [stdout] 2025-10-01T07:42:28.010365Z DEBUG cassadilia::transaction: Committing transaction blob_hash=8b238f317be0774b380bf423cdf32ff743c4f7c4681e7477b495329019155e1f key="key00" [INFO] [stdout] 2025-10-01T07:42:28.010921Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00307132 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.011255Z DEBUG cassadilia::cas_manager: Moved blob 7daf4870c31069d381520201dc445fabbd623dd0254163806fda08617a3546ca to CAS path '/tmp/.tmp7yrarT/cas/7d/af/4870c31069d381520201dc445fabbd623dd0254163806fda08617a3546ca' [INFO] [stdout] 2025-10-01T07:42:28.011820Z DEBUG cassadilia::cas_manager: Moved blob 8b238f317be0774b380bf423cdf32ff743c4f7c4681e7477b495329019155e1f to CAS path '/tmp/.tmpubvqas/cas/8b/23/8f317be0774b380bf423cdf32ff743c4f7c4681e7477b495329019155e1f' [INFO] [stdout] 2025-10-01T07:42:28.012096Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpubvqas/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.012922Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.012526Z DEBUG cassadilia::transaction: Starting transaction for key '"key00"' using staging file '/tmp/.tmpW4vIv0/staging/.tmpCpMuYO' [INFO] [stdout] 2025-10-01T07:42:28.013378Z DEBUG cassadilia::transaction: Finishing transaction for key '"key00"' [INFO] [stdout] 2025-10-01T07:42:28.011919Z DEBUG cassadilia::transaction: Starting transaction for key '"key00"' using staging file '/tmp/.tmpHc0IH2/staging/.tmp32RqRG' [INFO] [stdout] 2025-10-01T07:42:28.013509Z DEBUG cassadilia::transaction: Finishing transaction for key '"key00"' [INFO] [stdout] 2025-10-01T07:42:28.012055Z DEBUG cassadilia::cas_manager: Moved blob cd5d9b6e45fc2098572aefc35b48abf6c81505edcba343900721dfee610e5340 to CAS path '/tmp/.tmpFyoD4m/cas/cd/5d/9b6e45fc2098572aefc35b48abf6c81505edcba343900721dfee610e5340' [INFO] [stdout] 2025-10-01T07:42:28.012619Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.013741Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpFyoD4m/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.013964Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.013935Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpHnFyts/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.014087Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpHnFyts/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.014105Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.014114Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpHnFyts/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.014154Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpHnFyts/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.014169Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=1 [INFO] [stdout] 2025-10-01T07:42:28.014177Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=1 highest_op_version=1 [INFO] [stdout] 2025-10-01T07:42:28.014200Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.014211Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.014730Z DEBUG cassadilia::transaction: Committing transaction blob_hash=3e21a4068006bc8f919969e362f5f3ac3e0f589cb770a8b34342fd3b7224c359 key="key00" [INFO] [stdout] 2025-10-01T07:42:28.014800Z DEBUG cassadilia::transaction: Committing transaction blob_hash=a739eeab85b32b0d39eaa97be726ef2a5894f8273fb54ea8c17cda5a3e3be9e0 key="key00" [INFO] [stdout] 2025-10-01T07:42:28.015345Z DEBUG cassadilia::transaction: Starting transaction for key '"key02"' using staging file '/tmp/.tmp7yrarT/staging/.tmp5h8wdE' [INFO] [stdout] 2025-10-01T07:42:28.015377Z DEBUG cassadilia::transaction: Finishing transaction for key '"key02"' [INFO] [stdout] 2025-10-01T07:42:28.015424Z DEBUG cassadilia::cas_manager: Moved blob a739eeab85b32b0d39eaa97be726ef2a5894f8273fb54ea8c17cda5a3e3be9e0 to CAS path '/tmp/.tmpHc0IH2/cas/a7/39/eeab85b32b0d39eaa97be726ef2a5894f8273fb54ea8c17cda5a3e3be9e0' [INFO] [stdout] 2025-10-01T07:42:28.015463Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpHc0IH2/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.015502Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.016343Z  INFO cassadilia::index::persistence: Persisted 1 entries to index '/tmp/.tmpHnFyts/index' [INFO] [stdout] 2025-10-01T07:42:28.016372Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.016457Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpHnFyts/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.016495Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.016509Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:28.016520Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:28.016689Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00013353 total_blobs=1 [INFO] [stdout] 2025-10-01T07:42:28.016702Z DEBUG cassadilia::transaction: Committing transaction blob_hash=51cd9402fc08f863ab366f6ed36aa254e28597a0ab6a8f1baa94b0ce1540c04e key="key02" [INFO] [stdout] 2025-10-01T07:42:28.016877Z DEBUG cassadilia::cas_manager: Moved blob 51cd9402fc08f863ab366f6ed36aa254e28597a0ab6a8f1baa94b0ce1540c04e to CAS path '/tmp/.tmp7yrarT/cas/51/cd/9402fc08f863ab366f6ed36aa254e28597a0ab6a8f1baa94b0ce1540c04e' [INFO] [stdout] 2025-10-01T07:42:28.017337Z DEBUG cassadilia::transaction: Starting transaction for key '"key01"' using staging file '/tmp/.tmpubvqas/staging/.tmppGhq0S' [INFO] [stdout] 2025-10-01T07:42:28.017362Z DEBUG cassadilia::transaction: Finishing transaction for key '"key01"' [INFO] [stdout] 2025-10-01T07:42:28.017380Z DEBUG cassadilia::cas_manager: Moved blob 3e21a4068006bc8f919969e362f5f3ac3e0f589cb770a8b34342fd3b7224c359 to CAS path '/tmp/.tmpW4vIv0/cas/3e/21/a4068006bc8f919969e362f5f3ac3e0f589cb770a8b34342fd3b7224c359' [INFO] [stdout] 2025-10-01T07:42:28.017407Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpW4vIv0/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.017428Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.017494Z DEBUG cassadilia::transaction: Starting transaction for key '"key1"' using staging file '/tmp/.tmpFyoD4m/staging/.tmp9f4x5L' [INFO] [stdout] 2025-10-01T07:42:28.017514Z DEBUG cassadilia::transaction: Finishing transaction for key '"key1"' [INFO] [stdout] 2025-10-01T07:42:28.018820Z DEBUG cassadilia::transaction: Starting transaction for key '"key01"' using staging file '/tmp/.tmpHc0IH2/staging/.tmp470Jid' [INFO] [stdout] 2025-10-01T07:42:28.018854Z DEBUG cassadilia::transaction: Finishing transaction for key '"key01"' [INFO] [stdout] test tests::checkpoint::test_empty_database_checkpoint_on_first_write ... ok [INFO] [stdout] 2025-10-01T07:42:28.018937Z DEBUG cassadilia::transaction: Starting transaction for key '"key03"' using staging file '/tmp/.tmp7yrarT/staging/.tmp3MuGmZ' [INFO] [stdout] 2025-10-01T07:42:28.018969Z DEBUG cassadilia::transaction: Finishing transaction for key '"key03"' [INFO] [stdout] 2025-10-01T07:42:28.020287Z DEBUG cassadilia::transaction: Committing transaction blob_hash=284be493da5e8cf2de3c3bf4de5dd28d19ea3e07975e58d32b3c36b04a6ae8ee key="key1" [INFO] [stdout] 2025-10-01T07:42:28.020359Z DEBUG cassadilia::transaction: Committing transaction blob_hash=58549030e11366706bf6c8648f97e6d7e52baa493157aa1ef1679d64f131f93c key="key01" [INFO] [stdout] 2025-10-01T07:42:28.020959Z DEBUG cassadilia::cas_manager: Moved blob 58549030e11366706bf6c8648f97e6d7e52baa493157aa1ef1679d64f131f93c to CAS path '/tmp/.tmpubvqas/cas/58/54/9030e11366706bf6c8648f97e6d7e52baa493157aa1ef1679d64f131f93c' [INFO] [stdout] 2025-10-01T07:42:28.021289Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpCCj1Qb/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.021143Z DEBUG cassadilia::cas_manager: Moved blob 284be493da5e8cf2de3c3bf4de5dd28d19ea3e07975e58d32b3c36b04a6ae8ee to CAS path '/tmp/.tmpFyoD4m/cas/28/4b/e493da5e8cf2de3c3bf4de5dd28d19ea3e07975e58d32b3c36b04a6ae8ee' [INFO] [stdout] 2025-10-01T07:42:28.021211Z DEBUG cassadilia::transaction: Starting transaction for key '"key01"' using staging file '/tmp/.tmpW4vIv0/staging/.tmpv5AAde' [INFO] [stdout] 2025-10-01T07:42:28.021362Z DEBUG cassadilia::transaction: Finishing transaction for key '"key01"' [INFO] [stdout] 2025-10-01T07:42:28.021378Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.021394Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.021242Z DEBUG cassadilia::transaction: Committing transaction blob_hash=992adda1f42b66cc68a044037ce24619e07f5ce3900c955277f9e35abade2157 key="key03" [INFO] [stdout] 2025-10-01T07:42:28.021415Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpCCj1Qb/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.021268Z DEBUG cassadilia::transaction: Committing transaction blob_hash=7db435c33dc4a7d581a9259698dbcae28a7b130bbff1123b36b2e2e987bf9676 key="key01" [INFO] [stdout] 2025-10-01T07:42:28.022279Z DEBUG cassadilia::cas_manager: Moved blob 7db435c33dc4a7d581a9259698dbcae28a7b130bbff1123b36b2e2e987bf9676 to CAS path '/tmp/.tmpHc0IH2/cas/7d/b4/35c33dc4a7d581a9259698dbcae28a7b130bbff1123b36b2e2e987bf9676' [INFO] [stdout] 2025-10-01T07:42:28.022388Z DEBUG cassadilia::cas_manager: Moved blob 992adda1f42b66cc68a044037ce24619e07f5ce3900c955277f9e35abade2157 to CAS path '/tmp/.tmp7yrarT/cas/99/2a/dda1f42b66cc68a044037ce24619e07f5ce3900c955277f9e35abade2157' [INFO] [stdout] 2025-10-01T07:42:28.023122Z DEBUG cassadilia::transaction: Starting transaction for key '"key02"' using staging file '/tmp/.tmpubvqas/staging/.tmpnwHZUp' [INFO] [stdout] 2025-10-01T07:42:28.023170Z DEBUG cassadilia::transaction: Finishing transaction for key '"key02"' [INFO] [stdout] 2025-10-01T07:42:28.023179Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00051331 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.023280Z DEBUG cassadilia::transaction: Starting transaction for key '[115, 97, 109, 101, 32, 107, 101, 121]' using staging file '/tmp/.tmpCCj1Qb/staging/.tmposo0qF' [INFO] [stdout] 2025-10-01T07:42:28.023312Z DEBUG cassadilia::transaction: Finishing transaction for key '[115, 97, 109, 101, 32, 107, 101, 121]' [INFO] [stdout] 2025-10-01T07:42:28.023666Z DEBUG cassadilia::transaction: Starting transaction for key '"key02"' using staging file '/tmp/.tmpHc0IH2/staging/.tmpXThb8e' [INFO] [stdout] 2025-10-01T07:42:28.023720Z DEBUG cassadilia::transaction: Committing transaction blob_hash=e5a89c89723208444a472878d4887ef85565731bf2c6789ddbc7082040457e21 key="key01" [INFO] [stdout] 2025-10-01T07:42:28.023825Z DEBUG cassadilia::cas_manager: Successfully deleted unreferenced CAS file: /tmp/.tmpFyoD4m/cas/cd/5d/9b6e45fc2098572aefc35b48abf6c81505edcba343900721dfee610e5340 [INFO] [stdout] 2025-10-01T07:42:28.023984Z  INFO cassadilia::index: Starting checkpoint operation. reason=Explicit [INFO] [stdout] 2025-10-01T07:42:28.024085Z DEBUG cassadilia::transaction: Finishing transaction for key '"key02"' [INFO] [stdout] 2025-10-01T07:42:28.024204Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=Explicit [INFO] [stdout] 2025-10-01T07:42:28.025766Z DEBUG cassadilia::cas_manager: Moved blob e5a89c89723208444a472878d4887ef85565731bf2c6789ddbc7082040457e21 to CAS path '/tmp/.tmpW4vIv0/cas/e5/a8/9c89723208444a472878d4887ef85565731bf2c6789ddbc7082040457e21' [INFO] [stdout] 2025-10-01T07:42:28.025873Z DEBUG cassadilia::transaction: Starting transaction for key '"key04"' using staging file '/tmp/.tmp7yrarT/staging/.tmpGTCUDZ' [INFO] [stdout] 2025-10-01T07:42:28.025937Z DEBUG cassadilia::transaction: Finishing transaction for key '"key04"' [INFO] [stdout] 2025-10-01T07:42:28.025977Z DEBUG cassadilia::transaction: Committing transaction blob_hash=549969c8f7146d43a1c0f42bd2aa865c704cc491fef4fb887164b48ff1167a1a key="key02" [INFO] [stdout] 2025-10-01T07:42:28.026050Z DEBUG cassadilia::transaction: Committing transaction blob_hash=2b80d6e264690d8a19de16ffa3c3271cd778c96a1bf300c3231cc692786fed50 key=[115, 97, 109, 101, 32, 107, 101, 121] [INFO] [stdout] 2025-10-01T07:42:28.026672Z DEBUG cassadilia::cas_manager: Moved blob 549969c8f7146d43a1c0f42bd2aa865c704cc491fef4fb887164b48ff1167a1a to CAS path '/tmp/.tmpubvqas/cas/54/99/69c8f7146d43a1c0f42bd2aa865c704cc491fef4fb887164b48ff1167a1a' [INFO] [stdout] 2025-10-01T07:42:28.026701Z DEBUG cassadilia::cas_manager: Moved blob 2b80d6e264690d8a19de16ffa3c3271cd778c96a1bf300c3231cc692786fed50 to CAS path '/tmp/.tmpCCj1Qb/cas/2b/80/d6e264690d8a19de16ffa3c3271cd778c96a1bf300c3231cc692786fed50' [INFO] [stdout] 2025-10-01T07:42:28.026744Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpCCj1Qb/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.026768Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.026923Z DEBUG cassadilia::transaction: Committing transaction blob_hash=409d96651e5e689bdbe491c2ed943ed4d6744be9ee1f01aa5a61b387f2491b0f key="key02" [INFO] [stdout] 2025-10-01T07:42:28.028893Z DEBUG cassadilia::cas_manager: Moved blob 409d96651e5e689bdbe491c2ed943ed4d6744be9ee1f01aa5a61b387f2491b0f to CAS path '/tmp/.tmpHc0IH2/cas/40/9d/96651e5e689bdbe491c2ed943ed4d6744be9ee1f01aa5a61b387f2491b0f' [INFO] [stdout] 2025-10-01T07:42:28.028986Z  INFO cassadilia::index::persistence: Persisted 1 entries to index '/tmp/.tmpFyoD4m/index' [INFO] [stdout] 2025-10-01T07:42:28.029002Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.029087Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpFyoD4m/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.029107Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.029117Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=2 [INFO] [stdout] 2025-10-01T07:42:28.029128Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=2 [INFO] [stdout] 2025-10-01T07:42:28.029155Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.029282Z DEBUG cassadilia::transaction: Starting transaction for key '"key02"' using staging file '/tmp/.tmpW4vIv0/staging/.tmpvb57mu' [INFO] [stdout] 2025-10-01T07:42:28.029305Z DEBUG cassadilia::transaction: Finishing transaction for key '"key02"' [INFO] [stdout] 2025-10-01T07:42:28.029580Z  INFO cassadilia::index::persistence: Loaded 1 entries from index '/tmp/.tmpFyoD4m/index' and populated ref counts. [INFO] [stdout] 2025-10-01T07:42:28.029663Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpFyoD4m/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.029682Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 checkpoint=2 [INFO] [stdout] 2025-10-01T07:42:28.029694Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpFyoD4m/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.029736Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpFyoD4m/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.029752Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=0 [INFO] [stdout] 2025-10-01T07:42:28.029762Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=0 highest_op_version=2 [INFO] [stdout] test tests::checkpoint::test_overwrite_deletes_old_blob_no_orphans ... ok [INFO] [stdout] 2025-10-01T07:42:28.030706Z  INFO cassadilia::index: Starting checkpoint operation. reason=Explicit [INFO] [stdout] 2025-10-01T07:42:28.030725Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=Explicit [INFO] [stdout] 2025-10-01T07:42:28.030781Z DEBUG cassadilia::transaction: Committing transaction blob_hash=d8fbb1336bc105c90af17f12f0a5d7808e445cdcae40324a74c788fa13d7a2b5 key="key04" [INFO] [stdout] 2025-10-01T07:42:28.031172Z DEBUG cassadilia::transaction: Starting transaction for key '[115, 97, 109, 101, 32, 107, 101, 121]' using staging file '/tmp/.tmpCCj1Qb/staging/.tmp0z55jQ' [INFO] [stdout] 2025-10-01T07:42:28.031201Z DEBUG cassadilia::transaction: Finishing transaction for key '[115, 97, 109, 101, 32, 107, 101, 121]' [INFO] [stdout] 2025-10-01T07:42:28.032146Z DEBUG cassadilia::cas_manager: Moved blob d8fbb1336bc105c90af17f12f0a5d7808e445cdcae40324a74c788fa13d7a2b5 to CAS path '/tmp/.tmp7yrarT/cas/d8/fb/b1336bc105c90af17f12f0a5d7808e445cdcae40324a74c788fa13d7a2b5' [INFO] [stdout] 2025-10-01T07:42:28.032532Z DEBUG cassadilia::transaction: Committing transaction blob_hash=9238706b442fb58f55eca463f0f0b6fb5d3ee00853b61210178b88ea33e95195 key="key02" [INFO] [stdout] 2025-10-01T07:42:28.037837Z DEBUG cassadilia::transaction: Starting transaction for key '"key03"' using staging file '/tmp/.tmpHc0IH2/staging/.tmpdvLo7G' [INFO] [stdout] 2025-10-01T07:42:28.038045Z DEBUG cassadilia::transaction: Finishing transaction for key '"key03"' [INFO] [stdout] 2025-10-01T07:42:28.038682Z DEBUG cassadilia::cas_manager: Moved blob 9238706b442fb58f55eca463f0f0b6fb5d3ee00853b61210178b88ea33e95195 to CAS path '/tmp/.tmpW4vIv0/cas/92/38/706b442fb58f55eca463f0f0b6fb5d3ee00853b61210178b88ea33e95195' [INFO] [stdout] 2025-10-01T07:42:28.039047Z  INFO cassadilia::index::persistence: Persisted 3 entries to index '/tmp/.tmpubvqas/index' [INFO] [stdout] 2025-10-01T07:42:28.039163Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.039111Z DEBUG cassadilia::transaction: Committing transaction blob_hash=2b80d6e264690d8a19de16ffa3c3271cd778c96a1bf300c3231cc692786fed50 key=[115, 97, 109, 101, 32, 107, 101, 121] [INFO] [stdout] 2025-10-01T07:42:28.040220Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpubvqas/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.040250Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.040262Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=3 [INFO] [stdout] 2025-10-01T07:42:28.040272Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=3 [INFO] [stdout] 2025-10-01T07:42:28.040299Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.040788Z DEBUG cassadilia::cas_manager: Moved blob 2b80d6e264690d8a19de16ffa3c3271cd778c96a1bf300c3231cc692786fed50 to CAS path '/tmp/.tmpCCj1Qb/cas/2b/80/d6e264690d8a19de16ffa3c3271cd778c96a1bf300c3231cc692786fed50' [INFO] [stdout] 2025-10-01T07:42:28.040929Z  INFO cassadilia::index::persistence: Loaded 3 entries from index '/tmp/.tmpubvqas/index' and populated ref counts. [INFO] [stdout] 2025-10-01T07:42:28.041017Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpubvqas/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.041037Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 checkpoint=3 [INFO] [stdout] 2025-10-01T07:42:28.041049Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpubvqas/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.041097Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpubvqas/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.041187Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=0 [INFO] [stdout] 2025-10-01T07:42:28.041205Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=0 highest_op_version=3 [INFO] [stdout] 2025-10-01T07:42:28.041991Z DEBUG cassadilia::transaction: Committing transaction blob_hash=68e1d56d64bd1f355f8b709e19d56daa02c60139a5f0cee043e6255a3ce16abc key="key03" [INFO] [stdout] 2025-10-01T07:42:28.042270Z DEBUG cassadilia::transaction: Starting transaction for key '"key05"' using staging file '/tmp/.tmp7yrarT/staging/.tmprFx0hf' [INFO] [stdout] 2025-10-01T07:42:28.042303Z DEBUG cassadilia::transaction: Finishing transaction for key '"key05"' [INFO] [stdout] 2025-10-01T07:42:28.043102Z DEBUG cassadilia::transaction: Starting transaction for key '"key03"' using staging file '/tmp/.tmpW4vIv0/staging/.tmpGw8Oh8' [INFO] [stdout] 2025-10-01T07:42:28.043136Z DEBUG cassadilia::transaction: Finishing transaction for key '"key03"' [INFO] [stdout] 2025-10-01T07:42:28.043622Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] test tests::regression_put_same_content_should_not_delete_blob ... ok [INFO] [stdout] 2025-10-01T07:42:28.046722Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.0054701 total_blobs=3 [INFO] [stdout] 2025-10-01T07:42:28.049214Z DEBUG cassadilia::cas_manager: Moved blob 68e1d56d64bd1f355f8b709e19d56daa02c60139a5f0cee043e6255a3ce16abc to CAS path '/tmp/.tmpHc0IH2/cas/68/e1/d56d64bd1f355f8b709e19d56daa02c60139a5f0cee043e6255a3ce16abc' [INFO] [stdout] 2025-10-01T07:42:28.049677Z DEBUG cassadilia::transaction: Committing transaction blob_hash=82127748252e541c7b3bdfd5b9370d6e1764f374663863d4dcdd1a1845811823 key="key03" [INFO] [stdout] 2025-10-01T07:42:28.049944Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpuX4Tyu/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.050027Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.050042Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.050064Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpuX4Tyu/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.050476Z DEBUG cassadilia::cas_manager: Moved blob 82127748252e541c7b3bdfd5b9370d6e1764f374663863d4dcdd1a1845811823 to CAS path '/tmp/.tmpW4vIv0/cas/82/12/7748252e541c7b3bdfd5b9370d6e1764f374663863d4dcdd1a1845811823' [INFO] [stdout] 2025-10-01T07:42:28.050489Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpgPf7AE/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.050574Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.050591Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.050615Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpgPf7AE/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.050812Z DEBUG cassadilia::transaction: Committing transaction blob_hash=f8165b3c58c0f163fc0aacae0f050d2ba299f3170e772d1d9d5c2435f8c46cc2 key="key05" [INFO] [stdout] 2025-10-01T07:42:28.051192Z DEBUG cassadilia::cas_manager: Moved blob f8165b3c58c0f163fc0aacae0f050d2ba299f3170e772d1d9d5c2435f8c46cc2 to CAS path '/tmp/.tmp7yrarT/cas/f8/16/5b3c58c0f163fc0aacae0f050d2ba299f3170e772d1d9d5c2435f8c46cc2' [INFO] [stdout] 2025-10-01T07:42:28.052233Z DEBUG cassadilia::transaction: Starting transaction for key '"key04"' using staging file '/tmp/.tmpHc0IH2/staging/.tmp75Px5l' [INFO] [stdout] 2025-10-01T07:42:28.052266Z DEBUG cassadilia::transaction: Finishing transaction for key '"key04"' [INFO] [stdout] 2025-10-01T07:42:28.052918Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.0009081 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.053021Z DEBUG cassadilia::transaction: Starting transaction for key '"key1"' using staging file '/tmp/.tmpgPf7AE/staging/.tmpbJOyav' [INFO] [stdout] 2025-10-01T07:42:28.053042Z DEBUG cassadilia::transaction: Finishing transaction for key '"key1"' [INFO] [stdout] test tests::checkpoint::test_checkpoint_prevents_double_replay ... ok [INFO] [stdout] 2025-10-01T07:42:28.053872Z DEBUG cassadilia::transaction: Starting transaction for key '"key04"' using staging file '/tmp/.tmpW4vIv0/staging/.tmpdrW883' [INFO] [stdout] 2025-10-01T07:42:28.053916Z DEBUG cassadilia::transaction: Finishing transaction for key '"key04"' [INFO] [stdout] 2025-10-01T07:42:28.055038Z DEBUG cassadilia::transaction: Committing transaction blob_hash=f362faa031d3265e7457430a6907759557c8a983b6f2a31214df75273fe37472 key="key1" [INFO] [stdout] 2025-10-01T07:42:28.055078Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00302182 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.055245Z DEBUG cassadilia::cas_manager: Moved blob f362faa031d3265e7457430a6907759557c8a983b6f2a31214df75273fe37472 to CAS path '/tmp/.tmpgPf7AE/cas/f3/62/faa031d3265e7457430a6907759557c8a983b6f2a31214df75273fe37472' [INFO] [stdout] 2025-10-01T07:42:28.055277Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpgPf7AE/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.055297Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.055038Z DEBUG cassadilia::transaction: Committing transaction blob_hash=4607a1b43e8c29a684fe473b6bc2302a7c114692f84e8bf4432850fe81abd16d key="key04" [INFO] [stdout] 2025-10-01T07:42:28.055591Z DEBUG cassadilia::transaction: Starting transaction for key '"key06"' using staging file '/tmp/.tmp7yrarT/staging/.tmpoz8r3V' [INFO] [stdout] 2025-10-01T07:42:28.055615Z DEBUG cassadilia::transaction: Finishing transaction for key '"key06"' [INFO] [stdout] test tests::test_api_on_nonexistent_key ... ok [INFO] [stdout] 2025-10-01T07:42:28.056524Z DEBUG cassadilia::cas_manager: Moved blob 4607a1b43e8c29a684fe473b6bc2302a7c114692f84e8bf4432850fe81abd16d to CAS path '/tmp/.tmpHc0IH2/cas/46/07/a1b43e8c29a684fe473b6bc2302a7c114692f84e8bf4432850fe81abd16d' [INFO] [stdout] 2025-10-01T07:42:28.058268Z DEBUG cassadilia::transaction: Committing transaction blob_hash=40eea84cc0e36fc173385598c36a8beb7dfe908b93c8d0f8cf0a273fb805d293 key="key06" [INFO] [stdout] 2025-10-01T07:42:28.058461Z DEBUG cassadilia::cas_manager: Moved blob 40eea84cc0e36fc173385598c36a8beb7dfe908b93c8d0f8cf0a273fb805d293 to CAS path '/tmp/.tmp7yrarT/cas/40/ee/a84cc0e36fc173385598c36a8beb7dfe908b93c8d0f8cf0a273fb805d293' [INFO] [stdout] 2025-10-01T07:42:28.058576Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.058630Z DEBUG cassadilia::transaction: Committing transaction blob_hash=22a0b478a78b950b68b18cb61079b51012be4452c570fa98f9b5dba4f6e9bb27 key="key04" [INFO] [stdout] 2025-10-01T07:42:28.058799Z DEBUG cassadilia::cas_manager: Moved blob 22a0b478a78b950b68b18cb61079b51012be4452c570fa98f9b5dba4f6e9bb27 to CAS path '/tmp/.tmpW4vIv0/cas/22/a0/b478a78b950b68b18cb61079b51012be4452c570fa98f9b5dba4f6e9bb27' [INFO] [stdout] 2025-10-01T07:42:28.059005Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpHc0IH2/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.059092Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpHc0IH2/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.059111Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.059121Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpHc0IH2/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.059201Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpHc0IH2/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.059219Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=5 [INFO] [stdout] 2025-10-01T07:42:28.059229Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=5 highest_op_version=5 [INFO] [stdout] 2025-10-01T07:42:28.059248Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.059259Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.060600Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmp0oM3el/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.060686Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.060702Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.060723Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmp0oM3el/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.060812Z  INFO cassadilia::index: Starting checkpoint operation. reason=Explicit [INFO] [stdout] 2025-10-01T07:42:28.060828Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=Explicit [INFO] [stdout] 2025-10-01T07:42:28.062632Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00027898 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.062673Z  INFO cassadilia::index::persistence: Persisted 5 entries to index '/tmp/.tmpHc0IH2/index' [INFO] [stdout] 2025-10-01T07:42:28.062689Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.062767Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpHc0IH2/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.062786Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.062795Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=5 [INFO] [stdout] 2025-10-01T07:42:28.062804Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=5 [INFO] [stdout] 2025-10-01T07:42:28.062978Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpJocuaH/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.063050Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.063068Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.063088Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpJocuaH/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.063197Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.063714Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmp0oM3el/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.063790Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmp0oM3el/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.063807Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.063817Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmp0oM3el/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.063842Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmp0oM3el/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.063857Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=0 [INFO] [stdout] 2025-10-01T07:42:28.063868Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=0 [INFO] [stdout] 2025-10-01T07:42:28.064419Z  INFO cassadilia::index::persistence: Persisted 1 entries to index '/tmp/.tmpgPf7AE/index' [INFO] [stdout] test tests::test_cleanup_disabled ... ok [INFO] [stdout] 2025-10-01T07:42:28.064441Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.064525Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpgPf7AE/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.064549Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.064559Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:28.064569Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:28.064603Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.066329Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpW4vIv0/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.066419Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpW4vIv0/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.066437Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.066447Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpW4vIv0/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.066669Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=4.872e-5 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.066678Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpnRo6M4/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.066787Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.066786Z DEBUG cassadilia::transaction: Starting transaction for key '"range_blob"' using staging file '/tmp/.tmpJocuaH/staging/.tmpVDHSHx' [INFO] [stdout] 2025-10-01T07:42:28.066803Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.066810Z DEBUG cassadilia::transaction: Finishing transaction for key '"range_blob"' [INFO] [stdout] 2025-10-01T07:42:28.066826Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpnRo6M4/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.066933Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpW4vIv0/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.066955Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=5 [INFO] [stdout] 2025-10-01T07:42:28.066965Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=5 highest_op_version=5 [INFO] [stdout] 2025-10-01T07:42:28.066989Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 1 (for next op version 6) exists at path: /tmp/.tmpW4vIv0/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.067164Z DEBUG cassadilia::transaction: Starting transaction for key '"key07"' using staging file '/tmp/.tmp7yrarT/staging/.tmp7J8jDM' [INFO] [stdout] 2025-10-01T07:42:28.067193Z DEBUG cassadilia::transaction: Finishing transaction for key '"key07"' [INFO] [stdout] 2025-10-01T07:42:28.067455Z  INFO cassadilia::index::persistence: Loaded 1 entries from index '/tmp/.tmpgPf7AE/index' and populated ref counts. [INFO] [stdout] 2025-10-01T07:42:28.067483Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00464852 total_blobs=5 [INFO] [stdout] 2025-10-01T07:42:28.067537Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpgPf7AE/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.067554Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 checkpoint=1 [INFO] [stdout] 2025-10-01T07:42:28.067564Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpgPf7AE/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.067594Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpgPf7AE/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.067608Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=0 [INFO] [stdout] 2025-10-01T07:42:28.067618Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=0 highest_op_version=1 [INFO] [stdout] 2025-10-01T07:42:28.068015Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.000361191 total_blobs=1 [INFO] [stdout] 2025-10-01T07:42:28.068308Z DEBUG cassadilia::transaction: Committing transaction blob_hash=b8f6b168214bf501f5a4b84bff9e105cc5c1f3851b798dcb55ffe887391e84b0 key="range_blob" [INFO] [stdout] 2025-10-01T07:42:28.068370Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=4.665e-5 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.068470Z DEBUG cassadilia::transaction: Starting transaction for key '"test_key"' using staging file '/tmp/.tmpnRo6M4/staging/.tmpLe2I0m' [INFO] [stdout] 2025-10-01T07:42:28.068464Z DEBUG cassadilia::cas_manager: Moved blob b8f6b168214bf501f5a4b84bff9e105cc5c1f3851b798dcb55ffe887391e84b0 to CAS path '/tmp/.tmpJocuaH/cas/b8/f6/b168214bf501f5a4b84bff9e105cc5c1f3851b798dcb55ffe887391e84b0' [INFO] [stdout] 2025-10-01T07:42:28.068503Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpJocuaH/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.068523Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.068964Z DEBUG cassadilia::transaction: Starting transaction for key '"key05"' using staging file '/tmp/.tmpHc0IH2/staging/.tmpdqrWNg' [INFO] [stdout] 2025-10-01T07:42:28.068992Z DEBUG cassadilia::transaction: Finishing transaction for key '"key05"' [INFO] [stdout] test tests::test_checkpoint_persists_index ... ok [INFO] [stdout] 2025-10-01T07:42:28.070343Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.070367Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.070724Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] test tests::test_get_range ... 2025-10-01T07:42:28.071505Z DEBUG cassadilia::transaction: Committing transaction blob_hash=0d828af5e0bdbdb41f026cdf2d1f75e486383fc322c8fc601f308c21ddf00ceb key="key07" [INFO] [stdout] 2025-10-01T07:42:28.071740Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpbc0DcY/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.072725Z DEBUG cassadilia::cas_manager: Moved blob 0d828af5e0bdbdb41f026cdf2d1f75e486383fc322c8fc601f308c21ddf00ceb to CAS path '/tmp/.tmp7yrarT/cas/0d/82/8af5e0bdbdb41f026cdf2d1f75e486383fc322c8fc601f308c21ddf00ceb' [INFO] [stdout] 2025-10-01T07:42:28.072823Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.072841Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.072866Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpbc0DcY/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.072865Z DEBUG cassadilia::transaction: Committing transaction blob_hash=2f5899145239bb665c524eda56584e5e995df3dd675d5a78353262fcf61411b3 key="key05" [INFO] [stdout] ok [INFO] [stdout] 2025-10-01T07:42:28.073791Z DEBUG cassadilia::cas_manager: Moved blob 2f5899145239bb665c524eda56584e5e995df3dd675d5a78353262fcf61411b3 to CAS path '/tmp/.tmpHc0IH2/cas/2f/58/99145239bb665c524eda56584e5e995df3dd675d5a78353262fcf61411b3' [INFO] [stdout] 2025-10-01T07:42:28.073833Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpHc0IH2/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.073856Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.074024Z  INFO cassadilia::index::persistence: Persisted 5 entries to index '/tmp/.tmpW4vIv0/index' [INFO] [stdout] 2025-10-01T07:42:28.074048Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.074137Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpW4vIv0/0_index.wal" }, SegmentInfo { id: 1, path: "/tmp/.tmpW4vIv0/1_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.074161Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.074170Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=5 [INFO] [stdout] 2025-10-01T07:42:28.074179Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=5 [INFO] [stdout] 2025-10-01T07:42:28.074573Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00036123 total_blobs=5 [INFO] [stdout] 2025-10-01T07:42:28.074659Z DEBUG cassadilia::transaction: Starting transaction for key '"key05"' using staging file '/tmp/.tmpW4vIv0/staging/.tmpR5WMcE' [INFO] [stdout] 2025-10-01T07:42:28.074687Z DEBUG cassadilia::transaction: Finishing transaction for key '"key05"' [INFO] [stdout] 2025-10-01T07:42:28.075687Z DEBUG cassadilia::transaction: Starting transaction for key '"key08"' using staging file '/tmp/.tmp7yrarT/staging/.tmpQshFLN' [INFO] [stdout] 2025-10-01T07:42:28.075717Z DEBUG cassadilia::transaction: Finishing transaction for key '"key08"' [INFO] [stdout] 2025-10-01T07:42:28.077423Z DEBUG cassadilia::transaction: Committing transaction blob_hash=b230a71283381b75647c675fcb43daf84115851f6255178bb93a935f49349843 key="key08" [INFO] [stdout] 2025-10-01T07:42:28.077428Z DEBUG cassadilia::transaction: Committing transaction blob_hash=e1adf0a000db31748e36902436adb350e41197faf7375298faefb7fa2cae9ba9 key="key05" [INFO] [stdout] 2025-10-01T07:42:28.077598Z DEBUG cassadilia::cas_manager: Moved blob b230a71283381b75647c675fcb43daf84115851f6255178bb93a935f49349843 to CAS path '/tmp/.tmp7yrarT/cas/b2/30/a71283381b75647c675fcb43daf84115851f6255178bb93a935f49349843' [INFO] [stdout] 2025-10-01T07:42:28.077625Z DEBUG cassadilia::cas_manager: Moved blob e1adf0a000db31748e36902436adb350e41197faf7375298faefb7fa2cae9ba9 to CAS path '/tmp/.tmpW4vIv0/cas/e1/ad/f0a000db31748e36902436adb350e41197faf7375298faefb7fa2cae9ba9' [INFO] [stdout] 2025-10-01T07:42:28.077657Z DEBUG cassadilia::wal::storage: Opening WAL segment 1 for write at path: /tmp/.tmpW4vIv0/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.077678Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 1. [INFO] [stdout] 2025-10-01T07:42:28.079467Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00025475 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.080045Z DEBUG cassadilia::transaction: Starting transaction for key '"key06"' using staging file '/tmp/.tmpHc0IH2/staging/.tmp7wQRoM' [INFO] [stdout] 2025-10-01T07:42:28.080081Z DEBUG cassadilia::transaction: Finishing transaction for key '"key06"' [INFO] [stdout] 2025-10-01T07:42:28.080180Z DEBUG cassadilia::transaction: Starting transaction for key '"valid_key1"' using staging file '/tmp/.tmpbc0DcY/staging/.tmpsudNXY' [INFO] [stdout] 2025-10-01T07:42:28.080200Z DEBUG cassadilia::transaction: Finishing transaction for key '"valid_key1"' [INFO] [stdout] 2025-10-01T07:42:28.080430Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=SegmentRollover [INFO] [stdout] 2025-10-01T07:42:28.080518Z DEBUG cassadilia::transaction: Starting transaction for key '"key09"' using staging file '/tmp/.tmp7yrarT/staging/.tmpWmID9N' [INFO] [stdout] 2025-10-01T07:42:28.080541Z DEBUG cassadilia::transaction: Finishing transaction for key '"key09"' [INFO] [stdout] 2025-10-01T07:42:28.082014Z DEBUG cassadilia::transaction: Committing transaction blob_hash=983ad14be5c0e05f5f9cd121ecb76c248e547922a3a1e0c40ad63c0565e30244 key="key06" [INFO] [stdout] 2025-10-01T07:42:28.082096Z DEBUG cassadilia::transaction: Committing transaction blob_hash=cdf315a98d4330519b10c8c3a3965000d21a130b83f94fe6e172227923b07d5f key="valid_key1" [INFO] [stdout] 2025-10-01T07:42:28.082927Z DEBUG cassadilia::cas_manager: Moved blob cdf315a98d4330519b10c8c3a3965000d21a130b83f94fe6e172227923b07d5f to CAS path '/tmp/.tmpbc0DcY/cas/cd/f3/15a98d4330519b10c8c3a3965000d21a130b83f94fe6e172227923b07d5f' [INFO] [stdout] 2025-10-01T07:42:28.082969Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpbc0DcY/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.082991Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.083163Z DEBUG cassadilia::cas_manager: Moved blob 983ad14be5c0e05f5f9cd121ecb76c248e547922a3a1e0c40ad63c0565e30244 to CAS path '/tmp/.tmpHc0IH2/cas/98/3a/d14be5c0e05f5f9cd121ecb76c248e547922a3a1e0c40ad63c0565e30244' [INFO] [stdout] 2025-10-01T07:42:28.083200Z  INFO cassadilia::index::persistence: Persisted 6 entries to index '/tmp/.tmpW4vIv0/index' [INFO] [stdout] 2025-10-01T07:42:28.083215Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 1. [INFO] [stdout] 2025-10-01T07:42:28.083219Z DEBUG cassadilia::transaction: Committing transaction blob_hash=600c41b30aef01961df9b4d93fe56c5b0bd2d8b7ec60767a4f0135a712e16a81 key="key09" [INFO] [stdout] 2025-10-01T07:42:28.083317Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpW4vIv0/0_index.wal" }, SegmentInfo { id: 1, path: "/tmp/.tmpW4vIv0/1_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.083457Z DEBUG cassadilia::wal::storage: Removing stale WAL segment 0: /tmp/.tmpW4vIv0/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.084015Z DEBUG cassadilia::cas_manager: Moved blob 600c41b30aef01961df9b4d93fe56c5b0bd2d8b7ec60767a4f0135a712e16a81 to CAS path '/tmp/.tmp7yrarT/cas/60/0c/41b30aef01961df9b4d93fe56c5b0bd2d8b7ec60767a4f0135a712e16a81' [INFO] [stdout] 2025-10-01T07:42:28.084036Z  INFO cassadilia::wal::storage: Successfully removed 1 stale WAL segment(s). [INFO] [stdout] 2025-10-01T07:42:28.084052Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=6 [INFO] [stdout] 2025-10-01T07:42:28.084063Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=6 [INFO] [stdout] 2025-10-01T07:42:28.084141Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 1 [INFO] [stdout] 2025-10-01T07:42:28.085278Z  INFO cassadilia::index::persistence: Loaded 6 entries from index '/tmp/.tmpW4vIv0/index' and populated ref counts. [INFO] [stdout] 2025-10-01T07:42:28.085366Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 1, path: "/tmp/.tmpW4vIv0/1_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.085384Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 checkpoint=6 [INFO] [stdout] 2025-10-01T07:42:28.085396Z DEBUG cassadilia::wal::replay: Replaying segment segment=1 path=/tmp/.tmpW4vIv0/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.085430Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 1 at '/tmp/.tmpW4vIv0/1_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.085446Z  INFO cassadilia::wal::replay: Segment replay complete segment=1 entries=0 [INFO] [stdout] 2025-10-01T07:42:28.085456Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=0 highest_op_version=6 [INFO] [stdout] 2025-10-01T07:42:28.085625Z DEBUG cassadilia::transaction: Starting transaction for key '"key07"' using staging file '/tmp/.tmpHc0IH2/staging/.tmpSk0O5I' [INFO] [stdout] 2025-10-01T07:42:28.085652Z DEBUG cassadilia::transaction: Finishing transaction for key '"key07"' [INFO] [stdout] 2025-10-01T07:42:28.085717Z DEBUG cassadilia::transaction: Starting transaction for key '"key10"' using staging file '/tmp/.tmp7yrarT/staging/.tmp8AVE6k' [INFO] [stdout] 2025-10-01T07:42:28.085746Z DEBUG cassadilia::transaction: Finishing transaction for key '"key10"' [INFO] [stdout] 2025-10-01T07:42:28.087002Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.088158Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00265374 total_blobs=6 [INFO] [stdout] test tests::checkpoint::test_wal_segment_rollover_triggers_checkpoint ... ok [INFO] [stdout] 2025-10-01T07:42:28.089590Z DEBUG cassadilia::transaction: Committing transaction blob_hash=760ca58ec3df7085c57928fe29694647cb011a9dbb8b4fd0d5cf74819bfa90eb key="key07" [INFO] [stdout] 2025-10-01T07:42:28.089742Z DEBUG cassadilia::cas_manager: Moved blob 760ca58ec3df7085c57928fe29694647cb011a9dbb8b4fd0d5cf74819bfa90eb to CAS path '/tmp/.tmpHc0IH2/cas/76/0c/a58ec3df7085c57928fe29694647cb011a9dbb8b4fd0d5cf74819bfa90eb' [INFO] [stdout] 2025-10-01T07:42:28.089822Z DEBUG cassadilia::transaction: Committing transaction blob_hash=c2fe34f9a080b55c101dc9d336438763ddbe757f11ef794b602458e26a21ffe5 key="key10" [INFO] [stdout] 2025-10-01T07:42:28.090003Z DEBUG cassadilia::cas_manager: Moved blob c2fe34f9a080b55c101dc9d336438763ddbe757f11ef794b602458e26a21ffe5 to CAS path '/tmp/.tmp7yrarT/cas/c2/fe/34f9a080b55c101dc9d336438763ddbe757f11ef794b602458e26a21ffe5' [INFO] [stdout] 2025-10-01T07:42:28.090033Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpbc0DcY/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.090044Z DEBUG cassadilia::wal::storage: Sealing WAL segment 0 [INFO] [stdout] 2025-10-01T07:42:28.090061Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.090124Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpbc0DcY/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.090143Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.090153Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpbc0DcY/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.093025Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpbc0DcY/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.093058Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=1 [INFO] [stdout] 2025-10-01T07:42:28.093070Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=1 highest_op_version=1 [INFO] [stdout] 2025-10-01T07:42:28.093070Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.093091Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.093103Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.093121Z DEBUG cassadilia::wal::storage: Opening WAL segment 1 for write at path: /tmp/.tmp7yrarT/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.093372Z  INFO cassadilia::index::persistence: Loaded 5 entries from index '/tmp/.tmpHc0IH2/index' and populated ref counts. [INFO] [stdout] 2025-10-01T07:42:28.093459Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpHc0IH2/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.093485Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 checkpoint=5 [INFO] [stdout] 2025-10-01T07:42:28.093496Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpHc0IH2/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.093578Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpHc0IH2/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.093597Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=3 [INFO] [stdout] 2025-10-01T07:42:28.093613Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=3 highest_op_version=8 [INFO] [stdout] 2025-10-01T07:42:28.093641Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.093652Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.094086Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 1. [INFO] [stdout] 2025-10-01T07:42:28.094998Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpOZZxzs/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.095097Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.095111Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.095133Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpOZZxzs/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.095443Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=SegmentRollover [INFO] [stdout] 2025-10-01T07:42:28.095527Z  INFO cassadilia::index::persistence: Persisted 1 entries to index '/tmp/.tmpbc0DcY/index' [INFO] [stdout] 2025-10-01T07:42:28.095549Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.095624Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpbc0DcY/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.095651Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.095661Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:28.095671Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:28.096030Z  INFO cassadilia::orphan: Deleted orphaned blob hash=2b2e0f3ac5c07ce0e1a48cbac77e3efcc4b21d58de0826c22319ddb123e4432f [INFO] [stdout] 2025-10-01T07:42:28.096776Z  INFO cassadilia::index::persistence: Persisted 8 entries to index '/tmp/.tmpHc0IH2/index' [INFO] [stdout] 2025-10-01T07:42:28.096801Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.096962Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=8.739e-5 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.096873Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpHc0IH2/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.097101Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.097115Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=8 [INFO] [stdout] 2025-10-01T07:42:28.097124Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=8 [INFO] [stdout] 2025-10-01T07:42:28.097158Z DEBUG cassadilia::transaction: Starting transaction for key '"key1"' using staging file '/tmp/.tmpOZZxzs/staging/.tmpf13MSc' [INFO] [stdout] 2025-10-01T07:42:28.097682Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00052337 total_blobs=8 [INFO] [stdout] 2025-10-01T07:42:28.097198Z DEBUG cassadilia::transaction: Finishing transaction for key '"key1"' [INFO] [stdout] 2025-10-01T07:42:28.098059Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpW4qlLa/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.098145Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.098156Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.098177Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpW4qlLa/0_index.wal [INFO] [stdout] test tests::test_orphan_detection_and_cleanup ... ok [INFO] [stdout] 2025-10-01T07:42:28.099607Z  INFO cassadilia::index::persistence: Persisted 11 entries to index '/tmp/.tmp7yrarT/index' [INFO] [stdout] 2025-10-01T07:42:28.099631Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 1. [INFO] [stdout] 2025-10-01T07:42:28.099716Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmp7yrarT/0_index.wal" }, SegmentInfo { id: 1, path: "/tmp/.tmp7yrarT/1_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.099745Z DEBUG cassadilia::wal::storage: Removing stale WAL segment 0: /tmp/.tmp7yrarT/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.100238Z  INFO cassadilia::wal::storage: Successfully removed 1 stale WAL segment(s). [INFO] [stdout] 2025-10-01T07:42:28.100260Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=11 [INFO] [stdout] 2025-10-01T07:42:28.100270Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=11 [INFO] [stdout] 2025-10-01T07:42:28.100355Z DEBUG cassadilia::transaction: Starting transaction for key '"key11"' using staging file '/tmp/.tmp7yrarT/staging/.tmp99lbYO' [INFO] [stdout] 2025-10-01T07:42:28.100379Z DEBUG cassadilia::transaction: Finishing transaction for key '"key11"' [INFO] [stdout] 2025-10-01T07:42:28.102065Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00013413 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.102156Z DEBUG cassadilia::transaction: Starting transaction for key '"valid_key1"' using staging file '/tmp/.tmpW4qlLa/staging/.tmpK1sMFX' [INFO] [stdout] 2025-10-01T07:42:28.102178Z DEBUG cassadilia::transaction: Finishing transaction for key '"valid_key1"' [INFO] [stdout] 2025-10-01T07:42:28.103133Z DEBUG cassadilia::transaction: Committing transaction blob_hash=6b786ef158450a90c98f422b22808925948a61428b6b72fda90216a7bb2fb413 key="key11" [INFO] [stdout] 2025-10-01T07:42:28.104054Z DEBUG cassadilia::cas_manager: Moved blob 6b786ef158450a90c98f422b22808925948a61428b6b72fda90216a7bb2fb413 to CAS path '/tmp/.tmp7yrarT/cas/6b/78/6ef158450a90c98f422b22808925948a61428b6b72fda90216a7bb2fb413' [INFO] [stdout] test tests::checkpoint::test_replay_creates_checkpoint_after_restart ... ok [INFO] [stdout] 2025-10-01T07:42:28.105376Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmppRajVs/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.105454Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.105487Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.105509Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmppRajVs/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.105919Z DEBUG cassadilia::transaction: Committing transaction blob_hash=cdf315a98d4330519b10c8c3a3965000d21a130b83f94fe6e172227923b07d5f key="valid_key1" [INFO] [stdout] 2025-10-01T07:42:28.105996Z DEBUG cassadilia::transaction: Committing transaction blob_hash=b6e382289cdb3b5285d65af149c3420d418ae8d9737779515f4bc8bba7af1a22 key="key1" [INFO] [stdout] 2025-10-01T07:42:28.106203Z DEBUG cassadilia::transaction: Starting transaction for key '"key12"' using staging file '/tmp/.tmp7yrarT/staging/.tmpbwbhvo' [INFO] [stdout] 2025-10-01T07:42:28.106235Z DEBUG cassadilia::transaction: Finishing transaction for key '"key12"' [INFO] [stdout] 2025-10-01T07:42:28.106607Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=4.555e-5 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.106688Z DEBUG cassadilia::transaction: Starting transaction for key '"key1"' using staging file '/tmp/.tmppRajVs/staging/.tmpFLp6yY' [INFO] [stdout] 2025-10-01T07:42:28.106708Z DEBUG cassadilia::transaction: Finishing transaction for key '"key1"' [INFO] [stdout] 2025-10-01T07:42:28.112806Z DEBUG cassadilia::cas_manager: Moved blob cdf315a98d4330519b10c8c3a3965000d21a130b83f94fe6e172227923b07d5f to CAS path '/tmp/.tmpW4qlLa/cas/cd/f3/15a98d4330519b10c8c3a3965000d21a130b83f94fe6e172227923b07d5f' [INFO] [stdout] 2025-10-01T07:42:28.112861Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpW4qlLa/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.112900Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.112795Z DEBUG cassadilia::cas_manager: Moved blob b6e382289cdb3b5285d65af149c3420d418ae8d9737779515f4bc8bba7af1a22 to CAS path '/tmp/.tmpOZZxzs/cas/b6/e3/82289cdb3b5285d65af149c3420d418ae8d9737779515f4bc8bba7af1a22' [INFO] [stdout] 2025-10-01T07:42:28.113092Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpOZZxzs/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.113117Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.113131Z DEBUG cassadilia::transaction: Committing transaction blob_hash=bf264d7f3851008e6ba34171bc9b1d1c46999b67c2ab7ae74afa0210146da579 key="key12" [INFO] [stdout] 2025-10-01T07:42:28.113356Z DEBUG cassadilia::cas_manager: Moved blob bf264d7f3851008e6ba34171bc9b1d1c46999b67c2ab7ae74afa0210146da579 to CAS path '/tmp/.tmp7yrarT/cas/bf/26/4d7f3851008e6ba34171bc9b1d1c46999b67c2ab7ae74afa0210146da579' [INFO] [stdout] 2025-10-01T07:42:28.113506Z DEBUG cassadilia::transaction: Committing transaction blob_hash=b6e382289cdb3b5285d65af149c3420d418ae8d9737779515f4bc8bba7af1a22 key="key1" [INFO] [stdout] 2025-10-01T07:42:28.114251Z DEBUG cassadilia::cas_manager: Moved blob b6e382289cdb3b5285d65af149c3420d418ae8d9737779515f4bc8bba7af1a22 to CAS path '/tmp/.tmppRajVs/cas/b6/e3/82289cdb3b5285d65af149c3420d418ae8d9737779515f4bc8bba7af1a22' [INFO] [stdout] 2025-10-01T07:42:28.114289Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmppRajVs/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.114311Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.114675Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpZuhxBL/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.114766Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.114780Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.114803Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpZuhxBL/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.115139Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.115169Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.115636Z DEBUG cassadilia::transaction: Starting transaction for key '"key13"' using staging file '/tmp/.tmp7yrarT/staging/.tmprvJLDS' [INFO] [stdout] 2025-10-01T07:42:28.115666Z DEBUG cassadilia::transaction: Finishing transaction for key '"key13"' [INFO] [stdout] 2025-10-01T07:42:28.115690Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpOZZxzs/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.115780Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpW4qlLa/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.115787Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpOZZxzs/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.115808Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.115820Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpOZZxzs/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.115862Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpW4qlLa/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.115871Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpOZZxzs/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.115896Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.115913Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpW4qlLa/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.115912Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=1 [INFO] [stdout] 2025-10-01T07:42:28.115928Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=1 highest_op_version=1 [INFO] [stdout] test tests::test_io_error_on_staging_file_creation ... FAILED [INFO] [stdout] 2025-10-01T07:42:28.115949Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.115960Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.115959Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpW4qlLa/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.115982Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=1 [INFO] [stdout] 2025-10-01T07:42:28.115992Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=1 highest_op_version=1 [INFO] [stdout] 2025-10-01T07:42:28.116012Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.116023Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.120247Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.003733559 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.120511Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.120797Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmppRajVs/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.120898Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmppRajVs/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.120919Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.120930Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmppRajVs/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.120985Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmppRajVs/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.121003Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=1 [INFO] [stdout] 2025-10-01T07:42:28.121013Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=1 highest_op_version=1 [INFO] [stdout] 2025-10-01T07:42:28.121034Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.121045Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.121269Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpZuhxBL/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.121419Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpZuhxBL/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.121445Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.121455Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpZuhxBL/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.121509Z DEBUG cassadilia::transaction: Committing transaction blob_hash=022bf00ea24955df1ffd5f4de80b676435a254dbab8fe0067b4f4794c6290938 key="key13" [INFO] [stdout] 2025-10-01T07:42:28.129022Z  INFO cassadilia::index::persistence: Persisted 1 entries to index '/tmp/.tmpW4qlLa/index' [INFO] [stdout] 2025-10-01T07:42:28.129134Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.129288Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpW4qlLa/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.129386Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpZuhxBL/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.129479Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=0 [INFO] [stdout] 2025-10-01T07:42:28.129534Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.129617Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:28.129577Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=0 [INFO] [stdout] 2025-10-01T07:42:28.129753Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:28.130074Z  INFO cassadilia::orphan: Deleted orphaned blob hash=aa13b58b0bf7dad737cccadc8dba4f966d58f57c61c864c6e8228164358256f3 [INFO] [stdout] 2025-10-01T07:42:28.130351Z DEBUG cassadilia::cas_manager: Moved blob 022bf00ea24955df1ffd5f4de80b676435a254dbab8fe0067b4f4794c6290938 to CAS path '/tmp/.tmp7yrarT/cas/02/2b/f00ea24955df1ffd5f4de80b676435a254dbab8fe0067b4f4794c6290938' [INFO] [stdout] 2025-10-01T07:42:28.130423Z  INFO cassadilia::index::persistence: Persisted 1 entries to index '/tmp/.tmpOZZxzs/index' [INFO] [stdout] 2025-10-01T07:42:28.131097Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.131180Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpOZZxzs/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.131200Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.131209Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:28.131219Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:28.131336Z ERROR cassadilia::orphan: Missing blob in filesystem hash=b6e382289cdb3b5285d65af149c3420d418ae8d9737779515f4bc8bba7af1a22 [INFO] [stdout] 2025-10-01T07:42:28.131380Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=1 corrupted_blobs=0 staging_files=0 scan_time=0.0001318 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.130463Z ERROR cassadilia::orphan: Corrupted blob detected hash=cdf315a98d4330519b10c8c3a3965000d21a130b83f94fe6e172227923b07d5f expected_meta=ExpectedMeta { blob_size: 12 } [INFO] [stdout] 2025-10-01T07:42:28.130531Z  INFO cassadilia::index::persistence: Persisted 1 entries to index '/tmp/.tmppRajVs/index' [INFO] [stdout] 2025-10-01T07:42:28.131924Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.132008Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmppRajVs/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.132025Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.132034Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:28.132042Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=1 [INFO] [stdout] test tests::test_orphan_stats_holds_lock ... ok [INFO] [stdout] test tests::test_orphan_detection_with_missing_blobs ... ok [INFO] [stdout] 2025-10-01T07:42:28.132930Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=1 staging_files=0 scan_time=0.00310236 total_blobs=1 [INFO] [stdout] 2025-10-01T07:42:28.133838Z  INFO cassadilia::orphan: Quarantined orphaned blob hash=aa13b58b0bf7dad737cccadc8dba4f966d58f57c61c864c6e8228164358256f3 dest="/tmp/.tmppRajVs/quarantine/aa13b58b0bf7dad737cccadc8dba4f966d58f57c61c864c6e8228164358256f3" [INFO] [stdout] 2025-10-01T07:42:28.134399Z DEBUG cassadilia::transaction: Starting transaction for key '"key14"' using staging file '/tmp/.tmp7yrarT/staging/.tmp9wQVlh' [INFO] [stdout] 2025-10-01T07:42:28.134530Z DEBUG cassadilia::transaction: Finishing transaction for key '"key14"' [INFO] [stdout] test tests::test_orphan_detection_with_integrity_check ... ok [INFO] [stdout] 2025-10-01T07:42:28.134699Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpC2qOxI/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.134794Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.134812Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.134833Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpC2qOxI/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.137025Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpF6R8ty/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.137069Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=4.96e-5 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.137156Z DEBUG cassadilia::transaction: Starting transaction for key '"overwrite_test"' using staging file '/tmp/.tmpC2qOxI/staging/.tmpNRgyEI' [INFO] [stdout] 2025-10-01T07:42:28.137179Z DEBUG cassadilia::transaction: Finishing transaction for key '"overwrite_test"' [INFO] [stdout] 2025-10-01T07:42:28.137105Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.137221Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.137248Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpF6R8ty/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.137546Z DEBUG cassadilia::transaction: Committing transaction blob_hash=eeef0fe5509d1d8747688deb5189fbfdf1f99c790e1022b53084fe0c9e25a877 key="key14" [INFO] [stdout] 2025-10-01T07:42:28.138187Z DEBUG cassadilia::cas_manager: Moved blob eeef0fe5509d1d8747688deb5189fbfdf1f99c790e1022b53084fe0c9e25a877 to CAS path '/tmp/.tmp7yrarT/cas/ee/ef/0fe5509d1d8747688deb5189fbfdf1f99c790e1022b53084fe0c9e25a877' [INFO] [stdout] 2025-10-01T07:42:28.138341Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpI3XNwV/index' not found, starting fresh. State remains new. [INFO] [stdout] test tests::test_orphan_quarantine ... ok [INFO] [stdout] 2025-10-01T07:42:28.139004Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.139024Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.139045Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpI3XNwV/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.139125Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00068069 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.139200Z DEBUG cassadilia::transaction: Starting transaction for key '[109, 121, 95, 98, 121, 116, 101, 115, 95, 98, 108, 111, 98]' using staging file '/tmp/.tmpF6R8ty/staging/.tmpBxuu5m' [INFO] [stdout] 2025-10-01T07:42:28.139223Z DEBUG cassadilia::transaction: Finishing transaction for key '[109, 121, 95, 98, 121, 116, 101, 115, 95, 98, 108, 111, 98]' [INFO] [stdout] 2025-10-01T07:42:28.139998Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpNweZot/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.140271Z DEBUG cassadilia::transaction: Committing transaction blob_hash=a6e7f93354fdbbc10c31e6f310a968b94525d577fd4267550dedd55d846ab1c5 key="overwrite_test" [INFO] [stdout] 2025-10-01T07:42:28.147061Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpiQHddn/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.147180Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.147198Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.147223Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpiQHddn/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.147403Z DEBUG cassadilia::transaction: Committing transaction blob_hash=5926801fac07b300531ff182c815f42c1d11a65c72fb365204de81d2d00acff6 key=[109, 121, 95, 98, 121, 116, 101, 115, 95, 98, 108, 111, 98] [INFO] [stdout] 2025-10-01T07:42:28.147576Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 1 [INFO] [stdout] 2025-10-01T07:42:28.147756Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.147775Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.147799Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpNweZot/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.148426Z  INFO cassadilia::index::persistence: Loaded 11 entries from index '/tmp/.tmp7yrarT/index' and populated ref counts. [INFO] [stdout] 2025-10-01T07:42:28.148520Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 1, path: "/tmp/.tmp7yrarT/1_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.148541Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 checkpoint=11 [INFO] [stdout] 2025-10-01T07:42:28.148553Z DEBUG cassadilia::wal::replay: Replaying segment segment=1 path=/tmp/.tmp7yrarT/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.148648Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 1 at '/tmp/.tmp7yrarT/1_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.148669Z  INFO cassadilia::wal::replay: Segment replay complete segment=1 entries=4 [INFO] [stdout] 2025-10-01T07:42:28.148680Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=4 highest_op_version=15 [INFO] [stdout] 2025-10-01T07:42:28.148702Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.148901Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00120147 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.149006Z DEBUG cassadilia::transaction: Starting transaction for key '"my_first_blob"' using staging file '/tmp/.tmpI3XNwV/staging/.tmpseHjH7' [INFO] [stdout] 2025-10-01T07:42:28.149031Z DEBUG cassadilia::transaction: Finishing transaction for key '"my_first_blob"' [INFO] [stdout] 2025-10-01T07:42:28.149265Z DEBUG cassadilia::cas_manager: Moved blob a6e7f93354fdbbc10c31e6f310a968b94525d577fd4267550dedd55d846ab1c5 to CAS path '/tmp/.tmpC2qOxI/cas/a6/e7/f93354fdbbc10c31e6f310a968b94525d577fd4267550dedd55d846ab1c5' [INFO] [stdout] 2025-10-01T07:42:28.149309Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpC2qOxI/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.149356Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.148713Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.155192Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00296888 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.156197Z DEBUG cassadilia::transaction: Committing transaction blob_hash=3e5cebb6c8e26480a4b41a27eb356a16765db43f91e72a506fcb86c13a1628f7 key="my_first_blob" [INFO] [stdout] 2025-10-01T07:42:28.156279Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.156399Z DEBUG cassadilia::cas_manager: Moved blob 3e5cebb6c8e26480a4b41a27eb356a16765db43f91e72a506fcb86c13a1628f7 to CAS path '/tmp/.tmpI3XNwV/cas/3e/5c/ebb6c8e26480a4b41a27eb356a16765db43f91e72a506fcb86c13a1628f7' [INFO] [stdout] 2025-10-01T07:42:28.156415Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=5.474e-5 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.156440Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpI3XNwV/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.156478Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.156523Z DEBUG cassadilia::transaction: Starting transaction for key '"remove_persist_test"' using staging file '/tmp/.tmpNweZot/staging/.tmp08ULRk' [INFO] [stdout] 2025-10-01T07:42:28.156544Z DEBUG cassadilia::transaction: Finishing transaction for key '"remove_persist_test"' [INFO] [stdout] 2025-10-01T07:42:28.156751Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpC2qOxI/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.156859Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpC2qOxI/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.156896Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.156908Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpC2qOxI/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.157134Z DEBUG cassadilia::transaction: Starting transaction for key '"key_1"' using staging file '/tmp/.tmpiQHddn/staging/.tmpimG705' [INFO] [stdout] 2025-10-01T07:42:28.157168Z DEBUG cassadilia::transaction: Finishing transaction for key '"key_1"' [INFO] [stdout] 2025-10-01T07:42:28.158083Z DEBUG cassadilia::cas_manager: Moved blob 5926801fac07b300531ff182c815f42c1d11a65c72fb365204de81d2d00acff6 to CAS path '/tmp/.tmpF6R8ty/cas/59/26/801fac07b300531ff182c815f42c1d11a65c72fb365204de81d2d00acff6' [INFO] [stdout] 2025-10-01T07:42:28.158131Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpF6R8ty/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.158157Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.158250Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpC2qOxI/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.158279Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=1 [INFO] [stdout] 2025-10-01T07:42:28.158295Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=1 highest_op_version=1 [INFO] [stdout] 2025-10-01T07:42:28.158318Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.158330Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.158502Z DEBUG cassadilia::transaction: Committing transaction blob_hash=2c0e850013924f7df98347ff6dcf2d84d6f9d04e678ab76e343ae4732edf25d1 key="remove_persist_test" [INFO] [stdout] 2025-10-01T07:42:28.159213Z DEBUG cassadilia::cas_manager: Moved blob 2c0e850013924f7df98347ff6dcf2d84d6f9d04e678ab76e343ae4732edf25d1 to CAS path '/tmp/.tmpNweZot/cas/2c/0e/850013924f7df98347ff6dcf2d84d6f9d04e678ab76e343ae4732edf25d1' [INFO] [stdout] 2025-10-01T07:42:28.159252Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpNweZot/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.159276Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.162910Z DEBUG cassadilia::cas_manager: Successfully deleted unreferenced CAS file: /tmp/.tmpI3XNwV/cas/3e/5c/ebb6c8e26480a4b41a27eb356a16765db43f91e72a506fcb86c13a1628f7 [INFO] [stdout] 2025-10-01T07:42:28.162960Z DEBUG cassadilia::cas_manager: Successfully deleted unreferenced CAS file: /tmp/.tmpF6R8ty/cas/59/26/801fac07b300531ff182c815f42c1d11a65c72fb365204de81d2d00acff6 [INFO] [stdout] 2025-10-01T07:42:28.163016Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.163058Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.163224Z DEBUG cassadilia::transaction: Committing transaction blob_hash=7db435c33dc4a7d581a9259698dbcae28a7b130bbff1123b36b2e2e987bf9676 key="key_1" [INFO] [stdout] 2025-10-01T07:42:28.165936Z  INFO cassadilia::index::persistence: Persisted 15 entries to index '/tmp/.tmp7yrarT/index' [INFO] [stdout] 2025-10-01T07:42:28.165966Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 1. [INFO] [stdout] 2025-10-01T07:42:28.166333Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 1, path: "/tmp/.tmp7yrarT/1_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.166365Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 1). [INFO] [stdout] 2025-10-01T07:42:28.166377Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=15 [INFO] [stdout] 2025-10-01T07:42:28.166393Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=15 [INFO] [stdout] 2025-10-01T07:42:28.167726Z DEBUG cassadilia::cas_manager: Moved blob 7db435c33dc4a7d581a9259698dbcae28a7b130bbff1123b36b2e2e987bf9676 to CAS path '/tmp/.tmpiQHddn/cas/7d/b4/35c33dc4a7d581a9259698dbcae28a7b130bbff1123b36b2e2e987bf9676' [INFO] [stdout] 2025-10-01T07:42:28.167768Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpiQHddn/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.167792Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.167970Z  INFO cassadilia::index::persistence: Persisted 1 entries to index '/tmp/.tmpC2qOxI/index' [INFO] [stdout] 2025-10-01T07:42:28.168005Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.168084Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpC2qOxI/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.168130Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.168142Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:28.168153Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:28.168319Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.000132591 total_blobs=1 [INFO] [stdout] 2025-10-01T07:42:28.168436Z DEBUG cassadilia::transaction: Starting transaction for key '"overwrite_test"' using staging file '/tmp/.tmpC2qOxI/staging/.tmpZIuWkC' [INFO] [stdout] 2025-10-01T07:42:28.168458Z DEBUG cassadilia::transaction: Finishing transaction for key '"overwrite_test"' [INFO] [stdout] test tests::test_put_get_remove_string_key ... ok [INFO] [stdout] test tests::test_put_get_remove_bytes_key ... ok [INFO] [stdout] 2025-10-01T07:42:28.170233Z DEBUG cassadilia::transaction: Starting transaction for key '"key_2"' using staging file '/tmp/.tmpiQHddn/staging/.tmpv4eHSq' [INFO] [stdout] 2025-10-01T07:42:28.170289Z DEBUG cassadilia::transaction: Finishing transaction for key '"key_2"' [INFO] [stdout] 2025-10-01T07:42:28.170875Z DEBUG cassadilia::cas_manager: Successfully deleted unreferenced CAS file: /tmp/.tmpNweZot/cas/2c/0e/850013924f7df98347ff6dcf2d84d6f9d04e678ab76e343ae4732edf25d1 [INFO] [stdout] 2025-10-01T07:42:28.170938Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.171198Z DEBUG cassadilia::transaction: Committing transaction blob_hash=b4de87a1c0535ed854ef70db5a5e1b72f4dc30c696b58a4a3615d7317167099f key="overwrite_test" [INFO] [stdout] 2025-10-01T07:42:28.172061Z DEBUG cassadilia::cas_manager: Moved blob b4de87a1c0535ed854ef70db5a5e1b72f4dc30c696b58a4a3615d7317167099f to CAS path '/tmp/.tmpC2qOxI/cas/b4/de/87a1c0535ed854ef70db5a5e1b72f4dc30c696b58a4a3615d7317167099f' [INFO] [stdout] 2025-10-01T07:42:28.172078Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpNweZot/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.172102Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpC2qOxI/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.172122Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.172161Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpNweZot/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.172179Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.172189Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpNweZot/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.172297Z DEBUG cassadilia::transaction: Committing transaction blob_hash=409d96651e5e689bdbe491c2ed943ed4d6744be9ee1f01aa5a61b387f2491b0f key="key_2" [INFO] [stdout] 2025-10-01T07:42:28.172356Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpro2QmX/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.172394Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpNL0c02/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.172431Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.172449Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.172471Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpro2QmX/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.172488Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.172514Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.172534Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpNweZot/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.172539Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpNL0c02/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.172555Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=2 [INFO] [stdout] 2025-10-01T07:42:28.172566Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=2 highest_op_version=2 [INFO] [stdout] 2025-10-01T07:42:28.172588Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.172607Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.172852Z DEBUG cassadilia::cas_manager: Moved blob 409d96651e5e689bdbe491c2ed943ed4d6744be9ee1f01aa5a61b387f2491b0f to CAS path '/tmp/.tmpiQHddn/cas/40/9d/96651e5e689bdbe491c2ed943ed4d6744be9ee1f01aa5a61b387f2491b0f' [INFO] [stdout] 2025-10-01T07:42:28.174258Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.007825519 total_blobs=15 [INFO] [stdout] 2025-10-01T07:42:28.174772Z DEBUG cassadilia::cas_manager: Successfully deleted unreferenced CAS file: /tmp/.tmpC2qOxI/cas/a6/e7/f93354fdbbc10c31e6f310a968b94525d577fd4267550dedd55d846ab1c5 [INFO] [stdout] 2025-10-01T07:42:28.174872Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.175162Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.001144791 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.175235Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=4.9931e-5 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.175248Z  INFO cassadilia::index::persistence: Loaded 1 entries from index '/tmp/.tmpC2qOxI/index' and populated ref counts. [INFO] [stdout] 2025-10-01T07:42:28.175248Z DEBUG cassadilia::transaction: Starting transaction for key '"dropped_tx_key"' using staging file '/tmp/.tmpro2QmX/staging/.tmpJOskjV' [INFO] [stdout] 2025-10-01T07:42:28.175328Z DEBUG cassadilia::transaction: Starting transaction for key '"key1"' using staging file '/tmp/.tmpNL0c02/staging/.tmpVa3pR4' [INFO] [stdout] 2025-10-01T07:42:28.175327Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpC2qOxI/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.175351Z DEBUG cassadilia::transaction: Finishing transaction for key '"key1"' [INFO] [stdout] 2025-10-01T07:42:28.175350Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 checkpoint=1 [INFO] [stdout] 2025-10-01T07:42:28.175363Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpC2qOxI/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.175410Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpC2qOxI/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.175428Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=1 [INFO] [stdout] 2025-10-01T07:42:28.175439Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=1 highest_op_version=2 [INFO] [stdout] 2025-10-01T07:42:28.175462Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.175487Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] test tests::test_transaction_drop_cleans_up_staging_file ... ok [INFO] [stdout] 2025-10-01T07:42:28.175823Z DEBUG cassadilia::transaction: Starting transaction for key '"key10"' using staging file '/tmp/.tmp7yrarT/staging/.tmpGUntkN' [INFO] [stdout] 2025-10-01T07:42:28.175853Z DEBUG cassadilia::transaction: Finishing transaction for key '"key10"' [INFO] [stdout] 2025-10-01T07:42:28.176154Z DEBUG cassadilia::transaction: Starting transaction for key '"key_3"' using staging file '/tmp/.tmpiQHddn/staging/.tmpJLaJxa' [INFO] [stdout] 2025-10-01T07:42:28.176193Z DEBUG cassadilia::transaction: Finishing transaction for key '"key_3"' [INFO] [stdout] 2025-10-01T07:42:28.177112Z DEBUG cassadilia::transaction: Committing transaction blob_hash=637140a8a0a8e97655585db60b46b89af928c2c431953a2ec77b766e113a38a3 key="key1" [INFO] [stdout] 2025-10-01T07:42:28.178702Z DEBUG cassadilia::cas_manager: Moved blob 637140a8a0a8e97655585db60b46b89af928c2c431953a2ec77b766e113a38a3 to CAS path '/tmp/.tmpNL0c02/cas/63/71/40a8a0a8e97655585db60b46b89af928c2c431953a2ec77b766e113a38a3' [INFO] [stdout] 2025-10-01T07:42:28.178744Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpNL0c02/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.178770Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.178970Z  INFO cassadilia::index::persistence: Persisted 0 entries to index '/tmp/.tmpNweZot/index' [INFO] [stdout] 2025-10-01T07:42:28.179008Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.179097Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpNweZot/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.179119Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.179129Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=2 [INFO] [stdout] 2025-10-01T07:42:28.179139Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=2 [INFO] [stdout] 2025-10-01T07:42:28.179285Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.000110311 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:28.179961Z  INFO cassadilia::index::persistence: Persisted 1 entries to index '/tmp/.tmpC2qOxI/index' [INFO] [stdout] 2025-10-01T07:42:28.179981Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:28.180062Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpC2qOxI/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.180083Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.180093Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=2 [INFO] [stdout] 2025-10-01T07:42:28.180103Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=2 [INFO] [stdout] 2025-10-01T07:42:28.180318Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00018177 total_blobs=1 [INFO] [stdout] 2025-10-01T07:42:28.181981Z DEBUG cassadilia::transaction: Committing transaction blob_hash=68e1d56d64bd1f355f8b709e19d56daa02c60139a5f0cee043e6255a3ce16abc key="key_3" [INFO] [stdout] 2025-10-01T07:42:28.182192Z DEBUG cassadilia::cas_manager: Moved blob 68e1d56d64bd1f355f8b709e19d56daa02c60139a5f0cee043e6255a3ce16abc to CAS path '/tmp/.tmpiQHddn/cas/68/e1/d56d64bd1f355f8b709e19d56daa02c60139a5f0cee043e6255a3ce16abc' [INFO] [stdout] 2025-10-01T07:42:28.182347Z DEBUG cassadilia::transaction: Committing transaction blob_hash=74c13e56f64bbf12a0b5b010b2f289d174cda38aadd7b227f65af4e87b87078e key="key10" [INFO] [stdout] test tests::test_remove_persists_across_reopen ... ok [INFO] [stdout] test tests::test_overwrite_persists_across_reopen ... ok [INFO] [stdout] test types::tests::test_blob_hash_from_relative_path_valid_cases ... ok [INFO] [stdout] test types::tests::test_blob_hash_from_relative_path_with_prefix ... ok [INFO] [stdout] test types::tests::test_blob_hash_relative_path_roundtrip ... ok [INFO] [stdout] test types::tests::test_blob_hash_relative_path_structure ... ok [INFO] [stdout] 2025-10-01T07:42:28.184097Z DEBUG cassadilia::cas_manager: Moved blob 74c13e56f64bbf12a0b5b010b2f289d174cda38aadd7b227f65af4e87b87078e to CAS path '/tmp/.tmp7yrarT/cas/74/c1/3e56f64bbf12a0b5b010b2f289d174cda38aadd7b227f65af4e87b87078e' [INFO] [stdout] 2025-10-01T07:42:28.184140Z DEBUG cassadilia::wal::storage: Opening WAL segment 1 for write at path: /tmp/.tmp7yrarT/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.184167Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 1. [INFO] [stdout] 2025-10-01T07:42:28.184515Z DEBUG cassadilia::transaction: Starting transaction for key '"key_4"' using staging file '/tmp/.tmpiQHddn/staging/.tmpfbpYoO' [INFO] [stdout] 2025-10-01T07:42:28.184547Z DEBUG cassadilia::transaction: Finishing transaction for key '"key_4"' [INFO] [stdout] 2025-10-01T07:42:28.184714Z DEBUG cassadilia::transaction: Starting transaction for key '"key2"' using staging file '/tmp/.tmpNL0c02/staging/.tmpBlK38M' [INFO] [stdout] 2025-10-01T07:42:28.184740Z DEBUG cassadilia::transaction: Finishing transaction for key '"key2"' [INFO] [stdout] 2025-10-01T07:42:28.186035Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpurljou/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.186103Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.186190Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpxqU8D2/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.186693Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.187130Z DEBUG cassadilia::transaction: Committing transaction blob_hash=4607a1b43e8c29a684fe473b6bc2302a7c114692f84e8bf4432850fe81abd16d key="key_4" [INFO] [stdout] 2025-10-01T07:42:28.187258Z DEBUG cassadilia::transaction: Committing transaction blob_hash=71df75541f3ee9d14a6eecd051a88e1cf16f3ff146901a0886406caffe31004d key="key2" [INFO] [stdout] 2025-10-01T07:42:28.187849Z DEBUG cassadilia::cas_manager: Moved blob 4607a1b43e8c29a684fe473b6bc2302a7c114692f84e8bf4432850fe81abd16d to CAS path '/tmp/.tmpiQHddn/cas/46/07/a1b43e8c29a684fe473b6bc2302a7c114692f84e8bf4432850fe81abd16d' [INFO] [stdout] 2025-10-01T07:42:28.187869Z DEBUG cassadilia::cas_manager: Moved blob 71df75541f3ee9d14a6eecd051a88e1cf16f3ff146901a0886406caffe31004d to CAS path '/tmp/.tmpNL0c02/cas/71/df/75541f3ee9d14a6eecd051a88e1cf16f3ff146901a0886406caffe31004d' [INFO] [stdout] 2025-10-01T07:42:28.188097Z DEBUG cassadilia::cas_manager: Successfully deleted unreferenced CAS file: /tmp/.tmp7yrarT/cas/c2/fe/34f9a080b55c101dc9d336438763ddbe757f11ef794b602458e26a21ffe5 [INFO] [stdout] 2025-10-01T07:42:28.188207Z DEBUG cassadilia::transaction: Starting transaction for key '"key11"' using staging file '/tmp/.tmp7yrarT/staging/.tmpQukpBp' [INFO] [stdout] 2025-10-01T07:42:28.188404Z DEBUG cassadilia::transaction: Finishing transaction for key '"key11"' [INFO] [stdout] 2025-10-01T07:42:28.189312Z DEBUG cassadilia::wal::storage: Sealing WAL segment 0 [INFO] [stdout] 2025-10-01T07:42:28.189355Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.189384Z DEBUG cassadilia: Removing 2 keys in range "key_1".."key_3" [INFO] [stdout] 2025-10-01T07:42:28.189402Z DEBUG cassadilia::wal::storage: Sealing WAL segment 0 [INFO] [stdout] 2025-10-01T07:42:28.189411Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.189545Z DEBUG cassadilia::transaction: Starting transaction for key '"key3"' using staging file '/tmp/.tmpNL0c02/staging/.tmphcIbOQ' [INFO] [stdout] 2025-10-01T07:42:28.189673Z DEBUG cassadilia::transaction: Finishing transaction for key '"key3"' [INFO] [stdout] 2025-10-01T07:42:28.190554Z DEBUG cassadilia::wal::storage: Opening WAL segment 1 for write at path: /tmp/.tmpurljou/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.190622Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 1. [INFO] [stdout] 2025-10-01T07:42:28.190623Z DEBUG cassadilia::cas_manager: Successfully deleted unreferenced CAS file: /tmp/.tmpiQHddn/cas/7d/b4/35c33dc4a7d581a9259698dbcae28a7b130bbff1123b36b2e2e987bf9676 [INFO] [stdout] 2025-10-01T07:42:28.190696Z DEBUG cassadilia::wal::storage: Opening WAL segment 1 for write at path: /tmp/.tmpxqU8D2/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.190694Z DEBUG cassadilia::cas_manager: Successfully deleted unreferenced CAS file: /tmp/.tmpiQHddn/cas/40/9d/96651e5e689bdbe491c2ed943ed4d6744be9ee1f01aa5a61b387f2491b0f [INFO] [stdout] 2025-10-01T07:42:28.190753Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 1. [INFO] [stdout] 2025-10-01T07:42:28.190834Z DEBUG cassadilia::transaction: Committing transaction blob_hash=86339d2a113359e47d6335318e526d87cccad2c647633cd9b2130a6d11cf2e58 key="key11" [INFO] [stdout] 2025-10-01T07:42:28.191119Z DEBUG cassadilia::cas_manager: Moved blob 86339d2a113359e47d6335318e526d87cccad2c647633cd9b2130a6d11cf2e58 to CAS path '/tmp/.tmp7yrarT/cas/86/33/9d2a113359e47d6335318e526d87cccad2c647633cd9b2130a6d11cf2e58' [INFO] [stdout] 2025-10-01T07:42:28.190778Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.191769Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpiQHddn/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:28.191865Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpiQHddn/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.191908Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.191921Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpiQHddn/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.192017Z DEBUG cassadilia::transaction: Committing transaction blob_hash=0393cf92b050830bcda68a04a88ec0865c0aee91acd19966dff18d8958a38520 key="key3" [INFO] [stdout] 2025-10-01T07:42:28.192223Z DEBUG cassadilia::cas_manager: Moved blob 0393cf92b050830bcda68a04a88ec0865c0aee91acd19966dff18d8958a38520 to CAS path '/tmp/.tmpNL0c02/cas/03/93/cf92b050830bcda68a04a88ec0865c0aee91acd19966dff18d8958a38520' [INFO] [stdout] 2025-10-01T07:42:28.192258Z DEBUG cassadilia::wal::storage: Sealing WAL segment 0 [INFO] [stdout] 2025-10-01T07:42:28.192268Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.192323Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmpiQHddn/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.192340Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=5 [INFO] [stdout] 2025-10-01T07:42:28.192351Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=5 highest_op_version=5 [INFO] [stdout] 2025-10-01T07:42:28.192371Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.192381Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:28.193599Z DEBUG cassadilia::cas_manager: Successfully deleted unreferenced CAS file: /tmp/.tmp7yrarT/cas/6b/78/6ef158450a90c98f422b22808925948a61428b6b72fda90216a7bb2fb413 [INFO] [stdout] 2025-10-01T07:42:28.193633Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 1 [INFO] [stdout] 2025-10-01T07:42:28.193707Z DEBUG cassadilia::transaction: Starting transaction for key '"key12"' using staging file '/tmp/.tmp7yrarT/staging/.tmp8y4j1r' [INFO] [stdout] 2025-10-01T07:42:28.193736Z DEBUG cassadilia::transaction: Finishing transaction for key '"key12"' [INFO] [stdout] 2025-10-01T07:42:28.193971Z DEBUG cassadilia::wal::storage: Opening WAL segment 1 for write at path: /tmp/.tmpNL0c02/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.194091Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 1. [INFO] [stdout] 2025-10-01T07:42:28.194604Z  INFO cassadilia::index::persistence: Persisted 2 entries to index '/tmp/.tmpiQHddn/index' [INFO] [stdout] 2025-10-01T07:42:28.194683Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] test wal::tests::append_op_fails_when_segment_rollover_cannot_create_file ... FAILED [INFO] [stdout] 2025-10-01T07:42:28.194795Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpiQHddn/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.194829Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:28.194840Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=5 [INFO] [stdout] 2025-10-01T07:42:28.194857Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=5 [INFO] [stdout] 2025-10-01T07:42:28.195016Z DEBUG cassadilia::wal::storage: Sealing WAL segment 1 [INFO] [stdout] 2025-10-01T07:42:28.195037Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 1 [INFO] [stdout] 2025-10-01T07:42:28.195601Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.00069426 total_blobs=2 [INFO] [stdout] 2025-10-01T07:42:28.195971Z DEBUG cassadilia::transaction: Committing transaction blob_hash=2bef6d996a68d9ace889cd81ab739fe0408a00dd06046ac1eca9de4a86b23ccd key="key12" [INFO] [stdout] 2025-10-01T07:42:28.196062Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=SegmentRollover [INFO] [stdout] 2025-10-01T07:42:28.196724Z DEBUG cassadilia::cas_manager: Moved blob 2bef6d996a68d9ace889cd81ab739fe0408a00dd06046ac1eca9de4a86b23ccd to CAS path '/tmp/.tmp7yrarT/cas/2b/ef/6d996a68d9ace889cd81ab739fe0408a00dd06046ac1eca9de4a86b23ccd' [INFO] [stdout] 2025-10-01T07:42:28.197008Z DEBUG cassadilia::wal::storage: Opening WAL segment 2 for write at path: /tmp/.tmpurljou/2_index.wal [INFO] [stdout] test tests::test_remove_range_persists ... ok [INFO] [stdout] 2025-10-01T07:42:28.198288Z  INFO cassadilia::index::persistence: Persisted 3 entries to index '/tmp/.tmpNL0c02/index' [INFO] [stdout] 2025-10-01T07:42:28.198311Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 1. [INFO] [stdout] 2025-10-01T07:42:28.198322Z DEBUG cassadilia::cas_manager: Successfully deleted unreferenced CAS file: /tmp/.tmp7yrarT/cas/bf/26/4d7f3851008e6ba34171bc9b1d1c46999b67c2ab7ae74afa0210146da579 [INFO] [stdout] 2025-10-01T07:42:28.198395Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpNL0c02/0_index.wal" }, SegmentInfo { id: 1, path: "/tmp/.tmpNL0c02/1_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.198415Z DEBUG cassadilia::wal::storage: Removing stale WAL segment 0: /tmp/.tmpNL0c02/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.198419Z DEBUG cassadilia::transaction: Starting transaction for key '"key13"' using staging file '/tmp/.tmp7yrarT/staging/.tmpHC6Iai' [INFO] [stdout] 2025-10-01T07:42:28.198440Z DEBUG cassadilia::transaction: Finishing transaction for key '"key13"' [INFO] [stdout] 2025-10-01T07:42:28.198473Z  INFO cassadilia::wal::storage: Successfully removed 1 stale WAL segment(s). [INFO] [stdout] 2025-10-01T07:42:28.198485Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=3 [INFO] [stdout] 2025-10-01T07:42:28.198495Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=3 [INFO] [stdout] 2025-10-01T07:42:28.198531Z  INFO cassadilia::index: Starting checkpoint operation. reason=Explicit [INFO] [stdout] 2025-10-01T07:42:28.198570Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=Explicit [INFO] [stdout] 2025-10-01T07:42:28.198683Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 2. [INFO] [stdout] 2025-10-01T07:42:28.200227Z  INFO cassadilia::index::persistence: Persisted 3 entries to index '/tmp/.tmpNL0c02/index' [INFO] [stdout] 2025-10-01T07:42:28.200302Z DEBUG cassadilia::transaction: Committing transaction blob_hash=840fef00251a910c284e237a8ac74883de1a1d7af5a206afca8213fa2aa5a8ba key="key13" [INFO] [stdout] 2025-10-01T07:42:28.200384Z DEBUG cassadilia::wal: Skipping checkpoint commit (idempotent/no-op). last_checkpointed_version=3 commit_version=3 [INFO] [stdout] 2025-10-01T07:42:28.200400Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=3 [INFO] [stdout] 2025-10-01T07:42:28.200442Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 1 [INFO] [stdout] 2025-10-01T07:42:28.200623Z DEBUG cassadilia::cas_manager: Moved blob 840fef00251a910c284e237a8ac74883de1a1d7af5a206afca8213fa2aa5a8ba to CAS path '/tmp/.tmp7yrarT/cas/84/0f/ef00251a910c284e237a8ac74883de1a1d7af5a206afca8213fa2aa5a8ba' [INFO] [stdout] 2025-10-01T07:42:28.200856Z  INFO cassadilia::index::persistence: Loaded 3 entries from index '/tmp/.tmpNL0c02/index' and populated ref counts. [INFO] [stdout] 2025-10-01T07:42:28.201051Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 1, path: "/tmp/.tmpNL0c02/1_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.201077Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 checkpoint=3 [INFO] [stdout] 2025-10-01T07:42:28.201089Z DEBUG cassadilia::wal::replay: Replaying segment segment=1 path=/tmp/.tmpNL0c02/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.201124Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 1 at '/tmp/.tmpNL0c02/1_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.201141Z  INFO cassadilia::wal::replay: Segment replay complete segment=1 entries=0 [INFO] [stdout] 2025-10-01T07:42:28.201151Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=0 highest_op_version=3 [INFO] [stdout] 2025-10-01T07:42:28.201385Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 2. [INFO] [stdout] 2025-10-01T07:42:28.201450Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpurljou/0_index.wal" }, SegmentInfo { id: 1, path: "/tmp/.tmpurljou/1_index.wal" }, SegmentInfo { id: 2, path: "/tmp/.tmpurljou/2_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.201472Z DEBUG cassadilia::wal::storage: Removing stale WAL segment 0: /tmp/.tmpurljou/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.202251Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.0010606 total_blobs=3 [INFO] [stdout] 2025-10-01T07:42:28.202599Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpeFzJ1x/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.202640Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.202652Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpeFzJ1x/0_index.wal [INFO] [stdout] test wal::tests::replay_fails_on_corrupted_op_entry ... ok [INFO] [stdout] 2025-10-01T07:42:28.203032Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpQO7iEJ/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.203088Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] test tests::test_wal_rollover_and_cleanup ... ok [INFO] [stdout] 2025-10-01T07:42:28.202348Z DEBUG cassadilia::wal::storage: Removing stale WAL segment 1: /tmp/.tmpurljou/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.203305Z  INFO cassadilia::wal::storage: Successfully removed 2 stale WAL segment(s). [INFO] [stdout] 2025-10-01T07:42:28.203321Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=5 [INFO] [stdout] 2025-10-01T07:42:28.203394Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 2, path: "/tmp/.tmpurljou/2_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.203408Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:28.203427Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:28.203449Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpzzWMMR/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.203469Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 2 [INFO] [stdout] 2025-10-01T07:42:28.202612Z DEBUG cassadilia::cas_manager: Successfully deleted unreferenced CAS file: /tmp/.tmp7yrarT/cas/02/2b/f00ea24955df1ffd5f4de80b676435a254dbab8fe0067b4f4794c6290938 [INFO] [stdout] test wal::tests::checkpoint_succeeds_and_prunes_old_segments ... ok [INFO] [stdout] 2025-10-01T07:42:28.204841Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpVb35TT/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.204982Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.205107Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] test wal::tests::replay_on_completely_empty_directory ... ok [INFO] [stdout] 2025-10-01T07:42:28.205321Z DEBUG cassadilia::transaction: Starting transaction for key '"key14"' using staging file '/tmp/.tmp7yrarT/staging/.tmpcsiNgg' [INFO] [stdout] 2025-10-01T07:42:28.205346Z DEBUG cassadilia::transaction: Finishing transaction for key '"key14"' [INFO] [stdout] 2025-10-01T07:42:28.205506Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpQO7iEJ/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.205534Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:28.205544Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmpQO7iEJ/0_index.wal [INFO] [stdout] test wal::tests::segment_id_calculation_is_correct ... ok [INFO] [stdout] test wal::tests::replay_fails_on_key_decode_error ... ok [INFO] [stdout] 2025-10-01T07:42:28.206742Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmp3s1pGs/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.206942Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.207868Z DEBUG cassadilia::wal::storage: Sealing WAL segment 0 [INFO] [stdout] 2025-10-01T07:42:28.207899Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] test wal::tests::segment_reader_next_fails_on_checksum_mismatch ... ok [INFO] [stdout] 2025-10-01T07:42:28.208637Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmpB3Qtww/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.208696Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:28.208710Z DEBUG cassadilia::transaction: Committing transaction blob_hash=11587230fea50d75fc5c9cb733010a5c12f75426287ed081e5efd2a31cbdef00 key="key14" [INFO] [stdout] 2025-10-01T07:42:28.209308Z DEBUG cassadilia::cas_manager: Moved blob 11587230fea50d75fc5c9cb733010a5c12f75426287ed081e5efd2a31cbdef00 to CAS path '/tmp/.tmp7yrarT/cas/11/58/7230fea50d75fc5c9cb733010a5c12f75426287ed081e5efd2a31cbdef00' [INFO] [stdout] 2025-10-01T07:42:28.209551Z DEBUG cassadilia::wal::storage: Opening WAL segment 1 for write at path: /tmp/.tmpVb35TT/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.210242Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 1. [INFO] [stdout] 2025-10-01T07:42:28.210824Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.211244Z DEBUG cassadilia::cas_manager: Successfully deleted unreferenced CAS file: /tmp/.tmp7yrarT/cas/ee/ef/0fe5509d1d8747688deb5189fbfdf1f99c790e1022b53084fe0c9e25a877 [INFO] [stdout] test wal::tests::wal_manager_drop_is_safe_with_active_writer ... ok [INFO] [stdout] 2025-10-01T07:42:28.211349Z DEBUG cassadilia::transaction: Starting transaction for key '"key15"' using staging file '/tmp/.tmp7yrarT/staging/.tmpf2oWCP' [INFO] [stdout] 2025-10-01T07:42:28.211372Z DEBUG cassadilia::transaction: Finishing transaction for key '"key15"' [INFO] [stdout] test wal::tests::wal_manager_new_fails_on_uncreatable_directory ... ok [INFO] [stdout] test wal::tests::zero_panics - should panic ... ok [INFO] [stdout] 2025-10-01T07:42:28.212646Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 1. [INFO] [stdout] 2025-10-01T07:42:28.212715Z DEBUG cassadilia::transaction: Committing transaction blob_hash=d8e1044299623a8c5a69ac85299b0c1bee5296749eb81b398165b1db141f192e key="key15" [INFO] [stdout] 2025-10-01T07:42:28.212825Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpVb35TT/0_index.wal" }, SegmentInfo { id: 1, path: "/tmp/.tmpVb35TT/1_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.212859Z DEBUG cassadilia::wal::storage: Removing stale WAL segment 0: /tmp/.tmpVb35TT/0_index.wal [INFO] [stdout] 2025-10-01T07:42:28.213215Z DEBUG cassadilia::cas_manager: Moved blob d8e1044299623a8c5a69ac85299b0c1bee5296749eb81b398165b1db141f192e to CAS path '/tmp/.tmp7yrarT/cas/d8/e1/044299623a8c5a69ac85299b0c1bee5296749eb81b398165b1db141f192e' [INFO] [stdout] 2025-10-01T07:42:28.213254Z DEBUG cassadilia::wal::storage: Sealing WAL segment 1 [INFO] [stdout] 2025-10-01T07:42:28.213322Z  INFO cassadilia::wal::storage: Successfully removed 1 stale WAL segment(s). [INFO] [stdout] 2025-10-01T07:42:28.213379Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=4 [INFO] [stdout] 2025-10-01T07:42:28.213418Z DEBUG cassadilia::wal::storage: Sealing WAL segment 1 [INFO] [stdout] 2025-10-01T07:42:28.213435Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 1 [INFO] [stdout] 2025-10-01T07:42:28.213494Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 1 [INFO] [stdout] 2025-10-01T07:42:28.213512Z DEBUG cassadilia::wal::storage: Sealing WAL segment 0 [INFO] [stdout] 2025-10-01T07:42:28.213528Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:28.214828Z DEBUG cassadilia::wal::storage: Opening WAL segment 2 for write at path: /tmp/.tmp7yrarT/2_index.wal [INFO] [stdout] 2025-10-01T07:42:28.214896Z DEBUG cassadilia::wal::storage: Reached end-of-segment marker (version 0) in segment 0. Cleanly ending replay for this segment. [INFO] [stdout] 2025-10-01T07:42:28.214906Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 2. [INFO] [stdout] 2025-10-01T07:42:28.214983Z DEBUG cassadilia::wal::storage: Opening WAL segment 2 for write at path: /tmp/.tmpVb35TT/2_index.wal [INFO] [stdout] 2025-10-01T07:42:28.215036Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 2. [INFO] [stdout] test wal::tests::segment_reader_stops_at_explicit_sentinel ... ok [INFO] [stdout] 2025-10-01T07:42:28.216260Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=SegmentRollover [INFO] [stdout] 2025-10-01T07:42:28.218627Z  INFO cassadilia::index::persistence: Persisted 16 entries to index '/tmp/.tmp7yrarT/index' [INFO] [stdout] 2025-10-01T07:42:28.218656Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 2. [INFO] [stdout] 2025-10-01T07:42:28.218798Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 1, path: "/tmp/.tmp7yrarT/1_index.wal" }, SegmentInfo { id: 2, path: "/tmp/.tmp7yrarT/2_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.218829Z DEBUG cassadilia::wal::storage: Removing stale WAL segment 1: /tmp/.tmp7yrarT/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.218978Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 1, path: "/tmp/.tmpVb35TT/1_index.wal" }, SegmentInfo { id: 2, path: "/tmp/.tmpVb35TT/2_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.219009Z  INFO cassadilia::wal::replay: Starting WAL replay segments=2 checkpoint=4 [INFO] [stdout] 2025-10-01T07:42:28.219062Z  INFO cassadilia::wal::storage: Successfully removed 1 stale WAL segment(s). [INFO] [stdout] 2025-10-01T07:42:28.219095Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=21 [INFO] [stdout] 2025-10-01T07:42:28.219146Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=21 [INFO] [stdout] 2025-10-01T07:42:28.219237Z DEBUG cassadilia::wal::replay: Replaying segment segment=1 path=/tmp/.tmpVb35TT/1_index.wal [INFO] [stdout] 2025-10-01T07:42:28.219308Z DEBUG cassadilia::wal::storage: Reached end-of-segment marker (version 0) in segment 1. Cleanly ending replay for this segment. [INFO] [stdout] 2025-10-01T07:42:28.219336Z  INFO cassadilia::wal::replay: Segment replay complete segment=1 entries=0 [INFO] [stdout] 2025-10-01T07:42:28.219408Z DEBUG cassadilia::transaction: Starting transaction for key '"key16"' using staging file '/tmp/.tmp7yrarT/staging/.tmp513hiE' [INFO] [stdout] 2025-10-01T07:42:28.219470Z DEBUG cassadilia::wal::replay: Replaying segment segment=2 path=/tmp/.tmpVb35TT/2_index.wal [INFO] [stdout] 2025-10-01T07:42:28.219448Z DEBUG cassadilia::transaction: Finishing transaction for key '"key16"' [INFO] [stdout] 2025-10-01T07:42:28.219529Z DEBUG cassadilia::wal::tests: Replaying op #1 [INFO] [stdout] 2025-10-01T07:42:28.219663Z DEBUG cassadilia::wal::tests: Replaying op #2 [INFO] [stdout] 2025-10-01T07:42:28.219706Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 2 at '/tmp/.tmpVb35TT/2_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.219751Z  INFO cassadilia::wal::replay: Segment replay complete segment=2 entries=2 [INFO] [stdout] 2025-10-01T07:42:28.219769Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=2 highest_op_version=6 [INFO] [stdout] 2025-10-01T07:42:28.219790Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 3 (for next op version 7) exists at path: /tmp/.tmpVb35TT/3_index.wal [INFO] [stdout] 2025-10-01T07:42:28.221180Z DEBUG cassadilia::transaction: Committing transaction blob_hash=591c6c3a9299a2b4d2165956457aff50d6aa376007235a26675d223563d4863d key="key16" [INFO] [stdout] 2025-10-01T07:42:28.221486Z DEBUG cassadilia::cas_manager: Moved blob 591c6c3a9299a2b4d2165956457aff50d6aa376007235a26675d223563d4863d to CAS path '/tmp/.tmp7yrarT/cas/59/1c/6c3a9299a2b4d2165956457aff50d6aa376007235a26675d223563d4863d' [INFO] [stdout] 2025-10-01T07:42:28.221649Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 2 [INFO] [stdout] test wal::tests::replay_should_ignore_segments_before_checkpoint ... ok [INFO] [stdout] 2025-10-01T07:42:28.223208Z DEBUG cassadilia::transaction: Starting transaction for key '"key17"' using staging file '/tmp/.tmp7yrarT/staging/.tmpdzaGKO' [INFO] [stdout] 2025-10-01T07:42:28.223242Z DEBUG cassadilia::transaction: Finishing transaction for key '"key17"' [INFO] [stdout] 2025-10-01T07:42:28.224487Z DEBUG cassadilia::transaction: Committing transaction blob_hash=f1023e972ed87f319c2688019de45c89941bb75fcccfc0f1b73351a49da3f4f1 key="key17" [INFO] [stdout] 2025-10-01T07:42:28.224707Z DEBUG cassadilia::cas_manager: Moved blob f1023e972ed87f319c2688019de45c89941bb75fcccfc0f1b73351a49da3f4f1 to CAS path '/tmp/.tmp7yrarT/cas/f1/02/3e972ed87f319c2688019de45c89941bb75fcccfc0f1b73351a49da3f4f1' [INFO] [stdout] 2025-10-01T07:42:28.226163Z DEBUG cassadilia::transaction: Starting transaction for key '"key18"' using staging file '/tmp/.tmp7yrarT/staging/.tmpPU00kj' [INFO] [stdout] 2025-10-01T07:42:28.226196Z DEBUG cassadilia::transaction: Finishing transaction for key '"key18"' [INFO] [stdout] 2025-10-01T07:42:28.235944Z DEBUG cassadilia::transaction: Committing transaction blob_hash=7a6268a8c31792798d33b5670af2610093f0461ddc2c373416f17e666b6f8d90 key="key18" [INFO] [stdout] 2025-10-01T07:42:28.236180Z DEBUG cassadilia::cas_manager: Moved blob 7a6268a8c31792798d33b5670af2610093f0461ddc2c373416f17e666b6f8d90 to CAS path '/tmp/.tmp7yrarT/cas/7a/62/68a8c31792798d33b5670af2610093f0461ddc2c373416f17e666b6f8d90' [INFO] [stdout] 2025-10-01T07:42:28.238623Z DEBUG cassadilia::transaction: Starting transaction for key '"key19"' using staging file '/tmp/.tmp7yrarT/staging/.tmpwHfqr3' [INFO] [stdout] 2025-10-01T07:42:28.238658Z DEBUG cassadilia::transaction: Finishing transaction for key '"key19"' [INFO] [stdout] 2025-10-01T07:42:28.240021Z DEBUG cassadilia::transaction: Committing transaction blob_hash=f34e40d8cdd537244aa3858a6dbf867ae1e8c9258b2d846f7c9e9385f76dd14c key="key19" [INFO] [stdout] 2025-10-01T07:42:28.240263Z DEBUG cassadilia::cas_manager: Moved blob f34e40d8cdd537244aa3858a6dbf867ae1e8c9258b2d846f7c9e9385f76dd14c to CAS path '/tmp/.tmp7yrarT/cas/f3/4e/40d8cdd537244aa3858a6dbf867ae1e8c9258b2d846f7c9e9385f76dd14c' [INFO] [stdout] 2025-10-01T07:42:28.241948Z  INFO cassadilia::index: Starting checkpoint operation. reason=Explicit [INFO] [stdout] 2025-10-01T07:42:28.242015Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=Explicit [INFO] [stdout] 2025-10-01T07:42:28.246001Z  INFO cassadilia::index::persistence: Persisted 20 entries to index '/tmp/.tmp7yrarT/index' [INFO] [stdout] 2025-10-01T07:42:28.246074Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 2. [INFO] [stdout] 2025-10-01T07:42:28.246205Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 2, path: "/tmp/.tmp7yrarT/2_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.246259Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 2). [INFO] [stdout] 2025-10-01T07:42:28.246286Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=25 [INFO] [stdout] 2025-10-01T07:42:28.246314Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=25 [INFO] [stdout] 2025-10-01T07:42:28.246397Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 2 [INFO] [stdout] 2025-10-01T07:42:28.246744Z  INFO cassadilia::index::persistence: Loaded 20 entries from index '/tmp/.tmp7yrarT/index' and populated ref counts. [INFO] [stdout] 2025-10-01T07:42:28.246889Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 2, path: "/tmp/.tmp7yrarT/2_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.246943Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 checkpoint=25 [INFO] [stdout] 2025-10-01T07:42:28.249910Z DEBUG cassadilia::wal::replay: Replaying segment segment=2 path=/tmp/.tmp7yrarT/2_index.wal [INFO] [stdout] 2025-10-01T07:42:28.250032Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 2 at '/tmp/.tmp7yrarT/2_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.250124Z  INFO cassadilia::wal::replay: Segment replay complete segment=2 entries=0 [INFO] [stdout] 2025-10-01T07:42:28.250162Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=0 highest_op_version=25 [INFO] [stdout] 2025-10-01T07:42:28.251839Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.0015637 total_blobs=20 [INFO] [stdout] 2025-10-01T07:42:28.254735Z  INFO cassadilia::index::persistence: Loaded 20 entries from index '/tmp/.tmp7yrarT/index' and populated ref counts. [INFO] [stdout] 2025-10-01T07:42:28.256989Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 2, path: "/tmp/.tmp7yrarT/2_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:28.257067Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 checkpoint=25 [INFO] [stdout] 2025-10-01T07:42:28.257102Z DEBUG cassadilia::wal::replay: Replaying segment segment=2 path=/tmp/.tmp7yrarT/2_index.wal [INFO] [stdout] 2025-10-01T07:42:28.257199Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 2 at '/tmp/.tmp7yrarT/2_index.wal'. [INFO] [stdout] 2025-10-01T07:42:28.257261Z  INFO cassadilia::wal::replay: Segment replay complete segment=2 entries=0 [INFO] [stdout] 2025-10-01T07:42:28.257292Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=0 highest_op_version=25 [INFO] [stdout] 2025-10-01T07:42:28.258674Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=0.0012982 total_blobs=20 [INFO] [stdout] test tests::checkpoint::test_checkpoint_persists_overwrites_correctly ... ok [INFO] [stdout] test types::tests::test_blob_hash_from_relative_path_error_cases ... ok [INFO] [stdout] 2025-10-01T07:42:28.271395Z  WARN cassadilia::wal::storage: Found WAL-like file with non-numeric segment ID: /tmp/.tmpuMND3E/abc_index.wal [INFO] [stdout] 2025-10-01T07:42:28.271467Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmpuMND3E/0_index.wal" }, SegmentInfo { id: 2, path: "/tmp/.tmpuMND3E/2_index.wal" }] [INFO] [stdout] test wal::tests::discover_segments_ignores_malformed_filenames ... ok [INFO] [stdout] 2025-10-01T07:42:39.609176Z  INFO cassadilia: Pre-created 65,536 CAS directories [INFO] [stdout] 2025-10-01T07:42:39.693390Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmpa0t8sx/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:39.693575Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:39.693635Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:39.693689Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmpa0t8sx/0_index.wal [INFO] [stdout] 2025-10-01T07:42:40.115932Z  INFO cassadilia: Pre-created 65,536 CAS directories [INFO] [stdout] 2025-10-01T07:42:40.135251Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmp59aqYf/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:40.135626Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [] [INFO] [stdout] 2025-10-01T07:42:40.135673Z  INFO cassadilia::wal::replay: Starting WAL replay segments=0 [INFO] [stdout] 2025-10-01T07:42:40.135725Z DEBUG cassadilia::wal::storage: Ensuring WAL segment file 0 (for next op version 1) exists at path: /tmp/.tmp59aqYf/0_index.wal [INFO] [stdout] 2025-10-01T07:42:43.489715Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=3.794928542 total_blobs=0 [INFO] [stdout] 2025-10-01T07:42:44.466454Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=4.326900511 total_blobs=0 [INFO] [stdout] test settings::tests::test_settings_file ... ok [INFO] [stdout] 2025-10-01T07:42:53.357379Z DEBUG cassadilia::transaction: Starting transaction for key '"test_key"' using staging file '/tmp/.tmp59aqYf/staging/.tmp5F44Vj' [INFO] [stdout] 2025-10-01T07:42:53.357512Z DEBUG cassadilia::transaction: Finishing transaction for key '"test_key"' [INFO] [stdout] 2025-10-01T07:42:53.440479Z DEBUG cassadilia::transaction: Committing transaction blob_hash=6a953581d60dbebc9749b56d2383277fb02b58d260b4ccf6f119108fa0f1d4ef key="test_key" [INFO] [stdout] 2025-10-01T07:42:53.440671Z DEBUG cassadilia::cas_manager: Moved blob 6a953581d60dbebc9749b56d2383277fb02b58d260b4ccf6f119108fa0f1d4ef to CAS path '/tmp/.tmp59aqYf/cas/6a/95/3581d60dbebc9749b56d2383277fb02b58d260b4ccf6f119108fa0f1d4ef' [INFO] [stdout] 2025-10-01T07:42:53.440744Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmp59aqYf/0_index.wal [INFO] [stdout] 2025-10-01T07:42:53.440794Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:53.446074Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] 2025-10-01T07:42:53.447223Z  INFO cassadilia::index::persistence: Index file '/tmp/.tmp59aqYf/index' not found, starting fresh. State remains new. [INFO] [stdout] 2025-10-01T07:42:53.447368Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmp59aqYf/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:53.447424Z  INFO cassadilia::wal::replay: Starting WAL replay segments=1 [INFO] [stdout] 2025-10-01T07:42:53.447459Z DEBUG cassadilia::wal::replay: Replaying segment segment=0 path=/tmp/.tmp59aqYf/0_index.wal [INFO] [stdout] 2025-10-01T07:42:53.447540Z DEBUG cassadilia::wal::storage: Reached end of WAL segment 0 at '/tmp/.tmp59aqYf/0_index.wal'. [INFO] [stdout] 2025-10-01T07:42:53.447586Z  INFO cassadilia::wal::replay: Segment replay complete segment=0 entries=1 [INFO] [stdout] 2025-10-01T07:42:53.447620Z  INFO cassadilia::wal::replay: WAL replay complete total_entries=1 highest_op_version=1 [INFO] [stdout] 2025-10-01T07:42:53.447663Z  INFO cassadilia::index: Starting checkpoint operation. reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:53.447697Z  INFO cassadilia::index: Starting checkpoint operation. checkpoint_reason=AfterReplay [INFO] [stdout] 2025-10-01T07:42:53.449053Z  INFO cassadilia::index::persistence: Persisted 1 entries to index '/tmp/.tmp59aqYf/index' [INFO] [stdout] 2025-10-01T07:42:53.449075Z  INFO cassadilia::wal::storage: Removing stale WAL segments older than segment ID 0. [INFO] [stdout] 2025-10-01T07:42:53.449167Z DEBUG cassadilia::wal::storage: Discovered WAL segments: [SegmentInfo { id: 0, path: "/tmp/.tmp59aqYf/0_index.wal" }] [INFO] [stdout] 2025-10-01T07:42:53.449185Z DEBUG cassadilia::wal::storage: No stale WAL segments found to remove (older than 0). [INFO] [stdout] 2025-10-01T07:42:53.449195Z  INFO cassadilia::wal: Checkpoint committed checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:53.449204Z  INFO cassadilia::index: Checkpoint completed successfully. checkpoint_version=1 [INFO] [stdout] 2025-10-01T07:42:55.262994Z  INFO cassadilia: Orphan scan complete orphans=0 invalid_files=0 missing_blobs=0 corrupted_blobs=0 staging_files=0 scan_time=1.813738908 total_blobs=1 [INFO] [stdout] 2025-10-01T07:42:55.263295Z DEBUG cassadilia::transaction: Starting transaction for key '"test_key2"' using staging file '/tmp/.tmp59aqYf/staging/.tmpEH395j' [INFO] [stdout] 2025-10-01T07:42:55.263363Z DEBUG cassadilia::transaction: Finishing transaction for key '"test_key2"' [INFO] [stdout] 2025-10-01T07:42:55.266942Z DEBUG cassadilia::transaction: Committing transaction blob_hash=be8c48be81ad6bb45df2eae3f8261e6232ee8cebe8f8f8aa3c2fd7bf61ba5fc6 key="test_key2" [INFO] [stdout] 2025-10-01T07:42:55.267102Z DEBUG cassadilia::cas_manager: Moved blob be8c48be81ad6bb45df2eae3f8261e6232ee8cebe8f8f8aa3c2fd7bf61ba5fc6 to CAS path '/tmp/.tmp59aqYf/cas/be/8c/48be81ad6bb45df2eae3f8261e6232ee8cebe8f8f8aa3c2fd7bf61ba5fc6' [INFO] [stdout] 2025-10-01T07:42:55.267215Z DEBUG cassadilia::wal::storage: Opening WAL segment 0 for write at path: /tmp/.tmp59aqYf/0_index.wal [INFO] [stdout] 2025-10-01T07:42:55.267264Z  INFO cassadilia::wal::storage: Successfully opened writer for WAL segment 0. [INFO] [stdout] 2025-10-01T07:42:55.268372Z DEBUG cassadilia::wal::storage: Closing WAL segment writer for segment 0 [INFO] [stdout] test settings::tests::test_pre_create_cas_dirs ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- tests::test_io_error_on_staging_file_creation stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'tests::test_io_error_on_staging_file_creation' panicked at src/tests/mod.rs:324:5: [INFO] [stdout] assertion failed: result.is_err() [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5fa859cea772 - std::backtrace_rs::backtrace::libunwind::trace::h2d45396358f41939 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x5fa859cea772 - std::backtrace_rs::backtrace::trace_unsynchronized::hffcefc0b67f1d6e2 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x5fa859cea772 - std::sys::backtrace::_print_fmt::hd72f71d23b436b92 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x5fa859cea772 - ::fmt::hdcfcb6d4c8489523 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x5fa859d13c53 - core::fmt::rt::Argument::fmt::h2c56b3114963061a [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x5fa859d13c53 - core::fmt::write::h8a494366950f23bb [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x5fa859ce6dd3 - std::io::default_write_fmt::h7b8824096454f323 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x5fa859ce6dd3 - std::io::Write::write_fmt::h4e71294925c334d0 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x5fa859cea5c2 - std::sys::backtrace::BacktraceLock::print::hb2a626a81e06b2dc [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x5fa859cec3b9 - std::panicking::default_hook::{{closure}}::h4f78485264f12d10 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:300:27 [INFO] [stdout] 10: 0x5fa859cec1ee - std::panicking::default_hook::h2c66fc99e962531d [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:324:9 [INFO] [stdout] 11: 0x5fa859c6c0f4 - as core::ops::function::Fn>::call::hb6bd65493727d71a [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 12: 0x5fa859c6c0f4 - test::test_main_with_exit_callback::{{closure}}::h60217ec76a0ece4a [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x5fa859cece8e - as core::ops::function::Fn>::call::h318e4efb8c1a5689 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 14: 0x5fa859cece8e - std::panicking::rust_panic_with_hook::h33ac55f64bbd807d [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:841:13 [INFO] [stdout] 15: 0x5fa859cecb26 - std::panicking::begin_panic_handler::{{closure}}::h30e7cb89678a57fe [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:699:13 [INFO] [stdout] 16: 0x5fa859ceac79 - std::sys::backtrace::__rust_end_short_backtrace::hed60f27456c16ced [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x5fa859cec7ed - __rustc[de2ca18b4c54d5b8]::rust_begin_unwind [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:697:5 [INFO] [stdout] 18: 0x5fa859d11990 - core::panicking::panic_fmt::h62f63d096dd276af [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x5fa859d11a1c - core::panicking::panic::h89a5f2df32b0508a [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/panicking.rs:145:5 [INFO] [stdout] 20: 0x5fa859a06bdd - cassadilia::tests::test_io_error_on_staging_file_creation::he50cd48a2e31ef3a [INFO] [stdout] at /opt/rustwide/workdir/src/tests/mod.rs:324:5 [INFO] [stdout] 21: 0x5fa859a067a7 - cassadilia::tests::test_io_error_on_staging_file_creation::{{closure}}::h07a879e9b0ce3870 [INFO] [stdout] at /opt/rustwide/workdir/src/tests/mod.rs:314:48 [INFO] [stdout] 22: 0x5fa8598f67f6 - core::ops::function::FnOnce::call_once::hbbd42522fcf94ebe [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 23: 0x5fa859c7195b - core::ops::function::FnOnce::call_once::h1f9474f1347fff52 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 24: 0x5fa859c7195b - test::__rust_begin_short_backtrace::ha52ab26e77157f03 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/test/src/lib.rs:648:18 [INFO] [stdout] 25: 0x5fa859c70a95 - test::run_test_in_process::{{closure}}::h229c1a11a50b261f [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/test/src/lib.rs:671:74 [INFO] [stdout] 26: 0x5fa859c70a95 - as core::ops::function::FnOnce<()>>::call_once::h78821afbf97b39a9 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 27: 0x5fa859c70a95 - std::panicking::catch_unwind::do_call::h53abf3ed5bd32ba3 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:589:40 [INFO] [stdout] 28: 0x5fa859c70a95 - std::panicking::catch_unwind::h6deb5fe1e91873c9 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:552:19 [INFO] [stdout] 29: 0x5fa859c70a95 - std::panic::catch_unwind::hea0829b6b565654b [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panic.rs:359:14 [INFO] [stdout] 30: 0x5fa859c70a95 - test::run_test_in_process::hdc44dfecea3db21b [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/test/src/lib.rs:671:27 [INFO] [stdout] 31: 0x5fa859c70a95 - test::run_test::{{closure}}::h0364ba59bf23f652 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/test/src/lib.rs:592:43 [INFO] [stdout] 32: 0x5fa859c34694 - test::run_test::{{closure}}::h32730b304eec05da [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/test/src/lib.rs:622:41 [INFO] [stdout] 33: 0x5fa859c34694 - std::sys::backtrace::__rust_begin_short_backtrace::h177cb952fc54ad2c [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 34: 0x5fa859c3806a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h119d903520007597 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 35: 0x5fa859c3806a - as core::ops::function::FnOnce<()>>::call_once::hab88401d606702a6 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 36: 0x5fa859c3806a - std::panicking::catch_unwind::do_call::h163fe12cca9901c2 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:589:40 [INFO] [stdout] 37: 0x5fa859c3806a - std::panicking::catch_unwind::he81557d0e17cc1bc [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:552:19 [INFO] [stdout] 38: 0x5fa859c3806a - std::panic::catch_unwind::hc2b8e79c20593955 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panic.rs:359:14 [INFO] [stdout] 39: 0x5fa859c3806a - std::thread::Builder::spawn_unchecked_::{{closure}}::hfa6b4978e07815cc [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 40: 0x5fa859c3806a - core::ops::function::FnOnce::call_once{{vtable.shim}}::hddcd7484a13fd793 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 41: 0x5fa859cf01bf - as core::ops::function::FnOnce>::call_once::h5924238c754de3b8 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/alloc/src/boxed.rs:1971:9 [INFO] [stdout] 42: 0x5fa859cf01bf - std::sys::pal::unix::thread::Thread::new::thread_start::hb6e99e73da4d28f8 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/sys/pal/unix/thread.rs:107:17 [INFO] [stdout] 43: 0x7621cc8a2aa4 - [INFO] [stdout] 44: 0x7621cc92fa34 - clone [INFO] [stdout] 45: 0x0 - [INFO] [stdout] [INFO] [stdout] ---- wal::tests::append_op_fails_when_segment_rollover_cannot_create_file stdout ---- [INFO] [stdout] [INFO] [stdout] thread 'wal::tests::append_op_fails_when_segment_rollover_cannot_create_file' panicked at src/wal/tests.rs:106:9: [INFO] [stdout] want OpenSegmentWrite, got Ok(WalAppendInfo { version: 3, op_hash: BlobHash("a97aa27894ace3aa4ce112ee883097ea9f4d158b7c55757e1215a5f19dd7de26") }) [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x5fa859cea772 - std::backtrace_rs::backtrace::libunwind::trace::h2d45396358f41939 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x5fa859cea772 - std::backtrace_rs::backtrace::trace_unsynchronized::hffcefc0b67f1d6e2 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x5fa859cea772 - std::sys::backtrace::_print_fmt::hd72f71d23b436b92 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x5fa859cea772 - ::fmt::hdcfcb6d4c8489523 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x5fa859d13c53 - core::fmt::rt::Argument::fmt::h2c56b3114963061a [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x5fa859d13c53 - core::fmt::write::h8a494366950f23bb [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/fmt/mod.rs:1468:25 [INFO] [stdout] 6: 0x5fa859ce6dd3 - std::io::default_write_fmt::h7b8824096454f323 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x5fa859ce6dd3 - std::io::Write::write_fmt::h4e71294925c334d0 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x5fa859cea5c2 - std::sys::backtrace::BacktraceLock::print::hb2a626a81e06b2dc [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x5fa859cec3b9 - std::panicking::default_hook::{{closure}}::h4f78485264f12d10 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:300:27 [INFO] [stdout] 10: 0x5fa859cec1ee - std::panicking::default_hook::h2c66fc99e962531d [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:324:9 [INFO] [stdout] 11: 0x5fa859c6c0f4 - as core::ops::function::Fn>::call::hb6bd65493727d71a [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 12: 0x5fa859c6c0f4 - test::test_main_with_exit_callback::{{closure}}::h60217ec76a0ece4a [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x5fa859cece8e - as core::ops::function::Fn>::call::h318e4efb8c1a5689 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 14: 0x5fa859cece8e - std::panicking::rust_panic_with_hook::h33ac55f64bbd807d [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:841:13 [INFO] [stdout] 15: 0x5fa859cecb5a - std::panicking::begin_panic_handler::{{closure}}::h30e7cb89678a57fe [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:706:13 [INFO] [stdout] 16: 0x5fa859ceac79 - std::sys::backtrace::__rust_end_short_backtrace::hed60f27456c16ced [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 17: 0x5fa859cec7ed - __rustc[de2ca18b4c54d5b8]::rust_begin_unwind [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:697:5 [INFO] [stdout] 18: 0x5fa859d11990 - core::panicking::panic_fmt::h62f63d096dd276af [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x5fa8599eb8d0 - cassadilia::wal::tests::append_op_fails_when_segment_rollover_cannot_create_file::h6f602a69d86aebf3 [INFO] [stdout] at /opt/rustwide/workdir/src/wal/tests.rs:106:9 [INFO] [stdout] 20: 0x5fa8599eb397 - cassadilia::wal::tests::append_op_fails_when_segment_rollover_cannot_create_file::{{closure}}::h578fffab3f8d0e97 [INFO] [stdout] at /opt/rustwide/workdir/src/wal/tests.rs:87:62 [INFO] [stdout] 21: 0x5fa8598f6986 - core::ops::function::FnOnce::call_once::hc65af1c733e1d72f [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 22: 0x5fa859c7195b - core::ops::function::FnOnce::call_once::h1f9474f1347fff52 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 23: 0x5fa859c7195b - test::__rust_begin_short_backtrace::ha52ab26e77157f03 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/test/src/lib.rs:648:18 [INFO] [stdout] 24: 0x5fa859c70a95 - test::run_test_in_process::{{closure}}::h229c1a11a50b261f [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/test/src/lib.rs:671:74 [INFO] [stdout] 25: 0x5fa859c70a95 - as core::ops::function::FnOnce<()>>::call_once::h78821afbf97b39a9 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 26: 0x5fa859c70a95 - std::panicking::catch_unwind::do_call::h53abf3ed5bd32ba3 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:589:40 [INFO] [stdout] 27: 0x5fa859c70a95 - std::panicking::catch_unwind::h6deb5fe1e91873c9 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:552:19 [INFO] [stdout] 28: 0x5fa859c70a95 - std::panic::catch_unwind::hea0829b6b565654b [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panic.rs:359:14 [INFO] [stdout] 29: 0x5fa859c70a95 - test::run_test_in_process::hdc44dfecea3db21b [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/test/src/lib.rs:671:27 [INFO] [stdout] 30: 0x5fa859c70a95 - test::run_test::{{closure}}::h0364ba59bf23f652 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/test/src/lib.rs:592:43 [INFO] [stdout] 31: 0x5fa859c34694 - test::run_test::{{closure}}::h32730b304eec05da [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/test/src/lib.rs:622:41 [INFO] [stdout] 32: 0x5fa859c34694 - std::sys::backtrace::__rust_begin_short_backtrace::h177cb952fc54ad2c [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 33: 0x5fa859c3806a - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::h119d903520007597 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 34: 0x5fa859c3806a - as core::ops::function::FnOnce<()>>::call_once::hab88401d606702a6 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 35: 0x5fa859c3806a - std::panicking::catch_unwind::do_call::h163fe12cca9901c2 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:589:40 [INFO] [stdout] 36: 0x5fa859c3806a - std::panicking::catch_unwind::he81557d0e17cc1bc [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panicking.rs:552:19 [INFO] [stdout] 37: 0x5fa859c3806a - std::panic::catch_unwind::hc2b8e79c20593955 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/panic.rs:359:14 [INFO] [stdout] 38: 0x5fa859c3806a - std::thread::Builder::spawn_unchecked_::{{closure}}::hfa6b4978e07815cc [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 39: 0x5fa859c3806a - core::ops::function::FnOnce::call_once{{vtable.shim}}::hddcd7484a13fd793 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 40: 0x5fa859cf01bf - as core::ops::function::FnOnce>::call_once::h5924238c754de3b8 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/alloc/src/boxed.rs:1971:9 [INFO] [stdout] 41: 0x5fa859cf01bf - std::sys::pal::unix::thread::Thread::new::thread_start::hb6e99e73da4d28f8 [INFO] [stdout] at /rustc/1159e78c4747b02ef996e55082b704c09b970588/library/std/src/sys/pal/unix/thread.rs:107:17 [INFO] [stdout] 42: 0x7621cc8a2aa4 - [INFO] [stdout] 43: 0x7621cc92fa34 - clone [INFO] [stdout] 44: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] tests::test_io_error_on_staging_file_creation [INFO] [stdout] wal::tests::append_op_fails_when_segment_rollover_cannot_create_file [INFO] [stdout] [INFO] [stdout] test result: FAILED. 66 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 32.53s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "e6ecf4e89c295e5253ce411bfa14547c3b5e571c574080bc012800159e479734", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "e6ecf4e89c295e5253ce411bfa14547c3b5e571c574080bc012800159e479734", kill_on_drop: false }` [INFO] [stdout] e6ecf4e89c295e5253ce411bfa14547c3b5e571c574080bc012800159e479734