Nov 18 19:36:50.430 INFO testing bip_utracker-0.4.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 19:36:50.431 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 18 19:36:50.783 INFO [stdout] d8b00c5d89f9b2da5e32d751b760bf69fafdbd863bb737ee330fd56fe1e2db29
Nov 18 19:36:50.791 INFO running `"docker" "start" "-a" "d8b00c5d89f9b2da5e32d751b760bf69fafdbd863bb737ee330fd56fe1e2db29"`
Nov 18 19:36:52.188 INFO [stderr] usermod: no changes
Nov 18 19:36:52.415 INFO [stderr]    Compiling miow v0.1.5
Nov 18 19:36:52.415 INFO [stderr]    Compiling bip_util v0.5.0
Nov 18 19:36:53.174 INFO [stderr]    Compiling mio v0.5.1
Nov 18 19:36:54.675 INFO [stderr]    Compiling bip_handshake v0.7.1
Nov 18 19:36:57.339 INFO [stderr]    Compiling umio v0.3.0
Nov 18 19:37:03.071 INFO [stderr]    Compiling bip_utracker v0.4.0 (/source)
Nov 18 19:37:10.371 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 18.17s
Nov 18 19:37:10.393 INFO [stderr] su: No module specific data is present
Nov 18 19:37:11.349 INFO running `"docker" "inspect" "d8b00c5d89f9b2da5e32d751b760bf69fafdbd863bb737ee330fd56fe1e2db29"`
Nov 18 19:37:11.689 INFO running `"docker" "rm" "-f" "d8b00c5d89f9b2da5e32d751b760bf69fafdbd863bb737ee330fd56fe1e2db29"`
Nov 18 19:37:11.985 INFO [stdout] d8b00c5d89f9b2da5e32d751b760bf69fafdbd863bb737ee330fd56fe1e2db29
Nov 18 19:37:11.989 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 18 19:37:12.364 INFO [stdout] f7b673cfa47d8e7c353144299ea765ec850a4580361026729cdbc369e79e48ad
Nov 18 19:37:12.369 INFO running `"docker" "start" "-a" "f7b673cfa47d8e7c353144299ea765ec850a4580361026729cdbc369e79e48ad"`
Nov 18 19:37:13.624 INFO [stderr] usermod: no changes
Nov 18 19:37:13.802 INFO [stderr]    Compiling bip_utracker v0.4.0 (/source)
Nov 18 19:37:26.514 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.85s
Nov 18 19:37:26.526 INFO [stderr] su: No module specific data is present
Nov 18 19:37:27.791 INFO running `"docker" "inspect" "f7b673cfa47d8e7c353144299ea765ec850a4580361026729cdbc369e79e48ad"`
Nov 18 19:37:28.112 INFO running `"docker" "rm" "-f" "f7b673cfa47d8e7c353144299ea765ec850a4580361026729cdbc369e79e48ad"`
Nov 18 19:37:28.335 INFO [stdout] f7b673cfa47d8e7c353144299ea765ec850a4580361026729cdbc369e79e48ad
Nov 18 19:37:28.339 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-2/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-2/pr-55982/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/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 +6b9b97bd9b704f85f0184f7a213cc4d62bd9654c-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 18 19:37:28.835 INFO [stdout] 9204e7c27dd7999d3da26a3067de227e551aa187cdce86b4161f726be1f08323
Nov 18 19:37:28.837 INFO running `"docker" "start" "-a" "9204e7c27dd7999d3da26a3067de227e551aa187cdce86b4161f726be1f08323"`
Nov 18 19:37:29.877 INFO [stderr] usermod: no changes
Nov 18 19:37:30.089 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.18s
Nov 18 19:37:30.108 INFO [stderr]      Running /target/debug/deps/bip_utracker-b68f7749d255bd68
Nov 18 19:37:30.111 INFO [stdout] 
Nov 18 19:37:30.111 INFO [stdout] running 76 tests
Nov 18 19:37:30.111 INFO [stdout] test announce::tests::positive_parse_completed_event ... ok
Nov 18 19:37:30.111 INFO [stdout] test announce::tests::negative_parse_request_missing_key ... ok
Nov 18 19:37:30.111 INFO [stdout] test announce::tests::negative_parse_no_event ... ok
Nov 18 19:37:30.111 INFO [stdout] test announce::tests::negative_parse_incomplete_v6_source ... ok
Nov 18 19:37:30.111 INFO [stdout] test announce::tests::negative_parse_incomplete_state ... ok
Nov 18 19:37:30.111 INFO [stdout] test announce::tests::positive_parse_desired_peers_default ... ok
Nov 18 19:37:30.111 INFO [stdout] test announce::tests::positive_parse_desired_peers_specified ... ok
Nov 18 19:37:30.112 INFO [stdout] test announce::tests::negative_parse_empty_v6_source ... ok
Nov 18 19:37:30.112 INFO [stdout] test announce::tests::positive_parse_explicit_v4_source ... ok
Nov 18 19:37:30.112 INFO [stdout] test announce::tests::positive_parse_implied_v4_source ... ok
Nov 18 19:37:30.143 INFO [stdout] test announce::tests::negative_parse_empty_v4_source ... ok
Nov 18 19:37:30.143 INFO [stdout] test announce::tests::positive_parse_explicit_v6_source ... ok
Nov 18 19:37:30.143 INFO [stdout] test announce::tests::positive_parse_request_empty_options ... ok
Nov 18 19:37:30.143 INFO [stdout] test announce::tests::positive_parse_none_event ... ok
Nov 18 19:37:30.143 INFO [stdout] test announce::tests::negative_parse_incomplete_v4_source ... ok
Nov 18 19:37:30.143 INFO [stdout] test announce::tests::positive_parse_response_empty_peers ... ok
Nov 18 19:37:30.143 INFO [stdout] test announce::tests::positive_parse_state ... ok
Nov 18 19:37:30.143 INFO [stdout] test announce::tests::positive_parse_stopped_event ... ok
Nov 18 19:37:30.144 INFO [stdout] test announce::tests::positive_write_completed_event ... ok
Nov 18 19:37:30.144 INFO [stdout] test announce::tests::positive_write_desired_peers_specified ... ok
Nov 18 19:37:30.144 INFO [stdout] test announce::tests::positive_parse_implied_v6_source ... ok
Nov 18 19:37:30.144 INFO [stdout] test announce::tests::positive_parse_started_event ... ok
Nov 18 19:37:30.145 INFO [stdout] test announce::tests::positive_parse_response_many_peers ... ok
Nov 18 19:37:30.145 INFO [stdout] test announce::tests::positive_write_source_ipv6_implied ... ok
Nov 18 19:37:30.145 INFO [stdout] test announce::tests::positive_write_response ... ok
Nov 18 19:37:30.145 INFO [stdout] test announce::tests::positive_write_source_ipv4_explicit ... ok
Nov 18 19:37:30.145 INFO [stdout] test announce::tests::positive_write_source_ipv4_implied ... ok
Nov 18 19:37:30.145 INFO [stdout] test announce::tests::positive_write_started_event ... ok
Nov 18 19:37:30.145 INFO [stdout] test announce::tests::positive_write_desired_peers_default ... ok
Nov 18 19:37:30.145 INFO [stdout] test announce::tests::positive_write_state ... ok
Nov 18 19:37:30.145 INFO [stdout] test announce::tests::positive_write_stopped_event ... ok
Nov 18 19:37:30.147 INFO [stdout] test contact::tests::positive_iterate_v4 ... ok
Nov 18 19:37:30.147 INFO [stdout] test announce::tests::positive_write_request ... ok
Nov 18 19:37:30.147 INFO [stdout] test contact::tests::positive_iterate_v6 ... ok
Nov 18 19:37:30.147 INFO [stdout] test contact::tests::positive_parse_empty_v4 ... ok
Nov 18 19:37:30.147 INFO [stdout] test contact::tests::positive_parse_empty_v6 ... ok
Nov 18 19:37:30.147 INFO [stdout] test contact::tests::positive_write_empty_v6 ... ok
Nov 18 19:37:30.147 INFO [stdout] test contact::tests::positive_parse_peer_v4 ... ok
Nov 18 19:37:30.147 INFO [stdout] test announce::tests::positive_write_none_event ... ok
Nov 18 19:37:30.147 INFO [stdout] test contact::tests::positive_write_peer_v6 ... ok
Nov 18 19:37:30.147 INFO [stdout] test contact::tests::positive_write_peer_v4 ... ok
Nov 18 19:37:30.147 INFO [stdout] test option::tests::negative_parse_url_data_incomplete ... ok
Nov 18 19:37:30.149 INFO [stdout] test contact::tests::positive_parse_peer_v6 ... ok
Nov 18 19:37:30.149 INFO [stdout] test contact::tests::positive_parse_peers_v4 ... ok
Nov 18 19:37:30.149 INFO [stdout] test contact::tests::positive_parse_peers_v6 ... ok
Nov 18 19:37:30.151 INFO [stdout] test option::tests::positive_parse_noop_option ... ok
Nov 18 19:37:30.151 INFO [stdout] test option::tests::positive_parse_empty_option ... ok
Nov 18 19:37:30.159 INFO [stdout] test option::tests::positive_parse_end_option ... ok
Nov 18 19:37:30.159 INFO [stdout] test contact::tests::positive_write_peers_v4 ... ok
Nov 18 19:37:30.159 INFO [stdout] test contact::tests::positive_write_peers_v6 ... ok
Nov 18 19:37:30.159 INFO [stdout] test option::tests::positive_parse_url_data_noop_eof ... ok
Nov 18 19:37:30.159 INFO [stdout] test option::tests::negative_parse_url_data_unterminated ... ok
Nov 18 19:37:30.159 INFO [stdout] test option::tests::positive_parse_url_data_noop_end_of_options ... ok
Nov 18 19:37:30.159 INFO [stdout] test option::tests::positive_parse_url_data_single_chunk ... ok
Nov 18 19:37:30.159 INFO [stdout] test option::tests::positive_parse_url_data_end_of_options ... ok
Nov 18 19:37:30.159 INFO [stdout] test option::tests::positive_parse_url_data_two_chunks ... ok
Nov 18 19:37:30.159 INFO [stdout] test option::tests::positive_pasre_url_data_eof ... ok
Nov 18 19:37:30.159 INFO [stdout] test option::tests::positive_parse_url_data_undivisible_chunks ... ok
Nov 18 19:37:30.159 INFO [stdout] test option::tests::positive_write_chunked_option ... ok
Nov 18 19:37:30.159 INFO [stdout] test option::tests::positive_write_eof_option ... ok
Nov 18 19:37:30.159 INFO [stdout] test announce::tests::positive_write_source_ipv6_explicit ... ok
Nov 18 19:37:30.159 INFO [stdout] test contact::tests::positive_write_empty_v4 ... ok
Nov 18 19:37:30.159 INFO [stdout] test scrape::tests::positive_parse_request_empty ... ok
Nov 18 19:37:30.159 INFO [stdout] test option::tests::positive_write_single_option ... ok
Nov 18 19:37:30.159 INFO [stdout] test scrape::tests::positive_parse_response_empty ... ok
Nov 18 19:37:30.166 INFO [stdout] test scrape::tests::positive_parse_request_multiple_hashes ... ok
Nov 18 19:37:30.166 INFO [stdout] test option::tests::positive_write_empty_option ... ok
Nov 18 19:37:30.167 INFO [stdout] test scrape::tests::positive_parse_request_single_hash ... ok
Nov 18 19:37:30.167 INFO [stdout] test scrape::tests::positive_parse_response_many_stats ... ok
Nov 18 19:37:30.167 INFO [stdout] test scrape::tests::positive_write_request_single_hash ... ok
Nov 18 19:37:30.167 INFO [stdout] test scrape::tests::positive_write_response_empty ... ok
Nov 18 19:37:30.167 INFO [stdout] test scrape::tests::positive_write_response_many_stats ... ok
Nov 18 19:37:30.167 INFO [stdout] test scrape::tests::positive_write_response_single_stat ... ok
Nov 18 19:37:30.167 INFO [stdout] test scrape::tests::positive_parse_response_single_stat ... ok
Nov 18 19:37:30.167 INFO [stdout] test scrape::tests::positive_write_request_empty ... ok
Nov 18 19:37:30.167 INFO [stdout] test scrape::tests::positive_write_request_many_hashes ... ok
Nov 18 19:37:30.167 INFO [stdout] 
Nov 18 19:37:30.167 INFO [stdout] test result: ok. 76 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:37:30.167 INFO [stdout] 
Nov 18 19:37:30.171 INFO [stderr]      Running /target/debug/deps/test-2461e69c1fafb4dd
Nov 18 19:37:30.171 INFO [stdout] 
Nov 18 19:37:30.171 INFO [stdout] running 8 tests
Nov 18 19:37:30.191 INFO [stdout] test test_client_full::positive_client_request_dropped ... ok
Nov 18 19:37:30.207 INFO [stdout] test test_client_drop::positive_client_request_failed ... ok
Nov 18 19:37:30.281 INFO [stdout] test test_announce_start::positive_announce_started ... ok
Nov 18 19:37:30.282 INFO [stdout] test test_connect::positive_receive_connect_id ... ok
Nov 18 19:37:30.317 INFO [stdout] test test_scrape::positive_scrape ... ok
Nov 18 19:37:30.319 INFO [stdout] test test_announce_stop::positive_announce_stopped ... ok
Nov 18 19:37:30.319 INFO [stdout] test test_connect_cache::positive_connection_id_cache ... ok
Nov 18 19:37:30.391 INFO [stderr]    Doc-tests bip_utracker
Nov 18 19:37:30.391 INFO [stdout] test test_server_drop::positive_server_dropped ... ok
Nov 18 19:37:30.391 INFO [stdout] 
Nov 18 19:37:30.391 INFO [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:37:30.391 INFO [stdout] 
Nov 18 19:37:40.371 INFO [stdout] 
Nov 18 19:37:40.371 INFO [stdout] running 0 tests
Nov 18 19:37:40.371 INFO [stdout] 
Nov 18 19:37:40.371 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:37:40.371 INFO [stdout] 
Nov 18 19:37:40.379 INFO [stderr] su: No module specific data is present
Nov 18 19:37:41.423 INFO running `"docker" "inspect" "9204e7c27dd7999d3da26a3067de227e551aa187cdce86b4161f726be1f08323"`
Nov 18 19:37:41.765 INFO running `"docker" "rm" "-f" "9204e7c27dd7999d3da26a3067de227e551aa187cdce86b4161f726be1f08323"`
Nov 18 19:37:42.085 INFO [stdout] 9204e7c27dd7999d3da26a3067de227e551aa187cdce86b4161f726be1f08323