[INFO] fetching crate kad 0.6.0... [INFO] testing kad-0.6.0 against try#ecabaf78506b7a4668d42dc20268c086b93f0fad for pr-87041 [INFO] extracting crate kad 0.6.0 into /workspace/builds/worker-4/source [INFO] validating manifest of crates.io crate kad 0.6.0 on toolchain ecabaf78506b7a4668d42dc20268c086b93f0fad [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "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-4/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 2561674d38f8642f11f84961e741ad4ec714bb90fbb484326ae795bece5193e5 [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" "2561674d38f8642f11f84961e741ad4ec714bb90fbb484326ae795bece5193e5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "2561674d38f8642f11f84961e741ad4ec714bb90fbb484326ae795bece5193e5", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "2561674d38f8642f11f84961e741ad4ec714bb90fbb484326ae795bece5193e5", kill_on_drop: false }` [INFO] [stdout] 2561674d38f8642f11f84961e741ad4ec714bb90fbb484326ae795bece5193e5 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stderr] WARNING: Your kernel does not support swap limit capabilities or the cgroup is not mounted. Memory limited without swap. [INFO] [stdout] 78a99f5aac772139119983b0738d92c9925fe06e79fa65231525a93847901e1d [INFO] running `Command { std: "docker" "start" "-a" "78a99f5aac772139119983b0738d92c9925fe06e79fa65231525a93847901e1d", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.27 [INFO] [stderr] Compiling unicode-xid v0.2.2 [INFO] [stderr] Compiling rand_core v0.4.2 [INFO] [stderr] Compiling proc-macro-hack v0.5.19 [INFO] [stderr] Compiling futures-sink v0.3.15 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling log v0.4.14 [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-complex v0.2.4 [INFO] [stderr] Compiling num-rational v0.2.4 [INFO] [stderr] Compiling num-iter v0.1.42 [INFO] [stderr] Compiling tracing-core v0.1.18 [INFO] [stderr] Compiling futures-timer v0.1.1 [INFO] [stderr] Compiling futures-channel v0.3.15 [INFO] [stderr] Compiling clap v2.33.3 [INFO] [stderr] Compiling rand_core v0.3.1 [INFO] [stderr] Compiling rand v0.5.6 [INFO] [stderr] Compiling quote v1.0.9 [INFO] [stderr] Compiling syn v1.0.73 [INFO] [stderr] Compiling proc-macro-error-attr v1.0.4 [INFO] [stderr] Compiling num v0.2.1 [INFO] [stderr] Compiling proc-macro-error v1.0.4 [INFO] [stderr] Compiling ctor v0.1.20 [INFO] [stderr] Compiling tracing-attributes v0.1.15 [INFO] [stderr] Compiling structopt-derive v0.4.15 [INFO] [stderr] Compiling async-trait v0.1.50 [INFO] [stderr] Compiling value-bag v1.0.0-alpha.7 [INFO] [stderr] Compiling tracing v0.1.26 [INFO] [stderr] Compiling structopt v0.3.22 [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 1m 10s [INFO] running `Command { std: "docker" "inspect" "78a99f5aac772139119983b0738d92c9925fe06e79fa65231525a93847901e1d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "78a99f5aac772139119983b0738d92c9925fe06e79fa65231525a93847901e1d", kill_on_drop: false }` [INFO] [stdout] 78a99f5aac772139119983b0738d92c9925fe06e79fa65231525a93847901e1d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 66e073874d01aebcea2f719af798779f7217afad0b61e1f2e891eddb3e0c2bd8 [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" "66e073874d01aebcea2f719af798779f7217afad0b61e1f2e891eddb3e0c2bd8", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] [stderr] Compiling ident_case v1.0.1 [INFO] [stderr] Compiling strsim v0.9.3 [INFO] [stderr] Compiling crossbeam-utils v0.8.5 [INFO] [stderr] Compiling derive_builder v0.9.0 [INFO] [stderr] Compiling socket2 v0.4.0 [INFO] [stderr] Compiling time v0.1.44 [INFO] [stderr] Compiling num_cpus v1.13.0 [INFO] [stderr] Compiling async-attributes v1.1.2 [INFO] [stderr] Compiling polling v2.1.0 [INFO] [stderr] Compiling kv-log-macro v1.0.7 [INFO] [stderr] Compiling darling_core v0.10.2 [INFO] [stderr] Compiling async-io v1.6.0 [INFO] [stderr] Compiling chrono v0.4.19 [INFO] [stderr] Compiling async-global-executor v2.0.2 [INFO] [stderr] Compiling async-std v1.9.0 [INFO] [stderr] Compiling simplelog v0.8.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 { [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 = HashMap>; [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 { [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]) -> MockNetwork { [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 46.51s [INFO] running `Command { std: "docker" "inspect" "66e073874d01aebcea2f719af798779f7217afad0b61e1f2e891eddb3e0c2bd8", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "66e073874d01aebcea2f719af798779f7217afad0b61e1f2e891eddb3e0c2bd8", kill_on_drop: false }` [INFO] [stdout] 66e073874d01aebcea2f719af798779f7217afad0b61e1f2e891eddb3e0c2bd8 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4/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:34b6a614d2c27851fe6cbf88fbd1137609cefab8b10d0615aaeb6fd47975d74e" "/opt/rustwide/cargo-home/bin/cargo" "+ecabaf78506b7a4668d42dc20268c086b93f0fad" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] a3164bc3d4a38bf07dee9da06b1b84dd8b89ba36717eb05e05d2d44dd6a93208 [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" "a3164bc3d4a38bf07dee9da06b1b84dd8b89ba36717eb05e05d2d44dd6a93208", 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 { [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 = HashMap>; [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 { [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]) -> MockNetwork { [INFO] [stderr] | ^^^ [INFO] [stderr] [INFO] [stderr] warning: 10 warnings emitted [INFO] [stderr] [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.10s [INFO] [stderr] Running unittests (/opt/rustwide/target/debug/deps/kad-0f53863fc6d15517) [INFO] [stdout] [INFO] [stdout] running 11 tests [INFO] [stdout] test table::knodetable::test::test_k_node_table ... ok [INFO] [stdout] 16:59:36 [INFO] Start locate [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Registering operation id: 65265 [INFO] [stdout] 16:59:36 [INFO] Search round 0 [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Initiating operation FindNode (65265) sending FindNode([9]) request to [Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 918422756 }), state: Ok, frozen: false }, Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 918433956 }), state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Op 65265 tx: FindNode([9]) to: Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 918422756 }), state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Op 65265 tx: FindNode([9]) to: Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 918433956 }), state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Operation 65265 entering searching state [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Receive response id: 65265 (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:59:36 [DEBUG] (3) kad::dht: Operation 65265, adding entries to map: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [INFO] Start locate [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Registering operation id: 32941 [INFO] [stdout] test dht::tests::test_receive_find_nodes ... ok [INFO] [stdout] 16:59:36 [INFO] Search round 0 [INFO] [stdout] test dht::tests::test_receive_common ... ok [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Initiating operation FindValues (32941) sending FindValue([12]) request to [Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 919336876 }), state: Ok, frozen: false }, Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 919330276 }), state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Op 32941 tx: FindValue([12]) to: Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 919336876 }), state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Op 32941 tx: FindValue([12]) to: Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 919330276 }), state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Operation 32941 entering searching state [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Receive response id: 32941 (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] 16:59:36 [DEBUG] (4) kad::dht: Operation 32941, adding entries to map: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: update node: (Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 919330276 }), state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Receive response id: 32941 (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:59:36 [DEBUG] (4) kad::dht: Operation 32941, adding entries to map: [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: update node: (Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 919336876 }), state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [INFO] Search round 1 [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Operation 32941, all responses received, re-starting search [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Operation 32941 search iteration 1 [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Operation 32941 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:59:36 [DEBUG] (4) kad::dht: Operation 32941 entering searching state [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Receive response id: 32941 (ValuesFound([12], [500])) from: Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Operation 32941, adding data to map: [500] [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: update node: (Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Receive response id: 32941 (ValuesFound([12], [500])) from: Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Operation 32941, adding data to map: [500] [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: update node: (Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Operation 32941 search complete! [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Operation 32941 entering done state [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Operating 32941 done [INFO] [stdout] 16:59:36 [DEBUG] (4) kad::dht: Operation 32941 values found: [500, 500] [INFO] [stdout] 16:59:36 [INFO] Expecting search completion [INFO] [stdout] test dht::search::tests::test_search ... ok [INFO] [stdout] 16:59:36 [INFO] Start connect [INFO] [stdout] 16:59:36 [INFO] Start store [INFO] [stdout] 16:59:36 [INFO] Search round 0 [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: Initiating operation Connect (45254) 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:59:36 [DEBUG] (2) kad::dht: Op 45254 tx: FindNode([8]) to: Entry { id: [2], info: 300, seen: None, state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: Op 45254 tx: FindNode([8]) to: Entry { id: [3], info: 200, seen: None, state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: Operation 45254 entering searching state [INFO] [stdout] test table::kbucket::test::test_k_bucket_update ... ok [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: Receive response id: 45254 (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:59:36 [DEBUG] (2) kad::dht: Operation 45254, adding entries to map: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: update node: (Entry { id: [3], info: 200, seen: None, state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: Receive response id: 45254 (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:59:36 [DEBUG] (2) kad::dht: Operation 45254, adding entries to map: [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: update node: (Entry { id: [2], info: 300, seen: None, state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [INFO] Search round 1 [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: Operation 45254, all responses received, re-starting search [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: Operation 45254 search iteration 1 [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: Operation 45254 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:59:36 [DEBUG] (2) kad::dht: Operation 45254 entering searching state [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: Receive response id: 45254 (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:59:36 [DEBUG] (2) kad::dht: Operation 45254, adding entries to map: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: update node: (Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: Receive response id: 45254 (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:59:36 [DEBUG] (2) kad::dht: Operation 45254, adding entries to map: [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: update node: (Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: Operation 45254 search complete! [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: Operation 45254 entering done state [INFO] [stdout] 16:59:36 [DEBUG] (2) kad::dht: Operating 45254 done [INFO] [stdout] 16:59:36 [INFO] Expecting completion [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Registering operation id: 58555 [INFO] [stdout] 16:59:36 [INFO] Search round 0 [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Initiating operation Store (58555) sending FindNode([12]) request to [Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 923152160 }), state: Ok, frozen: false }, Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 923142860 }), state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Op 58555 tx: FindNode([12]) to: Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 923152160 }), state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Op 58555 tx: FindNode([12]) to: Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 923142860 }), state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Operation 58555 entering searching state [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Receive response id: 58555 (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:59:36 [DEBUG] (5) kad::dht: Operation 58555, adding entries to map: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: update node: (Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 923152160 }), state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Receive response id: 58555 (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:59:36 [DEBUG] (5) kad::dht: Operation 58555, adding entries to map: [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: update node: (Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 923142860 }), state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [INFO] Search round 1 [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Operation 58555, all responses received, re-starting search [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Operation 58555 search iteration 1 [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Operation 58555 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:59:36 [DEBUG] (5) kad::dht: Operation 58555 entering searching state [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Receive response id: 58555 (NodesFound([12], [])) from: Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Operation 58555, adding entries to map: [] [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: update node: (Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Receive response id: 58555 (NodesFound([12], [])) from: Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Operation 58555, adding entries to map: [] [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: update node: (Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [INFO] Store round [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Operation 58555 search complete! [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Operation 58555 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:59:36 [DEBUG] (5) kad::dht: Receive response id: 58555 (ValuesFound([12], [500])) from: Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Operation 58555, adding data to map: [500] [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: update node: (Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Receive response id: 58555 (ValuesFound([12], [500])) from: Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false } [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Operation 58555, adding data to map: [500] [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: update node: (Entry { id: [2], info: 300, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 918433956 }), state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Receive response id: 65265 (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] test dht::connect::tests::test_connect ... ok [INFO] [stdout] test dht::tests::test_receive_ping ... ok [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Operation 65265, adding entries to map: [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: update node: (Entry { id: [3], info: 200, seen: Some(Instant { tv_sec: 36638049, tv_nsec: 918422756 }), state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [INFO] Search round 1 [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Operation 65265, all responses received, re-starting search [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Operation 65265 search iteration 1 [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Operation 65265 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:59:36 [DEBUG] (3) kad::dht: Operation 65265 entering searching state [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Receive response id: 65265 (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:59:36 [DEBUG] (3) kad::dht: Operation 65265, adding entries to map: [Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: update node: (Entry { id: [9], info: 400, seen: None, state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Receive response id: 65265 (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:59:36 [DEBUG] (3) kad::dht: Operation 65265, adding entries to map: [Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }] [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: update node: (Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Operation 65265 search complete! [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Operation 65265 entering done state [INFO] [stdout] 16:59:36 [DEBUG] (3) kad::dht: Operating 65265 done [INFO] [stdout] 16:59:36 [INFO] Expecting completion [INFO] [stdout] test dht::locate::tests::test_lookup ... ok [INFO] [stdout] 16:59:36 [DEBUG] (8) kad::dht: No values found, returning closer nodes for id: [201] [INFO] [stdout] 16:59:36 [DEBUG] (8) kad::dht: Found 1 values for id: [201] [INFO] [stdout] 16:59:36 [DEBUG] (10) kad::dht: Stored 1 values for id: [2] [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: update node: (Entry { id: [10], info: 400, seen: None, state: Ok, frozen: false }, Complete) [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Operation 58555 entering done state [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Operating 58555 done [INFO] [stdout] 16:59:36 [DEBUG] (5) kad::dht: Operation 58555 stored at 2 peers [INFO] [stdout] 16:59:36 [INFO] Expecting store completion [INFO] [stdout] test dht::tests::test_receive_find_values ... ok [INFO] [stdout] test dht::tests::test_receive_store ... ok [INFO] [stdout] test dht::store::tests::test_store ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s [INFO] [stdout] [INFO] [stderr] Running tests/main.rs (/opt/rustwide/target/debug/deps/main-5253cbdb392ee7ea) [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" "a3164bc3d4a38bf07dee9da06b1b84dd8b89ba36717eb05e05d2d44dd6a93208", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "a3164bc3d4a38bf07dee9da06b1b84dd8b89ba36717eb05e05d2d44dd6a93208", kill_on_drop: false }` [INFO] [stdout] a3164bc3d4a38bf07dee9da06b1b84dd8b89ba36717eb05e05d2d44dd6a93208