[INFO] cloning repository https://github.com/BoxHeadDev/kvs [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/BoxHeadDev/kvs" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBoxHeadDev%2Fkvs", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBoxHeadDev%2Fkvs'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] a2afae612019cf7b5bb6aef331830582f2e8f76d [INFO] testing BoxHeadDev/kvs against master#ad85bc524b1ad696e42061ad8338d382dffbdbe5 for pr-146237 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2FBoxHeadDev%2Fkvs" "/workspace/builds/worker-7-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc1/source'... [INFO] [stderr] done. [INFO] started tweaking git repo https://github.com/BoxHeadDev/kvs [INFO] finished tweaking git repo https://github.com/BoxHeadDev/kvs [INFO] tweaked toml for git repo https://github.com/BoxHeadDev/kvs written to /workspace/builds/worker-7-tc1/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/BoxHeadDev/kvs on toolchain ad85bc524b1ad696e42061ad8338d382dffbdbe5 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate git repo https://github.com/BoxHeadDev/kvs 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" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded escargot v0.4.0 [INFO] [stderr] Downloaded assert_cmd v0.11.1 [INFO] [stderr] Downloaded predicates v1.0.8 [INFO] [stderr] Downloaded crossbeam-utils v0.6.6 [INFO] [stderr] Downloaded panic-control v0.1.4 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 1709ba4747289f2799aa689e15ee3435294a6c2aed02904643b9c4e7eb9fc31b [INFO] running `Command { std: "docker" "start" "-a" "1709ba4747289f2799aa689e15ee3435294a6c2aed02904643b9c4e7eb9fc31b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "1709ba4747289f2799aa689e15ee3435294a6c2aed02904643b9c4e7eb9fc31b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1709ba4747289f2799aa689e15ee3435294a6c2aed02904643b9c4e7eb9fc31b", kill_on_drop: false }` [INFO] [stdout] 1709ba4747289f2799aa689e15ee3435294a6c2aed02904643b9c4e7eb9fc31b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] f1517f6fdae2c73004ea2af84dd9ffdce28d32e8681b52bd0ab2238c7453d18a [INFO] running `Command { std: "docker" "start" "-a" "f1517f6fdae2c73004ea2af84dd9ffdce28d32e8681b52bd0ab2238c7453d18a", kill_on_drop: false }` [INFO] [stderr] Compiling syn v1.0.109 [INFO] [stderr] Compiling parking_lot_core v0.8.6 [INFO] [stderr] Compiling object v0.36.7 [INFO] [stderr] Compiling instant v0.1.13 [INFO] [stderr] Compiling failure_derive v0.1.8 [INFO] [stderr] Compiling syn v2.0.104 [INFO] [stderr] Compiling crossbeam-epoch v0.9.18 [INFO] [stderr] Compiling miniz_oxide v0.8.9 [INFO] [stderr] Compiling rustc-demangle v0.1.25 [INFO] [stderr] Compiling clap_builder v4.5.41 [INFO] [stderr] Compiling parking_lot_core v0.9.11 [INFO] [stderr] Compiling fs2 v0.4.3 [INFO] [stderr] Compiling hashbrown v0.14.5 [INFO] [stderr] Compiling either v1.15.0 [INFO] [stderr] Compiling tracing-log v0.1.4 [INFO] [stderr] Compiling crossbeam-deque v0.8.6 [INFO] [stderr] Compiling parking_lot v0.11.2 [INFO] [stderr] Compiling rayon-core v1.12.1 [INFO] [stderr] Compiling sled v0.34.7 [INFO] [stderr] Compiling rayon v1.10.0 [INFO] [stderr] Compiling dashmap v6.1.0 [INFO] [stderr] Compiling synstructure v0.12.6 [INFO] [stderr] Compiling backtrace v0.3.75 [INFO] [stderr] Compiling serde_derive v1.0.219 [INFO] [stderr] Compiling tracing-attributes v0.1.30 [INFO] [stderr] Compiling clap_derive v4.5.41 [INFO] [stderr] Compiling failure v0.1.8 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling clap v4.5.41 [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling serde_spanned v0.6.9 [INFO] [stderr] Compiling toml_datetime v0.6.11 [INFO] [stderr] Compiling serde_json v1.0.140 [INFO] [stderr] Compiling tracing-serde v0.2.0 [INFO] [stderr] Compiling toml_edit v0.22.27 [INFO] [stderr] Compiling tracing-subscriber v0.3.19 [INFO] [stderr] Compiling toml v0.8.23 [INFO] [stderr] Compiling kvs v0.1.0 (/opt/rustwide/workdir) [INFO] [stdout] warning: unused import: `Tree` [INFO] [stdout] --> src/storage/kvsled.rs:3:16 [INFO] [stdout] | [INFO] [stdout] 3 | use sled::{Db, Tree}; [INFO] [stdout] | ^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused imports: `error` and `info` [INFO] [stdout] --> src/storage/kvstore/segment.rs:10:15 [INFO] [stdout] | [INFO] [stdout] 10 | use tracing::{error, info}; [INFO] [stdout] | ^^^^^ ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused imports: `error` and `info` [INFO] [stdout] --> src/storage/kvstore/store.rs:11:15 [INFO] [stdout] | [INFO] [stdout] 11 | use tracing::{error, info}; [INFO] [stdout] | ^^^^^ ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused variable: `threads` [INFO] [stdout] --> src/threadpool/naive.rs:7:12 [INFO] [stdout] | [INFO] [stdout] 7 | fn new(threads: u32) -> Result { [INFO] [stdout] | ^^^^^^^ help: if this is intentional, prefix it with an underscore: `_threads` [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: enum `SegmentStatus` is never used [INFO] [stdout] --> src/storage/kvstore/segment.rs:221:10 [INFO] [stdout] | [INFO] [stdout] 221 | pub enum SegmentStatus { [INFO] [stdout] | ^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: struct `Segment` is never constructed [INFO] [stdout] --> src/storage/kvstore/segment.rs:229:12 [INFO] [stdout] | [INFO] [stdout] 229 | pub struct Segment { [INFO] [stdout] | ^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: associated items `new`, `open`, `read`, `append`, `index`, and `size` are never used [INFO] [stdout] --> src/storage/kvstore/segment.rs:239:12 [INFO] [stdout] | [INFO] [stdout] 238 | impl Segment { [INFO] [stdout] | ------------ associated items in this implementation [INFO] [stdout] 239 | pub fn new(dir_path: &Path, file_id: u64) -> Result { [INFO] [stdout] | ^^^ [INFO] [stdout] ... [INFO] [stdout] 267 | pub fn open(dir_path: &Path, file_id: u64, status: SegmentStatus) -> Result { [INFO] [stdout] | ^^^^ [INFO] [stdout] ... [INFO] [stdout] 291 | pub fn read(&mut self, offset: u64, length: u64) -> Result> { [INFO] [stdout] | ^^^^ [INFO] [stdout] ... [INFO] [stdout] 306 | pub fn append(&mut self, entry: Entry) -> Result { [INFO] [stdout] | ^^^^^^ [INFO] [stdout] ... [INFO] [stdout] 339 | pub fn index(&mut self, index: &mut DashMap) -> Result { [INFO] [stdout] | ^^^^^ [INFO] [stdout] ... [INFO] [stdout] 406 | pub fn size(&self) -> Result { [INFO] [stdout] | ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: non-local `impl` definition, `impl` blocks should be written at the same level as their item [INFO] [stdout] --> src/common/error.rs:3:10 [INFO] [stdout] | [INFO] [stdout] 3 | #[derive(Fail, Debug)] [INFO] [stdout] | ^--- [INFO] [stdout] | | [INFO] [stdout] | `Fail` is not local [INFO] [stdout] | move the `impl` block outside of this constant `_DERIVE_failure_Fail_FOR_KvsError` [INFO] [stdout] 4 | pub enum KvsError { [INFO] [stdout] | -------- `KvsError` is not local [INFO] [stdout] | [INFO] [stdout] = note: the derive macro `Fail` defines the non-local `impl`, and may need to be changed [INFO] [stdout] = note: the derive macro `Fail` may come from an old version of the `failure_derive` crate, try updating your dependency with `cargo update -p failure_derive` [INFO] [stdout] = note: an `impl` is never scoped, even when it is nested inside an item, as it may impact type checking outside of that item, which can be the case if neither the trait or the self type are at the same nesting level as the `impl` [INFO] [stdout] = note: items in an anonymous const item (`const _: () = { ... }`) are treated as in the same scope as the anonymous const's declaration for the purpose of this lint [INFO] [stdout] = note: `#[warn(non_local_definitions)]` on by default [INFO] [stdout] = note: this warning originates in the derive macro `Fail` (in Nightly builds, run with -Z macro-backtrace for more info) [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: non-local `impl` definition, `impl` blocks should be written at the same level as their item [INFO] [stdout] --> src/common/error.rs:3:10 [INFO] [stdout] | [INFO] [stdout] 3 | #[derive(Fail, Debug)] [INFO] [stdout] | ^--- [INFO] [stdout] | | [INFO] [stdout] | `Display` is not local [INFO] [stdout] | move the `impl` block outside of this constant `_DERIVE_failure_core_fmt_Display_FOR_KvsError` [INFO] [stdout] 4 | pub enum KvsError { [INFO] [stdout] | -------- `KvsError` is not local [INFO] [stdout] | [INFO] [stdout] = note: the derive macro `Fail` defines the non-local `impl`, and may need to be changed [INFO] [stdout] = note: the derive macro `Fail` may come from an old version of the `failure_derive` crate, try updating your dependency with `cargo update -p failure_derive` [INFO] [stdout] = note: an `impl` is never scoped, even when it is nested inside an item, as it may impact type checking outside of that item, which can be the case if neither the trait or the self type are at the same nesting level as the `impl` [INFO] [stdout] = note: items in an anonymous const item (`const _: () = { ... }`) are treated as in the same scope as the anonymous const's declaration for the purpose of this lint [INFO] [stdout] = note: this warning originates in the derive macro `Fail` (in Nightly builds, run with -Z macro-backtrace for more info) [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 31.54s [INFO] running `Command { std: "docker" "inspect" "f1517f6fdae2c73004ea2af84dd9ffdce28d32e8681b52bd0ab2238c7453d18a", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f1517f6fdae2c73004ea2af84dd9ffdce28d32e8681b52bd0ab2238c7453d18a", kill_on_drop: false }` [INFO] [stdout] f1517f6fdae2c73004ea2af84dd9ffdce28d32e8681b52bd0ab2238c7453d18a [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] c1cc70143cf53e44969150b8ac7e224773c4ab89a6893fed00c67f3cabdc4052 [INFO] running `Command { std: "docker" "start" "-a" "c1cc70143cf53e44969150b8ac7e224773c4ab89a6893fed00c67f3cabdc4052", kill_on_drop: false }` [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling autocfg v0.1.8 [INFO] [stderr] Compiling rand_core v0.4.2 [INFO] [stderr] Compiling rayon v1.10.0 [INFO] [stderr] Compiling escargot v0.4.0 [INFO] [stderr] Compiling rustix v1.0.7 [INFO] [stderr] Compiling predicates-core v1.0.9 [INFO] [stderr] Compiling textwrap v0.11.0 [INFO] [stderr] Compiling float-cmp v0.8.0 [INFO] [stderr] Compiling csv-core v0.1.12 [INFO] [stderr] Compiling half v1.8.3 [INFO] [stderr] Compiling termtree v0.5.1 [INFO] [stderr] Compiling normalize-line-endings v0.3.0 [INFO] [stderr] Compiling criterion-plot v0.4.5 [INFO] [stderr] Compiling tinytemplate v1.2.1 [INFO] [stderr] Compiling atty v0.2.14 [INFO] [stderr] Compiling rand_chacha v0.1.1 [INFO] [stderr] Compiling rand_pcg v0.1.2 [INFO] [stderr] Compiling rand v0.6.5 [INFO] [stderr] Compiling rand_core v0.3.1 [INFO] [stderr] Compiling serde_cbor v0.11.2 [INFO] [stderr] Compiling predicates-tree v1.0.12 [INFO] [stderr] Compiling csv v1.3.1 [INFO] [stderr] Compiling clap v2.34.0 [INFO] [stderr] Compiling rand_os v0.1.3 [INFO] [stderr] Compiling rand_xorshift v0.1.1 [INFO] [stderr] Compiling rand_isaac v0.1.1 [INFO] [stderr] Compiling rand_hc v0.1.0 [INFO] [stderr] Compiling rand_jitter v0.1.4 [INFO] [stderr] Compiling crossbeam-utils v0.6.6 [INFO] [stderr] Compiling num_cpus v1.17.0 [INFO] [stderr] Compiling panic-control v0.1.4 [INFO] [stderr] Compiling regex-automata v0.4.9 [INFO] [stderr] Compiling tempfile v3.20.0 [INFO] [stderr] Compiling regex v1.11.1 [INFO] [stderr] Compiling tracing-subscriber v0.3.19 [INFO] [stderr] Compiling predicates v1.0.8 [INFO] [stderr] Compiling criterion v0.3.6 [INFO] [stderr] Compiling assert_cmd v0.11.1 [INFO] [stderr] Compiling kvs v0.1.0 (/opt/rustwide/workdir) [INFO] [stdout] warning: unused import: `Tree` [INFO] [stdout] --> src/storage/kvsled.rs:3:16 [INFO] [stdout] | [INFO] [stdout] 3 | use sled::{Db, Tree}; [INFO] [stdout] | ^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused imports: `error` and `info` [INFO] [stdout] --> src/storage/kvstore/segment.rs:10:15 [INFO] [stdout] | [INFO] [stdout] 10 | use tracing::{error, info}; [INFO] [stdout] | ^^^^^ ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused imports: `error` and `info` [INFO] [stdout] --> src/storage/kvstore/store.rs:11:15 [INFO] [stdout] | [INFO] [stdout] 11 | use tracing::{error, info}; [INFO] [stdout] | ^^^^^ ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused variable: `threads` [INFO] [stdout] --> src/threadpool/naive.rs:7:12 [INFO] [stdout] | [INFO] [stdout] 7 | fn new(threads: u32) -> Result { [INFO] [stdout] | ^^^^^^^ help: if this is intentional, prefix it with an underscore: `_threads` [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: enum `SegmentStatus` is never used [INFO] [stdout] --> src/storage/kvstore/segment.rs:221:10 [INFO] [stdout] | [INFO] [stdout] 221 | pub enum SegmentStatus { [INFO] [stdout] | ^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: struct `Segment` is never constructed [INFO] [stdout] --> src/storage/kvstore/segment.rs:229:12 [INFO] [stdout] | [INFO] [stdout] 229 | pub struct Segment { [INFO] [stdout] | ^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: associated items `new`, `open`, `read`, `append`, `index`, and `size` are never used [INFO] [stdout] --> src/storage/kvstore/segment.rs:239:12 [INFO] [stdout] | [INFO] [stdout] 238 | impl Segment { [INFO] [stdout] | ------------ associated items in this implementation [INFO] [stdout] 239 | pub fn new(dir_path: &Path, file_id: u64) -> Result { [INFO] [stdout] | ^^^ [INFO] [stdout] ... [INFO] [stdout] 267 | pub fn open(dir_path: &Path, file_id: u64, status: SegmentStatus) -> Result { [INFO] [stdout] | ^^^^ [INFO] [stdout] ... [INFO] [stdout] 291 | pub fn read(&mut self, offset: u64, length: u64) -> Result> { [INFO] [stdout] | ^^^^ [INFO] [stdout] ... [INFO] [stdout] 306 | pub fn append(&mut self, entry: Entry) -> Result { [INFO] [stdout] | ^^^^^^ [INFO] [stdout] ... [INFO] [stdout] 339 | pub fn index(&mut self, index: &mut DashMap) -> Result { [INFO] [stdout] | ^^^^^ [INFO] [stdout] ... [INFO] [stdout] 406 | pub fn size(&self) -> Result { [INFO] [stdout] | ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: non-local `impl` definition, `impl` blocks should be written at the same level as their item [INFO] [stdout] --> src/common/error.rs:3:10 [INFO] [stdout] | [INFO] [stdout] 3 | #[derive(Fail, Debug)] [INFO] [stdout] | ^--- [INFO] [stdout] | | [INFO] [stdout] | `Fail` is not local [INFO] [stdout] | move the `impl` block outside of this constant `_DERIVE_failure_Fail_FOR_KvsError` [INFO] [stdout] 4 | pub enum KvsError { [INFO] [stdout] | -------- `KvsError` is not local [INFO] [stdout] | [INFO] [stdout] = note: the derive macro `Fail` defines the non-local `impl`, and may need to be changed [INFO] [stdout] = note: the derive macro `Fail` may come from an old version of the `failure_derive` crate, try updating your dependency with `cargo update -p failure_derive` [INFO] [stdout] = note: an `impl` is never scoped, even when it is nested inside an item, as it may impact type checking outside of that item, which can be the case if neither the trait or the self type are at the same nesting level as the `impl` [INFO] [stdout] = note: items in an anonymous const item (`const _: () = { ... }`) are treated as in the same scope as the anonymous const's declaration for the purpose of this lint [INFO] [stdout] = note: `#[warn(non_local_definitions)]` on by default [INFO] [stdout] = note: this warning originates in the derive macro `Fail` (in Nightly builds, run with -Z macro-backtrace for more info) [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: non-local `impl` definition, `impl` blocks should be written at the same level as their item [INFO] [stdout] --> src/common/error.rs:3:10 [INFO] [stdout] | [INFO] [stdout] 3 | #[derive(Fail, Debug)] [INFO] [stdout] | ^--- [INFO] [stdout] | | [INFO] [stdout] | `Display` is not local [INFO] [stdout] | move the `impl` block outside of this constant `_DERIVE_failure_core_fmt_Display_FOR_KvsError` [INFO] [stdout] 4 | pub enum KvsError { [INFO] [stdout] | -------- `KvsError` is not local [INFO] [stdout] | [INFO] [stdout] = note: the derive macro `Fail` defines the non-local `impl`, and may need to be changed [INFO] [stdout] = note: the derive macro `Fail` may come from an old version of the `failure_derive` crate, try updating your dependency with `cargo update -p failure_derive` [INFO] [stdout] = note: an `impl` is never scoped, even when it is nested inside an item, as it may impact type checking outside of that item, which can be the case if neither the trait or the self type are at the same nesting level as the `impl` [INFO] [stdout] = note: items in an anonymous const item (`const _: () = { ... }`) are treated as in the same scope as the anonymous const's declaration for the purpose of this lint [INFO] [stdout] = note: this warning originates in the derive macro `Fail` (in Nightly builds, run with -Z macro-backtrace for more info) [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: function `spawn_panic_task` is never used [INFO] [stdout] --> tests/threadpool.rs:31:4 [INFO] [stdout] | [INFO] [stdout] 31 | fn spawn_panic_task() -> Result<()> { [INFO] [stdout] | ^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused import: `Tree` [INFO] [stdout] --> src/storage/kvsled.rs:3:16 [INFO] [stdout] | [INFO] [stdout] 3 | use sled::{Db, Tree}; [INFO] [stdout] | ^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused imports: `error` and `info` [INFO] [stdout] --> src/storage/kvstore/segment.rs:10:15 [INFO] [stdout] | [INFO] [stdout] 10 | use tracing::{error, info}; [INFO] [stdout] | ^^^^^ ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused imports: `error` and `info` [INFO] [stdout] --> src/storage/kvstore/store.rs:11:15 [INFO] [stdout] | [INFO] [stdout] 11 | use tracing::{error, info}; [INFO] [stdout] | ^^^^^ ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused variable: `threads` [INFO] [stdout] --> src/threadpool/naive.rs:7:12 [INFO] [stdout] | [INFO] [stdout] 7 | fn new(threads: u32) -> Result { [INFO] [stdout] | ^^^^^^^ help: if this is intentional, prefix it with an underscore: `_threads` [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: enum `SegmentStatus` is never used [INFO] [stdout] --> src/storage/kvstore/segment.rs:221:10 [INFO] [stdout] | [INFO] [stdout] 221 | pub enum SegmentStatus { [INFO] [stdout] | ^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: struct `Segment` is never constructed [INFO] [stdout] --> src/storage/kvstore/segment.rs:229:12 [INFO] [stdout] | [INFO] [stdout] 229 | pub struct Segment { [INFO] [stdout] | ^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: associated items `new`, `open`, `read`, `append`, `index`, and `size` are never used [INFO] [stdout] --> src/storage/kvstore/segment.rs:239:12 [INFO] [stdout] | [INFO] [stdout] 238 | impl Segment { [INFO] [stdout] | ------------ associated items in this implementation [INFO] [stdout] 239 | pub fn new(dir_path: &Path, file_id: u64) -> Result { [INFO] [stdout] | ^^^ [INFO] [stdout] ... [INFO] [stdout] 267 | pub fn open(dir_path: &Path, file_id: u64, status: SegmentStatus) -> Result { [INFO] [stdout] | ^^^^ [INFO] [stdout] ... [INFO] [stdout] 291 | pub fn read(&mut self, offset: u64, length: u64) -> Result> { [INFO] [stdout] | ^^^^ [INFO] [stdout] ... [INFO] [stdout] 306 | pub fn append(&mut self, entry: Entry) -> Result { [INFO] [stdout] | ^^^^^^ [INFO] [stdout] ... [INFO] [stdout] 339 | pub fn index(&mut self, index: &mut DashMap) -> Result { [INFO] [stdout] | ^^^^^ [INFO] [stdout] ... [INFO] [stdout] 406 | pub fn size(&self) -> Result { [INFO] [stdout] | ^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: non-local `impl` definition, `impl` blocks should be written at the same level as their item [INFO] [stdout] --> src/common/error.rs:3:10 [INFO] [stdout] | [INFO] [stdout] 3 | #[derive(Fail, Debug)] [INFO] [stdout] | ^--- [INFO] [stdout] | | [INFO] [stdout] | `Fail` is not local [INFO] [stdout] | move the `impl` block outside of this constant `_DERIVE_failure_Fail_FOR_KvsError` [INFO] [stdout] 4 | pub enum KvsError { [INFO] [stdout] | -------- `KvsError` is not local [INFO] [stdout] | [INFO] [stdout] = note: the derive macro `Fail` defines the non-local `impl`, and may need to be changed [INFO] [stdout] = note: the derive macro `Fail` may come from an old version of the `failure_derive` crate, try updating your dependency with `cargo update -p failure_derive` [INFO] [stdout] = note: an `impl` is never scoped, even when it is nested inside an item, as it may impact type checking outside of that item, which can be the case if neither the trait or the self type are at the same nesting level as the `impl` [INFO] [stdout] = note: items in an anonymous const item (`const _: () = { ... }`) are treated as in the same scope as the anonymous const's declaration for the purpose of this lint [INFO] [stdout] = note: `#[warn(non_local_definitions)]` on by default [INFO] [stdout] = note: this warning originates in the derive macro `Fail` (in Nightly builds, run with -Z macro-backtrace for more info) [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: non-local `impl` definition, `impl` blocks should be written at the same level as their item [INFO] [stdout] --> src/common/error.rs:3:10 [INFO] [stdout] | [INFO] [stdout] 3 | #[derive(Fail, Debug)] [INFO] [stdout] | ^--- [INFO] [stdout] | | [INFO] [stdout] | `Display` is not local [INFO] [stdout] | move the `impl` block outside of this constant `_DERIVE_failure_core_fmt_Display_FOR_KvsError` [INFO] [stdout] 4 | pub enum KvsError { [INFO] [stdout] | -------- `KvsError` is not local [INFO] [stdout] | [INFO] [stdout] = note: the derive macro `Fail` defines the non-local `impl`, and may need to be changed [INFO] [stdout] = note: the derive macro `Fail` may come from an old version of the `failure_derive` crate, try updating your dependency with `cargo update -p failure_derive` [INFO] [stdout] = note: an `impl` is never scoped, even when it is nested inside an item, as it may impact type checking outside of that item, which can be the case if neither the trait or the self type are at the same nesting level as the `impl` [INFO] [stdout] = note: items in an anonymous const item (`const _: () = { ... }`) are treated as in the same scope as the anonymous const's declaration for the purpose of this lint [INFO] [stdout] = note: this warning originates in the derive macro `Fail` (in Nightly builds, run with -Z macro-backtrace for more info) [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 19.43s [INFO] running `Command { std: "docker" "inspect" "c1cc70143cf53e44969150b8ac7e224773c4ab89a6893fed00c67f3cabdc4052", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c1cc70143cf53e44969150b8ac7e224773c4ab89a6893fed00c67f3cabdc4052", kill_on_drop: false }` [INFO] [stdout] c1cc70143cf53e44969150b8ac7e224773c4ab89a6893fed00c67f3cabdc4052 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] f5b10aefff4b1999ba242409967ddd5197dd0273b4d2aae8b12036fed81e97c3 [INFO] running `Command { std: "docker" "start" "-a" "f5b10aefff4b1999ba242409967ddd5197dd0273b4d2aae8b12036fed81e97c3", kill_on_drop: false }` [INFO] [stderr] warning: unused import: `Tree` [INFO] [stderr] --> src/storage/kvsled.rs:3:16 [INFO] [stderr] | [INFO] [stderr] 3 | use sled::{Db, Tree}; [INFO] [stderr] | ^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: unused imports: `error` and `info` [INFO] [stderr] --> src/storage/kvstore/segment.rs:10:15 [INFO] [stderr] | [INFO] [stderr] 10 | use tracing::{error, info}; [INFO] [stderr] | ^^^^^ ^^^^ [INFO] [stderr] [INFO] [stderr] warning: unused imports: `error` and `info` [INFO] [stderr] --> src/storage/kvstore/store.rs:11:15 [INFO] [stderr] | [INFO] [stderr] 11 | use tracing::{error, info}; [INFO] [stderr] | ^^^^^ ^^^^ [INFO] [stderr] [INFO] [stderr] warning: unused variable: `threads` [INFO] [stderr] --> src/threadpool/naive.rs:7:12 [INFO] [stderr] | [INFO] [stderr] 7 | fn new(threads: u32) -> Result { [INFO] [stderr] | ^^^^^^^ help: if this is intentional, prefix it with an underscore: `_threads` [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: enum `SegmentStatus` is never used [INFO] [stderr] --> src/storage/kvstore/segment.rs:221:10 [INFO] [stderr] | [INFO] [stderr] 221 | pub enum SegmentStatus { [INFO] [stderr] | ^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: struct `Segment` is never constructed [INFO] [stderr] --> src/storage/kvstore/segment.rs:229:12 [INFO] [stderr] | [INFO] [stderr] 229 | pub struct Segment { [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] [INFO] [stderr] warning: associated items `new`, `open`, `read`, `append`, `index`, and `size` are never used [INFO] [stderr] --> src/storage/kvstore/segment.rs:239:12 [INFO] [stderr] | [INFO] [stderr] 238 | impl Segment { [INFO] [stderr] | ------------ associated items in this implementation [INFO] [stderr] 239 | pub fn new(dir_path: &Path, file_id: u64) -> Result { [INFO] [stderr] | ^^^ [INFO] [stderr] ... [INFO] [stderr] 267 | pub fn open(dir_path: &Path, file_id: u64, status: SegmentStatus) -> Result { [INFO] [stderr] | ^^^^ [INFO] [stderr] ... [INFO] [stderr] 291 | pub fn read(&mut self, offset: u64, length: u64) -> Result> { [INFO] [stderr] | ^^^^ [INFO] [stderr] ... [INFO] [stderr] 306 | pub fn append(&mut self, entry: Entry) -> Result { [INFO] [stderr] | ^^^^^^ [INFO] [stderr] ... [INFO] [stderr] 339 | pub fn index(&mut self, index: &mut DashMap) -> Result { [INFO] [stderr] | ^^^^^ [INFO] [stderr] ... [INFO] [stderr] 406 | pub fn size(&self) -> Result { [INFO] [stderr] | ^^^^ [INFO] [stderr] [INFO] [stderr] warning: non-local `impl` definition, `impl` blocks should be written at the same level as their item [INFO] [stderr] --> src/common/error.rs:3:10 [INFO] [stderr] | [INFO] [stderr] 3 | #[derive(Fail, Debug)] [INFO] [stderr] | ^--- [INFO] [stderr] | | [INFO] [stderr] | `Fail` is not local [INFO] [stderr] | move the `impl` block outside of this constant `_DERIVE_failure_Fail_FOR_KvsError` [INFO] [stderr] 4 | pub enum KvsError { [INFO] [stderr] | -------- `KvsError` is not local [INFO] [stderr] | [INFO] [stderr] = note: the derive macro `Fail` defines the non-local `impl`, and may need to be changed [INFO] [stderr] = note: the derive macro `Fail` may come from an old version of the `failure_derive` crate, try updating your dependency with `cargo update -p failure_derive` [INFO] [stderr] = note: an `impl` is never scoped, even when it is nested inside an item, as it may impact type checking outside of that item, which can be the case if neither the trait or the self type are at the same nesting level as the `impl` [INFO] [stderr] = note: items in an anonymous const item (`const _: () = { ... }`) are treated as in the same scope as the anonymous const's declaration for the purpose of this lint [INFO] [stderr] = note: `#[warn(non_local_definitions)]` on by default [INFO] [stderr] = note: this warning originates in the derive macro `Fail` (in Nightly builds, run with -Z macro-backtrace for more info) [INFO] [stderr] [INFO] [stderr] warning: non-local `impl` definition, `impl` blocks should be written at the same level as their item [INFO] [stderr] --> src/common/error.rs:3:10 [INFO] [stderr] | [INFO] [stderr] 3 | #[derive(Fail, Debug)] [INFO] [stderr] | ^--- [INFO] [stderr] | | [INFO] [stderr] | `Display` is not local [INFO] [stderr] | move the `impl` block outside of this constant `_DERIVE_failure_core_fmt_Display_FOR_KvsError` [INFO] [stderr] 4 | pub enum KvsError { [INFO] [stderr] | -------- `KvsError` is not local [INFO] [stderr] | [INFO] [stderr] = note: the derive macro `Fail` defines the non-local `impl`, and may need to be changed [INFO] [stderr] = note: the derive macro `Fail` may come from an old version of the `failure_derive` crate, try updating your dependency with `cargo update -p failure_derive` [INFO] [stderr] = note: an `impl` is never scoped, even when it is nested inside an item, as it may impact type checking outside of that item, which can be the case if neither the trait or the self type are at the same nesting level as the `impl` [INFO] [stderr] = note: items in an anonymous const item (`const _: () = { ... }`) are treated as in the same scope as the anonymous const's declaration for the purpose of this lint [INFO] [stderr] = note: this warning originates in the derive macro `Fail` (in Nightly builds, run with -Z macro-backtrace for more info) [INFO] [stderr] [INFO] [stderr] warning: `kvs` (lib) generated 9 warnings (run `cargo fix --lib -p kvs` to apply 3 suggestions) [INFO] [stderr] warning: function `spawn_panic_task` is never used [INFO] [stderr] --> tests/threadpool.rs:31:4 [INFO] [stderr] | [INFO] [stderr] 31 | fn spawn_panic_task() -> Result<()> { [INFO] [stderr] | ^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: `kvs` (lib test) generated 9 warnings (9 duplicates) [INFO] [stderr] warning: `kvs` (test "threadpool") generated 1 warning [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.21s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/kvs-ace45e06c5d11296) [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running unittests src/bin/kvs-client.rs (/opt/rustwide/target/debug/deps/kvs_client-3f0ab12a65ac1bd6) [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running unittests src/bin/kvs-server.rs (/opt/rustwide/target/debug/deps/kvs_server-182288dd2de16031) [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/cli_client.rs (/opt/rustwide/target/debug/deps/cli_client-d3ae667c3b2de5e0) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test client_cli_no_args ... ok [INFO] [stdout] test client_cli_version ... ok [INFO] [stdout] test client_cli_invalid_rm ... ok [INFO] [stdout] test client_cli_invalid_get ... ok [INFO] [stdout] test client_cli_invalid_set ... ok [INFO] [stdout] test client_cli_invalid_subcommand ... ok [INFO] [stderr] Running tests/cli_server.rs (/opt/rustwide/target/debug/deps/cli_server-4cfca20efd4ea37d) [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.13s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 5 tests [INFO] [stderr] 2025-09-10T03:54:35.087286Z  INFO kvs_server: kvs-server 0.1.0 [INFO] [stderr] at src/bin/kvs-server.rs:40 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.087648Z  INFO kvs_server: Storage engine: kvs [INFO] [stderr] at src/bin/kvs-server.rs:41 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.087699Z  INFO kvs_server: Listening on 127.0.0.1:4004 [INFO] [stderr] at src/bin/kvs-server.rs:42 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.088134Z  INFO kvs::threadpool: Thread Pool Type: queue [INFO] [stderr] at src/threadpool/mod.rs:50 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.088620Z  INFO kvs::server::sync_server: Server starting at 127.0.0.1:4004 [INFO] [stderr] at src/server/sync_server.rs:45 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.108118Z  INFO kvs_server: kvs-server 0.1.0 [INFO] [stderr] at src/bin/kvs-server.rs:40 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.108228Z  INFO kvs_server: Storage engine: sled [INFO] [stderr] at src/bin/kvs-server.rs:41 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.108247Z  INFO kvs_server: Listening on 127.0.0.1:4005 [INFO] [stderr] at src/bin/kvs-server.rs:42 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.119829Z  INFO kvs_server: kvs-server 0.1.0 [INFO] [stdout] test server_cli_version ... ok [INFO] [stderr] at src/bin/kvs-server.rs:40 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.119917Z  INFO kvs_server: Storage engine: sled [INFO] [stderr] at src/bin/kvs-server.rs:41 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.119945Z  INFO kvs_server: Listening on 127.0.0.1:4002 [INFO] [stderr] at src/bin/kvs-server.rs:42 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.247568Z DEBUG sled::pagecache::snapshot: no previous snapshot found [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/snapshot.rs:461 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.247704Z DEBUG sled::pagecache::iterator: ordering before clearing tears: {}, max_header_stable_lsn: 0 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:380 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.247761Z DEBUG sled::pagecache::iterator: in clean_tail_tears, found missing item in tail: None and we'll scan segments {} above lowest lsn 0 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:436 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.247805Z DEBUG sled::pagecache::iterator: unable to load new segment: Io(Custom { kind: Other, error: "no segments remaining to iterate over" }) [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:43 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.247847Z DEBUG sled::pagecache::iterator: filtering out segments after detected tear at (lsn, lid) -1 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:458 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.247894Z DEBUG sled::pagecache::iterator: unable to load new segment: Io(Custom { kind: Other, error: "no segments remaining to iterate over" }) [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:43 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.248153Z DEBUG sled::pagecache::segment: SA starting with tip 0 stable -1 free {} [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/segment.rs:503 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.248203Z DEBUG sled::pagecache::iobuf: starting log for a totally fresh system [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:361 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.248264Z DEBUG sled::pagecache::segment: segment accountant returning offset: 0 for lsn 0 on deck: {} [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/segment.rs:1032 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.248304Z DEBUG sled::pagecache::iobuf: starting IoBufs with next_lsn: 0 next_lid: 0 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:379 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.248373Z DEBUG sled::pagecache::iobuf: storing lsn 0 in beginning of buffer [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:123 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.248431Z DEBUG sled::pagecache: load_snapshot loading pages from 0..0 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/mod.rs:2065 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.248698Z DEBUG sled::meta: allocated pid 3 for root of new_tree [95, 95, 115, 108, 101, 100, 95, 95, 100, 101, 102, 97, 117, 108, 116] [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/meta.rs:90 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.248758Z DEBUG sled::pagecache::iobuf: advancing offset within the current segment from 0 to 71 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:1107 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.248882Z  INFO kvs::threadpool: Thread Pool Type: queue [INFO] [stderr] at src/threadpool/mod.rs:50 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.256589Z DEBUG sled::pagecache::iobuf: wrote lsns 0-70 to disk at offsets 0-70, maxed false complete_len 71 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:770 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.256694Z DEBUG sled::pagecache::iobuf: mark_interval(0, 71) [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:811 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.256731Z DEBUG sled::pagecache::iobuf: new highest interval: 0 - 70 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:236 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.256735Z  INFO kvs::server::sync_server: Server starting at 127.0.0.1:4005 [INFO] [stderr] at src/server/sync_server.rs:45 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.292194Z DEBUG sled::pagecache::snapshot: no previous snapshot found [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/snapshot.rs:461 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.292333Z DEBUG sled::pagecache::iterator: ordering before clearing tears: {}, max_header_stable_lsn: 0 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:380 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.292431Z DEBUG sled::pagecache::iterator: in clean_tail_tears, found missing item in tail: None and we'll scan segments {} above lowest lsn 0 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:436 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.292468Z DEBUG sled::pagecache::iterator: unable to load new segment: Io(Custom { kind: Other, error: "no segments remaining to iterate over" }) [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:43 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.292537Z DEBUG sled::pagecache::iterator: filtering out segments after detected tear at (lsn, lid) -1 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:458 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.292579Z DEBUG sled::pagecache::iterator: unable to load new segment: Io(Custom { kind: Other, error: "no segments remaining to iterate over" }) [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:43 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.292851Z DEBUG sled::pagecache::segment: SA starting with tip 0 stable -1 free {} [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/segment.rs:503 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.292912Z DEBUG sled::pagecache::iobuf: starting log for a totally fresh system [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:361 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.292960Z DEBUG sled::pagecache::segment: segment accountant returning offset: 0 for lsn 0 on deck: {} [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/segment.rs:1032 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.293080Z DEBUG sled::pagecache::iobuf: starting IoBufs with next_lsn: 0 next_lid: 0 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:379 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.293125Z DEBUG sled::pagecache::iobuf: storing lsn 0 in beginning of buffer [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:123 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.293198Z DEBUG sled::pagecache: load_snapshot loading pages from 0..0 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/mod.rs:2065 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.293467Z DEBUG sled::meta: allocated pid 3 for root of new_tree [95, 95, 115, 108, 101, 100, 95, 95, 100, 101, 102, 97, 117, 108, 116] [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/meta.rs:90 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.293515Z DEBUG sled::pagecache::iobuf: advancing offset within the current segment from 0 to 71 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:1107 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.293647Z  INFO kvs::threadpool: Thread Pool Type: queue [INFO] [stderr] at src/threadpool/mod.rs:50 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.298505Z  INFO kvs::server::sync_server: Server starting at 127.0.0.1:4002 [INFO] [stderr] at src/server/sync_server.rs:45 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.298572Z DEBUG sled::pagecache::iobuf: wrote lsns 0-70 to disk at offsets 0-70, maxed false complete_len 71 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:770 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.298630Z DEBUG sled::pagecache::iobuf: mark_interval(0, 71) [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:811 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.298646Z DEBUG sled::pagecache::iobuf: new highest interval: 0 - 70 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:236 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.748862Z DEBUG sled::pagecache::iobuf: advancing offset within the current segment from 71 to 96 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:1107 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.749010Z DEBUG sled::pagecache::iobuf: wrote lsns 71-95 to disk at offsets 71-95, maxed false complete_len 25 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:770 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.749047Z DEBUG sled::pagecache::iobuf: mark_interval(71, 25) [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:811 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.749063Z DEBUG sled::pagecache::iobuf: new highest interval: 71 - 95 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:236 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.793613Z DEBUG sled::pagecache::iobuf: advancing offset within the current segment from 71 to 96 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:1107 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.793859Z DEBUG sled::pagecache::iobuf: wrote lsns 71-95 to disk at offsets 71-95, maxed false complete_len 25 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:770 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.793894Z DEBUG sled::pagecache::iobuf: mark_interval(71, 25) [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:811 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:35.793908Z DEBUG sled::pagecache::iobuf: new highest interval: 71 - 95 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:236 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.088293Z  INFO kvs::protocols::resp: Deserialize: "*3\r\n$3\r\nSET\r\n$4\r\nkey1\r\n$6\r\nvalue1\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.089941Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $2 [INFO] [stderr] OK [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.089984Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 50, 13, 10, 79, 75, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.092538Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$3\r\nGET\r\n$4\r\nkey1\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.092639Z  INFO kvs::server::sync_server: Get Value: value1 [INFO] [stderr] at src/server/sync_server.rs:87 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.092678Z  INFO kvs::protocols::resp: Serialize: *2 [INFO] [stderr] $5 [INFO] [stderr] VALUE [INFO] [stderr] $6 [INFO] [stderr] value1 [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.092704Z  INFO kvs::server::sync_server: Encoded Server: [42, 50, 13, 10, 36, 53, 13, 10, 86, 65, 76, 85, 69, 13, 10, 36, 54, 13, 10, 118, 97, 108, 117, 101, 49, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.095489Z  INFO kvs::protocols::resp: Deserialize: "*3\r\n$3\r\nSET\r\n$4\r\nkey1\r\n$6\r\nvalue2\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.096867Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $2 [INFO] [stderr] OK [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.096943Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 50, 13, 10, 79, 75, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.100067Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$3\r\nGET\r\n$4\r\nkey1\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.100211Z  INFO kvs::server::sync_server: Get Value: value2 [INFO] [stderr] at src/server/sync_server.rs:87 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.100263Z  INFO kvs::protocols::resp: Serialize: *2 [INFO] [stderr] $5 [INFO] [stderr] VALUE [INFO] [stderr] $6 [INFO] [stderr] value2 [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.100295Z  INFO kvs::server::sync_server: Encoded Server: [42, 50, 13, 10, 36, 53, 13, 10, 86, 65, 76, 85, 69, 13, 10, 36, 54, 13, 10, 118, 97, 108, 117, 101, 50, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.102977Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$3\r\nGET\r\n$4\r\nkey2\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.103080Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $9 [INFO] [stderr] NOT_FOUND [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.103113Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 57, 13, 10, 78, 79, 84, 95, 70, 79, 85, 78, 68, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.106083Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$6\r\nREMOVE\r\n$4\r\nkey2\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.106137Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $9 [INFO] [stderr] NOT_FOUND [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.106150Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 57, 13, 10, 78, 79, 84, 95, 70, 79, 85, 78, 68, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stdout] test cli_log_configuration ... ok [INFO] [stderr] 2025-09-10T03:54:36.108805Z  INFO kvs::protocols::resp: Deserialize: "*3\r\n$3\r\nSET\r\n$4\r\nkey2\r\n$6\r\nvalue3\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.110318Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $2 [INFO] [stderr] OK [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.110342Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 50, 13, 10, 79, 75, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.114498Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$6\r\nREMOVE\r\n$4\r\nkey1\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.115955Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $2 [INFO] [stderr] OK [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.116028Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 50, 13, 10, 79, 75, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.121476Z  INFO kvs_server: kvs-server 0.1.0 [INFO] [stderr] at src/bin/kvs-server.rs:40 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.121518Z  INFO kvs_server: Storage engine: kvs [INFO] [stderr] at src/bin/kvs-server.rs:41 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.121528Z  INFO kvs_server: Listening on 127.0.0.1:4002 [INFO] [stderr] at src/bin/kvs-server.rs:42 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.121917Z  INFO kvs::threadpool: Thread Pool Type: queue [INFO] [stderr] at src/threadpool/mod.rs:50 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.122695Z  INFO kvs::protocols::resp: Deserialize: "*3\r\n$3\r\nSET\r\n$4\r\nkey1\r\n$6\r\nvalue1\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.122942Z DEBUG sled::pagecache::iobuf: advancing offset within the current segment from 96 to 117 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:1107 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.123061Z DEBUG sled::pagecache::iobuf: wrote lsns 96-116 to disk at offsets 96-116, maxed false complete_len 21 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:770 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.123084Z DEBUG sled::pagecache::iobuf: mark_interval(96, 21) [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:811 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.123098Z DEBUG sled::pagecache::iobuf: new highest interval: 96 - 116 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:236 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.123169Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $2 [INFO] [stderr] OK [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.123183Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 50, 13, 10, 79, 75, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.131730Z  INFO kvs_server: kvs-server 0.1.0 [INFO] [stderr] at src/bin/kvs-server.rs:40 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.131792Z  INFO kvs_server: Storage engine: kvs [INFO] [stderr] at src/bin/kvs-server.rs:41 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.131805Z  INFO kvs_server: Listening on 127.0.0.1:4004 [INFO] [stderr] at src/bin/kvs-server.rs:42 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.132193Z  INFO kvs::threadpool: Thread Pool Type: queue [INFO] [stderr] at src/threadpool/mod.rs:50 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.132743Z  INFO kvs::server::sync_server: Server starting at 127.0.0.1:4004 [INFO] [stderr] at src/server/sync_server.rs:45 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.134969Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$3\r\nGET\r\n$4\r\nkey1\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.135070Z  INFO kvs::server::sync_server: Get Value: value1 [INFO] [stderr] at src/server/sync_server.rs:87 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.135086Z  INFO kvs::protocols::resp: Serialize: *2 [INFO] [stderr] $5 [INFO] [stderr] VALUE [INFO] [stderr] $6 [INFO] [stderr] value1 [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.135094Z  INFO kvs::server::sync_server: Encoded Server: [42, 50, 13, 10, 36, 53, 13, 10, 86, 65, 76, 85, 69, 13, 10, 36, 54, 13, 10, 118, 97, 108, 117, 101, 49, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.136437Z  INFO kvs::server::sync_server: Server starting at 127.0.0.1:4002 [INFO] [stderr] at src/server/sync_server.rs:45 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.137942Z  INFO kvs::protocols::resp: Deserialize: "*3\r\n$3\r\nSET\r\n$4\r\nkey1\r\n$6\r\nvalue2\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.138054Z DEBUG sled::pagecache::iobuf: advancing offset within the current segment from 117 to 138 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:1107 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.138207Z DEBUG sled::pagecache::iobuf: wrote lsns 117-137 to disk at offsets 117-137, maxed false complete_len 21 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:770 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.138238Z DEBUG sled::pagecache::iobuf: mark_interval(117, 21) [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:811 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.138251Z DEBUG sled::pagecache::iobuf: new highest interval: 117 - 137 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:236 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.138383Z DEBUG sled::pagecache::iobuf: make_stable(137) returning [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:1000 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.138412Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $2 [INFO] [stderr] OK [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.138423Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 50, 13, 10, 79, 75, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.141089Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$3\r\nGET\r\n$4\r\nkey1\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.141189Z  INFO kvs::server::sync_server: Get Value: value2 [INFO] [stderr] at src/server/sync_server.rs:87 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.141212Z  INFO kvs::protocols::resp: Serialize: *2 [INFO] [stderr] $5 [INFO] [stderr] VALUE [INFO] [stderr] $6 [INFO] [stderr] value2 [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.141223Z  INFO kvs::server::sync_server: Encoded Server: [42, 50, 13, 10, 36, 53, 13, 10, 86, 65, 76, 85, 69, 13, 10, 36, 54, 13, 10, 118, 97, 108, 117, 101, 50, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.143603Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$3\r\nGET\r\n$4\r\nkey2\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.143688Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $9 [INFO] [stderr] NOT_FOUND [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.143702Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 57, 13, 10, 78, 79, 84, 95, 70, 79, 85, 78, 68, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.146173Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$6\r\nREMOVE\r\n$4\r\nkey2\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.146238Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $9 [INFO] [stderr] NOT_FOUND [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.146252Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 57, 13, 10, 78, 79, 84, 95, 70, 79, 85, 78, 68, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.148691Z  INFO kvs::protocols::resp: Deserialize: "*3\r\n$3\r\nSET\r\n$4\r\nkey2\r\n$6\r\nvalue3\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.148816Z DEBUG sled::pagecache::iobuf: advancing offset within the current segment from 138 to 159 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:1107 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.149000Z DEBUG sled::pagecache::iobuf: wrote lsns 138-158 to disk at offsets 138-158, maxed false complete_len 21 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:770 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.149027Z DEBUG sled::pagecache::iobuf: mark_interval(138, 21) [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:811 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.149040Z DEBUG sled::pagecache::iobuf: new highest interval: 138 - 158 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:236 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.149101Z DEBUG sled::pagecache::iobuf: make_stable(158) returning [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:1000 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.149120Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $2 [INFO] [stderr] OK [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.149130Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 50, 13, 10, 79, 75, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.151493Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$6\r\nREMOVE\r\n$4\r\nkey1\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.151577Z DEBUG sled::pagecache::iobuf: advancing offset within the current segment from 159 to 173 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:1107 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.151718Z DEBUG sled::pagecache::iobuf: wrote lsns 159-172 to disk at offsets 159-172, maxed false complete_len 14 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:770 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.151743Z DEBUG sled::pagecache::iobuf: mark_interval(159, 14) [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:811 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.151755Z DEBUG sled::pagecache::iobuf: new highest interval: 159 - 172 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:236 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.151820Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $2 [INFO] [stderr] OK [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.151832Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 50, 13, 10, 79, 75, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.159099Z  INFO kvs_server: kvs-server 0.1.0 [INFO] [stderr] at src/bin/kvs-server.rs:40 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.159153Z  INFO kvs_server: Storage engine: sled [INFO] [stderr] at src/bin/kvs-server.rs:41 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.159166Z  INFO kvs_server: Listening on 127.0.0.1:4005 [INFO] [stderr] at src/bin/kvs-server.rs:42 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.247177Z DEBUG sled::pagecache::snapshot: no previous snapshot found [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/snapshot.rs:461 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.248139Z DEBUG sled::pagecache::iterator: ordering before clearing tears: {0: 0}, max_header_stable_lsn: 0 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:380 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.248217Z DEBUG sled::pagecache::iterator: in clean_tail_tears, found missing item in tail: None and we'll scan segments {0: 0} above lowest lsn 0 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:436 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.248299Z DEBUG sled::pagecache::iterator: filtering out segments after detected tear at (lsn, lid) 172 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:458 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.248733Z DEBUG sled::pagecache::iterator: hit max_lsn 172 in iterator, stopping [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iterator.rs:60 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.248840Z DEBUG sled::pagecache::snapshot: zeroing the end of the recovered segment at lsn 0 between lids 173 and 524287 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/snapshot.rs:329 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.254639Z DEBUG sled::pagecache::blob_io: gc_blobs removing any blob with an lsn above 173 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/blob_io.rs:107 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.254914Z DEBUG sled::pagecache::segment: SA starting with tip 524288 stable -1 free {} [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/segment.rs:503 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.254945Z DEBUG sled::pagecache::iobuf: starting log at recovered active offset 173, recovered lsn 173 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:353 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.254956Z DEBUG sled::pagecache::iobuf: starting IoBufs with next_lsn: 173 next_lid: 173 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/iobuf.rs:379 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.254981Z DEBUG sled::pagecache: load_snapshot loading pages from 0..4 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/sled-0.34.7/src/pagecache/mod.rs:2065 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.255221Z  INFO kvs::threadpool: Thread Pool Type: queue [INFO] [stderr] at src/threadpool/mod.rs:50 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:36.255720Z  INFO kvs::server::sync_server: Server starting at 127.0.0.1:4005 [INFO] [stderr] at src/server/sync_server.rs:45 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:37.126974Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$3\r\nGET\r\n$4\r\nkey2\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:37.128873Z  INFO kvs::server::sync_server: Get Value: value3 [INFO] [stderr] at src/server/sync_server.rs:87 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:37.128959Z  INFO kvs::protocols::resp: Serialize: *2 [INFO] [stderr] $5 [INFO] [stderr] VALUE [INFO] [stderr] $6 [INFO] [stderr] value3 [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:37.130003Z  INFO kvs::server::sync_server: Encoded Server: [42, 50, 13, 10, 36, 53, 13, 10, 86, 65, 76, 85, 69, 13, 10, 36, 54, 13, 10, 118, 97, 108, 117, 101, 51, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:37.132516Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$3\r\nGET\r\n$4\r\nkey1\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:37.132852Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $9 [INFO] [stderr] NOT_FOUND [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:37.133028Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 57, 13, 10, 78, 79, 84, 95, 70, 79, 85, 78, 68, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stdout] test cli_wrong_engine ... ok [INFO] [stderr] 2025-09-10T03:54:37.159809Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$3\r\nGET\r\n$4\r\nkey2\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:37.160031Z  INFO kvs::server::sync_server: Get Value: value3 [INFO] [stderr] at src/server/sync_server.rs:87 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:37.160080Z  INFO kvs::protocols::resp: Serialize: *2 [INFO] [stderr] $5 [INFO] [stderr] VALUE [INFO] [stderr] $6 [INFO] [stderr] value3 [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:37.160091Z  INFO kvs::server::sync_server: Encoded Server: [42, 50, 13, 10, 36, 53, 13, 10, 86, 65, 76, 85, 69, 13, 10, 36, 54, 13, 10, 118, 97, 108, 117, 101, 51, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:37.163162Z  INFO kvs::protocols::resp: Deserialize: "*2\r\n$3\r\nGET\r\n$4\r\nkey1\r\n" [INFO] [stderr] at src/protocols/resp.rs:84 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:37.163248Z  INFO kvs::protocols::resp: Serialize: *1 [INFO] [stderr] $9 [INFO] [stderr] NOT_FOUND [INFO] [stderr]  [INFO] [stderr] at src/protocols/resp.rs:73 [INFO] [stderr] [INFO] [stderr] 2025-09-10T03:54:37.163276Z  INFO kvs::server::sync_server: Encoded Server: [42, 49, 13, 10, 36, 57, 13, 10, 78, 79, 84, 95, 70, 79, 85, 78, 68, 13, 10] [INFO] [stderr] at src/server/sync_server.rs:100 [INFO] [stderr] [INFO] [stdout] test cli_access_server_kvs_engine ... ok [INFO] [stderr] Running tests/kvmemory.rs (/opt/rustwide/target/debug/deps/kvmemory-099c31e071f40857) [INFO] [stdout] test cli_access_server_sled_engine ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.09s [INFO] [stdout] [INFO] [stderr] Running tests/kvstore.rs (/opt/rustwide/target/debug/deps/kvstore-0ee14bcfefe48aee) [INFO] [stdout] [INFO] [stdout] running 4 tests [INFO] [stdout] test overwrite_value ... ok [INFO] [stdout] test get_non_existent_value ... ok [INFO] [stdout] test get_stored_value ... ok [INFO] [stdout] test remove_key ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 8 tests [INFO] [stdout] test remove_non_existent_key ... ok [INFO] [stdout] test get_non_existent_value ... ok [INFO] [stdout] test get_stored_value ... ok [INFO] [stdout] test remove_key ... ok [INFO] [stdout] test overwrite_value ... ok [INFO] [stdout] test compaction has been running for over 60 seconds [INFO] [stdout] test concurrent_get has been running for over 60 seconds [INFO] [stdout] test concurrent_set has been running for over 60 seconds [ERROR] error running command: no output for 300 seconds [INFO] running `Command { std: "docker" "inspect" "f5b10aefff4b1999ba242409967ddd5197dd0273b4d2aae8b12036fed81e97c3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f5b10aefff4b1999ba242409967ddd5197dd0273b4d2aae8b12036fed81e97c3", kill_on_drop: false }` [INFO] [stdout] f5b10aefff4b1999ba242409967ddd5197dd0273b4d2aae8b12036fed81e97c3