[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] da9d885ae85faf322570d25ae7ebe41f1bb382e6 [INFO] testing ustulation/using-quinn against master#c5842b0be783dffa5a49693541acb79115c7eeef for pr-102570 [INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fustulation%2Fusing-quinn" "/workspace/builds/worker-1/source", kill_on_drop: false }` [INFO] [stderr] Cloning into '/workspace/builds/worker-1/source'... [INFO] [stderr] done. [INFO] validating manifest of git repo https://github.com/ustulation/using-quinn on toolchain c5842b0be783dffa5a49693541acb79115c7eeef [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+c5842b0be783dffa5a49693541acb79115c7eeef" "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-1/source/Cargo.toml [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+c5842b0be783dffa5a49693541acb79115c7eeef" "generate-lockfile" "--manifest-path" "Cargo.toml" "-Zno-index-update", kill_on_drop: false }` [INFO] running `Command { std: "/workspace/cargo-home/bin/cargo" "+c5842b0be783dffa5a49693541acb79115c7eeef" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded rcgen v0.9.3 [INFO] [stderr] Downloaded yasna v0.5.0 [INFO] [stderr] Downloaded pem v1.1.0 [INFO] [stderr] Downloaded time-core v0.1.0 [INFO] [stderr] Downloaded time v0.3.17 [INFO] [stderr] Downloaded quinn-proto v0.8.4 [INFO] [stderr] Downloaded xmltree v0.10.3 [INFO] [stderr] Downloaded quinn v0.8.5 [INFO] [stderr] Downloaded rustls-pemfile v1.0.1 [INFO] [stderr] Downloaded igd v0.12.0 [INFO] [stderr] Downloaded tracing-test-macro v0.1.0 [INFO] [stderr] Downloaded tracing-test v0.1.0 [INFO] [stderr] Downloaded rustls-native-certs v0.6.2 [INFO] [stderr] Downloaded rustls v0.20.7 [INFO] [stderr] Downloaded quinn-udp v0.1.3 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+c5842b0be783dffa5a49693541acb79115c7eeef" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] df20c1fe44561ef1924c275c1c90aa03fde90ca34848cea294058047139fb0f1 [INFO] running `Command { std: "docker" "start" "-a" "df20c1fe44561ef1924c275c1c90aa03fde90ca34848cea294058047139fb0f1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "df20c1fe44561ef1924c275c1c90aa03fde90ca34848cea294058047139fb0f1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "df20c1fe44561ef1924c275c1c90aa03fde90ca34848cea294058047139fb0f1", kill_on_drop: false }` [INFO] [stdout] df20c1fe44561ef1924c275c1c90aa03fde90ca34848cea294058047139fb0f1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+c5842b0be783dffa5a49693541acb79115c7eeef" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 8d2a70657de8bf0ae36110e0a48d26b720168fa940f55e9a9d9ecdab958d3ecd [INFO] running `Command { std: "docker" "start" "-a" "8d2a70657de8bf0ae36110e0a48d26b720168fa940f55e9a9d9ecdab958d3ecd", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.47 [INFO] [stderr] Compiling unicode-ident v1.0.5 [INFO] [stderr] Compiling quote v1.0.21 [INFO] [stderr] Compiling syn v1.0.103 [INFO] [stderr] Compiling libc v0.2.137 [INFO] [stderr] Compiling autocfg v1.1.0 [INFO] [stderr] Compiling cfg-if v1.0.0 [INFO] [stderr] Compiling serde_derive v1.0.147 [INFO] [stderr] Compiling serde v1.0.147 [INFO] [stderr] Compiling once_cell v1.16.0 [INFO] [stderr] Compiling pin-project-lite v0.2.9 [INFO] [stderr] Compiling log v0.4.17 [INFO] [stderr] Compiling memchr v2.5.0 [INFO] [stderr] Compiling futures-core v0.3.25 [INFO] [stderr] Compiling cc v1.0.76 [INFO] [stderr] Compiling futures-task v0.3.25 [INFO] [stderr] Compiling futures-channel v0.3.25 [INFO] [stderr] Compiling futures-sink v0.3.25 [INFO] [stderr] Compiling futures-util v0.3.25 [INFO] [stderr] Compiling tinyvec_macros v0.1.0 [INFO] [stderr] Compiling tinyvec v1.6.0 [INFO] [stderr] Compiling tracing-core v0.1.30 [INFO] [stderr] Compiling futures-io v0.3.25 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling untrusted v0.7.1 [INFO] [stderr] Compiling spin v0.5.2 [INFO] [stderr] Compiling ppv-lite86 v0.2.17 [INFO] [stderr] Compiling fnv v1.0.7 [INFO] [stderr] Compiling itoa v1.0.4 [INFO] [stderr] Compiling base64 v0.13.1 [INFO] [stderr] Compiling thiserror v1.0.37 [INFO] [stderr] Compiling rustls v0.20.7 [INFO] [stderr] Compiling hashbrown v0.12.3 [INFO] [stderr] Compiling unicode-bidi v0.3.8 [INFO] [stderr] Compiling httparse v1.8.0 [INFO] [stderr] Compiling slab v0.4.7 [INFO] [stderr] Compiling tokio v1.21.2 [INFO] [stderr] Compiling indexmap v1.9.1 [INFO] [stderr] Compiling byteorder v1.4.3 [INFO] [stderr] Compiling percent-encoding v2.2.0 [INFO] [stderr] Compiling fxhash v0.2.1 [INFO] [stderr] Compiling rustls-pemfile v0.2.1 [INFO] [stderr] Compiling try-lock v0.2.3 [INFO] [stderr] Compiling form_urlencoded v1.1.0 [INFO] [stderr] Compiling time-core v0.1.0 [INFO] [stderr] Compiling unicode-normalization v0.1.22 [INFO] [stderr] Compiling time v0.3.17 [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 tower-service v0.3.2 [INFO] [stderr] Compiling socket2 v0.4.7 [INFO] [stderr] Compiling mio v0.8.5 [INFO] [stderr] Compiling getrandom v0.2.8 [INFO] [stderr] Compiling pem v1.1.0 [INFO] [stderr] Compiling instant v0.1.12 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling yasna v0.5.0 [INFO] [stderr] Compiling idna v0.3.0 [INFO] [stderr] Compiling ring v0.16.20 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling xmltree v0.10.3 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling url v2.3.1 [INFO] [stderr] Compiling sct v0.7.0 [INFO] [stderr] Compiling webpki v0.22.0 [INFO] [stderr] Compiling rcgen v0.9.3 [INFO] [stderr] Compiling tracing-attributes v0.1.23 [INFO] [stderr] Compiling futures-macro v0.3.25 [INFO] [stderr] Compiling thiserror-impl v1.0.37 [INFO] [stderr] Compiling pin-project-internal v1.0.12 [INFO] [stderr] Compiling pin-project v1.0.12 [INFO] [stderr] Compiling tracing v0.1.37 [INFO] [stderr] Compiling futures-executor v0.3.25 [INFO] [stderr] Compiling futures v0.3.25 [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.15 [INFO] [stderr] Compiling hyper v0.14.23 [INFO] [stderr] Compiling igd v0.12.0 [INFO] [stderr] Compiling qp2p v0.30.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished dev [unoptimized + debuginfo] target(s) in 34.85s [INFO] running `Command { std: "docker" "inspect" "8d2a70657de8bf0ae36110e0a48d26b720168fa940f55e9a9d9ecdab958d3ecd", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "8d2a70657de8bf0ae36110e0a48d26b720168fa940f55e9a9d9ecdab958d3ecd", kill_on_drop: false }` [INFO] [stdout] 8d2a70657de8bf0ae36110e0a48d26b720168fa940f55e9a9d9ecdab958d3ecd [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+c5842b0be783dffa5a49693541acb79115c7eeef" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 5a7121586a0857904b178e4c56fce33e9dd052435eae5b9eef3a3d7f9761cbd1 [INFO] running `Command { std: "docker" "start" "-a" "5a7121586a0857904b178e4c56fce33e9dd052435eae5b9eef3a3d7f9761cbd1", kill_on_drop: false }` [INFO] [stderr] Compiling log v0.4.17 [INFO] [stderr] Compiling tracing-core v0.1.30 [INFO] [stderr] Compiling tokio v1.21.2 [INFO] [stderr] Compiling num-traits v0.2.15 [INFO] [stderr] Compiling num_cpus v1.14.0 [INFO] [stderr] Compiling tokio-macros v1.8.0 [INFO] [stderr] Compiling num-integer v0.1.45 [INFO] [stderr] Compiling regex-syntax v0.6.28 [INFO] [stderr] Compiling lazy_static v1.4.0 [INFO] [stderr] Compiling serde_json v1.0.87 [INFO] [stderr] Compiling ryu v1.0.11 [INFO] [stderr] Compiling iana-time-zone v0.1.53 [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 smallvec v1.10.0 [INFO] [stderr] Compiling sharded-slab v0.1.4 [INFO] [stderr] Compiling openssl-probe v0.1.5 [INFO] [stderr] Compiling getrandom v0.1.16 [INFO] [stderr] Compiling backtrace v0.3.66 [INFO] [stderr] Compiling rustls-native-certs v0.6.2 [INFO] [stderr] Compiling adler v1.0.2 [INFO] [stderr] Compiling crunchy v0.2.2 [INFO] [stderr] Compiling eyre v0.6.8 [INFO] [stderr] Compiling gimli v0.26.2 [INFO] [stderr] Compiling tracing v0.1.37 [INFO] [stderr] Compiling mio v0.8.5 [INFO] [stderr] Compiling tracing-log v0.1.3 [INFO] [stderr] Compiling tracing-serde v0.1.3 [INFO] [stderr] Compiling quinn-proto v0.8.4 [INFO] [stderr] Compiling want v0.3.0 [INFO] [stderr] Compiling attohttpc v0.16.3 [INFO] [stderr] Compiling rand_core v0.5.1 [INFO] [stderr] Compiling miniz_oxide v0.5.4 [INFO] [stderr] Compiling object v0.29.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 rand_chacha v0.2.2 [INFO] [stderr] Compiling tracing-test-macro v0.1.0 [INFO] [stderr] Compiling rand v0.7.3 [INFO] [stderr] Compiling ctor v0.1.26 [INFO] [stderr] Compiling chrono v0.4.22 [INFO] [stderr] Compiling regex-automata v0.1.10 [INFO] [stderr] Compiling regex v1.7.0 [INFO] [stderr] Compiling addr2line v0.17.0 [INFO] [stderr] Compiling matchers v0.0.1 [INFO] [stderr] Compiling tracing-subscriber v0.2.25 [INFO] [stderr] Compiling tracing-error v0.1.2 [INFO] [stderr] Compiling tracing-test v0.1.0 [INFO] [stderr] Compiling color-spantrace v0.1.6 [INFO] [stderr] Compiling color-eyre v0.5.11 [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.15 [INFO] [stderr] Compiling hyper v0.14.23 [INFO] [stderr] Compiling igd v0.12.0 [INFO] [stderr] Compiling qp2p v0.30.1 (/opt/rustwide/workdir) [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 28.19s [INFO] running `Command { std: "docker" "inspect" "5a7121586a0857904b178e4c56fce33e9dd052435eae5b9eef3a3d7f9761cbd1", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "5a7121586a0857904b178e4c56fce33e9dd052435eae5b9eef3a3d7f9761cbd1", kill_on_drop: false }` [INFO] [stdout] 5a7121586a0857904b178e4c56fce33e9dd052435eae5b9eef3a3d7f9761cbd1 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1/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:bb5b0feb4f87116a4ea905b7ff5bf5b85913db05e9f0fbc1abdd3168928d9075" "/opt/rustwide/cargo-home/bin/cargo" "+c5842b0be783dffa5a49693541acb79115c7eeef" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] dd8ef5d8d48c920e70c8d03d496547e55a63ebc49a21f4f1a919362a3e5b20bf [INFO] running `Command { std: "docker" "start" "-a" "dd8ef5d8d48c920e70c8d03d496547e55a63ebc49a21f4f1a919362a3e5b20bf", kill_on_drop: false }` [INFO] [stderr] Finished test [unoptimized + debuginfo] target(s) in 0.26s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/qp2p-6ffb7d07c7ae11c4) [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_port ... ok [INFO] [stdout] test endpoint::tests::new_with_external_ip ... ok [INFO] [stdout] test endpoint::tests::new_with_external_addr ... ok [INFO] [stdout] test tests::common::successful_connection ... ok [INFO] [stdout] test connection::tests::test_endpoint_echo ... ok [INFO] [stdout] test connection::tests::endpoint_verification ... ok [INFO] [stdout] test tests::common::single_message ... ok [INFO] [stdout] test tests::common::connection_attempts_to_bootstrap_contacts_should_succeed ... ok [INFO] [stdout] test tests::common::simultaneous_incoming_and_outgoing_connections ... ok [INFO] [stdout] test tests::common::client ... ok [INFO] [stdout] test connection::tests::benign_connection_loss ... ok [INFO] [stdout] test connection::tests::basic_usage ... FAILED [INFO] [stdout] test tests::common::no_reuse_incoming_connection ... ok [INFO] [stdout] test tests::common::no_reuse_outgoing_connection ... ok [INFO] [stdout] test tests::common::multiple_connections_with_many_concurrent_messages ... ok [INFO] [stdout] test tests::common::many_messages ... ok [INFO] [stdout] test tests::common::reachability ... ok [INFO] [stdout] test tests::common::multiple_concurrent_connects_to_the_same_peer ... 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] Nov 09 23:45:22.161 TRACE basic_usage: qp2p::connection: Started listener for incoming uni-streams from 127.0.0.1:43502 [INFO] [stdout] Nov 09 23:45:22.267 TRACE basic_usage: qp2p::connection: Started listener for incoming bi-streams from 127.0.0.1:43502 [INFO] [stdout] Nov 09 23:45:22.280 TRACE basic_usage: qp2p::connection: Started listener for incoming uni-streams from 127.0.0.1:46801 [INFO] [stdout] Nov 09 23:45:22.280 TRACE basic_usage: qp2p::connection: Started listener for incoming bi-streams from 127.0.0.1:46801 [INFO] [stdout] Nov 09 23:45:22.288 TRACE basic_usage: qp2p::connection: Handling incoming uni-stream from 127.0.0.1:46801 [INFO] [stdout] Nov 09 23:45:22.350 TRACE basic_usage: qp2p::connection: Handling incoming uni-stream from 127.0.0.1:43502 [INFO] [stdout] Nov 09 23:45:22.350 TRACE basic_usage: qp2p::connection: Stopped listener for incoming bi-streams from 127.0.0.1:46801: connection handles dropped [INFO] [stdout] Nov 09 23:45:22.350 TRACE basic_usage: qp2p::connection: Stopped listener for incoming uni-streams from 127.0.0.1:46801: connection handles dropped [INFO] [stdout] Nov 09 23:45:22.369 TRACE basic_usage: qp2p::connection: Stopped listener for incoming uni-streams from 127.0.0.1:46801: stream finished [INFO] [stdout] Nov 09 23:45:22.370 TRACE basic_usage: qp2p::connection: Stopped listener for incoming bi-streams from 127.0.0.1:43502: connection handles dropped [INFO] [stdout] Nov 09 23:45:22.370 TRACE basic_usage: qp2p::connection: Stopped listener for incoming uni-streams from 127.0.0.1:43502: connection handles dropped [INFO] [stdout] Nov 09 23:45:22.370 TRACE basic_usage: qp2p::connection: Stopped listener for incoming uni-streams from 127.0.0.1:43502: stream finished [INFO] [stdout] Error: [INFO] [stdout] 0: deadline has elapsed [INFO] [stdout] [INFO] [stdout] Location: [INFO] [stdout] src/connection.rs:711 [INFO] [stdout] [INFO] [stdout] ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ BACKTRACE ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ [INFO] [stdout]  ⋮ 5 frames hidden ⋮  [INFO] [stdout] 6:  as core::ops::try_trait::FromResidual>>::from_residual::h7dd2866ca2eb736a [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/result.rs:2108 [INFO] [stdout] 7: qp2p::connection::tests::basic_usage::{{closure}}::h72125aa481d0bcbb [INFO] [stdout] at /opt/rustwide/workdir/src/connection.rs:711 [INFO] [stdout] 709 │ [INFO] [stdout] 710 │ // check the connections were shutdown on drop [INFO] [stdout]  711 >  timeout(peer1.wait_idle()).await?; [INFO] [stdout] 712 │ timeout(peer2.wait_idle()).await?; [INFO] [stdout] 713 │ [INFO] [stdout] 8:  as core::future::future::Future>::poll::hb01ca973f9ea177b [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/future/mod.rs:91 [INFO] [stdout] 9:  as core::future::future::Future>::poll::h71bbe832fb84b571 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/future/future.rs:124 [INFO] [stdout] 10: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::{{closure}}::h6b9b38dc727b55d8 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/runtime/scheduler/current_thread.rs:525 [INFO] [stdout] 523 │ if core.spawner.reset_woken() { [INFO] [stdout] 524 │ let (c, res) = context.enter(core, || { [INFO] [stdout]  525 >  crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 526 │ }); [INFO] [stdout] 527 │ [INFO] [stdout] 11: tokio::coop::with_budget::{{closure}}::h6c99de0be98c0c81 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/coop.rs:102 [INFO] [stdout] 100 │ let _guard = ResetGuard { cell, prev }; [INFO] [stdout] 101 │ [INFO] [stdout]  102 >  f() [INFO] [stdout] 103 │ }) [INFO] [stdout] 104 │ } [INFO] [stdout] 12: std::thread::local::LocalKey::try_with::hf8e69f1b5c1853fd [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/thread/local.rs:446 [INFO] [stdout] 13: std::thread::local::LocalKey::with::h3178cb0f3d0d23b6 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/thread/local.rs:422 [INFO] [stdout] 14: tokio::coop::with_budget::h33a092207bd2ef6b [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/coop.rs:95 [INFO] [stdout] 93 │ } [INFO] [stdout] 94 │ [INFO] [stdout]  95 >  CURRENT.with(move |cell| { [INFO] [stdout] 96 │ let prev = cell.get(); [INFO] [stdout] 97 │ [INFO] [stdout] 15: tokio::coop::budget::h75d41c3ae7ea8759 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/coop.rs:72 [INFO] [stdout] 70 │ #[inline(always)] [INFO] [stdout] 71 │ pub(crate) fn budget(f: impl FnOnce() -> R) -> R { [INFO] [stdout]  72 >  with_budget(Budget::initial(), f) [INFO] [stdout] 73 │ } [INFO] [stdout] 74 │ [INFO] [stdout] 16: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::{{closure}}::hae487c7692c65213 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/runtime/scheduler/current_thread.rs:525 [INFO] [stdout] 523 │ if core.spawner.reset_woken() { [INFO] [stdout] 524 │ let (c, res) = context.enter(core, || { [INFO] [stdout]  525 >  crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 526 │ }); [INFO] [stdout] 527 │ [INFO] [stdout] 17: tokio::runtime::scheduler::current_thread::Context::enter::hcf5aeb796c9fca81 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/runtime/scheduler/current_thread.rs:349 [INFO] [stdout] 347 │ [INFO] [stdout] 348 │ // Execute the closure while tracking the execution budget [INFO] [stdout]  349 >  let ret = f(); [INFO] [stdout] 350 │ [INFO] [stdout] 351 │ // Take the scheduler core back [INFO] [stdout] 18: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::{{closure}}::h9385ed3006fbc8d9 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/runtime/scheduler/current_thread.rs:524 [INFO] [stdout] 522 │ 'outer: loop { [INFO] [stdout] 523 │ if core.spawner.reset_woken() { [INFO] [stdout]  524 >  let (c, res) = context.enter(core, || { [INFO] [stdout] 525 │ crate::coop::budget(|| future.as_mut().poll(&mut cx)) [INFO] [stdout] 526 │ }); [INFO] [stdout] 19: tokio::runtime::scheduler::current_thread::CoreGuard::enter::{{closure}}::h5ef9a08635d373e0 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/runtime/scheduler/current_thread.rs:595 [INFO] [stdout] 593 │ [INFO] [stdout] 594 │ // Call the closure and place `core` back [INFO] [stdout]  595 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stdout] 596 │ [INFO] [stdout] 597 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stdout] 20: tokio::macros::scoped_tls::ScopedKey::set::hca56f59be7cc323c [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/macros/scoped_tls.rs:61 [INFO] [stdout] 59 │ }; [INFO] [stdout] 60 │ [INFO] [stdout]  61 >  f() [INFO] [stdout] 62 │ } [INFO] [stdout] 63 │ [INFO] [stdout] 21: tokio::runtime::scheduler::current_thread::CoreGuard::enter::hbb0917a5a2e8c3ad [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/runtime/scheduler/current_thread.rs:595 [INFO] [stdout] 593 │ [INFO] [stdout] 594 │ // Call the closure and place `core` back [INFO] [stdout]  595 >  let (core, ret) = CURRENT.set(&self.context, || f(core, &self.context)); [INFO] [stdout] 596 │ [INFO] [stdout] 597 │ *self.context.core.borrow_mut() = Some(core); [INFO] [stdout] 22: tokio::runtime::scheduler::current_thread::CoreGuard::block_on::h6bc55db2ad08a6a0 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/runtime/scheduler/current_thread.rs:515 [INFO] [stdout] 513 │ #[track_caller] [INFO] [stdout] 514 │ fn block_on(self, future: F) -> F::Output { [INFO] [stdout]  515 >  let ret = self.enter(|mut core, context| { [INFO] [stdout] 516 │ let _enter = crate::runtime::enter(false); [INFO] [stdout] 517 │ let waker = context.spawner.waker_ref(); [INFO] [stdout] 23: tokio::runtime::scheduler::current_thread::CurrentThread::block_on::h9eb131aca1680112 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/runtime/scheduler/current_thread.rs:161 [INFO] [stdout] 159 │ loop { [INFO] [stdout] 160 │ if let Some(core) = self.take_core() { [INFO] [stdout]  161 >  return core.block_on(future); [INFO] [stdout] 162 │ } else { [INFO] [stdout] 163 │ let mut enter = crate::runtime::enter(false); [INFO] [stdout] 24: tokio::runtime::Runtime::block_on::hae5882a5e9d52610 [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/github.com-1ecc6299db9ec823/tokio-1.21.2/src/runtime/mod.rs:490 [INFO] [stdout] 488 │ [INFO] [stdout] 489 │ match &self.kind { [INFO] [stdout]  490 >  Kind::CurrentThread(exec) => exec.block_on(future), [INFO] [stdout] 491 │ #[cfg(all(feature = "rt-multi-thread", not(tokio_wasi)))] [INFO] [stdout] 492 │ Kind::MultiThread(exec) => exec.block_on(future), [INFO] [stdout] 25: qp2p::connection::tests::basic_usage::h8007bf49de1d691f [INFO] [stdout] at /opt/rustwide/workdir/src/connection.rs:714 [INFO] [stdout] 712 │ timeout(peer2.wait_idle()).await?; [INFO] [stdout] 713 │ [INFO] [stdout]  714 >  Ok(()) [INFO] [stdout] 715 │ } [INFO] [stdout] 716 │ [INFO] [stdout] 26: qp2p::connection::tests::basic_usage::{{closure}}::h2442e4a1b2f8f5fb [INFO] [stdout] at /opt/rustwide/workdir/src/connection.rs:662 [INFO] [stdout] 660 │ #[tokio::test] [INFO] [stdout] 661 │ #[tracing_test::traced_test] [INFO] [stdout]  662 >  async fn basic_usage() -> Result<()> { [INFO] [stdout] 663 │ let config = InternalConfig::try_from_config(Default::default())?; [INFO] [stdout] 664 │ [INFO] [stdout] 27: core::ops::function::FnOnce::call_once::h2983d1d4f078b9f7 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/ops/function.rs:510 [INFO] [stdout] 28: core::ops::function::FnOnce::call_once::h5dde45172d790a9d [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/ops/function.rs:510 [INFO] [stdout] 29: test::__rust_begin_short_backtrace::h78e3fbe0504ae84c [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/test/src/lib.rs:637 [INFO] [stdout] 30: test::run_test::{{closure}}::hecbec538c508056c [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/test/src/lib.rs:628 [INFO] [stdout] 31: core::ops::function::FnOnce::call_once{{vtable.shim}}::hc84a1fb3f0e67ee4 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/ops/function.rs:510 [INFO] [stdout] 32:  as core::ops::function::FnOnce>::call_once::h2deece96542e3e9a [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/alloc/src/boxed.rs:2000 [INFO] [stdout] 33:  as core::ops::function::FnOnce<()>>::call_once::ha9befd17c806a3b0 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/core/src/panic/unwind_safe.rs:271 [INFO] [stdout] 34: std::panicking::try::do_call::h17336b94b336bff1 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panicking.rs:483 [INFO] [stdout] 35: std::panicking::try::h701ebc92939c4760 [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panicking.rs:447 [INFO] [stdout] 36: std::panic::catch_unwind::h93b113531d625eab [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/std/src/panic.rs:137 [INFO] [stdout] 37: test::run_test_in_process::h08e9f627920c2a2a [INFO] [stdout] at /rustc/c5842b0be783dffa5a49693541acb79115c7eeef/library/test/src/lib.rs:660 [INFO] [stdout]  ⋮ 15 frames hidden ⋮  [INFO] [stdout] [INFO] [stdout] Run with COLORBT_SHOW_HIDDEN=1 environment variable to disable frame filtering. [INFO] [stdout] Warning: SpanTrace capture is Unsupported. [INFO] [stdout] Ensure that you've setup a tracing-error ErrorLayer and the semver versions are compatible [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] connection::tests::basic_usage [INFO] [stdout] [INFO] [stdout] test result: FAILED. 19 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 217.24s [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] running `Command { std: "docker" "inspect" "dd8ef5d8d48c920e70c8d03d496547e55a63ebc49a21f4f1a919362a3e5b20bf", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "dd8ef5d8d48c920e70c8d03d496547e55a63ebc49a21f4f1a919362a3e5b20bf", kill_on_drop: false }` [INFO] [stdout] dd8ef5d8d48c920e70c8d03d496547e55a63ebc49a21f4f1a919362a3e5b20bf