[INFO] fetching crate nakamoto-client 0.2.0...
[INFO] testing nakamoto-client-0.2.0 against 1.54.0 for beta-1.55-1
[INFO] extracting crate nakamoto-client 0.2.0 into /workspace/builds/worker-5/source
[INFO] validating manifest of crates.io crate nakamoto-client 0.2.0 on toolchain 1.54.0
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.54.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] started tweaking crates.io crate nakamoto-client 0.2.0
[INFO] finished tweaking crates.io crate nakamoto-client 0.2.0
[INFO] tweaked toml for crates.io crate nakamoto-client 0.2.0 written to /workspace/builds/worker-5/source/Cargo.toml
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.54.0" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }`
[INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+1.54.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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" "+1.54.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 049b4e9f8c95bc03644db8334ba5ebd4b2de6538bf3ab4e9371580762aee84cc
[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" "049b4e9f8c95bc03644db8334ba5ebd4b2de6538bf3ab4e9371580762aee84cc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "049b4e9f8c95bc03644db8334ba5ebd4b2de6538bf3ab4e9371580762aee84cc", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "049b4e9f8c95bc03644db8334ba5ebd4b2de6538bf3ab4e9371580762aee84cc", kill_on_drop: false }`
[INFO] [stdout] 049b4e9f8c95bc03644db8334ba5ebd4b2de6538bf3ab4e9371580762aee84cc
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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=warn" "-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" "+1.54.0" "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] c1456a5d07fc7a540e6808e3283cf4fb5c16e80f9137a7a3c638a8704d3d9b31
[INFO] running `Command { std: "docker" "start" "-a" "c1456a5d07fc7a540e6808e3283cf4fb5c16e80f9137a7a3c638a8704d3d9b31", kill_on_drop: false }`
[INFO] [stderr]    Compiling bitcoin_hashes v0.9.7
[INFO] [stderr]    Compiling microserde-derive v0.1.13
[INFO] [stderr]    Compiling microserde v0.1.13
[INFO] [stderr]    Compiling bitcoin v0.25.2
[INFO] [stderr]    Compiling nakamoto-common v0.2.0
[INFO] [stderr]    Compiling nakamoto-p2p v0.2.1
[INFO] [stderr]    Compiling nakamoto-chain v0.2.0
[INFO] [stderr]    Compiling nakamoto-client v0.2.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: panic message is not a string literal
[INFO] [stdout]    --> src/client.rs:235:32
[INFO] [stdout]     |
[INFO] [stdout] 235 |             Err(err) => panic!(err.to_string()),
[INFO] [stdout]     |                                ^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(non_fmt_panic)]` on by default
[INFO] [stdout]     = note: this is no longer accepted in Rust 2021
[INFO] [stdout] help: add a "{}" format string to Display the message
[INFO] [stdout]     |
[INFO] [stdout] 235 |             Err(err) => panic!("{}", err.to_string()),
[INFO] [stdout]     |                                ^^^^^
[INFO] [stdout] help: or use std::panic::panic_any instead
[INFO] [stdout]     |
[INFO] [stdout] 235 |             Err(err) => std::panic::panic_any(err.to_string()),
[INFO] [stdout]     |                         ^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: panic message is not a string literal
[INFO] [stdout]    --> src/client.rs:263:32
[INFO] [stdout]     |
[INFO] [stdout] 263 |             Err(err) => panic!(err.to_string()), // TODO
[INFO] [stdout]     |                                ^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: this is no longer accepted in Rust 2021
[INFO] [stdout] help: add a "{}" format string to Display the message
[INFO] [stdout]     |
[INFO] [stdout] 263 |             Err(err) => panic!("{}", err.to_string()), // TODO
[INFO] [stdout]     |                                ^^^^^
[INFO] [stdout] help: or use std::panic::panic_any instead
[INFO] [stdout]     |
[INFO] [stdout] 263 |             Err(err) => std::panic::panic_any(err.to_string()), // TODO
[INFO] [stdout]     |                         ^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 2 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 16.15s
[INFO] running `Command { std: "docker" "inspect" "c1456a5d07fc7a540e6808e3283cf4fb5c16e80f9137a7a3c638a8704d3d9b31", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c1456a5d07fc7a540e6808e3283cf4fb5c16e80f9137a7a3c638a8704d3d9b31", kill_on_drop: false }`
[INFO] [stdout] c1456a5d07fc7a540e6808e3283cf4fb5c16e80f9137a7a3c638a8704d3d9b31
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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=warn" "-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" "+1.54.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 9933c2281be690453a5c79df330d51caa9d7c4cfc0f9af6a284af9383d64b13b
[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" "9933c2281be690453a5c79df330d51caa9d7c4cfc0f9af6a284af9383d64b13b", kill_on_drop: false }`
[INFO] [stderr]    Compiling popol v0.4.0
[INFO] [stderr]    Compiling chrono v0.4.19
[INFO] [stderr]    Compiling tempfile v3.2.0
[INFO] [stdout] warning: panic message is not a string literal
[INFO] [stdout]    --> src/client.rs:235:32
[INFO] [stdout]     |
[INFO] [stdout] 235 |             Err(err) => panic!(err.to_string()),
[INFO] [stdout]     |                                ^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(non_fmt_panic)]` on by default
[INFO] [stdout]     = note: this is no longer accepted in Rust 2021
[INFO] [stdout] help: add a "{}" format string to Display the message
[INFO] [stdout]     |
[INFO] [stdout] 235 |             Err(err) => panic!("{}", err.to_string()),
[INFO] [stdout]     |                                ^^^^^
[INFO] [stdout] help: or use std::panic::panic_any instead
[INFO] [stdout]     |
[INFO] [stdout] 235 |             Err(err) => std::panic::panic_any(err.to_string()),
[INFO] [stdout]     |                         ^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: panic message is not a string literal
[INFO] [stdout]    --> src/client.rs:263:32
[INFO] [stdout]     |
[INFO] [stdout] 263 |             Err(err) => panic!(err.to_string()), // TODO
[INFO] [stdout]     |                                ^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: this is no longer accepted in Rust 2021
[INFO] [stdout] help: add a "{}" format string to Display the message
[INFO] [stdout]     |
[INFO] [stdout] 263 |             Err(err) => panic!("{}", err.to_string()), // TODO
[INFO] [stdout]     |                                ^^^^^
[INFO] [stdout] help: or use std::panic::panic_any instead
[INFO] [stdout]     |
[INFO] [stdout] 263 |             Err(err) => std::panic::panic_any(err.to_string()), // TODO
[INFO] [stdout]     |                         ^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 2 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling nakamoto-net-poll v0.2.0
[INFO] [stderr]    Compiling nakamoto-test v0.2.0
[INFO] [stderr]    Compiling nakamoto-client v0.2.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: panic message is not a string literal
[INFO] [stdout]    --> src/client.rs:235:32
[INFO] [stdout]     |
[INFO] [stdout] 235 |             Err(err) => panic!(err.to_string()),
[INFO] [stdout]     |                                ^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(non_fmt_panic)]` on by default
[INFO] [stdout]     = note: this is no longer accepted in Rust 2021
[INFO] [stdout] help: add a "{}" format string to Display the message
[INFO] [stdout]     |
[INFO] [stdout] 235 |             Err(err) => panic!("{}", err.to_string()),
[INFO] [stdout]     |                                ^^^^^
[INFO] [stdout] help: or use std::panic::panic_any instead
[INFO] [stdout]     |
[INFO] [stdout] 235 |             Err(err) => std::panic::panic_any(err.to_string()),
[INFO] [stdout]     |                         ^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: panic message is not a string literal
[INFO] [stdout]    --> src/client.rs:263:32
[INFO] [stdout]     |
[INFO] [stdout] 263 |             Err(err) => panic!(err.to_string()), // TODO
[INFO] [stdout]     |                                ^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: this is no longer accepted in Rust 2021
[INFO] [stdout] help: add a "{}" format string to Display the message
[INFO] [stdout]     |
[INFO] [stdout] 263 |             Err(err) => panic!("{}", err.to_string()), // TODO
[INFO] [stdout]     |                                ^^^^^
[INFO] [stdout] help: or use std::panic::panic_any instead
[INFO] [stdout]     |
[INFO] [stdout] 263 |             Err(err) => std::panic::panic_any(err.to_string()), // TODO
[INFO] [stdout]     |                         ^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: 2 warnings emitted
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 9.37s
[INFO] running `Command { std: "docker" "inspect" "9933c2281be690453a5c79df330d51caa9d7c4cfc0f9af6a284af9383d64b13b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "9933c2281be690453a5c79df330d51caa9d7c4cfc0f9af6a284af9383d64b13b", kill_on_drop: false }`
[INFO] [stdout] 9933c2281be690453a5c79df330d51caa9d7c4cfc0f9af6a284af9383d64b13b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5/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=warn" "-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" "+1.54.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 6609de75c69c19b5487d79bc0e58d61bb39ac61db233cfc814b2a62c0df228e6
[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" "6609de75c69c19b5487d79bc0e58d61bb39ac61db233cfc814b2a62c0df228e6", kill_on_drop: false }`
[INFO] [stderr] warning: panic message is not a string literal
[INFO] [stderr]    --> src/client.rs:235:32
[INFO] [stderr]     |
[INFO] [stderr] 235 |             Err(err) => panic!(err.to_string()),
[INFO] [stderr]     |                                ^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(non_fmt_panic)]` on by default
[INFO] [stderr]     = note: this is no longer accepted in Rust 2021
[INFO] [stderr] help: add a "{}" format string to Display the message
[INFO] [stderr]     |
[INFO] [stderr] 235 |             Err(err) => panic!("{}", err.to_string()),
[INFO] [stderr]     |                                ^^^^^
[INFO] [stderr] help: or use std::panic::panic_any instead
[INFO] [stderr]     |
[INFO] [stderr] 235 |             Err(err) => std::panic::panic_any(err.to_string()),
[INFO] [stderr]     |                         ^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: panic message is not a string literal
[INFO] [stderr]    --> src/client.rs:263:32
[INFO] [stderr]     |
[INFO] [stderr] 263 |             Err(err) => panic!(err.to_string()), // TODO
[INFO] [stderr]     |                                ^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: this is no longer accepted in Rust 2021
[INFO] [stderr] help: add a "{}" format string to Display the message
[INFO] [stderr]     |
[INFO] [stderr] 263 |             Err(err) => panic!("{}", err.to_string()), // TODO
[INFO] [stderr]     |                                ^^^^^
[INFO] [stderr] help: or use std::panic::panic_any instead
[INFO] [stderr]     |
[INFO] [stderr] 263 |             Err(err) => std::panic::panic_any(err.to_string()), // TODO
[INFO] [stderr]     |                         ^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: 2 warnings emitted
[INFO] [stderr] 
[INFO] [stderr] warning: panic message is not a string literal
[INFO] [stderr]    --> src/client.rs:235:32
[INFO] [stderr]     |
[INFO] [stderr] 235 |             Err(err) => panic!(err.to_string()),
[INFO] [stderr]     |                                ^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(non_fmt_panic)]` on by default
[INFO] [stderr]     = note: this is no longer accepted in Rust 2021
[INFO] [stderr] help: add a "{}" format string to Display the message
[INFO] [stderr]     |
[INFO] [stderr] 235 |             Err(err) => panic!("{}", err.to_string()),
[INFO] [stderr]     |                                ^^^^^
[INFO] [stderr] help: or use std::panic::panic_any instead
[INFO] [stderr]     |
[INFO] [stderr] 235 |             Err(err) => std::panic::panic_any(err.to_string()),
[INFO] [stderr]     |                         ^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: panic message is not a string literal
[INFO] [stderr]    --> src/client.rs:263:32
[INFO] [stderr]     |
[INFO] [stderr] 263 |             Err(err) => panic!(err.to_string()), // TODO
[INFO] [stderr]     |                                ^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: this is no longer accepted in Rust 2021
[INFO] [stderr] help: add a "{}" format string to Display the message
[INFO] [stderr]     |
[INFO] [stderr] 263 |             Err(err) => panic!("{}", err.to_string()), // TODO
[INFO] [stderr]     |                                ^^^^^
[INFO] [stderr] help: or use std::panic::panic_any instead
[INFO] [stderr]     |
[INFO] [stderr] 263 |             Err(err) => std::panic::panic_any(err.to_string()), // TODO
[INFO] [stderr]     |                         ^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: 2 warnings emitted
[INFO] [stderr] 
[INFO] [stderr]     Finished test [unoptimized + debuginfo] target(s) in 0.06s
[INFO] [stderr]      Running unittests (/opt/rustwide/target/debug/deps/nakamoto_client-30273133c1035d7c)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test peer::test::test_empty ... ok
[INFO] [stdout] test peer::test::test_save_and_load ... ok
[INFO] [stdout] test tests::test_full_sync has been running for over 60 seconds
[INFO] [stdout] test tests::test_full_sync ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::test_full_sync stdout ----
[INFO] [stdout] test> [nakamoto_client::client:src/client.rs:365] Initializing client (Mainnet)..
[INFO] [stdout] test> [nakamoto_client::client:src/client.rs:366] Genesis block hash is 000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f
[INFO] [stdout] test> [nakamoto_client::client:src/client.rs:367] Chain height is 0
[INFO] [stdout] test> [nakamoto_client::client:src/client.rs:373] 0 peer(s) found..
[INFO] [stdout] test> [nakamoto_net_poll::reactor:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-net-poll-0.2.0/src/reactor.rs:129] Listening on 0.0.0.0:34577
[INFO] [stdout] test> [nakamoto_net_poll::reactor:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-net-poll-0.2.0/src/reactor.rs:134] Initializing protocol..
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:123] [conn] Address book exhausted when attempting to connect..
[INFO] [stdout] test> [nakamoto_client::client:src/client.rs:365] Initializing client (Mainnet)..
[INFO] [stdout] test> [nakamoto_client::client:src/client.rs:366] Genesis block hash is 000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f
[INFO] [stdout] test> [nakamoto_client::client:src/client.rs:367] Chain height is 0
[INFO] [stdout] test> [nakamoto_client::client:src/client.rs:373] 0 peer(s) found..
[INFO] [stdout] test> [nakamoto_net_poll::reactor:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-net-poll-0.2.0/src/reactor.rs:129] Listening on 0.0.0.0:42365
[INFO] [stdout] test> [nakamoto_net_poll::reactor:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-net-poll-0.2.0/src/reactor.rs:134] Initializing protocol..
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:123] [conn] Address book exhausted when attempting to connect..
[INFO] [stdout] test> [nakamoto_client::client:src/client.rs:365] Initializing client (Mainnet)..
[INFO] [stdout] test> [nakamoto_client::client:src/client.rs:366] Genesis block hash is 000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f
[INFO] [stdout] test> [nakamoto_client::client:src/client.rs:367] Chain height is 0
[INFO] [stdout] test> [nakamoto_client::client:src/client.rs:373] 0 peer(s) found..
[INFO] [stdout] test> [nakamoto_net_poll::reactor:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-net-poll-0.2.0/src/reactor.rs:129] Listening on 0.0.0.0:46177
[INFO] [stdout] test> [nakamoto_net_poll::reactor:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-net-poll-0.2.0/src/reactor.rs:134] Initializing protocol..
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:123] [conn] Address book exhausted when attempting to connect..
[INFO] [stdout] test> [nakamoto_p2p::protocol:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:686] tip = 000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, height = 0/0 (0.0%), outbound = 0/8
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:555] Received command: Connect(0.0.0.0:42365)
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:120] 0.0.0.0:42365: Peer connected (Outbound)
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:42365: Sending "version"
[INFO] [stdout] test> [nakamoto_p2p::protocol:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:686] tip = 000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, height = 0/0 (0.0%), outbound = 0/8
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:123] [conn] 127.0.0.1:45942: Peer connected (Inbound)
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:555] Received command: Connect(0.0.0.0:46177)
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:120] 0.0.0.0:46177: Peer connected (Outbound)
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:46177: Sending "version"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:45942: Received "version"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:147] [peer] 127.0.0.1:45942: Peer version = 70012, height = 0, agent = /nakamoto:0.1.0/, services = ServiceFlags(NETWORK), timestamp = 1628320426
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:45942: Sending "version"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:45942: Sending "verack"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:555] Received command: Connect(0.0.0.0:46177)
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:42365: Received "version"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:147] [peer] 0.0.0.0:42365: Peer version = 70012, height = 0, agent = /nakamoto:0.1.0/, services = ServiceFlags(NETWORK), timestamp = 1628320426
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:42365: Sending "verack"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:120] 0.0.0.0:46177: Peer connected (Outbound)
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:46177: Sending "version"
[INFO] [stdout] test> [nakamoto_p2p::protocol:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:686] tip = 000000000019d6689c085ae165831e934ff763ae46a2a6c172b3f1b60a8ce26f, height = 0/0 (0.0%), outbound = 0/8
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:123] [conn] 127.0.0.1:41186: Peer connected (Inbound)
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:123] [conn] 127.0.0.1:41188: Peer connected (Inbound)
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:41186: Received "version"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:147] [peer] 127.0.0.1:41186: Peer version = 70012, height = 0, agent = /nakamoto:0.1.0/, services = ServiceFlags(NETWORK), timestamp = 1628320426
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:41186: Sending "version"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:41186: Sending "verack"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:41188: Received "version"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:147] [peer] 127.0.0.1:41188: Peer version = 70012, height = 0, agent = /nakamoto:0.1.0/, services = ServiceFlags(NETWORK), timestamp = 1628320426
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:41188: Sending "version"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:41188: Sending "verack"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:46177: Received "version"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:147] [peer] 0.0.0.0:46177: Peer version = 70012, height = 0, agent = /nakamoto:0.1.0/, services = ServiceFlags(NETWORK), timestamp = 1628320426
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:46177: Sending "verack"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:46177: Received "verack"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:147] [peer] 0.0.0.0:46177: Peer negotiated..
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:46177: Sending "ping"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:46177: Sending "sendheaders"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:188] [sync] Potential stale tip detected (last update is 6621898 minute(s) ago)
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:578] Received command: ImportHeaders(..)
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:42365: Received "verack"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:147] [peer] 0.0.0.0:42365: Peer negotiated..
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:42365: Sending "ping"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:45942: Received "verack"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:42365: Sending "sendheaders"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:147] [peer] 127.0.0.1:45942: Peer negotiated..
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:188] [sync] Potential stale tip detected (last update is 6621898 minute(s) ago)
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:45942: Sending "ping"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:45942: Sending "sendheaders"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:188] [sync] Potential stale tip detected (last update is 6621898 minute(s) ago)
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:45942: Received "ping"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:45942: Sending "pong"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:42365: Received "ping"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:42365: Sending "pong"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:45942: Received "sendheaders"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:836] 127.0.0.1:45942: Ignoring "sendheaders"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:42365: Received "sendheaders"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:836] 0.0.0.0:42365: Ignoring "sendheaders"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:46177: Received "version"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:147] [peer] 0.0.0.0:46177: Peer version = 70012, height = 0, agent = /nakamoto:0.1.0/, services = ServiceFlags(NETWORK), timestamp = 1628320426
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:46177: Sending "verack"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:45942: Received "pong"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:46177: Received "verack"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:147] [peer] 0.0.0.0:46177: Peer negotiated..
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:46177: Sending "ping"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:46177: Sending "sendheaders"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:188] [sync] Potential stale tip detected (last update is 6621898 minute(s) ago)
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:42365: Received "pong"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:188] [sync] Headers imported: TipChanged(00000000ca59764b4ff11d88ea67e641dba94a17520ebd10f1631b21a18d5805, 1111, [])
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:193] Block height = 1111, tip = 00000000ca59764b4ff11d88ea67e641dba94a17520ebd10f1631b21a18d5805
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:188] [sync] Headers synced up to hash=00000000ca59764b4ff11d88ea67e641dba94a17520ebd10f1631b21a18d5805 height=1111
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:41188: Received "verack"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:147] [peer] 127.0.0.1:41188: Peer negotiated..
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:41188: Sending "ping"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:41188: Sending "sendheaders"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:188] [sync] Potential stale tip detected (last update is 6598155 minute(s) ago)
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:41188: Received "ping"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:41188: Sending "pong"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:46177: Received "ping"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:46177: Sending "pong"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:41188: Received "sendheaders"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:836] 127.0.0.1:41188: Ignoring "sendheaders"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:41186: Received "verack"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:147] [peer] 127.0.0.1:41186: Peer negotiated..
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:41186: Sending "ping"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:41186: Sending "sendheaders"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:188] [sync] Potential stale tip detected (last update is 6598155 minute(s) ago)
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:46177: Received "sendheaders"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:836] 0.0.0.0:46177: Ignoring "sendheaders"
[INFO] [stdout] test> [alice:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:46177: Received "pong"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:46177: Received "ping"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 0.0.0.0:46177: Sending "pong"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:41186: Received "ping"
[INFO] [stdout] test> [nakamoto_p2p::protocol::channel:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol/channel.rs:62] 127.0.0.1:41186: Sending "pong"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:41186: Received "sendheaders"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:836] 127.0.0.1:41186: Ignoring "sendheaders"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:41188: Received "pong"
[INFO] [stdout] test> [misha:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 127.0.0.1:41186: Received "pong"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:46177: Received "sendheaders"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:836] 0.0.0.0:46177: Ignoring "sendheaders"
[INFO] [stdout] test> [olive:/opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/nakamoto-p2p-0.2.1/src/protocol.rs:714] 0.0.0.0:46177: Received "pong"
[INFO] [stdout] thread 'tests::test_full_sync' panicked at 'called `Result::unwrap()` on an `Err` value: Timeout', src/tests.rs:95:49
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x564cb7f1ba70 - std::backtrace_rs::backtrace::libunwind::trace::h34055254b57d8e79
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5
[INFO] [stdout]    1:     0x564cb7f1ba70 - std::backtrace_rs::backtrace::trace_unsynchronized::h8f1e3fbd9afff6ec
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
[INFO] [stdout]    2:     0x564cb7f1ba70 - std::sys_common::backtrace::_print_fmt::h3a99a796b770c360
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/sys_common/backtrace.rs:67:5
[INFO] [stdout]    3:     0x564cb7f1ba70 - <std::sys_common::backtrace::_print::DisplayBacktrace as core::fmt::Display>::fmt::h32d1f94a80615d18
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/sys_common/backtrace.rs:46:22
[INFO] [stdout]    4:     0x564cb7f4016c - core::fmt::write::h306731c068f7162c
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/core/src/fmt/mod.rs:1110:17
[INFO] [stdout]    5:     0x564cb7f152e5 - std::io::Write::write_fmt::h87f19b0a2cd190bc
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/io/mod.rs:1588:15
[INFO] [stdout]    6:     0x564cb7f1e08b - std::sys_common::backtrace::_print::h5abaa2601a852287
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/sys_common/backtrace.rs:49:5
[INFO] [stdout]    7:     0x564cb7f1e08b - std::sys_common::backtrace::print::h8d81445442bb638f
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/sys_common/backtrace.rs:36:9
[INFO] [stdout]    8:     0x564cb7f1e08b - std::panicking::default_hook::{{closure}}::hcfe804496a9fa747
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/panicking.rs:208:50
[INFO] [stdout]    9:     0x564cb7f1db9c - std::panicking::default_hook::hbea8e3ccf2ba8901
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/panicking.rs:222:9
[INFO] [stdout]   10:     0x564cb7f1e754 - std::panicking::rust_panic_with_hook::h7ee9e1a2d0f8975a
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/panicking.rs:622:17
[INFO] [stdout]   11:     0x564cb7f1e237 - std::panicking::begin_panic_handler::{{closure}}::h8ab3b4491718b2c7
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/panicking.rs:519:13
[INFO] [stdout]   12:     0x564cb7f1bf6c - std::sys_common::backtrace::__rust_end_short_backtrace::hd489062ffa586a9f
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/sys_common/backtrace.rs:141:18
[INFO] [stdout]   13:     0x564cb7f1e199 - rust_begin_unwind
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/panicking.rs:515:5
[INFO] [stdout]   14:     0x564cb7c2fb51 - core::panicking::panic_fmt::hca6330e3e14086b4
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/core/src/panicking.rs:92:14
[INFO] [stdout]   15:     0x564cb7c2fc43 - core::result::unwrap_failed::h6160d2b4bfbb0e87
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/core/src/result.rs:1355:5
[INFO] [stdout]   16:     0x564cb7d3057b - core::result::Result<T,E>::unwrap::h592e9f47c2ecb0fd
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/core/src/result.rs:1037:23
[INFO] [stdout]   17:     0x564cb7d0f880 - nakamoto_client::tests::test_full_sync::h170b681fc7c2f8c9
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests.rs:95:20
[INFO] [stdout]   18:     0x564cb7d0f1ca - nakamoto_client::tests::test_full_sync::{{closure}}::h1ddca6201cafcd45
[INFO] [stdout]                                at /opt/rustwide/workdir/src/tests.rs:72:1
[INFO] [stdout]   19:     0x564cb7c83d6e - core::ops::function::FnOnce::call_once::hd9d0891e90ca4344
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   20:     0x564cb7e15763 - core::ops::function::FnOnce::call_once::h152e7e439ff59e19
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   21:     0x564cb7e15763 - test::__rust_begin_short_backtrace::h0908f3b1fdbb72ab
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/test/src/lib.rs:577:5
[INFO] [stdout]   22:     0x564cb7e14220 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hf6d2a51e5587546c
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/alloc/src/boxed.rs:1575:9
[INFO] [stdout]   23:     0x564cb7e14220 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h8af660f3a89ef593
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/panic.rs:347:9
[INFO] [stdout]   24:     0x564cb7e14220 - std::panicking::try::do_call::hd36aef60f84b6d36
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/panicking.rs:401:40
[INFO] [stdout]   25:     0x564cb7e14220 - std::panicking::try::h9932b4480e69817c
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/panicking.rs:365:19
[INFO] [stdout]   26:     0x564cb7e14220 - std::panic::catch_unwind::h6aa177099cfff5f8
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/panic.rs:434:14
[INFO] [stdout]   27:     0x564cb7e14220 - test::run_test_in_process::h5884d6f2d99e20c3
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/test/src/lib.rs:600:18
[INFO] [stdout]   28:     0x564cb7e14220 - test::run_test::run_test_inner::{{closure}}::h6fdc58c6d5f5b6e3
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/test/src/lib.rs:492:39
[INFO] [stdout]   29:     0x564cb7ded8d2 - test::run_test::run_test_inner::{{closure}}::h408365d67a78ade8
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/test/src/lib.rs:519:37
[INFO] [stdout]   30:     0x564cb7ded8d2 - std::sys_common::backtrace::__rust_begin_short_backtrace::hb560b77c4f701523
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/sys_common/backtrace.rs:125:18
[INFO] [stdout]   31:     0x564cb7df1488 - std::thread::Builder::spawn_unchecked::{{closure}}::{{closure}}::hf2e7831dad93c597
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/thread/mod.rs:481:17
[INFO] [stdout]   32:     0x564cb7df1488 - <std::panic::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hd2e08783d8be4d02
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/panic.rs:347:9
[INFO] [stdout]   33:     0x564cb7df1488 - std::panicking::try::do_call::h062597b7acfd6b82
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/panicking.rs:401:40
[INFO] [stdout]   34:     0x564cb7df1488 - std::panicking::try::h0d7b79db04c14552
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/panicking.rs:365:19
[INFO] [stdout]   35:     0x564cb7df1488 - std::panic::catch_unwind::hb01a0b5e7c8cd43b
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/panic.rs:434:14
[INFO] [stdout]   36:     0x564cb7df1488 - std::thread::Builder::spawn_unchecked::{{closure}}::h5bde48cc887e0d1e
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/thread/mod.rs:480:30
[INFO] [stdout]   37:     0x564cb7df1488 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h745ea30807a29e55
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/core/src/ops/function.rs:227:5
[INFO] [stdout]   38:     0x564cb7f245d7 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h7ece6cfefaff1005
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/alloc/src/boxed.rs:1575:9
[INFO] [stdout]   39:     0x564cb7f245d7 - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::hb8b48e55c21f193e
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/alloc/src/boxed.rs:1575:9
[INFO] [stdout]   40:     0x564cb7f245d7 - std::sys::unix::thread::Thread::new::thread_start::h8c7c4450dba62914
[INFO] [stdout]                                at /rustc/a178d0322ce20e33eac124758e837cbd80a6f633/library/std/src/sys/unix/thread.rs:71:17
[INFO] [stdout]   41:     0x7fc94a45a609 - start_thread
[INFO] [stdout]   42:     0x7fc94a22c293 - clone
[INFO] [stdout]   43:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::test_full_sync
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 2 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 60.17s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass '--lib'
[INFO] running `Command { std: "docker" "inspect" "6609de75c69c19b5487d79bc0e58d61bb39ac61db233cfc814b2a62c0df228e6", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "6609de75c69c19b5487d79bc0e58d61bb39ac61db233cfc814b2a62c0df228e6", kill_on_drop: false }`
[INFO] [stdout] 6609de75c69c19b5487d79bc0e58d61bb39ac61db233cfc814b2a62c0df228e6
