Dec 07 22:41:20.094 INFO testing bip_dht-0.6.0 against master#906deae0790bd18681b937fe9a141a3c26cf1855 for pr-56514
Dec 07 22:41:20.094 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 07 22:41:20.412 INFO [stdout] 30257152af25e8f71ff842b0c898136beec824d22f3514f79bc4c4f99103272b
Dec 07 22:41:20.414 INFO running `"docker" "start" "-a" "30257152af25e8f71ff842b0c898136beec824d22f3514f79bc4c4f99103272b"`
Dec 07 22:41:21.260 INFO [stderr] usermod: no changes
Dec 07 22:41:21.451 INFO [stderr]    Compiling slab v0.2.0
Dec 07 22:41:21.493 INFO [stderr]    Compiling bip_bencode v0.2.0
Dec 07 22:41:21.501 INFO [stderr]    Compiling bip_util v0.5.0
Dec 07 22:41:25.162 INFO [stderr]    Compiling bip_handshake v0.4.1
Dec 07 22:41:28.140 INFO [stderr]    Compiling bip_dht v0.6.0 (/source)
Dec 07 22:41:35.493 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.21s
Dec 07 22:41:35.515 INFO [stderr] su: No module specific data is present
Dec 07 22:41:36.262 INFO running `"docker" "inspect" "30257152af25e8f71ff842b0c898136beec824d22f3514f79bc4c4f99103272b"`
Dec 07 22:41:36.660 INFO running `"docker" "rm" "-f" "30257152af25e8f71ff842b0c898136beec824d22f3514f79bc4c4f99103272b"`
Dec 07 22:41:37.012 INFO [stdout] 30257152af25e8f71ff842b0c898136beec824d22f3514f79bc4c4f99103272b
Dec 07 22:41:37.014 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 07 22:41:37.461 INFO [stdout] d233d450b9ae7530faa369b8e954802ed899c09380f8e0614c72930c70a0b159
Dec 07 22:41:37.463 INFO running `"docker" "start" "-a" "d233d450b9ae7530faa369b8e954802ed899c09380f8e0614c72930c70a0b159"`
Dec 07 22:41:38.912 INFO [stderr] usermod: no changes
Dec 07 22:41:39.128 INFO [stderr]    Compiling bip_dht v0.6.0 (/source)
Dec 07 22:41:52.316 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.38s
Dec 07 22:41:52.322 INFO [stderr] su: No module specific data is present
Dec 07 22:41:53.010 INFO running `"docker" "inspect" "d233d450b9ae7530faa369b8e954802ed899c09380f8e0614c72930c70a0b159"`
Dec 07 22:41:53.206 INFO running `"docker" "rm" "-f" "d233d450b9ae7530faa369b8e954802ed899c09380f8e0614c72930c70a0b159"`
Dec 07 22:41:53.531 INFO [stdout] d233d450b9ae7530faa369b8e954802ed899c09380f8e0614c72930c70a0b159
Dec 07 22:41:53.534 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56514/worker-6/master#906deae0790bd18681b937fe9a141a3c26cf1855:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/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 07 22:41:53.980 INFO [stdout] 6ba2532b0db303c2c877e5477a9036d1c9e59b744750c3bdf8d873e5c11138ac
Dec 07 22:41:53.982 INFO running `"docker" "start" "-a" "6ba2532b0db303c2c877e5477a9036d1c9e59b744750c3bdf8d873e5c11138ac"`
Dec 07 22:41:54.570 INFO [stderr] usermod: no changes
Dec 07 22:41:54.668 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.08s
Dec 07 22:41:54.681 INFO [stderr]      Running /target/debug/deps/bip_dht-ee8923f85c4fe7a5
Dec 07 22:41:54.684 INFO [stdout] 
Dec 07 22:41:54.684 INFO [stdout] running 57 tests
Dec 07 22:41:54.693 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_one ... ok
Dec 07 22:41:54.693 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_empty ... ok
Dec 07 22:41:54.693 INFO [stdout] test message::compact_info::tests::positive_compact_values_many ... ok
Dec 07 22:41:54.693 INFO [stdout] test message::compact_info::tests::positive_compact_values_empty ... ok
Dec 07 22:41:54.693 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_many ... ok
Dec 07 22:41:54.693 INFO [stdout] test message::compact_info::tests::positive_compact_values_one ... ok
Dec 07 22:41:54.693 INFO [stdout] test routing::bucket::tests::positive_all_good_nodes ... ok
Dec 07 22:41:54.693 INFO [stdout] test routing::node::tests::positive_as_questionable ... ok
Dec 07 22:41:54.693 INFO [stdout] test routing::node::tests::positive_as_good ... ok
Dec 07 22:41:54.693 INFO [stdout] test routing::bucket::tests::positive_resist_questionable_node_churn ... ok
Dec 07 22:41:54.693 INFO [stdout] test routing::node::tests::positive_as_bad ... ok
Dec 07 22:41:54.693 INFO [stdout] test routing::node::tests::positive_good_status_ordering ... ok
Dec 07 22:41:54.693 INFO [stdout] test routing::node::tests::positive_node_idle ... ok
Dec 07 22:41:54.693 INFO [stdout] test routing::node::tests::positive_node_idle_reqeusts ... ok
Dec 07 22:41:54.693 INFO [stdout] test routing::node::tests::positive_questionable_status_ordering ... ok
Dec 07 22:41:54.693 INFO [stdout] test routing::node::tests::positive_request_renewal ... ok
Dec 07 22:41:54.696 INFO [stdout] test routing::bucket::tests::positive_replace_questionable_node ... ok
Dec 07 22:41:54.696 INFO [stdout] test routing::bucket::tests::positive_resist_good_node_churn ... ok
Dec 07 22:41:54.696 INFO [stdout] test routing::bucket::tests::positive_initial_no_nodes ... ok
Dec 07 22:41:54.696 INFO [stdout] test routing::table::tests::negative_node_id_equal_table_id ... ok
Dec 07 22:41:54.696 INFO [stdout] test routing::bucket::tests::positive_all_questionable_nodes ... ok
Dec 07 22:41:54.708 INFO [stdout] test routing::table::tests::positive_initial_empty_buckets ... ok
Dec 07 22:41:54.708 INFO [stdout] test routing::node::tests::positive_response_renewal ... ok
Dec 07 22:41:54.708 INFO [stdout] test routing::node::tests::positive_bad_status_ordering ... ok
Dec 07 22:41:54.708 INFO [stdout] test routing::node::tests::positive_encode_node ... ok
Dec 07 22:41:54.708 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_one ... ok
Dec 07 22:41:54.708 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_four ... ok
Dec 07 22:41:54.720 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_five ... ok
Dec 07 22:41:54.720 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_four ... ok
Dec 07 22:41:54.737 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_one ... ok
Dec 07 22:41:54.737 INFO [stdout] test routing::table::tests::positive_first_bucket_sorted ... ok
Dec 07 22:41:54.738 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_two ... ok
Dec 07 22:41:54.738 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_five ... ok
Dec 07 22:41:54.738 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_three ... ok
Dec 07 22:41:54.738 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_three ... ok
Dec 07 22:41:54.738 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_two ... ok
Dec 07 22:41:54.738 INFO [stdout] test storage::tests::positive_add_and_retrieve_contact ... ok
Dec 07 22:41:54.746 INFO [stdout] test routing::table::tests::positive_last_bucket_sorted ... ok
Dec 07 22:41:54.760 INFO [stdout] test storage::tests::positive_full_storage_expire_two_infohash ... ok
Dec 07 22:41:54.761 INFO [stdout] test token::tests::positive_accept_v4_token_from_second_secret ... ok
Dec 07 22:41:54.799 INFO [stdout] test storage::tests::positive_renew_contacts ... ok
Dec 07 22:41:54.814 INFO [stdout] test token::tests::positive_accept_valid_v4_token ... ok
Dec 07 22:41:54.820 INFO [stdout] test token::tests::positive_accept_valid_v6_token ... ok
Dec 07 22:41:54.820 INFO [stdout] test transaction::tests::positive_overflow_aid_generate ... ok
Dec 07 22:41:54.820 INFO [stdout] test routing::table::tests::positive_add_node_max_recursion ... ok
Dec 07 22:41:54.820 INFO [stdout] test transaction::tests::positive_overflow_mid_generate ... ok
Dec 07 22:41:54.820 INFO [stdout] test transaction::tests::positive_tid_from_bytes ... ok
Dec 07 22:41:54.820 INFO [stdout] test transaction::tests::positive_overflow_tid_generate ... ok
Dec 07 22:41:54.820 INFO [stdout] test transaction::tests::positive_unique_aid_blocks ... ok
Dec 07 22:41:54.826 INFO [stdout] test transaction::tests::positive_unique_mid_blocks ... ok
Dec 07 22:41:54.826 INFO [stdout] test transaction::tests::positive_unique_tid_blocks ... ok
Dec 07 22:41:54.847 INFO [stdout] test token::tests::positive_accept_v6_token_from_second_secret ... ok
Dec 07 22:41:54.864 INFO [stdout] test storage::tests::positive_add_and_retrieve_contacts ... ok
Dec 07 22:41:54.872 INFO [stdout] test storage::tests::positive_full_storage_expire_one_infohash ... ok
Dec 07 22:41:54.895 INFO [stdout] test routing::table::tests::positive_all_sorted_buckets ... ok
Dec 07 22:41:54.916 INFO [stdout] test token::tests::negative_reject_expired_v4_token ... ok
Dec 07 22:41:54.919 INFO [stdout] test token::tests::negative_reject_expired_v6_token ... ok
Dec 07 22:41:54.919 INFO [stdout] 
Dec 07 22:41:54.919 INFO [stdout] test result: ok. 57 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 22:41:54.919 INFO [stdout] 
Dec 07 22:41:54.924 INFO [stderr]    Doc-tests bip_dht
Dec 07 22:42:00.580 INFO [stdout] 
Dec 07 22:42:00.580 INFO [stdout] running 0 tests
Dec 07 22:42:00.580 INFO [stdout] 
Dec 07 22:42:00.580 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 07 22:42:00.580 INFO [stdout] 
Dec 07 22:42:00.592 INFO [stderr] su: No module specific data is present
Dec 07 22:42:01.374 INFO running `"docker" "inspect" "6ba2532b0db303c2c877e5477a9036d1c9e59b744750c3bdf8d873e5c11138ac"`
Dec 07 22:42:01.557 INFO running `"docker" "rm" "-f" "6ba2532b0db303c2c877e5477a9036d1c9e59b744750c3bdf8d873e5c11138ac"`
Dec 07 22:42:01.745 INFO [stdout] 6ba2532b0db303c2c877e5477a9036d1c9e59b744750c3bdf8d873e5c11138ac