Nov 18 19:38:27.749 INFO testing bip_dht-0.6.0 against try#2e026d6e5d7621634147ad4c8074f1d964dbb1be for pr-55982
Nov 18 19:38:27.749 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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:28.057 INFO [stdout] 7913beca6e3874c830fcc486620a19ea1401b426d523f6955b115eac5e992d59
Nov 18 19:38:28.058 INFO running `"docker" "start" "-a" "7913beca6e3874c830fcc486620a19ea1401b426d523f6955b115eac5e992d59"`
Nov 18 19:38:28.910 INFO [stderr] usermod: no changes
Nov 18 19:38:29.020 INFO [stderr]    Compiling slab v0.2.0
Nov 18 19:38:29.023 INFO [stderr]    Compiling bip_bencode v0.2.0
Nov 18 19:38:29.663 INFO [stderr]    Compiling bip_handshake v0.4.1
Nov 18 19:38:33.171 INFO [stderr]    Compiling bip_dht v0.6.0 (/source)
Nov 18 19:38:40.071 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 11.12s
Nov 18 19:38:40.072 INFO [stderr] su: No module specific data is present
Nov 18 19:38:40.979 INFO running `"docker" "inspect" "7913beca6e3874c830fcc486620a19ea1401b426d523f6955b115eac5e992d59"`
Nov 18 19:38:41.341 INFO running `"docker" "rm" "-f" "7913beca6e3874c830fcc486620a19ea1401b426d523f6955b115eac5e992d59"`
Nov 18 19:38:41.655 INFO [stdout] 7913beca6e3874c830fcc486620a19ea1401b426d523f6955b115eac5e992d59
Nov 18 19:38:41.659 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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:42.331 INFO [stdout] 0e19b5c09aef0bdfee33c45b829e5b647ab1b9b57cbcd7183c726c14b6583bbb
Nov 18 19:38:42.335 INFO running `"docker" "start" "-a" "0e19b5c09aef0bdfee33c45b829e5b647ab1b9b57cbcd7183c726c14b6583bbb"`
Nov 18 19:38:43.519 INFO [stderr] usermod: no changes
Nov 18 19:38:43.699 INFO [stderr]    Compiling bip_dht v0.6.0 (/source)
Nov 18 19:38:56.028 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.49s
Nov 18 19:38:56.037 INFO [stderr] su: No module specific data is present
Nov 18 19:38:57.325 INFO running `"docker" "inspect" "0e19b5c09aef0bdfee33c45b829e5b647ab1b9b57cbcd7183c726c14b6583bbb"`
Nov 18 19:38:57.575 INFO running `"docker" "rm" "-f" "0e19b5c09aef0bdfee33c45b829e5b647ab1b9b57cbcd7183c726c14b6583bbb"`
Nov 18 19:38:57.859 INFO [stdout] 0e19b5c09aef0bdfee33c45b829e5b647ab1b9b57cbcd7183c726c14b6583bbb
Nov 18 19:38:57.865 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/pr-55982/worker-3/try#2e026d6e5d7621634147ad4c8074f1d964dbb1be:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-3/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:38:58.271 INFO [stdout] 9002c70042cc1bf03433d045855a8d9735d0e06dcc2430d6108a0433327db47c
Nov 18 19:38:58.275 INFO running `"docker" "start" "-a" "9002c70042cc1bf03433d045855a8d9735d0e06dcc2430d6108a0433327db47c"`
Nov 18 19:38:59.047 INFO [stderr] usermod: no changes
Nov 18 19:38:59.167 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.10s
Nov 18 19:38:59.168 INFO [stderr]      Running /target/debug/deps/bip_dht-bb4be95bc99bf8f2
Nov 18 19:38:59.170 INFO [stdout] 
Nov 18 19:38:59.170 INFO [stdout] running 57 tests
Nov 18 19:38:59.179 INFO [stdout] test message::compact_info::tests::positive_compact_values_empty ... ok
Nov 18 19:38:59.179 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_empty ... ok
Nov 18 19:38:59.179 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_one ... ok
Nov 18 19:38:59.180 INFO [stdout] test message::compact_info::tests::positive_compact_nodes_many ... ok
Nov 18 19:38:59.180 INFO [stdout] test message::compact_info::tests::positive_compact_values_one ... ok
Nov 18 19:38:59.180 INFO [stdout] test routing::bucket::tests::positive_all_good_nodes ... ok
Nov 18 19:38:59.180 INFO [stdout] test routing::bucket::tests::positive_all_questionable_nodes ... ok
Nov 18 19:38:59.180 INFO [stdout] test message::compact_info::tests::positive_compact_values_many ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::bucket::tests::positive_resist_good_node_churn ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::bucket::tests::positive_resist_questionable_node_churn ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::node::tests::positive_as_questionable ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::node::tests::positive_bad_status_ordering ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::node::tests::positive_as_good ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::bucket::tests::positive_replace_questionable_node ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::bucket::tests::positive_initial_no_nodes ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::node::tests::positive_encode_node ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::node::tests::positive_good_status_ordering ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::node::tests::positive_node_idle ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::node::tests::positive_node_idle_reqeusts ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::node::tests::positive_questionable_status_ordering ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::node::tests::positive_request_renewal ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::node::tests::positive_response_renewal ... ok
Nov 18 19:38:59.182 INFO [stdout] test routing::table::tests::negative_node_id_equal_table_id ... ok
Nov 18 19:38:59.185 INFO [stdout] test routing::table::tests::positive_initial_empty_buckets ... ok
Nov 18 19:38:59.186 INFO [stdout] test routing::table::tests::positive_first_bucket_sorted ... ok
Nov 18 19:38:59.191 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_four ... ok
Nov 18 19:38:59.198 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_five ... ok
Nov 18 19:38:59.198 INFO [stdout] test routing::node::tests::positive_as_bad ... ok
Nov 18 19:38:59.198 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_four ... ok
Nov 18 19:38:59.198 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_three ... ok
Nov 18 19:38:59.198 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_five ... ok
Nov 18 19:38:59.198 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_three ... ok
Nov 18 19:38:59.198 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_one ... ok
Nov 18 19:38:59.198 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_two ... ok
Nov 18 19:38:59.198 INFO [stdout] test security::tests::positive_generate_compliant_ipv4_test_one ... ok
Nov 18 19:38:59.209 INFO [stdout] test security::tests::positive_is_compliant_ipv4_test_two ... ok
Nov 18 19:38:59.209 INFO [stdout] test storage::tests::positive_add_and_retrieve_contact ... ok
Nov 18 19:38:59.229 INFO [stdout] test storage::tests::positive_add_and_retrieve_contacts ... ok
Nov 18 19:38:59.243 INFO [stdout] test storage::tests::positive_full_storage_expire_one_infohash ... ok
Nov 18 19:38:59.247 INFO [stdout] test token::tests::positive_accept_v4_token_from_second_secret ... ok
Nov 18 19:38:59.259 INFO [stdout] test routing::table::tests::positive_add_node_max_recursion ... ok
Nov 18 19:38:59.259 INFO [stdout] test token::tests::positive_accept_v6_token_from_second_secret ... ok
Nov 18 19:38:59.259 INFO [stdout] test storage::tests::positive_full_storage_expire_two_infohash ... ok
Nov 18 19:38:59.261 INFO [stdout] test routing::table::tests::positive_last_bucket_sorted ... ok
Nov 18 19:38:59.261 INFO [stdout] test transaction::tests::positive_overflow_aid_generate ... ok
Nov 18 19:38:59.261 INFO [stdout] test token::tests::positive_accept_valid_v6_token ... ok
Nov 18 19:38:59.262 INFO [stdout] test transaction::tests::positive_overflow_mid_generate ... ok
Nov 18 19:38:59.263 INFO [stdout] test token::tests::positive_accept_valid_v4_token ... ok
Nov 18 19:38:59.265 INFO [stdout] test routing::table::tests::positive_all_sorted_buckets ... ok
Nov 18 19:38:59.270 INFO [stdout] test transaction::tests::positive_overflow_tid_generate ... ok
Nov 18 19:38:59.270 INFO [stdout] test transaction::tests::positive_unique_mid_blocks ... ok
Nov 18 19:38:59.270 INFO [stdout] test storage::tests::positive_renew_contacts ... ok
Nov 18 19:38:59.270 INFO [stdout] test transaction::tests::positive_tid_from_bytes ... ok
Nov 18 19:38:59.272 INFO [stdout] test transaction::tests::positive_unique_tid_blocks ... ok
Nov 18 19:38:59.276 INFO [stdout] test transaction::tests::positive_unique_aid_blocks ... ok
Nov 18 19:38:59.295 INFO [stdout] test token::tests::negative_reject_expired_v4_token ... ok
Nov 18 19:38:59.295 INFO [stdout] test token::tests::negative_reject_expired_v6_token ... ok
Nov 18 19:38:59.295 INFO [stdout] 
Nov 18 19:38:59.295 INFO [stdout] test result: ok. 57 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:38:59.295 INFO [stdout] 
Nov 18 19:38:59.298 INFO [stderr]    Doc-tests bip_dht
Nov 18 19:39:03.712 INFO [stdout] 
Nov 18 19:39:03.712 INFO [stdout] running 0 tests
Nov 18 19:39:03.712 INFO [stdout] 
Nov 18 19:39:03.712 INFO [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Nov 18 19:39:03.712 INFO [stdout] 
Nov 18 19:39:03.721 INFO [stderr] su: No module specific data is present
Nov 18 19:39:03.971 INFO running `"docker" "inspect" "9002c70042cc1bf03433d045855a8d9735d0e06dcc2430d6108a0433327db47c"`
Nov 18 19:39:04.125 INFO running `"docker" "rm" "-f" "9002c70042cc1bf03433d045855a8d9735d0e06dcc2430d6108a0433327db47c"`
Nov 18 19:39:04.315 INFO [stdout] 9002c70042cc1bf03433d045855a8d9735d0e06dcc2430d6108a0433327db47c