[INFO] cloning repository https://github.com/ustulation/using-quinn [INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/ustulation/using-quinn" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fustulation%2Fusing-quinn", kill_on_drop: false }` [INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fustulation%2Fusing-quinn'... [INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }` [INFO] [stdout] 9e3d43254291b7a2b0c081dc2142ae9e0c311648 [INFO] testing ustulation/using-quinn against master#acb8934fd57b3c2740c4abac0a5728c2c9b1423b for pr-101061-1 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fustulation%2Fusing-quinn" "/workspace/builds/worker-6/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-6/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/ustulation/using-quinn on toolchain acb8934fd57b3c2740c4abac0a5728c2c9b1423b [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+acb8934fd57b3c2740c4abac0a5728c2c9b1423b" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] started tweaking git repo https://github.com/ustulation/using-quinn [INFO] removed 0 missing examples [INFO] finished tweaking git repo https://github.com/ustulation/using-quinn [INFO] tweaked toml for git repo https://github.com/ustulation/using-quinn written to /workspace/builds/worker-6/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+acb8934fd57b3c2740c4abac0a5728c2c9b1423b" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+acb8934fd57b3c2740c4abac0a5728c2c9b1423b" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded spin v0.5.2 [INFO] [stderr] Downloaded webpki v0.21.4 [INFO] [stderr] Downloaded untrusted v0.7.1 [INFO] [stderr] Downloaded tokio-util v0.7.4 [INFO] [stderr] Downloaded eyre v0.6.8 [INFO] [stderr] Downloaded color-eyre v0.5.11 [INFO] [stderr] Downloaded tracing-core v0.1.29 [INFO] [stderr] Downloaded tiny-keccak v2.0.2 [INFO] [stderr] Downloaded percent-encoding v2.2.0 [INFO] [stderr] Downloaded url v2.3.1 [INFO] [stderr] Downloaded indenter v0.3.3 [INFO] [stderr] Downloaded rustls-native-certs v0.6.2 [INFO] [stderr] Downloaded quinn-udp v0.1.3 [INFO] [stderr] Downloaded owo-colors v1.3.0 [INFO] [stderr] Downloaded wildmatch v1.1.0 [INFO] [stderr] Downloaded igd v0.12.0 [INFO] [stderr] Downloaded quinn v0.8.5 [INFO] [stderr] Downloaded quinn-proto v0.8.4 [INFO] [stderr] Downloaded sct v0.7.0 [INFO] [stderr] Downloaded pem v1.1.0 [INFO] [stderr] Downloaded backoff v0.3.0 [INFO] [stderr] Downloaded xmltree v0.10.3 [INFO] [stderr] Downloaded security-framework-sys v2.6.1 [INFO] [stderr] Downloaded rustls-pemfile v0.2.1 [INFO] [stderr] Downloaded attohttpc v0.16.3 [INFO] [stderr] Downloaded tracing-error v0.1.2 [INFO] [stderr] Downloaded xml-rs v0.8.4 [INFO] [stderr] Downloaded h2 v0.3.14 [INFO] [stderr] Downloaded color-spantrace v0.1.6 [INFO] [stderr] Downloaded yasna v0.5.0 [INFO] [stderr] Downloaded rustls v0.20.6 [INFO] [stderr] Downloaded tracing-subscriber v0.2.25 [INFO] [stderr] Downloaded schannel v0.1.20 [INFO] [stderr] Downloaded security-framework v2.7.0 [INFO] [stderr] Downloaded ctor v0.1.23 [INFO] [stderr] Downloaded hyper v0.14.20 [INFO] [stderr] Downloaded rustls-pemfile v1.0.1 [INFO] [stderr] Downloaded rcgen v0.9.3 [INFO] [stderr] Downloaded matchers v0.0.1 [INFO] [stderr] Downloaded tracing-serde v0.1.3 [INFO] [stderr] Downloaded tracing-test-macro v0.1.0 [INFO] [stderr] Downloaded tracing-test v0.1.0 [INFO] [stderr] Downloaded http-body v0.4.5 [INFO] [stderr] Downloaded httparse v1.8.0 [INFO] [stderr] Downloaded tower-service v0.3.2 [INFO] [stderr] Downloaded ring v0.16.20 [INFO] [stderr] Downloaded form_urlencoded v1.1.0 [INFO] [stderr] Downloaded unicode-bidi v0.3.8 [INFO] [stderr] Downloaded unicode-normalization v0.1.22 [INFO] [stderr] Downloaded idna v0.3.0 [INFO] [stderr] Downloaded openssl-probe v0.1.5 [INFO] [stderr] Downloaded num_threads v0.1.6 [INFO] [stderr] Downloaded time v0.3.14 [INFO] [stderr] Downloaded webpki v0.22.0 [INFO] [stderr] Downloaded structopt-derive v0.4.18 [INFO] [stderr] Downloaded structopt v0.3.26 [INFO] [stderr] Downloaded tracing-attributes v0.1.22 [INFO] [stderr] Downloaded tracing v0.1.36 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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:e92e462e59ac3446a933540fd361ddeb5362c86f92d432d6a560a917a465022e" "/opt/rustwide/cargo-home/bin/cargo" "+acb8934fd57b3c2740c4abac0a5728c2c9b1423b" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 61160c774062a8c4efa4b5444e3815767cc65ebab699c77b3f6f416466dab246 [INFO] running `Command { std: "docker" "start" "-a" "61160c774062a8c4efa4b5444e3815767cc65ebab699c77b3f6f416466dab246", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "61160c774062a8c4efa4b5444e3815767cc65ebab699c77b3f6f416466dab246", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "61160c774062a8c4efa4b5444e3815767cc65ebab699c77b3f6f416466dab246", kill_on_drop: false }` [INFO] [stdout] 61160c774062a8c4efa4b5444e3815767cc65ebab699c77b3f6f416466dab246 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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" "RUSTDOCFLAGS=--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:e92e462e59ac3446a933540fd361ddeb5362c86f92d432d6a560a917a465022e" "/opt/rustwide/cargo-home/bin/cargo" "+acb8934fd57b3c2740c4abac0a5728c2c9b1423b" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 5b55051c583a205dc8404a0bacc304074dc61b3e98442a958b92b5c595e9217c [INFO] running `Command { std: "docker" "start" "-a" "5b55051c583a205dc8404a0bacc304074dc61b3e98442a958b92b5c595e9217c", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.43 [INFO] [stderr] Compiling unicode-ident v1.0.4 [INFO] [stderr] Compiling quote v1.0.21 [INFO] [stderr] Compiling syn v1.0.100 [INFO] [stderr] Compiling libc v0.2.133 [INFO] [stderr] Compiling autocfg v1.1.0 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling once_cell v1.15.0 [INFO] [stderr] Compiling serde_derive v1.0.144 [INFO] [stderr] Compiling serde v1.0.144 [INFO] [stderr] Compiling pin-project-lite v0.2.9 [INFO] [stderr] Compiling memchr v2.5.0 [INFO] [stderr] Compiling log v0.4.17 [INFO] [stderr] Compiling futures-core v0.3.24 [INFO] [stderr] Compiling cc v1.0.73 [INFO] [stderr] Compiling slab v0.4.7 [INFO] [stderr] Compiling futures-channel v0.3.24 [INFO] [stderr] Compiling futures-task v0.3.24 [INFO] [stderr] Compiling futures-sink v0.3.24 [INFO] [stderr] Compiling tokio v1.21.1 [INFO] [stderr] Compiling untrusted v0.7.1 [INFO] [stderr] Compiling futures-util v0.3.24 [INFO] [stderr] Compiling spin v0.5.2 [INFO] [stderr] Compiling tinyvec_macros v0.1.0 [INFO] [stderr] Compiling mio v0.8.4 [INFO] [stderr] Compiling socket2 v0.4.7 [INFO] [stderr] Compiling getrandom v0.2.7 [INFO] [stderr] Compiling tinyvec v1.6.0 [INFO] [stderr] Compiling tracing-core v0.1.29 [INFO] [stderr] Compiling futures-io v0.3.24 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling ppv-lite86 v0.2.16 [INFO] [stderr] Compiling base64 v0.13.0 [INFO] [stderr] Compiling itoa v1.0.3 [INFO] [stderr] Compiling fnv v1.0.7 [INFO] [stderr] Compiling indexmap v1.9.1 [INFO] [stderr] Compiling rustls v0.20.6 [INFO] [stderr] Compiling ring v0.16.20 [INFO] [stderr] Compiling thiserror v1.0.35 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling byteorder v1.4.3 [INFO] [stderr] Compiling hashbrown v0.12.3 [INFO] [stderr] Compiling httparse v1.8.0 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling unicode-normalization v0.1.22 [INFO] [stderr] Compiling unicode-bidi v0.3.8 [INFO] [stderr] Compiling percent-encoding v2.2.0 [INFO] [stderr] Compiling form_urlencoded v1.1.0 [INFO] [stderr] Compiling fxhash v0.2.1 [INFO] [stderr] Compiling rustls-pemfile v0.2.1 [INFO] [stderr] Compiling num_threads v0.1.6 [INFO] [stderr] Compiling idna v0.3.0 [INFO] [stderr] Compiling try-lock v0.2.3 [INFO] [stderr] Compiling time v0.3.14 [INFO] [stderr] Compiling want v0.3.0 [INFO] [stderr] Compiling wildmatch v1.1.0 [INFO] [stderr] Compiling xml-rs v0.8.4 [INFO] [stderr] Compiling httpdate v1.0.2 [INFO] [stderr] Compiling url v2.3.1 [INFO] [stderr] Compiling tower-service v0.3.2 [INFO] [stderr] Compiling yasna v0.5.0 [INFO] [stderr] Compiling pem v1.1.0 [INFO] [stderr] Compiling instant v0.1.12 [INFO] [stderr] Compiling xmltree v0.10.3 [INFO] [stderr] Compiling webpki v0.22.0 [INFO] [stderr] Compiling sct v0.7.0 [INFO] [stderr] Compiling rcgen v0.9.3 [INFO] [stderr] Compiling webpki v0.21.4 [INFO] [stderr] Compiling futures-macro v0.3.24 [INFO] [stderr] Compiling tracing-attributes v0.1.22 [INFO] [stderr] Compiling thiserror-impl v1.0.35 [INFO] [stderr] Compiling pin-project-internal v1.0.12 [INFO] [stderr] Compiling pin-project v1.0.12 [INFO] [stderr] Compiling tracing v0.1.36 [INFO] [stderr] Compiling futures-executor v0.3.24 [INFO] [stderr] Compiling futures v0.3.24 [INFO] [stderr] Compiling bytes v1.2.1 [INFO] [stderr] Compiling bincode v1.3.3 [INFO] [stderr] Compiling http v0.2.8 [INFO] [stderr] Compiling quinn-proto v0.8.4 [INFO] [stderr] Compiling http-body v0.4.5 [INFO] [stderr] Compiling attohttpc v0.16.3 [INFO] [stderr] Compiling tokio-util v0.7.4 [INFO] [stderr] Compiling quinn-udp v0.1.3 [INFO] [stderr] Compiling backoff v0.3.0 [INFO] [stderr] Compiling quinn v0.8.5 [INFO] [stderr] Compiling h2 v0.3.14 [INFO] [stderr] Compiling hyper v0.14.20 [INFO] [stderr] Compiling igd v0.12.0 [INFO] [stderr] Compiling qp2p v0.30.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 1m 51s [INFO] running `Command { std: "docker" "inspect" "5b55051c583a205dc8404a0bacc304074dc61b3e98442a958b92b5c595e9217c", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5b55051c583a205dc8404a0bacc304074dc61b3e98442a958b92b5c595e9217c", kill_on_drop: false }` [INFO] [stdout] 5b55051c583a205dc8404a0bacc304074dc61b3e98442a958b92b5c595e9217c [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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" "RUSTDOCFLAGS=--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:e92e462e59ac3446a933540fd361ddeb5362c86f92d432d6a560a917a465022e" "/opt/rustwide/cargo-home/bin/cargo" "+acb8934fd57b3c2740c4abac0a5728c2c9b1423b" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 5bc02bcf2ee9c31d5f3786fd7fc3efc3a9e184f9beb1999243c40d375586ee70 [INFO] running `Command { std: "docker" "start" "-a" "5bc02bcf2ee9c31d5f3786fd7fc3efc3a9e184f9beb1999243c40d375586ee70", kill_on_drop: false }` [INFO] [stderr] Compiling log v0.4.17 [INFO] [stderr] Compiling tracing-core v0.1.29 [INFO] [stderr] Compiling tokio v1.21.1 [INFO] [stderr] Compiling tokio-macros v1.8.0 [INFO] [stderr] Compiling num_cpus v1.13.1 [INFO] [stderr] Compiling num-traits v0.2.15 [INFO] [stderr] Compiling num-integer v0.1.45 [INFO] [stderr] Compiling regex-syntax v0.6.27 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling serde_json v1.0.85 [INFO] [stderr] Compiling iana-time-zone v0.1.48 [INFO] [stderr] Compiling tracing v0.1.36 [INFO] [stderr] Compiling mio v0.8.4 [INFO] [stderr] Compiling ryu v1.0.11 [INFO] [stderr] Compiling tracing-log v0.1.3 [INFO] [stderr] Compiling tracing-serde v0.1.3 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling rustls-pemfile v1.0.1 [INFO] [stderr] Compiling thread_local v1.1.4 [INFO] [stderr] Compiling ansi_term v0.12.1 [INFO] [stderr] Compiling openssl-probe v0.1.5 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling smallvec v1.9.0 [INFO] [stderr] Compiling rustls-native-certs v0.6.2 [INFO] [stderr] Compiling chrono v0.4.22 [INFO] [stderr] Compiling want v0.3.0 [INFO] [stderr] Compiling quinn-proto v0.8.4 [INFO] [stderr] Compiling backtrace v0.3.66 [INFO] [stderr] Compiling adler v1.0.2 [INFO] [stderr] Compiling gimli v0.26.2 [INFO] [stderr] Compiling eyre v0.6.8 [INFO] [stderr] Compiling crunchy v0.2.2 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling regex v1.6.0 [INFO] [stderr] Compiling miniz_oxide v0.5.4 [INFO] [stderr] Compiling attohttpc v0.16.3 [INFO] [stderr] Compiling matchers v0.0.1 [INFO] [stderr] Compiling tracing-subscriber v0.2.25 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling object v0.29.0 [INFO] [stderr] Compiling addr2line v0.17.0 [INFO] [stderr] Compiling owo-colors v1.3.0 [INFO] [stderr] Compiling rustc-demangle v0.1.21 [INFO] [stderr] Compiling indenter v0.3.3 [INFO] [stderr] Compiling tiny-keccak v2.0.2 [INFO] [stderr] Compiling tracing-error v0.1.2 [INFO] [stderr] Compiling tokio-util v0.7.4 [INFO] [stderr] Compiling quinn-udp v0.1.3 [INFO] [stderr] Compiling color-spantrace v0.1.6 [INFO] [stderr] Compiling backoff v0.3.0 [INFO] [stderr] Compiling quinn v0.8.5 [INFO] [stderr] Compiling rand_chacha v0.2.2 [INFO] [stderr] Compiling h2 v0.3.14 [INFO] [stderr] Compiling tracing-test-macro v0.1.0 [INFO] [stderr] Compiling rand v0.7.3 [INFO] [stderr] Compiling tracing-test v0.1.0 [INFO] [stderr] Compiling ctor v0.1.23 [INFO] [stderr] Compiling color-eyre v0.5.11 [INFO] [stderr] Compiling hyper v0.14.20 [INFO] [stderr] Compiling igd v0.12.0 [INFO] [stderr] Compiling qp2p v0.30.0 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 1m 19s [INFO] running `Command { std: "docker" "inspect" "5bc02bcf2ee9c31d5f3786fd7fc3efc3a9e184f9beb1999243c40d375586ee70", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5bc02bcf2ee9c31d5f3786fd7fc3efc3a9e184f9beb1999243c40d375586ee70", kill_on_drop: false }` [INFO] [stdout] 5bc02bcf2ee9c31d5f3786fd7fc3efc3a9e184f9beb1999243c40d375586ee70 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6/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" "RUSTDOCFLAGS=--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:e92e462e59ac3446a933540fd361ddeb5362c86f92d432d6a560a917a465022e" "/opt/rustwide/cargo-home/bin/cargo" "+acb8934fd57b3c2740c4abac0a5728c2c9b1423b" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 8a3c7618a2b08790f768eb33dc2e7a5248949e7f27c3b9d6dbd1453b18344207 [INFO] running `Command { std: "docker" "start" "-a" "8a3c7618a2b08790f768eb33dc2e7a5248949e7f27c3b9d6dbd1453b18344207", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.33s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/qp2p-7deaf58e0b3304e4) [INFO] [stdout] [INFO] [stdout] running 20 tests [INFO] [stdout] test endpoint::tests::new_without_external_addr ... ok [INFO] [stdout] test endpoint::tests::new_with_external_ip ... ok [INFO] [stdout] test endpoint::tests::new_with_external_port ... ok [INFO] [stdout] test endpoint::tests::new_with_external_addr ... ok [INFO] [stdout] test connection::tests::test_endpoint_echo ... ok [INFO] [stdout] test tests::common::connection_attempts_to_bootstrap_contacts_should_succeed ... ok [INFO] [stdout] test tests::common::client ... ok [INFO] [stdout] test connection::tests::benign_connection_loss ... ok [INFO] [stderr] Error: [INFO] [stderr] 0: deadline has elapsed [INFO] [stderr] [INFO] [stderr] Location: [INFO] [stderr] src/connection.rs:698 [INFO] [stderr] [INFO] [stderr] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stderr]  ⋮ 5 frames hidden ⋮  [INFO] [stderr] 6:  as core::ops::try_trait::FromResidual>>::from_residual::h2e1b5e643b988c1b [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/result.rs:2105 [INFO] [stderr] 7: qp2p::connection::tests::basic_usage::{{closure}}::hb6e917d7b3db1426 [INFO] [stderr] at /opt/rustwide/workdir/src/connection.rs:698 [INFO] [stderr] 696 │ [INFO] [stderr] 697 │ // check the connections were shutdown on drop [INFO] [stderr]  698 >  timeout(peer1.wait_idle()).await?; [INFO] [stderr] 699 │ timeout(peer2.wait_idle()).await?; [INFO] [stderr] 700 │ [INFO] [stderr] 8:  as core::future::future::Future>::poll::h7c77f0f3f460818f [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/future/mod.rs:91 [INFO] [stderr] 9:  as core::future::future::Future>::poll::h5f35b48bf4ed63a7 [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/future/future.rs:124 [INFO] [stderr] 10: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::h12a80e669a33b6c9 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:525 [INFO] [stderr] 523 │ if core.spawner.reset_woken() { [INFO] [stderr] 524 │ let (c, res) = context.enter(core, || { [INFO] [stderr]  525 >  crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stderr] 526 │ }); [INFO] [stderr] 527 │ [INFO] [stderr] 11: tokio::coop::with_budget::{{closure}}::hbdff2196cacf9880 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/coop.rs:102 [INFO] [stderr] 100 │ let _guard = ResetGuard { cell, prev }; [INFO] [stderr] 101 │ [INFO] [stderr]  102 >  f() [INFO] [stderr] 103 │ }) [INFO] [stderr] 104 │ } [INFO] [stderr] 12: std::thread::local::LocalKey::try_with::h82deda49a893c0da [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/thread/local.rs:445 [INFO] [stderr] 13: std::thread::local::LocalKey::with::h69adbbc0fce977ec [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/thread/local.rs:421 [INFO] [stderr] 14: tokio::coop::with_budget::hb6d0f76cd075c926 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/coop.rs:95 [INFO] [stderr] 93 │ } [INFO] [stderr] 94 │ [INFO] [stderr]  95 >  CURRENT.with(move |cell| { [INFO] [stderr] 96 │ let prev = cell.get(); [INFO] [stderr] 97 │ [INFO] [stderr] 15: tokio::coop::budget::h371117c232c5fb00 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/coop.rs:72 [INFO] [stderr] 70 │ #[inline(always)] [INFO] [stderr] 71 │ pub(crate) fn budget(f: impl FnOnce() -> R) -> R { [INFO] [stderr]  72 >  with_budget(Budget::initial(), f) [INFO] [stderr] 73 │ } [INFO] [stderr] 74 │ [INFO] [stderr] 16: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::hb254c7c8734aa542 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:525 [INFO] [stderr] 523 │ if core.spawner.reset_woken() { [INFO] [stderr] 524 │ let (c, res) = context.enter(core, || { [INFO] [stderr]  525 >  crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stderr] 526 │ }); [INFO] [stderr] 527 │ [INFO] [stderr] 17: tokio::runtime::scheduler::current_thread::Context::enter::hca898d31bc33febd [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:349 [INFO] [stderr] 347 │ [INFO] [stderr] 348 │ // Execute the closure while tracking the execution budget [INFO] [stderr]  349 >  let ret = f(); [INFO] [stderr] 350 │ [INFO] [stderr] 351 │ // Take the scheduler core back [INFO] [stderr] 18: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::h0bcb72ff1f9b0bb4 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:524 [INFO] [stderr] 522 │ 'outer: loop { [INFO] [stderr] 523 │ if core.spawner.reset_woken() { [INFO] [stderr]  524 >  let (c, res) = context.enter(core, || { [INFO] [stderr] 525 │ crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stderr] 526 │ }); [INFO] [stderr] 19: tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::h3219d1b11357b8e5 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:595 [INFO] [stderr] 593 │ [INFO] [stderr] 594 │ // Call the closure and place `core` back [INFO] [stderr]  595 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stderr] 596 │ [INFO] [stderr] 597 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stderr] 20: tokio::macros::scoped_tls::ScopedKey::set::hc258b995dbe19e64 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/macros/scoped_tls.rs:61 [INFO] [stderr] 59 │ }; [INFO] [stderr] 60 │ [INFO] [stderr]  61 >  f() [INFO] [stderr] 62 │ } [INFO] [stderr] 63 │ [INFO] [stderr] 21: tokio::runtime::scheduler::current_thread::CoreGuard::enter::h42973a2b4ea89a72 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:595 [INFO] [stderr] 593 │ [INFO] [stderr] 594 │ // Call the closure and place `core` back [INFO] [stderr]  595 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stderr] 596 │ [INFO] [stderr] 597 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stderr] 22: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::hd1c8d3ff61584f82 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:515 [INFO] [stderr] 513 │ #[track_caller] [INFO] [stderr] 514 │ fn block_on(self, future: F) -> F::Output { [INFO] [stderr]  515 >  let ret = self.enter(|mut core, context| { [INFO] [stderr] 516 │ let _enter = crate::runtime::enter(false); [INFO] [stderr] 517 │ let waker = context.spawner.waker_ref(); [INFO] [stderr] 23: tokio::runtime::scheduler::current_thread::CurrentThread::block_on::hd776f06c51e8cc0f [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:161 [INFO] [stderr] 159 │ loop { [INFO] [stderr] 160 │ if let Some(core) = self.take_core() { [INFO] [stderr]  161 >  return core.block_on(future); [INFO] [stderr] 162 │ } else { [INFO] [stderr] 163 │ let mut enter = crate::runtime::enter(false); [INFO] [stderr] 24: tokio::runtime::Runtime::block_on::h2134ee41a6c99bd0 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/mod.rs:490 [INFO] [stderr] 488 │ [INFO] [stderr] 489 │ match &self.kind { [INFO] [stderr]  490 >  Kind::CurrentThread(exec) => exec.block_on(future), [INFO] [stderr] 491 │ #[cfg(all(feature = "rt-multi-thread", not(tokio_wasi)))] [INFO] [stderr] 492 │ Kind::MultiThread(exec) => exec.block_on(future), [INFO] [stderr] 25: qp2p::connection::tests::basic_usage::hbb92e6470f7f1d77 [INFO] [stderr] at /opt/rustwide/workdir/src/connection.rs:701 [INFO] [stderr] 699 │ timeout(peer2.wait_idle()).await?; [INFO] [stderr] 700 │ [INFO] [stderr]  701 >  Ok(()) [INFO] [stderr] 702 │ } [INFO] [stderr] 703 │ [INFO] [stderr] 26: qp2p::connection::tests::basic_usage::{{closure}}::he9be21f56cfc2c49 [INFO] [stderr] at /opt/rustwide/workdir/src/connection.rs:649 [INFO] [stderr] 647 │ #[tokio::test] [INFO] [stderr] 648 │ #[tracing_test::traced_test] [INFO] [stderr]  649 >  async fn basic_usage() -> Result<()> { [INFO] [stderr] 650 │ let config = InternalConfig::try_from_config(Default::default())?; [INFO] [stderr] 651 │ [INFO] [stderr] 27: core::ops::function::FnOnce::call_once::h2a3d886de65eb981 [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/ops/function.rs:248 [INFO] [stderr] 28: core::ops::function::FnOnce::call_once::hae127a4052d969da [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/ops/function.rs:248 [INFO] [stderr] 29: test::__rust_begin_short_backtrace::hc9cf07dacb3ad40a [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/test/src/lib.rs:572 [INFO] [stderr] 30:  as core::ops::function::FnOnce>::call_once::h4b1db4c653ec54dd [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/alloc/src/boxed.rs:1940 [INFO] [stderr] 31:  as core::ops::function::FnOnce<()>>::call_once::h13b5215634a67f28 [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/panic/unwind_safe.rs:271 [INFO] [stderr] 32: std::panicking::try::do_call::hc26017491df10edc [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/panicking.rs:492 [INFO] [stderr] 33: std::panicking::try::h8b80e6a6e965fdcc [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/panicking.rs:456 [INFO] [stderr] 34: std::panic::catch_unwind::hf03e4203f471ef17 [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/panic.rs:137 [INFO] [stderr] 35: test::run_test_in_process::h5ab5341b491129ba [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/test/src/lib.rs:595 [INFO] [stderr]  ⋮ 15 frames hidden ⋮  [INFO] [stderr] [INFO] [stderr] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. [INFO] [stderr] Warning: SpanTrace capture is Unsupported. [INFO] [stderr] Ensure that you've setup a tracing-error ErrorLayer and the semver versions are compatible [INFO] [stderr] Error: [INFO] [stderr] 0: deadline has elapsed [INFO] [stderr] [INFO] [stderr] Location: [INFO] [stderr] src/connection.rs:873 [INFO] [stderr] [INFO] [stderr] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stderr]  ⋮ 5 frames hidden ⋮  [INFO] [stderr] 6:  as core::ops::try_trait::FromResidual>>::from_residual::h2e1b5e643b988c1b [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/result.rs:2105 [INFO] [stderr] 7: qp2p::connection::tests::endpoint_verification::{{closure}}::h47630c8721336dbc [INFO] [stderr] at /opt/rustwide/workdir/src/connection.rs:873 [INFO] [stderr] 871 │ [INFO] [stderr] 872 │ // check the connections were shutdown on drop [INFO] [stderr]  873 >  timeout(peer1.wait_idle()).await?; [INFO] [stderr] 874 │ timeout(peer2.wait_idle()).await?; [INFO] [stderr] 875 │ [INFO] [stderr] 8:  as core::future::future::Future>::poll::h81657b030e532ae4 [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/future/mod.rs:91 [INFO] [stderr] 9:  as core::future::future::Future>::poll::h36418cb65d9d239b [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/future/future.rs:124 [INFO] [stderr] 10: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::h0fb55dd63a931812 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:525 [INFO] [stderr] 523 │ if core.spawner.reset_woken() { [INFO] [stderr] 524 │ let (c, res) = context.enter(core, || { [INFO] [stderr]  525 >  crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stderr] 526 │ }); [INFO] [stderr] 527 │ [INFO] [stderr] 11: tokio::coop::with_budget::{{closure}}::h419063af402fcc88 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/coop.rs:102 [INFO] [stderr] 100 │ let _guard = ResetGuard { cell, prev }; [INFO] [stderr] 101 │ [INFO] [stderr]  102 >  f() [INFO] [stderr] 103 │ }) [INFO] [stderr] 104 │ } [INFO] [stderr] 12: std::thread::local::LocalKey::try_with::h63063a1c89617be8 [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/thread/local.rs:445 [INFO] [stderr] 13: std::thread::local::LocalKey::with::h274661465d804a14 [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/thread/local.rs:421 [INFO] [stderr] 14: tokio::coop::with_budget::hc0bee5a3389952b2 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/coop.rs:95 [INFO] [stderr] 93 │ } [INFO] [stderr] 94 │ [INFO] [stderr]  95 >  CURRENT.with(move |cell| { [INFO] [stderr] 96 │ let prev = cell.get(); [INFO] [stderr] 97 │ [INFO] [stderr] 15: tokio::coop::budget::h93767439c34e3c46 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/coop.rs:72 [INFO] [stderr] 70 │ #[inline(always)] [INFO] [stderr] 71 │ pub(crate) fn budget(f: impl FnOnce() -> R) -> R { [INFO] [stderr]  72 >  with_budget(Budget::initial(), f) [INFO] [stderr] 73 │ } [INFO] [stderr] 74 │ [INFO] [stderr] 16: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::h782e4ed21b7feed2 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:525 [INFO] [stderr] 523 │ if core.spawner.reset_woken() { [INFO] [stderr] 524 │ let (c, res) = context.enter(core, || { [INFO] [stderr]  525 >  crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stderr] 526 │ }); [INFO] [stderr] 527 │ [INFO] [stderr] 17: tokio::runtime::scheduler::current_thread::Context::enter::hdf5b9139075ef8ef [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:349 [INFO] [stderr] 347 │ [INFO] [stderr] 348 │ // Execute the closure while tracking the execution budget [INFO] [stderr]  349 >  let ret = f(); [INFO] [stderr] 350 │ [INFO] [stderr] 351 │ // Take the scheduler core back [INFO] [stderr] 18: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::hf2e5039e82904752 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:524 [INFO] [stderr] 522 │ 'outer: loop { [INFO] [stderr] 523 │ if core.spawner.reset_woken() { [INFO] [stderr]  524 >  let (c, res) = context.enter(core, || { [INFO] [stderr] 525 │ crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stderr] 526 │ }); [INFO] [stderr] 19: tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::hc410765c0d534f99 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:595 [INFO] [stderr] 593 │ [INFO] [stderr] 594 │ // Call the closure and place `core` back [INFO] [stderr]  595 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stderr] 596 │ [INFO] [stderr] 597 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stderr] 20: tokio::macros::scoped_tls::ScopedKey::set::h275091c37d04f2db [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/macros/scoped_tls.rs:61 [INFO] [stderr] 59 │ }; [INFO] [stderr] 60 │ [INFO] [stderr]  61 >  f() [INFO] [stderr] 62 │ } [INFO] [stderr] 63 │ [INFO] [stderr] 21: tokio::runtime::scheduler::current_thread::CoreGuard::enter::h5420d374da6a6058 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:595 [INFO] [stderr] 593 │ [INFO] [stderr] 594 │ // Call the closure and place `core` back [INFO] [stderr]  595 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stderr] 596 │ [INFO] [stderr] 597 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stderr] 22: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::h1045c40b56eddee6 [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:515 [INFO] [stderr] 513 │ #[track_caller] [INFO] [stderr] 514 │ fn block_on(self, future: F) -> F::Output { [INFO] [stderr]  515 >  let ret = self.enter(|mut core, context| { [INFO] [stderr] 516 │ let _enter = crate::runtime::enter(false); [INFO] [stderr] 517 │ let waker = context.spawner.waker_ref(); [INFO] [stderr] 23: tokio::runtime::scheduler::current_thread::CurrentThread::block_on::hf8c1512b13a4e29c [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/scheduler/current_thread.rs:161 [INFO] [stderr] 159 │ loop { [INFO] [stderr] 160 │ if let Some(core) = self.take_core() { [INFO] [stderr]  161 >  return core.block_on(future); [INFO] [stderr] 162 │ } else { [INFO] [stderr] 163 │ let mut enter = crate::runtime::enter(false); [INFO] [stderr] 24: tokio::runtime::Runtime::block_on::h5843aa449fb077af [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.1/src/runtime/mod.rs:490 [INFO] [stderr] 488 │ [INFO] [stderr] 489 │ match &self.kind { [INFO] [stderr]  490 >  Kind::CurrentThread(exec) => exec.block_on(future), [INFO] [stderr] 491 │ #[cfg(all(feature = "rt-multi-thread", not(tokio_wasi)))] [INFO] [stderr] 492 │ Kind::MultiThread(exec) => exec.block_on(future), [INFO] [stderr] 25: qp2p::connection::tests::endpoint_verification::h60639a85136c68f0 [INFO] [stderr] at /opt/rustwide/workdir/src/connection.rs:876 [INFO] [stderr] 874 │ timeout(peer2.wait_idle()).await?; [INFO] [stderr] 875 │ [INFO] [stderr]  876 >  Ok(()) [INFO] [stderr] 877 │ } [INFO] [stderr] 878 │ [INFO] [stderr] 26: qp2p::connection::tests::endpoint_verification::{{closure}}::h9f8c8fab9d9b0b3d [INFO] [stderr] at /opt/rustwide/workdir/src/connection.rs:804 [INFO] [stderr] 802 │ #[tokio::test] [INFO] [stderr] 803 │ #[tracing_test::traced_test] [INFO] [stderr]  804 >  async fn endpoint_verification() -> Result<()> { [INFO] [stderr] 805 │ let config = InternalConfig::try_from_config(Default::default())?; [INFO] [stderr] 806 │ [INFO] [stderr] 27: core::ops::function::FnOnce::call_once::hd201cf1b58591bee [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/ops/function.rs:248 [INFO] [stderr] 28: core::ops::function::FnOnce::call_once::hae127a4052d969da [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/ops/function.rs:248 [INFO] [stderr] 29: test::__rust_begin_short_backtrace::hc9cf07dacb3ad40a [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/test/src/lib.rs:572 [INFO] [stderr] 30:  as core::ops::function::FnOnce>::call_once::h4b1db4c653ec54dd [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/alloc/src/boxed.rs:1940 [INFO] [stderr] 31:  as core::ops::function::FnOnce<()>>::call_once::h13b5215634a67f28 [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/panic/unwind_safe.rs:271 [INFO] [stderr] 32: std::panicking::try::do_call::hc26017491df10edc [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/panicking.rs:492 [INFO] [stderr] 33: std::panicking::try::h8b80e6a6e965fdcc [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/panicking.rs:456 [INFO] [stderr] 34: std::panic::catch_unwind::hf03e4203f471ef17 [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/panic.rs:137 [INFO] [stderr] 35: test::run_test_in_process::h5ab5341b491129ba [INFO] [stderr] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/test/src/lib.rs:595 [INFO] [stderr]  ⋮ 15 frames hidden ⋮  [INFO] [stderr] [INFO] [stderr] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. [INFO] [stderr] Warning: SpanTrace capture is Unsupported. [INFO] [stderr] Ensure that you've setup a tracing-error ErrorLayer and the semver versions are compatible [INFO] [stdout] test connection::tests::basic_usage ... FAILED [INFO] [stdout] test connection::tests::endpoint_verification ... FAILED [INFO] [stdout] test tests::common::simultaneous_incoming_and_outgoing_connections ... ok [INFO] [stdout] test tests::common::single_message ... ok [INFO] [stdout] test tests::common::successful_connection ... ok [INFO] [stdout] test tests::common::no_reuse_incoming_connection ... ok [INFO] [stdout] test tests::common::multiple_connections_with_many_concurrent_messages ... ok [INFO] [stdout] test tests::common::no_reuse_outgoing_connection ... ok [INFO] [stdout] test tests::common::many_messages ... ok [INFO] [stdout] test tests::common::multiple_concurrent_connects_to_the_same_peer ... ok [INFO] [stdout] test tests::common::reachability ... ok [INFO] [stdout] test tests::common::multiple_connections_with_many_larger_concurrent_messages has been running for over 60 seconds [INFO] [stdout] test tests::common::multiple_connections_with_many_larger_concurrent_messages ... ok [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- connection::tests::basic_usage stdout ---- [INFO] [stdout] Sep 22 07:28:53.838 TRACE basic_usage: qp2p::connection: Started listener for incoming uni-streams from 127.0.0.1:40656 [INFO] [stdout] Sep 22 07:28:53.841 TRACE basic_usage: qp2p::connection: Started listener for incoming bi-streams from 127.0.0.1:40656 [INFO] [stdout] Sep 22 07:28:53.843 TRACE basic_usage: qp2p::connection: Started listener for incoming uni-streams from 127.0.0.1:51780 [INFO] [stdout] Sep 22 07:28:53.843 TRACE basic_usage: qp2p::connection: Started listener for incoming bi-streams from 127.0.0.1:51780 [INFO] [stdout] Sep 22 07:28:53.844 TRACE basic_usage: qp2p::connection: Handling incoming uni-stream from 127.0.0.1:51780 [INFO] [stdout] Sep 22 07:28:53.845 TRACE basic_usage: qp2p::connection: Handling incoming uni-stream from 127.0.0.1:40656 [INFO] [stdout] Sep 22 07:28:53.846 TRACE basic_usage: qp2p::connection: Stopped listener for incoming bi-streams from 127.0.0.1:51780: connection handles dropped [INFO] [stdout] Sep 22 07:28:53.846 TRACE basic_usage: qp2p::connection: Stopped listener for incoming uni-streams from 127.0.0.1:51780: connection handles dropped [INFO] [stdout] Sep 22 07:28:53.846 TRACE basic_usage: qp2p::connection: Stopped listener for incoming uni-streams from 127.0.0.1:51780: stream finished [INFO] [stdout] Sep 22 07:28:53.846 TRACE basic_usage: qp2p::connection: Stopped listener for incoming bi-streams from 127.0.0.1:40656: connection handles dropped [INFO] [stdout] Sep 22 07:28:53.846 TRACE basic_usage: qp2p::connection: Stopped listener for incoming uni-streams from 127.0.0.1:40656: connection handles dropped [INFO] [stdout] Sep 22 07:28:53.846 TRACE basic_usage: qp2p::connection: Stopped listener for incoming uni-streams from 127.0.0.1:40656: stream finished [INFO] [stdout] The application panicked (crashed). [INFO] [stdout] Message: assertion failed: `(left == right)` [INFO] [stdout] left: `1`, [INFO] [stdout] right: `0`: the test returned a termination value with a non-zero status code (1) which indicates a failure [INFO] [stdout] Location: /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/test/src/lib.rs:184 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 7 frames hidden ⋮  [INFO] [stdout] 8: core::panicking::assert_failed_inner::he7aad82125e01d8a [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/panicking.rs: [INFO] [stdout] 9: core::panicking::assert_failed::hea4e645be5b0c542 [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/panicking.rs:181 [INFO] [stdout] 10: test::assert_test_result::hafd88fa476254edb [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/test/src/lib.rs:184 [INFO] [stdout] 11: qp2p::connection::tests::basic_usage::{{closure}}::he9be21f56cfc2c49 [INFO] [stdout] at /opt/rustwide/workdir/src/connection.rs:649 [INFO] [stdout] 647 │ #[tokio::test] [INFO] [stdout] 648 │ #[tracing_test::traced_test] [INFO] [stdout]  649 >  async fn basic_usage() -> Result<()> { [INFO] [stdout] 650 │ let config = InternalConfig::try_from_config(Default::default())?; [INFO] [stdout] 651 │ [INFO] [stdout] 12: core::ops::function::FnOnce::call_once::h2a3d886de65eb981 [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/ops/function.rs:248 [INFO] [stdout] 13: core::ops::function::FnOnce::call_once::hae127a4052d969da [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/ops/function.rs:248 [INFO] [stdout] 14: test::__rust_begin_short_backtrace::hc9cf07dacb3ad40a [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/test/src/lib.rs:572 [INFO] [stdout] 15:  as core::ops::function::FnOnce>::call_once::h4b1db4c653ec54dd [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/alloc/src/boxed.rs:1940 [INFO] [stdout] 16:  as core::ops::function::FnOnce<()>>::call_once::h13b5215634a67f28 [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/panic/unwind_safe.rs:271 [INFO] [stdout] 17: std::panicking::try::do_call::hc26017491df10edc [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/panicking.rs:492 [INFO] [stdout] 18: std::panicking::try::h8b80e6a6e965fdcc [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/panicking.rs:456 [INFO] [stdout] 19: std::panic::catch_unwind::hf03e4203f471ef17 [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/panic.rs:137 [INFO] [stdout] 20: test::run_test_in_process::h5ab5341b491129ba [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/test/src/lib.rs:595 [INFO] [stdout]  ⋮ 15 frames hidden ⋮  [INFO] [stdout] [INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. [INFO] [stdout] [INFO] [stdout] ---- connection::tests::endpoint_verification stdout ---- [INFO] [stdout] Sep 22 07:28:53.762 TRACE endpoint_verification: qp2p::connection: Started listener for incoming uni-streams from 127.0.0.1:50214 [INFO] [stdout] Sep 22 07:28:53.766 TRACE endpoint_verification: qp2p::connection: Started listener for incoming bi-streams from 127.0.0.1:50214 [INFO] [stdout] Sep 22 07:28:53.828 TRACE endpoint_verification: qp2p::connection: Started listener for incoming uni-streams from 127.0.0.1:49359 [INFO] [stdout] Sep 22 07:28:53.828 TRACE endpoint_verification: qp2p::connection: Started listener for incoming bi-streams from 127.0.0.1:49359 [INFO] [stdout] Sep 22 07:28:53.829 TRACE endpoint_verification: qp2p::connection: Handling incoming bi-stream from 127.0.0.1:49359 [INFO] [stdout] Sep 22 07:28:53.829 TRACE endpoint_verification: qp2p::connection: Performing endpoint verification for 127.0.0.1:49359 [INFO] [stdout] Sep 22 07:28:53.829 TRACE endpoint_verification: qp2p::connection: EndpointVerificationReq: opening new connection to 127.0.0.1:49359 [INFO] [stdout] Sep 22 07:28:53.929 TRACE endpoint_verification: qp2p::connection: EndpointVerificationReq: sending EndpointEchoReq to 127.0.0.1:49359 over connection 139757766616800 [INFO] [stdout] Sep 22 07:28:53.930 TRACE endpoint_verification: qp2p::connection: Started listener for incoming uni-streams from 127.0.0.1:50214 [INFO] [stdout] Sep 22 07:28:53.930 TRACE endpoint_verification: qp2p::connection: Started listener for incoming bi-streams from 127.0.0.1:50214 [INFO] [stdout] Sep 22 07:28:53.930 TRACE endpoint_verification: qp2p::connection: Handling incoming bi-stream from 127.0.0.1:50214 [INFO] [stdout] Sep 22 07:28:53.930 TRACE endpoint_verification: qp2p::connection: Replying to EndpointEchoReq from 127.0.0.1:50214 [INFO] [stdout] Sep 22 07:28:53.959 TRACE endpoint_verification: qp2p::connection: EndpointVerificationReq: Received EndpointEchoResp from 127.0.0.1:49359 [INFO] [stdout] Sep 22 07:28:53.996 WARN endpoint_verification: qp2p::connection: Benign error ignored Closed(Application { error_code: 0, reason: b"" }) [INFO] [stdout] Sep 22 07:28:53.996 TRACE endpoint_verification: qp2p::connection: Stopped listener for incoming bi-streams from 127.0.0.1:50214: stream ended [INFO] [stdout] Sep 22 07:28:53.996 WARN endpoint_verification: qp2p::connection: Benign error ignored Closed(Application { error_code: 0, reason: b"" }) [INFO] [stdout] Sep 22 07:28:53.996 TRACE endpoint_verification: qp2p::connection: Stopped listener for incoming uni-streams from 127.0.0.1:50214: stream finished [INFO] [stdout] Sep 22 07:28:53.997 TRACE endpoint_verification: qp2p::connection: Performing endpoint verification for 127.0.0.1:0 [INFO] [stdout] Sep 22 07:28:53.997 TRACE endpoint_verification: qp2p::connection: EndpointVerificationReq: opening new connection to 127.0.0.1:0 [INFO] [stdout] Sep 22 07:28:53.997 WARN endpoint_verification: qp2p::connection: Endpoint verification for 127.0.0.1:0 failed: Send(ConnectionLost(InvalidAddress(127.0.0.1:0))) [INFO] [stdout] Sep 22 07:28:53.998 TRACE endpoint_verification: qp2p::connection: Stopped listener for incoming bi-streams from 127.0.0.1:49359: connection handles dropped [INFO] [stdout] Sep 22 07:28:53.998 TRACE endpoint_verification: qp2p::connection: Stopped listener for incoming uni-streams from 127.0.0.1:49359: connection handles dropped [INFO] [stdout] Sep 22 07:28:53.998 TRACE endpoint_verification: qp2p::connection: Stopped listener for incoming uni-streams from 127.0.0.1:49359: stream finished [INFO] [stdout] Sep 22 07:28:54.002 TRACE endpoint_verification: qp2p::connection: Stopped listener for incoming bi-streams from 127.0.0.1:50214: connection handles dropped [INFO] [stdout] Sep 22 07:28:54.002 TRACE endpoint_verification: qp2p::connection: Stopped listener for incoming uni-streams from 127.0.0.1:50214: connection handles dropped [INFO] [stdout] Sep 22 07:28:54.002 TRACE endpoint_verification: qp2p::connection: Stopped listener for incoming uni-streams from 127.0.0.1:50214: stream finished [INFO] [stdout] The application panicked (crashed). [INFO] [stdout] Message: assertion failed: `(left == right)` [INFO] [stdout] left: `1`, [INFO] [stdout] right: `0`: the test returned a termination value with a non-zero status code (1) which indicates a failure [INFO] [stdout] Location: /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/test/src/lib.rs:184 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 7 frames hidden ⋮  [INFO] [stdout] 8: core::panicking::assert_failed_inner::he7aad82125e01d8a [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/panicking.rs: [INFO] [stdout] 9: core::panicking::assert_failed::hea4e645be5b0c542 [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/panicking.rs:181 [INFO] [stdout] 10: test::assert_test_result::hafd88fa476254edb [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/test/src/lib.rs:184 [INFO] [stdout] 11: qp2p::connection::tests::endpoint_verification::{{closure}}::h9f8c8fab9d9b0b3d [INFO] [stdout] at /opt/rustwide/workdir/src/connection.rs:804 [INFO] [stdout] 802 │ #[tokio::test] [INFO] [stdout] 803 │ #[tracing_test::traced_test] [INFO] [stdout]  804 >  async fn endpoint_verification() -> Result<()> { [INFO] [stdout] 805 │ let config = InternalConfig::try_from_config(Default::default())?; [INFO] [stdout] 806 │ [INFO] [stdout] 12: core::ops::function::FnOnce::call_once::hd201cf1b58591bee [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/ops/function.rs:248 [INFO] [stdout] 13: core::ops::function::FnOnce::call_once::hae127a4052d969da [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/ops/function.rs:248 [INFO] [stdout] 14: test::__rust_begin_short_backtrace::hc9cf07dacb3ad40a [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/test/src/lib.rs:572 [INFO] [stdout] 15:  as core::ops::function::FnOnce>::call_once::h4b1db4c653ec54dd [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/alloc/src/boxed.rs:1940 [INFO] [stdout] 16:  as core::ops::function::FnOnce<()>>::call_once::h13b5215634a67f28 [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/core/src/panic/unwind_safe.rs:271 [INFO] [stdout] 17: std::panicking::try::do_call::hc26017491df10edc [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/panicking.rs:492 [INFO] [stdout] 18: std::panicking::try::h8b80e6a6e965fdcc [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/panicking.rs:456 [INFO] [stdout] 19: std::panic::catch_unwind::hf03e4203f471ef17 [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/std/src/panic.rs:137 [INFO] [stdout] 20: test::run_test_in_process::h5ab5341b491129ba [INFO] [stdout] at /rustc/acb8934fd57b3c2740c4abac0a5728c2c9b1423b/library/test/src/lib.rs:595 [INFO] [stdout]  ⋮ 15 frames hidden ⋮  [INFO] [stdout] [INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] connection::tests::basic_usage [INFO] [stdout] connection::tests::endpoint_verification [INFO] [stdout] [INFO] [stdout] test result: FAILED. 18 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 238.60s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "8a3c7618a2b08790f768eb33dc2e7a5248949e7f27c3b9d6dbd1453b18344207", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "8a3c7618a2b08790f768eb33dc2e7a5248949e7f27c3b9d6dbd1453b18344207", kill_on_drop: false }` [INFO] [stdout] 8a3c7618a2b08790f768eb33dc2e7a5248949e7f27c3b9d6dbd1453b18344207