[INFO] cloning repository https://github.com/wcygan/raft-a-tui [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/wcygan/raft-a-tui" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fwcygan%2Fraft-a-tui", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fwcygan%2Fraft-a-tui'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 9f544513bda32b4f341d4389999ba86afba10405 [INFO] testing wcygan/raft-a-tui against master#d933cf483edf1605142ac6899ff32536c0ad8b22 for pr-150933 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fwcygan%2Fraft-a-tui" "/workspace/builds/worker-6-tc1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc1/source'... [INFO] [stderr] done. [INFO] started tweaking git repo https://github.com/wcygan/raft-a-tui [INFO] finished tweaking git repo https://github.com/wcygan/raft-a-tui [INFO] tweaked toml for git repo https://github.com/wcygan/raft-a-tui written to /workspace/builds/worker-6-tc1/source/Cargo.toml [INFO] validating manifest of git repo https://github.com/wcygan/raft-a-tui on toolchain d933cf483edf1605142ac6899ff32536c0ad8b22 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate git repo https://github.com/wcygan/raft-a-tui 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" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Updating crates.io index [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded socket2 v0.6.1 [INFO] [stderr] Downloaded time-core v0.1.6 [INFO] [stderr] Downloaded time-macros v0.2.24 [INFO] [stderr] Downloaded unty v0.0.4 [INFO] [stderr] Downloaded take_mut v0.2.2 [INFO] [stderr] Downloaded thiserror v2.0.17 [INFO] [stderr] Downloaded bincode_derive v2.0.1 [INFO] [stderr] Downloaded home v0.5.12 [INFO] [stderr] Downloaded which v4.4.2 [INFO] [stderr] Downloaded raft-proto v0.7.0 [INFO] [stderr] Downloaded fs2 v0.4.3 [INFO] [stderr] Downloaded multimap v0.8.3 [INFO] [stderr] Downloaded num_threads v0.1.7 [INFO] [stderr] Downloaded thiserror-impl v2.0.17 [INFO] [stderr] Downloaded slog-term v2.9.2 [INFO] [stderr] Downloaded tonic-build v0.12.3 [INFO] [stderr] Downloaded prost-derive v0.11.9 [INFO] [stderr] Downloaded prettyplease v0.1.25 [INFO] [stderr] Downloaded virtue v0.0.18 [INFO] [stderr] Downloaded bincode v2.0.1 [INFO] [stderr] Downloaded cc v1.2.46 [INFO] [stderr] Downloaded hyper-util v0.1.18 [INFO] [stderr] Downloaded time v0.3.44 [INFO] [stderr] Downloaded crossterm v0.29.0 [INFO] [stderr] Downloaded protobuf v2.28.0 [INFO] [stderr] Downloaded regex v1.12.2 [INFO] [stderr] Downloaded raft v0.7.0 [INFO] [stderr] Downloaded chrono v0.4.42 [INFO] [stderr] Downloaded h2 v0.4.12 [INFO] [stderr] Downloaded tokio-util v0.7.17 [INFO] [stderr] Downloaded tempfile v3.23.0 [INFO] [stderr] Downloaded uuid v1.18.1 [INFO] [stderr] Downloaded find-msvc-tools v0.1.5 [INFO] [stderr] Downloaded slog v2.8.2 [INFO] [stderr] Downloaded term v1.2.0 [INFO] [stderr] Downloaded bytes v1.11.0 [INFO] [stderr] Downloaded prost-types v0.11.9 [INFO] [stderr] Downloaded prost-build v0.11.9 [INFO] [stderr] Downloaded regex-syntax v0.8.8 [INFO] [stderr] Downloaded rustix v1.1.2 [INFO] [stderr] Downloaded slog-async v2.8.0 [INFO] [stderr] Downloaded iana-time-zone v0.1.64 [INFO] [stderr] Downloaded js-sys v0.3.82 [INFO] [stderr] Downloaded sled v0.34.7 [INFO] [stderr] Downloaded prost v0.11.9 [INFO] [stderr] Downloaded prost-build v0.13.5 [INFO] [stderr] Downloaded deranged v0.5.5 [INFO] [stderr] Downloaded regex-automata v0.4.13 [INFO] [stderr] Downloaded tokio v1.48.0 [INFO] [stderr] Downloaded protobuf-build v0.14.1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 1118b2a203df84a00b1938952df2e5a9774987fe7e1e19294da43066e9608e96 [INFO] running `Command { std: "docker" "start" "-a" "1118b2a203df84a00b1938952df2e5a9774987fe7e1e19294da43066e9608e96", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "1118b2a203df84a00b1938952df2e5a9774987fe7e1e19294da43066e9608e96", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "1118b2a203df84a00b1938952df2e5a9774987fe7e1e19294da43066e9608e96", kill_on_drop: false }` [INFO] [stdout] 1118b2a203df84a00b1938952df2e5a9774987fe7e1e19294da43066e9608e96 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] c70e4ae0c709c8e0f3acb257f7fd6ff8d742294e6b0466b561f8198eb1006cb3 [INFO] running `Command { std: "docker" "start" "-a" "c70e4ae0c709c8e0f3acb257f7fd6ff8d742294e6b0466b561f8198eb1006cb3", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.103 [INFO] [stderr] Compiling quote v1.0.42 [INFO] [stderr] Compiling unicode-ident v1.0.22 [INFO] [stderr] Compiling cfg-if v1.0.4 [INFO] [stderr] Compiling libc v0.2.177 [INFO] [stderr] Compiling log v0.4.28 [INFO] [stderr] Compiling either v1.15.0 [INFO] [stderr] Compiling anyhow v1.0.100 [INFO] [stderr] Compiling equivalent v1.0.2 [INFO] [stderr] Compiling bytes v1.11.0 [INFO] [stderr] Compiling bitflags v2.10.0 [INFO] [stderr] Compiling smallvec v1.15.1 [INFO] [stderr] Compiling hashbrown v0.16.0 [INFO] [stderr] Compiling rustversion v1.0.22 [INFO] [stderr] Compiling lock_api v0.4.14 [INFO] [stderr] Compiling getrandom v0.3.4 [INFO] [stderr] Compiling parking_lot_core v0.9.12 [INFO] [stderr] Compiling linux-raw-sys v0.11.0 [INFO] [stderr] Compiling rustix v1.1.2 [INFO] [stderr] Compiling syn v1.0.109 [INFO] [stderr] Compiling regex-syntax v0.8.8 [INFO] [stderr] Compiling fixedbitset v0.4.2 [INFO] [stderr] Compiling fastrand v2.3.0 [INFO] [stderr] Compiling serde_core v1.0.228 [INFO] [stderr] Compiling itertools v0.13.0 [INFO] [stderr] Compiling itertools v0.10.5 [INFO] [stderr] Compiling multimap v0.8.3 [INFO] [stderr] Compiling linux-raw-sys v0.4.15 [INFO] [stderr] Compiling indexmap v2.12.0 [INFO] [stderr] Compiling http v1.3.1 [INFO] [stderr] Compiling heck v0.5.0 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling syn v2.0.110 [INFO] [stderr] Compiling signal-hook-registry v1.4.6 [INFO] [stderr] Compiling mio v1.1.0 [INFO] [stderr] Compiling socket2 v0.6.1 [INFO] [stderr] Compiling zerocopy v0.8.27 [INFO] [stderr] Compiling futures-task v0.3.31 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling tracing-core v0.1.34 [INFO] [stderr] Compiling futures-sink v0.3.31 [INFO] [stderr] Compiling petgraph v0.6.5 [INFO] [stderr] Compiling rustix v0.38.44 [INFO] [stderr] Compiling tower-service v0.3.3 [INFO] [stderr] Compiling parking_lot v0.12.5 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling httparse v1.10.1 [INFO] [stderr] Compiling regex-automata v0.4.13 [INFO] [stderr] Compiling http-body v1.0.1 [INFO] [stderr] Compiling slab v0.4.11 [INFO] [stderr] Compiling prettyplease v0.2.37 [INFO] [stderr] Compiling prettyplease v0.1.25 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling atomic-waker v1.1.2 [INFO] [stderr] Compiling home v0.5.12 [INFO] [stderr] Compiling tower-layer v0.3.3 [INFO] [stderr] Compiling try-lock v0.2.5 [INFO] [stderr] Compiling strsim v0.11.1 [INFO] [stderr] Compiling want v0.3.1 [INFO] [stderr] Compiling tempfile v3.23.0 [INFO] [stderr] Compiling indexmap v1.9.3 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling sync_wrapper v1.0.2 [INFO] [stderr] Compiling signal-hook v0.3.18 [INFO] [stderr] Compiling unicode-segmentation v1.12.0 [INFO] [stderr] Compiling httpdate v1.0.3 [INFO] [stderr] Compiling heck v0.4.1 [INFO] [stderr] Compiling http-body-util v0.1.3 [INFO] [stderr] Compiling slog v2.8.2 [INFO] [stderr] Compiling memchr v2.7.6 [INFO] [stderr] Compiling powerfmt v0.2.0 [INFO] [stderr] Compiling num-conv v0.1.0 [INFO] [stderr] Compiling mime v0.3.17 [INFO] [stderr] Compiling bitflags v1.3.2 [INFO] [stderr] Compiling time-core v0.1.6 [INFO] [stderr] Compiling time-macros v0.2.24 [INFO] [stderr] Compiling deranged v0.5.5 [INFO] [stderr] Compiling percent-encoding v2.3.2 [INFO] [stderr] Compiling parking_lot_core v0.8.6 [INFO] [stderr] Compiling matchit v0.7.3 [INFO] [stderr] Compiling protobuf v2.28.0 [INFO] [stderr] Compiling num_threads v0.1.7 [INFO] [stderr] Compiling regex v1.12.2 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling tower v0.5.2 [INFO] [stderr] Compiling utf8parse v0.2.2 [INFO] [stderr] Compiling anstyle-parse v0.2.7 [INFO] [stderr] Compiling signal-hook-mio v0.2.5 [INFO] [stderr] Compiling which v4.4.2 [INFO] [stderr] Compiling convert_case v0.7.1 [INFO] [stderr] Compiling proc-macro-error-attr2 v2.0.0 [INFO] [stderr] Compiling socket2 v0.5.10 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling instant v0.1.13 [INFO] [stderr] Compiling allocator-api2 v0.2.21 [INFO] [stderr] Compiling anstyle-query v1.1.5 [INFO] [stderr] Compiling base64 v0.22.1 [INFO] [stderr] Compiling instability v0.3.9 [INFO] [stderr] Compiling byteorder v1.5.0 [INFO] [stderr] Compiling foldhash v0.1.5 [INFO] [stderr] Compiling crc32fast v1.5.0 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling anstyle v1.0.13 [INFO] [stderr] Compiling is_terminal_polyfill v1.70.2 [INFO] [stderr] Compiling paste v1.0.15 [INFO] [stderr] Compiling colorchoice v1.0.4 [INFO] [stderr] Compiling fxhash v0.2.1 [INFO] [stderr] Compiling hashbrown v0.15.5 [INFO] [stderr] Compiling anstream v0.6.21 [INFO] [stderr] Compiling castaway v0.2.4 [INFO] [stderr] Compiling thread_local v1.1.9 [INFO] [stderr] Compiling litrs v1.0.0 [INFO] [stderr] Compiling serde_json v1.0.145 [INFO] [stderr] Compiling unicode-width v0.1.14 [INFO] [stderr] Compiling indoc v2.0.7 [INFO] [stderr] Compiling virtue v0.0.18 [INFO] [stderr] Compiling static_assertions v1.1.0 [INFO] [stderr] Compiling iana-time-zone v0.1.64 [INFO] [stderr] Compiling thiserror v2.0.17 [INFO] [stderr] Compiling slog-async v2.8.0 [INFO] [stderr] Compiling clap_lex v0.7.6 [INFO] [stderr] Compiling lru v0.12.5 [INFO] [stderr] Compiling time v0.3.44 [INFO] [stderr] Compiling clap_builder v4.5.51 [INFO] [stderr] Compiling chrono v0.4.42 [INFO] [stderr] Compiling compact_str v0.8.1 [INFO] [stderr] Compiling parking_lot v0.11.2 [INFO] [stderr] Compiling bincode_derive v2.0.1 [INFO] [stderr] Compiling document-features v0.2.12 [INFO] [stderr] Compiling crossbeam-channel v0.5.15 [INFO] [stderr] Compiling crossbeam-epoch v0.9.18 [INFO] [stderr] Compiling darling_core v0.20.11 [INFO] [stderr] Compiling proc-macro-error2 v2.0.1 [INFO] [stderr] Compiling crossterm v0.28.1 [INFO] [stderr] Compiling unicode-truncate v1.1.0 [INFO] [stderr] Compiling is-terminal v0.4.17 [INFO] [stderr] Compiling fs2 v0.4.3 [INFO] [stderr] Compiling cassowary v0.3.0 [INFO] [stderr] Compiling take_mut v0.2.2 [INFO] [stderr] Compiling unty v0.0.4 [INFO] [stderr] Compiling unicode-width v0.2.0 [INFO] [stderr] Compiling term v1.2.0 [INFO] [stderr] Compiling sled v0.34.7 [INFO] [stderr] Compiling uuid v1.18.1 [INFO] [stderr] Compiling bincode v2.0.1 [INFO] [stderr] Compiling prost-derive v0.11.9 [INFO] [stderr] Compiling tokio-macros v2.6.0 [INFO] [stderr] Compiling prost-derive v0.13.5 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling tracing-attributes v0.1.30 [INFO] [stderr] Compiling async-trait v0.1.89 [INFO] [stderr] Compiling pin-project-internal v1.1.10 [INFO] [stderr] Compiling async-stream-impl v0.3.6 [INFO] [stderr] Compiling strum_macros v0.26.4 [INFO] [stderr] Compiling derive_more-impl v2.0.1 [INFO] [stderr] Compiling thiserror-impl v1.0.69 [INFO] [stderr] Compiling async-stream v0.3.6 [INFO] [stderr] Compiling tokio v1.48.0 [INFO] [stderr] Compiling thiserror-impl v2.0.17 [INFO] [stderr] Compiling derive_more v2.0.1 [INFO] [stderr] Compiling clap_derive v4.5.49 [INFO] [stderr] Compiling getset v0.1.6 [INFO] [stderr] Compiling pin-project v1.1.10 [INFO] [stderr] Compiling crossterm v0.29.0 [INFO] [stderr] Compiling tracing v0.1.41 [INFO] [stderr] Compiling prost v0.11.9 [INFO] [stderr] Compiling axum-core v0.4.5 [INFO] [stderr] Compiling prost-types v0.11.9 [INFO] [stderr] Compiling darling_macro v0.20.11 [INFO] [stderr] Compiling thiserror v1.0.69 [INFO] [stderr] Compiling prost v0.13.5 [INFO] [stderr] Compiling darling v0.20.11 [INFO] [stderr] Compiling prost-types v0.13.5 [INFO] [stderr] Compiling strum v0.26.3 [INFO] [stderr] Compiling prost-build v0.11.9 [INFO] [stderr] Compiling ratatui v0.29.0 [INFO] [stderr] Compiling clap v4.5.51 [INFO] [stderr] Compiling protobuf-build v0.14.1 [INFO] [stderr] Compiling raft-proto v0.7.0 [INFO] [stderr] Compiling prost-build v0.13.5 [INFO] [stderr] Compiling erased-serde v0.3.31 [INFO] [stderr] Compiling axum v0.7.9 [INFO] [stderr] Compiling tonic-build v0.12.3 [INFO] [stderr] Compiling slog-term v2.9.2 [INFO] [stderr] Compiling raft v0.7.0 [INFO] [stderr] Compiling raft-proto v0.1.0 (/opt/rustwide/workdir/crates/raft-proto) [INFO] [stderr] Compiling tokio-util v0.7.17 [INFO] [stderr] Compiling tokio-stream v0.1.17 [INFO] [stderr] Compiling h2 v0.4.12 [INFO] [stderr] Compiling tower v0.4.13 [INFO] [stderr] Compiling hyper v1.8.1 [INFO] [stderr] Compiling hyper-util v0.1.18 [INFO] [stderr] Compiling hyper-timeout v0.5.2 [INFO] [stderr] Compiling tonic v0.12.3 [INFO] [stderr] Compiling raft-core v0.1.0 (/opt/rustwide/workdir/crates/raft-core) [INFO] [stdout] warning: unexpected `cfg` condition value: `test-support` [INFO] [stdout] --> crates/raft-core/src/lib.rs:16:17 [INFO] [stdout] | [INFO] [stdout] 16 | #[cfg(any(test, feature = "test-support"))] [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^ help: remove the condition [INFO] [stdout] | [INFO] [stdout] = note: no expected values for `feature` [INFO] [stdout] = help: consider adding `test-support` as a feature in `Cargo.toml` [INFO] [stdout] = note: see for more information about checking conditional configuration [INFO] [stdout] = note: `#[warn(unexpected_cfgs)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Compiling raft-client v0.1.0 (/opt/rustwide/workdir/crates/raft-client) [INFO] [stderr] Compiling raft-server v0.1.0 (/opt/rustwide/workdir/crates/raft-server) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 23s [INFO] running `Command { std: "docker" "inspect" "c70e4ae0c709c8e0f3acb257f7fd6ff8d742294e6b0466b561f8198eb1006cb3", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c70e4ae0c709c8e0f3acb257f7fd6ff8d742294e6b0466b561f8198eb1006cb3", kill_on_drop: false }` [INFO] [stdout] c70e4ae0c709c8e0f3acb257f7fd6ff8d742294e6b0466b561f8198eb1006cb3 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] c829f239151e0b4e84ce6511958dbbb64bf8a282eefeffc40933bcb3b193d8ef [INFO] running `Command { std: "docker" "start" "-a" "c829f239151e0b4e84ce6511958dbbb64bf8a282eefeffc40933bcb3b193d8ef", kill_on_drop: false }` [INFO] [stderr] Compiling tempfile v3.23.0 [INFO] [stderr] Compiling rustix v1.1.2 [INFO] [stderr] Compiling prost-build v0.13.5 [INFO] [stderr] Compiling prost-build v0.11.9 [INFO] [stderr] Compiling tonic-build v0.12.3 [INFO] [stderr] Compiling protobuf-build v0.14.1 [INFO] [stderr] Compiling raft-proto v0.1.0 (/opt/rustwide/workdir/crates/raft-proto) [INFO] [stderr] Compiling raft-proto v0.7.0 [INFO] [stderr] Compiling raft v0.7.0 [INFO] [stderr] Compiling raft-core v0.1.0 (/opt/rustwide/workdir/crates/raft-core) [INFO] [stderr] Compiling crossterm v0.29.0 [INFO] [stdout] warning: unexpected `cfg` condition value: `test-support` [INFO] [stdout] --> crates/raft-core/src/lib.rs:16:17 [INFO] [stdout] | [INFO] [stdout] 16 | #[cfg(any(test, feature = "test-support"))] [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^ help: remove the condition [INFO] [stdout] | [INFO] [stdout] = note: no expected values for `feature` [INFO] [stdout] = help: consider adding `test-support` as a feature in `Cargo.toml` [INFO] [stdout] = note: see for more information about checking conditional configuration [INFO] [stdout] = note: `#[warn(unexpected_cfgs)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unexpected `cfg` condition value: `test-support` [INFO] [stdout] --> crates/raft-core/src/lib.rs:16:17 [INFO] [stdout] | [INFO] [stdout] 16 | #[cfg(any(test, feature = "test-support"))] [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^ help: remove the condition [INFO] [stdout] | [INFO] [stdout] = note: no expected values for `feature` [INFO] [stdout] = help: consider adding `test-support` as a feature in `Cargo.toml` [INFO] [stdout] = note: see for more information about checking conditional configuration [INFO] [stdout] = note: `#[warn(unexpected_cfgs)]` on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Compiling raft-client v0.1.0 (/opt/rustwide/workdir/crates/raft-client) [INFO] [stderr] Compiling raft-server v0.1.0 (/opt/rustwide/workdir/crates/raft-server) [INFO] [stdout] warning: unused import: `RaftDriver` [INFO] [stdout] --> crates/raft-core/tests/three_node_cluster.rs:11:28 [INFO] [stdout] | [INFO] [stdout] 11 | use raft_core::raft_loop::{RaftDriver, RaftLoopError, StateUpdate}; [INFO] [stdout] | ^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/storage_applied_index.rs:15:5 [INFO] [stdout] | [INFO] [stdout] 15 | storage.set_applied_index(5); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] = note: `#[warn(unused_must_use)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 15 | let _ = storage.set_applied_index(5); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/storage_applied_index.rs:18:5 [INFO] [stdout] | [INFO] [stdout] 18 | storage.set_applied_index(100); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 18 | let _ = storage.set_applied_index(100); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/storage_applied_index.rs:25:5 [INFO] [stdout] | [INFO] [stdout] 25 | storage1.set_applied_index(10); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 25 | let _ = storage1.set_applied_index(10); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/storage_applied_index.rs:32:5 [INFO] [stdout] | [INFO] [stdout] 32 | storage2.set_applied_index(20); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 32 | let _ = storage2.set_applied_index(20); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/storage_applied_index.rs:87:5 [INFO] [stdout] | [INFO] [stdout] 87 | storage.set_applied_index(50); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 87 | let _ = storage.set_applied_index(50); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused import: `RaftDriver` [INFO] [stdout] --> crates/raft-core/tests/raft_loop_basic.rs:11:28 [INFO] [stdout] | [INFO] [stdout] 11 | use raft_core::raft_loop::{RaftDriver, RaftLoopError, StateUpdate}; [INFO] [stdout] | ^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused variable: `logger` [INFO] [stdout] --> crates/raft-core/tests/crash_recovery_e2e.rs:202:13 [INFO] [stdout] | [INFO] [stdout] 202 | let logger = setup_logger(1); [INFO] [stdout] | ^^^^^^ help: if this is intentional, prefix it with an underscore: `_logger` [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused variable: `logger` [INFO] [stdout] --> crates/raft-core/tests/crash_recovery_e2e.rs:314:13 [INFO] [stdout] | [INFO] [stdout] 314 | let logger = setup_logger(1); [INFO] [stdout] | ^^^^^^ help: if this is intentional, prefix it with an underscore: `_logger` [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/crash_recovery_e2e.rs:116:9 [INFO] [stdout] | [INFO] [stdout] 116 | storage.set_hardstate(hard_state); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] = note: `#[warn(unused_must_use)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 116 | let _ = storage.set_hardstate(hard_state); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/crash_recovery_e2e.rs:171:9 [INFO] [stdout] | [INFO] [stdout] 171 | storage.set_applied_index(expected_applied); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 171 | let _ = storage.set_applied_index(expected_applied); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/crash_recovery_e2e.rs:287:9 [INFO] [stdout] | [INFO] [stdout] 287 | storage.set_applied_index(10); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 287 | let _ = storage.set_applied_index(10); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/crash_recovery_e2e.rs:298:9 [INFO] [stdout] | [INFO] [stdout] 298 | storage.set_applied_index(20); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 298 | let _ = storage.set_applied_index(20); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/crash_recovery_e2e.rs:308:9 [INFO] [stdout] | [INFO] [stdout] 308 | storage.set_applied_index(30); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 308 | let _ = storage.set_applied_index(30); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/crash_recovery_e2e.rs:331:5 [INFO] [stdout] | [INFO] [stdout] 331 | storage.set_applied_index(100); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 331 | let _ = storage.set_applied_index(100); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: field `node_id` is never read [INFO] [stdout] --> crates/raft-core/tests/three_node_cluster.rs:51:5 [INFO] [stdout] | [INFO] [stdout] 50 | struct TestNode { [INFO] [stdout] | -------- field in this struct [INFO] [stdout] 51 | node_id: u64, [INFO] [stdout] | ^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/disk_storage_basic.rs:54:9 [INFO] [stdout] | [INFO] [stdout] 54 | storage.set_applied_index(42); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] = note: `#[warn(unused_must_use)]` (part of `#[warn(unused)]`) on by default [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 54 | let _ = storage.set_applied_index(42); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/disk_storage_basic.rs:82:9 [INFO] [stdout] | [INFO] [stdout] 82 | storage.set_hardstate(hs.clone()); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 82 | let _ = storage.set_hardstate(hs.clone()); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/disk_storage_basic.rs:107:9 [INFO] [stdout] | [INFO] [stdout] 107 | storage.set_hardstate(hs); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 107 | let _ = storage.set_hardstate(hs); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/disk_storage_basic.rs:422:9 [INFO] [stdout] | [INFO] [stdout] 422 | storage.set_applied_index(10); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 422 | let _ = storage.set_applied_index(10); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/disk_storage_basic.rs:435:9 [INFO] [stdout] | [INFO] [stdout] 435 | storage.set_applied_index(20); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 435 | let _ = storage.set_applied_index(20); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/disk_storage_basic.rs:481:5 [INFO] [stdout] | [INFO] [stdout] 481 | storage1.set_applied_index(42); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 481 | let _ = storage1.set_applied_index(42); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] warning: unused `Result` that must be used [INFO] [stdout] --> crates/raft-core/tests/disk_storage_basic.rs:491:5 [INFO] [stdout] | [INFO] [stdout] 491 | storage1.set_applied_index(100); [INFO] [stdout] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stdout] | [INFO] [stdout] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stdout] help: use `let _ = ...` to ignore the resulting value [INFO] [stdout] | [INFO] [stdout] 491 | let _ = storage1.set_applied_index(100); [INFO] [stdout] | +++++++ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 21.64s [INFO] running `Command { std: "docker" "inspect" "c829f239151e0b4e84ce6511958dbbb64bf8a282eefeffc40933bcb3b193d8ef", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c829f239151e0b4e84ce6511958dbbb64bf8a282eefeffc40933bcb3b193d8ef", kill_on_drop: false }` [INFO] [stdout] c829f239151e0b4e84ce6511958dbbb64bf8a282eefeffc40933bcb3b193d8ef [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] e4a9fa942280a7011def845ba426dbdd03b5f5e220790c716abee715fd5bec50 [INFO] running `Command { std: "docker" "start" "-a" "e4a9fa942280a7011def845ba426dbdd03b5f5e220790c716abee715fd5bec50", kill_on_drop: false }` [INFO] [stderr] warning: unexpected `cfg` condition value: `test-support` [INFO] [stderr] --> crates/raft-core/src/lib.rs:16:17 [INFO] [stderr] | [INFO] [stderr] 16 | #[cfg(any(test, feature = "test-support"))] [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^ help: remove the condition [INFO] [stderr] | [INFO] [stderr] = note: no expected values for `feature` [INFO] [stderr] = help: consider adding `test-support` as a feature in `Cargo.toml` [INFO] [stderr] = note: see for more information about checking conditional configuration [INFO] [stderr] = note: `#[warn(unexpected_cfgs)]` on by default [INFO] [stderr] [INFO] [stderr] warning: `raft-core` (lib) generated 1 warning [INFO] [stderr] warning: unused import: `RaftDriver` [INFO] [stderr] --> crates/raft-core/tests/three_node_cluster.rs:11:28 [INFO] [stderr] | [INFO] [stderr] 11 | use raft_core::raft_loop::{RaftDriver, RaftLoopError, StateUpdate}; [INFO] [stderr] | ^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: field `node_id` is never read [INFO] [stderr] --> crates/raft-core/tests/three_node_cluster.rs:51:5 [INFO] [stderr] | [INFO] [stderr] 50 | struct TestNode { [INFO] [stderr] | -------- field in this struct [INFO] [stderr] 51 | node_id: u64, [INFO] [stderr] | ^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: `raft-core` (test "three_node_cluster") generated 2 warnings (run `cargo fix --test "three_node_cluster" -p raft-core` to apply 1 suggestion) [INFO] [stderr] warning: `raft-core` (lib test) generated 1 warning (1 duplicate) [INFO] [stderr] warning: unused import: `RaftDriver` [INFO] [stderr] --> crates/raft-core/tests/raft_loop_basic.rs:11:28 [INFO] [stderr] | [INFO] [stderr] 11 | use raft_core::raft_loop::{RaftDriver, RaftLoopError, StateUpdate}; [INFO] [stderr] | ^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: `raft-core` (test "raft_loop_basic") generated 1 warning (run `cargo fix --test "raft_loop_basic" -p raft-core` to apply 1 suggestion) [INFO] [stderr] warning: unused variable: `logger` [INFO] [stderr] --> crates/raft-core/tests/crash_recovery_e2e.rs:202:13 [INFO] [stderr] | [INFO] [stderr] 202 | let logger = setup_logger(1); [INFO] [stderr] | ^^^^^^ help: if this is intentional, prefix it with an underscore: `_logger` [INFO] [stderr] | [INFO] [stderr] = note: `#[warn(unused_variables)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] [INFO] [stderr] warning: unused variable: `logger` [INFO] [stderr] --> crates/raft-core/tests/crash_recovery_e2e.rs:314:13 [INFO] [stderr] | [INFO] [stderr] 314 | let logger = setup_logger(1); [INFO] [stderr] | ^^^^^^ help: if this is intentional, prefix it with an underscore: `_logger` [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/crash_recovery_e2e.rs:116:9 [INFO] [stderr] | [INFO] [stderr] 116 | storage.set_hardstate(hard_state); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] = note: `#[warn(unused_must_use)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 116 | let _ = storage.set_hardstate(hard_state); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/crash_recovery_e2e.rs:171:9 [INFO] [stderr] | [INFO] [stderr] 171 | storage.set_applied_index(expected_applied); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 171 | let _ = storage.set_applied_index(expected_applied); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/crash_recovery_e2e.rs:287:9 [INFO] [stderr] | [INFO] [stderr] 287 | storage.set_applied_index(10); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 287 | let _ = storage.set_applied_index(10); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/crash_recovery_e2e.rs:298:9 [INFO] [stderr] | [INFO] [stderr] 298 | storage.set_applied_index(20); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 298 | let _ = storage.set_applied_index(20); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/crash_recovery_e2e.rs:308:9 [INFO] [stderr] | [INFO] [stderr] 308 | storage.set_applied_index(30); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 308 | let _ = storage.set_applied_index(30); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/crash_recovery_e2e.rs:331:5 [INFO] [stderr] | [INFO] [stderr] 331 | storage.set_applied_index(100); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 331 | let _ = storage.set_applied_index(100); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: `raft-core` (test "crash_recovery_e2e") generated 8 warnings (run `cargo fix --test "crash_recovery_e2e" -p raft-core` to apply 2 suggestions) [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/disk_storage_basic.rs:54:9 [INFO] [stderr] | [INFO] [stderr] 54 | storage.set_applied_index(42); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] = note: `#[warn(unused_must_use)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 54 | let _ = storage.set_applied_index(42); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/disk_storage_basic.rs:82:9 [INFO] [stderr] | [INFO] [stderr] 82 | storage.set_hardstate(hs.clone()); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 82 | let _ = storage.set_hardstate(hs.clone()); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/disk_storage_basic.rs:107:9 [INFO] [stderr] | [INFO] [stderr] 107 | storage.set_hardstate(hs); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 107 | let _ = storage.set_hardstate(hs); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/disk_storage_basic.rs:422:9 [INFO] [stderr] | [INFO] [stderr] 422 | storage.set_applied_index(10); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 422 | let _ = storage.set_applied_index(10); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/disk_storage_basic.rs:435:9 [INFO] [stderr] | [INFO] [stderr] 435 | storage.set_applied_index(20); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 435 | let _ = storage.set_applied_index(20); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/disk_storage_basic.rs:481:5 [INFO] [stderr] | [INFO] [stderr] 481 | storage1.set_applied_index(42); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 481 | let _ = storage1.set_applied_index(42); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/disk_storage_basic.rs:491:5 [INFO] [stderr] | [INFO] [stderr] 491 | storage1.set_applied_index(100); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 491 | let _ = storage1.set_applied_index(100); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: `raft-core` (test "disk_storage_basic") generated 7 warnings [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/storage_applied_index.rs:15:5 [INFO] [stderr] | [INFO] [stderr] 15 | storage.set_applied_index(5); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] = note: `#[warn(unused_must_use)]` (part of `#[warn(unused)]`) on by default [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 15 | let _ = storage.set_applied_index(5); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/storage_applied_index.rs:18:5 [INFO] [stderr] | [INFO] [stderr] 18 | storage.set_applied_index(100); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 18 | let _ = storage.set_applied_index(100); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/storage_applied_index.rs:25:5 [INFO] [stderr] | [INFO] [stderr] 25 | storage1.set_applied_index(10); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 25 | let _ = storage1.set_applied_index(10); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/storage_applied_index.rs:32:5 [INFO] [stderr] | [INFO] [stderr] 32 | storage2.set_applied_index(20); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 32 | let _ = storage2.set_applied_index(20); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: unused `Result` that must be used [INFO] [stderr] --> crates/raft-core/tests/storage_applied_index.rs:87:5 [INFO] [stderr] | [INFO] [stderr] 87 | storage.set_applied_index(50); [INFO] [stderr] | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ [INFO] [stderr] | [INFO] [stderr] = note: this `Result` may be an `Err` variant, which should be handled [INFO] [stderr] help: use `let _ = ...` to ignore the resulting value [INFO] [stderr] | [INFO] [stderr] 87 | let _ = storage.set_applied_index(50); [INFO] [stderr] | +++++++ [INFO] [stderr] [INFO] [stderr] warning: `raft-core` (test "storage_applied_index") generated 5 warnings [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.47s [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/raft_client-dfea04a3e8453a3e) [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/raft_core-500da59e5aabe42f) [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 29 tests [INFO] [stdout] test builder::tests::test_builder_missing_raft_node ... ok [INFO] [stdout] test builder::tests::test_builder_missing_cmd_rx ... ok [INFO] [stdout] test builder::tests::test_builder_missing_kv_node ... ok [INFO] [stdout] test builder::tests::test_builder_error_display ... ok [INFO] [stdout] test builder::tests::test_builder_missing_msg_rx ... ok [INFO] [stdout] test builder::tests::test_builder_fluent_api ... ok [INFO] [stdout] test disk_storage::tests::test_index_key_conversion ... ok [INFO] [stdout] test builder::tests::test_builder_success ... ok [INFO] [stdout] test builder::tests::test_builder_missing_shutdown_rx ... ok [INFO] [stdout] test builder::tests::test_builder_missing_transport ... ok [INFO] [stdout] test builder::tests::test_builder_missing_state_tx ... ok [INFO] [stdout] test snapshot::tests::test_bincode_empty ... ok [INFO] [stdout] test snapshot::tests::test_bincode_roundtrip ... ok [INFO] [stdout] test snapshot::tests::test_json_human_readable ... ok [INFO] [stdout] test snapshot::tests::test_snapshot_error_display ... ok [INFO] [stdout] test tcp_transport::tests::test_message_length_prefix ... ok [INFO] [stdout] test snapshot::tests::test_json_roundtrip ... ok [INFO] [stdout] test snapshot::tests::test_bincode_empty_bytes ... ok [INFO] [stdout] test test_support::tests::test_mock_applicator_can_fail ... ok [INFO] [stdout] test tcp_transport::tests::test_message_serialization_roundtrip ... ok [INFO] [stdout] test test_support::tests::test_failing_transport_always_fails ... ok [INFO] [stdout] test test_support::tests::test_mock_applicator_clear ... ok [INFO] [stdout] test test_support::tests::test_mock_applicator_records_entries ... ok [INFO] [stdout] test snapshot::tests::test_json_empty_bytes ... ok [INFO] [stdout] test disk_storage::tests::test_key_ordering ... ok [INFO] [stdout] test snapshot::tests::test_json_empty ... ok [INFO] [stdout] test snapshot::tests::test_json_decode_error ... ok [INFO] [stdout] test builder::tests::test_builder_default ... ok [INFO] [stdout] test snapshot::tests::test_bincode_decode_error ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 29 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.05s [INFO] [stdout] [INFO] [stderr] Running tests/codec_generic.rs (/opt/rustwide/target/debug/deps/codec_generic-4caabd58a0b7ba6c) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test generic_encode_decode_works ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/crash_recovery_e2e.rs (/opt/rustwide/target/debug/deps/crash_recovery_e2e-9edfafb761536101) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test test_in_memory_storage_no_persistence ... ok [INFO] [stdout] test test_crash_recovery_with_hardstate ... ok [INFO] [stdout] test test_crash_recovery_with_applied_index ... ok [INFO] [stdout] test test_single_node_crash_recovery ... ok [INFO] [stdout] test test_crash_recovery_with_log_entries ... ok [INFO] [stdout] test test_multiple_crash_recovery_cycles ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.34s [INFO] [stdout] [INFO] [stderr] Running tests/disk_storage_basic.rs (/opt/rustwide/target/debug/deps/disk_storage_basic-aed7265c06d9b0a8) [INFO] [stdout] [INFO] [stdout] running 20 tests [INFO] [stdout] test test_initial_state_defaults ... ok [INFO] [stdout] test test_compact_removes_entries ... ok [INFO] [stdout] test test_entries_max_size_limit ... ok [INFO] [stdout] test test_empty_storage_snapshot_returns_default ... ok [INFO] [stdout] test test_snapshot_compacts_log ... ok [INFO] [stdout] test test_large_entry_storage ... ok [INFO] [stdout] test test_confstate_persistence ... ok [INFO] [stdout] test test_disk_storage_create_and_reopen ... ok [INFO] [stdout] test test_snapshot_updates_first_index ... ok [INFO] [stdout] test test_applied_index_persistence ... ok [INFO] [stdout] test test_hardstate_persistence ... ok [INFO] [stdout] test test_entries_range_queries ... ok [INFO] [stdout] test test_snapshot_persistence ... ok [INFO] [stdout] test test_first_and_last_index ... ok [INFO] [stdout] test test_term_from_snapshot ... ok [INFO] [stdout] test test_storage_clone ... ok [INFO] [stdout] test test_hardstate_updates ... ok [INFO] [stdout] test test_multiple_restarts_preserve_state ... ok [INFO] [stdout] test test_append_and_retrieve_entries ... ok [INFO] [stdout] test test_term_lookup ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 20 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.87s [INFO] [stdout] [INFO] [stderr] Running tests/leader_only_proposals.rs (/opt/rustwide/target/debug/deps/leader_only_proposals-5c26153de2e34ecf) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test test_follower_rejects_proposals ... ok [INFO] [stderr] Mar 07 04:48:09.743 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.743 DEBGMar 07 04:48:09.743 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.743 DEBG reset election timeout 0 -> 16 at 0, election_elapsed: 0, timeout: 16, prev_timeout: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.743 INFO became follower at term 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.743 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.743Mar 07 04:48:09.743 INFOINFO switched to configurationRawNode created with id 1., , idconfig:: 1Configuration { voters: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, , raft_idraft_id:: 11 [INFO] [stderr] [INFO] [stderr] Mar 07 04:48:09.743 DEBG reset election timeout 0 -> 11 at 0, election_elapsed: 0, timeout: 11, prev_timeout: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.743 INFO became follower at term 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.743 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.743 INFO RawNode created with id 1., id: 1, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.743 INFO starting a new election, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.743Mar 07 04:48:09.743 INFOINFO became pre-candidate at term 0switched to configuration, , termconfig:: 0Configuration { voters: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, , raft_idraft_id:: 11 [INFO] [stderr] [INFO] [stderr] Mar 07 04:48:09.744 DEBG Mar 07 04:48:09.744 reset election timeout 11 -> 10 at 0DEBG reset election timeout 0 -> 11 at 0, election_elapsed: 0, timeout: 11, prev_timeout: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.744 INFO became follower at term 0, term: 0, raft_id: 1 [INFO] [stderr] , election_elapsed: Mar 07 04:48:09.7440 INFO newRaft, peers, timeout: 10, prev_timeout: 11, raft_id: reset election timeout 0 -> 18 at 0, election_elapsed: 0, timeout: 18, prev_timeout: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.744: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } } , INFOlast term :switched to configuration , config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, raft_id: 1 [INFO] [stderr] 0, last index: 0, applied:1Mar 07 04:48:09.744 Mar 07 04:48:09.744INFO became follower at term 0, term: 0, [INFO] [stderr] 0, commit: 0, term: 0DEBGraft_id: 1 [INFO] [stderr] reset election timeout 0 -> 19 at 0Mar 07 04:48:09.744, election_elapsedINFO: newRaft0, , , raft_id: 1 [INFO] [stderr] timeout:Mar 07 04:48:09.744 19INFO, prev_timeoutRawNode created with id 1.:, id0:, raft_id1:, raft_id1: [INFO] [stderr] 1 [INFO] [stderr] Mar 07 04:48:09.744 INFO Mar 07 04:48:09.744starting a new election , INFOterm :became follower at term 0 , 0term, :raft_id :0 , 1raft_id [INFO] [stderr] : 1 [INFO] [stderr] Mar 07 04:48:09.744 Mar 07 04:48:09.744INFO INFObecame pre-candidate at term 0 , became candidate at term 1term, :term :0 , 1raft_id, :raft_id :1 [INFO] [stderr] 1 [INFO] [stderr] Mar 07 04:48:09.744Mar 07 04:48:09.744 DEBGDEBG reset election timeout 11 -> 16 at 0reset election timeout 10 -> 15 at 0, , election_elapsedelection_elapsed:: 00, , timeouttimeout:: 1615, , prev_timeoutprev_timeout:: 1110, , raft_idraft_id:: 11 [INFO] [stderr] [INFO] [stdout] test test_candidate_rejects_proposals ... ok [INFO] [stderr] Mar 07 04:48:09.744Mar 07 04:48:09.744 INFOINFO became candidate at term 1became leader at term 1, , termterm:: 11, , raft_idraft_id:: 11 [INFO] [stdout] test test_state_query_on_follower ... ok [INFO] [stderr] [INFO] [stdout] test test_leader_accepts_proposals ... ok [INFO] [stderr] Mar 07 04:48:09.744 DEBG reset election timeout 16 -> 17 at 0, election_elapsed: 0, timeout: 17, prev_timeout: 16, raft_id: 1 [INFO] [stdout] test test_leader_accepts_multiple_proposals ... ok [INFO] [stderr] Mar 07 04:48:09.744 INFO became leader at term 1, term:Mar 07 04:48:09.744peers: INFOConfiguration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } } , newRaftlast term, :peers :0 , Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }last index, :last term :0 , 0applied, :last index :0 , 0commit, :applied :0 , 0term, :commit :0 , 0raft_id, :term :1 [INFO] [stderr] 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.744 INFO RawNode created with id 1., id: 1, raft_idMar 07 04:48:09.744: INFO1 [INFO] [stderr] RawNode created with id 1., id: 1, raft_idMar 07 04:48:09.745: INFO1 [INFO] [stderr] starting a new election, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.745 INFO became pre-candidate at term 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.745 DEBG reset election timeout 18 -> 10 at 0, election_elapsed: 0, timeout: 10, prev_timeout: 18, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.745 INFO became candidate at term 1, term: 1, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.745 DEBG reset election timeout 10 -> 18 at 0, election_elapsed: 0, timeout: 18, prev_timeout: 10, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.745 INFO became leader at term 1, term: 1, raft_id: 1 [INFO] [stderr] 1, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.746 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.746 DEBG reset election timeout 0 -> 12 at 0, election_elapsed: 0, timeout: 12, prev_timeout: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.746 INFO became follower at term 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.746 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:09.746 INFO RawNode created with id 1., id: 1, raft_id: 1 [INFO] [stdout] test test_multiple_proposals_rejected_by_follower ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Running tests/network_routing.rs (/opt/rustwide/target/debug/deps/network_routing-4ef88c609d980576) [INFO] [stdout] [INFO] [stdout] running 9 tests [INFO] [stdout] test test_channel_full ... ok [INFO] [stdout] test test_channel_closed ... ok [INFO] [stdout] test test_peer_not_found ... ok [INFO] [stdout] test test_peer_count ... ok [INFO] [stdout] test test_node_id ... ok [INFO] [stdout] test test_send_message_to_peer ... ok [INFO] [stdout] test test_has_peer ... ok [INFO] [stdout] test test_three_node_routing ... ok [INFO] [stdout] test test_transport_error_display ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/node_basic.rs (/opt/rustwide/target/debug/deps/node_basic-1456266dcd1bbea8) [INFO] [stdout] [INFO] [stdout] running 5 tests [INFO] [stdout] test test_apply_kv_command_via_protobuf ... ok [INFO] [stdout] test test_status ... ok [INFO] [stdout] test test_campaign_noop ... ok [INFO] [stdout] test test_put_and_get ... ok [INFO] [stdout] test test_keys_sorted ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/node_snapshots.rs (/opt/rustwide/target/debug/deps/node_snapshots-4b1990cb5654f0d7) [INFO] [stdout] [INFO] [stdout] running 9 tests [INFO] [stdout] test test_restore_from_malformed_snapshot ... ok [INFO] [stdout] test test_snapshot_idempotent ... ok [INFO] [stdout] test test_empty_snapshot_clears_state ... ok [INFO] [stdout] test test_snapshot_roundtrip_multiple_entries ... ok [INFO] [stdout] test test_restore_replaces_existing_state ... ok [INFO] [stdout] test test_snapshot_preserves_btree_ordering ... ok [INFO] [stdout] test test_snapshot_roundtrip_single_entry ... ok [INFO] [stdout] test test_snapshot_roundtrip_empty ... ok [INFO] [stdout] test test_snapshot_large_dataset ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Running tests/node_snapshots_edge_cases.rs (/opt/rustwide/target/debug/deps/node_snapshots_edge_cases-c19d46d4ac64037a) [INFO] [stdout] [INFO] [stdout] running 11 tests [INFO] [stdout] test test_multiple_snapshots_same_node ... ok [INFO] [stdout] test test_restore_multiple_times ... ok [INFO] [stdout] test test_snapshot_after_clear ... ok [INFO] [stdout] test test_snapshot_binary_data ... ok [INFO] [stdout] test test_snapshot_empty_keys_and_values ... ok [INFO] [stdout] test test_snapshot_special_characters ... ok [INFO] [stdout] test test_snapshot_size_growth ... ok [INFO] [stdout] test test_snapshot_unicode_data ... ok [INFO] [stdout] test test_snapshot_preserves_exact_state ... ok [INFO] [stdout] test test_snapshot_very_long_keys_and_values ... ok [INFO] [stdout] test test_snapshot_with_duplicate_keys ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/protobuf_roundtrip.rs (/opt/rustwide/target/debug/deps/protobuf_roundtrip-356dfaccff685dc8) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stdout] test roundtrip_put_proto ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running tests/raft_loop_basic.rs (/opt/rustwide/target/debug/deps/raft_loop_basic-e50b4fb735727f2f) [INFO] [stdout] [INFO] [stdout] running 14 tests [INFO] [stdout] test test_ready_loop_handles_cmd_rx_closed ... ok [INFO] [stdout] test test_ready_loop_starts_and_stops_with_shutdown ... ok [INFO] [stdout] test test_ready_loop_handles_msg_rx_closed ... ok [INFO] [stdout] test test_ready_loop_handles_raft_messages ... ok [INFO] [stdout] test test_malformed_entry_handling ... ok [INFO] [stdout] test test_read_only_commands_dont_go_through_raft ... ok [INFO] [stdout] test test_ready_loop_emits_state_updates ... ok [INFO] [stdout] test test_ready_loop_handles_put_command ... ok [INFO] [stdout] test test_ready_loop_handles_campaign_command ... ok [INFO] [stdout] test test_ready_loop_handles_user_commands ... ok [INFO] [stdout] test test_ready_loop_ticks_periodically ... ok [INFO] [stdout] test test_transport_send_failures_dont_crash_loop ... ok [INFO] [stdout] test test_single_node_commit_emits_kv_update ... ok [INFO] [stdout] test test_multiple_commands_produce_state_updates ... ok [INFO] [stderr] Running tests/raft_node_basic.rs (/opt/rustwide/target/debug/deps/raft_node_basic-a7b573db57b24469) [INFO] [stdout] [INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.90s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 10 tests [INFO] [stderr] Mar 07 04:48:10.723 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, raft_id: 1 [INFO] [stdout] test test_config_validation ... ok [INFO] [stderr] Mar 07 04:48:10.723 DEBG reset election timeout 0 -> 10 at 0, election_elapsed: 0, timeout: 10, prev_timeout: 0, raft_id: 1 [INFO] [stdout] test test_raft_state_equality ... ok [INFO] [stderr] Mar 07 04:48:10.723 INFO became follower at term 0, term: 0, raft_id: 1 [INFO] [stdout] test test_is_leader_initially_false ... ok [INFO] [stderr] Mar 07 04:48:10.723 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 1 [INFO] [stdout] test test_logger_access ... ok [INFO] [stderr] Mar 07 04:48:10.723 Mar 07 04:48:10.723INFO INFOswitched to configuration RawNode created with id 1., , configid:: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }1, , raft_idraft_id:: 11 [INFO] [stdout] test test_new_raft_node ... ok [INFO] [stderr] [INFO] [stdout] test test_propose_command_without_callback ... ok [INFO] [stderr] Mar 07 04:48:10.723 DEBG reset election timeout 0 -> 16 at 0, election_elapsed: 0, timeout: 16, prev_timeout: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.723 INFO became follower at term 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.723 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.723 INFO RawNode created with id 1., id: 1, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 DEBG reset election timeout 0 -> 18 at 0, election_elapsed: 0, timeout: 18, prev_timeout: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO became follower at term 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO RawNode created with id 1., id: 1, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO Mar 07 04:48:10.724 DEBG reset election timeout 0 -> 15 at 0, election_elapsed: 0, timeout: 15, prev_timeout: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO became follower at term 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 1Mar 07 04:48:10.724 [INFO] [stderr] switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, Mar 07 04:48:10.724raft_id :INFO 1RawNode created with id 1. [INFO] [stderr] , id: 1, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 DEBG reset election timeout 0 -> 12 at 0, election_elapsed: 0, timeout: 12, prev_timeout: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO became follower at term 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO RawNode created with id 1., id: 1, raft_id: 1 [INFO] [stderr] INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 DEBG reset election timeout 0 -> 18 at 0, election_elapsed: 0, timeout: 18, prev_timeout: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO became follower at term 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.724 INFO RawNode created with id 1., id: 1, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.725 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, raft_id: 2 [INFO] [stderr] Mar 07 04:48:10.725 DEBG reset election timeout 0 -> 15 at 0, election_elapsed: 0, timeout: 15, prev_timeout: 0, raft_id: 2 [INFO] [stderr] Mar 07 04:48:10.725Mar 07 04:48:10.725 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, raft_id: 3 [INFO] [stderr] Mar 07 04:48:10.725 DEBG reset election timeout 0 -> 12 at 0, election_elapsed: 0, timeout: 12, prev_timeout: 0, raft_id: 3 [INFO] [stderr] Mar 07 04:48:10.725 INFO became follower at term 0, term: 0, raft_id: 3 [INFO] [stderr] Mar 07 04:48:10.725 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 3 [INFO] [stderr] Mar 07 04:48:10.725 INFO RawNode created with id 3., id: 3, raft_id: 3 [INFO] [stderr] INFO became follower at term 0, term: 0, raft_id: 2 [INFO] [stderr] Mar 07 04:48:10.725 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 2 [INFO] [stderr] Mar 07 04:48:10.725 INFO RawNode created with id 2., id: 2, raft_id: 2 [INFO] [stderr] Mar 07 04:48:10.726 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.726 DEBG reset election timeout 0 -> 19 at 0, election_elapsed: 0, timeout: 19, prev_timeout: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.726 INFO became follower at term 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.726 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.726 INFO RawNode created with id 1., id: 1, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.728 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.728 DEBG reset election timeout 0 -> 10 at 0, election_elapsed: 0, timeout: 10, prev_timeout: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.728 INFO became follower at term 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.728 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 1 [INFO] [stderr] Mar 07 04:48:10.728 INFO RawNode created with id 1., id: 1, raft_id: 1 [INFO] [stdout] test test_three_node_cluster_initialization ... ok [INFO] [stderr] Mar 07 04:48:10.792 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false }, raft_id: 1 [INFO] [stdout] test test_propose_command_api ... ok [INFO] [stderr] Mar 07 04:48:10.792 DEBG reset election timeout 0 -> 11 at 0, election_elapsed: 0, timeout: 11, prev_timeout: 0, raft_id: 1 [INFO] [stdout] test test_initial_state ... ok [INFO] [stderr] Mar 07 04:48:10.792 INFO became follower at term 0, term: 0, raft_id: 1 [INFO] [stdout] test test_raw_node_access ... ok [INFO] [stderr] Mar 07 04:48:10.792 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0, raft_id: 1 [INFO] [stdout] [INFO] [stderr] Mar 07 04:48:10.792 INFO RawNode created with id 1., id: 1, raft_id: 1 [INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s [INFO] [stdout] [INFO] [stderr] Running tests/storage_applied_index.rs (/opt/rustwide/target/debug/deps/storage_applied_index-32f20b58ed740da4) [INFO] [stdout] [INFO] [stdout] running 7 tests [INFO] [stdout] test test_new_storage_has_zero_applied_index ... ok [INFO] [stdout] test test_storage_trait_delegation_initial_state ... ok [INFO] [stdout] test test_storage_trait_delegation_first_index ... ok [INFO] [stdout] test test_applied_index_independence_from_commit_index ... ok [INFO] [stdout] test test_set_and_get_applied_index ... ok [INFO] [stdout] test test_applied_index_shared_across_clones ... ok [INFO] [stdout] test test_new_with_conf_state ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s [INFO] [stdout] [INFO] [stderr] Running tests/tcp_transport_e2e.rs (/opt/rustwide/target/debug/deps/tcp_transport_e2e-03939359ad314fc2) [INFO] [stdout] [INFO] [stdout] running 1 test [INFO] [stderr] Mar 07 04:48:10.939 INFO TCP transport started, peer_count: 2, listen_addr: 127.0.0.1:39099, node_id: 1 [INFO] [stderr] Mar 07 04:48:10.940 INFO TCP transport started, peer_count: 2, listen_addr: 127.0.0.1:36619, node_id: 2 [INFO] [stderr] Mar 07 04:48:10.940 INFO Listener thread started, listen_addr: 127.0.0.1:36619 [INFO] [stderr] Mar 07 04:48:10.940 INFO Peer sender thread started, peer_addr: 127.0.0.1:39099, peer_id: 1 [INFO] [stderr] Mar 07 04:48:10.943 INFO Listener thread started, listen_addr: 127.0.0.1:39099 [INFO] [stderr] Mar 07 04:48:10.943 INFO Peer sender thread started, peer_addr: 127.0.0.1:36619, peer_id: 2 [INFO] [stderr] Mar 07 04:48:11.115 DEBG Connecting to peer, attempt: 1, peer_id: 2 [INFO] [stderr] Mar 07 04:48:11.116 DEBG Connected to peer, peer_id: 2 [INFO] [stderr] Mar 07 04:48:11.116 DEBG Accepted connection, peer_addr: Some(127.0.0.1:38032) [INFO] [stdout] test test_tcp_transport_e2e ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.18s [INFO] [stderr] Mar 07 04:48:11.120 INFO Peer sender thread exiting, peer_id: 1 [INFO] [stdout] [INFO] [stderr] Mar 07 04:48:11.120 INFO Peer sender thread exiting, peer_id: 2 [INFO] [stderr] Mar 07 04:48:11.120 DEBG Connection closed by peer [INFO] [stderr] Running tests/three_node_cluster.rs (/opt/rustwide/target/debug/deps/three_node_cluster-52cb57cd0edee52c) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test test_three_node_consensus_full ... ignored [INFO] [stdout] node_id: 1 [INFO] [stdout] raft_id: 1 [INFO] [stdout] node_id: 1 [INFO] [stdout] raft_id: 1 [INFO] [stdout] Mar 07 04:48:11.142 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false } [INFO] [stdout] Mar 07 04:48:11.142 DEBG reset election timeout 0 -> 16 at 0, election_elapsed: 0, timeout: 16, prev_timeout: 0 [INFO] [stdout] Mar 07 04:48:11.142 INFO became follower at term 0, term: 0 [INFO] [stdout] Mar 07 04:48:11.142 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0 [INFO] [stdout] Mar 07 04:48:11.142 INFO RawNode created with id 1., id: 1 [INFO] [stdout] Mar 07 04:48:11.142 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false } [INFO] [stdout] Mar 07 04:48:11.142 DEBG reset election timeout 0 -> 14 at 0, election_elapsed: 0, timeout: 14, prev_timeout: 0 [INFO] [stdout] Mar 07 04:48:11.142 INFO became follower at term 0, term: 0 [INFO] [stdout] Mar 07 04:48:11.142 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0 [INFO] [stdout] Mar 07 04:48:11.142 INFO RawNode created with id 1., id: 1 [INFO] [stdout] node_id: 2 [INFO] [stdout] raft_id: 2 [INFO] [stdout] Mar 07 04:48:11.143 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false } [INFO] [stdout] Mar 07 04:48:11.143 DEBG reset election timeout 0 -> 16 at 0, election_elapsed: 0, timeout: 16, prev_timeout: 0 [INFO] [stdout] Mar 07 04:48:11.143 INFO became follower at term 0, term: 0 [INFO] [stdout] Mar 07 04:48:11.143 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0 [INFO] [stdout] Mar 07 04:48:11.143 INFO RawNode created with id 2., id: 2 [INFO] [stdout] node_id: 3 [INFO] [stdout] raft_id: 3 [INFO] [stdout] Mar 07 04:48:11.143 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false } [INFO] [stdout] Mar 07 04:48:11.143 DEBG reset election timeout 0 -> 13 at 0, election_elapsed: 0, timeout: 13, prev_timeout: 0 [INFO] [stdout] Mar 07 04:48:11.143 INFO became follower at term 0, term: 0 [INFO] [stdout] Mar 07 04:48:11.143 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0 [INFO] [stdout] Mar 07 04:48:11.143 INFO RawNode created with id 3., id: 3 [INFO] [stdout] Mar 07 04:48:11.144 INFO Raft ready loop starting, node_id: 1 [INFO] [stdout] Mar 07 04:48:11.144 INFO Raft ready loop starting, node_id: 1 [INFO] [stdout] node_id: 3 [INFO] [stdout] raft_id: 3 [INFO] [stdout] Mar 07 04:48:11.144 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false } [INFO] [stdout] Mar 07 04:48:11.144 DEBG reset election timeout 0 -> 18 at 0, election_elapsed: 0, timeout: 18, prev_timeout: 0 [INFO] [stdout] Mar 07 04:48:11.144 INFO became follower at term 0, term: 0 [INFO] [stdout] Mar 07 04:48:11.144 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0 [INFO] [stdout] Mar 07 04:48:11.144 INFO RawNode created with id 3., id: 3 [INFO] [stdout] node_id: 2 [INFO] [stdout] raft_id: 2 [INFO] [stdout] Mar 07 04:48:11.178 INFO switched to configuration, config: Configuration { voters: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, learners: {}, learners_next: {}, auto_leave: false } [INFO] [stdout] Mar 07 04:48:11.178 DEBG reset election timeout 0 -> 14 at 0, election_elapsed: 0, timeout: 14, prev_timeout: 0 [INFO] [stdout] Mar 07 04:48:11.178 INFO became follower at term 0, term: 0 [INFO] [stdout] Mar 07 04:48:11.178 INFO newRaft, peers: Configuration { incoming: Configuration { voters: {1, 2, 3} }, outgoing: Configuration { voters: {} } }, last term: 0, last index: 0, applied: 0, commit: 0, term: 0 [INFO] [stdout] Mar 07 04:48:11.178 INFO RawNode created with id 2., id: 2 [INFO] [stdout] Mar 07 04:48:11.179 INFO Raft ready loop starting, node_id: 2 [INFO] [stdout] Mar 07 04:48:11.179 INFO Raft ready loop starting, node_id: 3 [INFO] [stdout] Mar 07 04:48:11.244Mar 07 04:48:11.244 DEBGDEBG Ticking Raft state machineTicking Raft state machine [INFO] [stdout] [INFO] [stdout] Mar 07 04:48:11.247 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.304 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.328 INFO Raft ready loop starting, node_id: 3 [INFO] [stdout] Mar 07 04:48:11.335 INFO Raft ready loop starting, node_id: 2 [INFO] [stdout] Mar 07 04:48:11.335 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.344 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.347 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.378 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.379 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.386 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.428 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.444 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.448 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.465 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.479 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.479 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.529 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.544 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.548 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.567 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.579 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.589 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.629 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.644 INFO Shutdown signal received, exiting ready loop [INFO] [stdout] Mar 07 04:48:11.649 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.649 INFO Shutdown signal received, exiting ready loop [INFO] [stdout] Mar 07 04:48:11.650 INFO Shutdown signal received, exiting ready loop [INFO] [stdout] test test_three_node_cluster_basic_setup ... ok [INFO] [stdout] Mar 07 04:48:11.667 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.679 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.748 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.767 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.780 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.848 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.867 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.880 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.951 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.973 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:11.981 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.049 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.079 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.082 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.149 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.188 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.195 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.250 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.285 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.288 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.350 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.385 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.388 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.450 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.486 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.489 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.553 DEBG Received Raft message, msg_type: MsgRequestPreVote, to: 3, from: 2 [INFO] [stdout] Mar 07 04:48:12.553 INFO [logterm: 0, index: 0, vote: 0] cast vote for 2 [logterm: 0, index: 0] at term 0, msg type: MsgRequestPreVote, term: 0, msg_index: 0, msg_term: 0, from: 2, vote: 0, log_index: 0, log_term: 0 [INFO] [stdout] Mar 07 04:48:12.553 DEBG Sending from 3 to 2, msg: Message { msg_type: MsgRequestPreVoteResponse, to: 2, from: 0, term: 1, log_term: 0, index: 0, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 3 [INFO] [stdout] Mar 07 04:48:12.553 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.553 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.554 DEBG Received Raft message, msg_type: MsgRequestPreVote, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.554 INFO [logterm: 0, index: 0, vote: 0] cast vote for 2 [logterm: 0, index: 0] at term 0, msg type: MsgRequestPreVote, term: 0, msg_index: 0, msg_term: 0, from: 2, vote: 0, log_index: 0, log_term: 0 [INFO] [stdout] Mar 07 04:48:12.554 DEBG Sending from 1 to 2, msg: Message { msg_type: MsgRequestPreVoteResponse, to: 2, from: 0, term: 1, log_term: 0, index: 0, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.554 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.554 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Received Raft message, msg_type: MsgRequestVote, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Received Raft message, msg_type: MsgRequestVote, to: 3, from: 2 [INFO] [stdout] Mar 07 04:48:12.555 INFO received a message with higher term from 2, msg type: MsgRequestVote, message_term: 1, term: 0, from: 2 [INFO] [stdout] Mar 07 04:48:12.555 DEBG reset election timeout 18 -> 10 at 13, election_elapsed: 13, timeout: 10, prev_timeout: 18 [INFO] [stdout] Mar 07 04:48:12.555 INFO became follower at term 1, term: 1 [INFO] [stdout] Mar 07 04:48:12.555 INFO [logterm: 0, index: 0, vote: 0] cast vote for 2 [logterm: 0, index: 0] at term 1, msg type: MsgRequestVote, term: 1, msg_index: 0, msg_term: 0, from: 2, vote: 0, log_index: 0, log_term: 0 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Sending from 3 to 2, msg: Message { msg_type: MsgRequestVoteResponse, to: 2, from: 0, term: 1, log_term: 0, index: 0, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 3 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.555 DEBG Persisting HardState, commit: 0, vote: 2, term: 1 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.555 INFO received a message with higher term from 2, msg type: MsgRequestVote, message_term: 1, term: 0, from: 2 [INFO] [stdout] Mar 07 04:48:12.555 DEBG reset election timeout 14 -> 18 at 13, election_elapsed: 13, timeout: 18, prev_timeout: 14 [INFO] [stdout] Mar 07 04:48:12.555 INFO became follower at term 1, term: 1 [INFO] [stdout] Mar 07 04:48:12.555 INFO [logterm: 0, index: 0, vote: 0] cast vote for 2 [logterm: 0, index: 0] at term 1, msg type: MsgRequestVote, term: 1, msg_index: 0, msg_term: 0, from: 2, vote: 0, log_index: 0, log_term: 0 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Sending from 1 to 2, msg: Message { msg_type: MsgRequestVoteResponse, to: 2, from: 0, term: 1, log_term: 0, index: 0, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.555 DEBG Persisting HardState, commit: 0, vote: 2, term: 1 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Received Raft message, msg_type: MsgAppend, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Sending from 1 to 2, msg: Message { msg_type: MsgAppendResponse, to: 2, from: 0, term: 0, log_term: 0, index: 1, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entries: 1, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.555 DEBG Appending entries to storage, count: 1 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.555 INFO Leadership changed, new_leader: 2 [INFO] [stdout] Mar 07 04:48:12.555 DEBG persisted index 1 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Received Raft message, msg_type: MsgAppend, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Sending from 1 to 2, msg: Message { msg_type: MsgAppendResponse, to: 2, from: 0, term: 0, log_term: 0, index: 1, entries: [], commit: 1, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Processing Ready state, num_committed: 1, num_persisted_messages: 1, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.555 DEBG Persisting HardState, commit: 1, vote: 2, term: 1 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Received Raft message, msg_type: MsgAppend, to: 3, from: 2 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Sending from 3 to 2, msg: Message { msg_type: MsgAppendResponse, to: 2, from: 0, term: 0, log_term: 0, index: 1, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 3 [INFO] [stdout] Mar 07 04:48:12.555 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entries: 1, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.556 DEBG Appending entries to storage, count: 1 [INFO] [stdout] Mar 07 04:48:12.556 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.556 INFO Leadership changed, new_leader: 2 [INFO] [stdout] Mar 07 04:48:12.556 DEBG persisted index 1 [INFO] [stdout] Mar 07 04:48:12.556 DEBG Received Raft message, msg_type: MsgAppend, to: 3, from: 2 [INFO] [stdout] Mar 07 04:48:12.556 DEBG Sending from 3 to 2, msg: Message { msg_type: MsgAppendResponse, to: 2, from: 0, term: 0, log_term: 0, index: 1, entries: [], commit: 1, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 3 [INFO] [stdout] Mar 07 04:48:12.556 DEBG Processing Ready state, num_committed: 1, num_persisted_messages: 1, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.556 DEBG Persisting HardState, commit: 1, vote: 2, term: 1 [INFO] [stdout] Mar 07 04:48:12.556 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.565 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.565 INFO starting a new election, term: 0 [INFO] [stdout] Mar 07 04:48:12.565 INFO became pre-candidate at term 0, term: 0 [INFO] [stdout] Mar 07 04:48:12.565 DEBG Sending from 2 to 1, msg: Message { msg_type: MsgRequestPreVote, to: 1, from: 0, term: 1, log_term: 0, index: 0, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.565 DEBG Sending from 2 to 3, msg: Message { msg_type: MsgRequestPreVote, to: 3, from: 0, term: 1, log_term: 0, index: 0, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 3, from: 2 [INFO] [stdout] Mar 07 04:48:12.565 INFO broadcasting vote request, to: [1, 3], log_index: 0, log_term: 0, term: 0, type: MsgRequestPreVote [INFO] [stdout] Mar 07 04:48:12.565 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 2, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.565 DEBG Sending messages, count: 2 [INFO] [stdout] Mar 07 04:48:12.565 DEBG Received Raft message, msg_type: MsgRequestPreVoteResponse, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.565 INFO received votes response, term: 0, type: MsgRequestPreVoteResponse, approvals: 2, rejections: 0, from: 1, vote: true [INFO] [stdout] Mar 07 04:48:12.565 DEBG reset election timeout 14 -> 14 at 0, election_elapsed: 0, timeout: 14, prev_timeout: 14 [INFO] [stdout] Mar 07 04:48:12.565 INFO became candidate at term 1, term: 1 [INFO] [stdout] Mar 07 04:48:12.565 DEBG Sending from 2 to 1, msg: Message { msg_type: MsgRequestVote, to: 1, from: 0, term: 1, log_term: 0, index: 0, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.565 DEBG Sending from 2 to 3, msg: Message { msg_type: MsgRequestVote, to: 3, from: 0, term: 1, log_term: 0, index: 0, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 3, from: 2 [INFO] [stdout] Mar 07 04:48:12.565 INFO broadcasting vote request, to: [1, 3], log_index: 0, log_term: 0, term: 1, type: MsgRequestVote [INFO] [stdout] Mar 07 04:48:12.565 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 2, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.565 DEBG Persisting HardState, commit: 0, vote: 2, term: 1 [INFO] [stdout] Mar 07 04:48:12.565 DEBG Sending messages, count: 2 [INFO] [stdout] Mar 07 04:48:12.565 DEBG Received Raft message, msg_type: MsgRequestPreVoteResponse, to: 2, from: 3 [INFO] [stdout] Mar 07 04:48:12.565 DEBG Received Raft message, msg_type: MsgRequestVoteResponse, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.565 INFO received votes response, term: 1, type: MsgRequestVoteResponse, approvals: 2, rejections: 0, from: 1, vote: true [INFO] [stdout] Mar 07 04:48:12.565 DEBG reset election timeout 14 -> 18 at 0, election_elapsed: 0, timeout: 18, prev_timeout: 14 [INFO] [stdout] Mar 07 04:48:12.565 INFO became leader at term 1, term: 1 [INFO] [stdout] Mar 07 04:48:12.565 DEBG Sending from 2 to 1, msg: Message { msg_type: MsgAppend, to: 1, from: 0, term: 0, log_term: 0, index: 0, entries: [Entry { entry_type: EntryNormal, term: 1, index: 1, data: [], context: [], sync_log: false }], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.565 DEBG Sending from 2 to 3, msg: Message { msg_type: MsgAppend, to: 3, from: 0, term: 0, log_term: 0, index: 0, entries: [Entry { entry_type: EntryNormal, term: 1, index: 1, data: [], context: [], sync_log: false }], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 3, from: 2 [INFO] [stdout] Mar 07 04:48:12.566 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 0, num_messages: 2, num_entries: 1, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.566 DEBG Sending messages, count: 2 [INFO] [stdout] Mar 07 04:48:12.566 DEBG Appending entries to storage, count: 1 [INFO] [stdout] Mar 07 04:48:12.566 INFO Leadership changed, new_leader: 2 [INFO] [stdout] Mar 07 04:48:12.566 DEBG persisted index 1 [INFO] [stdout] Mar 07 04:48:12.566 DEBG Received Raft message, msg_type: MsgRequestVoteResponse, to: 2, from: 3 [INFO] [stdout] Mar 07 04:48:12.566 DEBG Received Raft message, msg_type: MsgAppendResponse, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.566 DEBG committing index 1, index: 1 [INFO] [stdout] Mar 07 04:48:12.566 DEBG Sending from 2 to 1, msg: Message { msg_type: MsgAppend, to: 1, from: 0, term: 0, log_term: 1, index: 1, entries: [], commit: 1, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.566 DEBG Processing Ready state, num_committed: 1, num_persisted_messages: 0, num_messages: 1, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.566 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.566 DEBG Persisting HardState, commit: 1, vote: 2, term: 1 [INFO] [stdout] Mar 07 04:48:12.566 DEBG Received Raft message, msg_type: MsgAppendResponse, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.566 DEBG Received Raft message, msg_type: MsgAppendResponse, to: 2, from: 3 [INFO] [stdout] Mar 07 04:48:12.566 DEBG Sending from 2 to 3, msg: Message { msg_type: MsgAppend, to: 3, from: 0, term: 0, log_term: 1, index: 1, entries: [], commit: 1, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 3, from: 2 [INFO] [stdout] Mar 07 04:48:12.566 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 0, num_messages: 1, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.566 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.566 DEBG Received Raft message, msg_type: MsgAppendResponse, to: 2, from: 3 [INFO] [stdout] Mar 07 04:48:12.583 DEBG Received Raft message, msg_type: MsgRequestPreVote, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.584 INFO [logterm: 1, index: 1, vote: 2] cast vote for 1 [logterm: 1, index: 1] at term 1, msg type: MsgRequestPreVote, term: 1, msg_index: 1, msg_term: 1, from: 1, vote: 2, log_index: 1, log_term: 1 [INFO] [stdout] Mar 07 04:48:12.584 DEBG Sending from 2 to 1, msg: Message { msg_type: MsgRequestPreVoteResponse, to: 1, from: 0, term: 2, log_term: 0, index: 0, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.584 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 0, num_messages: 1, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.584 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.584 DEBG Received Raft message, msg_type: MsgRequestVote, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.584 INFO received a message with higher term from 1, msg type: MsgRequestVote, message_term: 2, term: 1, from: 1 [INFO] [stdout] Mar 07 04:48:12.584 DEBG reset election timeout 18 -> 17 at 0, election_elapsed: 0, timeout: 17, prev_timeout: 18 [INFO] [stdout] Mar 07 04:48:12.584 INFO became follower at term 2, term: 2 [INFO] [stdout] Mar 07 04:48:12.585 INFO [logterm: 1, index: 1, vote: 0] cast vote for 1 [logterm: 1, index: 1] at term 2, msg type: MsgRequestVote, term: 2, msg_index: 1, msg_term: 1, from: 1, vote: 0, log_index: 1, log_term: 1 [INFO] [stdout] Mar 07 04:48:12.585 DEBG Sending from 2 to 1, msg: Message { msg_type: MsgRequestVoteResponse, to: 1, from: 0, term: 2, log_term: 0, index: 0, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.585 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.585 DEBG Persisting HardState, commit: 1, vote: 1, term: 2 [INFO] [stdout] Mar 07 04:48:12.585 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.585 DEBG Received Raft message, msg_type: MsgAppend, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.585 DEBG Sending from 2 to 1, msg: Message { msg_type: MsgAppendResponse, to: 1, from: 0, term: 0, log_term: 0, index: 2, entries: [], commit: 1, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.585 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entries: 1, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.585 DEBG Appending entries to storage, count: 1 [INFO] [stdout] Mar 07 04:48:12.585 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.585 INFO Leadership changed, new_leader: 1 [INFO] [stdout] Mar 07 04:48:12.585 DEBG persisted index 2 [INFO] [stdout] Mar 07 04:48:12.585 DEBG Received Raft message, msg_type: MsgAppend, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.585 DEBG Sending from 2 to 1, msg: Message { msg_type: MsgAppendResponse, to: 1, from: 0, term: 0, log_term: 0, index: 2, entries: [], commit: 2, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.585 DEBG Processing Ready state, num_committed: 1, num_persisted_messages: 1, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.585 DEBG Persisting HardState, commit: 2, vote: 1, term: 2 [INFO] [stdout] Mar 07 04:48:12.585 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Received user command, command: Campaign [INFO] [stdout] Mar 07 04:48:12.590 INFO starting a new election, term: 1 [INFO] [stdout] Mar 07 04:48:12.590 INFO became pre-candidate at term 1, term: 1 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Sending from 1 to 2, msg: Message { msg_type: MsgRequestPreVote, to: 2, from: 0, term: 2, log_term: 1, index: 1, entries: [], commit: 1, commit_term: 1, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Sending from 1 to 3, msg: Message { msg_type: MsgRequestPreVote, to: 3, from: 0, term: 2, log_term: 1, index: 1, entries: [], commit: 1, commit_term: 1, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 3, from: 1 [INFO] [stdout] Mar 07 04:48:12.590 INFO broadcasting vote request, to: [2, 3], log_index: 1, log_term: 1, term: 1, type: MsgRequestPreVote [INFO] [stdout] Mar 07 04:48:12.590 INFO Starting election campaign [INFO] [stdout] Mar 07 04:48:12.590 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 2, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.590 DEBG Sending messages, count: 2 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Received Raft message, msg_type: MsgRequestPreVoteResponse, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.590 INFO received votes response, term: 1, type: MsgRequestPreVoteResponse, approvals: 2, rejections: 0, from: 2, vote: true [INFO] [stdout] Mar 07 04:48:12.590 DEBG reset election timeout 18 -> 14 at 0, election_elapsed: 0, timeout: 14, prev_timeout: 18 [INFO] [stdout] Mar 07 04:48:12.590 INFO became candidate at term 2, term: 2 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Sending from 1 to 2, msg: Message { msg_type: MsgRequestVote, to: 2, from: 0, term: 2, log_term: 1, index: 1, entries: [], commit: 1, commit_term: 1, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Sending from 1 to 3, msg: Message { msg_type: MsgRequestVote, to: 3, from: 0, term: 2, log_term: 1, index: 1, entries: [], commit: 1, commit_term: 1, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 3, from: 1 [INFO] [stdout] Mar 07 04:48:12.590 INFO broadcasting vote request, to: [2, 3], log_index: 1, log_term: 1, term: 2, type: MsgRequestVote [INFO] [stdout] Mar 07 04:48:12.590 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 2, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.590 DEBG Persisting HardState, commit: 1, vote: 1, term: 2 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Sending messages, count: 2 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Received Raft message, msg_type: MsgRequestPreVoteResponse, to: 1, from: 3 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Received Raft message, msg_type: MsgRequestVoteResponse, to: 1, from: 3 [INFO] [stdout] Mar 07 04:48:12.590 INFO received votes response, term: 2, type: MsgRequestVoteResponse, approvals: 2, rejections: 0, from: 3, vote: true [INFO] [stdout] Mar 07 04:48:12.590 DEBG reset election timeout 14 -> 19 at 0, election_elapsed: 0, timeout: 19, prev_timeout: 14 [INFO] [stdout] Mar 07 04:48:12.590 INFO became leader at term 2, term: 2 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Sending from 1 to 2, msg: Message { msg_type: MsgAppend, to: 2, from: 0, term: 0, log_term: 1, index: 1, entries: [Entry { entry_type: EntryNormal, term: 2, index: 2, data: [], context: [], sync_log: false }], commit: 1, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Sending from 1 to 3, msg: Message { msg_type: MsgAppend, to: 3, from: 0, term: 0, log_term: 1, index: 1, entries: [Entry { entry_type: EntryNormal, term: 2, index: 2, data: [], context: [], sync_log: false }], commit: 1, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 3, from: 1 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 0, num_messages: 2, num_entries: 1, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.590 DEBG Sending messages, count: 2 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Appending entries to storage, count: 1 [INFO] [stdout] Mar 07 04:48:12.590 INFO Leadership changed, new_leader: 1 [INFO] [stdout] Mar 07 04:48:12.590 DEBG persisted index 2 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Received Raft message, msg_type: MsgRequestVoteResponse, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.590 DEBG Received Raft message, msg_type: MsgAppendResponse, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.591 DEBG committing index 2, index: 2 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Sending from 1 to 2, msg: Message { msg_type: MsgAppend, to: 2, from: 0, term: 0, log_term: 2, index: 2, entries: [], commit: 2, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Processing Ready state, num_committed: 1, num_persisted_messages: 0, num_messages: 1, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.591 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Persisting HardState, commit: 2, vote: 1, term: 2 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Received Raft message, msg_type: MsgAppendResponse, to: 1, from: 3 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Sending from 1 to 3, msg: Message { msg_type: MsgAppend, to: 3, from: 0, term: 0, log_term: 2, index: 2, entries: [], commit: 2, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 3, from: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 0, num_messages: 1, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.591 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Received Raft message, msg_type: MsgAppendResponse, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Received Raft message, msg_type: MsgAppendResponse, to: 1, from: 3 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.591 DEBG Received Raft message, msg_type: MsgRequestPreVote, to: 3, from: 1 [INFO] [stdout] Mar 07 04:48:12.591 INFO [logterm: 1, index: 1, vote: 2] cast vote for 1 [logterm: 1, index: 1] at term 1, msg type: MsgRequestPreVote, term: 1, msg_index: 1, msg_term: 1, from: 1, vote: 2, log_index: 1, log_term: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Sending from 3 to 1, msg: Message { msg_type: MsgRequestPreVoteResponse, to: 1, from: 0, term: 2, log_term: 0, index: 0, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 3 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.591 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Received Raft message, msg_type: MsgRequestVote, to: 3, from: 1 [INFO] [stdout] Mar 07 04:48:12.591 INFO received a message with higher term from 1, msg type: MsgRequestVote, message_term: 2, term: 1, from: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG reset election timeout 10 -> 13 at 0, election_elapsed: 0, timeout: 13, prev_timeout: 10 [INFO] [stdout] Mar 07 04:48:12.591 INFO became follower at term 2, term: 2 [INFO] [stdout] Mar 07 04:48:12.591 INFO [logterm: 1, index: 1, vote: 0] cast vote for 1 [logterm: 1, index: 1] at term 2, msg type: MsgRequestVote, term: 2, msg_index: 1, msg_term: 1, from: 1, vote: 0, log_index: 1, log_term: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Sending from 3 to 1, msg: Message { msg_type: MsgRequestVoteResponse, to: 1, from: 0, term: 2, log_term: 0, index: 0, entries: [], commit: 0, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 3 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.591 DEBG Persisting HardState, commit: 1, vote: 1, term: 2 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Received Raft message, msg_type: MsgAppend, to: 3, from: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Sending from 3 to 1, msg: Message { msg_type: MsgAppendResponse, to: 1, from: 0, term: 0, log_term: 0, index: 2, entries: [], commit: 1, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 3 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entries: 1, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.591 DEBG Appending entries to storage, count: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.591 INFO Leadership changed, new_leader: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG persisted index 2 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Received Raft message, msg_type: MsgAppend, to: 3, from: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Sending from 3 to 1, msg: Message { msg_type: MsgAppendResponse, to: 1, from: 0, term: 0, log_term: 0, index: 2, entries: [], commit: 2, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 3 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Processing Ready state, num_committed: 1, num_persisted_messages: 1, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.591 DEBG Persisting HardState, commit: 2, vote: 1, term: 2 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.591 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.654 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.691 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.691 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.765 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.786 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.786 DEBG Sending from 1 to 2, msg: Message { msg_type: MsgHeartbeat, to: 2, from: 0, term: 0, log_term: 0, index: 0, entries: [], commit: 2, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.786 DEBG Sending from 1 to 3, msg: Message { msg_type: MsgHeartbeat, to: 3, from: 0, term: 0, log_term: 0, index: 0, entries: [], commit: 2, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 3, from: 1 [INFO] [stdout] Mar 07 04:48:12.786 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 0, num_messages: 2, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.786 DEBG Sending messages, count: 2 [INFO] [stdout] Mar 07 04:48:12.786 DEBG Received Raft message, msg_type: MsgHeartbeatResponse, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.786 DEBG Received Raft message, msg_type: MsgHeartbeat, to: 3, from: 1 [INFO] [stdout] Mar 07 04:48:12.786 DEBG Sending from 3 to 1, msg: Message { msg_type: MsgHeartbeatResponse, to: 1, from: 0, term: 0, log_term: 0, index: 0, entries: [], commit: 2, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 3 [INFO] [stdout] Mar 07 04:48:12.786 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.786 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.786 DEBG Received Raft message, msg_type: MsgHeartbeatResponse, to: 1, from: 3 [INFO] [stdout] Mar 07 04:48:12.789 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.792 DEBG Received Raft message, msg_type: MsgHeartbeat, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:12.792 DEBG Sending from 2 to 1, msg: Message { msg_type: MsgHeartbeatResponse, to: 1, from: 0, term: 0, log_term: 0, index: 0, entries: [], commit: 2, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:12.792 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:12.792 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:12.856 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.888 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.889 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.976 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.986 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:12.989 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:13.060 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:13.087 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:13.087 DEBG Sending from 1 to 2, msg: Message { msg_type: MsgHeartbeat, to: 2, from: 0, term: 0, log_term: 0, index: 0, entries: [], commit: 2, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:13.087 DEBG Sending from 1 to 3, msg: Message { msg_type: MsgHeartbeat, to: 3, from: 0, term: 0, log_term: 0, index: 0, entries: [], commit: 2, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, to: 3, from: 1 [INFO] [stdout] Mar 07 04:48:13.087 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 0, num_messages: 2, num_entries: 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:13.087 DEBG Sending messages, count: 2 [INFO] [stdout] Mar 07 04:48:13.087 DEBG Received Raft message, msg_type: MsgHeartbeat, to: 2, from: 1 [INFO] [stdout] Mar 07 04:48:13.087 Mar 07 04:48:13.087DEBG DEBG Sending from 2 to 1Received Raft message, , msgmsg_type:: Message { msg_type: MsgHeartbeatResponse, to: 1, from: 0, term: 0, log_term: 0, index: 0, entries: [], commit: 2, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }MsgHeartbeat, , toto:: 13, from, :from :2 1 [INFO] [stdout] [INFO] [stdout] Mar 07 04:48:13.087 DEBG Mar 07 04:48:13.087 Processing Ready stateDEBG Sending from 3 to 1, num_committed, :msg :0 Message { msg_type: MsgHeartbeatResponse, to: 1, from: 0, term: 0, log_term: 0, index: 0, entries: [], commit: 2, commit_term: 0, snapshot: None, request_snapshot: 0, reject: false, reject_hint: 0, context: [], deprecated_priority: 0, priority: 0 }, , num_persisted_messagesto:: 11, , num_messagesfrom:: 03, num_entries [INFO] [stdout] : 0, has_snapshot: false [INFO] [stdout] Mar 07 04:48:13.087 DEBG Processing Ready state, num_committed: 0, num_persisted_messages: 1, num_messages: 0, num_entriesMar 07 04:48:13.087: DEBG0 , Sending messageshas_snapshot: , falsecount: [INFO] [stdout] 1 [INFO] [stdout] Mar 07 04:48:13.087 DEBG Sending messages, count: 1 [INFO] [stdout] Mar 07 04:48:13.087 DEBG Received Raft message, msg_type: MsgHeartbeatResponse, to: 1, from: 2 [INFO] [stdout] Mar 07 04:48:13.087 DEBG Received Raft message, msg_type: MsgHeartbeatResponse, to: 1, from: 3 [INFO] [stdout] Mar 07 04:48:13.089 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:13.193 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:13.193 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:13.196 DEBG Ticking Raft state machine [INFO] [stdout] Mar 07 04:48:13.284 INFO Shutdown signal received, exiting ready loop [INFO] [stdout] Mar 07 04:48:13.284 INFO Shutdown signal received, exiting ready loop [INFO] [stdout] Mar 07 04:48:13.285 INFO Shutdown signal received, exiting ready loop [INFO] [stdout] test test_follower_campaign_becomes_leader ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 2.15s [INFO] [stdout] [INFO] [stderr] Running tests/user_command_aliases.rs (/opt/rustwide/target/debug/deps/user_command_aliases-9eebd22cd7dd5157) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test long_and_short_forms_equivalence ... ok [INFO] [stdout] test test_campaign_alias ... ok [INFO] [stdout] test test_keys_alias ... ok [INFO] [stdout] test test_status_alias ... ok [INFO] [stdout] test test_get_alias ... ok [INFO] [stdout] test test_put_alias ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stderr] Running tests/user_command_parse.rs (/opt/rustwide/target/debug/deps/user_command_parse-6fc3635020e2c91a) [INFO] [stdout] [INFO] [stdout] running 3 tests [INFO] [stdout] test test_keys_parsing ... ok [INFO] [stdout] test test_put_parsing ... ok [INFO] [stdout] test test_get_parsing ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/raft_proto-df0c200a53e84b76) [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] [stderr] Running unittests src/main.rs (/opt/rustwide/target/debug/deps/raft_server-a6f4e381043c42e0) [INFO] [stdout] [INFO] [stdout] running 9 tests [INFO] [stdout] test cli::tests::test_parse_peers_empty ... ok [INFO] [stdout] test cli::tests::test_parse_peers_duplicate_id ... ok [INFO] [stdout] test cli::tests::test_parse_peers_invalid_format_missing_equals ... ok [INFO] [stdout] test cli::tests::test_parse_peers_invalid_id ... ok [INFO] [stdout] test cli::tests::test_parse_peers_valid_multiple ... ok [INFO] [stdout] test cli::tests::test_parse_peers_valid_single ... ok [INFO] [stdout] test cli::tests::test_parse_peers_localhost_variants ... ok [INFO] [stdout] test cli::tests::test_parse_peers_invalid_address ... ok [INFO] [stdout] test cli::tests::test_parse_peers_with_whitespace ... ok [INFO] [stderr] Doc-tests raft_core [INFO] [stdout] [INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] running 2 tests [INFO] [stdout] test crates/raft-core/src/raft_node.rs - raft_node::RaftNode::new (line 38) - compile ... ok [INFO] [stdout] test crates/raft-core/src/network.rs - network::LocalTransport::new (line 51) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.18s [INFO] [stdout] [INFO] [stderr] Doc-tests raft_proto [INFO] [stdout] [INFO] [stdout] running 0 tests [INFO] [stdout] [INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "e4a9fa942280a7011def845ba426dbdd03b5f5e220790c716abee715fd5bec50", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "e4a9fa942280a7011def845ba426dbdd03b5f5e220790c716abee715fd5bec50", kill_on_drop: false }` [INFO] [stdout] e4a9fa942280a7011def845ba426dbdd03b5f5e220790c716abee715fd5bec50