Nov 18 19:38:28.185 INFO testing bip_dht-0.6.0 against master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c for pr-55982
Nov 18 19:38:28.187 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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:38:28.547 INFO [stdout] dca2f54bbce15af770db86ab22d5c705d9ff6594c57b3b2015ad8b6c1f4cc0e0
Nov 18 19:38:28.552 INFO running `"docker" "start" "-a" "dca2f54bbce15af770db86ab22d5c705d9ff6594c57b3b2015ad8b6c1f4cc0e0"`
Nov 18 19:38:29.110 INFO [stderr] usermod: no changes
Nov 18 19:38:29.239 INFO [stderr]    Compiling slab v0.2.0
Nov 18 19:38:29.252 INFO [stderr]    Compiling bip_bencode v0.2.0
Nov 18 19:38:29.263 INFO [stderr]    Compiling bip_util v0.5.0
Nov 18 19:38:31.681 INFO [stderr]    Compiling bip_handshake v0.4.1
Nov 18 19:38:34.753 INFO [stderr]    Compiling bip_dht v0.6.0 (/source)
Nov 18 19:38:41.559 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.43s
Nov 18 19:38:41.567 INFO [stderr] su: No module specific data is present
Nov 18 19:38:42.747 INFO running `"docker" "inspect" "dca2f54bbce15af770db86ab22d5c705d9ff6594c57b3b2015ad8b6c1f4cc0e0"`
Nov 18 19:38:43.021 INFO running `"docker" "rm" "-f" "dca2f54bbce15af770db86ab22d5c705d9ff6594c57b3b2015ad8b6c1f4cc0e0"`
Nov 18 19:38:43.370 INFO [stdout] dca2f54bbce15af770db86ab22d5c705d9ff6594c57b3b2015ad8b6c1f4cc0e0
Nov 18 19:38:43.375 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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:38:43.759 INFO [stdout] bb67396154447ff2a07f15ac1f2f94548320589866d87c30f753fe8138771b78
Nov 18 19:38:43.761 INFO running `"docker" "start" "-a" "bb67396154447ff2a07f15ac1f2f94548320589866d87c30f753fe8138771b78"`
Nov 18 19:38:46.235 INFO [stderr] usermod: no changes
Nov 18 19:38:46.422 INFO [stderr]    Compiling bip_dht v0.6.0 (/source)
Nov 18 19:38:59.290 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.03s
Nov 18 19:38:59.298 INFO [stderr] su: No module specific data is present
Nov 18 19:38:59.703 INFO running `"docker" "inspect" "bb67396154447ff2a07f15ac1f2f94548320589866d87c30f753fe8138771b78"`
Nov 18 19:39:00.067 INFO running `"docker" "rm" "-f" "bb67396154447ff2a07f15ac1f2f94548320589866d87c30f753fe8138771b78"`
Nov 18 19:39:00.371 INFO [stdout] bb67396154447ff2a07f15ac1f2f94548320589866d87c30f753fe8138771b78
Nov 18 19:39:00.376 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-1/master#6b9b97bd9b704f85f0184f7a213cc4d62bd9654c:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-1/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:39:00.966 INFO [stdout] 326378ff8bb17bfe9b5b15c3e5099265afea7cb016853e7b7fdb8e86f6364c4d
Nov 18 19:39:00.969 INFO running `"docker" "start" "-a" "326378ff8bb17bfe9b5b15c3e5099265afea7cb016853e7b7fdb8e86f6364c4d"`
Nov 18 19:39:02.071 INFO [stderr] usermod: no changes
Nov 18 19:39:02.209 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.11s
Nov 18 19:39:02.225 INFO [stderr]      Running /target/debug/deps/bip_dht-5340060a8640e094
Nov 18 19:39:02.229 INFO [stdout] 
Nov 18 19:39:02.229 INFO [stdout] running 57 tests
Nov 18 19:39:02.229 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_empty ... ok
Nov 18 19:39:02.229 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_one ... ok
Nov 18 19:39:02.229 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_many ... ok
Nov 18 19:39:02.229 INFO [stdout] test message::compact_info::tests::positive_compact_values_empty ... ok
Nov 18 19:39:02.229 INFO [stdout] test message::compact_info::tests::positive_compact_values_many ... ok
Nov 18 19:39:02.229 INFO [stdout] test routing::bucket::tests::positive_all_good_nodes ... ok
Nov 18 19:39:02.229 INFO [stdout] test routing::bucket::tests::positive_initial_no_nodes ... ok
Nov 18 19:39:02.229 INFO [stdout] test routing::bucket::tests::positive_replace_questionable_node ... ok
Nov 18 19:39:02.229 INFO [stdout] test routing::node::tests::positive_as_bad ... ok
Nov 18 19:39:02.230 INFO [stdout] test routing::bucket::tests::positive_resist_good_node_churn ... ok
Nov 18 19:39:02.230 INFO [stdout] test routing::node::tests::positive_as_good ... ok
Nov 18 19:39:02.230 INFO [stdout] test routing::node::tests::positive_good_status_ordering ... ok
Nov 18 19:39:02.235 INFO [stdout] test routing::node::tests::positive_bad_status_ordering ... ok
Nov 18 19:39:02.235 INFO [stdout] test routing::node::tests::positive_encode_node ... ok
Nov 18 19:39:02.235 INFO [stdout] test routing::node::tests::positive_node_idle ... ok
Nov 18 19:39:02.239 INFO [stdout] test routing::bucket::tests::positive_all_questionable_nodes ... ok
Nov 18 19:39:02.239 INFO [stdout] test message::compact_info::tests::positive_compact_values_one ... ok
Nov 18 19:39:02.239 INFO [stdout] test routing::bucket::tests::positive_resist_questionable_node_churn ... ok
Nov 18 19:39:02.239 INFO [stdout] test routing::node::tests::positive_as_questionable ... ok
Nov 18 19:39:02.247 INFO [stdout] test routing::node::tests::positive_questionable_status_ordering ... ok
Nov 18 19:39:02.247 INFO [stdout] test routing::node::tests::positive_request_renewal ... ok
Nov 18 19:39:02.251 INFO [stdout] test routing::node::tests::positive_node_idle_reqeusts ... ok
Nov 18 19:39:02.251 INFO [stdout] test routing::table::tests::positive_first_bucket_sorted ... ok
Nov 18 19:39:02.251 INFO [stdout] test routing::node::tests::positive_response_renewal ... ok
Nov 18 19:39:02.255 INFO [stdout] test routing::table::tests::negative_node_id_equal_table_id ... ok
Nov 18 19:39:02.255 INFO [stdout] test routing::table::tests::positive_initial_empty_buckets ... ok
Nov 18 19:39:02.255 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_four ... ok
Nov 18 19:39:02.259 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_five ... ok
Nov 18 19:39:02.259 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_four ... ok
Nov 18 19:39:02.259 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_one ... ok
Nov 18 19:39:02.259 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_three ... ok
Nov 18 19:39:02.259 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_two ... ok
Nov 18 19:39:02.265 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_two ... ok
Nov 18 19:39:02.291 INFO [stdout] test storage::tests::positive_add_and_retrieve_contact ... ok
Nov 18 19:39:02.295 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_three ... ok
Nov 18 19:39:02.296 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_five ... ok
Nov 18 19:39:02.297 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_one ... ok
Nov 18 19:39:02.362 INFO [stdout] test routing::table::tests::positive_add_node_max_recursion ... ok
Nov 18 19:39:02.371 INFO [stdout] test storage::tests::positive_full_storage_expire_two_infohash ... ok
Nov 18 19:39:02.371 INFO [stdout] test storage::tests::positive_add_and_retrieve_contacts ... ok
Nov 18 19:39:02.375 INFO [stdout] test token::tests::positive_accept_v6_token_from_second_secret ... ok
Nov 18 19:39:02.375 INFO [stdout] test token::tests::positive_accept_v4_token_from_second_secret ... ok
Nov 18 19:39:02.379 INFO [stdout] test token::tests::positive_accept_valid_v6_token ... ok
Nov 18 19:39:02.379 INFO [stdout] test token::tests::positive_accept_valid_v4_token ... ok
Nov 18 19:39:02.379 INFO [stdout] test transaction::tests::positive_overflow_mid_generate ... ok
Nov 18 19:39:02.379 INFO [stdout] test transaction::tests::positive_overflow_aid_generate ... ok
Nov 18 19:39:02.383 INFO [stdout] test routing::table::tests::positive_last_bucket_sorted ... ok
Nov 18 19:39:02.401 INFO [stdout] test storage::tests::positive_full_storage_expire_one_infohash ... ok
Nov 18 19:39:02.419 INFO [stdout] test transaction::tests::positive_unique_aid_blocks ... ok
Nov 18 19:39:02.427 INFO [stdout] test routing::table::tests::positive_all_sorted_buckets ... ok
Nov 18 19:39:02.427 INFO [stdout] test transaction::tests::positive_overflow_tid_generate ... ok
Nov 18 19:39:02.447 INFO [stdout] test storage::tests::positive_renew_contacts ... ok
Nov 18 19:39:02.447 INFO [stdout] test transaction::tests::positive_unique_mid_blocks ... ok
Nov 18 19:39:02.447 INFO [stdout] test transaction::tests::positive_tid_from_bytes ... ok
Nov 18 19:39:02.447 INFO [stdout] test transaction::tests::positive_unique_tid_blocks ... ok
Nov 18 19:39:02.466 INFO [stdout] test token::tests::negative_reject_expired_v6_token ... ok
Nov 18 19:39:02.467 INFO [stdout] test token::tests::negative_reject_expired_v4_token ... ok
Nov 18 19:39:02.468 INFO [stdout] 
Nov 18 19:39:02.468 INFO [stdout] test result: ok. 57 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:39:02.468 INFO [stdout] 
Nov 18 19:39:02.469 INFO [stderr]    Doc-tests bip_dht
Nov 18 19:39:06.751 INFO [stdout] 
Nov 18 19:39:06.751 INFO [stdout] running 0 tests
Nov 18 19:39:06.751 INFO [stdout] 
Nov 18 19:39:06.751 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:39:06.751 INFO [stdout] 
Nov 18 19:39:06.763 INFO [stderr] su: No module specific data is present
Nov 18 19:39:07.270 INFO running `"docker" "inspect" "326378ff8bb17bfe9b5b15c3e5099265afea7cb016853e7b7fdb8e86f6364c4d"`
Nov 18 19:39:07.549 INFO running `"docker" "rm" "-f" "326378ff8bb17bfe9b5b15c3e5099265afea7cb016853e7b7fdb8e86f6364c4d"`
Nov 18 19:39:07.835 INFO [stdout] 326378ff8bb17bfe9b5b15c3e5099265afea7cb016853e7b7fdb8e86f6364c4d