[INFO] fetching crate dcs2-raft 0.1.0... [INFO] testing dcs2-raft-0.1.0 against master#d98a5da813da67eb189387b8ccfb73cf481275d8+rustflags=-Copt-level=3 for pr-138759 [INFO] extracting crate dcs2-raft 0.1.0 into /workspace/builds/worker-1-tc1/source [INFO] started tweaking crates.io crate dcs2-raft 0.1.0 [INFO] finished tweaking crates.io crate dcs2-raft 0.1.0 [INFO] tweaked toml for crates.io crate dcs2-raft 0.1.0 written to /workspace/builds/worker-1-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate dcs2-raft 0.1.0 on toolchain d98a5da813da67eb189387b8ccfb73cf481275d8 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 32 packages to latest compatible versions [INFO] [stderr] Adding rand v0.8.5 (available: v0.9.2) [INFO] [stderr] Adding rand_chacha v0.3.1 (available: v0.9.0) [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded atomic-polyfill v1.0.3 [INFO] [stderr] Downloaded dcs2-clock v0.1.0 [INFO] [stderr] Downloaded heapless v0.7.17 [INFO] [stderr] Downloaded dcs2 v0.1.0 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 8057f86394b67a86e712f29f31f92fe334e65695bd9ebad9397b630624b51060 [INFO] running `Command { std: "docker" "start" "-a" "8057f86394b67a86e712f29f31f92fe334e65695bd9ebad9397b630624b51060", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "8057f86394b67a86e712f29f31f92fe334e65695bd9ebad9397b630624b51060", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "8057f86394b67a86e712f29f31f92fe334e65695bd9ebad9397b630624b51060", kill_on_drop: false }` [INFO] [stdout] 8057f86394b67a86e712f29f31f92fe334e65695bd9ebad9397b630624b51060 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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 -Copt-level=3" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] f4a238bee177e6239ebd70a50da0dd5f2986101ad488d5637f983b3b8d139fb4 [INFO] running `Command { std: "docker" "start" "-a" "f4a238bee177e6239ebd70a50da0dd5f2986101ad488d5637f983b3b8d139fb4", kill_on_drop: false }` [INFO] [stderr] Compiling libc v0.2.177 [INFO] [stderr] Compiling byteorder v1.5.0 [INFO] [stderr] Compiling stable_deref_trait v1.2.1 [INFO] [stderr] Compiling bumpalo v3.19.0 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling heapless v0.7.17 [INFO] [stderr] Compiling spin v0.9.8 [INFO] [stderr] Compiling hash32 v0.2.1 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling dcs2 v0.1.0 [INFO] [stderr] Compiling dcs2-clock v0.1.0 [INFO] [stderr] Compiling dcs2-raft v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 20.77s [INFO] running `Command { std: "docker" "inspect" "f4a238bee177e6239ebd70a50da0dd5f2986101ad488d5637f983b3b8d139fb4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "f4a238bee177e6239ebd70a50da0dd5f2986101ad488d5637f983b3b8d139fb4", kill_on_drop: false }` [INFO] [stdout] f4a238bee177e6239ebd70a50da0dd5f2986101ad488d5637f983b3b8d139fb4 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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 -Copt-level=3" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 6351b5fb297a2ecabaf78175e8dfa3a969824e7a4ce277874efafdf24b535510 [INFO] running `Command { std: "docker" "start" "-a" "6351b5fb297a2ecabaf78175e8dfa3a969824e7a4ce277874efafdf24b535510", kill_on_drop: false }` [INFO] [stderr] Compiling dcs2-raft v0.1.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 5.96s [INFO] running `Command { std: "docker" "inspect" "6351b5fb297a2ecabaf78175e8dfa3a969824e7a4ce277874efafdf24b535510", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6351b5fb297a2ecabaf78175e8dfa3a969824e7a4ce277874efafdf24b535510", kill_on_drop: false }` [INFO] [stdout] 6351b5fb297a2ecabaf78175e8dfa3a969824e7a4ce277874efafdf24b535510 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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 -Copt-level=3" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+d98a5da813da67eb189387b8ccfb73cf481275d8" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 4f58323b1fae5a722be7b37b74dbfbe3c00866fc8ca2a2d3b279afc892fd55eb [INFO] running `Command { std: "docker" "start" "-a" "4f58323b1fae5a722be7b37b74dbfbe3c00866fc8ca2a2d3b279afc892fd55eb", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.09s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/dcs2_raft-875685aa44c11513) [INFO] [stdout] [INFO] [stdout] running 87 tests [INFO] [stdout] test messages::log_tests::can_calculate_log_capacity ... ok [INFO] [stdout] test messages::log_tests::all_snapshotted_values_are_the_same ... ok [INFO] [stdout] test messages::log_tests::can_get_last_included_index ... ok [INFO] [stdout] test messages::log_tests::can_get_value_not_snapshotted ... ok [INFO] [stdout] test messages::log_tests::can_get_last_included_index_after_multiple_snapshots ... ok [INFO] [stdout] test messages::log_tests::append_after_install_snapshot ... ok [INFO] [stdout] test messages::log_tests::different_indices_generate_different_snapshot_term ... ok [INFO] [stdout] test messages::log_tests::entries_with_data_config_change_and_rule ... ok [INFO] [stdout] test messages::log_tests::install_snapshot ... ok [INFO] [stdout] test messages::log_tests::different_indices_generate_different_snapshot_data ... ok [INFO] [stdout] test messages::log_tests::multiple_install_snapshot ... ok [INFO] [stdout] test messages::log_tests::if_snapshot_then_last_included_term_is_given ... ok [INFO] [stdout] test messages::log_tests::one_is_the_first_index ... ok [INFO] [stdout] test messages::log_tests::snapshot_empty_log_leaves_everything_untouched ... ok [INFO] [stdout] test messages::log_tests::snapshot_is_the_merge_of_entries_up_to_idx ... ok [INFO] [stdout] test messages::log_tests::snapshot_log_increases_capacity ... ok [INFO] [stdout] test messages::log_tests::the_snapshot_term_is_the_greater_term_of_all_entries ... ok [INFO] [stdout] test messages::log_tests::when_inserting_in_position_greater_than_length_then_is_the_same_as_pushing ... ok [INFO] [stdout] test messages::log_tests::snapshot_several_times ... ok [INFO] [stdout] test messages::log_tests::the_snapshot_change_request_is_the_latest_change_req ... ok [INFO] [stdout] test messages::log_tests::more_elements_in_snapshot_means_more_capacity_gained ... ok [INFO] [stdout] test server::candidate::tests::given_candidate__when_receives_appendlog__and_appendlog_term_is_GE_than_current_term__then_returns_to_follower_with_discovered_term ... ok [INFO] [stdout] test messages::log_tests::when_inserting_in_existing_position_replaces_data ... ok [INFO] [stdout] test server::candidate::tests::given_candidate__when_receives_appendlog__and_term_is_smaller_than_current_term__then_rejects_message ... ok [INFO] [stdout] test server::candidate::tests::given_follower__when_appendlog_is_received__then_response_goes_to_requester ... ok [INFO] [stdout] test server::candidate::tests::given_candidate__when_retrieves_votes_from_majority__then_sends_heartbeat_to_all ... ok [INFO] [stdout] test server::follower::given_follower::given_install_snapshot::if_term_is_less_than_current_term_responds_immediately_and_dont_update ... ok [INFO] [stdout] test server::candidate::tests::given_candidate__when_times_out__then_start_new_election ... ok [INFO] [stdout] test server::candidate::tests::given_follower__when_timeout__then_send_request_vote_msg ... ok [INFO] [stdout] test messages::log_tests::new_log_is_empty ... ok [INFO] [stdout] test messages::log_tests::snapshot_log_with_one_element_leaves_everything_untouched ... ok [INFO] [stdout] test messages::log_tests::can_insert ... ok [INFO] [stdout] test server::candidate::tests::given_candidate__when_starting_election__then_sends_vote_req_to_all_servers_in_cluster ... ok [INFO] [stdout] test messages::log_tests::can_check_if_entry_was_snapshotted ... ok [INFO] [stdout] test messages::log_tests::if_no_snapshot_then_last_included_term_is_zero ... ok [INFO] [stdout] test server::candidate::tests::given_timer_is_up__when_server_ticks__then_increments_current_term ... ok [INFO] [stdout] test messages::log_tests::zero_is_not_valid_index_when_getting - should panic ... ok [INFO] [stdout] test messages::log_tests::zero_is_not_valid_index_for_snapshot - should panic ... ok [INFO] [stdout] test messages::log_tests::zero_is_not_valid_index_when_inserting - should panic ... ok [INFO] [stdout] test server::follower::given_follower::given_install_snapshot::replaces_up_to_last_included_entry_with_snapshot ... ok [INFO] [stdout] test server::follower::given_follower::receives_append_entry::duplicated_entries_are_ignored ... ok [INFO] [stdout] test server::follower::given_follower::receives_append_entry::receives_new_config::applies_it_immediately ... ok [INFO] [stdout] test server::follower::given_follower::receives_append_entry::with_nonempty_log::when_receives_append_entry_with_correct_nextIndex_and_term__then_request_succeeds ... ok [INFO] [stdout] test server::follower::given_follower::receives_append_entry::if_invalid_then_dont_update_commit_idx ... ok [INFO] [stdout] test server::follower::given_follower::receives_append_entry::with_empty_log::and_entry_has_correct_nextIndex_and_term_match__then__request_succeeds ... ok [INFO] [stdout] test server::follower::given_follower::given_install_snapshot::responds_with_current_term ... ok [INFO] [stdout] test server::follower::given_follower::receives_append_entry::if_valid_then_updates_commit_index_with_leader_commit_idx ... ok [INFO] [stdout] test server::follower::given_follower::receives_append_entry::with_multiple_entries_if_valid_then_updates_commit_index_with_last_new_entry_idx ... ok [INFO] [stdout] test server::follower::given_follower::given_install_snapshot::updates_the_log ... ok [INFO] [stdout] test server::follower::given_follower::receives_append_entry::from_leader__then_resets_timer ... ok [INFO] [stdout] test server::follower::given_follower::receives_append_entry::with_empty_log::and_entry_has_greater_nextIndex__then_request_fails ... ok [INFO] [stdout] test server::follower::given_follower::given_install_snapshot::updates_leader_id ... ok [INFO] [stdout] test server::follower::given_follower::receives_append_entry::with_nonempty_log::when_receives_append_entry_with_correct_nextIndex_but_term_doesnt_match__then_request_fails ... ok [INFO] [stdout] test server::follower::given_follower::receives_append_entry::with_single_entry_if_valid_then_updates_commit_index_with_last_new_entry_idx ... ok [INFO] [stdout] test server::follower::given_follower::receives_client_interaction::receives_read_request__then_redirects_to_leader ... ok [INFO] [stdout] test server::follower::given_follower::receives_client_interaction::receives_write_request__then_redirects_to_leader ... ok [INFO] [stdout] test server::follower::given_follower::receives_vote_request::from_candidate_with_same_last_term_and_shorter_log__then_request_is_rejected ... ok [INFO] [stdout] test server::leader::config_change_tests::final_config::after_intermediate_config_commited_uses_final_config ... ok [INFO] [stdout] test server::leader::config_change_tests::final_config::if_member_is_removed_from_config_does_not_receive_messages ... ok [INFO] [stdout] test server::follower::given_follower::receives_vote_request::from_candidate_with_smaller_last_term_in_log__then_request_is_rejected ... ok [INFO] [stdout] test server::follower::given_follower::snapshots::can_receive_append_log_after_snapshot ... ok [INFO] [stdout] test server::follower::given_follower::receives_vote_request::at_startup_when_granting_vote_request_then_resets_timer ... ok [INFO] [stdout] test server::follower::given_follower::receives_append_entry::with_nonempty_log::when_receives_append_entry_with_smaller_nextIndex__then_request_succeeds_and_log_is_updated ... ok [INFO] [stdout] test server::follower::given_follower::receives_vote_request::with_nonempty_log::candidate_has_valid_log__and_voted_granted_to_other__then_vote_is_denied ... ok [INFO] [stdout] test server::leader::config_change_tests::intermediate_config::adding_one_node_cluster_size_increased ... ok [INFO] [stdout] test server::leader::config_change_tests::final_config::after_intermediate_config_commited_replicates_final_config ... ok [INFO] [stdout] test server::leader::config_change_tests::empty_config_change_is_accepted_and_cluster_doesnt_change ... ok [INFO] [stdout] test server::follower::given_follower::snapshots::snapshot_occurs_at_75_percent_capacity ... ok [INFO] [stdout] test server::leader::config_change_tests::intermediate_config::deleting_one_node_cluster_config_is_propagated ... ok [INFO] [stdout] test server::leader::config_change_tests::intermediate_config::adding_one_node_cluster_config_is_propagated ... ok [INFO] [stdout] test server::leader::leader_tests::given_append_log_rejection__then_decrease_next_index_and_retries ... ok [INFO] [stdout] test server::leader::leader_tests::given_leader__when_receives_client_request__then_append_request_to_log_and_replicates_log ... ok [INFO] [stdout] test server::leader::leader_tests::given_append_log_success__then_increase_next_index ... ok [INFO] [stdout] test server::follower::given_follower::receives_vote_request::with_nonempty_log::candidate_has_valid_log__and_voted_granted_to_no_other__then_vote_is_granted ... ok [INFO] [stdout] test server::leader::config_change_tests::intermediate_config::config_commited_after_majority_reply ... ok [INFO] [stdout] test server::leader::config_change_tests::intermediate_config::deleting_one_node_cluster_size_remains_the_same ... ok [INFO] [stdout] test server::leader::leader_tests::given_leader__when_receives_read_only_request__then_sends_heartbeats_to_followers ... ok [INFO] [stdout] test server::leader::leader_tests::given_write_request__when_replicating_state__and_all_accept_appendlog__then_data_is_commited ... ok [INFO] [stdout] test server::leader::leader_tests::given_write_request__then_can_read_data ... ok [INFO] [stdout] test state::raft_state_tests::given_two_measurements_from_two_sensors_when_merging_the_results_dont_collision ... ok [INFO] [stdout] test server::leader::leader_tests::given_multiple_write_request__when_reading_data__result_is_the_accumulation_of_all_log_states ... FAILED [INFO] [stdout] test state::raft_state_tests::given_two_measurements_from_same_sensor_when_merging_then_results_are_merged ... ok [INFO] [stdout] test server::leader::snapshot_tests::if_follower_responds_with_greater_term_then_become_follower ... ok [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] [stdout] test server::leader::snapshot_tests::follower_rejects_append_log_below_snapshot_idx_then_send_install_snapshot ... ok [INFO] [stdout] test state::raft_state_tests::given_measurement_when_merging_with_empty_then_value_doesnt_change ... ok [INFO] [stdout] test server::leader::snapshot_tests::snapshot_contains_relevant_data ... ok [INFO] [stdout] test server::leader::snapshot_tests::snapshot_occurs_at_75_percent_capacity ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- server::leader::leader_tests::given_multiple_write_request__when_reading_data__result_is_the_accumulation_of_all_log_states stdout ---- [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 1 } }, body: RequestVote(RequestVoteArgs { term: 1, prev_log_index: 0, prev_log_term: 0 }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: RequestVote(RequestVoteArgs { term: 1, prev_log_index: 0, prev_log_term: 0 }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 1 } }, body: RequestVote(RequestVoteArgs { term: 1, prev_log_index: 0, prev_log_term: 0 }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: RequestVote(RequestVoteArgs { term: 1, prev_log_index: 0, prev_log_term: 0 }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 1 }, to: SystemNodeId { id: 0 } }, body: RequestVoteResponse(RequestVoteResponseResult { term: 1, granted: true }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 1 }, to: SystemNodeId { id: 0 } }, body: RequestVoteResponse(RequestVoteResponseResult { term: 1, granted: true }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 1 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 0, prev_log_term: 0, entries: Log { vec: [LogEntry { term: 1, data: Some(TestState(0, 0)), config_change: None, rule: None }], index_shift: 1 }, leader_commit: 0 }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 0, prev_log_term: 0, entries: Log { vec: [LogEntry { term: 1, data: Some(TestState(0, 0)), config_change: None, rule: None }], index_shift: 1 }, leader_commit: 0 }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 2 }, to: SystemNodeId { id: 0 } }, body: RequestVoteResponse(RequestVoteResponseResult { term: 1, granted: true }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 2 }, to: SystemNodeId { id: 0 } }, body: RequestVoteResponse(RequestVoteResponseResult { term: 1, granted: true }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 1 }, to: SystemNodeId { id: 0 } }, body: AppendLogResponse(AppendLogResponseResult { term: 1, success: true }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 1 }, to: SystemNodeId { id: 0 } }, body: AppendLogResponse(AppendLogResponseResult { term: 1, success: true }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 2 }, to: SystemNodeId { id: 0 } }, body: AppendLogResponse(AppendLogResponseResult { term: 1, success: true }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 2 }, to: SystemNodeId { id: 0 } }, body: AppendLogResponse(AppendLogResponseResult { term: 1, success: true }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 1 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 0, prev_log_term: 0, entries: Log { vec: [LogEntry { term: 1, data: Some(TestState(0, 0)), config_change: None, rule: None }], index_shift: 1 }, leader_commit: 0 }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 0, prev_log_term: 0, entries: Log { vec: [LogEntry { term: 1, data: Some(TestState(0, 0)), config_change: None, rule: None }], index_shift: 1 }, leader_commit: 0 }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 2 }, to: SystemNodeId { id: 0 } }, body: WriteRequest(WriteRequestArgs { measurement: Some(T: 3), rule: None, id: SystemNodeId { id: 0 } }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 2 }, to: SystemNodeId { id: 0 } }, body: WriteRequest(WriteRequestArgs { measurement: Some(T: 3), rule: None, id: SystemNodeId { id: 0 } }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 1 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 1, prev_log_term: 1, entries: Log { vec: [LogEntry { term: 1, data: Some(TestState(3, 0)), config_change: None, rule: None }], index_shift: 1 }, leader_commit: 1 }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 1, prev_log_term: 1, entries: Log { vec: [LogEntry { term: 1, data: Some(TestState(3, 0)), config_change: None, rule: None }], index_shift: 1 }, leader_commit: 1 }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: WriteRequestReply(true, None) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 2 }, to: SystemNodeId { id: 0 } }, body: WriteRequest(WriteRequestArgs { measurement: Some(T: 3), rule: None, id: SystemNodeId { id: 0 } }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 2 }, to: SystemNodeId { id: 0 } }, body: WriteRequest(WriteRequestArgs { measurement: Some(T: 3), rule: None, id: SystemNodeId { id: 0 } }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 1 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 2, prev_log_term: 1, entries: Log { vec: [LogEntry { term: 1, data: Some(TestState(3, 0)), config_change: None, rule: None }], index_shift: 1 }, leader_commit: 1 }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 2, prev_log_term: 1, entries: Log { vec: [LogEntry { term: 1, data: Some(TestState(3, 0)), config_change: None, rule: None }], index_shift: 1 }, leader_commit: 1 }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: WriteRequestReply(true, None) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 1 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 1, prev_log_term: 1, entries: Log { vec: [LogEntry { term: 1, data: Some(TestState(3, 0)), config_change: None, rule: None }], index_shift: 1 }, leader_commit: 1 }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 1, prev_log_term: 1, entries: Log { vec: [LogEntry { term: 1, data: Some(TestState(3, 0)), config_change: None, rule: None }], index_shift: 1 }, leader_commit: 1 }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: WriteRequestReply(true, None) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 1 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 2, prev_log_term: 1, entries: Log { vec: [LogEntry { term: 1, data: Some(TestState(3, 0)), config_change: None, rule: None }], index_shift: 1 }, leader_commit: 1 }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 2, prev_log_term: 1, entries: Log { vec: [LogEntry { term: 1, data: Some(TestState(3, 0)), config_change: None, rule: None }], index_shift: 1 }, leader_commit: 1 }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: WriteRequestReply(true, None) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 0 } }, body: ReadRequest } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 0 } }, body: ReadRequest } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 1 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 3, prev_log_term: 1, entries: Log { vec: [], index_shift: 1 }, leader_commit: 1 }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 3, prev_log_term: 1, entries: Log { vec: [], index_shift: 1 }, leader_commit: 1 }) } [INFO] [stdout] PUSH Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 0 } }, body: ReadRequestReply(ReadRequestReplyArgs { success: false, data: None, redirect: None }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 1 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 3, prev_log_term: 1, entries: Log { vec: [], index_shift: 1 }, leader_commit: 1 }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 2 } }, body: AppendLog(AppendLogArgs { term: 1, prev_log_index: 3, prev_log_term: 1, entries: Log { vec: [], index_shift: 1 }, leader_commit: 1 }) } [INFO] [stdout] POP Package { header: Header { from: SystemNodeId { id: 0 }, to: SystemNodeId { id: 0 } }, body: ReadRequestReply(ReadRequestReplyArgs { success: false, data: None, redirect: None }) } [INFO] [stdout] [INFO] [stdout] thread 'server::leader::leader_tests::given_multiple_write_request__when_reading_data__result_is_the_accumulation_of_all_log_states' panicked at src/server/leader.rs:562:62: [INFO] [stdout] called `Option::unwrap()` on a `None` value [INFO] [stdout] stack backtrace: [INFO] [stdout] 0: 0x63c03390b262 - std::backtrace_rs::backtrace::libunwind::trace::hd39b1f53d3cf9745 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9 [INFO] [stdout] 1: 0x63c03390b262 - std::backtrace_rs::backtrace::trace_unsynchronized::he91d9a75d4e3972b [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14 [INFO] [stdout] 2: 0x63c03390b262 - std::sys::backtrace::_print_fmt::hca46938f8c6e22cf [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/sys/backtrace.rs:66:9 [INFO] [stdout] 3: 0x63c03390b262 - ::fmt::ha499add612cccf8e [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/sys/backtrace.rs:39:26 [INFO] [stdout] 4: 0x63c0339305f3 - core::fmt::rt::Argument::fmt::hd21145b75a833b7a [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/fmt/rt.rs:173:76 [INFO] [stdout] 5: 0x63c0339305f3 - core::fmt::write::hb10c956f5235c8a4 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/fmt/mod.rs:1465:25 [INFO] [stdout] 6: 0x63c033908443 - std::io::default_write_fmt::hdb7615052be2ba4d [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/io/mod.rs:639:11 [INFO] [stdout] 7: 0x63c033908443 - std::io::Write::write_fmt::he1bcd251ec6e4153 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/io/mod.rs:1954:13 [INFO] [stdout] 8: 0x63c03390b0b2 - std::sys::backtrace::BacktraceLock::print::hb47c770ef659fd10 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/sys/backtrace.rs:42:9 [INFO] [stdout] 9: 0x63c03390c7cc - std::panicking::default_hook::{{closure}}::hdda8afb9d457a22c [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panicking.rs:300:27 [INFO] [stdout] 10: 0x63c03390c622 - std::panicking::default_hook::h7c46b44874fe5c9a [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panicking.rs:324:9 [INFO] [stdout] 11: 0x63c0338d4504 - as core::ops::function::Fn>::call::h178a5fcedee41e2f [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 12: 0x63c0338d4504 - test::test_main_with_exit_callback::{{closure}}::h951a41e0149d6d5d [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/test/src/lib.rs:145:21 [INFO] [stdout] 13: 0x63c03390d1ab - as core::ops::function::Fn>::call::h13602080f5b63276 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] 14: 0x63c03390d1ab - std::panicking::rust_panic_with_hook::ha6cb99ed099eb1c5 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panicking.rs:841:13 [INFO] [stdout] 15: 0x63c03390cf46 - std::panicking::begin_panic_handler::{{closure}}::he11808bc797ee921 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panicking.rs:699:13 [INFO] [stdout] 16: 0x63c03390b759 - std::sys::backtrace::__rust_end_short_backtrace::h9418807cb7346258 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/sys/backtrace.rs:168:18 [INFO] [stdout] 17: 0x63c03390cc0d - __rustc[18fb429eef004894]::rust_begin_unwind [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panicking.rs:697:5 [INFO] [stdout] 18: 0x63c03392ee70 - core::panicking::panic_fmt::hd890aeb12c3a3fc3 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/panicking.rs:75:14 [INFO] [stdout] 19: 0x63c03392eedc - core::panicking::panic::h318cb8731d7e290d [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/panicking.rs:145:5 [INFO] [stdout] 20: 0x63c03392ede9 - core::option::unwrap_failed::hb0c40146d06ba338 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/option.rs:2072:5 [INFO] [stdout] 21: 0x63c03387e1b8 - dcs2_raft::server::leader::leader_tests::given_multiple_write_request__when_reading_data__result_is_the_accumulation_of_all_log_states::hd9c6dc26486ce8aa [INFO] [stdout] at /opt/rustwide/workdir/src/server/leader.rs:562:0 [INFO] [stdout] 22: 0x63c033882209 - dcs2_raft::server::leader::leader_tests::given_multiple_write_request__when_reading_data__result_is_the_accumulation_of_all_log_states::{{closure}}::ha63fc41b3754def3 [INFO] [stdout] at /opt/rustwide/workdir/src/server/leader.rs:551:6 [INFO] [stdout] 23: 0x63c033882209 - core::ops::function::FnOnce::call_once::hb983d0b42f707ee5 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 24: 0x63c0338d9c8b - core::ops::function::FnOnce::call_once::h2869fb5b0a2b0bdc [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 25: 0x63c0338d9c8b - test::__rust_begin_short_backtrace::h7dd7142bd62fa711 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/test/src/lib.rs:648:18 [INFO] [stdout] 26: 0x63c0338d8e7e - test::run_test_in_process::{{closure}}::h43a753f038d36b3f [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/test/src/lib.rs:671:74 [INFO] [stdout] 27: 0x63c0338d8e7e - as core::ops::function::FnOnce<()>>::call_once::h0ca95dd3e12d1e16 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 28: 0x63c0338d8e7e - std::panicking::catch_unwind::do_call::he308587d70ac34ba [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panicking.rs:589:40 [INFO] [stdout] 29: 0x63c0338d8e7e - std::panicking::catch_unwind::h30dcba31973e8fb0 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panicking.rs:552:19 [INFO] [stdout] 30: 0x63c0338d8e7e - std::panic::catch_unwind::hfb68364e5621fbee [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panic.rs:359:14 [INFO] [stdout] 31: 0x63c0338d8e7e - test::run_test_in_process::hcdcc2977903b998a [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/test/src/lib.rs:671:27 [INFO] [stdout] 32: 0x63c0338d8e7e - test::run_test::{{closure}}::h544a6550958c5d14 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/test/src/lib.rs:592:43 [INFO] [stdout] 33: 0x63c03389c9f4 - test::run_test::{{closure}}::hb172e48ebe2b92c7 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/test/src/lib.rs:622:41 [INFO] [stdout] 34: 0x63c03389c9f4 - std::sys::backtrace::__rust_begin_short_backtrace::h7e79d8706638bea0 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/sys/backtrace.rs:152:18 [INFO] [stdout] 35: 0x63c0338a03ca - std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}::hc4a275f1e71b8ab0 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 36: 0x63c0338a03ca - as core::ops::function::FnOnce<()>>::call_once::h01ed0d242df78cfd [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 37: 0x63c0338a03ca - std::panicking::catch_unwind::do_call::h052f373fb905fee0 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panicking.rs:589:40 [INFO] [stdout] 38: 0x63c0338a03ca - std::panicking::catch_unwind::hb425d20c8ffb09c8 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panicking.rs:552:19 [INFO] [stdout] 39: 0x63c0338a03ca - std::panic::catch_unwind::ha0d5dfbf18fdeda5 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/panic.rs:359:14 [INFO] [stdout] 40: 0x63c0338a03ca - std::thread::Builder::spawn_unchecked_::{{closure}}::hbe79182bd37949c7 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 41: 0x63c0338a03ca - core::ops::function::FnOnce::call_once{{vtable.shim}}::hc096c4a06972fde5 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/core/src/ops/function.rs:250:5 [INFO] [stdout] 42: 0x63c033910107 - as core::ops::function::FnOnce>::call_once::h47377e27fb938a26 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/alloc/src/boxed.rs:1971:9 [INFO] [stdout] 43: 0x63c033910107 - as core::ops::function::FnOnce>::call_once::h72f1fe5d095abf57 [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/alloc/src/boxed.rs:1971:9 [INFO] [stdout] 44: 0x63c033910107 - std::sys::pal::unix::thread::Thread::new::thread_start::h0a4d8e1b9c0d38cf [INFO] [stdout] at /rustc/d98a5da813da67eb189387b8ccfb73cf481275d8/library/std/src/sys/pal/unix/thread.rs:97:17 [INFO] [stdout] 45: 0x7adf1850caa4 - [INFO] [stdout] 46: 0x7adf18599a34 - clone [INFO] [stdout] 47: 0x0 - [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] server::leader::leader_tests::given_multiple_write_request__when_reading_data__result_is_the_accumulation_of_all_log_states [INFO] [stdout] [INFO] [stdout] test result: FAILED. 86 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.12s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "4f58323b1fae5a722be7b37b74dbfbe3c00866fc8ca2a2d3b279afc892fd55eb", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4f58323b1fae5a722be7b37b74dbfbe3c00866fc8ca2a2d3b279afc892fd55eb", kill_on_drop: false }` [INFO] [stdout] 4f58323b1fae5a722be7b37b74dbfbe3c00866fc8ca2a2d3b279afc892fd55eb