[INFO] fetching crate tender 0.1.1...
[INFO] testing tender-0.1.1 against 1.76.0 for beta-1.77-1
[INFO] extracting crate tender 0.1.1 into /workspace/builds/worker-5-tc1/source
[INFO] validating manifest of crates.io crate tender 0.1.1 on toolchain 1.76.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.76.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate tender 0.1.1
[INFO] finished tweaking crates.io crate tender 0.1.1
[INFO] tweaked toml for crates.io crate tender 0.1.1 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.76.0" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.76.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded watch v0.2.3
[INFO] [stderr]   Downloaded sync-wait-group v0.1.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.76.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f3ebca8db8b9425291c56580dcd5eb3e493498368e684b76b27ea28bb7b4b0ae
[INFO] running `Command { std: "docker" "start" "-a" "f3ebca8db8b9425291c56580dcd5eb3e493498368e684b76b27ea28bb7b4b0ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f3ebca8db8b9425291c56580dcd5eb3e493498368e684b76b27ea28bb7b4b0ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f3ebca8db8b9425291c56580dcd5eb3e493498368e684b76b27ea28bb7b4b0ae", kill_on_drop: false }`
[INFO] [stdout] f3ebca8db8b9425291c56580dcd5eb3e493498368e684b76b27ea28bb7b4b0ae
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.76.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] f29e03984f1d5da04bea9c2edb458381ccbd92f42bb2373566cd4d6bf5aa2703
[INFO] running `Command { std: "docker" "start" "-a" "f29e03984f1d5da04bea9c2edb458381ccbd92f42bb2373566cd4d6bf5aa2703", kill_on_drop: false }`
[INFO] [stderr]    Compiling crossbeam-utils v0.8.19
[INFO] [stderr]    Compiling byteorder v1.5.0
[INFO] [stderr]    Compiling fastrand v1.9.0
[INFO] [stderr]    Compiling log v0.4.20
[INFO] [stderr]    Compiling sync-wait-group v0.1.1
[INFO] [stderr]    Compiling watch v0.2.3
[INFO] [stderr]    Compiling fxhash v0.2.1
[INFO] [stderr]    Compiling crossbeam-channel v0.5.11
[INFO] [stderr]    Compiling tender v0.1.1 (/opt/rustwide/workdir)
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]   --> src/core/observer.rs:17:16
[INFO] [stdout]    |
[INFO] [stdout] 17 |     pub fn run(mut self) {
[INFO] [stdout]    |                ----^^^^
[INFO] [stdout]    |                |
[INFO] [stdout]    |                help: remove this `mut`
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_mut)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.93s
[INFO] running `Command { std: "docker" "inspect" "f29e03984f1d5da04bea9c2edb458381ccbd92f42bb2373566cd4d6bf5aa2703", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f29e03984f1d5da04bea9c2edb458381ccbd92f42bb2373566cd4d6bf5aa2703", kill_on_drop: false }`
[INFO] [stdout] f29e03984f1d5da04bea9c2edb458381ccbd92f42bb2373566cd4d6bf5aa2703
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.76.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 8c48e5e5b6a4af9005a3f75de8e33bc14971545cd51fac71a19b6d41f3e8a6ff
[INFO] running `Command { std: "docker" "start" "-a" "8c48e5e5b6a4af9005a3f75de8e33bc14971545cd51fac71a19b6d41f3e8a6ff", kill_on_drop: false }`
[INFO] [stderr]    Compiling memchr v2.7.1
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling tender v0.1.1 (/opt/rustwide/workdir)
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]   --> src/core/observer.rs:17:16
[INFO] [stdout]    |
[INFO] [stdout] 17 |     pub fn run(mut self) {
[INFO] [stdout]    |                ----^^^^
[INFO] [stdout]    |                |
[INFO] [stdout]    |                help: remove this `mut`
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_mut)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling aho-corasick v1.1.2
[INFO] [stderr]    Compiling regex-automata v0.4.5
[INFO] [stderr]    Compiling regex v1.10.3
[INFO] [stderr]    Compiling env_logger v0.9.3
[INFO] [stdout] warning: variable does not need to be mutable
[INFO] [stdout]   --> src/core/observer.rs:17:16
[INFO] [stdout]    |
[INFO] [stdout] 17 |     pub fn run(mut self) {
[INFO] [stdout]    |                ----^^^^
[INFO] [stdout]    |                |
[INFO] [stdout]    |                help: remove this `mut`
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_mut)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 1 warning emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 4.27s
[INFO] running `Command { std: "docker" "inspect" "8c48e5e5b6a4af9005a3f75de8e33bc14971545cd51fac71a19b6d41f3e8a6ff", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8c48e5e5b6a4af9005a3f75de8e33bc14971545cd51fac71a19b6d41f3e8a6ff", kill_on_drop: false }`
[INFO] [stdout] 8c48e5e5b6a4af9005a3f75de8e33bc14971545cd51fac71a19b6d41f3e8a6ff
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:2788e3201cd34a07e3172128adcd8b3090168a8e3bcc40d7c032b9dda1df7d1c" "/opt/rustwide/cargo-home/bin/cargo" "+1.76.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 1f9c8510dada4749964368ddb148b1278fe47909a824259cd0476f9a2de4fccd
[INFO] running `Command { std: "docker" "start" "-a" "1f9c8510dada4749964368ddb148b1278fe47909a824259cd0476f9a2de4fccd", kill_on_drop: false }`
[INFO] [stderr] warning: variable does not need to be mutable
[INFO] [stderr]   --> src/core/observer.rs:17:16
[INFO] [stderr]    |
[INFO] [stderr] 17 |     pub fn run(mut self) {
[INFO] [stderr]    |                ----^^^^
[INFO] [stderr]    |                |
[INFO] [stderr]    |                help: remove this `mut`
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(unused_mut)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `tender` (lib) generated 1 warning (run `cargo fix --lib -p tender` to apply 1 suggestion)
[INFO] [stderr] warning: `tender` (lib test) generated 1 warning (1 duplicate)
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/tender-75b9f2db74e33e6c)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/fixtures.rs (/opt/rustwide/target/debug/deps/fixtures-5a7ef0bd1fbbc178)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/group_election.rs (/opt/rustwide/target/debug/deps/group_election-97c697065cdfd725)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test test_election_with_initial_candidate ... ok
[INFO] [stdout] test test_step_up_and_down ... FAILED
[INFO] [stdout] test test_election_with_quorum ... ok
[INFO] [stdout] test test_election ... ok
[INFO] [stdout] test test_move_leader ... ok
[INFO] [stdout] test test_election_with_observer ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- test_step_up_and_down stdout ----
[INFO] [stdout] [2024-02-13T11:06:02Z INFO  tender::core] [NodeId(1000-1001)][Term(0)] start election main thread
[INFO] [stdout] [2024-02-13T11:06:02Z INFO  tender::core::startup] [NodeId(1000-1001)][Term(0)] start the startup loop
[INFO] [stdout] [2024-02-13T11:06:02Z INFO  group_election::fixtures] [Node(NodeId(1000-1001))] an event has happened: Startup
[INFO] [stdout] [2024-02-13T11:06:02Z INFO  tender::core] [NodeId(1000-1002)][Term(0)] start election main thread
[INFO] [stdout] [2024-02-13T11:06:02Z INFO  tender::core::startup] [NodeId(1000-1002)][Term(0)] start the startup loop
[INFO] [stdout] [2024-02-13T11:06:02Z INFO  group_election::fixtures] [Node(NodeId(1000-1002))] an event has happened: Startup
[INFO] [stdout] [2024-02-13T11:06:02Z INFO  tender::core] [NodeId(1000-1003)][Term(0)] start election main thread
[INFO] [stdout] [2024-02-13T11:06:02Z INFO  tender::core::startup] [NodeId(1000-1003)][Term(0)] start the startup loop
[INFO] [stdout] [2024-02-13T11:06:02Z INFO  group_election::fixtures] [Node(NodeId(1000-1003))] an event has happened: Startup
[INFO] [stdout] [2024-02-13T11:06:03Z INFO  tender::core::startup] [NodeId(1000-1001)][Term(1)] this node is forced to be leader
[INFO] [stdout] [2024-02-13T11:06:03Z INFO  tender::core::startup] [NodeId(1000-1002)][Term(0)] this node is initialized with 3 members, and transit to follower
[INFO] [stdout] [2024-02-13T11:06:03Z INFO  tender::core::startup] [NodeId(1000-1003)][Term(0)] this node is initialized with 3 members, and transit to follower
[INFO] [stdout] [2024-02-13T11:06:03Z INFO  tender::core::follower] [NodeId(1000-1003)][Term(0)] start the follower loop
[INFO] [stdout] [2024-02-13T11:06:03Z INFO  tender::core::leader] [NodeId(1000-1001)][Term(1)] start the leader loop
[INFO] [stdout] [2024-02-13T11:06:03Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1003))
[INFO] [stdout] [2024-02-13T11:06:03Z INFO  tender::core::follower] [NodeId(1000-1002)][Term(0)] start the follower loop
[INFO] [stdout] [2024-02-13T11:06:03Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1002))
[INFO] [stdout] [2024-02-13T11:06:03Z INFO  group_election::fixtures] [Node(NodeId(1000-1001))] an event has happened: TransitToLeader { term: 1, caused_by_step_up: false }
[INFO] [stdout] [2024-02-13T11:06:03Z INFO  group_election::fixtures] [Node(NodeId(1000-1003))] an event has happened: TransitToFollower { term: 0, prev_state: Startup, caused_by_step_down: false }
[INFO] [stdout] [2024-02-13T11:06:03Z INFO  group_election::fixtures] [Node(NodeId(1000-1002))] an event has happened: TransitToFollower { term: 0, prev_state: Startup, caused_by_step_down: false }
[INFO] [stdout] [2024-02-13T11:06:03Z TRACE tender::core::follower] [NodeId(1000-1003)][Term(0)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1003 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:03Z INFO  tender::core] [NodeId(1000-1003)][Term(1)] change leader to NodeId(1000-1001)
[INFO] [stdout] [2024-02-13T11:06:03Z TRACE tender::core::follower] [NodeId(1000-1002)][Term(0)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1002 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:03Z INFO  group_election::fixtures] [Node(NodeId(1000-1003))] an event has happened: ChangeLeader(NodeId { group_id: 1000, group_node_id: 1001 })
[INFO] [stdout] [2024-02-13T11:06:03Z INFO  tender::core] [NodeId(1000-1002)][Term(1)] change leader to NodeId(1000-1001)
[INFO] [stdout] [2024-02-13T11:06:03Z DEBUG tender::core::follower] [NodeId(1000-1003)][Term(1)] event (ChangeLeader(NodeId { group_id: 1000, group_node_id: 1001 })) in term 1 is handled
[INFO] [stdout] [2024-02-13T11:06:03Z INFO  group_election::fixtures] [Node(NodeId(1000-1002))] an event has happened: ChangeLeader(NodeId { group_id: 1000, group_node_id: 1001 })
[INFO] [stdout] [2024-02-13T11:06:03Z DEBUG tender::core::follower] [NodeId(1000-1002)][Term(1)] event (ChangeLeader(NodeId { group_id: 1000, group_node_id: 1001 })) in term 1 is handled
[INFO] [stdout] [2024-02-13T11:06:03Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1003))
[INFO] [stdout] [2024-02-13T11:06:03Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1002))
[INFO] [stdout] [2024-02-13T11:06:03Z TRACE tender::core::follower] [NodeId(1000-1003)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1003 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:03Z TRACE tender::core::follower] [NodeId(1000-1002)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1002 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:04Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1003))
[INFO] [stdout] [2024-02-13T11:06:04Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1002))
[INFO] [stdout] [2024-02-13T11:06:04Z TRACE tender::core::follower] [NodeId(1000-1003)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1003 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:04Z TRACE tender::core::follower] [NodeId(1000-1002)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1002 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:04Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1003))
[INFO] [stdout] [2024-02-13T11:06:04Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1002))
[INFO] [stdout] [2024-02-13T11:06:04Z TRACE tender::core::follower] [NodeId(1000-1002)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1002 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:04Z TRACE tender::core::follower] [NodeId(1000-1003)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1003 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:05Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1003))
[INFO] [stdout] [2024-02-13T11:06:05Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1002))
[INFO] [stdout] [2024-02-13T11:06:05Z TRACE tender::core::follower] [NodeId(1000-1003)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1003 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:05Z TRACE tender::core::follower] [NodeId(1000-1002)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1002 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:05Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1003))
[INFO] [stdout] [2024-02-13T11:06:05Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1002))
[INFO] [stdout] [2024-02-13T11:06:05Z TRACE tender::core::follower] [NodeId(1000-1002)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1002 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:05Z TRACE tender::core::follower] [NodeId(1000-1003)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1003 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:06Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1003))
[INFO] [stdout] [2024-02-13T11:06:06Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(1)] send heartbeat to node(NodeId(1000-1002))
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  group_election] --- node NodeId(1000-1001) step down to follower
[INFO] [stdout] [2024-02-13T11:06:06Z DEBUG tender::core::leader] [NodeId(1000-1001)][Term(1)] step down to follower
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  group_election] --- node NodeId(1000-1002) step up to leader
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  tender::core::follower] [NodeId(1000-1001)][Term(1)] start the follower loop
[INFO] [stdout] [2024-02-13T11:06:06Z DEBUG tender::core::follower] [NodeId(1000-1002)][Term(1)] step up to leader
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  tender::core::leader] [NodeId(1000-1002)][Term(1)] start the leader loop
[INFO] [stdout] [2024-02-13T11:06:06Z TRACE tender::core::leader] [NodeId(1000-1002)][Term(1)] send heartbeat to node(NodeId(1000-1001))
[INFO] [stdout] [2024-02-13T11:06:06Z TRACE tender::core::leader] [NodeId(1000-1002)][Term(1)] send heartbeat to node(NodeId(1000-1003))
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  group_election::fixtures] [Node(NodeId(1000-1002))] an event has happened: TransitToLeader { term: 1, caused_by_step_up: true }
[INFO] [stdout] [2024-02-13T11:06:06Z TRACE tender::core::follower] [NodeId(1000-1003)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1003 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  tender::core] [NodeId(1000-1002)][Term(1)] change leader from NodeId(1000-1002) to NodeId(1000-1001)
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  tender::core] [NodeId(1000-1002)][Term(1)] received valid heartbeat in Leader state, so transit to follower
[INFO] [stdout] [2024-02-13T11:06:06Z TRACE tender::core::follower] [NodeId(1000-1003)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1003 }, leader_id: NodeId { group_id: 1000, group_node_id: 1002 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  tender::core::follower] [NodeId(1000-1002)][Term(1)] start the follower loop
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  tender::core] [NodeId(1000-1003)][Term(1)] change leader from NodeId(1000-1001) to NodeId(1000-1002)
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  group_election::fixtures] [Node(NodeId(1000-1002))] an event has happened: ChangeLeader(NodeId { group_id: 1000, group_node_id: 1001 })
[INFO] [stdout] [2024-02-13T11:06:06Z DEBUG tender::core::follower] [NodeId(1000-1002)][Term(1)] event (ChangeLeader(NodeId { group_id: 1000, group_node_id: 1001 })) in term 1 is handled
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  group_election::fixtures] [Node(NodeId(1000-1002))] an event has happened: TransitToFollower { term: 1, prev_state: Leader, caused_by_step_down: false }
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  group_election::fixtures] [Node(NodeId(1000-1003))] an event has happened: ChangeLeader(NodeId { group_id: 1000, group_node_id: 1002 })
[INFO] [stdout] [2024-02-13T11:06:06Z DEBUG tender::core::follower] [NodeId(1000-1003)][Term(1)] event (ChangeLeader(NodeId { group_id: 1000, group_node_id: 1002 })) in term 1 is handled
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  group_election::fixtures] [Node(NodeId(1000-1001))] an event has happened: TransitToFollower { term: 1, prev_state: Leader, caused_by_step_down: true }
[INFO] [stdout] [2024-02-13T11:06:06Z TRACE tender::core::follower] [NodeId(1000-1001)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1001 }, leader_id: NodeId { group_id: 1000, group_node_id: 1002 }, term: 1 }
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  tender::core] [NodeId(1000-1001)][Term(1)] change leader from NodeId(1000-1001) to NodeId(1000-1002)
[INFO] [stdout] [2024-02-13T11:06:06Z INFO  group_election::fixtures] [Node(NodeId(1000-1001))] an event has happened: ChangeLeader(NodeId { group_id: 1000, group_node_id: 1002 })
[INFO] [stdout] [2024-02-13T11:06:06Z DEBUG tender::core::follower] [NodeId(1000-1001)][Term(1)] event (ChangeLeader(NodeId { group_id: 1000, group_node_id: 1002 })) in term 1 is handled
[INFO] [stdout] [2024-02-13T11:06:07Z INFO  tender::core::follower] [NodeId(1000-1001)][Term(1)] an election timeout is hit, need to transit to pre-candidate
[INFO] [stdout] [2024-02-13T11:06:07Z INFO  tender::core::candidate] [NodeId(1000-1001)][Term(1)] start the pre-candidate loop
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core::candidate] [NodeId(1000-1001)][Term(1)] quorum is Major, votes granted(1/2)
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core::candidate] [NodeId(1000-1001)][Term(1)] start to send pre-vote request to all nodes(2)
[INFO] [stdout] [2024-02-13T11:06:07Z INFO  group_election::fixtures] [Node(NodeId(1000-1001))] an event has happened: TransitToPreCandidate
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core] [NodeId(1000-1003)][Term(1)] voted for pre-candidate(NodeId(1000-1001))
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core] [NodeId(1000-1002)][Term(1)] voted for pre-candidate(NodeId(1000-1001))
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core::candidate] [NodeId(1000-1001)][Term(1)] received vote response : VoteResponse { node_id: NodeId { group_id: 1000, group_node_id: 1003 }, candidate_id: NodeId { group_id: 1000, group_node_id: 1001 }, vote_id: 1, term: 1, pre_vote: true, vote_result: Granted }
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core::candidate] [NodeId(1000-1001)][Term(1)] votes granted(2/2)
[INFO] [stdout] [2024-02-13T11:06:07Z INFO  tender::core::candidate] [NodeId(1000-1001)][Term(1)] minimum number of pre-votes have been received, so transit to candidate
[INFO] [stdout] [2024-02-13T11:06:07Z INFO  tender::core::candidate] [NodeId(1000-1001)][Term(1)] start the candidate loop
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core::candidate] [NodeId(1000-1001)][Term(1)] quorum is Major, votes granted(1/2)
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core::candidate] [NodeId(1000-1001)][Term(2)] start to send vote request to all nodes(2)
[INFO] [stdout] [2024-02-13T11:06:07Z INFO  group_election::fixtures] [Node(NodeId(1000-1001))] an event has happened: TransitToCandidate
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core::candidate] [NodeId(1000-1001)][Term(2)] received vote response : VoteResponse { node_id: NodeId { group_id: 1000, group_node_id: 1002 }, candidate_id: NodeId { group_id: 1000, group_node_id: 1001 }, vote_id: 1, term: 1, pre_vote: true, vote_result: Granted }
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core::candidate] [NodeId(1000-1001)][Term(2)] vote id is 2, so ignore vote response: VoteResponse { node_id: NodeId { group_id: 1000, group_node_id: 1002 }, candidate_id: NodeId { group_id: 1000, group_node_id: 1001 }, vote_id: 1, term: 1, pre_vote: true, vote_result: Granted }
[INFO] [stdout] [2024-02-13T11:06:07Z INFO  tender::core] [NodeId(1000-1003)][Term(2)] vote request term(2) is greater than current term(2)
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core] [NodeId(1000-1003)][Term(2)] granted vote for candidate(NodeId(1000-1001))
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core::candidate] [NodeId(1000-1001)][Term(2)] received vote response : VoteResponse { node_id: NodeId { group_id: 1000, group_node_id: 1003 }, candidate_id: NodeId { group_id: 1000, group_node_id: 1001 }, vote_id: 2, term: 2, pre_vote: false, vote_result: Granted }
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core::candidate] [NodeId(1000-1001)][Term(2)] votes granted(2/2)
[INFO] [stdout] [2024-02-13T11:06:07Z INFO  tender::core::candidate] [NodeId(1000-1001)][Term(2)] minimum number of votes have been received, so transit to leader
[INFO] [stdout] [2024-02-13T11:06:07Z INFO  tender::core::leader] [NodeId(1000-1001)][Term(2)] start the leader loop
[INFO] [stdout] [2024-02-13T11:06:07Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(2)] send heartbeat to node(NodeId(1000-1003))
[INFO] [stdout] [2024-02-13T11:06:07Z INFO  group_election::fixtures] [Node(NodeId(1000-1001))] an event has happened: TransitToLeader { term: 2, caused_by_step_up: false }
[INFO] [stdout] [2024-02-13T11:06:07Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(2)] send heartbeat to node(NodeId(1000-1002))
[INFO] [stdout] [2024-02-13T11:06:07Z TRACE tender::core::follower] [NodeId(1000-1003)][Term(2)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1003 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 2 }
[INFO] [stdout] [2024-02-13T11:06:07Z INFO  tender::core] [NodeId(1000-1003)][Term(2)] change leader from NodeId(1000-1002) to NodeId(1000-1001)
[INFO] [stdout] [2024-02-13T11:06:07Z INFO  group_election::fixtures] [Node(NodeId(1000-1003))] an event has happened: ChangeLeader(NodeId { group_id: 1000, group_node_id: 1001 })
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core::follower] [NodeId(1000-1003)][Term(2)] event (ChangeLeader(NodeId { group_id: 1000, group_node_id: 1001 })) in term 2 is handled
[INFO] [stdout] [2024-02-13T11:06:07Z TRACE tender::core::follower] [NodeId(1000-1002)][Term(1)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1002 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 2 }
[INFO] [stdout] [2024-02-13T11:06:07Z DEBUG tender::core] [NodeId(1000-1002)][Term(2)] reject vote request received within election timeout minimum
[INFO] [stdout] [2024-02-13T11:06:08Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(2)] send heartbeat to node(NodeId(1000-1003))
[INFO] [stdout] [2024-02-13T11:06:08Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(2)] send heartbeat to node(NodeId(1000-1002))
[INFO] [stdout] [2024-02-13T11:06:08Z TRACE tender::core::follower] [NodeId(1000-1003)][Term(2)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1003 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 2 }
[INFO] [stdout] [2024-02-13T11:06:08Z TRACE tender::core::follower] [NodeId(1000-1002)][Term(2)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1002 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 2 }
[INFO] [stdout] [2024-02-13T11:06:08Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(2)] send heartbeat to node(NodeId(1000-1003))
[INFO] [stdout] [2024-02-13T11:06:08Z TRACE tender::core::leader] [NodeId(1000-1001)][Term(2)] send heartbeat to node(NodeId(1000-1002))
[INFO] [stdout] [2024-02-13T11:06:08Z TRACE tender::core::follower] [NodeId(1000-1002)][Term(2)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1002 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 2 }
[INFO] [stdout] [2024-02-13T11:06:08Z TRACE tender::core::follower] [NodeId(1000-1003)][Term(2)] received heartbeat: HeartbeatRequest { target_node_id: NodeId { group_id: 1000, group_node_id: 1003 }, leader_id: NodeId { group_id: 1000, group_node_id: 1001 }, term: 2 }
[INFO] [stdout] thread 'test_step_up_and_down' panicked at tests/fixtures.rs:279:9:
[INFO] [stdout] assertion `left == right` failed
[INFO] [stdout]   left: Leader
[INFO] [stdout]  right: Follower
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x55d7a60961b6 - std::backtrace_rs::backtrace::libunwind::trace::hbee8a7973eeb6c93
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/../../backtrace/src/backtrace/libunwind.rs:104:5
[INFO] [stdout]    1:     0x55d7a60961b6 - std::backtrace_rs::backtrace::trace_unsynchronized::hc8ac75eea3aa6899
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x55d7a60961b6 - std::sys_common::backtrace::_print_fmt::hc7f3e3b5298b1083
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys_common/backtrace.rs:68:5
[INFO] [stdout]    3:     0x55d7a60961b6 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::hbb235daedd7c6190
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys_common/backtrace.rs:44:22
[INFO] [stdout]    4:     0x55d7a60bf6f0 - core::fmt::rt::Argument::fmt::h76c38a80d925a410
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/fmt/rt.rs:142:9
[INFO] [stdout]    5:     0x55d7a60bf6f0 - core::fmt::write::h3ed6aeaa977c8e45
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/fmt/mod.rs:1120:17
[INFO] [stdout]    6:     0x55d7a6093bdf - std::io::Write::write_fmt::h1299aa7741865f2b
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/io/mod.rs:1810:15
[INFO] [stdout]    7:     0x55d7a6095f94 - std::sys_common::backtrace::_print::h5d645a07e0fcfdbb
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys_common/backtrace.rs:47:5
[INFO] [stdout]    8:     0x55d7a6095f94 - std::sys_common::backtrace::print::h85035a511aafe7a8
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys_common/backtrace.rs:34:9
[INFO] [stdout]    9:     0x55d7a6097d07 - std::panicking::default_hook::{{closure}}::hcce8cea212785a25
[INFO] [stdout]   10:     0x55d7a60979ed - std::panicking::default_hook::hf5fcb0f213fe709a
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:289:9
[INFO] [stdout]   11:     0x55d7a603dce7 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h60265c2dfa87ee34
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   12:     0x55d7a603dce7 - test::test_main::{{closure}}::h77865bd3127078c6
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/test/src/lib.rs:138:21
[INFO] [stdout]   13:     0x55d7a6098326 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hbc5ccf4eb663e1e5
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/alloc/src/boxed.rs:2029:9
[INFO] [stdout]   14:     0x55d7a6098326 - std::panicking::rust_panic_with_hook::h095fccf1dc9379ee
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:783:13
[INFO] [stdout]   15:     0x55d7a6098072 - std::panicking::begin_panic_handler::{{closure}}::h032ba12139b353db
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:657:13
[INFO] [stdout]   16:     0x55d7a60966b6 - std::sys_common::backtrace::__rust_end_short_backtrace::h9259bc2ff8fd0f76
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys_common/backtrace.rs:171:18
[INFO] [stdout]   17:     0x55d7a6097dd0 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:645:5
[INFO] [stdout]   18:     0x55d7a5cae285 - core::panicking::panic_fmt::h784f20a50eaab275
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/panicking.rs:72:14
[INFO] [stdout]   19:     0x55d7a5cae6fb - core::panicking::assert_failed_inner::hbf94b40c37b92af0
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/panicking.rs:342:17
[INFO] [stdout]   20:     0x55d7a5cc1a2e - core::panicking::assert_failed::hba2f875d5c3629f2
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/panicking.rs:297:5
[INFO] [stdout]   21:     0x55d7a5cbcd00 - group_election::fixtures::MemRouter::assert_node_state::h87d7fbfda55c8156
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/fixtures.rs:279:9
[INFO] [stdout]   22:     0x55d7a5cc93db - group_election::test_step_up_and_down::ha78b9b2768d4c651
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/group_election.rs:272:5
[INFO] [stdout]   23:     0x55d7a5cc8847 - group_election::test_step_up_and_down::{{closure}}::h2a0d2c722aabc83d
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/group_election.rs:235:27
[INFO] [stdout]   24:     0x55d7a5cd6116 - core::ops::function::FnOnce::call_once::h400900f81561906f
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x55d7a60435bf - core::ops::function::FnOnce::call_once::h8dc6907944022cf6
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   26:     0x55d7a60435bf - test::__rust_begin_short_backtrace::haae1a87433f1efb3
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/test/src/lib.rs:627:18
[INFO] [stdout]   27:     0x55d7a6042341 - test::run_test_in_process::{{closure}}::h8c7decfa7c14e152
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/test/src/lib.rs:650:60
[INFO] [stdout]   28:     0x55d7a6042341 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h19e6ff056d9d21e9
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   29:     0x55d7a6042341 - std::panicking::try::do_call::h89c848fcaa37c035
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:552:40
[INFO] [stdout]   30:     0x55d7a6042341 - std::panicking::try::h57ab3dc74e2839b8
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:516:19
[INFO] [stdout]   31:     0x55d7a6042341 - std::panic::catch_unwind::hfb6a1b1abc120fb9
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panic.rs:142:14
[INFO] [stdout]   32:     0x55d7a6042341 - test::run_test_in_process::h5ae2f9875edd562d
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/test/src/lib.rs:650:27
[INFO] [stdout]   33:     0x55d7a6042341 - test::run_test::{{closure}}::h35d7300d8928a067
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/test/src/lib.rs:573:43
[INFO] [stdout]   34:     0x55d7a6009676 - test::run_test::{{closure}}::h7525ced405d23d1b
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/test/src/lib.rs:601:41
[INFO] [stdout]   35:     0x55d7a6009676 - std::sys_common::backtrace::__rust_begin_short_backtrace::h4e7db78ce05afad8
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys_common/backtrace.rs:155:18
[INFO] [stdout]   36:     0x55d7a600e6d7 - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hcfbcb64f1a1b3482
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/thread/mod.rs:529:17
[INFO] [stdout]   37:     0x55d7a600e6d7 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h9d89c5c4108bd689
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/panic/unwind_safe.rs:272:9
[INFO] [stdout]   38:     0x55d7a600e6d7 - std::panicking::try::do_call::h8a4869bc94ec50c9
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:552:40
[INFO] [stdout]   39:     0x55d7a600e6d7 - std::panicking::try::h9a576f20ff81ac30
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panicking.rs:516:19
[INFO] [stdout]   40:     0x55d7a600e6d7 - std::panic::catch_unwind::hbcb4e3f860ef9830
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/panic.rs:142:14
[INFO] [stdout]   41:     0x55d7a600e6d7 - std::thread::Builder::spawn_unchecked_::{{closure}}::h93c79a6be1505948
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/thread/mod.rs:528:30
[INFO] [stdout]   42:     0x55d7a600e6d7 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h426d96740c81bdaf
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x55d7a609cdd5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h12de4fc57affb195
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   44:     0x55d7a609cdd5 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h3c619f45059d5cf1
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/alloc/src/boxed.rs:2015:9
[INFO] [stdout]   45:     0x55d7a609cdd5 - std::sys::unix::thread::Thread::new::thread_start::hbac657605e4b7389
[INFO] [stdout]                                at /rustc/07dca489ac2d933c78d3c5158e3f43beefeb02ce/library/std/src/sys/unix/thread.rs:108:17
[INFO] [stdout]   46:     0x7f65cfda0ac3 - <unknown>
[INFO] [stdout]   47:     0x7f65cfe31bf4 - __clone
[INFO] [stdout]   48:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     test_step_up_and_down
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test group_election`
[INFO] [stdout] test result: FAILED. 5 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 10.03s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "1f9c8510dada4749964368ddb148b1278fe47909a824259cd0476f9a2de4fccd", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1f9c8510dada4749964368ddb148b1278fe47909a824259cd0476f9a2de4fccd", kill_on_drop: false }`
[INFO] [stdout] 1f9c8510dada4749964368ddb148b1278fe47909a824259cd0476f9a2de4fccd
