Dec 06 03:23:14.012 INFO testing conwayste/netwayste against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 06 03:23:14.012 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 06 03:23:14.568 INFO [stdout] 2f624b15c92fc89bd7bcdbda2f54e68834baba2d7814e3cfcd30b259d2ff6c73
Dec 06 03:23:14.570 INFO running `"docker" "start" "-a" "2f624b15c92fc89bd7bcdbda2f54e68834baba2d7814e3cfcd30b259d2ff6c73"`
Dec 06 03:23:15.659 INFO [stderr] usermod: no changes
Dec 06 03:23:15.864 INFO [stderr]    Compiling num-traits v0.2.5
Dec 06 03:23:15.864 INFO [stderr]    Compiling bit-vec v0.5.0
Dec 06 03:23:15.864 INFO [stderr]    Compiling rand v0.4.2
Dec 06 03:23:15.864 INFO [stderr]    Compiling wait-timeout v0.1.5
Dec 06 03:23:15.868 INFO [stderr]    Compiling bincode v0.9.2
Dec 06 03:23:15.876 INFO [stderr]    Compiling tokio-io v0.1.4
Dec 06 03:23:17.432 INFO [stderr]    Compiling bit-set v0.5.0
Dec 06 03:23:19.396 INFO [stderr]    Compiling tokio-core v0.1.12
Dec 06 03:23:19.472 INFO [stderr]    Compiling tempfile v3.0.2
Dec 06 03:23:20.308 INFO [stderr]    Compiling rusty-fork v0.2.0
Dec 06 03:23:22.685 INFO [stderr]    Compiling proptest v0.7.2
Dec 06 03:23:35.817 INFO [stderr]    Compiling netwayste v0.1.1 (/source)
Dec 06 03:23:37.269 INFO [stderr] warning: unused variable: `exit_tx`
Dec 06 03:23:37.269 INFO [stderr]    --> src/client.rs:231:13
Dec 06 03:23:37.269 INFO [stderr]     |
Dec 06 03:23:37.269 INFO [stderr] 231 |             exit_tx: &mpsc::UnboundedSender<()>,
Dec 06 03:23:37.269 INFO [stderr]     |             ^^^^^^^ help: consider using `_exit_tx` instead
Dec 06 03:23:37.269 INFO [stderr]     |
Dec 06 03:23:37.269 INFO [stderr]     = note: #[warn(unused_variables)] on by default
Dec 06 03:23:37.269 INFO [stderr] 
Dec 06 03:23:37.772 INFO [stderr] warning: method is never used: `new`
Dec 06 03:23:37.772 INFO [stderr]    --> src/net.rs:140:5
Dec 06 03:23:37.772 INFO [stderr]     |
Dec 06 03:23:37.772 INFO [stderr] 140 |     pub fn new(sequence: u64, name: String, msg: String) -> BroadcastChatMessage {
Dec 06 03:23:37.772 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 03:23:37.772 INFO [stderr]     |
Dec 06 03:23:37.772 INFO [stderr]     = note: #[warn(dead_code)] on by default
Dec 06 03:23:37.772 INFO [stderr] 
Dec 06 03:23:37.844 INFO [stderr] warning: struct is never constructed: `NetworkStatistics`
Dec 06 03:23:37.844 INFO [stderr]    --> src/net.rs:318:1
Dec 06 03:23:37.844 INFO [stderr]     |
Dec 06 03:23:37.844 INFO [stderr] 318 | pub struct NetworkStatistics {
Dec 06 03:23:37.844 INFO [stderr]     | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 03:23:37.844 INFO [stderr]     |
Dec 06 03:23:37.844 INFO [stderr]     = note: #[warn(dead_code)] on by default
Dec 06 03:23:37.844 INFO [stderr] 
Dec 06 03:23:37.844 INFO [stderr] warning: method is never used: `new`
Dec 06 03:23:37.844 INFO [stderr]    --> src/net.rs:326:5
Dec 06 03:23:37.844 INFO [stderr]     |
Dec 06 03:23:37.844 INFO [stderr] 326 |     fn new() -> Self {
Dec 06 03:23:37.844 INFO [stderr]     |     ^^^^^^^^^^^^^^^^
Dec 06 03:23:37.844 INFO [stderr] 
Dec 06 03:23:37.844 INFO [stderr] warning: method is never used: `reset`
Dec 06 03:23:37.844 INFO [stderr]    --> src/net.rs:335:5
Dec 06 03:23:37.844 INFO [stderr]     |
Dec 06 03:23:37.844 INFO [stderr] 335 |     fn reset(&mut self) {
Dec 06 03:23:37.844 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^
Dec 06 03:23:37.844 INFO [stderr] 
Dec 06 03:23:37.845 INFO [stderr] warning: struct is never constructed: `TXQueue`
Dec 06 03:23:37.845 INFO [stderr]    --> src/net.rs:452:1
Dec 06 03:23:37.845 INFO [stderr]     |
Dec 06 03:23:37.845 INFO [stderr] 452 | pub struct TXQueue {
Dec 06 03:23:37.845 INFO [stderr]     | ^^^^^^^^^^^^^^^^^^
Dec 06 03:23:37.845 INFO [stderr] 
Dec 06 03:23:37.852 INFO [stderr] warning: struct is never constructed: `NetworkManager`
Dec 06 03:23:37.852 INFO [stderr]    --> src/net.rs:705:1
Dec 06 03:23:37.852 INFO [stderr]     |
Dec 06 03:23:37.852 INFO [stderr] 705 | pub struct NetworkManager {
Dec 06 03:23:37.852 INFO [stderr]     | ^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 03:23:37.852 INFO [stderr] 
Dec 06 03:23:37.852 INFO [stderr] warning: method is never used: `new`
Dec 06 03:23:37.852 INFO [stderr]    --> src/net.rs:715:5
Dec 06 03:23:37.852 INFO [stderr]     |
Dec 06 03:23:37.852 INFO [stderr] 715 |     pub fn new() -> Self {
Dec 06 03:23:37.852 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^
Dec 06 03:23:37.852 INFO [stderr] 
Dec 06 03:23:37.852 INFO [stderr] warning: method is never used: `with_message_buffering`
Dec 06 03:23:37.852 INFO [stderr]    --> src/net.rs:724:5
Dec 06 03:23:37.852 INFO [stderr]     |
Dec 06 03:23:37.852 INFO [stderr] 724 |     pub fn with_message_buffering(self) -> NetworkManager {
Dec 06 03:23:37.852 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 03:23:37.852 INFO [stderr] 
Dec 06 03:23:37.852 INFO [stderr] warning: method is never used: `reset`
Dec 06 03:23:37.852 INFO [stderr]    --> src/net.rs:736:5
Dec 06 03:23:37.852 INFO [stderr]     |
Dec 06 03:23:37.852 INFO [stderr] 736 |     pub fn reset(&mut self) {
Dec 06 03:23:37.852 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 03:23:37.852 INFO [stderr] 
Dec 06 03:23:43.007 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 27.32s
Dec 06 03:23:43.020 INFO [stderr] su: No module specific data is present
Dec 06 03:23:43.303 INFO running `"docker" "inspect" "2f624b15c92fc89bd7bcdbda2f54e68834baba2d7814e3cfcd30b259d2ff6c73"`
Dec 06 03:23:43.408 INFO running `"docker" "rm" "-f" "2f624b15c92fc89bd7bcdbda2f54e68834baba2d7814e3cfcd30b259d2ff6c73"`
Dec 06 03:23:43.499 INFO [stdout] 2f624b15c92fc89bd7bcdbda2f54e68834baba2d7814e3cfcd30b259d2ff6c73
Dec 06 03:23:43.502 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 06 03:23:43.691 INFO [stdout] 7e33f1f7b29ce0a9688477b08c06bd4ff70aa9491045f718b0541e0164a6d5e3
Dec 06 03:23:43.693 INFO running `"docker" "start" "-a" "7e33f1f7b29ce0a9688477b08c06bd4ff70aa9491045f718b0541e0164a6d5e3"`
Dec 06 03:23:44.125 INFO [stderr] usermod: no changes
Dec 06 03:23:44.228 INFO [stderr]    Compiling netwayste v0.1.1 (/source)
Dec 06 03:23:45.800 INFO [stderr] warning: unused variable: `chat_msg`
Dec 06 03:23:45.800 INFO [stderr]     --> src/net.rs:1578:13
Dec 06 03:23:45.800 INFO [stderr]      |
Dec 06 03:23:45.800 INFO [stderr] 1578 |         let chat_msg = BroadcastChatMessage::new(0, "chatchat".to_owned(), "chatchat".to_owned());
Dec 06 03:23:45.800 INFO [stderr]      |             ^^^^^^^^ help: consider using `_chat_msg` instead
Dec 06 03:23:45.800 INFO [stderr]      |
Dec 06 03:23:45.800 INFO [stderr]      = note: #[warn(unused_variables)] on by default
Dec 06 03:23:45.800 INFO [stderr] 
Dec 06 03:23:45.800 INFO [stderr] warning: unused variable: `exit_tx`
Dec 06 03:23:45.800 INFO [stderr]    --> src/client.rs:231:13
Dec 06 03:23:45.800 INFO [stderr]     |
Dec 06 03:23:45.800 INFO [stderr] 231 |             exit_tx: &mpsc::UnboundedSender<()>,
Dec 06 03:23:45.800 INFO [stderr]     |             ^^^^^^^ help: consider using `_exit_tx` instead
Dec 06 03:23:45.800 INFO [stderr] 
Dec 06 03:23:46.135 INFO [stderr] warning: unused variable: `chat_msg`
Dec 06 03:23:46.135 INFO [stderr]     --> src/net.rs:1578:13
Dec 06 03:23:46.135 INFO [stderr]      |
Dec 06 03:23:46.135 INFO [stderr] 1578 |         let chat_msg = BroadcastChatMessage::new(0, "chatchat".to_owned(), "chatchat".to_owned());
Dec 06 03:23:46.135 INFO [stderr]      |             ^^^^^^^^ help: consider using `_chat_msg` instead
Dec 06 03:23:46.135 INFO [stderr]      |
Dec 06 03:23:46.135 INFO [stderr]      = note: #[warn(unused_variables)] on by default
Dec 06 03:23:46.135 INFO [stderr] 
Dec 06 03:23:46.980 INFO [stderr] warning: method is never used: `reset`
Dec 06 03:23:46.980 INFO [stderr]    --> src/net.rs:335:5
Dec 06 03:23:46.980 INFO [stderr]     |
Dec 06 03:23:46.980 INFO [stderr] 335 |     fn reset(&mut self) {
Dec 06 03:23:46.980 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^
Dec 06 03:23:46.980 INFO [stderr]     |
Dec 06 03:23:46.980 INFO [stderr]     = note: #[warn(dead_code)] on by default
Dec 06 03:23:46.980 INFO [stderr] 
Dec 06 03:23:46.980 INFO [stderr] warning: method is never used: `with_message_buffering`
Dec 06 03:23:46.980 INFO [stderr]    --> src/net.rs:724:5
Dec 06 03:23:46.980 INFO [stderr]     |
Dec 06 03:23:46.980 INFO [stderr] 724 |     pub fn with_message_buffering(self) -> NetworkManager {
Dec 06 03:23:46.980 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 03:23:46.980 INFO [stderr] 
Dec 06 03:23:46.980 INFO [stderr] warning: method is never used: `reset`
Dec 06 03:23:46.980 INFO [stderr]    --> src/net.rs:736:5
Dec 06 03:23:46.980 INFO [stderr]     |
Dec 06 03:23:46.980 INFO [stderr] 736 |     pub fn reset(&mut self) {
Dec 06 03:23:46.980 INFO [stderr]     |     ^^^^^^^^^^^^^^^^^^^^^^^
Dec 06 03:23:46.980 INFO [stderr] 
Dec 06 03:23:51.629 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 7.49s
Dec 06 03:23:51.636 INFO [stderr] su: No module specific data is present
Dec 06 03:23:51.873 INFO running `"docker" "inspect" "7e33f1f7b29ce0a9688477b08c06bd4ff70aa9491045f718b0541e0164a6d5e3"`
Dec 06 03:23:51.956 INFO running `"docker" "rm" "-f" "7e33f1f7b29ce0a9688477b08c06bd4ff70aa9491045f718b0541e0164a6d5e3"`
Dec 06 03:23:52.013 INFO [stdout] 7e33f1f7b29ce0a9688477b08c06bd4ff70aa9491045f718b0541e0164a6d5e3
Dec 06 03:23:52.019 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-1/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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 06 03:23:52.181 INFO [stdout] faaf8e64a3a7a2aaea9640dfd618276090f40cc29f8b6a0328a52f18bad18841
Dec 06 03:23:52.190 INFO running `"docker" "start" "-a" "faaf8e64a3a7a2aaea9640dfd618276090f40cc29f8b6a0328a52f18bad18841"`
Dec 06 03:23:52.517 INFO [stderr] usermod: no changes
Dec 06 03:23:52.620 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
Dec 06 03:23:52.623 INFO [stderr]      Running /target/debug/deps/client-f4a776d126f2a5b8
Dec 06 03:23:52.626 INFO [stdout] 
Dec 06 03:23:52.626 INFO [stdout] running 51 tests
Dec 06 03:23:52.631 INFO [stdout] test net::test::test_buffer_item_advanced_max_sequence_number_arrives_after_a_wrap ... ok
Dec 06 03:23:52.631 INFO [stdout] test net::test::test_buffer_item_advanced_reverse_sequential_then_random_then_reverse_sequential ... ok
Dec 06 03:23:52.631 INFO [stdout] test net::test::test_buffer_item_advanced_oldest_sequence_number_arrived_last ... ok
Dec 06 03:23:52.631 INFO [stdout] test net::test::test_buffer_item_advanced_wrap_occurs_with_two_item_queue_in_reverse ... ok
Dec 06 03:23:52.631 INFO [stdout] test net::test::test_buffer_item_advanced_wrap_occurs_with_two_item_queue ... ok
Dec 06 03:23:52.631 INFO [stdout] test net::test::test_buffer_item_advanced_wrapping_case_everything_out_of_order ... ok
Dec 06 03:23:52.631 INFO [stdout] test net::test::test_buffer_item_advanced_sequential_then_pseudorandom_then_sequential ... ok
Dec 06 03:23:52.631 INFO [stdout] test net::test::test_buffer_item_basic_repetition ... ok
Dec 06 03:23:52.631 INFO [stdout] test net::test::test_buffer_item_advanced_wrapping_case_sequence_number_descending ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_advanced_wrapping_case_sequence_number_alternating ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_advanced_wrapping_case_max_arrives_first ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_basic_contiguous_ascending ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_basic_sequencing ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_basic_random ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_basic_contiguous_descending ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_queue_is_empty ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_max_queue_limit_maintained ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_newer_packet_has_smaller_sequence_number ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_butterfly_pattern ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_basic_wrapping_case_then_out_of_order ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_basic_sequential_gap_ascending ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_basic_wrapping_case ... ok
Dec 06 03:23:52.632 INFO [stdout] test net::test::test_buffer_item_basic_sequential_gap_descending ... ok
Dec 06 03:23:52.634 INFO [stdout] test net::test::test_discard_older_packets_exceeds_limit_retains_max ... ok
Dec 06 03:23:52.634 INFO [stdout] test net::test::test_discard_older_packets_under_limit_keeps_all_messages ... ok
Dec 06 03:23:52.634 INFO [stdout] test net::test::test_reinitialize_all_queues_cleared ... ok
Dec 06 03:23:52.634 INFO [stdout] test test::build_command_request_action_disconnect ... ok
Dec 06 03:23:52.634 INFO [stdout] test test::build_command_request_action_disconnect_with_args_returns_no_action ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::build_command_request_action_list_in_lobby ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::build_command_request_action_unknown_command ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::handle_incoming_chats_client_is_up_to_date ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::handle_incoming_chats_new_messages_are_old_and_new ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::handle_incoming_chats_new_messages_are_older ... ok
Dec 06 03:23:52.637 INFO [stdout] test net::test::test_buffer_item_sequence_number_reused ... ok
Dec 06 03:23:52.637 INFO [stdout] test net::test::test_discard_older_packets_empty_queue ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::handle_incoming_chats_no_new_chat_messages ... ok
Dec 06 03:23:52.637 INFO [stdout] test net::test::test_discard_older_packets_equal_to_limit ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::handle_logged_in_verify_connection_cookie ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::build_command_request_action_leave_cases ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::handle_response_ok_join_request_sent ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::handle_response_ok_no_request_sent ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::build_command_request_action_help_returns_no_action ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::handle_user_input_event_increment_sequence_number ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::build_command_request_action_join_cases ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::parse_stdin_input_has_no_leading_forward_slash ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::parse_stdin_input_no_arguments ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::parse_stdin_input_multiple_arguments ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::handle_response_ok_none_request_sent ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::build_command_request_action_list_in_game ... ok
Dec 06 03:23:52.637 INFO [stdout] test test::handle_response_ok_leave_request_sent ... ok
Dec 06 03:23:52.685 INFO [stdout] test test::handle_incoming_chats_new_messages_player_name_not_set_panics ... ok
Dec 06 03:23:52.685 INFO [stdout] 
Dec 06 03:23:52.685 INFO [stdout] test result: ok. 51 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 03:23:52.685 INFO [stdout] 
Dec 06 03:23:52.687 INFO [stderr]      Running /target/debug/deps/server-30c973b20c08c586
Dec 06 03:23:52.689 INFO [stdout] 
Dec 06 03:23:52.689 INFO [stdout] running 77 tests
Dec 06 03:23:52.690 INFO [stdout] test net::test::test_buffer_item_advanced_wrap_occurs_with_two_item_queue ... ok
Dec 06 03:23:52.690 INFO [stdout] test net::test::test_buffer_item_advanced_oldest_sequence_number_arrived_last ... ok
Dec 06 03:23:52.690 INFO [stdout] test net::test::test_buffer_item_advanced_sequential_then_pseudorandom_then_sequential ... ok
Dec 06 03:23:52.690 INFO [stdout] test net::test::test_buffer_item_advanced_max_sequence_number_arrives_after_a_wrap ... ok
Dec 06 03:23:52.690 INFO [stdout] test net::test::test_buffer_item_advanced_wrap_occurs_with_two_item_queue_in_reverse ... ok
Dec 06 03:23:52.690 INFO [stdout] test net::test::test_buffer_item_advanced_reverse_sequential_then_random_then_reverse_sequential ... ok
Dec 06 03:23:52.691 INFO [stdout] test net::test::test_buffer_item_advanced_wrapping_case_everything_out_of_order ... ok
Dec 06 03:23:52.691 INFO [stdout] test net::test::test_buffer_item_advanced_wrapping_case_sequence_number_alternating ... ok
Dec 06 03:23:52.691 INFO [stdout] test net::test::test_buffer_item_advanced_wrapping_case_sequence_number_descending ... ok
Dec 06 03:23:52.691 INFO [stdout] test net::test::test_buffer_item_basic_contiguous_ascending ... ok
Dec 06 03:23:52.691 INFO [stdout] test net::test::test_buffer_item_basic_contiguous_descending ... ok
Dec 06 03:23:52.691 INFO [stdout] test net::test::test_buffer_item_basic_random ... ok
Dec 06 03:23:52.691 INFO [stdout] test net::test::test_buffer_item_advanced_wrapping_case_max_arrives_first ... ok
Dec 06 03:23:52.691 INFO [stdout] test net::test::test_buffer_item_basic_repetition ... ok
Dec 06 03:23:52.691 INFO [stdout] test net::test::test_buffer_item_basic_sequential_gap_ascending ... ok
Dec 06 03:23:52.691 INFO [stdout] test net::test::test_buffer_item_basic_wrapping_case ... ok
Dec 06 03:23:52.691 INFO [stdout] test net::test::test_buffer_item_butterfly_pattern ... ok
Dec 06 03:23:52.691 INFO [stdout] test net::test::test_buffer_item_basic_sequential_gap_descending ... ok
Dec 06 03:23:52.692 INFO [stdout] test net::test::test_buffer_item_basic_wrapping_case_then_out_of_order ... ok
Dec 06 03:23:52.692 INFO [stdout] test net::test::test_discard_older_packets_empty_queue ... ok
Dec 06 03:23:52.692 INFO [stdout] test net::test::test_discard_older_packets_equal_to_limit ... ok
Dec 06 03:23:52.692 INFO [stdout] test net::test::test_discard_older_packets_under_limit_keeps_all_messages ... ok
Dec 06 03:23:52.692 INFO [stdout] test net::test::test_buffer_item_sequence_number_reused ... ok
Dec 06 03:23:52.692 INFO [stdout] test net::test::test_buffer_item_basic_sequencing ... ok
Dec 06 03:23:52.692 INFO [stdout] test net::test::test_buffer_item_queue_is_empty ... ok
Dec 06 03:23:52.692 INFO [stdout] test net::test::test_reinitialize_all_queues_cleared ... ok
Dec 06 03:23:52.692 INFO [stdout] test net::test::test_discard_older_packets_exceeds_limit_retains_max ... ok
Dec 06 03:23:52.692 INFO [stdout] test net::test::test_buffer_item_newer_packet_has_smaller_sequence_number ... ok
Dec 06 03:23:52.693 INFO [stdout] test test::construct_client_updates_no_rooms ... ok
Dec 06 03:23:52.693 INFO [stdout] test net::test::test_buffer_item_max_queue_limit_maintained ... ok
Dec 06 03:23:52.695 INFO [stdout] test test::collect_unacknowledged_messages_an_active_room_which_expired_all_messages_returns_none ... ok
Dec 06 03:23:52.695 INFO [stdout] test test::collect_unacknowledged_messages_a_rooms_unacknowledged_chat_messages_are_collected_for_their_player ... ok
Dec 06 03:23:52.695 INFO [stdout] test test::construct_client_updates_empty_rooms ... ok
Dec 06 03:23:52.695 INFO [stdout] test test::add_new_player_player_added_with_initial_sequence_number ... ok
Dec 06 03:23:52.696 INFO [stdout] test test::broadcast_message_to_two_players_in_room ... ok
Dec 06 03:23:52.696 INFO [stdout] test test::broadcast_message_to_an_empty_room ... ok
Dec 06 03:23:52.696 INFO [stdout] test test::create_new_room_name_is_too_long ... ok
Dec 06 03:23:52.700 INFO [stdout] test test::construct_client_updates_populated_room_returns_updates_after_client_acked ... ok
Dec 06 03:23:52.703 INFO [stdout] test test::create_new_room_good_case ... ok
Dec 06 03:23:52.703 INFO [stdout] test test::construct_client_updates_populated_room_returns_all_messages ... ok
Dec 06 03:23:52.703 INFO [stdout] test test::create_new_room_name_taken ... ok
Dec 06 03:23:52.703 INFO [stdout] test test::create_new_room_player_already_in_room ... ok
Dec 06 03:23:52.707 INFO [stdout] test test::decode_packet_update_reply_good_case ... ok
Dec 06 03:23:52.707 INFO [stdout] test test::disconnect_get_player_by_cookie_fails ... ok
Dec 06 03:23:52.707 INFO [stdout] test test::expire_old_messages_in_all_rooms_one_room_old_messages_are_wiped ... ok
Dec 06 03:23:52.707 INFO [stdout] test test::disconnect_while_in_room_removes_all_traces_of_player ... ok
Dec 06 03:23:52.707 INFO [stdout] test test::expire_old_messages_in_all_rooms_room_is_empty ... ok
Dec 06 03:23:52.707 INFO [stdout] test test::create_new_room_join_room_good_case ... ok
Dec 06 03:23:52.710 INFO [stdout] test test::expire_old_messages_in_all_rooms_one_room_good_case ... ok
Dec 06 03:23:52.710 INFO [stdout] test test::expire_old_messages_in_all_rooms_several_rooms_good_case ... ok
Dec 06 03:23:52.710 INFO [stdout] test test::expire_old_messages_in_all_rooms_several_rooms_old_messages_are_wiped ... ok
Dec 06 03:23:52.710 INFO [stdout] test test::get_confirmed_chat_seq_num_server_tracks_players_chat_updates ... ok
Dec 06 03:23:52.710 INFO [stdout] test test::get_message_skip_count_player_acked_messages_are_not_included_in_skip_count_wrapped_case ... ok
Dec 06 03:23:52.711 INFO [stdout] test test::decode_packet_update_reply_invalid_cookie ... ok
Dec 06 03:23:52.711 INFO [stdout] test test::handle_chat_message_player_in_game_many_messages ... ok
Dec 06 03:23:52.711 INFO [stdout] test test::handle_new_connection_good_case ... ok
Dec 06 03:23:52.711 INFO [stdout] test test::handle_chat_message_player_not_in_game ... ok
Dec 06 03:23:52.711 INFO [stdout] test test::handle_chat_message_player_in_game_one_message ... ok
Dec 06 03:23:52.712 INFO [stdout] test test::handle_request_action_spot_check_response_packet ... ok
Dec 06 03:23:52.712 INFO [stdout] test test::handle_new_connection_player_name_taken ... ok
Dec 06 03:23:52.712 INFO [stdout] test test::get_message_skip_count_player_acked_messages_are_not_included_in_skip_count ... ok
Dec 06 03:23:52.712 INFO [stdout] test test::is_unique_player_name_yes_and_no_case ... ok
Dec 06 03:23:52.712 INFO [stdout] test test::join_room_player_already_in_room ... ok
Dec 06 03:23:52.713 INFO [stdout] test test::leave_room_player_not_in_room ... ok
Dec 06 03:23:52.713 INFO [stdout] test test::join_room_room_does_not_exist ... ok
Dec 06 03:23:52.713 INFO [stdout] test test::has_chatted_player_did_not_chat_on_join ... ok
Dec 06 03:23:52.713 INFO [stdout] test test::leave_room_good_case ... ok
Dec 06 03:23:52.713 INFO [stdout] test test::leave_room_unregistered_player_id ... ok
Dec 06 03:23:52.713 INFO [stdout] test test::process_request_action_connect_while_connected ... ok
Dec 06 03:23:52.716 INFO [stdout] test test::validate_client_version_client_is_from_the_future ... ok
Dec 06 03:23:52.716 INFO [stdout] test test::validate_client_version_client_is_up_to_date ... ok
Dec 06 03:23:52.716 INFO [stdout] test test::validate_client_version_client_is_very_old ... ok
Dec 06 03:23:52.720 INFO [stdout] test test::process_request_action_none_is_invalid ... ok
Dec 06 03:23:52.724 INFO [stdout] test test::list_players_player_shows_up_in_player_list ... ok
Dec 06 03:23:52.727 INFO [stdout] test test::process_request_action_simple ... ok
Dec 06 03:23:52.775 INFO [stdout] test test::disconnect_get_player_by_id_fails ... ok
Dec 06 03:23:52.831 INFO [stdout] test test::process_request_action_complex ... ok
Dec 06 03:23:52.831 INFO [stdout] 
Dec 06 03:23:52.831 INFO [stdout] test result: ok. 77 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 06 03:23:52.831 INFO [stdout] 
Dec 06 03:23:52.835 INFO [stderr] su: No module specific data is present
Dec 06 03:23:53.043 INFO running `"docker" "inspect" "faaf8e64a3a7a2aaea9640dfd618276090f40cc29f8b6a0328a52f18bad18841"`
Dec 06 03:23:53.159 INFO running `"docker" "rm" "-f" "faaf8e64a3a7a2aaea9640dfd618276090f40cc29f8b6a0328a52f18bad18841"`
Dec 06 03:23:53.241 INFO [stdout] faaf8e64a3a7a2aaea9640dfd618276090f40cc29f8b6a0328a52f18bad18841