Nov 18 19:38:22.957 INFO testing bip_handshake-0.7.1 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 19:38:22.957 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 18 19:38:23.161 INFO [stdout] dfd800fb138487b3f462db55270e4fe1e43cac2292296d3ba611ac62254c8bde
Nov 18 19:38:23.162 INFO running `"docker" "start" "-a" "dfd800fb138487b3f462db55270e4fe1e43cac2292296d3ba611ac62254c8bde"`
Nov 18 19:38:23.533 INFO [stderr] usermod: no changes
Nov 18 19:38:23.687 INFO [stderr]    Compiling chrono v0.2.25
Nov 18 19:38:26.254 INFO [stderr]    Compiling bip_util v0.5.0
Nov 18 19:38:27.732 INFO [stderr]    Compiling bip_handshake v0.7.1 (/source)
Nov 18 19:38:33.680 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.13s
Nov 18 19:38:33.690 INFO [stderr] su: No module specific data is present
Nov 18 19:38:34.133 INFO running `"docker" "inspect" "dfd800fb138487b3f462db55270e4fe1e43cac2292296d3ba611ac62254c8bde"`
Nov 18 19:38:34.407 INFO running `"docker" "rm" "-f" "dfd800fb138487b3f462db55270e4fe1e43cac2292296d3ba611ac62254c8bde"`
Nov 18 19:38:34.611 INFO [stdout] dfd800fb138487b3f462db55270e4fe1e43cac2292296d3ba611ac62254c8bde
Nov 18 19:38:34.615 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 18 19:38:34.992 INFO [stdout] 11b51ef2fe1e310fbe7d605c65055f336956f2744be5985ae4d9986dd31e33f9
Nov 18 19:38:34.994 INFO running `"docker" "start" "-a" "11b51ef2fe1e310fbe7d605c65055f336956f2744be5985ae4d9986dd31e33f9"`
Nov 18 19:38:35.975 INFO [stderr] usermod: no changes
Nov 18 19:38:36.199 INFO [stderr]    Compiling bip_handshake v0.7.1 (/source)
Nov 18 19:38:59.271 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 23.24s
Nov 18 19:38:59.279 INFO [stderr] su: No module specific data is present
Nov 18 19:38:59.671 INFO running `"docker" "inspect" "11b51ef2fe1e310fbe7d605c65055f336956f2744be5985ae4d9986dd31e33f9"`
Nov 18 19:38:59.947 INFO running `"docker" "rm" "-f" "11b51ef2fe1e310fbe7d605c65055f336956f2744be5985ae4d9986dd31e33f9"`
Nov 18 19:39:00.327 INFO [stdout] 11b51ef2fe1e310fbe7d605c65055f336956f2744be5985ae4d9986dd31e33f9
Nov 18 19:39:00.332 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-6/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 18 19:39:00.874 INFO [stdout] e2300c888fe26afed8481c624b5a6d9de78336e71421465c6a78494ab2844d3d
Nov 18 19:39:00.878 INFO running `"docker" "start" "-a" "e2300c888fe26afed8481c624b5a6d9de78336e71421465c6a78494ab2844d3d"`
Nov 18 19:39:02.083 INFO [stderr] usermod: no changes
Nov 18 19:39:02.272 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.16s
Nov 18 19:39:02.290 INFO [stderr]      Running /target/debug/deps/bip_handshake-2f8e954b8eb46e02
Nov 18 19:39:02.297 INFO [stdout] 
Nov 18 19:39:02.297 INFO [stdout] running 34 tests
Nov 18 19:39:02.299 INFO [stdout] test bittorrent::framed::tests::positive_read_byte_after_handshake ... ok
Nov 18 19:39:02.299 INFO [stdout] test bittorrent::message::tests::positive_many_bytes_protocol ... ok
Nov 18 19:39:02.299 INFO [stdout] test bittorrent::framed::tests::positive_read_bytes_after_handshake ... ok
Nov 18 19:39:02.299 INFO [stdout] test bittorrent::message::tests::positive_decode_zero_bytes_protocol ... ok
Nov 18 19:39:02.299 INFO [stdout] test bittorrent::message::tests::positive_bittorrent_protocol ... ok
Nov 18 19:39:02.299 INFO [stdout] test bittorrent::framed::tests::positive_write_handshake_message ... ok
Nov 18 19:39:02.299 INFO [stdout] test filter::filters::tests::positive_add_filter ... ok
Nov 18 19:39:02.299 INFO [stdout] test bittorrent::framed::tests::positive_read_handshake_message ... ok
Nov 18 19:39:02.299 INFO [stdout] test bittorrent::framed::tests::positive_write_multiple_handshake_messages ... ok
Nov 18 19:39:02.299 INFO [stdout] test filter::filters::tests::positive_add_filter_already_present ... ok
Nov 18 19:39:02.303 INFO [stdout] test filter::filters::tests::positive_clear_filters_none_present ... ok
Nov 18 19:39:02.303 INFO [stdout] test filter::filters::tests::positive_clear_filters_multiple_present ... ok
Nov 18 19:39:02.307 INFO [stdout] test filter::tests::positive_decision_keep_higher ... ok
Nov 18 19:39:02.307 INFO [stdout] test filter::tests::positive_decision_choose_self ... ok
Nov 18 19:39:02.332 INFO [stdout] test filter::tests::positive_decision_choose_higher ... ok
Nov 18 19:39:02.332 INFO [stdout] test filter::filters::tests::positive_remove_filter_not_present ... ok
Nov 18 19:39:02.333 INFO [stdout] test filter::filters::tests::positive_remove_filter ... ok
Nov 18 19:39:02.333 INFO [stdout] test filter::filters::tests::positive_clear_filters_one_present ... ok
Nov 18 19:39:02.333 INFO [stdout] test filter::filters::tests::positive_remove_filter_multiple_present ... ok
Nov 18 19:39:02.338 INFO [stdout] test handshake::handler::handshaker::tests::positive_complete_handshake ... ok
Nov 18 19:39:02.339 INFO [stdout] test handshake::handler::listener::tests::positive_fails_filter ... ok
Nov 18 19:39:02.339 INFO [stdout] test handshake::handler::listener::tests::positive_needs_data_filter ... ok
Nov 18 19:39:02.339 INFO [stdout] test handshake::handler::listener::tests::positive_passes_filter ... ok
Nov 18 19:39:02.342 INFO [stdout] test handshake::handler::listener::tests::positive_empty_filter ... ok
Nov 18 19:39:02.343 INFO [stdout] test handshake::handler::handshaker::tests::positive_initiate_handshake ... ok
Nov 18 19:39:02.343 INFO [stdout] test message::extensions::tests::positive_add_extension_protocol ... ok
Nov 18 19:39:02.347 INFO [stdout] test handshake::handler::initiator::tests::positive_fails_filter ... ok
Nov 18 19:39:02.347 INFO [stdout] test handshake::handler::initiator::tests::positive_passes_filter ... ok
Nov 18 19:39:02.348 INFO [stdout] test handshake::handler::initiator::tests::positive_empty_filter ... ok
Nov 18 19:39:02.348 INFO [stdout] test handshake::handler::initiator::tests::positive_needs_data_filter ... ok
Nov 18 19:39:02.348 INFO [stdout] test handshake::handler::timer::tests::positive_finish_before_timeout ... ok
Nov 18 19:39:02.355 INFO [stdout] test message::extensions::tests::positive_remove_extension_protocol ... ok
Nov 18 19:39:02.440 INFO [stdout] test handshake::handler::timer::tests::negative_finish_after_timeout ... ok
Nov 18 19:39:02.440 INFO [stderr]      Running /target/debug/deps/test-8751d7a9ea35d978
Nov 18 19:39:02.441 INFO [stdout] test bittorrent::message::tests::negative_create_overflow_protocol ... ok
Nov 18 19:39:02.441 INFO [stdout] 
Nov 18 19:39:02.441 INFO [stdout] test result: ok. 34 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:39:02.441 INFO [stdout] 
Nov 18 19:39:02.447 INFO [stdout] 
Nov 18 19:39:02.447 INFO [stdout] running 7 tests
Nov 18 19:39:02.471 INFO [stdout] test test_filter_allow_all::test_filter_all ... ok
Nov 18 19:39:02.483 INFO [stdout] test test_filter_whitelist_same_data::test_filter_whitelist_same_data ... ok
Nov 18 19:39:02.485 INFO [stdout] test test_connect::positive_connect ... ok
Nov 18 19:39:02.487 INFO [stdout] test test_bytes_after_handshake::positive_recover_bytes ... ok
Nov 18 19:39:02.498 INFO [stdout] test test_byte_after_handshake::positive_recover_bytes ... ok
Nov 18 19:39:02.516 INFO [stdout] test test_filter_block_all::test_filter_all ... ok
Nov 18 19:39:02.547 INFO [stdout] test test_filter_whitelist_diff_data::test_filter_whitelist_diff_data ... ok
Nov 18 19:39:02.548 INFO [stderr]    Doc-tests bip_handshake
Nov 18 19:39:02.548 INFO [stdout] 
Nov 18 19:39:02.548 INFO [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:39:02.548 INFO [stdout] 
Nov 18 19:39:09.993 INFO [stdout] 
Nov 18 19:39:09.994 INFO [stdout] running 0 tests
Nov 18 19:39:09.994 INFO [stdout] 
Nov 18 19:39:09.994 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:39:09.995 INFO [stdout] 
Nov 18 19:39:10.002 INFO [stderr] su: No module specific data is present
Nov 18 19:39:10.471 INFO running `"docker" "inspect" "e2300c888fe26afed8481c624b5a6d9de78336e71421465c6a78494ab2844d3d"`
Nov 18 19:39:10.715 INFO running `"docker" "rm" "-f" "e2300c888fe26afed8481c624b5a6d9de78336e71421465c6a78494ab2844d3d"`
Nov 18 19:39:10.998 INFO [stdout] e2300c888fe26afed8481c624b5a6d9de78336e71421465c6a78494ab2844d3d