Dec 15 21:12:43.864 INFO testing bip_dht-0.6.0 against master#bd47d6825bf4090517549d33cfef10d3300b4a75 for pr-56550
Dec 15 21:12:43.864 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 21:12:44.105 INFO [stdout] 8b91d846468bb134c85b7c5bea61becaaa511c1d4ae9b562add8a8ec47fcb75d
Dec 15 21:12:44.110 INFO running `"docker" "start" "-a" "8b91d846468bb134c85b7c5bea61becaaa511c1d4ae9b562add8a8ec47fcb75d"`
Dec 15 21:12:45.056 INFO [stderr] usermod: no changes
Dec 15 21:12:45.239 INFO [stderr]    Compiling slab v0.2.0
Dec 15 21:12:45.248 INFO [stderr]    Compiling bip_bencode v0.2.0
Dec 15 21:12:46.216 INFO [stderr]    Compiling bip_handshake v0.4.1
Dec 15 21:12:49.734 INFO [stderr]    Compiling bip_dht v0.6.0 (/source)
Dec 15 21:12:56.368 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.28s
Dec 15 21:12:56.370 INFO [stderr] su: No module specific data is present
Dec 15 21:12:57.081 INFO running `"docker" "inspect" "8b91d846468bb134c85b7c5bea61becaaa511c1d4ae9b562add8a8ec47fcb75d"`
Dec 15 21:12:57.327 INFO running `"docker" "rm" "-f" "8b91d846468bb134c85b7c5bea61becaaa511c1d4ae9b562add8a8ec47fcb75d"`
Dec 15 21:12:57.603 INFO [stdout] 8b91d846468bb134c85b7c5bea61becaaa511c1d4ae9b562add8a8ec47fcb75d
Dec 15 21:12:57.608 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 21:12:58.180 INFO [stdout] 1ac937c241238892685e286cfcc1c3859a577cd70c33080f58e3bd237dd8450f
Dec 15 21:12:58.182 INFO running `"docker" "start" "-a" "1ac937c241238892685e286cfcc1c3859a577cd70c33080f58e3bd237dd8450f"`
Dec 15 21:12:59.503 INFO [stderr] usermod: no changes
Dec 15 21:12:59.643 INFO [stderr]    Compiling bip_dht v0.6.0 (/source)
Dec 15 21:13:13.499 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 13.97s
Dec 15 21:13:13.518 INFO [stderr] su: No module specific data is present
Dec 15 21:13:14.140 INFO running `"docker" "inspect" "1ac937c241238892685e286cfcc1c3859a577cd70c33080f58e3bd237dd8450f"`
Dec 15 21:13:14.376 INFO running `"docker" "rm" "-f" "1ac937c241238892685e286cfcc1c3859a577cd70c33080f58e3bd237dd8450f"`
Dec 15 21:13:14.684 INFO [stdout] 1ac937c241238892685e286cfcc1c3859a577cd70c33080f58e3bd237dd8450f
Dec 15 21:13:14.689 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-7/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-7/pr-56550/master#bd47d6825bf4090517549d33cfef10d3300b4a75:/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 +bd47d6825bf4090517549d33cfef10d3300b4a75-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 15 21:13:15.164 INFO [stdout] 647b156ce3ab7654f814bcf0d07364d03b4a5e8fcff13c60f69b82c273865bfb
Dec 15 21:13:15.166 INFO running `"docker" "start" "-a" "647b156ce3ab7654f814bcf0d07364d03b4a5e8fcff13c60f69b82c273865bfb"`
Dec 15 21:13:16.606 INFO [stderr] usermod: no changes
Dec 15 21:13:16.765 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.13s
Dec 15 21:13:16.769 INFO [stderr]      Running /target/debug/deps/bip_dht-bc5abb9354c3f2c6
Dec 15 21:13:16.778 INFO [stdout] 
Dec 15 21:13:16.778 INFO [stdout] running 57 tests
Dec 15 21:13:16.780 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_many ... ok
Dec 15 21:13:16.780 INFO [stdout] test message::compact_info::tests::positive_compact_values_many ... ok
Dec 15 21:13:16.780 INFO [stdout] test message::compact_info::tests::positive_compact_values_one ... ok
Dec 15 21:13:16.780 INFO [stdout] test message::compact_info::tests::positive_compact_values_empty ... ok
Dec 15 21:13:16.780 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_one ... ok
Dec 15 21:13:16.780 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_empty ... ok
Dec 15 21:13:16.781 INFO [stdout] test routing::bucket::tests::positive_all_good_nodes ... ok
Dec 15 21:13:16.782 INFO [stdout] test routing::node::tests::positive_as_bad ... ok
Dec 15 21:13:16.782 INFO [stdout] test routing::bucket::tests::positive_initial_no_nodes ... ok
Dec 15 21:13:16.782 INFO [stdout] test routing::bucket::tests::positive_resist_questionable_node_churn ... ok
Dec 15 21:13:16.782 INFO [stdout] test routing::bucket::tests::positive_resist_good_node_churn ... ok
Dec 15 21:13:16.782 INFO [stdout] test routing::bucket::tests::positive_replace_questionable_node ... ok
Dec 15 21:13:16.782 INFO [stdout] test routing::node::tests::positive_bad_status_ordering ... ok
Dec 15 21:13:16.782 INFO [stdout] test routing::node::tests::positive_encode_node ... ok
Dec 15 21:13:16.782 INFO [stdout] test routing::node::tests::positive_good_status_ordering ... ok
Dec 15 21:13:16.783 INFO [stdout] test routing::node::tests::positive_node_idle_reqeusts ... ok
Dec 15 21:13:16.784 INFO [stdout] test routing::node::tests::positive_questionable_status_ordering ... ok
Dec 15 21:13:16.784 INFO [stdout] test routing::node::tests::positive_as_good ... ok
Dec 15 21:13:16.784 INFO [stdout] test routing::node::tests::positive_as_questionable ... ok
Dec 15 21:13:16.784 INFO [stdout] test routing::node::tests::positive_request_renewal ... ok
Dec 15 21:13:16.784 INFO [stdout] test routing::node::tests::positive_response_renewal ... ok
Dec 15 21:13:16.784 INFO [stdout] test routing::node::tests::positive_node_idle ... ok
Dec 15 21:13:16.785 INFO [stdout] test routing::bucket::tests::positive_all_questionable_nodes ... ok
Dec 15 21:13:16.787 INFO [stdout] test routing::table::tests::positive_initial_empty_buckets ... ok
Dec 15 21:13:16.789 INFO [stdout] test routing::table::tests::negative_node_id_equal_table_id ... ok
Dec 15 21:13:16.795 INFO [stdout] test routing::table::tests::positive_first_bucket_sorted ... ok
Dec 15 21:13:16.805 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_two ... ok
Dec 15 21:13:16.811 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_four ... ok
Dec 15 21:13:16.819 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_four ... ok
Dec 15 21:13:16.819 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_five ... ok
Dec 15 21:13:16.831 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_one ... ok
Dec 15 21:13:16.831 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_three ... ok
Dec 15 21:13:16.836 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_five ... ok
Dec 15 21:13:16.851 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_two ... ok
Dec 15 21:13:16.852 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_three ... ok
Dec 15 21:13:16.852 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_one ... ok
Dec 15 21:13:16.867 INFO [stdout] test storage::tests::positive_add_and_retrieve_contact ... ok
Dec 15 21:13:16.967 INFO [stdout] test storage::tests::positive_add_and_retrieve_contacts ... ok
Dec 15 21:13:16.986 INFO [stdout] test storage::tests::positive_full_storage_expire_two_infohash ... ok
Dec 15 21:13:17.016 INFO [stdout] test routing::table::tests::positive_add_node_max_recursion ... ok
Dec 15 21:13:17.035 INFO [stdout] test token::tests::positive_accept_v4_token_from_second_secret ... ok
Dec 15 21:13:17.035 INFO [stdout] test token::tests::positive_accept_v6_token_from_second_secret ... ok
Dec 15 21:13:17.039 INFO [stdout] test routing::table::tests::positive_last_bucket_sorted ... ok
Dec 15 21:13:17.045 INFO [stdout] test storage::tests::positive_full_storage_expire_one_infohash ... ok
Dec 15 21:13:17.051 INFO [stdout] test routing::table::tests::positive_all_sorted_buckets ... ok
Dec 15 21:13:17.051 INFO [stdout] test token::tests::positive_accept_valid_v6_token ... ok
Dec 15 21:13:17.062 INFO [stdout] test storage::tests::positive_renew_contacts ... ok
Dec 15 21:13:17.067 INFO [stdout] test transaction::tests::positive_overflow_aid_generate ... ok
Dec 15 21:13:17.074 INFO [stdout] test transaction::tests::positive_tid_from_bytes ... ok
Dec 15 21:13:17.074 INFO [stdout] test transaction::tests::positive_overflow_tid_generate ... ok
Dec 15 21:13:17.075 INFO [stdout] test transaction::tests::positive_overflow_mid_generate ... ok
Dec 15 21:13:17.079 INFO [stdout] test token::tests::positive_accept_valid_v4_token ... ok
Dec 15 21:13:17.081 INFO [stdout] test transaction::tests::positive_unique_mid_blocks ... ok
Dec 15 21:13:17.095 INFO [stdout] test transaction::tests::positive_unique_tid_blocks ... ok
Dec 15 21:13:17.103 INFO [stdout] test transaction::tests::positive_unique_aid_blocks ... ok
Dec 15 21:13:17.109 INFO [stdout] test token::tests::negative_reject_expired_v4_token ... ok
Dec 15 21:13:17.109 INFO [stdout] test token::tests::negative_reject_expired_v6_token ... ok
Dec 15 21:13:17.109 INFO [stdout] 
Dec 15 21:13:17.109 INFO [stdout] test result: ok. 57 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 21:13:17.109 INFO [stdout] 
Dec 15 21:13:17.110 INFO [stderr]    Doc-tests bip_dht
Dec 15 21:13:22.926 INFO [stdout] 
Dec 15 21:13:22.926 INFO [stdout] running 0 tests
Dec 15 21:13:22.926 INFO [stdout] 
Dec 15 21:13:22.926 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 21:13:22.926 INFO [stdout] 
Dec 15 21:13:22.940 INFO [stderr] su: No module specific data is present
Dec 15 21:13:23.254 INFO running `"docker" "inspect" "647b156ce3ab7654f814bcf0d07364d03b4a5e8fcff13c60f69b82c273865bfb"`
Dec 15 21:13:23.312 INFO running `"docker" "rm" "-f" "647b156ce3ab7654f814bcf0d07364d03b4a5e8fcff13c60f69b82c273865bfb"`
Dec 15 21:13:23.398 INFO [stdout] 647b156ce3ab7654f814bcf0d07364d03b4a5e8fcff13c60f69b82c273865bfb