Dec 15 21:12:42.827 INFO testing bip_dht-0.6.0 against try#aa49d8ef14939ddec0e34b346b60174a5673d48f for pr-56550
Dec 15 21:12:42.827 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:43.047 INFO [stdout] 4e7f799f04354379091ad47176edca8731396c0c0cc5e9fbed57e1b93f1764fd
Dec 15 21:12:43.049 INFO running `"docker" "start" "-a" "4e7f799f04354379091ad47176edca8731396c0c0cc5e9fbed57e1b93f1764fd"`
Dec 15 21:12:43.571 INFO [stderr] usermod: no changes
Dec 15 21:12:43.693 INFO [stderr]    Compiling slab v0.2.0
Dec 15 21:12:43.699 INFO [stderr]    Compiling bip_bencode v0.2.0
Dec 15 21:12:44.373 INFO [stderr]    Compiling bip_handshake v0.4.1
Dec 15 21:12:47.539 INFO [stderr]    Compiling bip_dht v0.6.0 (/source)
Dec 15 21:12:54.276 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 10.67s
Dec 15 21:12:54.282 INFO [stderr] su: No module specific data is present
Dec 15 21:12:55.086 INFO running `"docker" "inspect" "4e7f799f04354379091ad47176edca8731396c0c0cc5e9fbed57e1b93f1764fd"`
Dec 15 21:12:55.291 INFO running `"docker" "rm" "-f" "4e7f799f04354379091ad47176edca8731396c0c0cc5e9fbed57e1b93f1764fd"`
Dec 15 21:12:55.619 INFO [stdout] 4e7f799f04354379091ad47176edca8731396c0c0cc5e9fbed57e1b93f1764fd
Dec 15 21:12:55.627 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:56.023 INFO [stdout] c6a77d08ee0ecd45d4ba543d9c39da93b22f444d86b46262b44dc42cdd2922ad
Dec 15 21:12:56.031 INFO running `"docker" "start" "-a" "c6a77d08ee0ecd45d4ba543d9c39da93b22f444d86b46262b44dc42cdd2922ad"`
Dec 15 21:12:56.846 INFO [stderr] usermod: no changes
Dec 15 21:12:57.047 INFO [stderr]    Compiling bip_dht v0.6.0 (/source)
Dec 15 21:13:11.339 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 14.46s
Dec 15 21:13:11.347 INFO [stderr] su: No module specific data is present
Dec 15 21:13:12.190 INFO running `"docker" "inspect" "c6a77d08ee0ecd45d4ba543d9c39da93b22f444d86b46262b44dc42cdd2922ad"`
Dec 15 21:13:12.465 INFO running `"docker" "rm" "-f" "c6a77d08ee0ecd45d4ba543d9c39da93b22f444d86b46262b44dc42cdd2922ad"`
Dec 15 21:13:12.651 INFO [stdout] c6a77d08ee0ecd45d4ba543d9c39da93b22f444d86b46262b44dc42cdd2922ad
Dec 15 21:13:12.655 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-56550/worker-6/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-6/pr-56550/try#aa49d8ef14939ddec0e34b346b60174a5673d48f:/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 +aa49d8ef14939ddec0e34b346b60174a5673d48f-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:13.036 INFO [stdout] 0c45dfb21cb5e5947f8152e551e64bdb7562d0eedf20a49eaee6599116714af9
Dec 15 21:13:13.044 INFO running `"docker" "start" "-a" "0c45dfb21cb5e5947f8152e551e64bdb7562d0eedf20a49eaee6599116714af9"`
Dec 15 21:13:13.951 INFO [stderr] usermod: no changes
Dec 15 21:13:14.126 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.14s
Dec 15 21:13:14.147 INFO [stderr]      Running /target/debug/deps/bip_dht-263f42a506f710b6
Dec 15 21:13:14.147 INFO [stdout] 
Dec 15 21:13:14.147 INFO [stdout] running 57 tests
Dec 15 21:13:14.175 INFO [stdout] test routing::bucket::tests::positive_all_good_nodes ... ok
Dec 15 21:13:14.175 INFO [stdout] test message::compact_info::tests::positive_compact_values_empty ... ok
Dec 15 21:13:14.175 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_empty ... ok
Dec 15 21:13:14.175 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_one ... ok
Dec 15 21:13:14.175 INFO [stdout] test message::compact_info::tests::positive_compact_values_many ... ok
Dec 15 21:13:14.175 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_many ... ok
Dec 15 21:13:14.175 INFO [stdout] test message::compact_info::tests::positive_compact_values_one ... ok
Dec 15 21:13:14.175 INFO [stdout] test routing::bucket::tests::positive_all_questionable_nodes ... ok
Dec 15 21:13:14.175 INFO [stdout] test routing::node::tests::positive_as_bad ... ok
Dec 15 21:13:14.175 INFO [stdout] test routing::node::tests::positive_bad_status_ordering ... ok
Dec 15 21:13:14.175 INFO [stdout] test routing::node::tests::positive_as_good ... ok
Dec 15 21:13:14.175 INFO [stdout] test routing::node::tests::positive_good_status_ordering ... ok
Dec 15 21:13:14.175 INFO [stdout] test routing::node::tests::positive_node_idle ... ok
Dec 15 21:13:14.175 INFO [stdout] test routing::node::tests::positive_node_idle_reqeusts ... ok
Dec 15 21:13:14.175 INFO [stdout] test routing::node::tests::positive_questionable_status_ordering ... ok
Dec 15 21:13:14.175 INFO [stdout] test routing::node::tests::positive_request_renewal ... ok
Dec 15 21:13:14.175 INFO [stdout] test routing::node::tests::positive_response_renewal ... ok
Dec 15 21:13:14.175 INFO [stdout] test routing::table::tests::negative_node_id_equal_table_id ... ok
Dec 15 21:13:14.175 INFO [stdout] test routing::bucket::tests::positive_initial_no_nodes ... ok
Dec 15 21:13:14.191 INFO [stdout] test routing::bucket::tests::positive_replace_questionable_node ... ok
Dec 15 21:13:14.191 INFO [stdout] test routing::bucket::tests::positive_resist_questionable_node_churn ... ok
Dec 15 21:13:14.191 INFO [stdout] test routing::node::tests::positive_encode_node ... ok
Dec 15 21:13:14.191 INFO [stdout] test routing::node::tests::positive_as_questionable ... ok
Dec 15 21:13:14.191 INFO [stdout] test routing::bucket::tests::positive_resist_good_node_churn ... ok
Dec 15 21:13:14.199 INFO [stdout] test routing::table::tests::positive_initial_empty_buckets ... ok
Dec 15 21:13:14.208 INFO [stdout] test routing::table::tests::positive_first_bucket_sorted ... ok
Dec 15 21:13:14.221 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_five ... ok
Dec 15 21:13:14.223 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_one ... ok
Dec 15 21:13:14.227 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_four ... ok
Dec 15 21:13:14.227 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_five ... ok
Dec 15 21:13:14.239 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_four ... ok
Dec 15 21:13:14.239 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_one ... ok
Dec 15 21:13:14.245 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_two ... ok
Dec 15 21:13:14.245 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_three ... ok
Dec 15 21:13:14.250 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_three ... ok
Dec 15 21:13:14.250 INFO [stdout] test storage::tests::positive_add_and_retrieve_contact ... ok
Dec 15 21:13:14.250 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_two ... ok
Dec 15 21:13:14.338 INFO [stdout] test storage::tests::positive_add_and_retrieve_contacts ... ok
Dec 15 21:13:14.385 INFO [stdout] test storage::tests::positive_renew_contacts ... ok
Dec 15 21:13:14.391 INFO [stdout] test routing::table::tests::positive_add_node_max_recursion ... ok
Dec 15 21:13:14.407 INFO [stdout] test storage::tests::positive_full_storage_expire_one_infohash ... ok
Dec 15 21:13:14.407 INFO [stdout] test storage::tests::positive_full_storage_expire_two_infohash ... ok
Dec 15 21:13:14.407 INFO [stdout] test token::tests::positive_accept_v6_token_from_second_secret ... ok
Dec 15 21:13:14.415 INFO [stdout] test token::tests::positive_accept_valid_v6_token ... ok
Dec 15 21:13:14.415 INFO [stdout] test token::tests::positive_accept_valid_v4_token ... ok
Dec 15 21:13:14.415 INFO [stdout] test transaction::tests::positive_overflow_aid_generate ... ok
Dec 15 21:13:14.435 INFO [stdout] test token::tests::positive_accept_v4_token_from_second_secret ... ok
Dec 15 21:13:14.439 INFO [stdout] test routing::table::tests::positive_last_bucket_sorted ... ok
Dec 15 21:13:14.443 INFO [stdout] test transaction::tests::positive_unique_aid_blocks ... ok
Dec 15 21:13:14.443 INFO [stdout] test transaction::tests::positive_tid_from_bytes ... ok
Dec 15 21:13:14.443 INFO [stdout] test transaction::tests::positive_unique_mid_blocks ... ok
Dec 15 21:13:14.451 INFO [stdout] test transaction::tests::positive_overflow_tid_generate ... ok
Dec 15 21:13:14.451 INFO [stdout] test transaction::tests::positive_unique_tid_blocks ... ok
Dec 15 21:13:14.451 INFO [stdout] test transaction::tests::positive_overflow_mid_generate ... ok
Dec 15 21:13:14.475 INFO [stdout] test routing::table::tests::positive_all_sorted_buckets ... ok
Dec 15 21:13:14.475 INFO [stdout] test token::tests::negative_reject_expired_v6_token ... ok
Dec 15 21:13:14.487 INFO [stdout] test token::tests::negative_reject_expired_v4_token ... ok
Dec 15 21:13:14.487 INFO [stderr]    Doc-tests bip_dht
Dec 15 21:13:14.487 INFO [stdout] 
Dec 15 21:13:14.487 INFO [stdout] test result: ok. 57 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 21:13:14.487 INFO [stdout] 
Dec 15 21:13:20.219 INFO [stdout] 
Dec 15 21:13:20.219 INFO [stdout] running 0 tests
Dec 15 21:13:20.219 INFO [stdout] 
Dec 15 21:13:20.219 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 15 21:13:20.219 INFO [stdout] 
Dec 15 21:13:20.231 INFO [stderr] su: No module specific data is present
Dec 15 21:13:21.186 INFO running `"docker" "inspect" "0c45dfb21cb5e5947f8152e551e64bdb7562d0eedf20a49eaee6599116714af9"`
Dec 15 21:13:21.467 INFO running `"docker" "rm" "-f" "0c45dfb21cb5e5947f8152e551e64bdb7562d0eedf20a49eaee6599116714af9"`
Dec 15 21:13:21.771 INFO [stdout] 0c45dfb21cb5e5947f8152e551e64bdb7562d0eedf20a49eaee6599116714af9