Dec 07 03:12:33.151 INFO testing raft-0.4.0 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 03:12:33.151 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 07 03:12:33.576 INFO [stdout] f6d9491fa74bf7cf902e804ba5e726fec06ae00d41c71bbab9ca01b50f993e7c
Dec 07 03:12:33.582 INFO running `"docker" "start" "-a" "f6d9491fa74bf7cf902e804ba5e726fec06ae00d41c71bbab9ca01b50f993e7c"`
Dec 07 03:12:34.936 INFO [stderr] usermod: no changes
Dec 07 03:12:35.158 INFO [stderr]    Compiling raft v0.4.0 (/source)
Dec 07 03:12:41.033 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 6.05s
Dec 07 03:12:41.045 INFO [stderr] su: No module specific data is present
Dec 07 03:12:42.224 INFO running `"docker" "inspect" "f6d9491fa74bf7cf902e804ba5e726fec06ae00d41c71bbab9ca01b50f993e7c"`
Dec 07 03:12:42.572 INFO running `"docker" "rm" "-f" "f6d9491fa74bf7cf902e804ba5e726fec06ae00d41c71bbab9ca01b50f993e7c"`
Dec 07 03:12:42.880 INFO [stdout] f6d9491fa74bf7cf902e804ba5e726fec06ae00d41c71bbab9ca01b50f993e7c
Dec 07 03:12:42.888 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 07 03:12:43.252 INFO [stdout] ddd6cf2fed5a6095dc884ef5bce0d6e83e9ea678a62cd3343f601b2e921eb227
Dec 07 03:12:43.254 INFO running `"docker" "start" "-a" "ddd6cf2fed5a6095dc884ef5bce0d6e83e9ea678a62cd3343f601b2e921eb227"`
Dec 07 03:12:44.735 INFO [stderr] usermod: no changes
Dec 07 03:12:45.053 INFO [stderr]    Compiling raft v0.4.0 (/source)
Dec 07 03:13:02.296 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 17.54s
Dec 07 03:13:02.304 INFO [stderr] su: No module specific data is present
Dec 07 03:13:03.056 INFO running `"docker" "inspect" "ddd6cf2fed5a6095dc884ef5bce0d6e83e9ea678a62cd3343f601b2e921eb227"`
Dec 07 03:13:03.254 INFO running `"docker" "rm" "-f" "ddd6cf2fed5a6095dc884ef5bce0d6e83e9ea678a62cd3343f601b2e921eb227"`
Dec 07 03:13:03.515 INFO [stdout] ddd6cf2fed5a6095dc884ef5bce0d6e83e9ea678a62cd3343f601b2e921eb227
Dec 07 03:13:03.523 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-3/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/pr-56514/master#906deae0790bd18681b937fe9a141a3c26cf1855:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +906deae0790bd18681b937fe9a141a3c26cf1855-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 07 03:13:03.712 INFO [stdout] 2843719e08127b47037646315e2319bc8e768e7dc4bea2057b1a64134c5a93b1
Dec 07 03:13:03.714 INFO running `"docker" "start" "-a" "2843719e08127b47037646315e2319bc8e768e7dc4bea2057b1a64134c5a93b1"`
Dec 07 03:13:04.187 INFO [stderr] usermod: no changes
Dec 07 03:13:04.327 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.12s
Dec 07 03:13:04.333 INFO [stderr]      Running /target/debug/deps/raft-b7e1667c4bf801ad
Dec 07 03:13:04.337 INFO [stdout] 
Dec 07 03:13:04.337 INFO [stdout] running 37 tests
Dec 07 03:13:04.338 INFO [stdout] test log_unstable::test::test_maybe_last_index ... ok
Dec 07 03:13:04.338 INFO [stdout] test log_unstable::test::test_maybe_first_index ... ok
Dec 07 03:13:04.338 INFO [stdout] test log_unstable::test::test_maybe_term ... ok
Dec 07 03:13:04.338 INFO [stdout] test log_unstable::test::test_restore ... ok
Dec 07 03:13:04.338 INFO [stdout] test log_unstable::test::test_stable_to ... ok
Dec 07 03:13:04.338 INFO [stdout] test log_unstable::test::test_truncate_and_append ... ok
Dec 07 03:13:04.338 INFO [stdout] test errors::tests::test_storage_error_equal ... ok
Dec 07 03:13:04.341 INFO [stdout] test errors::tests::test_error_equal ... ok
Dec 07 03:13:04.341 INFO [stdout] test progress::test::test_inflight_free_first_one ... ok
Dec 07 03:13:04.341 INFO [stdout] test progress::test::test_inflight_free_to ... ok
Dec 07 03:13:04.341 INFO [stdout] test progress::test::test_inflight_add ... ok
Dec 07 03:13:04.341 INFO [stdout] test raft_log::test::test_append ... ok
Dec 07 03:13:04.341 INFO [stdout] test raft_log::test::test_find_conflict ... ok
Dec 07 03:13:04.342 INFO [stdout] test raft_log::test::test_is_up_to_date ... ok
Dec 07 03:13:04.342 INFO [stdout] test raft_log::test::test_log_restore ... ok
Dec 07 03:13:04.343 INFO [stdout] test raft_log::test::test_next_ents ... ok
Dec 07 03:13:04.343 INFO [stdout] test raft_log::test::test_compaction_side_effects ... ok
Dec 07 03:13:04.343 INFO [stdout] test raft_log::test::test_stable_to ... ok
Dec 07 03:13:04.344 INFO [stdout] test raft_log::test::test_stable_to_with_snap ... ok
Dec 07 03:13:04.344 INFO [stdout] test raft_log::test::test_term ... ok
Dec 07 03:13:04.344 INFO [stdout] test raft_log::test::test_unstable_ents ... ok
Dec 07 03:13:04.344 INFO [stdout] test raft_log::test::test_term_with_unstable_snapshot ... ok
Dec 07 03:13:04.345 INFO [stdout] test storage::test::test_storage_append ... ok
Dec 07 03:13:04.345 INFO [stdout] test raw_node::test::test_is_local_msg ... ok
Dec 07 03:13:04.345 INFO [stdout] test storage::test::test_storage_compact ... ok
Dec 07 03:13:04.345 INFO [stdout] test storage::test::test_storage_apply_snapshot ... ok
Dec 07 03:13:04.345 INFO [stdout] test storage::test::test_storage_create_snapshot ... ok
Dec 07 03:13:04.346 INFO [stdout] test storage::test::test_storage_entries ... ok
Dec 07 03:13:04.346 INFO [stdout] test storage::test::test_storage_first_index ... ok
Dec 07 03:13:04.347 INFO [stdout] test storage::test::test_storage_last_index ... ok
Dec 07 03:13:04.348 INFO [stdout] test storage::test::test_storage_term ... ok
Dec 07 03:13:04.352 INFO [stdout] test raft_log::test::test_has_next_ents ... ok
Dec 07 03:13:04.430 INFO [stdout] test raft_log::test::test_compaction ... ok
Dec 07 03:13:04.431 INFO [stdout] test raft_log::test::test_log_maybe_append ... ok
Dec 07 03:13:04.431 INFO [stdout] test raft_log::test::test_is_outofbounds ... ok
Dec 07 03:13:04.431 INFO [stdout] test raft_log::test::test_slice ... ok
Dec 07 03:13:04.431 INFO [stdout] test raft_log::test::test_commit_to ... ok
Dec 07 03:13:04.431 INFO [stdout] 
Dec 07 03:13:04.431 INFO [stdout] test result: ok. 37 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 03:13:04.431 INFO [stdout] 
Dec 07 03:13:04.436 INFO [stderr]      Running /target/debug/deps/tests-93174b4d908e9a14
Dec 07 03:13:04.439 INFO [stdout] 
Dec 07 03:13:04.439 INFO [stdout] running 152 tests
Dec 07 03:13:04.444 INFO [stdout] test integration_cases::test_raft::test_candidate_concede ... ok
Dec 07 03:13:04.446 INFO [stdout] test integration_cases::test_raft::test_candidate_reset_term_msg_append ... ok
Dec 07 03:13:04.447 INFO [stdout] test integration_cases::test_raft::test_campaign_while_leader ... ok
Dec 07 03:13:04.449 INFO [stdout] test integration_cases::test_raft::test_candidate_reset_term_msg_heartbeat ... ok
Dec 07 03:13:04.450 INFO [stdout] test integration_cases::test_raft::test_bcast_beat ... ok
Dec 07 03:13:04.457 INFO [stdout] test integration_cases::test_raft::test_add_node ... ok
Dec 07 03:13:04.457 INFO [stdout] test integration_cases::test_raft::test_add_learner ... ok
Dec 07 03:13:04.457 INFO [stdout] test integration_cases::test_raft::test_commit_after_remove_node ... ok
Dec 07 03:13:04.457 INFO [stdout] test integration_cases::test_raft::test_commit ... ok
Dec 07 03:13:04.457 INFO [stdout] test integration_cases::test_raft::test_all_server_stepdown ... ok
Dec 07 03:13:04.457 INFO [stdout] test integration_cases::test_raft::test_add_node_check_quorum ... ok
Dec 07 03:13:04.457 INFO [stdout] test integration_cases::test_raft::test_commit_without_new_term_entry ... ok
Dec 07 03:13:04.459 INFO [stdout] test integration_cases::test_raft::test_cannot_commit_without_new_term_entry ... ok
Dec 07 03:13:04.464 INFO [stdout] test integration_cases::test_raft::test_disruptive_follower_pre_vote ... ok
Dec 07 03:13:04.464 INFO [stdout] test integration_cases::test_raft::test_handle_msg_append ... ok
Dec 07 03:13:04.464 INFO [stdout] test integration_cases::test_raft::test_dueling_candidates ... ok
Dec 07 03:13:04.464 INFO [stdout] test integration_cases::test_raft::test_disruptive_follower ... ok
Dec 07 03:13:04.464 INFO [stdout] test integration_cases::test_raft::test_leader_append_response ... ok
Dec 07 03:13:04.465 INFO [stdout] test integration_cases::test_raft::test_ignore_providing_snapshot ... ok
Dec 07 03:13:04.466 INFO [stdout] test integration_cases::test_raft::test_handle_heartbeat_resp ... ok
Dec 07 03:13:04.466 INFO [stdout] test integration_cases::test_raft::test_dueling_pre_candidates ... ok
Dec 07 03:13:04.469 INFO [stdout] test integration_cases::test_raft::test_handle_heartbeat ... ok
Dec 07 03:13:04.469 INFO [stdout] test integration_cases::test_raft::test_free_stuck_candidate_with_check_quorum ... ok
Dec 07 03:13:04.469 INFO [stdout] test integration_cases::test_raft::test_election_tick_range ... ok
Dec 07 03:13:04.471 INFO [stdout] test integration_cases::test_raft::test_leader_cycle ... ok
Dec 07 03:13:04.471 INFO [stdout] test integration_cases::test_raft::test_leader_election_overwrite_newer_logs_pre_vote ... ok
Dec 07 03:13:04.472 INFO [stdout] test integration_cases::test_raft::test_leader_cycle_pre_vote ... ok
Dec 07 03:13:04.479 INFO [stdout] test integration_cases::test_raft::test_leader_election_overwrite_newer_logs ... ok
Dec 07 03:13:04.482 INFO [stdout] test integration_cases::test_raft::test_leader_election ... ok
Dec 07 03:13:04.483 INFO [stdout] test integration_cases::test_raft::test_leader_election_pre_vote ... ok
Dec 07 03:13:04.483 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_back ... ok
Dec 07 03:13:04.488 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_after_snapshot ... ok
Dec 07 03:13:04.489 INFO [stdout] test integration_cases::test_raft::test_leader_stepdown_when_quorum_lost ... ok
Dec 07 03:13:04.489 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_receive_higher_term_vote ... ok
Dec 07 03:13:04.489 INFO [stdout] test integration_cases::test_raft::test_leader_stepdown_when_quorum_active ... ok
Dec 07 03:13:04.489 INFO [stdout] test integration_cases::test_raft::test_leader_increase_next ... ok
Dec 07 03:13:04.489 INFO [stdout] test integration_cases::test_raft::test_leader_superseding_with_check_quorum ... ok
Dec 07 03:13:04.489 INFO [stdout] test integration_cases::test_raft::test_leader_election_with_check_quorum ... ok
Dec 07 03:13:04.490 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_ignore_proposal ... ok
Dec 07 03:13:04.492 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_to_uptodate_node ... ok
Dec 07 03:13:04.494 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_second_transfer_to_another_node ... ok
Dec 07 03:13:04.495 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_to_non_existing_node ... ok
Dec 07 03:13:04.496 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_to_self ... ok
Dec 07 03:13:04.497 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_second_transfer_to_same_node ... ok
Dec 07 03:13:04.497 INFO [stdout] test integration_cases::test_raft::test_learner_log_replication ... ok
Dec 07 03:13:04.497 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_to_slow_follower ... ok
Dec 07 03:13:04.498 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_to_uptodate_node_from_follower ... ok
Dec 07 03:13:04.502 INFO [stdout] test integration_cases::test_raft::test_learner_respond_vote ... ok
Dec 07 03:13:04.504 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_with_check_quorum ... ok
Dec 07 03:13:04.504 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_remove_node ... ok
Dec 07 03:13:04.505 INFO [stdout] test integration_cases::test_raft::test_log_replicatioin ... ok
Dec 07 03:13:04.509 INFO [stdout] test integration_cases::test_raft::test_msg_append_response_wait_reset ... ok
Dec 07 03:13:04.509 INFO [stdout] test integration_cases::test_raft::test_leader_transfer_timeout ... ok
Dec 07 03:13:04.509 INFO [stdout] test integration_cases::test_raft::test_learner_receive_snapshot ... ok
Dec 07 03:13:04.513 INFO [stdout] test integration_cases::test_raft::test_learner_election_timeout ... ok
Dec 07 03:13:04.513 INFO [stdout] test integration_cases::test_raft::test_learner_promotion ... ok
Dec 07 03:13:04.515 INFO [stdout] test integration_cases::test_raft::test_node_with_smaller_term_can_complete_election ... ok
Dec 07 03:13:04.515 INFO [stdout] test integration_cases::test_raft::test_prevote_migration_can_complete_election ... ok
Dec 07 03:13:04.516 INFO [stdout] test integration_cases::test_raft::test_new_leader_pending_config ... ok
Dec 07 03:13:04.516 INFO [stdout] test integration_cases::test_raft::test_non_promotable_voter_which_check_quorum ... ok
Dec 07 03:13:04.519 INFO [stdout] test integration_cases::test_raft::test_old_messages ... ok
Dec 07 03:13:04.519 INFO [stdout] test integration_cases::test_raft::test_pre_campaign_while_leader ... ok
Dec 07 03:13:04.519 INFO [stdout] test integration_cases::test_raft::test_progress_become_probe ... ok
Dec 07 03:13:04.522 INFO [stdout] test integration_cases::test_raft::test_progress_become_replicate ... ok
Dec 07 03:13:04.522 INFO [stdout] test integration_cases::test_raft::test_progress_is_paused ... ok
Dec 07 03:13:04.522 INFO [stdout] test integration_cases::test_raft::test_prevote_from_any_state ... ok
Dec 07 03:13:04.522 INFO [stdout] test integration_cases::test_raft::test_prevote_with_check_quorum ... ok
Dec 07 03:13:04.522 INFO [stdout] test integration_cases::test_raft::test_progress_become_snapshot ... ok
Dec 07 03:13:04.522 INFO [stdout] test integration_cases::test_raft::test_progress_maybe_decr ... ok
Dec 07 03:13:04.524 INFO [stdout] test integration_cases::test_raft::test_progress_update ... ok
Dec 07 03:13:04.529 INFO [stdout] test integration_cases::test_raft::test_progress_resume ... ok
Dec 07 03:13:04.533 INFO [stdout] test integration_cases::test_raft::test_proposal ... ok
Dec 07 03:13:04.535 INFO [stdout] test integration_cases::test_raft::test_prevote_migration_with_free_stuck_pre_candidate ... ok
Dec 07 03:13:04.535 INFO [stdout] test integration_cases::test_raft::test_promotable ... ok
Dec 07 03:13:04.537 INFO [stdout] test integration_cases::test_raft::test_prevote_with_split_vote ... ok
Dec 07 03:13:04.538 INFO [stdout] test integration_cases::test_raft::test_progress_paused ... ok
Dec 07 03:13:04.538 INFO [stdout] test integration_cases::test_raft::test_progress_resume_by_heartbeat_resp ... ok
Dec 07 03:13:04.539 INFO [stdout] test integration_cases::test_raft::test_provide_snap ... ok
Dec 07 03:13:04.541 INFO [stdout] test integration_cases::test_raft::test_proposal_by_proxy ... ok
Dec 07 03:13:04.541 INFO [stdout] test integration_cases::test_raft::test_raft_nodes ... ok
Dec 07 03:13:04.542 INFO [stdout] test integration_cases::test_raft::test_raft_frees_read_only_mem ... ok
Dec 07 03:13:04.543 INFO [stdout] test integration_cases::test_raft::test_read_only_for_new_leader ... ok
Dec 07 03:13:04.552 INFO [stdout] test integration_cases::test_raft::test_read_only_option_lease_without_check_quorum ... ok
Dec 07 03:13:04.553 INFO [stdout] test integration_cases::test_raft::test_remove_node ... ok
Dec 07 03:13:04.553 INFO [stdout] test integration_cases::test_raft::test_recv_msg_beat ... ok
Dec 07 03:13:04.568 INFO [stdout] test integration_cases::test_raft::test_restore ... ok
Dec 07 03:13:04.568 INFO [stdout] test integration_cases::test_raft::test_recv_msg_request_vote ... ok
Dec 07 03:13:04.568 INFO [stdout] test integration_cases::test_raft::test_recv_msg_unreachable ... ok
Dec 07 03:13:04.568 INFO [stdout] test integration_cases::test_raft::test_read_only_option_safe ... ok
Dec 07 03:13:04.568 INFO [stdout] test integration_cases::test_raft::test_remove_learner ... ok
Dec 07 03:13:04.568 INFO [stdout] test integration_cases::test_raft::test_read_only_option_lease ... ok
Dec 07 03:13:04.569 INFO [stdout] test integration_cases::test_raft::test_restore_invalid_learner ... ok
Dec 07 03:13:04.569 INFO [stderr] ERROR 2018-12-07T03:13:04Z: raft::raft:  can't become learner when restores snapshot [index: 11, term: 11]
Dec 07 03:13:04.570 INFO [stdout] test integration_cases::test_raft::test_restore_ignore_snapshot ... ok
Dec 07 03:13:04.571 INFO [stdout] test integration_cases::test_raft::test_send_append_for_progress_replicate ... ok
Dec 07 03:13:04.572 INFO [stdout] test integration_cases::test_raft::test_restore_with_learner ... ok
Dec 07 03:13:04.572 INFO [stdout] test integration_cases::test_raft::test_restore_learner_promotion ... ok
Dec 07 03:13:04.576 INFO [stdout] test integration_cases::test_raft::test_send_append_for_progress_snapshot ... ok
Dec 07 03:13:04.576 INFO [stdout] test integration_cases::test_raft::test_send_append_for_progress_probe ... ok
Dec 07 03:13:04.576 INFO [stdout] test integration_cases::test_raft::test_restore_from_snap_msg ... ok
Dec 07 03:13:04.589 INFO [stdout] test integration_cases::test_raft::test_pass_election_timeout ... ok
Dec 07 03:13:04.589 INFO [stdout] test integration_cases::test_raft::test_single_node_commit ... ok
Dec 07 03:13:04.591 INFO [stdout] test integration_cases::test_raft::test_sinle_node_pre_candidate ... ok
Dec 07 03:13:04.591 INFO [stdout] test integration_cases::test_raft::test_slow_node_restore ... ok
Dec 07 03:13:04.600 INFO [stdout] test integration_cases::test_raft::test_single_node_candidate ... ok
Dec 07 03:13:04.603 INFO [stdout] test integration_cases::test_raft::test_step_ignore_config ... ok
Dec 07 03:13:04.603 INFO [stdout] test integration_cases::test_raft::test_step_config ... ok
Dec 07 03:13:04.603 INFO [stdout] test integration_cases::test_raft::test_vote_from_any_state ... ok
Dec 07 03:13:04.603 INFO [stdout] test integration_cases::test_raft::test_transfer_non_member ... ok
Dec 07 03:13:04.632 INFO [stdout] test integration_cases::test_raft_paper::test_candidate_start_new_election ... ok
Dec 07 03:13:04.632 INFO [stdout] test integration_cases::test_raft_paper::test_candidate_fallback ... ok
Dec 07 03:13:04.636 INFO [stdout] test integration_cases::test_raft_paper::test_candidate_update_term_from_message ... ok
Dec 07 03:13:04.641 INFO [stdout] test integration_cases::test_raft_paper::test_follower_append_entries ... ok
Dec 07 03:13:04.641 INFO [stdout] test integration_cases::test_raft_paper::test_follower_check_msg_append ... ok
Dec 07 03:13:04.641 INFO [stdout] test integration_cases::test_raft_flow_control::test_msg_app_flow_control_full ... ok
Dec 07 03:13:04.664 INFO [stdout] test integration_cases::test_raft_flow_control::test_msg_app_flow_control_recv_heartbeat ... ok
Dec 07 03:13:04.677 INFO [stdout] test integration_cases::test_raft_paper::test_follower_commit_entry ... ok
Dec 07 03:13:04.680 INFO [stdout] test integration_cases::test_raft_paper::test_follower_update_term_from_message ... ok
Dec 07 03:13:04.700 INFO [stdout] test integration_cases::test_raft_paper::test_follower_vote ... ok
Dec 07 03:13:04.700 INFO [stdout] test integration_cases::test_raft_paper::test_follower_start_election ... ok
Dec 07 03:13:04.712 INFO [stdout] test integration_cases::test_raft_paper::test_leader_acknowledge_commit ... ok
Dec 07 03:13:04.712 INFO [stdout] test integration_cases::test_raft_paper::test_leader_bcast_beat ... ok
Dec 07 03:13:04.716 INFO [stdout] test integration_cases::test_raft_paper::test_leader_commit_entry ... ok
Dec 07 03:13:04.724 INFO [stdout] test integration_cases::test_raft_paper::test_leader_commit_preceding_entries ... ok
Dec 07 03:13:04.724 INFO [stdout] test integration_cases::test_raft_paper::test_leader_election_in_one_round_rpc ... ok
Dec 07 03:13:04.748 INFO [stdout] test integration_cases::test_raft_paper::test_leader_start_replication ... ok
Dec 07 03:13:04.788 INFO [stdout] test integration_cases::test_raft_paper::test_leader_only_commits_log_from_current_term ... ok
Dec 07 03:13:04.788 INFO [stdout] test integration_cases::test_raft_paper::test_leader_sync_follower_log ... ok
Dec 07 03:13:04.804 INFO [stdout] test integration_cases::test_raft_paper::test_leader_update_term_from_message ... ok
Dec 07 03:13:04.828 INFO [stdout] test integration_cases::test_raft_paper::test_start_as_follower ... ok
Dec 07 03:13:04.832 INFO [stdout] test integration_cases::test_raft_paper::test_voter ... ok
Dec 07 03:13:04.836 INFO [stdout] test integration_cases::test_raft_paper::test_vote_request ... ok
Dec 07 03:13:04.864 INFO [stdout] test integration_cases::test_raft_snap::test_sending_snapshot_set_pending_snapshot ... ok
Dec 07 03:13:04.864 INFO [stdout] test integration_cases::test_raft_paper::test_acandidates_election_timeout_nonconf ... ok
Dec 07 03:13:04.879 INFO [stdout] test integration_cases::test_raft_paper::test_follower_election_timeout_nonconflict ... ok
Dec 07 03:13:04.889 INFO [stdout] test integration_cases::test_raft_snap::test_snapshot_failure ... ok
Dec 07 03:13:04.905 INFO [stdout] test integration_cases::test_raw_node::test_raw_node_propose_add_duplicate_node ... ok
Dec 07 03:13:04.909 INFO [stdout] test integration_cases::test_raft_snap::test_snapshot_succeed ... ok
Dec 07 03:13:04.912 INFO [stdout] test integration_cases::test_raft_snap::test_pending_snapshot_pause_replication ... ok
Dec 07 03:13:04.912 INFO [stdout] test integration_cases::test_raft_snap::test_snapshot_abort ... ok
Dec 07 03:13:04.940 INFO [stdout] test integration_cases::test_raw_node::test_raw_node_read_index_to_old_leader ... ok
Dec 07 03:13:04.940 INFO [stdout] test integration_cases::test_raw_node::test_raw_node_propose_add_learner_node ... ok
Dec 07 03:13:04.940 INFO [stdout] test integration_cases::test_raw_node::test_raw_node_read_index ... ok
Dec 07 03:13:04.940 INFO [stdout] test integration_cases::test_raw_node::test_raw_node_restart ... ok
Dec 07 03:13:04.940 INFO [stdout] test integration_cases::test_raw_node::test_raw_node_propose_and_conf_change ... ok
Dec 07 03:13:04.940 INFO [stdout] test integration_cases::test_raw_node::test_raw_node_restart_from_snapshot ... ok
Dec 07 03:13:04.940 INFO [stdout] test integration_cases::test_raw_node::test_raw_node_start ... ok
Dec 07 03:13:04.964 INFO [stdout] test integration_cases::test_raw_node::test_skip_bcast_commit ... ok
Dec 07 03:13:04.964 INFO [stderr] ERROR 2018-12-07T03:13:04Z: raft::raft:  invalid format of MsgReadIndexResp from 0, entries count: 0
Dec 07 03:13:04.964 INFO [stdout] test integration_cases::test_raw_node::test_raw_node_step ... ok
Dec 07 03:13:05.015 INFO [stdout] test integration_cases::test_raft::test_state_transition ... ok
Dec 07 03:13:05.022 INFO [stdout] test integration_cases::test_raft_flow_control::test_msg_app_flow_control_move_forward ... ok
Dec 07 03:13:05.400 INFO [stdout] test integration_cases::test_raft_paper::test_candidate_election_timeout_randomized ... ok
Dec 07 03:13:05.429 INFO [stdout] test integration_cases::test_raft_paper::test_follower_election_timeout_randomized ... ok
Dec 07 03:13:05.429 INFO [stdout] 
Dec 07 03:13:05.429 INFO [stdout] test result: ok. 152 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 03:13:05.429 INFO [stdout] 
Dec 07 03:13:05.434 INFO [stderr]    Doc-tests raft
Dec 07 03:13:06.975 INFO [stdout] 
Dec 07 03:13:06.976 INFO [stdout] running 12 tests
Dec 07 03:13:06.976 INFO [stdout] test src/lib.rs -  (line 172) ... ignored
Dec 07 03:13:06.976 INFO [stdout] test src/lib.rs -  (line 185) ... ignored
Dec 07 03:13:06.976 INFO [stdout] test src/lib.rs -  (line 198) ... ignored
Dec 07 03:13:06.976 INFO [stdout] test src/lib.rs -  (line 208) ... ignored
Dec 07 03:13:06.976 INFO [stdout] test src/lib.rs -  (line 217) ... ignored
Dec 07 03:13:06.976 INFO [stdout] test src/lib.rs -  (line 230) ... ignored
Dec 07 03:13:06.976 INFO [stdout] test src/lib.rs -  (line 253) ... ignored
Dec 07 03:13:14.319 INFO [stdout] test src/lib.rs - prelude (line 318) ... ok
Dec 07 03:13:15.454 INFO [stdout] test src/util.rs - util::limit_size (line 29) ... ok
Dec 07 03:13:15.739 INFO [stdout] test src/lib.rs -  (line 61) ... ok
Dec 07 03:13:16.252 INFO [stdout] test src/lib.rs -  (line 32) ... ok
Dec 07 03:13:16.656 INFO [stdout] test src/lib.rs -  (line 112) ... ok
Dec 07 03:13:16.656 INFO [stdout] 
Dec 07 03:13:16.656 INFO [stdout] test result: ok. 5 passed; 0 failed; 7 ignored; 0 measured; 0 filtered out
Dec 07 03:13:16.656 INFO [stdout] 
Dec 07 03:13:16.681 INFO [stderr] su: No module specific data is present
Dec 07 03:13:16.996 INFO running `"docker" "inspect" "2843719e08127b47037646315e2319bc8e768e7dc4bea2057b1a64134c5a93b1"`
Dec 07 03:13:17.106 INFO running `"docker" "rm" "-f" "2843719e08127b47037646315e2319bc8e768e7dc4bea2057b1a64134c5a93b1"`
Dec 07 03:13:17.264 INFO [stdout] 2843719e08127b47037646315e2319bc8e768e7dc4bea2057b1a64134c5a93b1