[INFO] fetching crate kad 0.6.0...
[INFO] testing kad-0.6.0 against try#ce59db7a1b1b012fb2793c4641c1bdecad7a128b for pr-85530
[INFO] extracting crate kad 0.6.0 into /workspace/builds/worker-8/source
[INFO] validating manifest of crates.io crate kad 0.6.0 on toolchain ce59db7a1b1b012fb2793c4641c1bdecad7a128b
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate kad 0.6.0
[INFO] finished tweaking crates.io crate kad 0.6.0
[INFO] tweaked toml for crates.io crate kad 0.6.0 written to /workspace/builds/worker-8/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded rr-mux v0.10.1
[INFO] [stderr]   Downloaded futures-timer v0.1.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:20528e8c29e4536546f0102ce88568b16ea7538393a33fcca951030f7af10457" "/opt/rustwide/cargo-home/bin/cargo" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 563095ef2fff86431c2d7f24d97babf2ca91a23f0e1b9291dc799a7fe845fa3a
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "563095ef2fff86431c2d7f24d97babf2ca91a23f0e1b9291dc799a7fe845fa3a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "563095ef2fff86431c2d7f24d97babf2ca91a23f0e1b9291dc799a7fe845fa3a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "563095ef2fff86431c2d7f24d97babf2ca91a23f0e1b9291dc799a7fe845fa3a", kill_on_drop: false }`
[INFO] [stdout] 563095ef2fff86431c2d7f24d97babf2ca91a23f0e1b9291dc799a7fe845fa3a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:20528e8c29e4536546f0102ce88568b16ea7538393a33fcca951030f7af10457" "/opt/rustwide/cargo-home/bin/cargo" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 411742fe61ef7795ee691c7190ac12b4f4df0c5a4bbf1436fbbcdd0d25e0f3a9
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "411742fe61ef7795ee691c7190ac12b4f4df0c5a4bbf1436fbbcdd0d25e0f3a9", kill_on_drop: false }`
[INFO] [stderr]    Compiling autocfg v1.0.1
[INFO] [stderr]    Compiling proc-macro2 v1.0.27
[INFO] [stderr]    Compiling unicode-xid v0.2.2
[INFO] [stderr]    Compiling syn v1.0.72
[INFO] [stderr]    Compiling version_check v0.9.3
[INFO] [stderr]    Compiling libc v0.2.95
[INFO] [stderr]    Compiling rand_core v0.4.2
[INFO] [stderr]    Compiling futures-core v0.3.15
[INFO] [stderr]    Compiling proc-macro-hack v0.5.19
[INFO] [stderr]    Compiling proc-macro-nested v0.1.7
[INFO] [stderr]    Compiling futures-channel v0.3.15
[INFO] [stderr]    Compiling futures-task v0.3.15
[INFO] [stderr]    Compiling memchr v2.4.0
[INFO] [stderr]    Compiling futures-sink v0.3.15
[INFO] [stderr]    Compiling bitflags v1.2.1
[INFO] [stderr]    Compiling pin-project-lite v0.2.6
[INFO] [stderr]    Compiling futures-io v0.3.15
[INFO] [stderr]    Compiling unicode-width v0.1.8
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling slab v0.4.3
[INFO] [stderr]    Compiling async-trait v0.1.50
[INFO] [stderr]    Compiling log v0.4.14
[INFO] [stderr]    Compiling vec_map v0.8.2
[INFO] [stderr]    Compiling cfg-if v1.0.0
[INFO] [stderr]    Compiling futures v0.1.31
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling heck v0.3.3
[INFO] [stderr]    Compiling tracing-core v0.1.18
[INFO] [stderr]    Compiling textwrap v0.11.0
[INFO] [stderr]    Compiling humantime v1.3.0
[INFO] [stderr]    Compiling rand_core v0.3.1
[INFO] [stderr]    Compiling proc-macro-error-attr v1.0.4
[INFO] [stderr]    Compiling proc-macro-error v1.0.4
[INFO] [stderr]    Compiling value-bag v1.0.0-alpha.7
[INFO] [stderr]    Compiling num-traits v0.2.14
[INFO] [stderr]    Compiling num-integer v0.1.44
[INFO] [stderr]    Compiling futures-macro v0.3.15
[INFO] [stderr]    Compiling futures-util v0.3.15
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling num-rational v0.2.4
[INFO] [stderr]    Compiling num-complex v0.2.4
[INFO] [stderr]    Compiling num-iter v0.1.42
[INFO] [stderr]    Compiling rand v0.5.6
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling clap v2.33.3
[INFO] [stderr]    Compiling quote v1.0.9
[INFO] [stderr]    Compiling futures-timer v0.1.1
[INFO] [stderr]    Compiling num v0.2.1
[INFO] [stderr]    Compiling ctor v0.1.20
[INFO] [stderr]    Compiling structopt-derive v0.4.14
[INFO] [stderr]    Compiling tracing-attributes v0.1.15
[INFO] [stderr]    Compiling tracing v0.1.26
[INFO] [stderr]    Compiling structopt v0.3.21
[INFO] [stderr]    Compiling futures-executor v0.3.15
[INFO] [stderr]    Compiling futures v0.3.15
[INFO] [stderr]    Compiling kad v0.6.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 34.12s
[INFO] running `Command { std: "docker" "inspect" "411742fe61ef7795ee691c7190ac12b4f4df0c5a4bbf1436fbbcdd0d25e0f3a9", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "411742fe61ef7795ee691c7190ac12b4f4df0c5a4bbf1436fbbcdd0d25e0f3a9", kill_on_drop: false }`
[INFO] [stdout] 411742fe61ef7795ee691c7190ac12b4f4df0c5a4bbf1436fbbcdd0d25e0f3a9
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:20528e8c29e4536546f0102ce88568b16ea7538393a33fcca951030f7af10457" "/opt/rustwide/cargo-home/bin/cargo" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 7356d52cd04be3465da2ec471ddc9ee6572280b5bd809318869a59b469109123
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "7356d52cd04be3465da2ec471ddc9ee6572280b5bd809318869a59b469109123", kill_on_drop: false }`
[INFO] [stderr]    Compiling cache-padded v1.1.1
[INFO] [stderr]    Compiling waker-fn v1.1.0
[INFO] [stderr]    Compiling parking v2.0.0
[INFO] [stderr]    Compiling strsim v0.9.3
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling event-listener v2.5.1
[INFO] [stderr]    Compiling fastrand v1.4.1
[INFO] [stderr]    Compiling ident_case v1.0.1
[INFO] [stderr]    Compiling once_cell v1.7.2
[INFO] [stderr]    Compiling async-task v4.0.3
[INFO] [stderr]    Compiling crossbeam-utils v0.8.5
[INFO] [stderr]    Compiling atomic-waker v1.0.0
[INFO] [stderr]    Compiling derive_builder v0.9.0
[INFO] [stderr]    Compiling termcolor v1.1.2
[INFO] [stderr]    Compiling socket2 v0.4.0
[INFO] [stderr]    Compiling num_cpus v1.13.0
[INFO] [stderr]    Compiling time v0.1.44
[INFO] [stderr]    Compiling async-attributes v1.1.2
[INFO] [stderr]    Compiling polling v2.0.3
[INFO] [stderr]    Compiling kv-log-macro v1.0.7
[INFO] [stderr]    Compiling concurrent-queue v1.2.2
[INFO] [stderr]    Compiling async-mutex v1.4.0
[INFO] [stderr]    Compiling async-lock v2.4.0
[INFO] [stderr]    Compiling async-channel v1.6.1
[INFO] [stderr]    Compiling darling_core v0.10.2
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling futures-lite v1.12.0
[INFO] [stderr]    Compiling simplelog v0.8.0
[INFO] [stderr]    Compiling blocking v1.0.2
[INFO] [stderr]    Compiling async-executor v1.4.1
[INFO] [stderr]    Compiling async-io v1.4.1
[INFO] [stderr]    Compiling async-global-executor v2.0.2
[INFO] [stderr]    Compiling async-std v1.9.0
[INFO] [stderr]    Compiling darling_macro v0.10.2
[INFO] [stderr]    Compiling darling v0.10.2
[INFO] [stderr]    Compiling derive_builder_core v0.9.0
[INFO] [stderr]    Compiling rr-mux v0.10.1
[INFO] [stderr]    Compiling kad v0.6.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused import: `std::marker::PhantomData`
[INFO] [stdout]   --> tests/main.rs:10:5
[INFO] [stdout]    |
[INFO] [stdout] 10 | use std::marker::PhantomData;
[INFO] [stdout]    |     ^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_imports)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `kad::mock::MockSync`
[INFO] [stdout]   --> tests/main.rs:17:5
[INFO] [stdout]    |
[INFO] [stdout] 17 | use kad::mock::MockSync;
[INFO] [stdout]    |     ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `kad::store::HashMapStore`
[INFO] [stdout]   --> tests/main.rs:18:5
[INFO] [stdout]    |
[INFO] [stdout] 18 | use kad::store::HashMapStore;
[INFO] [stdout]    |     ^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `kad::table::KNodeTable`
[INFO] [stdout]   --> tests/main.rs:19:5
[INFO] [stdout]    |
[INFO] [stdout] 19 | use kad::table::KNodeTable;
[INFO] [stdout]    |     ^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `futures::executor::block_on`
[INFO] [stdout]   --> tests/main.rs:24:5
[INFO] [stdout]    |
[INFO] [stdout] 24 | use futures::executor::block_on;
[INFO] [stdout]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused variable: `sink_rx`
[INFO] [stdout]   --> tests/main.rs:50:27
[INFO] [stdout]    |
[INFO] [stdout] 50 |             let (sink_tx, sink_rx) = mpsc::channel(10);
[INFO] [stdout]    |                           ^^^^^^^ help: if this is intentional, prefix it with an underscore: `_sink_rx`
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(unused_variables)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: struct is never constructed: `MockPeer`
[INFO] [stdout]   --> tests/main.rs:26:8
[INFO] [stdout]    |
[INFO] [stdout] 26 | struct MockPeer<Id: Debug, Info: Debug, Data: Debug> {
[INFO] [stdout]    |        ^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: `#[warn(dead_code)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: type alias is never used: `PeerMap`
[INFO] [stdout]   --> tests/main.rs:30:1
[INFO] [stdout]    |
[INFO] [stdout] 30 | type PeerMap<Id, Info, Data> = HashMap<Id, MockPeer<Id, Info, Data>>;
[INFO] [stdout]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: struct is never constructed: `MockNetwork`
[INFO] [stdout]   --> tests/main.rs:32:8
[INFO] [stdout]    |
[INFO] [stdout] 32 | struct MockNetwork<Id: Debug, Info: Debug, Data: Debug> {
[INFO] [stdout]    |        ^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: associated function is never used: `new`
[INFO] [stdout]   --> tests/main.rs:42:12
[INFO] [stdout]    |
[INFO] [stdout] 42 |     pub fn new(config: Config, nodes: &[Entry<Id, Info>]) -> MockNetwork<Id, Info, Data> {
[INFO] [stdout]    |            ^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `std::time::Duration`
[INFO] [stdout]    --> src/dht/mod.rs:730:9
[INFO] [stdout]     |
[INFO] [stdout] 730 |     use std::time::Duration;
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(unused_imports)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `futures::executor::block_on`
[INFO] [stdout]    --> src/dht/mod.rs:734:9
[INFO] [stdout]     |
[INFO] [stdout] 734 |     use futures::executor::block_on;
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `HashMapStore`
[INFO] [stdout]    --> src/dht/mod.rs:737:35
[INFO] [stdout]     |
[INFO] [stdout] 737 |     use crate::store::{Datastore, HashMapStore};
[INFO] [stdout]     |                                   ^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unused import: `KNodeTable`
[INFO] [stdout]    --> src/dht/mod.rs:738:24
[INFO] [stdout]     |
[INFO] [stdout] 738 |     use crate::table::{KNodeTable, NodeTable};
[INFO] [stdout]     |                        ^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 10 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 4 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 23.09s
[INFO] running `Command { std: "docker" "inspect" "7356d52cd04be3465da2ec471ddc9ee6572280b5bd809318869a59b469109123", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7356d52cd04be3465da2ec471ddc9ee6572280b5bd809318869a59b469109123", kill_on_drop: false }`
[INFO] [stdout] 7356d52cd04be3465da2ec471ddc9ee6572280b5bd809318869a59b469109123
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-8/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:20528e8c29e4536546f0102ce88568b16ea7538393a33fcca951030f7af10457" "/opt/rustwide/cargo-home/bin/cargo" "+ce59db7a1b1b012fb2793c4641c1bdecad7a128b" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 1910d0bf50f69c319f75110bb6148815881000fb53a797cd3a36043a6892b65f
[INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap.
[INFO] running `Command { std: "docker" "start" "-a" "1910d0bf50f69c319f75110bb6148815881000fb53a797cd3a36043a6892b65f", kill_on_drop: false }`
[INFO] [stderr] warning: unused import: `std::time::Duration`
[INFO] [stderr]    --> src/dht/mod.rs:730:9
[INFO] [stderr]     |
[INFO] [stderr] 730 |     use std::time::Duration;
[INFO] [stderr]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(unused_imports)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `futures::executor::block_on`
[INFO] [stderr]    --> src/dht/mod.rs:734:9
[INFO] [stderr]     |
[INFO] [stderr] 734 |     use futures::executor::block_on;
[INFO] [stderr]     |         ^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `HashMapStore`
[INFO] [stderr]    --> src/dht/mod.rs:737:35
[INFO] [stderr]     |
[INFO] [stderr] 737 |     use crate::store::{Datastore, HashMapStore};
[INFO] [stderr]     |                                   ^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `KNodeTable`
[INFO] [stderr]    --> src/dht/mod.rs:738:24
[INFO] [stderr]     |
[INFO] [stderr] 738 |     use crate::table::{KNodeTable, NodeTable};
[INFO] [stderr]     |                        ^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: 4 warnings emitted
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `std::marker::PhantomData`
[INFO] [stderr]   --> tests/main.rs:10:5
[INFO] [stderr]    |
[INFO] [stderr] 10 | use std::marker::PhantomData;
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(unused_imports)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `kad::mock::MockSync`
[INFO] [stderr]   --> tests/main.rs:17:5
[INFO] [stderr]    |
[INFO] [stderr] 17 | use kad::mock::MockSync;
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `kad::store::HashMapStore`
[INFO] [stderr]   --> tests/main.rs:18:5
[INFO] [stderr]    |
[INFO] [stderr] 18 | use kad::store::HashMapStore;
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `kad::table::KNodeTable`
[INFO] [stderr]   --> tests/main.rs:19:5
[INFO] [stderr]    |
[INFO] [stderr] 19 | use kad::table::KNodeTable;
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused import: `futures::executor::block_on`
[INFO] [stderr]   --> tests/main.rs:24:5
[INFO] [stderr]    |
[INFO] [stderr] 24 | use futures::executor::block_on;
[INFO] [stderr]    |     ^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: unused variable: `sink_rx`
[INFO] [stderr]   --> tests/main.rs:50:27
[INFO] [stderr]    |
[INFO] [stderr] 50 |             let (sink_tx, sink_rx) = mpsc::channel(10);
[INFO] [stderr]    |                           ^^^^^^^ help: if this is intentional, prefix it with an underscore: `_sink_rx`
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(unused_variables)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: struct is never constructed: `MockPeer`
[INFO] [stderr]   --> tests/main.rs:26:8
[INFO] [stderr]    |
[INFO] [stderr] 26 | struct MockPeer<Id: Debug, Info: Debug, Data: Debug> {
[INFO] [stderr]    |        ^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: `#[warn(dead_code)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: type alias is never used: `PeerMap`
[INFO] [stderr]   --> tests/main.rs:30:1
[INFO] [stderr]    |
[INFO] [stderr] 30 | type PeerMap<Id, Info, Data> = HashMap<Id, MockPeer<Id, Info, Data>>;
[INFO] [stderr]    | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: struct is never constructed: `MockNetwork`
[INFO] [stderr]   --> tests/main.rs:32:8
[INFO] [stderr]    |
[INFO] [stderr] 32 | struct MockNetwork<Id: Debug, Info: Debug, Data: Debug> {
[INFO] [stderr]    |        ^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: associated function is never used: `new`
[INFO] [stderr]   --> tests/main.rs:42:12
[INFO] [stderr]    |
[INFO] [stderr] 42 |     pub fn new(config: Config, nodes: &[Entry<Id, Info>]) -> MockNetwork<Id, Info, Data> {
[INFO] [stderr]    |            ^^^
[INFO] [stderr] 
[INFO] [stderr] warning: 10 warnings emitted
[INFO] [stderr] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.11s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/kad-aa16095a20870c7b)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test table::kbucket::test::test_k_bucket_update ... ok
[INFO] [stdout] test dht::tests::test_receive_common ... ok
[INFO] [stdout] test dht::tests::test_receive_store ... ok
[INFO] [stdout] 16:13:48 [INFO] Start connect
[INFO] [stdout] 16:13:48 [INFO] Start locate
[INFO] [stdout] 16:13:48 [INFO] Start locate
[INFO] [stdout] test dht::tests::test_receive_ping ... ok
[INFO] [stdout] test dht::tests::test_receive_find_nodes ... ok
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Registering operation id: 34059
[INFO] [stdout] 16:13:48 [INFO] Search round 0
[INFO] [stdout] 16:13:48 [DEBUG] (8) kad::dht: No values found, returning closer nodes for id: [201]
[INFO] [stdout] 16:13:48 [DEBUG] (8) kad::dht: Found 1 values for id: [201]
[INFO] [stdout] 16:13:48 [INFO] Start store
[INFO] [stdout] test dht::tests::test_receive_find_values ... ok
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Initiating operation FindNode (34059)  sending FindNode([9]) request to [Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 823868, tv_nsec: 828394020 }), state: Ok, frozen: false }, Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 823868, tv_nsec: 828416270 }), state: Ok, frozen: false }]
[INFO] [stdout] test table::knodetable::test::test_k_node_table ... ok
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Op 34059 tx: FindNode([9]) to: Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 823868, tv_nsec: 828394020 }), state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Op 34059 tx: FindNode([9]) to: Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 823868, tv_nsec: 828416270 }), state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Operation 34059 entering searching state
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Receive response id: 34059 (NodesFound([9], [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }])) from: Entry { id: [2], info: 300, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Operation 34059, adding entries to map: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: update node: (Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 823868, tv_nsec: 828416270 }), state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Receive response id: 34059 (NodesFound([9], [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }])) from: Entry { id: [3], info: 200, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Operation 34059, adding entries to map: [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: update node: (Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 823868, tv_nsec: 828394020 }), state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [INFO] Search round 1
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Operation 34059, all responses received, re-starting search
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Operation 34059 search iteration 1
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Operation 34059 issuing search request: FindNode([9]) to: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Operation 34059 entering searching state
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Receive response id: 34059 (NodesFound([9], [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }])) from: Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Operation 34059, adding entries to map: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: update node: (Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Receive response id: 34059 (NodesFound([9], [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }])) from: Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Operation 34059, adding entries to map: [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: update node: (Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Operation 34059 search complete!
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Operation 34059 entering done state
[INFO] [stdout] 16:13:48 [DEBUG] (3) kad::dht: Operating 34059 done
[INFO] [stdout] 16:13:48 [INFO] Expecting completion
[INFO] [stdout] 16:13:48 [INFO] Search round 0
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Registering operation id: 60229
[INFO] [stdout] 16:13:48 [INFO] Search round 0
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Initiating operation FindValues (60229)  sending FindValue([12]) request to [Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 823868, tv_nsec: 822704077 }), state: Ok, frozen: false }, Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 823868, tv_nsec: 822693737 }), state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Op 60229 tx: FindValue([12]) to: Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 823868, tv_nsec: 822704077 }), state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Op 60229 tx: FindValue([12]) to: Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 823868, tv_nsec: 822693737 }), state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Operation 60229 entering searching state
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Receive response id: 60229 (NodesFound([12], [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }])) from: Entry { id: [3], info: 200, seen: None, state: Ok, frozen: false }
[INFO] [stdout] test dht::locate::tests::test_lookup ... ok
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Registering operation id: 48582
[INFO] [stdout] 16:13:48 [INFO] Search round 0
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Initiating operation Store (48582)  sending FindNode([12]) request to [Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 823868, tv_nsec: 824389455 }), state: Ok, frozen: false }, Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 823868, tv_nsec: 824379945 }), state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Op 48582 tx: FindNode([12]) to: Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 823868, tv_nsec: 824389455 }), state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Op 48582 tx: FindNode([12]) to: Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 823868, tv_nsec: 824379945 }), state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582 entering searching state
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Receive response id: 48582 (NodesFound([12], [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }])) from: Entry { id: [2], info: 300, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582, adding entries to map: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: update node: (Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 823868, tv_nsec: 824389455 }), state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Receive response id: 48582 (NodesFound([12], [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }])) from: Entry { id: [3], info: 200, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582, adding entries to map: [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: update node: (Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 823868, tv_nsec: 824379945 }), state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [INFO] Search round 1
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582, all responses received, re-starting search
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582 search iteration 1
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582 issuing search request: FindNode([12]) to: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582 entering searching state
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Receive response id: 48582 (NodesFound([12], [])) from: Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582, adding entries to map: []
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: update node: (Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Receive response id: 48582 (NodesFound([12], [])) from: Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582, adding entries to map: []
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: update node: (Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [INFO] Store round
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582 search complete!
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582 issuing request: Store([12], [500]) to: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Receive response id: 48582 (ValuesFound([12], [500])) from: Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582, adding data to map: [500]
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: update node: (Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Receive response id: 48582 (ValuesFound([12], [500])) from: Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582, adding data to map: [500]
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: update node: (Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582 entering done state
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operating 48582 done
[INFO] [stdout] 16:13:48 [DEBUG] (5) kad::dht: Operation 48582 stored at 2 peers
[INFO] [stdout] 16:13:48 [INFO] Expecting store completion
[INFO] [stdout] test dht::store::tests::test_store ... ok
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Operation 60229, adding entries to map: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: update node: (Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 823868, tv_nsec: 822693737 }), state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Receive response id: 60229 (NodesFound([12], [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }])) from: Entry { id: [2], info: 300, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Operation 60229, adding entries to map: [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: update node: (Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 823868, tv_nsec: 822704077 }), state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [INFO] Search round 1
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Operation 60229, all responses received, re-starting search
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Operation 60229 search iteration 1
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Operation 60229 issuing search request: FindValue([12]) to: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Operation 60229 entering searching state
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Receive response id: 60229 (ValuesFound([12], [500])) from: Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Operation 60229, adding data to map: [500]
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: update node: (Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Receive response id: 60229 (ValuesFound([12], [500])) from: Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Operation 60229, adding data to map: [500]
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: update node: (Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Operation 60229 search complete!
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Operation 60229 entering done state
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Operating 60229 done
[INFO] [stdout] 16:13:48 [DEBUG] (4) kad::dht: Operation 60229 values found: [500, 500]
[INFO] [stdout] 16:13:48 [INFO] Expecting search completion
[INFO] [stdout] test dht::search::tests::test_search ... ok
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Initiating operation Connect (25319)  sending FindNode([8]) request to [Entry { id: [2], info: 300, seen: None, state: Ok, frozen: false }, Entry { id: [3], info: 200, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Op 25319 tx: FindNode([8]) to: Entry { id: [2], info: 300, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Op 25319 tx: FindNode([8]) to: Entry { id: [3], info: 200, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Operation 25319 entering searching state
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Receive response id: 25319 (NodesFound([8], [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }])) from: Entry { id: [3], info: 200, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Operation 25319, adding entries to map: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: update node: (Entry { id: [3], info: 200, seen: None, state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Receive response id: 25319 (NodesFound([8], [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }])) from: Entry { id: [2], info: 300, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Operation 25319, adding entries to map: [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: update node: (Entry { id: [2], info: 300, seen: None, state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [INFO] Search round 1
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Operation 25319, all responses received, re-starting search
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Operation 25319 search iteration 1
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Operation 25319 issuing search request: FindNode([8]) to: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Operation 25319 entering searching state
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Receive response id: 25319 (NodesFound([8], [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }])) from: Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Operation 25319, adding entries to map: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: update node: (Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Receive response id: 25319 (NodesFound([8], [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }])) from: Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Operation 25319, adding entries to map: [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }]
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: update node: (Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }, Complete)
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Operation 25319 search complete!
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Operation 25319 entering done state
[INFO] [stdout] 16:13:48 [DEBUG] (2) kad::dht: Operating 25319 done
[INFO] [stdout] 16:13:48 [INFO] Expecting completion
[INFO] [stdout] test dht::connect::tests::test_connect ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.06s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/main.rs (/opt/rustwide/target/debug/deps/main-d7f9746c6cf82cf7)
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests kad
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "1910d0bf50f69c319f75110bb6148815881000fb53a797cd3a36043a6892b65f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1910d0bf50f69c319f75110bb6148815881000fb53a797cd3a36043a6892b65f", kill_on_drop: false }`
[INFO] [stdout] 1910d0bf50f69c319f75110bb6148815881000fb53a797cd3a36043a6892b65f