Dec 14 14:00:52.749 INFO testing webrtc-sctp-0.0.0 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 14 14:00:52.750 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 14 14:00:53.254 INFO [stdout] e0ddb1ace3009eaf7cb3400e257ebf18f3c0e069c14b50686036b8a823ffe226
Dec 14 14:00:53.256 INFO running `"docker" "start" "-a" "e0ddb1ace3009eaf7cb3400e257ebf18f3c0e069c14b50686036b8a823ffe226"`
Dec 14 14:00:55.195 INFO [stderr] usermod: no changes
Dec 14 14:00:55.413 INFO [stderr]    Compiling tokio-timer v0.1.2
Dec 14 14:00:55.451 INFO [stderr]    Compiling blake2 v0.7.1
Dec 14 14:00:58.595 INFO [stderr]    Compiling webrtc-sctp v0.0.0 (/source)
Dec 14 14:01:10.322 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 15.11s
Dec 14 14:01:10.331 INFO [stderr] su: No module specific data is present
Dec 14 14:01:11.271 INFO running `"docker" "inspect" "e0ddb1ace3009eaf7cb3400e257ebf18f3c0e069c14b50686036b8a823ffe226"`
Dec 14 14:01:11.617 INFO running `"docker" "rm" "-f" "e0ddb1ace3009eaf7cb3400e257ebf18f3c0e069c14b50686036b8a823ffe226"`
Dec 14 14:01:11.959 INFO [stdout] e0ddb1ace3009eaf7cb3400e257ebf18f3c0e069c14b50686036b8a823ffe226
Dec 14 14:01:11.967 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 14 14:01:12.499 INFO [stdout] ac49242dcee606ed9df5bd06824f504fcab0e2c93d7206904a5d229fa639cfa5
Dec 14 14:01:12.507 INFO running `"docker" "start" "-a" "ac49242dcee606ed9df5bd06824f504fcab0e2c93d7206904a5d229fa639cfa5"`
Dec 14 14:01:13.675 INFO [stderr] usermod: no changes
Dec 14 14:01:13.958 INFO [stderr]    Compiling webrtc-sctp v0.0.0 (/source)
Dec 14 14:01:37.993 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 24.28s
Dec 14 14:01:38.027 INFO [stderr] su: No module specific data is present
Dec 14 14:01:39.080 INFO running `"docker" "inspect" "ac49242dcee606ed9df5bd06824f504fcab0e2c93d7206904a5d229fa639cfa5"`
Dec 14 14:01:39.421 INFO running `"docker" "rm" "-f" "ac49242dcee606ed9df5bd06824f504fcab0e2c93d7206904a5d229fa639cfa5"`
Dec 14 14:01:39.795 INFO [stdout] ac49242dcee606ed9df5bd06824f504fcab0e2c93d7206904a5d229fa639cfa5
Dec 14 14:01:39.801 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-1/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 14 14:01:40.233 INFO [stdout] 97b0e02d0e15cf29969f75a00fa974d84883930ef18b8a06a3aaff1cf0f2b270
Dec 14 14:01:40.236 INFO running `"docker" "start" "-a" "97b0e02d0e15cf29969f75a00fa974d84883930ef18b8a06a3aaff1cf0f2b270"`
Dec 14 14:01:41.407 INFO [stderr] usermod: no changes
Dec 14 14:01:41.671 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.24s
Dec 14 14:01:41.681 INFO [stderr]      Running /target/debug/deps/webrtc_sctp-972690f0c19083fc
Dec 14 14:01:41.687 INFO [stdout] 
Dec 14 14:01:41.687 INFO [stdout] running 79 tests
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_cookie_ack_chunk ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_cookie_echo_chunk ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_data_chunk_full ... ignored
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_error_chunk ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_abort_chunk_full ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::checksum::tests::test_checksum_write ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::checksum::tests::test_checksum_verify ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::checksum::tests::test_checksum_read ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_abort_chunk ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_data_chunk ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::checksum::tests::test_checksum_compute ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_init_ack_chunk_full ... ignored
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_init_chunk_full ... ignored
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_heartbeat_chunk ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_sack_chunk_full ... ignored
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_error_chunk_full ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_shutdown_chunk ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_heartbeat_ack_chunk ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_heartbeat_chunk_full ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_shutdown_ack_chunk ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_init_ack_chunk ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_heartbeat_ack_chunk_full ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_sack_chunk ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::chunk::tests::test_init_chunk ... ok
Dec 14 14:01:41.687 INFO [stdout] test packet::error_cause::tests::test_missing_mandatory_parameter ... ok
Dec 14 14:01:41.695 INFO [stdout] test packet::chunk::tests::test_shutdown_chunk_full ... ok
Dec 14 14:01:41.695 INFO [stdout] test packet::chunk::tests::test_shutdown_complete_chunk ... ok
Dec 14 14:01:41.695 INFO [stdout] test packet::chunk::tests::test_shutdown_complete_chunk_full ... ok
Dec 14 14:01:41.695 INFO [stdout] test packet::error_cause::tests::test_cookie_received_while_shutting_down ... ok
Dec 14 14:01:41.695 INFO [stdout] test packet::error_cause::tests::test_invalid_mandatory_parameter ... ok
Dec 14 14:01:41.695 INFO [stdout] test packet::error_cause::tests::test_invalid_stream_identifier ... ok
Dec 14 14:01:41.695 INFO [stdout] test packet::error_cause::tests::test_invalid_stream_identifier_full ... ok
Dec 14 14:01:41.695 INFO [stdout] test packet::error_cause::tests::test_no_user_data ... ok
Dec 14 14:01:41.695 INFO [stdout] test packet::error_cause::tests::test_no_user_data_full ... ok
Dec 14 14:01:41.695 INFO [stdout] test packet::error_cause::tests::test_out_of_resource ... ok
Dec 14 14:01:41.695 INFO [stdout] test packet::error_cause::tests::test_protocol_violation ... ok
Dec 14 14:01:41.695 INFO [stdout] test packet::error_cause::tests::test_stale_cookie ... ok
Dec 14 14:01:41.699 INFO [stdout] test packet::error_cause::tests::test_restart_association_with_new_addresses ... ok
Dec 14 14:01:41.699 INFO [stdout] test packet::error_cause::tests::test_restart_association_with_new_addresses_full ... ok
Dec 14 14:01:41.699 INFO [stdout] test packet::error_cause::tests::test_unrecognized_chunk_type ... ok
Dec 14 14:01:41.699 INFO [stdout] test packet::error_cause::tests::test_unrecognized_parameters ... ok
Dec 14 14:01:41.699 INFO [stdout] test packet::error_cause::tests::test_unrecognized_parameters_full ... ok
Dec 14 14:01:41.704 INFO [stdout] test packet::error_cause::tests::test_stale_cookie_full ... ok
Dec 14 14:01:41.707 INFO [stdout] test packet::parameter::tests::test_cookie_preservative_parameter ... ok
Dec 14 14:01:41.707 INFO [stdout] test packet::parameter::tests::test_cookie_preservative_parameter_full ... ok
Dec 14 14:01:41.731 INFO [stdout] test packet::error_cause::tests::test_unresolvable_address ... ok
Dec 14 14:01:41.731 INFO [stdout] test packet::error_cause::tests::test_unresolvable_address_full ... ok
Dec 14 14:01:41.731 INFO [stdout] test packet::error_cause::tests::test_user_initiated_abort ... ok
Dec 14 14:01:41.731 INFO [stdout] test packet::parameter::tests::test_ecn_capable ... ok
Dec 14 14:01:41.731 INFO [stdout] test packet::parameter::tests::test_heartbeat_info ... ok
Dec 14 14:01:41.731 INFO [stdout] test packet::parameter::tests::test_forward_tsn_supported ... ok
Dec 14 14:01:41.739 INFO [stdout] test packet::parameter::tests::test_host_name_address_parameter ... ok
Dec 14 14:01:41.739 INFO [stdout] test packet::parameter::tests::test_host_name_address_parameter_full ... ok
Dec 14 14:01:41.739 INFO [stdout] test packet::parameter::tests::test_ipv4_address ... ok
Dec 14 14:01:41.739 INFO [stdout] test packet::parameter::tests::test_ipv6_address ... ok
Dec 14 14:01:41.739 INFO [stdout] test packet::parameter::tests::test_ipv4_address_full ... ok
Dec 14 14:01:41.739 INFO [stdout] test packet::parameter::tests::test_state_cookie ... ok
Dec 14 14:01:41.739 INFO [stdout] test packet::parameter::tests::test_supported_address_types ... ok
Dec 14 14:01:41.743 INFO [stdout] test packet::parameter::tests::test_supported_address_types_full ... ok
Dec 14 14:01:41.743 INFO [stdout] test packet::parameter::tests::test_unrecognized_parameter ... ok
Dec 14 14:01:41.743 INFO [stdout] test packet::parameter::tests::test_unrecognized_parameter_full ... ok
Dec 14 14:01:41.759 INFO [stdout] test packet::tests::test_packet_init ... ok
Dec 14 14:01:41.759 INFO [stdout] test stack::queue::tests::test_inverse_order ... ok
Dec 14 14:01:41.759 INFO [stdout] test packet::tests::test_packet_cookie_ack_and_sack ... ok
Dec 14 14:01:41.775 INFO [stdout] test packet::error_cause::tests::test_missing_mandatory_parameter_full ... ok
Dec 14 14:01:41.807 INFO [stdout] test packet::error_cause::tests::test_unrecognized_chunk_type_full ... ok
Dec 14 14:01:41.851 INFO [stdout] test packet::parameter::tests::test_heartbeat_info_full ... ok
Dec 14 14:01:41.879 INFO [stdout] test packet::error_cause::tests::test_protocol_violation_full ... ok
Dec 14 14:01:41.907 INFO [stdout] test packet::error_cause::tests::test_user_initiated_abort_full ... ok
Dec 14 14:01:41.907 INFO [stdout] test util::serial::tests::test_ops ... ok
Dec 14 14:01:41.959 INFO [stdout] test stack::recvtracker::tests::test_recvtracker_rwnd_tracking ... ok
Dec 14 14:01:41.975 INFO [stdout] test util::serial::tests::test_serial_add ... ok
Dec 14 14:01:41.975 INFO [stdout] test util::serial::tests::test_serial_compare ... ok
Dec 14 14:01:42.225 INFO [stdout] test util::tests::test_generate_test_data ... ok
Dec 14 14:01:42.815 INFO [stdout] test stack::recvtracker::tests::test_recvtracker_tsn_tracking ... ok
Dec 14 14:01:44.199 INFO [stdout] test stack::queue::tests::test_ordered_data_queue ... ok
Dec 14 14:01:44.512 INFO [stdout] test util::buffer::tests::test_buffer ... ok
Dec 14 14:01:44.611 INFO [stdout] test stack::queue::tests::test_outgoing_data_queue ... ok
Dec 14 14:01:44.631 INFO [stdout] test stack::queue::tests::test_unordered_data_queue ... ok
Dec 14 14:01:44.631 INFO [stdout] 
Dec 14 14:01:44.631 INFO [stdout] test result: ok. 75 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out
Dec 14 14:01:44.631 INFO [stdout] 
Dec 14 14:01:44.631 INFO [stderr]      Running /target/debug/deps/test_simulation-cbf89656ae300e5b
Dec 14 14:01:44.643 INFO [stdout] 
Dec 14 14:01:44.643 INFO [stdout] running 10 tests
Dec 14 14:01:44.643 INFO [stdout] test test_many_associate_shutdown ... ignored
Dec 14 14:01:44.707 INFO [stdout] test test_associate ... ok
Dec 14 14:01:44.792 INFO [stdout] test test_shutdown ... ok
Dec 14 14:01:44.815 INFO [stdout] test test_simultaneous_shutdown ... ok
Dec 14 14:01:44.835 INFO [stdout] test test_data ... ok
Dec 14 14:01:44.935 INFO [stdout] test test_recv_timeout ... ok
Dec 14 14:01:44.943 INFO [stdout] test test_single_fragmented_message ... ok
Dec 14 14:01:45.007 INFO [stdout] test test_connect_timeout ... ok
Dec 14 14:01:47.655 INFO [stdout] re-sending chunk: DATA tsn=2997834249 stream_id=0 ssn=0 UBE=011 ppi=0
Dec 14 14:01:47.655 INFO [stdout] 	0000: 74 65 73 74 69 6e 67 31 32 33                   testing123
Dec 14 14:01:47.659 INFO [stdout] test test_retransmit ... ok
Dec 14 14:01:50.155 INFO [stdout] test test_many_fragmented_messages ... ok
Dec 14 14:01:50.155 INFO [stdout] 
Dec 14 14:01:50.155 INFO [stdout] test result: ok. 9 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out
Dec 14 14:01:50.155 INFO [stdout] 
Dec 14 14:01:50.157 INFO [stderr]    Doc-tests webrtc-sctp
Dec 14 14:01:57.379 INFO [stdout] 
Dec 14 14:01:57.379 INFO [stdout] running 0 tests
Dec 14 14:01:57.379 INFO [stdout] 
Dec 14 14:01:57.379 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 14 14:01:57.379 INFO [stdout] 
Dec 14 14:01:57.391 INFO [stderr] su: No module specific data is present
Dec 14 14:01:58.572 INFO running `"docker" "inspect" "97b0e02d0e15cf29969f75a00fa974d84883930ef18b8a06a3aaff1cf0f2b270"`
Dec 14 14:01:58.927 INFO running `"docker" "rm" "-f" "97b0e02d0e15cf29969f75a00fa974d84883930ef18b8a06a3aaff1cf0f2b270"`
Dec 14 14:01:59.262 INFO [stdout] 97b0e02d0e15cf29969f75a00fa974d84883930ef18b8a06a3aaff1cf0f2b270