Nov 17 18:19:05.174 INFO testing webrtc-sctp-0.0.0 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 17 18:19:05.176 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 18:19:05.657 INFO [stdout] ac75941fd07c6582f6ec5a8cb4dd945368f43708f6b36de0f88cadd55dd5c38d
Nov 17 18:19:05.663 INFO running `"docker" "start" "-a" "ac75941fd07c6582f6ec5a8cb4dd945368f43708f6b36de0f88cadd55dd5c38d"`
Nov 17 18:19:06.994 INFO [stderr] usermod: no changes
Nov 17 18:19:07.166 INFO [stderr]    Compiling tokio-timer v0.1.2
Nov 17 18:19:07.169 INFO [stderr]    Compiling blake2 v0.7.1
Nov 17 18:19:10.584 INFO [stderr]    Compiling webrtc-sctp v0.0.0 (/source)
Nov 17 18:19:21.793 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.77s
Nov 17 18:19:21.802 INFO [stderr] su: No module specific data is present
Nov 17 18:19:22.777 INFO running `"docker" "inspect" "ac75941fd07c6582f6ec5a8cb4dd945368f43708f6b36de0f88cadd55dd5c38d"`
Nov 17 18:19:23.145 INFO running `"docker" "rm" "-f" "ac75941fd07c6582f6ec5a8cb4dd945368f43708f6b36de0f88cadd55dd5c38d"`
Nov 17 18:19:23.545 INFO [stdout] ac75941fd07c6582f6ec5a8cb4dd945368f43708f6b36de0f88cadd55dd5c38d
Nov 17 18:19:23.547 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 18:19:24.004 INFO [stdout] 623160c122eaa69306b2752402c1948153372f73715211693c57a0f2368ac285
Nov 17 18:19:24.011 INFO running `"docker" "start" "-a" "623160c122eaa69306b2752402c1948153372f73715211693c57a0f2368ac285"`
Nov 17 18:19:25.063 INFO [stderr] usermod: no changes
Nov 17 18:19:25.279 INFO [stderr]    Compiling webrtc-sctp v0.0.0 (/source)
Nov 17 18:19:47.416 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 22.33s
Nov 17 18:19:47.428 INFO [stderr] su: No module specific data is present
Nov 17 18:19:48.564 INFO running `"docker" "inspect" "623160c122eaa69306b2752402c1948153372f73715211693c57a0f2368ac285"`
Nov 17 18:19:48.885 INFO running `"docker" "rm" "-f" "623160c122eaa69306b2752402c1948153372f73715211693c57a0f2368ac285"`
Nov 17 18:19:49.247 INFO [stdout] 623160c122eaa69306b2752402c1948153372f73715211693c57a0f2368ac285
Nov 17 18:19:49.254 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-0/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-0/pr-55982/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/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 +2e026d6e5d7621634147ad4c8074f1d964dbb1be-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"`
Nov 17 18:19:49.839 INFO [stdout] 12ab7f1dad2daccc0de88e911d112f2a28d0c40bf8a4cfe1dc9da25cc1f0c476
Nov 17 18:19:49.841 INFO running `"docker" "start" "-a" "12ab7f1dad2daccc0de88e911d112f2a28d0c40bf8a4cfe1dc9da25cc1f0c476"`
Nov 17 18:19:51.655 INFO [stderr] usermod: no changes
Nov 17 18:19:51.851 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.17s
Nov 17 18:19:51.867 INFO [stderr]      Running /target/debug/deps/webrtc_sctp-8d25a8825da6f75d
Nov 17 18:19:51.879 INFO [stdout] 
Nov 17 18:19:51.879 INFO [stdout] running 79 tests
Nov 17 18:19:51.879 INFO [stdout] test packet::checksum::tests::test_checksum_verify ... ok
Nov 17 18:19:51.879 INFO [stdout] test packet::checksum::tests::test_checksum_read ... ok
Nov 17 18:19:51.879 INFO [stdout] test packet::checksum::tests::test_checksum_compute ... ok
Nov 17 18:19:51.879 INFO [stdout] test packet::chunk::tests::test_data_chunk_full ... ignored
Nov 17 18:19:51.879 INFO [stdout] test packet::chunk::tests::test_error_chunk ... ok
Nov 17 18:19:51.879 INFO [stdout] test packet::chunk::tests::test_data_chunk ... ok
Nov 17 18:19:51.879 INFO [stdout] test packet::chunk::tests::test_error_chunk_full ... ok
Nov 17 18:19:51.879 INFO [stdout] test packet::chunk::tests::test_heartbeat_ack_chunk ... ok
Nov 17 18:19:51.879 INFO [stdout] test packet::chunk::tests::test_heartbeat_ack_chunk_full ... ok
Nov 17 18:19:51.879 INFO [stdout] test packet::chunk::tests::test_heartbeat_chunk ... ok
Nov 17 18:19:51.879 INFO [stdout] test packet::chunk::tests::test_init_ack_chunk_full ... ignored
Nov 17 18:19:51.879 INFO [stdout] test packet::chunk::tests::test_cookie_ack_chunk ... ok
Nov 17 18:19:51.879 INFO [stdout] test packet::chunk::tests::test_init_chunk_full ... ignored
Nov 17 18:19:51.879 INFO [stdout] test packet::chunk::tests::test_cookie_echo_chunk ... ok
Nov 17 18:19:51.879 INFO [stdout] test packet::chunk::tests::test_sack_chunk_full ... ignored
Nov 17 18:19:51.879 INFO [stdout] test packet::chunk::tests::test_heartbeat_chunk_full ... ok
Nov 17 18:19:51.882 INFO [stdout] test packet::chunk::tests::test_init_ack_chunk ... ok
Nov 17 18:19:51.882 INFO [stdout] test packet::chunk::tests::test_abort_chunk_full ... ok
Nov 17 18:19:51.882 INFO [stdout] test packet::chunk::tests::test_init_chunk ... ok
Nov 17 18:19:51.882 INFO [stdout] test packet::chunk::tests::test_abort_chunk ... ok
Nov 17 18:19:51.882 INFO [stdout] test packet::chunk::tests::test_sack_chunk ... ok
Nov 17 18:19:51.882 INFO [stdout] test packet::chunk::tests::test_shutdown_ack_chunk ... ok
Nov 17 18:19:51.882 INFO [stdout] test packet::chunk::tests::test_shutdown_chunk_full ... ok
Nov 17 18:19:51.882 INFO [stdout] test packet::chunk::tests::test_shutdown_chunk ... ok
Nov 17 18:19:51.882 INFO [stdout] test packet::chunk::tests::test_shutdown_complete_chunk ... ok
Nov 17 18:19:51.882 INFO [stdout] test packet::checksum::tests::test_checksum_write ... ok
Nov 17 18:19:51.882 INFO [stdout] test packet::chunk::tests::test_shutdown_complete_chunk_full ... ok
Nov 17 18:19:51.882 INFO [stdout] test packet::error_cause::tests::test_cookie_received_while_shutting_down ... ok
Nov 17 18:19:51.882 INFO [stdout] test packet::error_cause::tests::test_invalid_stream_identifier ... ok
Nov 17 18:19:51.883 INFO [stdout] test packet::error_cause::tests::test_invalid_mandatory_parameter ... ok
Nov 17 18:19:51.883 INFO [stdout] test packet::error_cause::tests::test_missing_mandatory_parameter ... ok
Nov 17 18:19:51.883 INFO [stdout] test packet::error_cause::tests::test_no_user_data ... ok
Nov 17 18:19:51.883 INFO [stdout] test packet::error_cause::tests::test_out_of_resource ... ok
Nov 17 18:19:51.883 INFO [stdout] test packet::error_cause::tests::test_no_user_data_full ... ok
Nov 17 18:19:51.883 INFO [stdout] test packet::error_cause::tests::test_invalid_stream_identifier_full ... ok
Nov 17 18:19:51.885 INFO [stdout] test packet::error_cause::tests::test_protocol_violation ... ok
Nov 17 18:19:51.891 INFO [stdout] test packet::error_cause::tests::test_restart_association_with_new_addresses ... ok
Nov 17 18:19:51.891 INFO [stdout] test packet::error_cause::tests::test_restart_association_with_new_addresses_full ... ok
Nov 17 18:19:51.891 INFO [stdout] test packet::error_cause::tests::test_stale_cookie ... ok
Nov 17 18:19:51.891 INFO [stdout] test packet::error_cause::tests::test_stale_cookie_full ... ok
Nov 17 18:19:51.891 INFO [stdout] test packet::error_cause::tests::test_unrecognized_chunk_type ... ok
Nov 17 18:19:51.891 INFO [stdout] test packet::error_cause::tests::test_unrecognized_parameters ... ok
Nov 17 18:19:51.891 INFO [stdout] test packet::error_cause::tests::test_unrecognized_parameters_full ... ok
Nov 17 18:19:51.895 INFO [stdout] test packet::error_cause::tests::test_unresolvable_address ... ok
Nov 17 18:19:51.895 INFO [stdout] test packet::error_cause::tests::test_unresolvable_address_full ... ok
Nov 17 18:19:51.895 INFO [stdout] test packet::parameter::tests::test_cookie_preservative_parameter ... ok
Nov 17 18:19:51.895 INFO [stdout] test packet::error_cause::tests::test_user_initiated_abort ... ok
Nov 17 18:19:51.903 INFO [stdout] test packet::parameter::tests::test_forward_tsn_supported ... ok
Nov 17 18:19:51.903 INFO [stdout] test packet::parameter::tests::test_ecn_capable ... ok
Nov 17 18:19:51.903 INFO [stdout] test packet::parameter::tests::test_cookie_preservative_parameter_full ... ok
Nov 17 18:19:51.903 INFO [stdout] test packet::parameter::tests::test_heartbeat_info ... ok
Nov 17 18:19:51.919 INFO [stdout] test packet::parameter::tests::test_ipv4_address ... ok
Nov 17 18:19:51.919 INFO [stdout] test packet::parameter::tests::test_ipv4_address_full ... ok
Nov 17 18:19:51.927 INFO [stdout] test packet::parameter::tests::test_host_name_address_parameter_full ... ok
Nov 17 18:19:51.927 INFO [stdout] test packet::parameter::tests::test_host_name_address_parameter ... ok
Nov 17 18:19:51.927 INFO [stdout] test packet::parameter::tests::test_ipv6_address ... ok
Nov 17 18:19:51.927 INFO [stdout] test packet::parameter::tests::test_state_cookie ... ok
Nov 17 18:19:51.967 INFO [stdout] test packet::parameter::tests::test_unrecognized_parameter ... ok
Nov 17 18:19:51.967 INFO [stdout] test packet::parameter::tests::test_supported_address_types_full ... ok
Nov 17 18:19:51.967 INFO [stdout] test packet::parameter::tests::test_supported_address_types ... ok
Nov 17 18:19:51.967 INFO [stdout] test packet::parameter::tests::test_unrecognized_parameter_full ... ok
Nov 17 18:19:51.967 INFO [stdout] test stack::queue::tests::test_inverse_order ... ok
Nov 17 18:19:51.967 INFO [stdout] test packet::tests::test_packet_cookie_ack_and_sack ... ok
Nov 17 18:19:51.995 INFO [stdout] test packet::error_cause::tests::test_protocol_violation_full ... ok
Nov 17 18:19:52.039 INFO [stdout] test packet::tests::test_packet_init ... ok
Nov 17 18:19:52.039 INFO [stdout] test packet::error_cause::tests::test_unrecognized_chunk_type_full ... ok
Nov 17 18:19:52.039 INFO [stdout] test packet::parameter::tests::test_heartbeat_info_full ... ok
Nov 17 18:19:52.039 INFO [stdout] test packet::error_cause::tests::test_user_initiated_abort_full ... ok
Nov 17 18:19:52.039 INFO [stdout] test util::serial::tests::test_ops ... ok
Nov 17 18:19:52.039 INFO [stdout] test util::serial::tests::test_serial_add ... ok
Nov 17 18:19:52.043 INFO [stdout] test packet::error_cause::tests::test_missing_mandatory_parameter_full ... ok
Nov 17 18:19:52.055 INFO [stdout] test util::serial::tests::test_serial_compare ... ok
Nov 17 18:19:52.203 INFO [stdout] test util::tests::test_generate_test_data ... ok
Nov 17 18:19:52.218 INFO [stdout] test stack::recvtracker::tests::test_recvtracker_rwnd_tracking ... ok
Nov 17 18:19:52.732 INFO [stdout] test stack::recvtracker::tests::test_recvtracker_tsn_tracking ... ok
Nov 17 18:19:53.609 INFO [stdout] test stack::queue::tests::test_unordered_data_queue ... ok
Nov 17 18:19:53.671 INFO [stdout] test stack::queue::tests::test_ordered_data_queue ... ok
Nov 17 18:19:53.719 INFO [stdout] test util::buffer::tests::test_buffer ... ok
Nov 17 18:19:53.936 INFO [stdout] test stack::queue::tests::test_outgoing_data_queue ... ok
Nov 17 18:19:53.936 INFO [stdout] 
Nov 17 18:19:53.936 INFO [stdout] test result: ok. 75 passed; 0 failed; 4 ignored; 0 measured; 0 filtered out
Nov 17 18:19:53.936 INFO [stdout] 
Nov 17 18:19:53.938 INFO [stderr]      Running /target/debug/deps/test_simulation-5c1873dc6d3b2fce
Nov 17 18:19:53.943 INFO [stdout] 
Nov 17 18:19:53.943 INFO [stdout] running 10 tests
Nov 17 18:19:53.946 INFO [stdout] test test_many_associate_shutdown ... ignored
Nov 17 18:19:53.999 INFO [stdout] test test_associate ... ok
Nov 17 18:19:54.091 INFO [stdout] test test_shutdown ... ok
Nov 17 18:19:54.096 INFO [stdout] test test_simultaneous_shutdown ... ok
Nov 17 18:19:54.163 INFO [stdout] test test_data ... ok
Nov 17 18:19:54.239 INFO [stdout] test test_connect_timeout ... ok
Nov 17 18:19:54.239 INFO [stdout] test test_recv_timeout ... ok
Nov 17 18:19:54.271 INFO [stdout] test test_single_fragmented_message ... ok
Nov 17 18:19:56.991 INFO [stdout] re-sending chunk: DATA tsn=2406808602 stream_id=0 ssn=0 UBE=011 ppi=0
Nov 17 18:19:56.991 INFO [stdout] 	0000: 74 65 73 74 69 6e 67 31 32 33                   testing123
Nov 17 18:19:56.994 INFO [stdout] test test_retransmit ... ok
Nov 17 18:20:00.666 INFO [stdout] test test_many_fragmented_messages ... ok
Nov 17 18:20:00.666 INFO [stdout] 
Nov 17 18:20:00.666 INFO [stdout] test result: ok. 9 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out
Nov 17 18:20:00.666 INFO [stdout] 
Nov 17 18:20:00.671 INFO [stderr]    Doc-tests webrtc-sctp
Nov 17 18:20:05.783 INFO [stdout] 
Nov 17 18:20:05.783 INFO [stdout] running 0 tests
Nov 17 18:20:05.784 INFO [stdout] 
Nov 17 18:20:05.784 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 17 18:20:05.784 INFO [stdout] 
Nov 17 18:20:05.807 INFO [stderr] su: No module specific data is present
Nov 17 18:20:06.445 INFO running `"docker" "inspect" "12ab7f1dad2daccc0de88e911d112f2a28d0c40bf8a4cfe1dc9da25cc1f0c476"`
Nov 17 18:20:06.829 INFO running `"docker" "rm" "-f" "12ab7f1dad2daccc0de88e911d112f2a28d0c40bf8a4cfe1dc9da25cc1f0c476"`
Nov 17 18:20:07.155 INFO [stdout] 12ab7f1dad2daccc0de88e911d112f2a28d0c40bf8a4cfe1dc9da25cc1f0c476