[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 try#a7ee93192a766fb0dafa23def7154fc03cfcfc3e for pr-146989
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2F0xReLogic%2FChronos" "/workspace/builds/worker-2-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc2/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-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/0xReLogic/Chronos on toolchain a7ee93192a766fb0dafa23def7154fc03cfcfc3e
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "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" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] dcafb899e5bd690a75a4445477c44acbda5f4e630a27e2b12fc3c910bb58589f
[INFO] running `Command { std: "docker" "start" "-a" "dcafb899e5bd690a75a4445477c44acbda5f4e630a27e2b12fc3c910bb58589f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "dcafb899e5bd690a75a4445477c44acbda5f4e630a27e2b12fc3c910bb58589f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "dcafb899e5bd690a75a4445477c44acbda5f4e630a27e2b12fc3c910bb58589f", kill_on_drop: false }`
[INFO] [stdout] dcafb899e5bd690a75a4445477c44acbda5f4e630a27e2b12fc3c910bb58589f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 5c77e99e0103fe948150c0c8df62fd2926772539bd9097ccc43c012f095b6530
[INFO] running `Command { std: "docker" "start" "-a" "5c77e99e0103fe948150c0c8df62fd2926772539bd9097ccc43c012f095b6530", 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 serde v1.0.219
[INFO] [stderr]    Compiling either v1.15.0
[INFO] [stderr]    Compiling find-msvc-tools v0.1.5
[INFO] [stderr]    Compiling itertools v0.10.5
[INFO] [stderr]    Compiling parking_lot_core v0.9.11
[INFO] [stderr]    Compiling cc v1.2.49
[INFO] [stderr]    Compiling rustversion v1.0.21
[INFO] [stderr]    Compiling parking_lot v0.12.4
[INFO] [stderr]    Compiling libc v0.2.174
[INFO] [stderr]    Compiling allocator-api2 v0.2.21
[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 rustix v1.0.8
[INFO] [stderr]    Compiling hashbrown v0.15.4
[INFO] [stderr]    Compiling getrandom v0.3.3
[INFO] [stderr]    Compiling bytes v1.10.1
[INFO] [stderr]    Compiling linux-raw-sys v0.9.4
[INFO] [stderr]    Compiling regex-syntax v0.8.5
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling slab v0.4.11
[INFO] [stderr]    Compiling untrusted v0.9.0
[INFO] [stderr]    Compiling thiserror v2.0.12
[INFO] [stderr]    Compiling prettyplease v0.2.36
[INFO] [stderr]    Compiling http-body v0.4.6
[INFO] [stderr]    Compiling indexmap v2.10.0
[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 rand v0.8.5
[INFO] [stderr]    Compiling petgraph v0.6.5
[INFO] [stderr]    Compiling axum-core v0.3.4
[INFO] [stderr]    Compiling tempfile v3.20.0
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling rustix v0.38.44
[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 multimap v0.10.1
[INFO] [stderr]    Compiling axum v0.6.20
[INFO] [stderr]    Compiling instant v0.1.13
[INFO] [stderr]    Compiling powerfmt v0.2.0
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling linux-raw-sys v0.4.15
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling deranged v0.5.5
[INFO] [stderr]    Compiling regex v1.11.1
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling nibble_vec v0.1.0
[INFO] [stderr]    Compiling matchit v0.7.3
[INFO] [stderr]    Compiling virtue v0.0.18
[INFO] [stderr]    Compiling rustls-webpki v0.101.7
[INFO] [stderr]    Compiling sct v0.7.1
[INFO] [stderr]    Compiling humantime v2.2.0
[INFO] [stderr]    Compiling tokio-macros v2.5.0
[INFO] [stderr]    Compiling serde_derive v1.0.219
[INFO] [stderr]    Compiling tracing-attributes v0.1.30
[INFO] [stderr]    Compiling prost-derive v0.12.6
[INFO] [stderr]    Compiling thiserror-impl v2.0.12
[INFO] [stderr]    Compiling pin-project-internal v1.1.10
[INFO] [stderr]    Compiling tokio v1.47.0
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling async-trait v0.1.88
[INFO] [stderr]    Compiling pin-project v1.1.10
[INFO] [stderr]    Compiling async-stream-impl v0.3.6
[INFO] [stderr]    Compiling prost v0.12.6
[INFO] [stderr]    Compiling pest v2.8.1
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling time-core v0.1.6
[INFO] [stderr]    Compiling prost-types v0.12.6
[INFO] [stderr]    Compiling num-conv v0.1.0
[INFO] [stderr]    Compiling endian-type v0.1.2
[INFO] [stderr]    Compiling radix_trie v0.2.1
[INFO] [stderr]    Compiling time v0.3.44
[INFO] [stderr]    Compiling prost-build v0.12.6
[INFO] [stderr]    Compiling pest_meta v2.8.1
[INFO] [stderr]    Compiling clap_builder v4.5.41
[INFO] [stderr]    Compiling fxhash v0.2.1
[INFO] [stderr]    Compiling tonic-build v0.10.2
[INFO] [stderr]    Compiling pest_generator v2.8.1
[INFO] [stderr]    Compiling async-stream v0.3.6
[INFO] [stderr]    Compiling tokio-util v0.7.15
[INFO] [stderr]    Compiling tokio-io-timeout v1.2.1
[INFO] [stderr]    Compiling chronos v1.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling tokio-rustls v0.24.1
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling fd-lock v3.0.13
[INFO] [stderr]    Compiling bincode_derive v2.0.1
[INFO] [stderr]    Compiling clap_derive v4.5.41
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling h2 v0.3.27
[INFO] [stderr]    Compiling tower v0.4.13
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling serde_json v1.0.141
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.18
[INFO] [stderr]    Compiling parking_lot v0.11.2
[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 unicode-segmentation v1.12.0
[INFO] [stderr]    Compiling unicode-width v0.1.14
[INFO] [stderr]    Compiling twox-hash v2.1.2
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling spin v0.10.0
[INFO] [stderr]    Compiling unty v0.0.4
[INFO] [stderr]    Compiling home v0.5.11
[INFO] [stderr]    Compiling termcolor v1.4.1
[INFO] [stderr]    Compiling uhlc v0.8.2
[INFO] [stderr]    Compiling bincode v2.0.1
[INFO] [stderr]    Compiling tracing-subscriber v0.3.20
[INFO] [stderr]    Compiling lz4_flex v0.11.5
[INFO] [stderr]    Compiling env_logger v0.10.2
[INFO] [stderr]    Compiling rustyline v12.0.0
[INFO] [stderr]    Compiling hyper v0.14.32
[INFO] [stderr]    Compiling csv v1.3.1
[INFO] [stderr]    Compiling sled v0.34.7
[INFO] [stderr]    Compiling clap v4.5.41
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling pest_derive v2.8.1
[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 59s
[INFO] running `Command { std: "docker" "inspect" "5c77e99e0103fe948150c0c8df62fd2926772539bd9097ccc43c012f095b6530", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5c77e99e0103fe948150c0c8df62fd2926772539bd9097ccc43c012f095b6530", kill_on_drop: false }`
[INFO] [stdout] 5c77e99e0103fe948150c0c8df62fd2926772539bd9097ccc43c012f095b6530
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 03f34ca400550933ace501718ebee00c421d701dcb9d2acd0198ffc96bced67b
[INFO] running `Command { std: "docker" "start" "-a" "03f34ca400550933ace501718ebee00c421d701dcb9d2acd0198ffc96bced67b", 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 bit-vec v0.8.0
[INFO] [stderr]    Compiling futures-io v0.3.31
[INFO] [stderr]    Compiling walkdir v2.5.0
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling plotters v0.3.7
[INFO] [stderr]    Compiling rayon v1.11.0
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling oorandom v11.1.5
[INFO] [stderr]    Compiling anes v0.1.6
[INFO] [stderr]    Compiling criterion-plot v0.5.0
[INFO] [stderr]    Compiling unarray v0.1.4
[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 h2 v0.3.27
[INFO] [stderr]    Compiling axum-core v0.3.4
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling tonic-build v0.10.2
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling chronos v1.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling criterion v0.5.1
[INFO] [stderr]    Compiling tower v0.4.13
[INFO] [stderr]    Compiling uhlc v0.8.2
[INFO] [stderr]    Compiling proptest v1.9.0
[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 2m 01s
[INFO] running `Command { std: "docker" "inspect" "03f34ca400550933ace501718ebee00c421d701dcb9d2acd0198ffc96bced67b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "03f34ca400550933ace501718ebee00c421d701dcb9d2acd0198ffc96bced67b", kill_on_drop: false }`
[INFO] [stdout] 03f34ca400550933ace501718ebee00c421d701dcb9d2acd0198ffc96bced67b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/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" "+a7ee93192a766fb0dafa23def7154fc03cfcfc3e" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 5fa93b4d313c7b6a95d75ec8201f6beeac56c23e25ab1e7a0a95b05eb1b29544
[INFO] running `Command { std: "docker" "start" "-a" "5fa93b4d313c7b6a95d75ec8201f6beeac56c23e25ab1e7a0a95b05eb1b29544", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.51s
[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_basic_avg_last_1h ... ok
[INFO] [stdout] test common::timestamp::tests::monotonic_timestamps_increase ... ok
[INFO] [stdout] test config::tests::config_default_values_are_sensible ... ok
[INFO] [stdout] test executor::tests::window_agg_ignores_data_older_than_1h ... ok
[INFO] [stdout] test network::offline_queue::tests::enqueue_rejects_when_full_and_strategy_reject_new ... 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::http_admin::tests::health_reports_ok_with_initial_follower_state ... ok
[INFO] [stdout] test network::offline_queue::tests::enqueue_and_drain_respects_max_size_drop_oldest ... ok
[INFO] [stdout] test network::server::tests::sync_status_server_returns_current_status ... ok
[INFO] [stdout] test network::metrics::tests::record_and_snapshot_move_counters_forward ... ok
[INFO] [stdout] test network::http_admin::tests::ingest_returns_429_when_inflight_limit_exceeded ... ok
[INFO] [stdout] test network::server::tests::authenticate_request_admin_token_allows_write ... ok
[INFO] [stdout] test network::sync_worker::tests::compact_lww_ops_uses_timestamp_not_id ... ok
[INFO] [stdout] test network::tests::from_tonic_status_maps_to_rpc_error ... ok
[INFO] [stdout] test network::sync_worker::tests::compact_lww_ops_keeps_latest_per_key ... ok
[INFO] [stdout] test network::server::tests::health_server_reports_connectivity_state ... ok
[INFO] [stdout] test network::http_admin::tests::metrics_exports_expected_keys ... 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_avg_1h ... ok
[INFO] [stdout] test parser::tests::test_select ... ok
[INFO] [stdout] test parser::tests::test_select_avg_24h ... ok
[INFO] [stdout] test parser::tests::test_select_avg_7d ... ok
[INFO] [stdout] test parser::tests::test_select_count_column_with_where ... 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_group_count_with_where ... 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 raft::log::tests::new_initializes_dummy_entry ... ok
[INFO] [stdout] test raft::node::tests::can_serve_read_locally_false_when_not_leader_or_no_lease ... ok
[INFO] [stdout] test raft::node::tests::abort_pending_commands_cancels_waiters ... ok
[INFO] [stdout] test raft::log::tests::truncate_discards_entries_and_rewrites_log ... ok
[INFO] [stdout] test raft::node::tests::start_election_single_node_becomes_leader_and_sets_lease ... ok
[INFO] [stdout] test raft::log::tests::append_and_persist_entries_across_restarts ... ok
[INFO] [stdout] test raft::node::tests::can_serve_read_locally_false_when_lease_expired ... ok
[INFO] [stdout] test storage::compression::chimp::tests::roundtrip_simple_series ... ok
[INFO] [stdout] test parser::tests::test_update ... ok
[INFO] [stdout] test network::tests::display_includes_error_kind ... ok
[INFO] [stdout] test storage::wal::tests::wal_entry_roundtrip_bincode ... ok
[INFO] [stdout] test storage::sled_engine::tests::test_sled_indexed_query ... ok
[INFO] [stdout] test storage::sled_engine::tests::test_sled_insert_query ... ok
[INFO] [stdout] test storage::sled_engine::tests::test_sled_ttl_cleanup_with_index ... ok
[INFO] [stdout] test embedded::tests::embedded_create_insert_select ... ok
[INFO] [stdout] test storage::sled_engine::tests::test_sled_create_table ... ok
[INFO] [stdout] test storage::sled_engine::tests::test_sled_indexed_delete ... ok
[INFO] [stdout] test storage::offline_queue::tests::enqueue_and_drain_persistent_queue ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 53 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.36s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/chronos-a84870830d51e852)
[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/chimp_integration.rs (/opt/rustwide/target/debug/deps/chimp_integration-04c49a99947e3fa8)
[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.15s
[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] [stderr]      Running tests/raft_cluster.rs (/opt/rustwide/target/debug/deps/raft_cluster-5ad62b6fd41130bd)
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test three_node_cluster_soak_and_chaos_smoke ... ignored
[INFO] [stdout] 2026-01-24T18:31:16.002499278Z  INFO chronos: Starting Chronos node node1 at 127.0.0.1:23392
[INFO] [stdout] 2026-01-24T18:31:16.002874478Z  INFO chronos: Added peer: node2 at 127.0.0.1:23393
[INFO] [stdout] 2026-01-24T18:31:16.002904638Z  INFO chronos: Added peer: node3 at 127.0.0.1:23394
[INFO] [stdout] 2026-01-24T18:31:16.006407738Z  INFO chronos: Starting Chronos node node1 at 127.0.0.1:25839
[INFO] [stdout] 2026-01-24T18:31:16.006706318Z  INFO chronos: Added peer: node2 at 127.0.0.1:25840
[INFO] [stdout] 2026-01-24T18:31:16.006734388Z  INFO chronos: Added peer: node3 at 127.0.0.1:25841
[INFO] [stdout] 2026-01-24T18:31:16.008251058Z  INFO chronos: Starting Chronos node node1 at 127.0.0.1:21101
[INFO] [stdout] 2026-01-24T18:31:16.008423498Z  INFO chronos: Starting Chronos node node1 at 127.0.0.1:21975
[INFO] [stdout] 2026-01-24T18:31:16.008531688Z  INFO chronos: Added peer: node2 at 127.0.0.1:21102
[INFO] [stdout] 2026-01-24T18:31:16.008562768Z  INFO chronos: Added peer: node3 at 127.0.0.1:21103
[INFO] [stdout] 2026-01-24T18:31:16.008652878Z  INFO chronos: Added peer: node2 at 127.0.0.1:21976
[INFO] [stdout] 2026-01-24T18:31:16.008684628Z  INFO chronos: Added peer: node3 at 127.0.0.1:21977
[INFO] [stdout] 2026-01-24T18:31:16.010614108Z  INFO chronos: Starting Chronos node node2 at 127.0.0.1:25840
[INFO] [stdout] 2026-01-24T18:31:16.010807538Z  INFO chronos: Added peer: node1 at 127.0.0.1:25839
[INFO] [stdout] 2026-01-24T18:31:16.010840858Z  INFO chronos: Added peer: node3 at 127.0.0.1:25841
[INFO] [stdout] 2026-01-24T18:31:16.014240268Z  INFO chronos: Starting Chronos node node3 at 127.0.0.1:21977
[INFO] [stdout] 2026-01-24T18:31:16.014483128Z  INFO chronos: Added peer: node1 at 127.0.0.1:21975
[INFO] [stdout] 2026-01-24T18:31:16.014512928Z  INFO chronos: Added peer: node2 at 127.0.0.1:21976
[INFO] [stdout] 2026-01-24T18:31:16.014502198Z  INFO chronos: Starting Chronos node node2 at 127.0.0.1:21976
[INFO] [stdout] 2026-01-24T18:31:16.014704248Z  INFO chronos: Added peer: node1 at 127.0.0.1:21975
[INFO] [stdout] 2026-01-24T18:31:16.014731478Z  INFO chronos: Added peer: node3 at 127.0.0.1:21977
[INFO] [stdout] 2026-01-24T18:31:16.017919868Z  INFO chronos: Starting Chronos node node3 at 127.0.0.1:21103
[INFO] [stdout] 2026-01-24T18:31:16.018129828Z  INFO chronos: Added peer: node1 at 127.0.0.1:21101
[INFO] [stdout] 2026-01-24T18:31:16.018185578Z  INFO chronos: Added peer: node2 at 127.0.0.1:21102
[INFO] [stdout] 2026-01-24T18:31:16.019852538Z  INFO chronos: Starting Chronos node node2 at 127.0.0.1:21102
[INFO] [stdout] 2026-01-24T18:31:16.020045378Z  INFO chronos: Added peer: node1 at 127.0.0.1:21101
[INFO] [stdout] 2026-01-24T18:31:16.020069698Z  INFO chronos: Added peer: node3 at 127.0.0.1:21103
[INFO] [stdout] 2026-01-24T18:31:16.012133698Z  INFO chronos: Starting Chronos node node3 at 127.0.0.1:25841
[INFO] [stdout] 2026-01-24T18:31:16.021847608Z  INFO chronos: Added peer: node1 at 127.0.0.1:25839
[INFO] [stdout] 2026-01-24T18:31:16.021875978Z  INFO chronos: Added peer: node2 at 127.0.0.1:25840
[INFO] [stdout] 2026-01-24T18:31:16.023613718Z  INFO chronos: Starting Chronos node node3 at 127.0.0.1:23394
[INFO] [stdout] 2026-01-24T18:31:16.023805108Z  INFO chronos: Added peer: node1 at 127.0.0.1:23392
[INFO] [stdout] 2026-01-24T18:31:16.023828288Z  INFO chronos: Added peer: node2 at 127.0.0.1:23393
[INFO] [stdout] 2026-01-24T18:31:16.024516158Z  INFO chronos: Starting Chronos node node2 at 127.0.0.1:23393
[INFO] [stdout] 2026-01-24T18:31:16.024705588Z  INFO chronos: Added peer: node1 at 127.0.0.1:23392
[INFO] [stdout] 2026-01-24T18:31:16.024730188Z  INFO chronos: Added peer: node3 at 127.0.0.1:23394
[INFO] [stdout] 2026-01-24T18:31:16.32591711Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpetUyTo/cluster/node1"
[INFO] [stdout] 2026-01-24T18:31:16.33090641Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpEeJ8qO/cluster/node1"
[INFO] [stdout] 2026-01-24T18:31:16.33582794Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpNk8ZVQ/cluster/node1"
[INFO] [stdout] 2026-01-24T18:31:16.34761193Z  INFO chronos: gRPC server listening on 127.0.0.1:23392
[INFO] [stdout] 2026-01-24T18:31:16.36007954Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpEeJ8qO/cluster/node2"
[INFO] [stdout] 2026-01-24T18:31:16.36254954Z  INFO chronos: gRPC server listening on 127.0.0.1:21101
[INFO] [stdout] 2026-01-24T18:31:16.36654526Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpEeJ8qO/cluster/node3"
[INFO] [stdout] 2026-01-24T18:31:16.36958398Z  INFO chronos: gRPC server listening on 127.0.0.1:25840
[INFO] [stdout] 2026-01-24T18:31:16.37352779Z  INFO chronos: gRPC server listening on 127.0.0.1:25841
[INFO] [stdout] 2026-01-24T18:31:16.37471584Z  INFO chronos: gRPC server listening on 127.0.0.1:25839
[INFO] [stdout] 2026-01-24T18:31:16.40866773Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpcRfkFg/cluster/node3"
[INFO] [stdout] 2026-01-24T18:31:16.443318081Z  INFO chronos: gRPC server listening on 127.0.0.1:21977
[INFO] [stdout] 2026-01-24T18:31:16.509691101Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpcRfkFg/cluster/node1"
[INFO] [stdout] 2026-01-24T18:31:16.535172861Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpetUyTo/cluster/node2"
[INFO] [stdout] 2026-01-24T18:31:16.542106181Z  INFO chronos: gRPC server listening on 127.0.0.1:21975
[INFO] [stdout] 2026-01-24T18:31:16.549336651Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpNk8ZVQ/cluster/node2"
[INFO] [stdout] 2026-01-24T18:31:16.552880251Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpNk8ZVQ/cluster/node3"
[INFO] [stdout] 2026-01-24T18:31:16.566512181Z  INFO chronos: gRPC server listening on 127.0.0.1:21102
[INFO] [stdout] 2026-01-24T18:31:16.576181051Z  INFO chronos: gRPC server listening on 127.0.0.1:23393
[INFO] [stdout] 2026-01-24T18:31:16.577262121Z  INFO chronos: gRPC server listening on 127.0.0.1:21103
[INFO] [stdout] 2026-01-24T18:31:16.741586063Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpetUyTo/cluster/node3"
[INFO] [stdout] 2026-01-24T18:31:16.754626503Z  INFO chronos: gRPC server listening on 127.0.0.1:23394
[INFO] [stdout] 2026-01-24T18:31:16.830756623Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpcRfkFg/cluster/node2"
[INFO] [stdout] 2026-01-24T18:31:16.840145863Z  INFO chronos: gRPC server listening on 127.0.0.1:21976
[INFO] [stdout] 2026-01-24T18:31:18.190433982Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:31:18.195752832Z  INFO chronos::raft::node: Node node1 becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:31:18.501465754Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:31:18.509691074Z  INFO chronos::raft::node: Node node3 becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:31:18.652439395Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:31:18.660556915Z  INFO chronos::raft::node: Node node2 becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:31:18.986421937Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:31:18.995751157Z  INFO chronos::raft::node: Node node1 becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:31:19.001949217Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.002026087Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.002065057Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.002274037Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.002319287Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:19.005389947Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:19.005439917Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:19.007130667Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:31:19.007152577Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.007216567Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.007220497Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.007255327Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.007253427Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.007284867Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.007420407Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.007420977Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.007450887Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:19.007453507Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:19.009923297Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.009968827Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.009977587Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.010008507Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.010006827Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.010035907Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.010171267Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.010175007Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.010210387Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:19.010209677Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:19.010411037Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.010441527Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (42, 42.0);
[INFO] [stdout] 2026-01-24T18:31:19.010475717Z  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:31:19.010598897Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.010633237Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:19.013120897Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.013190187Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.013236667Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.013417977Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.013456767Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:19.013932527Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:31:19.014140707Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:19.014197607Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:19.015827507Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.015872077Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.015907417Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.016068367Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.016104307Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:19.016702377Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.016735727Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.016763387Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.016803197Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:19.016838347Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:19.016887987Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:19.016893237Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.016918687Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:19.016926527Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:19.017343317Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:31:19.018263317Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:31:19.019786637Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.019815747Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (1, 10.0);
[INFO] [stdout] 2026-01-24T18:31:19.019841127Z  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:31:19.019979847Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.020009737Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:19.022612137Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.022651597Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (1, 10.0);
[INFO] [stdout] 2026-01-24T18:31:19.022643967Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:31:19.022681767Z  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:31:19.022715267Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:31:19.022801397Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.022836537Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:19.024762177Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:19.024800287Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:19.026039737Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:31:19.027707097Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:19.027745007Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:19.029589537Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.029619317Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (2, 20.0);
[INFO] [stdout] 2026-01-24T18:31:19.029648867Z  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:31:19.029784227Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.030289987Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:19.030324497Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:19.030320637Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:19.032424697Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:31:19.034124437Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:19.034174397Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:19.035786277Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.035817937Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (2, 20.0);
[INFO] [stdout] 2026-01-24T18:31:19.035846137Z  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:31:19.035982627Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.037449277Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:19.039716597Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.039762817Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:19.039795057Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:19.039965717Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.040085537Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:19.040259737Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:19.040292567Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:19.042222227Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:19.042265547Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:19.055477207Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.055551747Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.055597578Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.055795817Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.055836167Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:19.560143081Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.560234321Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.560270971Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.560508231Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.560552641Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:19.563465531Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:19.563520331Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.563556061Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:19.563666611Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:19.563700401Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:20.044604654Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:20.044671564Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (3, 30.0);
[INFO] [stdout] 2026-01-24T18:31:20.044708404Z  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:31:20.044857144Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:20.044898204Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:20.047884634Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:20.047934774Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:20.050362864Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:20.050406234Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (4, 40.0);
[INFO] [stdout] 2026-01-24T18:31:20.050433024Z  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:31:20.050564514Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:20.066903164Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:20.067907084Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:20.067946424Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:20.067989344Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:20.068115284Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:20.068149724Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:20.070857754Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:20.070900154Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:20.071303424Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:20.071344674Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:20.071376554Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:20.071492264Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:20.071526694Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:20.073141964Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:20.073195044Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (5, 50.0);
[INFO] [stdout] 2026-01-24T18:31:20.073231034Z  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:31:20.073345654Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:20.073378604Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:20.076913984Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:20.076982174Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:20.086859684Z  INFO chronos: Starting Chronos node node1 at 127.0.0.1:21975
[INFO] [stdout] 2026-01-24T18:31:20.087066734Z  INFO chronos: Added peer: node2 at 127.0.0.1:21976
[INFO] [stdout] 2026-01-24T18:31:20.087091664Z  INFO chronos: Added peer: node3 at 127.0.0.1:21977
[INFO] [stdout] 2026-01-24T18:31:20.160884805Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpcRfkFg/cluster/node1"
[INFO] [stdout] 2026-01-24T18:31:20.167370985Z  INFO chronos: gRPC server listening on 127.0.0.1:21975
[INFO] [stdout] 2026-01-24T18:31:20.575608357Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:20.575670927Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:20.575708607Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:20.575839087Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:20.575875887Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:20.579128927Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:20.579191527Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:20.579228597Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:20.579334437Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:20.579369677Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:21.08412378Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:21.08434442Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:21.084381071Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:21.08449432Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:21.08452284Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:21.08737394Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:21.08741338Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:21.087446611Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:21.08755246Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:21.087586891Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:21.093856991Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:31:21.411044193Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:31:21.417431763Z  INFO chronos::raft::node: Node node2 becoming leader for term 2
[INFO] [stdout] 2026-01-24T18:31:21.420398763Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:31:21.425500543Z  INFO chronos::raft::node: Node node1 becoming leader for term 2
[INFO] [stdout] 2026-01-24T18:31:21.591909904Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:21.591975914Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:21.592012844Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:21.592137174Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:21.592191464Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:21.595486244Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:31:21.595823664Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:21.595860654Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:21.598731594Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:21.598779204Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:21.598810414Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:21.598994164Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:21.599038674Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:21.599150474Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:21.599200044Z  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:31:21.650398524Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:31:22.035198407Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:22.035266237Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (id, temperature) VALUES (99, 99.0);
[INFO] [stdout] 2026-01-24T18:31:22.035303027Z  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:31:22.035513907Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:22.035551287Z  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:31:24.04339672Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:24.04345744Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:24.04349198Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:24.04362272Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:24.04365997Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:24.04699067Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:24.04704247Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:24.050495Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:24.05053319Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:24.05056165Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:24.05073481Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:24.05077161Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:24.05089363Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:24.05092332Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:24.05598263Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:24.05602646Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:24.05605504Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:24.05623237Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:24.05626946Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:24.05638775Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:24.05641786Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:24.05885269Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:24.05888483Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:24.05891094Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:24.05904906Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:24.05907931Z  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:31:25.081655166Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:25.081709366Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:25.081739246Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:25.081999366Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:25.082035406Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:25.087560846Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:25.087606536Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:25.087639126Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:25.087754396Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:25.087791966Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:25.094923166Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:25.094975646Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:25.099706357Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:25.099746486Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:25.099780126Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:25.100040126Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:25.100076746Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:25.101851966Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:25.101890866Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stderr]      Running tests/raft_log_proptest.rs (/opt/rustwide/target/debug/deps/raft_log_proptest-ca4841f39c611490)
[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.16s
[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.70s
[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.28s
[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:31:27.18076399Z  INFO chronos: Starting Chronos node cloud at 127.0.0.1:23870
[INFO] [stdout] 2026-01-24T18:31:27.18086985Z  INFO chronos: --clean flag detected, removing data directory: /tmp/.tmpx36sIB/sync_cluster_basic/cloud
[INFO] [stdout] 2026-01-24T18:31:27.20976718Z  INFO chronos: Starting Chronos node cloud at 127.0.0.1:25190
[INFO] [stdout] 2026-01-24T18:31:27.21925473Z  INFO chronos: --clean flag detected, removing data directory: /tmp/.tmpaGhKyp/sync_cluster_lww/cloud
[INFO] [stdout] 2026-01-24T18:31:27.723141303Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpx36sIB/sync_cluster_basic/cloud"
[INFO] [stdout] 2026-01-24T18:31:27.796643504Z  INFO chronos: gRPC server listening on 127.0.0.1:23870
[INFO] [stdout] 2026-01-24T18:31:27.906816144Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpaGhKyp/sync_cluster_lww/cloud"
[INFO] [stdout] test sync_idempotent_replay_same_batch ... ok
[INFO] [stdout] 2026-01-24T18:31:27.923489255Z  INFO chronos: gRPC server listening on 127.0.0.1:25190
[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_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:31:30.153055689Z  INFO chronos: Starting Chronos node edge1 at 127.0.0.1:23871
[INFO] [stdout] 2026-01-24T18:31:30.153149049Z  INFO chronos: --clean flag detected, removing data directory: /tmp/.tmpx36sIB/sync_cluster_basic/edge1
[INFO] [stdout] 2026-01-24T18:31:30.214004859Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpx36sIB/sync_cluster_basic/edge1"
[INFO] [stdout] 2026-01-24T18:31:30.28470742Z  INFO chronos: gRPC server listening on 127.0.0.1:23871
[INFO] [stdout] 2026-01-24T18:31:30.2882881Z  INFO chronos: Starting Chronos node edge1 at 127.0.0.1:25191
[INFO] [stdout] 2026-01-24T18:31:30.28837526Z  INFO chronos: --clean flag detected, removing data directory: /tmp/.tmpaGhKyp/sync_cluster_lww/edge1
[INFO] [stdout] 2026-01-24T18:31:30.32329987Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:31:30.32341343Z  INFO chronos::raft::node: Election won (single node), becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:31:30.32344257Z  INFO chronos::raft::node: Node cloud becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:31:30.394230231Z  INFO chronos::storage::sled_engine: Sled storage engine initialized at "/tmp/.tmpaGhKyp/sync_cluster_lww/edge1"
[INFO] [stdout] 2026-01-24T18:31:30.446651011Z  INFO chronos: gRPC server listening on 127.0.0.1:25191
[INFO] [stdout] 2026-01-24T18:31:30.569426852Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:31:30.569545212Z  INFO chronos::raft::node: Election won (single node), becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:31:30.569575302Z  INFO chronos::raft::node: Node cloud becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:31:32.254439423Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:31:32.254535813Z  INFO chronos::raft::node: Election won (single node), becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:31:32.254554953Z  INFO chronos::raft::node: Node edge1 becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:31:33.158020138Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:33.158084289Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (sensor_id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:33.158127278Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (sensor_id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:33.158330669Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:33.158372218Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:33.158571818Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:33.158601189Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:33.159187578Z  INFO chronos::storage::sled_engine: Created table 'sensors' with 2 columns
[INFO] [stdout] 2026-01-24T18:31:33.160913609Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:33.160947529Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (sensor_id, temperature) VALUES (1, 10);
[INFO] [stdout] 2026-01-24T18:31:33.160984169Z  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:31:33.161106578Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:33.161135938Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:33.161352938Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:33.161378169Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:33.163093489Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:33.163119769Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (sensor_id, temperature) VALUES (2, 20);
[INFO] [stdout] 2026-01-24T18:31:33.163143939Z  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:31:33.163262629Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:33.163293929Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:33.163467949Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:33.163497558Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:33.164686998Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:33.164714449Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (sensor_id, temperature) VALUES (3, 30);
[INFO] [stdout] 2026-01-24T18:31:33.164740749Z  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:31:33.164840649Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:33.164872178Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:33.165054549Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:33.165084178Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:33.283363449Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:33.283434309Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: CREATE TABLE sensors (sensor_id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:33.283470939Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=CREATE TABLE sensors (sensor_id INT, temperature FLOAT);
[INFO] [stdout] 2026-01-24T18:31:33.283695549Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:33.283733039Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:33.285422609Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:33.285455549Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: INSERT INTO sensors (sensor_id, temperature) VALUES (1, 10.0);
[INFO] [stdout] 2026-01-24T18:31:33.285481309Z  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:31:33.285593509Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:33.285622479Z  INFO chronos::network::server: SqlServer::execute_sql: Not the leader, returning error
[INFO] [stdout] 2026-01-24T18:31:33.312898599Z  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:31:33.314091839Z  INFO chronos::network::sync_worker: SyncWorker synced 3 operations to http://127.0.0.1:23870 (applied=3, drained=3)
[INFO] [stdout] 2026-01-24T18:31:33.37944189Z  INFO chronos::raft: Election timeout elapsed, starting pre-vote
[INFO] [stdout] 2026-01-24T18:31:33.37955442Z  INFO chronos::raft::node: Election won (single node), becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:31:33.37958881Z  INFO chronos::raft::node: Node edge1 becoming leader for term 1
[INFO] [stdout] 2026-01-24T18:31:38.180592631Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:38.180659641Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:38.180697541Z  INFO chronos::network::server: audit_sql user=anonymous role=None sql=SELECT sensor_id, temperature FROM sensors;
[INFO] [stdout] 2026-01-24T18:31:38.180945311Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:38.180996321Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:38.181213381Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:38.181248291Z  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:31:38.287631962Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:38.287701172Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: UPDATE sensors SET temperature = 30.0 WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:31:38.287741032Z  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:31:38.287941062Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:38.287992782Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:38.288216362Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:38.288247762Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:38.289072112Z ERROR chronos: Raft apply worker error: Storage error: Table 'sensors' not found
[INFO] [stdout] 2026-01-24T18:31:38.290260662Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:38.290293322Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:31:38.290319331Z  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:31:38.290480991Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:38.290512631Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:38.290584371Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:38.290605751Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:38.495395823Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:38.495465173Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:31:38.495506203Z  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:31:38.495732413Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:38.495783933Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:38.495917713Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:38.495956673Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:38.699396104Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:38.699462414Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:31:38.699496414Z  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:31:38.699705764Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:38.699752854Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:38.699875514Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:38.699903814Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:38.905333655Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:38.905398726Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:31:38.905434506Z  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:31:38.905652066Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:38.905701106Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:38.905852336Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:38.905890686Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:39.109121897Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:39.109207717Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:31:39.109246047Z  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:31:39.109458967Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:39.109507277Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:39.109641807Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:39.109675397Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:39.312832328Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:39.312895258Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:31:39.312928938Z  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:31:39.313682808Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:39.313734548Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:39.314081388Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:39.314144648Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:39.51657217Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:39.516630499Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:31:39.516662939Z  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:31:39.516868579Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:39.51690655Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:39.51704175Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:39.517065279Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:39.720390051Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:39.720454881Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:31:39.720496811Z  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:31:39.720705611Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:39.720746111Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:39.720879441Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:39.720905231Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:39.924262152Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:39.924323582Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:31:39.924359232Z  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:31:39.924571522Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:39.924615892Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:39.924753192Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:39.924781182Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] 2026-01-24T18:31:40.128094774Z  INFO chronos::network::server: SqlServer::execute_sql: ENTER
[INFO] [stdout] 2026-01-24T18:31:40.128171554Z  INFO chronos::network::server: SqlServer::execute_sql: Received SQL: SELECT sensor_id, temperature FROM sensors WHERE sensor_id = 1;
[INFO] [stdout] 2026-01-24T18:31:40.128208743Z  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:31:40.128421814Z  INFO chronos::network::server: SqlServer::execute_sql: parse OK
[INFO] [stdout] 2026-01-24T18:31:40.128462523Z  INFO chronos::network::server: SqlServer::execute_sql: calling Executor::execute
[INFO] [stdout] 2026-01-24T18:31:40.128592034Z  INFO chronos::network::server: SqlServer::execute_sql: Executor::execute done
[INFO] [stdout] 2026-01-24T18:31:40.128616243Z  INFO chronos::network::server: SqlServer::execute_sql: returning SqlResponse (success=true)
[INFO] [stdout] test edge_to_cloud_lww_conflict_uses_latest_value ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- edge_to_cloud_lww_conflict_uses_latest_value stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'edge_to_cloud_lww_conflict_uses_latest_value' (371) panicked at tests/sync_lww.rs:800:5:
[INFO] [stdout] expected to observe updated temperature 30.0 for sensor_id=1 on edge before sync
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x63ff1edd49f2 - std[b14e17aa63033746]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x63ff1edd49f2 - std[b14e17aa63033746]::backtrace_rs::backtrace::trace_unsynchronized::<std[b14e17aa63033746]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x63ff1edd49f2 - std[b14e17aa63033746]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x63ff1edd49f2 - <<std[b14e17aa63033746]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[7ebf52c056bd56a4]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x63ff1ededa3a - <core[7ebf52c056bd56a4]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x63ff1ededa3a - core[7ebf52c056bd56a4]::fmt::write
[INFO] [stdout]    6:     0x63ff1edda636 - std[b14e17aa63033746]::io::default_write_fmt::<alloc[ed4a2d6a5f8966cc]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x63ff1edda636 - <alloc[ed4a2d6a5f8966cc]::vec::Vec<u8> as std[b14e17aa63033746]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x63ff1edaef5f - <std[b14e17aa63033746]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x63ff1edaef5f - std[b14e17aa63033746]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x63ff1edcadb9 - std[b14e17aa63033746]::panicking::default_hook
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x63ff1e1b471e - <alloc[ed4a2d6a5f8966cc]::boxed::Box<dyn for<'a, 'b> core[7ebf52c056bd56a4]::ops::function::Fn<(&'a std[b14e17aa63033746]::panic::PanicHookInfo<'b>,), Output = ()> + core[7ebf52c056bd56a4]::marker::Send + core[7ebf52c056bd56a4]::marker::Sync> as core[7ebf52c056bd56a4]::ops::function::Fn<(&std[b14e17aa63033746]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x63ff1e1b471e - test[3318909d72adbd73]::test_main_with_exit_callback::<test[3318909d72adbd73]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x63ff1edcb022 - <alloc[ed4a2d6a5f8966cc]::boxed::Box<dyn for<'a, 'b> core[7ebf52c056bd56a4]::ops::function::Fn<(&'a std[b14e17aa63033746]::panic::PanicHookInfo<'b>,), Output = ()> + core[7ebf52c056bd56a4]::marker::Send + core[7ebf52c056bd56a4]::marker::Sync> as core[7ebf52c056bd56a4]::ops::function::Fn<(&std[b14e17aa63033746]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x63ff1edcb022 - std[b14e17aa63033746]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x63ff1edaf04a - std[b14e17aa63033746]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x63ff1eda6199 - std[b14e17aa63033746]::sys::backtrace::__rust_end_short_backtrace::<std[b14e17aa63033746]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x63ff1edb01ed - __rustc[f8abce35e9738d16]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x63ff1edee22c - core[7ebf52c056bd56a4]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x63ff1e17f371 - sync_lww[980fa5a787236085]::edge_to_cloud_lww_conflict_uses_latest_value::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/sync_lww.rs:800:5
[INFO] [stdout]   20:     0x63ff1e1a45f2 - <core[7ebf52c056bd56a4]::pin::Pin<&mut dyn core[7ebf52c056bd56a4]::future::future::Future<Output = ()>> as core[7ebf52c056bd56a4]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/core/src/future/future.rs:133:9
[INFO] [stdout]   21:     0x63ff1e18c06d - <tokio[f2989f064a3422c8]::runtime::park::CachedParkThread>::block_on::<core[7ebf52c056bd56a4]::pin::Pin<&mut dyn core[7ebf52c056bd56a4]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.0/src/runtime/park.rs:285:71
[INFO] [stdout]   22:     0x63ff1e189a82 - tokio[f2989f064a3422c8]::task::coop::with_budget::<core[7ebf52c056bd56a4]::task::poll::Poll<()>, <tokio[f2989f064a3422c8]::runtime::park::CachedParkThread>::block_on<core[7ebf52c056bd56a4]::pin::Pin<&mut dyn core[7ebf52c056bd56a4]::future::future::Future<Output = ()>>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.0/src/task/coop/mod.rs:167:5
[INFO] [stdout]   23:     0x63ff1e189a82 - tokio[f2989f064a3422c8]::task::coop::budget::<core[7ebf52c056bd56a4]::task::poll::Poll<()>, <tokio[f2989f064a3422c8]::runtime::park::CachedParkThread>::block_on<core[7ebf52c056bd56a4]::pin::Pin<&mut dyn core[7ebf52c056bd56a4]::future::future::Future<Output = ()>>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.0/src/task/coop/mod.rs:133:5
[INFO] [stdout]   24:     0x63ff1e189a82 - <tokio[f2989f064a3422c8]::runtime::park::CachedParkThread>::block_on::<core[7ebf52c056bd56a4]::pin::Pin<&mut dyn core[7ebf52c056bd56a4]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.0/src/runtime/park.rs:285:31
[INFO] [stdout]   25:     0x63ff1e187b84 - <tokio[f2989f064a3422c8]::runtime::context::blocking::BlockingRegionGuard>::block_on::<core[7ebf52c056bd56a4]::pin::Pin<&mut dyn core[7ebf52c056bd56a4]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.0/src/runtime/context/blocking.rs:66:14
[INFO] [stdout]   26:     0x63ff1e19aa68 - <tokio[f2989f064a3422c8]::runtime::scheduler::multi_thread::MultiThread>::block_on::<core[7ebf52c056bd56a4]::pin::Pin<&mut dyn core[7ebf52c056bd56a4]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.0/src/runtime/scheduler/multi_thread/mod.rs:87:22
[INFO] [stdout]   27:     0x63ff1e18afcb - tokio[f2989f064a3422c8]::runtime::context::runtime::enter_runtime::<<tokio[f2989f064a3422c8]::runtime::scheduler::multi_thread::MultiThread>::block_on<core[7ebf52c056bd56a4]::pin::Pin<&mut dyn core[7ebf52c056bd56a4]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.0/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   28:     0x63ff1e1981a4 - <tokio[f2989f064a3422c8]::runtime::scheduler::multi_thread::MultiThread>::block_on::<core[7ebf52c056bd56a4]::pin::Pin<&mut dyn core[7ebf52c056bd56a4]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.0/src/runtime/scheduler/multi_thread/mod.rs:86:9
[INFO] [stdout]   29:     0x63ff1e1a0924 - <tokio[f2989f064a3422c8]::runtime::runtime::Runtime>::block_on_inner::<core[7ebf52c056bd56a4]::pin::Pin<&mut dyn core[7ebf52c056bd56a4]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.0/src/runtime/runtime.rs:358:50
[INFO] [stdout]   30:     0x63ff1e1a0b1c - <tokio[f2989f064a3422c8]::runtime::runtime::Runtime>::block_on::<core[7ebf52c056bd56a4]::pin::Pin<&mut dyn core[7ebf52c056bd56a4]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.0/src/runtime/runtime.rs:330:18
[INFO] [stdout]   31:     0x63ff1e183311 - sync_lww[980fa5a787236085]::edge_to_cloud_lww_conflict_uses_latest_value
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/sync_lww.rs:873:25
[INFO] [stdout]   32:     0x63ff1e172947 - sync_lww[980fa5a787236085]::edge_to_cloud_lww_conflict_uses_latest_value::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/sync_lww.rs:715:56
[INFO] [stdout]   33:     0x63ff1e187536 - <sync_lww[980fa5a787236085]::edge_to_cloud_lww_conflict_uses_latest_value::{closure#0} as core[7ebf52c056bd56a4]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   34:     0x63ff1e1a8acb - <fn() -> core[7ebf52c056bd56a4]::result::Result<(), alloc[ed4a2d6a5f8966cc]::string::String> as core[7ebf52c056bd56a4]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   35:     0x63ff1e1a8acb - test[3318909d72adbd73]::__rust_begin_short_backtrace::<core[7ebf52c056bd56a4]::result::Result<(), alloc[ed4a2d6a5f8966cc]::string::String>, fn() -> core[7ebf52c056bd56a4]::result::Result<(), alloc[ed4a2d6a5f8966cc]::string::String>>
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/test/src/lib.rs:663:18
[INFO] [stdout]   36:     0x63ff1e1b532a - test[3318909d72adbd73]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/test/src/lib.rs:686:74
[INFO] [stdout]   37:     0x63ff1e1b532a - <core[7ebf52c056bd56a4]::panic::unwind_safe::AssertUnwindSafe<test[3318909d72adbd73]::run_test_in_process::{closure#0}> as core[7ebf52c056bd56a4]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   38:     0x63ff1e1b532a - std[b14e17aa63033746]::panicking::catch_unwind::do_call::<core[7ebf52c056bd56a4]::panic::unwind_safe::AssertUnwindSafe<test[3318909d72adbd73]::run_test_in_process::{closure#0}>, core[7ebf52c056bd56a4]::result::Result<(), alloc[ed4a2d6a5f8966cc]::string::String>>
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/panicking.rs:581:40
[INFO] [stdout]   39:     0x63ff1e1b532a - std[b14e17aa63033746]::panicking::catch_unwind::<core[7ebf52c056bd56a4]::result::Result<(), alloc[ed4a2d6a5f8966cc]::string::String>, core[7ebf52c056bd56a4]::panic::unwind_safe::AssertUnwindSafe<test[3318909d72adbd73]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/panicking.rs:544:19
[INFO] [stdout]   40:     0x63ff1e1b532a - std[b14e17aa63033746]::panic::catch_unwind::<core[7ebf52c056bd56a4]::panic::unwind_safe::AssertUnwindSafe<test[3318909d72adbd73]::run_test_in_process::{closure#0}>, core[7ebf52c056bd56a4]::result::Result<(), alloc[ed4a2d6a5f8966cc]::string::String>>
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/panic.rs:359:14
[INFO] [stdout]   41:     0x63ff1e1b532a - test[3318909d72adbd73]::run_test_in_process
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/test/src/lib.rs:686:27
[INFO] [stdout]   42:     0x63ff1e1b532a - test[3318909d72adbd73]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/test/src/lib.rs:607:43
[INFO] [stdout]   43:     0x63ff1e1b08d4 - test[3318909d72adbd73]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/test/src/lib.rs:637:41
[INFO] [stdout]   44:     0x63ff1e1b08d4 - std[b14e17aa63033746]::sys::backtrace::__rust_begin_short_backtrace::<test[3318909d72adbd73]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   45:     0x63ff1e1b7e22 - std[b14e17aa63033746]::thread::lifecycle::spawn_unchecked::<test[3318909d72adbd73]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   46:     0x63ff1e1b7e22 - <core[7ebf52c056bd56a4]::panic::unwind_safe::AssertUnwindSafe<std[b14e17aa63033746]::thread::lifecycle::spawn_unchecked<test[3318909d72adbd73]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[7ebf52c056bd56a4]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   47:     0x63ff1e1b7e22 - std[b14e17aa63033746]::panicking::catch_unwind::do_call::<core[7ebf52c056bd56a4]::panic::unwind_safe::AssertUnwindSafe<std[b14e17aa63033746]::thread::lifecycle::spawn_unchecked<test[3318909d72adbd73]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/panicking.rs:581:40
[INFO] [stdout]   48:     0x63ff1e1b7e22 - std[b14e17aa63033746]::panicking::catch_unwind::<(), core[7ebf52c056bd56a4]::panic::unwind_safe::AssertUnwindSafe<std[b14e17aa63033746]::thread::lifecycle::spawn_unchecked<test[3318909d72adbd73]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/panicking.rs:544:19
[INFO] [stdout]   49:     0x63ff1e1b7e22 - std[b14e17aa63033746]::panic::catch_unwind::<core[7ebf52c056bd56a4]::panic::unwind_safe::AssertUnwindSafe<std[b14e17aa63033746]::thread::lifecycle::spawn_unchecked<test[3318909d72adbd73]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/panic.rs:359:14
[INFO] [stdout]   50:     0x63ff1e1b7e22 - std[b14e17aa63033746]::thread::lifecycle::spawn_unchecked::<test[3318909d72adbd73]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   51:     0x63ff1e1b7e22 - <std[b14e17aa63033746]::thread::lifecycle::spawn_unchecked<test[3318909d72adbd73]::run_test::{closure#1}, ()>::{closure#1} as core[7ebf52c056bd56a4]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   52:     0x63ff1edd393f - <alloc[ed4a2d6a5f8966cc]::boxed::Box<dyn core[7ebf52c056bd56a4]::ops::function::FnOnce<(), Output = ()> + core[7ebf52c056bd56a4]::marker::Send> as core[7ebf52c056bd56a4]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   53:     0x63ff1edd393f - <std[b14e17aa63033746]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/a7ee93192a766fb0dafa23def7154fc03cfcfc3e/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   54:     0x7d0746cfbaa4 - <unknown>
[INFO] [stdout]   55:     0x7d0746d88a64 - clone
[INFO] [stdout]   56:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     edge_to_cloud_lww_conflict_uses_latest_value
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 7 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 13.45s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test sync_lww`
[INFO] running `Command { std: "docker" "inspect" "5fa93b4d313c7b6a95d75ec8201f6beeac56c23e25ab1e7a0a95b05eb1b29544", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5fa93b4d313c7b6a95d75ec8201f6beeac56c23e25ab1e7a0a95b05eb1b29544", kill_on_drop: false }`
[INFO] [stdout] 5fa93b4d313c7b6a95d75ec8201f6beeac56c23e25ab1e7a0a95b05eb1b29544