[INFO] cloning repository https://github.com/0xReLogic/Chronos
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/0xReLogic/Chronos" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2F0xReLogic%2FChronos", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2F0xReLogic%2FChronos'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 6fedc8b36d7a34a3164b5a1e2cf634c77e3ae873
[INFO] testing 0xReLogic/Chronos against master#7704328ba5ae8d6ce0ac303c9d5a1a1605906766 for pr-146989
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2F0xReLogic%2FChronos" "/workspace/builds/worker-2-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/0xReLogic/Chronos
[INFO] finished tweaking git repo https://github.com/0xReLogic/Chronos
[INFO] tweaked toml for git repo https://github.com/0xReLogic/Chronos written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/0xReLogic/Chronos on toolchain 7704328ba5ae8d6ce0ac303c9d5a1a1605906766
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/0xReLogic/Chronos 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" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded tokio-io-timeout v1.2.1
[INFO] [stderr]   Downloaded tonic-build v0.10.2
[INFO] [stderr]   Downloaded spin v0.10.0
[INFO] [stderr]   Downloaded tracing-serde v0.2.0
[INFO] [stderr]   Downloaded bincode_derive v2.0.1
[INFO] [stderr]   Downloaded unty v0.0.4
[INFO] [stderr]   Downloaded endian-type v0.1.2
[INFO] [stderr]   Downloaded is-terminal v0.4.16
[INFO] [stderr]   Downloaded nibble_vec v0.1.0
[INFO] [stderr]   Downloaded uhlc v0.8.2
[INFO] [stderr]   Downloaded pest_generator v2.8.1
[INFO] [stderr]   Downloaded fd-lock v3.0.13
[INFO] [stderr]   Downloaded csv-core v0.1.12
[INFO] [stderr]   Downloaded virtue v0.0.18
[INFO] [stderr]   Downloaded bincode v2.0.1
[INFO] [stderr]   Downloaded lz4_flex v0.11.5
[INFO] [stderr]   Downloaded rustyline v12.0.0
[INFO] [stderr]   Downloaded pest v2.8.1
[INFO] [stderr]   Downloaded pest_meta v2.8.1
[INFO] [stderr]   Downloaded tonic v0.10.2
[INFO] [stderr]   Downloaded prettyplease v0.2.36
[INFO] [stderr]   Downloaded clap_builder v4.5.41
[INFO] [stderr]   Downloaded tracing-subscriber v0.3.20
[INFO] [stderr]   Downloaded clap_derive v4.5.41
[INFO] [stderr]   Downloaded radix_trie v0.2.1
[INFO] [stderr]   Downloaded clap v4.5.41
[INFO] [stderr]   Downloaded env_logger v0.10.2
[INFO] [stderr]   Downloaded pest_derive v2.8.1
[INFO] [stderr]   Downloaded cc v1.2.49
[INFO] [stderr]   Downloaded sled v0.34.7
[INFO] [stderr]   Downloaded csv v1.3.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] c1f63dcee84244dae62f75ca1e78bd363ed65fea7c809622136a48558a272322
[INFO] running `Command { std: "docker" "start" "-a" "c1f63dcee84244dae62f75ca1e78bd363ed65fea7c809622136a48558a272322", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c1f63dcee84244dae62f75ca1e78bd363ed65fea7c809622136a48558a272322", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c1f63dcee84244dae62f75ca1e78bd363ed65fea7c809622136a48558a272322", kill_on_drop: false }`
[INFO] [stdout] c1f63dcee84244dae62f75ca1e78bd363ed65fea7c809622136a48558a272322
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 54d5fe9918cda6436ffc71aeaa7619c9346a6ca99b9cf422937cb784353ffb53
[INFO] running `Command { std: "docker" "start" "-a" "54d5fe9918cda6436ffc71aeaa7619c9346a6ca99b9cf422937cb784353ffb53", kill_on_drop: false }`
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling syn v2.0.104
[INFO] [stderr]    Compiling mio v1.0.4
[INFO] [stderr]    Compiling socket2 v0.6.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.5
[INFO] [stderr]    Compiling tracing-core v0.1.34
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling find-msvc-tools v0.1.5
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling parking_lot_core v0.9.11
[INFO] [stderr]    Compiling itertools v0.10.5
[INFO] [stderr]    Compiling parking_lot v0.12.4
[INFO] [stderr]    Compiling cc v1.2.49
[INFO] [stderr]    Compiling rustversion v1.0.21
[INFO] [stderr]    Compiling allocator-api2 v0.2.21
[INFO] [stderr]    Compiling libc v0.2.174
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling http v0.2.12
[INFO] [stderr]    Compiling hashbrown v0.15.4
[INFO] [stderr]    Compiling rustix v1.0.8
[INFO] [stderr]    Compiling getrandom v0.3.3
[INFO] [stderr]    Compiling linux-raw-sys v0.9.4
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling prettyplease v0.2.36
[INFO] [stderr]    Compiling untrusted v0.9.0
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling bytes v1.10.1
[INFO] [stderr]    Compiling thiserror v2.0.12
[INFO] [stderr]    Compiling slab v0.4.11
[INFO] [stderr]    Compiling indexmap v2.10.0
[INFO] [stderr]    Compiling http-body v0.4.6
[INFO] [stderr]    Compiling indexmap v1.9.3
[INFO] [stderr]    Compiling fixedbitset v0.4.2
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stderr]    Compiling regex-automata v0.4.9
[INFO] [stderr]    Compiling petgraph v0.6.5
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling tempfile v3.20.0
[INFO] [stderr]    Compiling axum-core v0.3.4
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling rustls v0.21.12
[INFO] [stderr]    Compiling ucd-trie v0.1.7
[INFO] [stderr]    Compiling hashbrown v0.12.3
[INFO] [stderr]    Compiling parking_lot_core v0.8.6
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling multimap v0.10.1
[INFO] [stderr]    Compiling rustix v0.38.44
[INFO] [stderr]    Compiling axum v0.6.20
[INFO] [stderr]    Compiling instant v0.1.13
[INFO] [stderr]    Compiling powerfmt v0.2.0
[INFO] [stderr]    Compiling linux-raw-sys v0.4.15
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling deranged v0.5.5
[INFO] [stderr]    Compiling nibble_vec v0.1.0
[INFO] [stderr]    Compiling num-conv v0.1.0
[INFO] [stderr]    Compiling sct v0.7.1
[INFO] [stderr]    Compiling rustls-webpki v0.101.7
[INFO] [stderr]    Compiling matchit v0.7.3
[INFO] [stderr]    Compiling time-core v0.1.6
[INFO] [stderr]    Compiling tokio-macros v2.5.0
[INFO] [stderr]    Compiling serde_derive v1.0.219
[INFO] [stderr]    Compiling prost-derive v0.12.6
[INFO] [stderr]    Compiling tokio v1.47.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.30
[INFO] [stderr]    Compiling thiserror-impl v2.0.12
[INFO] [stderr]    Compiling pin-project-internal v1.1.10
[INFO] [stderr]    Compiling prost v0.12.6
[INFO] [stderr]    Compiling prost-types v0.12.6
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling pin-project v1.1.10
[INFO] [stderr]    Compiling async-trait v0.1.88
[INFO] [stderr]    Compiling pest v2.8.1
[INFO] [stderr]    Compiling async-stream-impl v0.3.6
[INFO] [stderr]    Compiling virtue v0.0.18
[INFO] [stderr]    Compiling prost-build v0.12.6
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling humantime v2.2.0
[INFO] [stderr]    Compiling pest_meta v2.8.1
[INFO] [stderr]    Compiling tonic-build v0.10.2
[INFO] [stderr]    Compiling endian-type v0.1.2
[INFO] [stderr]    Compiling radix_trie v0.2.1
[INFO] [stderr]    Compiling pest_generator v2.8.1
[INFO] [stderr]    Compiling fxhash v0.2.1
[INFO] [stderr]    Compiling bincode_derive v2.0.1
[INFO] [stderr]    Compiling chronos v1.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling async-stream v0.3.6
[INFO] [stderr]    Compiling fd-lock v3.0.13
[INFO] [stderr]    Compiling time v0.3.44
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling clap_derive v4.5.41
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling clap_builder v4.5.41
[INFO] [stderr]    Compiling tokio-util v0.7.15
[INFO] [stderr]    Compiling tokio-io-timeout v1.2.1
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling serde_json v1.0.141
[INFO] [stderr]    Compiling tokio-rustls v0.24.1
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling h2 v0.3.27
[INFO] [stderr]    Compiling tower v0.4.13
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling parking_lot v0.11.2
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.18
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling nix v0.26.4
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling is-terminal v0.4.16
[INFO] [stderr]    Compiling fs2 v0.4.3
[INFO] [stderr]    Compiling csv-core v0.1.12
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling spin v0.10.0
[INFO] [stderr]    Compiling twox-hash v2.1.2
[INFO] [stderr]    Compiling unty v0.0.4
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling unicode-width v0.1.14
[INFO] [stderr]    Compiling home v0.5.11
[INFO] [stderr]    Compiling termcolor v1.4.1
[INFO] [stderr]    Compiling unicode-segmentation v1.12.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.20
[INFO] [stderr]    Compiling clap v4.5.41
[INFO] [stderr]    Compiling env_logger v0.10.2
[INFO] [stderr]    Compiling lz4_flex v0.11.5
[INFO] [stderr]    Compiling bincode v2.0.1
[INFO] [stderr]    Compiling uhlc v0.8.2
[INFO] [stderr]    Compiling csv v1.3.1
[INFO] [stderr]    Compiling rustyline v12.0.0
[INFO] [stderr]    Compiling sled v0.34.7
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling pest_derive v2.8.1
[INFO] [stderr]    Compiling hyper v0.14.32
[INFO] [stderr]    Compiling lru v0.12.5
[INFO] [stderr]    Compiling hyper-timeout v0.4.1
[INFO] [stderr]    Compiling tonic v0.10.2
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 2m 31s
[INFO] running `Command { std: "docker" "inspect" "54d5fe9918cda6436ffc71aeaa7619c9346a6ca99b9cf422937cb784353ffb53", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "54d5fe9918cda6436ffc71aeaa7619c9346a6ca99b9cf422937cb784353ffb53", kill_on_drop: false }`
[INFO] [stdout] 54d5fe9918cda6436ffc71aeaa7619c9346a6ca99b9cf422937cb784353ffb53
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2b0287ac4f25612d46d177ee2adb5a1a15496e47cc2e00f1dedbc7c8c00b3897
[INFO] running `Command { std: "docker" "start" "-a" "2b0287ac4f25612d46d177ee2adb5a1a15496e47cc2e00f1dedbc7c8c00b3897", kill_on_drop: false }`
[INFO] [stderr]    Compiling zerocopy v0.8.26
[INFO] [stderr]    Compiling zerocopy-derive v0.8.26
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling rustix v1.0.8
[INFO] [stderr]    Compiling linux-raw-sys v0.9.4
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling rayon-core v1.13.0
[INFO] [stderr]    Compiling crossbeam-deque v0.8.6
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling plotters-backend v0.3.7
[INFO] [stderr]    Compiling ciborium-io v0.2.2
[INFO] [stderr]    Compiling itertools v0.10.5
[INFO] [stderr]    Compiling plotters-svg v0.3.7
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling cast v0.3.0
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling futures-io v0.3.31
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling rayon v1.11.0
[INFO] [stderr]    Compiling plotters v0.3.7
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling walkdir v2.5.0
[INFO] [stderr]    Compiling criterion-plot v0.5.0
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling anes v0.1.6
[INFO] [stderr]    Compiling oorandom v11.1.5
[INFO] [stderr]    Compiling unarray v0.1.4
[INFO] [stderr]    Compiling h2 v0.3.27
[INFO] [stderr]    Compiling axum-core v0.3.4
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling tempfile v3.20.0
[INFO] [stderr]    Compiling prost-build v0.12.6
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling tonic-build v0.10.2
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling criterion v0.5.1
[INFO] [stderr]    Compiling chronos v1.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling proptest v1.9.0
[INFO] [stderr]    Compiling tower v0.4.13
[INFO] [stderr]    Compiling uhlc v0.8.2
[INFO] [stderr]    Compiling hyper v0.14.32
[INFO] [stderr]    Compiling axum v0.6.20
[INFO] [stderr]    Compiling hyper-timeout v0.4.1
[INFO] [stderr]    Compiling tonic v0.10.2
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1m 59s
[INFO] running `Command { std: "docker" "inspect" "2b0287ac4f25612d46d177ee2adb5a1a15496e47cc2e00f1dedbc7c8c00b3897", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2b0287ac4f25612d46d177ee2adb5a1a15496e47cc2e00f1dedbc7c8c00b3897", kill_on_drop: false }`
[INFO] [stdout] 2b0287ac4f25612d46d177ee2adb5a1a15496e47cc2e00f1dedbc7c8c00b3897
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+7704328ba5ae8d6ce0ac303c9d5a1a1605906766" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 05a7e8beed49e1b0dca4a4e47ece5b53df1c4f739c6c853cbe8738d9ca3cb52a
[INFO] running `Command { std: "docker" "start" "-a" "05a7e8beed49e1b0dca4a4e47ece5b53df1c4f739c6c853cbe8738d9ca3cb52a", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.54s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/chronos-14d03a594eaeed8e)
[INFO] [stdout] 
[INFO] [stdout] running 53 tests
[INFO] [stdout] test common::timestamp::tests::merge_with_remote_timestamp_produces_newer_ts ... ok
[INFO] [stdout] test config::tests::storage_config_default_uses_sled_data_dir ... ok
[INFO] [stdout] test executor::tests::window_agg_ignores_data_older_than_1h ... ok
[INFO] [stdout] test network::http_admin::tests::health_reports_ok_with_initial_follower_state ... ok
[INFO] [stdout] test network::http_admin::tests::ingest_returns_429_when_inflight_limit_exceeded ... ok
[INFO] [stdout] test network::offline_queue::tests::enqueue_and_drain_respects_max_size_drop_oldest ... ok
[INFO] [stdout] test network::offline_queue::tests::enqueue_rejects_when_full_and_strategy_reject_new ... ok
[INFO] [stdout] test executor::tests::window_agg_basic_avg_last_1h ... ok
[INFO] [stdout] test network::http_admin::tests::ingest_rejects_payload_too_large ... ok
[INFO] [stdout] test network::server::tests::authenticate_request_allows_anonymous_when_no_tokens_set ... ok
[INFO] [stdout] test network::server::tests::authenticate_request_readonly_rejects_write_queries ... ok
[INFO] [stdout] test network::server::tests::authenticate_request_admin_token_allows_write ... ok
[INFO] [stdout] test network::http_admin::tests::metrics_exports_expected_keys ... ok
[INFO] [stdout] test network::server::tests::sync_status_server_returns_current_status ... ok
[INFO] [stdout] test network::sync_worker::tests::compact_lww_ops_uses_timestamp_not_id ... ok
[INFO] [stdout] test network::sync_worker::tests::compact_lww_ops_keeps_latest_per_key ... ok
[INFO] [stdout] test network::tests::from_tonic_status_maps_to_rpc_error ... ok
[INFO] [stdout] test parser::tests::test_create_index ... ok
[INFO] [stdout] test parser::tests::test_create_table ... ok
[INFO] [stdout] test parser::tests::test_delete ... ok
[INFO] [stdout] test parser::tests::test_insert ... ok
[INFO] [stdout] test parser::tests::test_select ... ok
[INFO] [stdout] test parser::tests::test_select_avg_1h ... ok
[INFO] [stdout] test network::tests::display_includes_error_kind ... ok
[INFO] [stdout] test parser::tests::test_select_avg_7d ... ok
[INFO] [stdout] test config::tests::config_default_values_are_sensible ... ok
[INFO] [stdout] test parser::tests::test_select_avg_24h ... ok
[INFO] [stdout] test parser::tests::test_select_count_star ... ok
[INFO] [stdout] test parser::tests::test_select_group_count_simple ... ok
[INFO] [stdout] test parser::tests::test_select_join_using ... ok
[INFO] [stdout] test parser::tests::test_select_sum_with_where ... ok
[INFO] [stdout] test common::timestamp::tests::monotonic_timestamps_increase ... ok
[INFO] [stdout] test network::metrics::tests::record_and_snapshot_move_counters_forward ... ok
[INFO] [stdout] test network::server::tests::health_server_reports_connectivity_state ... ok
[INFO] [stdout] test parser::tests::test_select_group_count_with_where ... ok
[INFO] [stdout] test parser::tests::test_update ... ok
[INFO] [stdout] test raft::log::tests::append_and_persist_entries_across_restarts ... ok
[INFO] [stdout] test raft::node::tests::abort_pending_commands_cancels_waiters ... ok
[INFO] [stdout] test storage::compression::chimp::tests::roundtrip_simple_series ... ok
[INFO] [stdout] test raft::node::tests::can_serve_read_locally_false_when_not_leader_or_no_lease ... ok
[INFO] [stdout] test raft::log::tests::new_initializes_dummy_entry ... ok
[INFO] [stdout] test raft::node::tests::can_serve_read_locally_false_when_lease_expired ... ok
[INFO] [stdout] test raft::node::tests::start_election_single_node_becomes_leader_and_sets_lease ... ok
[INFO] [stdout] test raft::log::tests::truncate_discards_entries_and_rewrites_log ... ok
[INFO] [stdout] test parser::tests::test_select_count_column_with_where ... ok
[INFO] [stdout] test storage::wal::tests::wal_entry_roundtrip_bincode ... ok
[INFO] [stdout] test storage::sled_engine::tests::test_sled_create_table ... ok
[INFO] [stdout] test storage::sled_engine::tests::test_sled_insert_query ... ok
[INFO] [stdout] test storage::offline_queue::tests::enqueue_and_drain_persistent_queue ... ok
[INFO] [stdout] test storage::sled_engine::tests::test_sled_indexed_query ... ok
[INFO] [stdout] test storage::sled_engine::tests::test_sled_indexed_delete ... ok
[INFO] [stdout] test embedded::tests::embedded_create_insert_select ... ok
[INFO] [stdout] test storage::sled_engine::tests::test_sled_ttl_cleanup_with_index ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.49s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/chronos-a84870830d51e852)
[INFO] [stderr]      Running tests/chimp_integration.rs (/opt/rustwide/target/debug/deps/chimp_integration-04c49a99947e3fa8)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test chimp_shadow_series_written_and_decodes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.20s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/connectivity_monitor.rs (/opt/rustwide/target/debug/deps/connectivity_monitor-cb8cbc0937349cb0)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test connectivity_check_once_returns_false_for_unreachable_target ... ok
[INFO] [stdout] test connectivity_check_once_returns_true_for_healthy_server ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/raft_cluster.rs (/opt/rustwide/target/debug/deps/raft_cluster-5ad62b6fd41130bd)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test three_node_cluster_soak_and_chaos_smoke ... ignored
[INFO] [stdout] 2026-01-24T18:25:41.90927674Z  INFO chronos: Starting Chronos node node1 at 127.0.0.1:22163
[INFO] [stdout] 2026-01-24T18:25:41.90961078Z  INFO chronos: Added peer: node2 at 127.0.0.1:22164
[INFO] [stdout] 2026-01-24T18:25:41.90965041Z  INFO chronos: Added peer: node3 at 127.0.0.1:22165
[INFO] [stdout] 2026-01-24T18:25:41.90971662Z  INFO chronos: Starting Chronos node node2 at 127.0.0.1:26098
[INFO] [stdout] 2026-01-24T18:25:41.90997844Z  INFO chronos: Added peer: node1 at 127.0.0.1:26097
[INFO] [stdout] 2026-01-24T18:25:41.91002083Z  INFO chronos: Added peer: node3 at 127.0.0.1:26099
[INFO] [stdout] 2026-01-24T18:25:41.91247939Z  INFO chronos: Starting Chronos node node1 at 127.0.0.1:26097
[INFO] [stdout] 2026-01-24T18:25:41.91266905Z  INFO chronos: Added peer: node2 at 127.0.0.1:26098
[INFO] [stdout] 2026-01-24T18:25:41.91270314Z  INFO chronos: Added peer: node3 at 127.0.0.1:26099
[INFO] [stdout] 2026-01-24T18:25:41.9141128Z  INFO chronos: Starting Chronos node node3 at 127.0.0.1:22165
[INFO] [stdout] 2026-01-24T18:25:41.91430524Z  INFO chronos: Added peer: node1 at 127.0.0.1:22163
[INFO] [stdout] 2026-01-24T18:25:41.91433611Z  INFO chronos: Added peer: node2 at 127.0.0.1:22164
[INFO] [stdout] 2026-01-24T18:25:41.91512802Z  INFO chronos: Starting Chronos node node1 at 127.0.0.1:22750
[INFO] [stdout] 2026-01-24T18:25:41.91543089Z  INFO chronos: Added peer: node2 at 127.0.0.1:22751
[INFO] [stdout] 2026-01-24T18:25:41.91547118Z  INFO chronos: Added peer: node3 at 127.0.0.1:22752
[INFO] [stdout] 2026-01-24T18:25:41.91775284Z  INFO chronos: Starting Chronos node node3 at 127.0.0.1:26099
[INFO] [stdout] 2026-01-24T18:25:41.91791739Z  INFO chronos: Added peer: node1 at 127.0.0.1:26097
[INFO] [stdout] 2026-01-24T18:25:41.91794408Z  INFO chronos: Added peer: node2 at 127.0.0.1:26098
[INFO] [stdout] 2026-01-24T18:25:41.91899221Z  INFO chronos: Starting Chronos node node2 at 127.0.0.1:22751
[INFO] [stdout] 2026-01-24T18:25:41.91920942Z  INFO chronos: Added peer: node1 at 127.0.0.1:22750
[INFO] [stdout] 2026-01-24T18:25:41.91925367Z  INFO chronos: Added peer: node3 at 127.0.0.1:22752
[INFO] [stdout] 2026-01-24T18:25:41.92077462Z  INFO chronos: Starting Chronos node node2 at 127.0.0.1:22164
[INFO] [stdout] 2026-01-24T18:25:41.92096722Z  INFO chronos: Added peer: node1 at 127.0.0.1:22163
[INFO] [stdout] 2026-01-24T18:25:41.92099901Z  INFO chronos: Added peer: node3 at 127.0.0.1:22165
[INFO] [stdout] 2026-01-24T18:25:41.92140384Z  INFO chronos: Starting Chronos node node3 at 127.0.0.1:22752
[INFO] [stdout] 2026-01-24T18:25:41.92159056Z  INFO chronos: Added peer: node1 at 127.0.0.1:22750
[INFO] [stdout] 2026-01-24T18:25:41.92162764Z  INFO chronos: Added peer: node2 at 127.0.0.1:22751
[INFO] [stdout] 2026-01-24T18:25:41.954844801Z  INFO chronos: Starting Chronos node node1 at 127.0.0.1:21174
[INFO] [stdout] 2026-01-24T18:25:41.955252381Z  INFO chronos: Added peer: node2 at 127.0.0.1:21175
[INFO] [stdout] 2026-01-24T18:25:41.955309491Z  INFO chronos: Added peer: node3 at 127.0.0.1:21176
[INFO] [stdout] 2026-01-24T18:25:41.974049222Z  INFO chronos: Starting Chronos node node2 at 127.0.0.1:21175
[INFO] [stdout] 2026-01-24T18:25:41.995672862Z  INFO chronos: Starting Chronos node node3 at 127.0.0.1:21176
[INFO] [stdout] 2026-01-24T18:25:41.996041062Z  INFO chronos: Added peer: node1 at 127.0.0.1:21174
[INFO] [stdout] 2026-01-24T18:25:41.996143472Z  INFO chronos: Added peer: node3 at 127.0.0.1:21176
[INFO] [stdout] 2026-01-24T18:25:42.014549752Z  INFO chronos: Added peer: node1 at 127.0.0.1:21174
[INFO] [stdout] 2026-01-24T18:25:42.014688323Z  INFO chronos: Added peer: node2 at 127.0.0.1:21175
[INFO] [stdout] 2026-01-24T18:25:42.438346423Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpRvnimb/cluster/node3"
[INFO] [stdout] 2026-01-24T18:25:42.441727183Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpDvNBjL/cluster/node3"
[INFO] [stdout] 2026-01-24T18:25:42.477647974Z  INFO chronos: gRPC server listening on 127.0.0.1:22165
[INFO] [stdout] 2026-01-24T18:25:42.489762744Z  INFO chronos: gRPC server listening on 127.0.0.1:26099
[INFO] [stdout] 2026-01-24T18:25:42.606069947Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpDvNBjL/cluster/node2"
[INFO] [stdout] 2026-01-24T18:25:42.617767817Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpHojB7s/cluster/node2"
[INFO] [stdout] 2026-01-24T18:25:42.627495187Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpRvnimb/cluster/node1"
[INFO] [stdout] 2026-01-24T18:25:42.631579958Z  INFO chronos: gRPC server listening on 127.0.0.1:26098
[INFO] [stdout] 2026-01-24T18:25:42.636853508Z  INFO chronos: gRPC server listening on 127.0.0.1:22163
[INFO] [stdout] 2026-01-24T18:25:42.638287738Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpHojB7s/cluster/node3"
[INFO] [stdout] 2026-01-24T18:25:42.665432119Z  INFO chronos: gRPC server listening on 127.0.0.1:22752
[INFO] [stdout] 2026-01-24T18:25:42.671822399Z  INFO chronos: gRPC server listening on 127.0.0.1:22751
[INFO] [stdout] 2026-01-24T18:25:42.820383432Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpJT78VJ/cluster/node2"
[INFO] [stdout] 2026-01-24T18:25:42.823343212Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpDvNBjL/cluster/node1"
[INFO] [stdout] 2026-01-24T18:25:42.864675933Z  INFO chronos: gRPC server listening on 127.0.0.1:26097
[INFO] [stdout] 2026-01-24T18:25:42.870060153Z  INFO chronos: gRPC server listening on 127.0.0.1:21175
[INFO] [stdout] 2026-01-24T18:25:42.893423044Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpHojB7s/cluster/node1"
[INFO] [stdout] 2026-01-24T18:25:42.916778955Z  INFO chronos: gRPC server listening on 127.0.0.1:22750
[INFO] [stdout] 2026-01-24T18:25:42.934358695Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpJT78VJ/cluster/node1"
[INFO] [stdout] 2026-01-24T18:25:42.936431985Z  INFO chronos: gRPC server listening on 127.0.0.1:21174
[INFO] [stdout] 2026-01-24T18:25:42.936488705Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpRvnimb/cluster/node2"
[INFO] [stdout] 2026-01-24T18:25:42.942580135Z  INFO chronos: gRPC server listening on 127.0.0.1:22164
[INFO] [stdout] 2026-01-24T18:25:42.947834525Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpJT78VJ/cluster/node3"
[INFO] [stdout] 2026-01-24T18:25:42.966343536Z  INFO chronos: gRPC server listening on 127.0.0.1:21176
[INFO] [stdout] 2026-01-24T18:25:44.735442289Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:25:44.743399929Z  INFO chronos::raft::node: Node node1 becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:25:44.803442871Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:25:44.811789231Z  INFO chronos::raft::node: Node node3 becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:25:44.926390094Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:44.926457944Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.926493804Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.926683554Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:44.926735814Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:44.927867114Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:44.927917844Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.927952244Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.928118914Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:44.928199914Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:44.932731584Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:44.932802974Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.932840814Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.933023384Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:44.933066994Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:44.934279074Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:44.934323264Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:44.935037914Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:25:44.939714894Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:44.939776994Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.939809904Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.939984964Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:44.940032494Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:44.940995884Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:44.941042034Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.941424034Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.941555704Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:44.941590894Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:44.942083044Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:44.942131914Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.942186454Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.942359134Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:44.942417544Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:44.943704244Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:44.943751584Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.943782844Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.943919124Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:44.943956784Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:44.944321914Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:44.944354924Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.944379484Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.944496784Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:44.944523744Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:44.944908304Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:44.944942704Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.944970004Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.945091114Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:44.945125144Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:44.946771615Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:44.946812935Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.946840584Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:44.946969435Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:44.946998164Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:44.954858904Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:44.954919695Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:44.955422845Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:25:44.957881875Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:44.957922925Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (1, 10.0);
[INFO] [stdout] 2026-01-24T18:25:44.957956285Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=INSERT INTO sensors (id, temperature) VALUES (1, 10.0);
[INFO] [stdout] 2026-01-24T18:25:44.958094895Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:44.958129955Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:44.960437865Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:25:44.960817795Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:25:44.961295805Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:44.961335805Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:44.963786355Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:44.963825305Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (2, 20.0);
[INFO] [stdout] 2026-01-24T18:25:44.963852995Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=INSERT INTO sensors (id, temperature) VALUES (2, 20.0);
[INFO] [stdout] 2026-01-24T18:25:44.963970495Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:44.964000465Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:44.967639375Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:44.967684155Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:44.969943695Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:44.969978655Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:25:44.970005555Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:25:44.970254635Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:44.970289165Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:44.970402195Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:44.970551675Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:45.116404089Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:25:45.16409105Z  INFO chronos::raft::node: Node node2 becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:25:45.18590716Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:25:45.19144555Z  INFO chronos::raft::node: Node node2 becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:25:45.448147917Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:45.448235247Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.448270427Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.448406837Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:45.448443227Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:45.450862117Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:45.450904367Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.450930047Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.451026477Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:45.451057637Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:45.451572837Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:45.451619417Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.451656037Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.451761587Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:45.451798217Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:45.453651897Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:45.453700187Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.453734117Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.453833087Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:45.453873947Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:45.456569967Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:45.456606167Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:45.457186487Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:25:45.457801057Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:45.457834387Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:45.458297177Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:25:45.460174497Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:45.460217887Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (42, 42.0);
[INFO] [stdout] 2026-01-24T18:25:45.460248027Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=INSERT INTO sensors (id, temperature) VALUES (42, 42.0);
[INFO] [stdout] 2026-01-24T18:25:45.460390897Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:45.460425447Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:45.460741677Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:45.460780247Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (1, 10.0);
[INFO] [stdout] 2026-01-24T18:25:45.460808127Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=INSERT INTO sensors (id, temperature) VALUES (1, 10.0);
[INFO] [stdout] 2026-01-24T18:25:45.460927467Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:45.460978987Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:45.463486137Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:25:45.463566427Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:25:45.464245327Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:45.464290537Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:45.465039937Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:25:45.465291447Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:25:45.465854087Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:45.465888557Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:45.466690757Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:45.466726447Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (2, 20.0);
[INFO] [stdout] 2026-01-24T18:25:45.466755707Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=INSERT INTO sensors (id, temperature) VALUES (2, 20.0);
[INFO] [stdout] 2026-01-24T18:25:45.466875107Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:45.466906037Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:45.470596987Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:45.470638197Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:45.470963257Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:45.471009807Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.471040907Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.471170687Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:45.471205667Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:45.473932357Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:45.473969277Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.473997917Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.474099937Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:45.474133547Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:45.97998024Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:45.98004105Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.98007624Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.98021643Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:45.98025398Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:45.98344525Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:45.98349023Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.98352299Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:45.98363057Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:45.9836652Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:46.473553192Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:46.473624792Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (3, 30.0);
[INFO] [stdout] 2026-01-24T18:25:46.473658282Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=INSERT INTO sensors (id, temperature) VALUES (3, 30.0);
[INFO] [stdout] 2026-01-24T18:25:46.473806982Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:46.473845342Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:46.477182682Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:46.477226772Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:46.479568952Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:46.479641432Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (4, 40.0);
[INFO] [stdout] 2026-01-24T18:25:46.479676582Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=INSERT INTO sensors (id, temperature) VALUES (4, 40.0);
[INFO] [stdout] 2026-01-24T18:25:46.479823712Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:46.479861032Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:46.483474262Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:46.483529802Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:46.485907322Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:46.485959422Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (5, 50.0);
[INFO] [stdout] 2026-01-24T18:25:46.485993192Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=INSERT INTO sensors (id, temperature) VALUES (5, 50.0);
[INFO] [stdout] 2026-01-24T18:25:46.486110442Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:46.486145432Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:46.488449432Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:46.488491462Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:46.488522042Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:46.488625142Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:46.488658102Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:46.489881062Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:46.489929952Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:46.491572712Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:46.491608252Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:46.491636012Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:46.491729252Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:46.491758552Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:46.495572162Z  INFO chronos: Starting Chronos node node1 at 127.0.0.1:21174
[INFO] [stdout] 2026-01-24T18:25:46.495764502Z  INFO chronos: Added peer: node2 at 127.0.0.1:21175
[INFO] [stdout] 2026-01-24T18:25:46.495803072Z  INFO chronos: Added peer: node3 at 127.0.0.1:21176
[INFO] [stdout] 2026-01-24T18:25:46.563461574Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpJT78VJ/cluster/node1"
[INFO] [stdout] 2026-01-24T18:25:46.569368894Z  INFO chronos: gRPC server listening on 127.0.0.1:21174
[INFO] [stdout] 2026-01-24T18:25:46.996777985Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:46.996839165Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:46.996875635Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:46.997013545Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:46.997050165Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:46.999991015Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:47.000027975Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:47.000060825Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:47.000176275Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:47.000213115Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:47.254316081Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:25:47.260272831Z  INFO chronos::raft::node: Node node1 becoming leader for term 2
[INFO] [stdout] 2026-01-24T18:25:47.440854776Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:25:47.446768996Z  INFO chronos::raft::node: Node node3 becoming leader for term 2
[INFO] [stdout] 2026-01-24T18:25:47.504465517Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:47.504792627Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:47.504843777Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:47.504978527Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:47.505012097Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:25:47.507641217Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:47.507687947Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:47.507720467Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:47.507827397Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:47.507877117Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:47.510513947Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:47.510866967Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:47.514631637Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:47.514734777Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:25:47.514769367Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:25:47.514937597Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:47.514982597Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:47.515081407Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:47.515110487Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] test three_node_cluster_failover_mttr_under_60s ... ok
[INFO] [stdout] 2026-01-24T18:25:47.568430639Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:25:47.941455538Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:47.941520238Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (99, 99.0);
[INFO] [stdout] 2026-01-24T18:25:47.941556268Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=INSERT INTO sensors (id, temperature) VALUES (99, 99.0);
[INFO] [stdout] 2026-01-24T18:25:47.941746338Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:47.941782488Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] test three_node_cluster_isolated_node_rejects_writes ... ok
[INFO] [stdout] 2026-01-24T18:25:49.976425228Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:49.976484288Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:49.976515128Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:49.976637868Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:49.976678578Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:49.981778898Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:49.981830328Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:49.984142448Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:49.984196768Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:25:49.984232778Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:25:49.984402268Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:49.984435838Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:49.984538948Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:49.984566628Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:49.987808538Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:49.987852098Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:25:49.987881718Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:25:49.988042448Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:49.988077868Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:49.988233638Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:49.988281908Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:49.994347568Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:49.994405438Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:25:49.994439568Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:25:49.994619638Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:49.994654768Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] test three_node_cluster_write_and_failover_persists_data ... ok
[INFO] [stdout] 2026-01-24T18:25:51.508547655Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:51.508621475Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:51.508656825Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:51.508833225Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:51.508867795Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stderr]      Running tests/raft_log_proptest.rs (/opt/rustwide/target/debug/deps/raft_log_proptest-ca4841f39c611490)
[INFO] [stdout] 2026-01-24T18:25:51.515741635Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:51.515799635Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:51.515831425Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:51.515957945Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:51.515993875Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:51.521838256Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:51.521890556Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:51.525684856Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:51.525729236Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:25:51.525762566Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:25:51.525931266Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:51.525963626Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:51.526111996Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:51.526140506Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] test three_node_cluster_follower_restart_preserves_writes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 9.64s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test raft_log_preserves_prefix_and_indices ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.32s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/snapshot.rs (/opt/rustwide/target/debug/deps/snapshot-5f9600a72555b5eb)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test snapshot_backup_restore_basic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/sync_lww.rs (/opt/rustwide/target/debug/deps/sync_lww-447de8c5a89cfa34)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] 2026-01-24T18:25:53.090449154Z  INFO chronos: Starting Chronos node cloud at 127.0.0.1:24680
[INFO] [stdout] 2026-01-24T18:25:53.090523474Z  INFO chronos: --clean flag detected, removing data directory: /tmp/.tmpvpzBmM/sync_cluster_basic/cloud
[INFO] [stdout] 2026-01-24T18:25:53.170555316Z  INFO chronos: Starting Chronos node cloud at 127.0.0.1:25920
[INFO] [stdout] 2026-01-24T18:25:53.170642066Z  INFO chronos: --clean flag detected, removing data directory: /tmp/.tmpZmEjZ1/sync_cluster_lww/cloud
[INFO] [stdout] 2026-01-24T18:25:53.240478698Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpZmEjZ1/sync_cluster_lww/cloud"
[INFO] [stdout] 2026-01-24T18:25:53.269289018Z  INFO chronos: gRPC server listening on 127.0.0.1:25920
[INFO] [stdout] 2026-01-24T18:25:53.32171561Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpvpzBmM/sync_cluster_basic/cloud"
[INFO] [stdout] 2026-01-24T18:25:53.3259518Z  INFO chronos: gRPC server listening on 127.0.0.1:24680
[INFO] [stdout] test sync_mixed_batch_applies_only_newer_per_key ... ok
[INFO] [stdout] test sync_delete_propagates_row_removal ... ok
[INFO] [stdout] test sync_idempotent_replay_same_batch ... ok
[INFO] [stdout] test sync_multi_edge_has_independent_cursors ... ok
[INFO] [stdout] test sync_restart_persists_cursor_and_skips_old_ids ... ok
[INFO] [stdout] test sync_large_batch_is_idempotent_and_advances_cursor ... ok
[INFO] [stdout] 2026-01-24T18:25:55.184449355Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:25:55.184557665Z  INFO chronos::raft::node: Election won (single node), becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:25:55.184587105Z  INFO chronos::raft::node: Node cloud becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:25:55.713535218Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:25:55.713645288Z  INFO chronos::raft::node: Election won (single node), becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:25:55.713672888Z  INFO chronos::raft::node: Node cloud becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:25:56.093702228Z  INFO chronos: Starting Chronos node edge1 at 127.0.0.1:24681
[INFO] [stdout] 2026-01-24T18:25:56.093790318Z  INFO chronos: --clean flag detected, removing data directory: /tmp/.tmpvpzBmM/sync_cluster_basic/edge1
[INFO] [stdout] 2026-01-24T18:25:56.143332219Z  INFO chronos: Starting Chronos node edge1 at 127.0.0.1:25921
[INFO] [stdout] 2026-01-24T18:25:56.143404089Z  INFO chronos: --clean flag detected, removing data directory: /tmp/.tmpZmEjZ1/sync_cluster_lww/edge1
[INFO] [stdout] 2026-01-24T18:25:56.159635629Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpvpzBmM/sync_cluster_basic/edge1"
[INFO] [stdout] 2026-01-24T18:25:56.163515969Z  INFO chronos: gRPC server listening on 127.0.0.1:24681
[INFO] [stdout] 2026-01-24T18:25:56.313487273Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpZmEjZ1/sync_cluster_lww/edge1"
[INFO] [stdout] 2026-01-24T18:25:56.333824214Z  INFO chronos: gRPC server listening on 127.0.0.1:25921
[INFO] [stdout] 2026-01-24T18:25:58.725193052Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:25:58.725292962Z  INFO chronos::raft::node: Election won (single node), becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:25:58.725313432Z  INFO chronos::raft::node: Node edge1 becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:25:58.845312275Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:25:58.845802835Z  INFO chronos::raft::node: Election won (single node), becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:25:58.846396735Z  INFO chronos::raft::node: Node edge1 becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:25:59.100219911Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:59.100282721Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (sensor_id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:59.100316831Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (sensor_id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:59.100503821Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:59.100545921Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:59.100773401Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:59.100803481Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:59.101385331Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:25:59.102658171Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:59.102688042Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (sensor_id, temperature) VALUES (1, 10);
[INFO] [stdout] 2026-01-24T18:25:59.102710462Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=INSERT INTO sensors (sensor_id, temperature) VALUES (1, 10);
[INFO] [stdout] 2026-01-24T18:25:59.102813791Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:59.102840082Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:59.103021742Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:59.103046051Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:59.104596951Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:59.104638131Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (sensor_id, temperature) VALUES (2, 20);
[INFO] [stdout] 2026-01-24T18:25:59.104666691Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=INSERT INTO sensors (sensor_id, temperature) VALUES (2, 20);
[INFO] [stdout] 2026-01-24T18:25:59.104772451Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:59.104812131Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:59.105002671Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:59.105036622Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:59.106332631Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:59.106404582Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (sensor_id, temperature) VALUES (3, 30);
[INFO] [stdout] 2026-01-24T18:25:59.106456042Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=INSERT INTO sensors (sensor_id, temperature) VALUES (3, 30);
[INFO] [stdout] 2026-01-24T18:25:59.106632642Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:59.106704331Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:59.106900482Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:59.106974002Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:59.146602442Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:59.146672312Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (sensor_id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:59.146711492Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (sensor_id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:25:59.146897752Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:59.147035963Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:59.147295412Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:59.147323783Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:59.148045152Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:25:59.149231152Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:25:59.149262383Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (sensor_id, temperature) VALUES (1, 10.0);
[INFO] [stdout] 2026-01-24T18:25:59.149292752Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=INSERT INTO sensors (sensor_id, temperature) VALUES (1, 10.0);
[INFO] [stdout] 2026-01-24T18:25:59.149420792Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:25:59.149454243Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:25:59.149677503Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:25:59.149796143Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:25:59.187528304Z  INFO chronos::network::server: SyncServer applied 3 operations from edge (skipped_lww=0, total_applied=3, total_skipped_lww=0)
[INFO] [stdout] 2026-01-24T18:25:59.188742413Z  INFO chronos::network::sync_worker: SyncWorker synced 3 operations to http://127.0.0.1:24680 (applied=3, drained=3)
[INFO] [stdout] 2026-01-24T18:25:59.477711451Z  INFO chronos::network::server: SyncServer applied 1 operations from edge (skipped_lww=0, total_applied=1, total_skipped_lww=0)
[INFO] [stdout] 2026-01-24T18:25:59.478909181Z  INFO chronos::network::sync_worker: SyncWorker synced 1 operations to http://127.0.0.1:25920 (applied=1, drained=1)
[INFO] [stdout] 2026-01-24T18:26:04.111871564Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:26:04.111971364Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:26:04.112015674Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT sensor_id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:26:04.112273304Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:26:04.112314404Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:26:04.112540934Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:26:04.112573474Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] test edge_to_cloud_sync_basic_propagates_rows ... ok
[INFO] [stdout] 2026-01-24T18:26:04.159764315Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:26:04.159834145Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: UPDATE sensors SET temperature = 30.0 WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:26:04.159870155Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=UPDATE sensors SET temperature = 30.0 WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:26:04.160064565Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:26:04.160114545Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:26:04.160412845Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:26:04.160450915Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:26:04.161400565Z  INFO chronos::storage::sled_engine: Deleted 1 rows from table 'sensors'
[INFO] [stdout] 2026-01-24T18:26:04.162661395Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:26:04.162700155Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:26:04.162729385Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:26:04.162907125Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:26:04.162950395Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:26:04.163070415Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:26:04.163100655Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:26:04.490502363Z  INFO chronos::network::server: SyncServer applied 1 operations from edge (skipped_lww=0, total_applied=2, total_skipped_lww=0)
[INFO] [stdout] 2026-01-24T18:26:04.491371403Z  INFO chronos::network::sync_worker: SyncWorker synced 1 operations to http://127.0.0.1:25920 (applied=1, drained=1)
[INFO] [stdout] 2026-01-24T18:26:09.168627728Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:26:09.168693518Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:26:09.168730008Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:26:09.169010178Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:26:09.169057408Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:26:09.169404138Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:26:09.169436868Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] test edge_to_cloud_lww_conflict_uses_latest_value ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 16.11s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/ttl.rs (/opt/rustwide/target/debug/deps/ttl-fa2f401eb851a7c6)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test ttl_basic_expiry_removes_rows ... ok
[INFO] [stdout] test ttl_does_not_affect_tables_without_ttl ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.21s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests chronos
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "05a7e8beed49e1b0dca4a4e47ece5b53df1c4f739c6c853cbe8738d9ca3cb52a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "05a7e8beed49e1b0dca4a4e47ece5b53df1c4f739c6c853cbe8738d9ca3cb52a", kill_on_drop: false }`
[INFO] [stdout] 05a7e8beed49e1b0dca4a4e47ece5b53df1c4f739c6c853cbe8738d9ca3cb52a