[INFO] fetching crate rumq-client 0.1.0-alpha.10...
[INFO] testing rumq-client-0.1.0-alpha.10 against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] extracting crate rumq-client 0.1.0-alpha.10 into /workspace/builds/worker-5-tc1/source
[INFO] started tweaking crates.io crate rumq-client 0.1.0-alpha.10
[INFO] finished tweaking crates.io crate rumq-client 0.1.0-alpha.10
[INFO] tweaked toml for crates.io crate rumq-client 0.1.0-alpha.10 written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate rumq-client 0.1.0-alpha.10 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate rumq-client 0.1.0-alpha.10 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded untrusted v0.7.0
[INFO] [stderr]   Downloaded webpki v0.21.2
[INFO] [stderr]   Downloaded wasm-bindgen v0.2.60
[INFO] [stderr]   Downloaded futures-macro v0.3.4
[INFO] [stderr]   Downloaded arc-swap v0.4.5
[INFO] [stderr]   Downloaded thiserror-impl v1.0.15
[INFO] [stderr]   Downloaded winapi-util v0.1.4
[INFO] [stderr]   Downloaded pem v0.7.0
[INFO] [stderr]   Downloaded proc-macro-hack v0.5.15
[INFO] [stderr]   Downloaded stable_deref_trait v1.1.1
[INFO] [stderr]   Downloaded wasm-bindgen-backend v0.2.60
[INFO] [stderr]   Downloaded quote v1.0.3
[INFO] [stderr]   Downloaded tokio-macros v0.2.5
[INFO] [stderr]   Downloaded fnv v1.0.6
[INFO] [stderr]   Downloaded proc-macro-nested v0.1.4
[INFO] [stderr]   Downloaded pin-utils v0.1.0-alpha.4
[INFO] [stderr]   Downloaded futures-task v0.3.4
[INFO] [stderr]   Downloaded memmap v0.7.0
[INFO] [stderr]   Downloaded async-stream-impl v0.2.1
[INFO] [stderr]   Downloaded hermit-abi v0.1.11
[INFO] [stderr]   Downloaded mio-uds v0.6.7
[INFO] [stderr]   Downloaded mio-named-pipes v0.1.6
[INFO] [stderr]   Downloaded futures-core v0.3.4
[INFO] [stderr]   Downloaded simple_asn1 v0.4.0
[INFO] [stderr]   Downloaded lazycell v1.2.1
[INFO] [stderr]   Downloaded async-stream v0.2.1
[INFO] [stderr]   Downloaded wasm-bindgen-shared v0.2.60
[INFO] [stderr]   Downloaded findshlibs v0.5.0
[INFO] [stderr]   Downloaded signal-hook-registry v1.2.0
[INFO] [stderr]   Downloaded rumq-core v0.1.0-alpha.10
[INFO] [stderr]   Downloaded jsonwebtoken v7.1.0
[INFO] [stderr]   Downloaded itoa v0.4.5
[INFO] [stderr]   Downloaded envy v0.4.1
[INFO] [stderr]   Downloaded scroll v0.10.1
[INFO] [stderr]   Downloaded scroll_derive v0.10.1
[INFO] [stderr]   Downloaded thread_local v1.0.1
[INFO] [stderr]   Downloaded futures-sink v0.3.4
[INFO] [stderr]   Downloaded wasm-bindgen-macro v0.2.60
[INFO] [stderr]   Downloaded pin-project-lite v0.1.4
[INFO] [stderr]   Downloaded thiserror v1.0.15
[INFO] [stderr]   Downloaded autocfg v1.0.0
[INFO] [stderr]   Downloaded net2 v0.2.33
[INFO] [stderr]   Downloaded socket2 v0.3.12
[INFO] [stderr]   Downloaded bytes v0.5.4
[INFO] [stderr]   Downloaded tokio-rustls v0.13.0
[INFO] [stderr]   Downloaded async-trait v0.1.30
[INFO] [stderr]   Downloaded time v0.1.42
[INFO] [stderr]   Downloaded num-traits v0.2.11
[INFO] [stderr]   Downloaded color-backtrace v0.3.0
[INFO] [stderr]   Downloaded wasm-bindgen-macro-support v0.2.60
[INFO] [stderr]   Downloaded miow v0.3.3
[INFO] [stderr]   Downloaded num-integer v0.1.42
[INFO] [stderr]   Downloaded ryu v1.0.3
[INFO] [stderr]   Downloaded smallvec v1.3.0
[INFO] [stderr]   Downloaded proc-macro2 v1.0.10
[INFO] [stderr]   Downloaded js-sys v0.3.37
[INFO] [stderr]   Downloaded serde_derive v1.0.106
[INFO] [stderr]   Downloaded serde_json v1.0.51
[INFO] [stderr]   Downloaded backtrace v0.3.46
[INFO] [stderr]   Downloaded cc v1.0.50
[INFO] [stderr]   Downloaded serde v1.0.106
[INFO] [stderr]   Downloaded crossbeam-channel v0.4.2
[INFO] [stderr]   Downloaded addr2line v0.11.0
[INFO] [stderr]   Downloaded mio v0.6.21
[INFO] [stderr]   Downloaded aho-corasick v0.7.10
[INFO] [stderr]   Downloaded chrono v0.4.11
[INFO] [stderr]   Downloaded futures-util v0.3.4
[INFO] [stderr]   Downloaded goblin v0.2.1
[INFO] [stderr]   Downloaded bumpalo v3.2.1
[INFO] [stderr]   Downloaded rustls v0.17.0
[INFO] [stderr]   Downloaded syn v1.0.17
[INFO] [stderr]   Downloaded regex v1.3.6
[INFO] [stderr]   Downloaded regex-syntax v0.6.17
[INFO] [stderr]   Downloaded tokio v0.2.18
[INFO] [stderr]   Downloaded libc v0.2.69
[INFO] [stderr]   Downloaded web-sys v0.3.37
[INFO] [stderr]   Downloaded backtrace-sys v0.1.35
[INFO] [stderr]   Downloaded gimli v0.20.0
[INFO] [stderr]   Downloaded ring v0.16.12
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 96210b8e766c29397cdd6f8661a2d12a6a1f444e28c89a71c81a69cefc5116ea
[INFO] running `Command { std: "docker" "start" "96210b8e766c29397cdd6f8661a2d12a6a1f444e28c89a71c81a69cefc5116ea", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "96210b8e766c29397cdd6f8661a2d12a6a1f444e28c89a71c81a69cefc5116ea", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "96210b8e766c29397cdd6f8661a2d12a6a1f444e28c89a71c81a69cefc5116ea" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "96210b8e766c29397cdd6f8661a2d12a6a1f444e28c89a71c81a69cefc5116ea", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "96210b8e766c29397cdd6f8661a2d12a6a1f444e28c89a71c81a69cefc5116ea" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.69
[INFO] [stderr]    Compiling proc-macro2 v1.0.10
[INFO] [stderr]    Compiling unicode-xid v0.2.0
[INFO] [stderr]    Compiling syn v1.0.17
[INFO] [stderr]    Compiling log v0.4.8
[INFO] [stderr]    Compiling slab v0.4.2
[INFO] [stderr]    Compiling cc v1.0.50
[INFO] [stderr]    Compiling memchr v2.3.3
[INFO] [stderr]    Compiling futures-core v0.3.4
[INFO] [stderr]    Compiling arc-swap v0.4.5
[INFO] [stderr]    Compiling untrusted v0.7.0
[INFO] [stderr]    Compiling spin v0.5.2
[INFO] [stderr]    Compiling bytes v0.5.4
[INFO] [stderr]    Compiling fnv v1.0.6
[INFO] [stderr]    Compiling pin-project-lite v0.1.4
[INFO] [stderr]    Compiling futures-sink v0.3.4
[INFO] [stderr]    Compiling proc-macro-nested v0.1.4
[INFO] [stderr]    Compiling byteorder v1.3.4
[INFO] [stderr]    Compiling proc-macro-hack v0.5.15
[INFO] [stderr]    Compiling futures-task v0.3.4
[INFO] [stderr]    Compiling pin-utils v0.1.0-alpha.4
[INFO] [stderr]    Compiling net2 v0.2.33
[INFO] [stderr]    Compiling iovec v0.1.4
[INFO] [stderr]    Compiling signal-hook-registry v1.2.0
[INFO] [stderr]    Compiling num_cpus v1.13.0
[INFO] [stderr]    Compiling ring v0.16.12
[INFO] [stderr]    Compiling mio v0.6.21
[INFO] [stderr]    Compiling quote v1.0.3
[INFO] [stderr]    Compiling mio-uds v0.6.7
[INFO] [stderr]    Compiling tokio-macros v0.2.5
[INFO] [stderr]    Compiling thiserror-impl v1.0.15
[INFO] [stderr]    Compiling async-trait v0.1.30
[INFO] [stderr]    Compiling futures-macro v0.3.4
[INFO] [stderr]    Compiling async-stream-impl v0.2.1
[INFO] [stderr]    Compiling tokio v0.2.18
[INFO] [stderr]    Compiling async-stream v0.2.1
[INFO] [stderr]    Compiling futures-util v0.3.4
[INFO] [stderr]    Compiling thiserror v1.0.15
[INFO] [stderr]    Compiling webpki v0.21.2
[INFO] [stderr]    Compiling sct v0.6.0
[INFO] [stderr]    Compiling rustls v0.17.0
[INFO] [stderr]    Compiling tokio-util v0.3.1
[INFO] [stderr]    Compiling tokio-rustls v0.13.0
[INFO] [stderr]    Compiling rumq-core v0.1.0-alpha.10
[INFO] [stderr]    Compiling rumq-client v0.1.0-alpha.10 (/opt/rustwide/workdir)
[INFO] [stdout] warning: private item shadows public glob re-export
[INFO] [stdout]    --> src/lib.rs:111:24
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                        ^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout] note: the name `MqttRead` in the type namespace is supposed to be publicly re-exported here
[INFO] [stdout]    --> src/lib.rs:123:9
[INFO] [stdout]     |
[INFO] [stdout] 123 | pub use rumq_core::mqtt4::*;
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] note: but the private item here shadows it
[INFO] [stdout]    --> src/lib.rs:111:24
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                        ^^^^^^^^
[INFO] [stdout]     = note: `#[warn(hidden_glob_reexports)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: private item shadows public glob re-export
[INFO] [stdout]    --> src/lib.rs:111:34
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                                  ^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout] note: the name `MqttWrite` in the type namespace is supposed to be publicly re-exported here
[INFO] [stdout]    --> src/lib.rs:123:9
[INFO] [stdout]     |
[INFO] [stdout] 123 | pub use rumq_core::mqtt4::*;
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] note: but the private item here shadows it
[INFO] [stdout]    --> src/lib.rs:111:34
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                                  ^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: private item shadows public glob re-export
[INFO] [stdout]    --> src/lib.rs:111:45
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                                             ^^^^^^
[INFO] [stdout]     |
[INFO] [stdout] note: the name `Packet` in the type namespace is supposed to be publicly re-exported here
[INFO] [stdout]    --> src/lib.rs:123:9
[INFO] [stdout]     |
[INFO] [stdout] 123 | pub use rumq_core::mqtt4::*;
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] note: but the private item here shadows it
[INFO] [stdout]    --> src/lib.rs:111:45
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                                             ^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 30.18s
[INFO] running `Command { std: "docker" "inspect" "96210b8e766c29397cdd6f8661a2d12a6a1f444e28c89a71c81a69cefc5116ea", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "96210b8e766c29397cdd6f8661a2d12a6a1f444e28c89a71c81a69cefc5116ea" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling autocfg v1.0.0
[INFO] [stderr]    Compiling thread_local v1.0.1
[INFO] [stderr]    Compiling regex-syntax v0.6.17
[INFO] [stderr]    Compiling serde v1.0.106
[INFO] [stderr]    Compiling backtrace-sys v0.1.35
[INFO] [stderr]    Compiling ryu v1.0.3
[INFO] [stderr]    Compiling arrayvec v0.5.1
[INFO] [stderr]    Compiling aho-corasick v0.7.10
[INFO] [stderr]    Compiling stable_deref_trait v1.1.1
[INFO] [stderr]    Compiling fallible-iterator v0.2.0
[INFO] [stderr]    Compiling smallvec v1.3.0
[INFO] [stderr]    Compiling serde_derive v1.0.106
[INFO] [stderr]    Compiling scroll_derive v0.10.1
[INFO] [stderr]    Compiling time v0.1.42
[INFO] [stderr]    Compiling atty v0.2.14
[INFO] [stderr]    Compiling plain v0.2.3
[INFO] [stderr]    Compiling termcolor v1.1.0
[INFO] [stderr]    Compiling lazycell v1.2.1
[INFO] [stderr]    Compiling findshlibs v0.5.0
[INFO] [stderr]    Compiling memmap v0.7.0
[INFO] [stderr]    Compiling rustc-demangle v0.1.16
[INFO] [stderr]    Compiling itoa v0.4.5
[INFO] [stderr]    Compiling num-traits v0.2.11
[INFO] [stderr]    Compiling num-integer v0.1.42
[INFO] [stderr]    Compiling num-bigint v0.2.6
[INFO] [stderr]    Compiling crossbeam-utils v0.7.2
[INFO] [stderr]    Compiling scroll v0.10.1
[INFO] [stdout] warning: private item shadows public glob re-export
[INFO] [stdout]    --> src/lib.rs:111:24
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                        ^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout] note: the name `MqttRead` in the type namespace is supposed to be publicly re-exported here
[INFO] [stdout]    --> src/lib.rs:123:9
[INFO] [stdout]     |
[INFO] [stdout] 123 | pub use rumq_core::mqtt4::*;
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] note: but the private item here shadows it
[INFO] [stdout]    --> src/lib.rs:111:24
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                        ^^^^^^^^
[INFO] [stdout]     = note: `#[warn(hidden_glob_reexports)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: private item shadows public glob re-export
[INFO] [stdout]    --> src/lib.rs:111:34
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                                  ^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout] note: the name `MqttWrite` in the type namespace is supposed to be publicly re-exported here
[INFO] [stdout]    --> src/lib.rs:123:9
[INFO] [stdout]     |
[INFO] [stdout] 123 | pub use rumq_core::mqtt4::*;
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] note: but the private item here shadows it
[INFO] [stdout]    --> src/lib.rs:111:34
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                                  ^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: private item shadows public glob re-export
[INFO] [stdout]    --> src/lib.rs:111:45
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                                             ^^^^^^
[INFO] [stdout]     |
[INFO] [stdout] note: the name `Packet` in the type namespace is supposed to be publicly re-exported here
[INFO] [stdout]    --> src/lib.rs:123:9
[INFO] [stdout]     |
[INFO] [stdout] 123 | pub use rumq_core::mqtt4::*;
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] note: but the private item here shadows it
[INFO] [stdout]    --> src/lib.rs:111:45
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                                             ^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling gimli v0.20.0
[INFO] [stderr]    Compiling goblin v0.2.1
[INFO] [stderr]    Compiling crossbeam-channel v0.4.2
[INFO] [stderr]    Compiling chrono v0.4.11
[INFO] [stderr]    Compiling regex v1.3.6
[INFO] [stderr]    Compiling simple_asn1 v0.4.0
[INFO] [stderr]    Compiling addr2line v0.11.0
[INFO] [stderr]    Compiling env_logger v0.7.1
[INFO] [stderr]    Compiling pem v0.7.0
[INFO] [stderr]    Compiling pretty_env_logger v0.4.0
[INFO] [stderr]    Compiling backtrace v0.3.46
[INFO] [stderr]    Compiling color-backtrace v0.3.0
[INFO] [stderr]    Compiling serde_json v1.0.51
[INFO] [stderr]    Compiling envy v0.4.1
[INFO] [stderr]    Compiling jsonwebtoken v7.1.0
[INFO] [stderr]    Compiling rumq-client v0.1.0-alpha.10 (/opt/rustwide/workdir)
[INFO] [stdout] warning: private item shadows public glob re-export
[INFO] [stdout]    --> src/lib.rs:111:24
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                        ^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout] note: the name `MqttRead` in the type namespace is supposed to be publicly re-exported here
[INFO] [stdout]    --> src/lib.rs:123:9
[INFO] [stdout]     |
[INFO] [stdout] 123 | pub use rumq_core::mqtt4::*;
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] note: but the private item here shadows it
[INFO] [stdout]    --> src/lib.rs:111:24
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                        ^^^^^^^^
[INFO] [stdout]     = note: `#[warn(hidden_glob_reexports)]` on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: private item shadows public glob re-export
[INFO] [stdout]    --> src/lib.rs:111:34
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                                  ^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout] note: the name `MqttWrite` in the type namespace is supposed to be publicly re-exported here
[INFO] [stdout]    --> src/lib.rs:123:9
[INFO] [stdout]     |
[INFO] [stdout] 123 | pub use rumq_core::mqtt4::*;
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] note: but the private item here shadows it
[INFO] [stdout]    --> src/lib.rs:111:34
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                                  ^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: private item shadows public glob re-export
[INFO] [stdout]    --> src/lib.rs:111:45
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                                             ^^^^^^
[INFO] [stdout]     |
[INFO] [stdout] note: the name `Packet` in the type namespace is supposed to be publicly re-exported here
[INFO] [stdout]    --> src/lib.rs:123:9
[INFO] [stdout]     |
[INFO] [stdout] 123 | pub use rumq_core::mqtt4::*;
[INFO] [stdout]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stdout] note: but the private item here shadows it
[INFO] [stdout]    --> src/lib.rs:111:45
[INFO] [stdout]     |
[INFO] [stdout] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stdout]     |                                             ^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unexpected `cfg` condition value: `cargo-clippy`
[INFO] [stdout]   --> examples/gcloud.rs:85:21
[INFO] [stdout]    |
[INFO] [stdout] 85 |     #[derive(Debug, Serialize, Deserialize)]
[INFO] [stdout]    |                     ^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: no expected values for `feature`
[INFO] [stdout]    = note: using a cfg inside a derive macro will use the cfgs from the destination crate and not the ones from the defining crate
[INFO] [stdout]    = help: try referring to `Serialize` crate for guidance on how handle this unexpected cfg
[INFO] [stdout]    = note: see <https://doc.rust-lang.org/nightly/rustc/check-cfg/cargo-specifics.html> for more information about checking conditional configuration
[INFO] [stdout]    = note: `#[warn(unexpected_cfgs)]` on by default
[INFO] [stdout]    = note: this warning originates in the derive macro `Serialize` (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: unexpected `cfg` condition value: `cargo-clippy`
[INFO] [stdout]   --> examples/gcloud.rs:85:32
[INFO] [stdout]    |
[INFO] [stdout] 85 |     #[derive(Debug, Serialize, Deserialize)]
[INFO] [stdout]    |                                ^^^^^^^^^^^
[INFO] [stdout]    |
[INFO] [stdout]    = note: no expected values for `feature`
[INFO] [stdout]    = note: using a cfg inside a derive macro will use the cfgs from the destination crate and not the ones from the defining crate
[INFO] [stdout]    = help: try referring to `Deserialize` crate for guidance on how handle this unexpected cfg
[INFO] [stdout]    = note: see <https://doc.rust-lang.org/nightly/rustc/check-cfg/cargo-specifics.html> for more information about checking conditional configuration
[INFO] [stdout]    = note: this warning originates in the derive macro `Deserialize` (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: non-local `impl` definition, `impl` blocks should be written at the same level as their item
[INFO] [stdout]   --> examples/gcloud.rs:85:21
[INFO] [stdout]    |
[INFO] [stdout] 85 |     #[derive(Debug, Serialize, Deserialize)]
[INFO] [stdout]    |                     ^--------
[INFO] [stdout]    |                     |
[INFO] [stdout]    |                     `Serialize` is not local
[INFO] [stdout]    |                     move the `impl` block outside of this constant `_IMPL_SERIALIZE_FOR_Claims` and up 2 bodies
[INFO] [stdout] 86 |     struct Claims {
[INFO] [stdout]    |            ------ `Claims` is not local
[INFO] [stdout]    |
[INFO] [stdout]    = note: the derive macro `Serialize` defines the non-local `impl`, and may need to be changed
[INFO] [stdout]    = note: the derive macro `Serialize` may come from an old version of the `serde_derive` crate, try updating your dependency with `cargo update -p serde_derive`
[INFO] [stdout]    = note: an `impl` is never scoped, even when it is nested inside an item, as it may impact type checking outside of that item, which can be the case if neither the trait or the self type are at the same nesting level as the `impl`
[INFO] [stdout]    = note: items in an anonymous const item (`const _: () = { ... }`) are treated as in the same scope as the anonymous const's declaration for the purpose of this lint
[INFO] [stdout]    = note: `#[warn(non_local_definitions)]` on by default
[INFO] [stdout]    = note: this warning originates in the derive macro `Serialize` (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: non-local `impl` definition, `impl` blocks should be written at the same level as their item
[INFO] [stdout]   --> examples/gcloud.rs:85:32
[INFO] [stdout]    |
[INFO] [stdout] 85 |     #[derive(Debug, Serialize, Deserialize)]
[INFO] [stdout]    |                                ^----------
[INFO] [stdout]    |                                |
[INFO] [stdout]    |                                `Deserialize` is not local
[INFO] [stdout]    |                                move the `impl` block outside of this constant `_IMPL_DESERIALIZE_FOR_Claims` and up 2 bodies
[INFO] [stdout] 86 |     struct Claims {
[INFO] [stdout]    |            ------ `Claims` is not local
[INFO] [stdout]    |
[INFO] [stdout]    = note: the derive macro `Deserialize` defines the non-local `impl`, and may need to be changed
[INFO] [stdout]    = note: the derive macro `Deserialize` may come from an old version of the `serde_derive` crate, try updating your dependency with `cargo update -p serde_derive`
[INFO] [stdout]    = note: an `impl` is never scoped, even when it is nested inside an item, as it may impact type checking outside of that item, which can be the case if neither the trait or the self type are at the same nesting level as the `impl`
[INFO] [stdout]    = note: items in an anonymous const item (`const _: () = { ... }`) are treated as in the same scope as the anonymous const's declaration for the purpose of this lint
[INFO] [stdout]    = note: this warning originates in the derive macro `Deserialize` (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 48.10s
[INFO] running `Command { std: "docker" "inspect" "96210b8e766c29397cdd6f8661a2d12a6a1f444e28c89a71c81a69cefc5116ea", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "96210b8e766c29397cdd6f8661a2d12a6a1f444e28c89a71c81a69cefc5116ea" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: private item shadows public glob re-export
[INFO] [stderr]    --> src/lib.rs:111:24
[INFO] [stderr]     |
[INFO] [stderr] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stderr]     |                        ^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr] note: the name `MqttRead` in the type namespace is supposed to be publicly re-exported here
[INFO] [stderr]    --> src/lib.rs:123:9
[INFO] [stderr]     |
[INFO] [stderr] 123 | pub use rumq_core::mqtt4::*;
[INFO] [stderr]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] note: but the private item here shadows it
[INFO] [stderr]    --> src/lib.rs:111:24
[INFO] [stderr]     |
[INFO] [stderr] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stderr]     |                        ^^^^^^^^
[INFO] [stderr]     = note: `#[warn(hidden_glob_reexports)]` on by default
[INFO] [stderr] 
[INFO] [stderr] warning: private item shadows public glob re-export
[INFO] [stderr]    --> src/lib.rs:111:34
[INFO] [stderr]     |
[INFO] [stderr] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stderr]     |                                  ^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr] note: the name `MqttWrite` in the type namespace is supposed to be publicly re-exported here
[INFO] [stderr]    --> src/lib.rs:123:9
[INFO] [stderr]     |
[INFO] [stderr] 123 | pub use rumq_core::mqtt4::*;
[INFO] [stderr]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] note: but the private item here shadows it
[INFO] [stderr]    --> src/lib.rs:111:34
[INFO] [stderr]     |
[INFO] [stderr] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stderr]     |                                  ^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: private item shadows public glob re-export
[INFO] [stderr]    --> src/lib.rs:111:45
[INFO] [stderr]     |
[INFO] [stderr] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stderr]     |                                             ^^^^^^
[INFO] [stderr]     |
[INFO] [stderr] note: the name `Packet` in the type namespace is supposed to be publicly re-exported here
[INFO] [stderr]    --> src/lib.rs:123:9
[INFO] [stderr]     |
[INFO] [stderr] 123 | pub use rumq_core::mqtt4::*;
[INFO] [stderr]     |         ^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] note: but the private item here shadows it
[INFO] [stderr]    --> src/lib.rs:111:45
[INFO] [stderr]     |
[INFO] [stderr] 111 | use rumq_core::mqtt4::{MqttRead, MqttWrite, Packet};
[INFO] [stderr]     |                                             ^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: `rumq-client` (lib) generated 3 warnings
[INFO] [stderr] warning: unexpected `cfg` condition value: `cargo-clippy`
[INFO] [stderr]   --> examples/gcloud.rs:85:21
[INFO] [stderr]    |
[INFO] [stderr] 85 |     #[derive(Debug, Serialize, Deserialize)]
[INFO] [stderr]    |                     ^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: no expected values for `feature`
[INFO] [stderr]    = note: using a cfg inside a derive macro will use the cfgs from the destination crate and not the ones from the defining crate
[INFO] [stderr]    = help: try referring to `Serialize` crate for guidance on how handle this unexpected cfg
[INFO] [stderr]    = note: see <https://doc.rust-lang.org/nightly/rustc/check-cfg/cargo-specifics.html> for more information about checking conditional configuration
[INFO] [stderr]    = note: `#[warn(unexpected_cfgs)]` on by default
[INFO] [stderr]    = note: this warning originates in the derive macro `Serialize` (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stderr] 
[INFO] [stderr] warning: unexpected `cfg` condition value: `cargo-clippy`
[INFO] [stderr]   --> examples/gcloud.rs:85:32
[INFO] [stderr]    |
[INFO] [stderr] 85 |     #[derive(Debug, Serialize, Deserialize)]
[INFO] [stderr]    |                                ^^^^^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr]    = note: no expected values for `feature`
[INFO] [stderr]    = note: using a cfg inside a derive macro will use the cfgs from the destination crate and not the ones from the defining crate
[INFO] [stderr]    = help: try referring to `Deserialize` crate for guidance on how handle this unexpected cfg
[INFO] [stderr]    = note: see <https://doc.rust-lang.org/nightly/rustc/check-cfg/cargo-specifics.html> for more information about checking conditional configuration
[INFO] [stderr]    = note: this warning originates in the derive macro `Deserialize` (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stderr] 
[INFO] [stderr] warning: non-local `impl` definition, `impl` blocks should be written at the same level as their item
[INFO] [stderr]   --> examples/gcloud.rs:85:21
[INFO] [stderr]    |
[INFO] [stderr] 85 |     #[derive(Debug, Serialize, Deserialize)]
[INFO] [stderr]    |                     ^--------
[INFO] [stderr]    |                     |
[INFO] [stderr]    |                     `Serialize` is not local
[INFO] [stderr]    |                     move the `impl` block outside of this constant `_IMPL_SERIALIZE_FOR_Claims` and up 2 bodies
[INFO] [stderr] 86 |     struct Claims {
[INFO] [stderr]    |            ------ `Claims` is not local
[INFO] [stderr]    |
[INFO] [stderr]    = note: the derive macro `Serialize` defines the non-local `impl`, and may need to be changed
[INFO] [stderr]    = note: the derive macro `Serialize` may come from an old version of the `serde_derive` crate, try updating your dependency with `cargo update -p serde_derive`
[INFO] [stderr]    = note: an `impl` is never scoped, even when it is nested inside an item, as it may impact type checking outside of that item, which can be the case if neither the trait or the self type are at the same nesting level as the `impl`
[INFO] [stderr]    = note: items in an anonymous const item (`const _: () = { ... }`) are treated as in the same scope as the anonymous const's declaration for the purpose of this lint
[INFO] [stderr]    = note: `#[warn(non_local_definitions)]` on by default
[INFO] [stderr]    = note: this warning originates in the derive macro `Serialize` (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stderr] 
[INFO] [stderr] warning: non-local `impl` definition, `impl` blocks should be written at the same level as their item
[INFO] [stderr]   --> examples/gcloud.rs:85:32
[INFO] [stderr]    |
[INFO] [stderr] 85 |     #[derive(Debug, Serialize, Deserialize)]
[INFO] [stderr]    |                                ^----------
[INFO] [stderr]    |                                |
[INFO] [stderr]    |                                `Deserialize` is not local
[INFO] [stderr]    |                                move the `impl` block outside of this constant `_IMPL_DESERIALIZE_FOR_Claims` and up 2 bodies
[INFO] [stderr] 86 |     struct Claims {
[INFO] [stderr]    |            ------ `Claims` is not local
[INFO] [stderr]    |
[INFO] [stderr]    = note: the derive macro `Deserialize` defines the non-local `impl`, and may need to be changed
[INFO] [stderr]    = note: the derive macro `Deserialize` may come from an old version of the `serde_derive` crate, try updating your dependency with `cargo update -p serde_derive`
[INFO] [stderr]    = note: an `impl` is never scoped, even when it is nested inside an item, as it may impact type checking outside of that item, which can be the case if neither the trait or the self type are at the same nesting level as the `impl`
[INFO] [stderr]    = note: items in an anonymous const item (`const _: () = { ... }`) are treated as in the same scope as the anonymous const's declaration for the purpose of this lint
[INFO] [stderr]    = note: this warning originates in the derive macro `Deserialize` (in Nightly builds, run with -Z macro-backtrace for more info)
[INFO] [stderr] 
[INFO] [stderr] warning: `rumq-client` (lib test) generated 3 warnings (3 duplicates)
[INFO] [stderr] warning: `rumq-client` (example "gcloud") generated 4 warnings
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.12s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/rumq_client-1a1755062d90b45f)
[INFO] [stdout] 
[INFO] [stdout] running 23 tests
[INFO] [stdout] test state::test::incoming_pubrec_should_release_correct_publish_from_queue_and_add_releaseid_to_rel_queue ... ok
[INFO] [stdout] test state::test::incoming_pubrel_should_send_comp_to_network_and_nothing_to_user ... ok
[INFO] [stdout] test state::test::incoming_qos2_publish_should_send_rec_to_network_and_publish_to_user ... ok
[INFO] [stdout] test state::test::incoming_publish_should_be_added_to_queue_correctly ... ok
[INFO] [stdout] test state::test::incoming_pubrec_should_send_release_to_network_and_nothing_to_user ... ok
[INFO] [stdout] test state::test::next_pkid_roll ... ok
[INFO] [stdout] test state::test::outgoing_ping_handle_should_succeed_if_pingresp_is_received ... ok
[INFO] [stdout] test state::test::outgoing_ping_handle_should_throw_errors_for_no_pingresp ... ok
[INFO] [stdout] test state::test::outgoing_publish_handle_should_set_pkid_correctly_and_add_publish_to_queue_correctly ... ok
[INFO] [stdout] test eventloop::test::reconnection_resends_unacked_packets_from_the_previous_connection_before_sending_current_connection_requests ... FAILED
[INFO] [stdout] test eventloop::test::reconnection_resumes_from_the_previous_state ... FAILED
[INFO] [stdout] test state::test::incoming_puback_should_remove_correct_publish_from_queue ... ok
[INFO] [stdout] test test::client_id_startswith_space - should panic ... ok
[INFO] [stdout] test eventloop::test::some_incoming_and_no_outgoing_packets_should_trigger_pings_on_time ... FAILED
[INFO] [stdout] test state::test::incoming_pubcomp_should_release_correct_pkid_from_release_queue ... ok
[INFO] [stdout] test test::no_client_id - should panic ... ok
[INFO] [stdout] test eventloop::test::idle_connection_triggers_pings_on_time ... FAILED
[INFO] [stdout] test eventloop::test::throttled_requests_works_with_correct_delays_between_requests ... FAILED
[INFO] [stdout] test eventloop::test::requests_are_blocked_after_max_inflight_queue_size ... FAILED
[INFO] [stdout] test eventloop::test::requests_are_recovered_after_inflight_queue_size_falls_below_max ... FAILED
[INFO] [stdout] test eventloop::test::some_outgoing_and_no_incoming_packets_should_trigger_pings_on_time ... FAILED
[INFO] [stdout] test eventloop::test::detects_halfopen_connections_in_the_second_ping_request ... FAILED
[INFO] [stdout] test eventloop::test::connection_should_timeout_on_time ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- eventloop::test::reconnection_resends_unacked_packets_from_the_previous_connection_before_sending_current_connection_requests stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'eventloop::test::reconnection_resends_unacked_packets_from_the_previous_connection_before_sending_current_connection_requests' (2345) panicked at src/eventloop.rs:687:63:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 22, kind: InvalidInput, message: "Invalid argument" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c6f6a166c81 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c6f6a17c89a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c6f6a16b72c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c6f6a142426 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c6f6a15efc9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c6f6a05b140 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c6f6a15f182 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c6f6a1424d2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c6f6a13b179 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c6f6a1432bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c6f6a17cffc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c6f6a17cdd2 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c6f69ffbc4c - <rumq_client[38c58c527444fe9a]::eventloop::broker::Broker>::new::{closure#0}
[INFO] [stdout]   13:     0x5c6f69ffc044 - rumq_client[38c58c527444fe9a]::eventloop::test::reconnection_resends_unacked_packets_from_the_previous_connection_before_sending_current_connection_requests::{closure#0}
[INFO] [stdout]   14:     0x5c6f6a00e987 - <tokio[4a3b5a5108b76c30]::macros::scoped_tls::ScopedKey<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Context>>::set::<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::reconnection_resends_unacked_packets_from_the_previous_connection_before_sending_current_connection_requests::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>::{closure#0}, ()>
[INFO] [stdout]   15:     0x5c6f6a04c3f2 - tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter::<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::reconnection_resends_unacked_packets_from_the_previous_connection_before_sending_current_connection_requests::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>
[INFO] [stdout]   16:     0x5c6f6a01f4df - tokio[4a3b5a5108b76c30]::runtime::context::enter::<<tokio[4a3b5a5108b76c30]::runtime::Runtime>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::reconnection_resends_unacked_packets_from_the_previous_connection_before_sending_current_connection_requests::{closure#0}>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5c6f6a03192f - rumq_client[38c58c527444fe9a]::eventloop::test::reconnection_resends_unacked_packets_from_the_previous_connection_before_sending_current_connection_requests
[INFO] [stdout]   18:     0x5c6f6a02c199 - <rumq_client[38c58c527444fe9a]::eventloop::test::reconnection_resends_unacked_packets_from_the_previous_connection_before_sending_current_connection_requests::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5c6f6a04e42b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   20:     0x5c6f6a05ba95 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5c6f6a055354 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5c6f6a05ebf2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5c6f6a1661e9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x750241ec8dfa - <unknown>
[INFO] [stdout]   25:     0x750241f5c3d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- eventloop::test::reconnection_resumes_from_the_previous_state stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'eventloop::test::reconnection_resumes_from_the_previous_state' (2346) panicked at src/eventloop.rs:687:63:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 22, kind: InvalidInput, message: "Invalid argument" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c6f6a166c81 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c6f6a17c89a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c6f6a16b72c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c6f6a142426 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c6f6a15efc9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c6f6a05b140 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c6f6a15f182 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c6f6a1424d2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c6f6a13b179 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c6f6a1432bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c6f6a17cffc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c6f6a17cdd2 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c6f69ffbc4c - <rumq_client[38c58c527444fe9a]::eventloop::broker::Broker>::new::{closure#0}
[INFO] [stdout]   13:     0x5c6f69ffcff4 - rumq_client[38c58c527444fe9a]::eventloop::test::reconnection_resumes_from_the_previous_state::{closure#0}
[INFO] [stdout]   14:     0x5c6f6a00f887 - <tokio[4a3b5a5108b76c30]::macros::scoped_tls::ScopedKey<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Context>>::set::<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::reconnection_resumes_from_the_previous_state::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>::{closure#0}, ()>
[INFO] [stdout]   15:     0x5c6f6a04c722 - tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter::<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::reconnection_resumes_from_the_previous_state::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>
[INFO] [stdout]   16:     0x5c6f6a01f89f - tokio[4a3b5a5108b76c30]::runtime::context::enter::<<tokio[4a3b5a5108b76c30]::runtime::Runtime>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::reconnection_resumes_from_the_previous_state::{closure#0}>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5c6f6a031e9f - rumq_client[38c58c527444fe9a]::eventloop::test::reconnection_resumes_from_the_previous_state
[INFO] [stdout]   18:     0x5c6f6a02c1f9 - <rumq_client[38c58c527444fe9a]::eventloop::test::reconnection_resumes_from_the_previous_state::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5c6f6a04e42b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   20:     0x5c6f6a05ba95 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5c6f6a055354 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5c6f6a05ebf2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5c6f6a1661e9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x750241ec8dfa - <unknown>
[INFO] [stdout]   25:     0x750241f5c3d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- eventloop::test::some_incoming_and_no_outgoing_packets_should_trigger_pings_on_time stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'eventloop::test::some_incoming_and_no_outgoing_packets_should_trigger_pings_on_time' (2349) panicked at src/eventloop.rs:687:63:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 22, kind: InvalidInput, message: "Invalid argument" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c6f6a166c81 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c6f6a17c89a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c6f6a16b72c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c6f6a142426 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c6f6a15efc9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c6f6a05b140 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c6f6a15f182 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c6f6a1424d2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c6f6a13b179 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c6f6a1432bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c6f6a17cffc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c6f6a17cdd2 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c6f69ffbc4c - <rumq_client[38c58c527444fe9a]::eventloop::broker::Broker>::new::{closure#0}
[INFO] [stdout]   13:     0x5c6f69fff7ec - rumq_client[38c58c527444fe9a]::eventloop::test::some_incoming_and_no_outgoing_packets_should_trigger_pings_on_time::{closure#0}
[INFO] [stdout]   14:     0x5c6f6a011187 - <tokio[4a3b5a5108b76c30]::macros::scoped_tls::ScopedKey<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Context>>::set::<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::some_incoming_and_no_outgoing_packets_should_trigger_pings_on_time::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>::{closure#0}, ()>
[INFO] [stdout]   15:     0x5c6f6a04cc72 - tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter::<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::some_incoming_and_no_outgoing_packets_should_trigger_pings_on_time::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>
[INFO] [stdout]   16:     0x5c6f6a01fedf - tokio[4a3b5a5108b76c30]::runtime::context::enter::<<tokio[4a3b5a5108b76c30]::runtime::Runtime>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::some_incoming_and_no_outgoing_packets_should_trigger_pings_on_time::{closure#0}>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5c6f6a0327af - rumq_client[38c58c527444fe9a]::eventloop::test::some_incoming_and_no_outgoing_packets_should_trigger_pings_on_time
[INFO] [stdout]   18:     0x5c6f6a02c299 - <rumq_client[38c58c527444fe9a]::eventloop::test::some_incoming_and_no_outgoing_packets_should_trigger_pings_on_time::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5c6f6a04e42b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   20:     0x5c6f6a05ba95 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5c6f6a055354 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5c6f6a05ebf2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5c6f6a1661e9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x750241ec8dfa - <unknown>
[INFO] [stdout]   25:     0x750241f5c3d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- eventloop::test::idle_connection_triggers_pings_on_time stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'eventloop::test::idle_connection_triggers_pings_on_time' (2344) panicked at src/eventloop.rs:687:63:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 22, kind: InvalidInput, message: "Invalid argument" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c6f6a166c81 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c6f6a17c89a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c6f6a16b72c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c6f6a142426 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c6f6a15efc9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c6f6a05b140 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c6f6a15f182 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c6f6a1424d2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c6f6a13b179 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c6f6a1432bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c6f6a17cffc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c6f6a17cdd2 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c6f69ffbc4c - <rumq_client[38c58c527444fe9a]::eventloop::broker::Broker>::new::{closure#0}
[INFO] [stdout]   13:     0x5c6f69ffcacf - rumq_client[38c58c527444fe9a]::eventloop::test::idle_connection_triggers_pings_on_time::{closure#0}
[INFO] [stdout]   14:     0x5c6f6a00f387 - <tokio[4a3b5a5108b76c30]::macros::scoped_tls::ScopedKey<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Context>>::set::<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::idle_connection_triggers_pings_on_time::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>::{closure#0}, ()>
[INFO] [stdout]   15:     0x5c6f6a04c60f - tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter::<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::idle_connection_triggers_pings_on_time::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>
[INFO] [stdout]   16:     0x5c6f6a01f75f - tokio[4a3b5a5108b76c30]::runtime::context::enter::<<tokio[4a3b5a5108b76c30]::runtime::Runtime>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::idle_connection_triggers_pings_on_time::{closure#0}>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5c6f6a031cd2 - rumq_client[38c58c527444fe9a]::eventloop::test::idle_connection_triggers_pings_on_time
[INFO] [stdout]   18:     0x5c6f6a02c1d9 - <rumq_client[38c58c527444fe9a]::eventloop::test::idle_connection_triggers_pings_on_time::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5c6f6a04e42b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   20:     0x5c6f6a05ba95 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5c6f6a055354 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5c6f6a05ebf2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5c6f6a1661e9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x750241ec8dfa - <unknown>
[INFO] [stdout]   25:     0x750241f5c3d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- eventloop::test::throttled_requests_works_with_correct_delays_between_requests stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'eventloop::test::throttled_requests_works_with_correct_delays_between_requests' (2351) panicked at src/eventloop.rs:687:63:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 22, kind: InvalidInput, message: "Invalid argument" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c6f6a166c81 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c6f6a17c89a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c6f6a16b72c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c6f6a142426 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c6f6a15efc9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c6f6a05b140 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c6f6a15f182 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c6f6a1424d2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c6f6a13b179 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c6f6a1432bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c6f6a17cffc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c6f6a17cdd2 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c6f69ffbc4c - <rumq_client[38c58c527444fe9a]::eventloop::broker::Broker>::new::{closure#0}
[INFO] [stdout]   13:     0x5c6f69ffe812 - rumq_client[38c58c527444fe9a]::eventloop::test::throttled_requests_works_with_correct_delays_between_requests::{closure#0}
[INFO] [stdout]   14:     0x5c6f6a010787 - <tokio[4a3b5a5108b76c30]::macros::scoped_tls::ScopedKey<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Context>>::set::<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::throttled_requests_works_with_correct_delays_between_requests::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>::{closure#0}, ()>
[INFO] [stdout]   15:     0x5c6f6a04ca52 - tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter::<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::throttled_requests_works_with_correct_delays_between_requests::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>
[INFO] [stdout]   16:     0x5c6f6a01fc5f - tokio[4a3b5a5108b76c30]::runtime::context::enter::<<tokio[4a3b5a5108b76c30]::runtime::Runtime>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::throttled_requests_works_with_correct_delays_between_requests::{closure#0}>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5c6f6a03240f - rumq_client[38c58c527444fe9a]::eventloop::test::throttled_requests_works_with_correct_delays_between_requests
[INFO] [stdout]   18:     0x5c6f6a02c259 - <rumq_client[38c58c527444fe9a]::eventloop::test::throttled_requests_works_with_correct_delays_between_requests::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5c6f6a04e42b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   20:     0x5c6f6a05ba95 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5c6f6a055354 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5c6f6a05ebf2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5c6f6a1661e9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x750241ec8dfa - <unknown>
[INFO] [stdout]   25:     0x750241f5c3d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- eventloop::test::requests_are_blocked_after_max_inflight_queue_size stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'eventloop::test::requests_are_blocked_after_max_inflight_queue_size' (2347) panicked at src/eventloop.rs:687:63:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 22, kind: InvalidInput, message: "Invalid argument" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c6f6a166c81 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c6f6a17c89a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c6f6a16b72c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c6f6a142426 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c6f6a15efc9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c6f6a05b140 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c6f6a15f182 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c6f6a1424d2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c6f6a13b179 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c6f6a1432bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c6f6a17cffc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c6f6a17cdd2 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c6f69ffbc4c - <rumq_client[38c58c527444fe9a]::eventloop::broker::Broker>::new::{closure#0}
[INFO] [stdout]   13:     0x5c6f69ffd827 - rumq_client[38c58c527444fe9a]::eventloop::test::requests_are_blocked_after_max_inflight_queue_size::{closure#0}
[INFO] [stdout]   14:     0x5c6f6a00fd87 - <tokio[4a3b5a5108b76c30]::macros::scoped_tls::ScopedKey<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Context>>::set::<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::requests_are_blocked_after_max_inflight_queue_size::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>::{closure#0}, ()>
[INFO] [stdout]   15:     0x5c6f6a04c82f - tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter::<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::requests_are_blocked_after_max_inflight_queue_size::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>
[INFO] [stdout]   16:     0x5c6f6a01f9df - tokio[4a3b5a5108b76c30]::runtime::context::enter::<<tokio[4a3b5a5108b76c30]::runtime::Runtime>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::requests_are_blocked_after_max_inflight_queue_size::{closure#0}>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5c6f6a032072 - rumq_client[38c58c527444fe9a]::eventloop::test::requests_are_blocked_after_max_inflight_queue_size
[INFO] [stdout]   18:     0x5c6f6a02c219 - <rumq_client[38c58c527444fe9a]::eventloop::test::requests_are_blocked_after_max_inflight_queue_size::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5c6f6a04e42b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   20:     0x5c6f6a05ba95 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5c6f6a055354 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5c6f6a05ebf2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5c6f6a1661e9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x750241ec8dfa - <unknown>
[INFO] [stdout]   25:     0x750241f5c3d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- eventloop::test::requests_are_recovered_after_inflight_queue_size_falls_below_max stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'eventloop::test::requests_are_recovered_after_inflight_queue_size_falls_below_max' (2348) panicked at src/eventloop.rs:687:63:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 22, kind: InvalidInput, message: "Invalid argument" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c6f6a166c81 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c6f6a17c89a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c6f6a16b72c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c6f6a142426 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c6f6a15efc9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c6f6a05b140 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c6f6a15f182 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c6f6a1424d2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c6f6a13b179 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c6f6a1432bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c6f6a17cffc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c6f6a17cdd2 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c6f69ffbc4c - <rumq_client[38c58c527444fe9a]::eventloop::broker::Broker>::new::{closure#0}
[INFO] [stdout]   13:     0x5c6f69ffef0c - rumq_client[38c58c527444fe9a]::eventloop::test::requests_are_recovered_after_inflight_queue_size_falls_below_max::{closure#0}
[INFO] [stdout]   14:     0x5c6f6a010c87 - <tokio[4a3b5a5108b76c30]::macros::scoped_tls::ScopedKey<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Context>>::set::<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::requests_are_recovered_after_inflight_queue_size_falls_below_max::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>::{closure#0}, ()>
[INFO] [stdout]   15:     0x5c6f6a04cb5f - tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter::<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::requests_are_recovered_after_inflight_queue_size_falls_below_max::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>
[INFO] [stdout]   16:     0x5c6f6a01fd9f - tokio[4a3b5a5108b76c30]::runtime::context::enter::<<tokio[4a3b5a5108b76c30]::runtime::Runtime>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::requests_are_recovered_after_inflight_queue_size_falls_below_max::{closure#0}>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5c6f6a0325e2 - rumq_client[38c58c527444fe9a]::eventloop::test::requests_are_recovered_after_inflight_queue_size_falls_below_max
[INFO] [stdout]   18:     0x5c6f6a02c279 - <rumq_client[38c58c527444fe9a]::eventloop::test::requests_are_recovered_after_inflight_queue_size_falls_below_max::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5c6f6a04e42b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   20:     0x5c6f6a05ba95 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5c6f6a055354 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5c6f6a05ebf2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5c6f6a1661e9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x750241ec8dfa - <unknown>
[INFO] [stdout]   25:     0x750241f5c3d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- eventloop::test::some_outgoing_and_no_incoming_packets_should_trigger_pings_on_time stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'eventloop::test::some_outgoing_and_no_incoming_packets_should_trigger_pings_on_time' (2350) panicked at src/eventloop.rs:687:63:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 22, kind: InvalidInput, message: "Invalid argument" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c6f6a166c81 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c6f6a17c89a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c6f6a16b72c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c6f6a142426 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c6f6a15efc9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c6f6a05b140 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c6f6a15f182 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c6f6a1424d2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c6f6a13b179 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c6f6a1432bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c6f6a17cffc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c6f6a17cdd2 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c6f69ffbc4c - <rumq_client[38c58c527444fe9a]::eventloop::broker::Broker>::new::{closure#0}
[INFO] [stdout]   13:     0x5c6f6a000306 - rumq_client[38c58c527444fe9a]::eventloop::test::some_outgoing_and_no_incoming_packets_should_trigger_pings_on_time::{closure#0}
[INFO] [stdout]   14:     0x5c6f6a011687 - <tokio[4a3b5a5108b76c30]::macros::scoped_tls::ScopedKey<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Context>>::set::<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::some_outgoing_and_no_incoming_packets_should_trigger_pings_on_time::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>::{closure#0}, ()>
[INFO] [stdout]   15:     0x5c6f6a04cd82 - tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter::<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::some_outgoing_and_no_incoming_packets_should_trigger_pings_on_time::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>
[INFO] [stdout]   16:     0x5c6f6a02001f - tokio[4a3b5a5108b76c30]::runtime::context::enter::<<tokio[4a3b5a5108b76c30]::runtime::Runtime>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::some_outgoing_and_no_incoming_packets_should_trigger_pings_on_time::{closure#0}>::{closure#0}, ()>
[INFO] [stdout]   17:     0x5c6f6a03297f - rumq_client[38c58c527444fe9a]::eventloop::test::some_outgoing_and_no_incoming_packets_should_trigger_pings_on_time
[INFO] [stdout]   18:     0x5c6f6a02c2b9 - <rumq_client[38c58c527444fe9a]::eventloop::test::some_outgoing_and_no_incoming_packets_should_trigger_pings_on_time::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   19:     0x5c6f6a04e42b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   20:     0x5c6f6a05ba95 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   21:     0x5c6f6a055354 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   22:     0x5c6f6a05ebf2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   23:     0x5c6f6a1661e9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   24:     0x750241ec8dfa - <unknown>
[INFO] [stdout]   25:     0x750241f5c3d4 - clone
[INFO] [stdout]   26:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- eventloop::test::detects_halfopen_connections_in_the_second_ping_request stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'eventloop::test::detects_halfopen_connections_in_the_second_ping_request' (2343) panicked at src/eventloop.rs:687:63:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 22, kind: InvalidInput, message: "Invalid argument" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c6f6a166c81 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c6f6a17c89a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c6f6a16b72c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c6f6a142426 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c6f6a15efc9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c6f6a05b140 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c6f6a15f182 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c6f6a1424d2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c6f6a13b179 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c6f6a1432bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c6f6a17cffc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c6f6a17cdd2 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c6f6a008b2c - <rumq_client[38c58c527444fe9a]::eventloop::broker::Broker>::new::{closure#0}
[INFO] [stdout]   13:     0x5c6f6a005e25 - rumq_client[38c58c527444fe9a]::eventloop::test::detects_halfopen_connections_in_the_second_ping_request::{closure#0}::{closure#0}
[INFO] [stdout]   14:     0x5c6f69ff219e - <tokio[4a3b5a5108b76c30]::runtime::task::harness::Harness<rumq_client[38c58c527444fe9a]::eventloop::test::detects_halfopen_connections_in_the_second_ping_request::{closure#0}::{closure#0}, alloc[2182bb758b4b3781]::sync::Arc<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Shared>>>::poll
[INFO] [stdout]   15:     0x5c6f6a0103a2 - <tokio[4a3b5a5108b76c30]::macros::scoped_tls::ScopedKey<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Context>>::set::<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::detects_halfopen_connections_in_the_second_ping_request::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>::{closure#0}, ()>
[INFO] [stdout]   16:     0x5c6f6a04c942 - tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter::<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::detects_halfopen_connections_in_the_second_ping_request::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>
[INFO] [stdout]   17:     0x5c6f6a01fb1f - tokio[4a3b5a5108b76c30]::runtime::context::enter::<<tokio[4a3b5a5108b76c30]::runtime::Runtime>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::detects_halfopen_connections_in_the_second_ping_request::{closure#0}>::{closure#0}, ()>
[INFO] [stdout]   18:     0x5c6f6a03223f - rumq_client[38c58c527444fe9a]::eventloop::test::detects_halfopen_connections_in_the_second_ping_request
[INFO] [stdout]   19:     0x5c6f6a02c239 - <rumq_client[38c58c527444fe9a]::eventloop::test::detects_halfopen_connections_in_the_second_ping_request::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   20:     0x5c6f6a04e42b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   21:     0x5c6f6a05ba95 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   22:     0x5c6f6a055354 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   23:     0x5c6f6a05ebf2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   24:     0x5c6f6a1661e9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   25:     0x750241ec8dfa - <unknown>
[INFO] [stdout]   26:     0x750241f5c3d4 - clone
[INFO] [stdout]   27:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] thread 'eventloop::test::detects_halfopen_connections_in_the_second_ping_request' (2343) panicked at src/eventloop.rs:494:52:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Network(Io(Os { code: 22, kind: InvalidInput, message: "Invalid argument" }))
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c6f6a166c81 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c6f6a17c89a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c6f6a16b72c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c6f6a142426 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c6f6a15efc9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c6f6a05b140 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c6f6a15f182 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c6f6a1424d2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c6f6a13b179 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c6f6a1432bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c6f6a17cffc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c6f6a17cdd2 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c6f69ffdf30 - rumq_client[38c58c527444fe9a]::eventloop::test::detects_halfopen_connections_in_the_second_ping_request::{closure#0}
[INFO] [stdout]   13:     0x5c6f6a010287 - <tokio[4a3b5a5108b76c30]::macros::scoped_tls::ScopedKey<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Context>>::set::<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::detects_halfopen_connections_in_the_second_ping_request::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>::{closure#0}, ()>
[INFO] [stdout]   14:     0x5c6f6a04c942 - tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter::<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::detects_halfopen_connections_in_the_second_ping_request::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>
[INFO] [stdout]   15:     0x5c6f6a01fb1f - tokio[4a3b5a5108b76c30]::runtime::context::enter::<<tokio[4a3b5a5108b76c30]::runtime::Runtime>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::detects_halfopen_connections_in_the_second_ping_request::{closure#0}>::{closure#0}, ()>
[INFO] [stdout]   16:     0x5c6f6a03223f - rumq_client[38c58c527444fe9a]::eventloop::test::detects_halfopen_connections_in_the_second_ping_request
[INFO] [stdout]   17:     0x5c6f6a02c239 - <rumq_client[38c58c527444fe9a]::eventloop::test::detects_halfopen_connections_in_the_second_ping_request::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   18:     0x5c6f6a04e42b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   19:     0x5c6f6a05ba95 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   20:     0x5c6f6a055354 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   21:     0x5c6f6a05ebf2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   22:     0x5c6f6a1661e9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   23:     0x750241ec8dfa - <unknown>
[INFO] [stdout]   24:     0x750241f5c3d4 - clone
[INFO] [stdout]   25:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- eventloop::test::connection_should_timeout_on_time stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'eventloop::test::connection_should_timeout_on_time' (2342) panicked at src/eventloop.rs:687:63:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Os { code: 22, kind: InvalidInput, message: "Invalid argument" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c6f6a166c81 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c6f6a17c89a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c6f6a16b72c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c6f6a142426 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c6f6a15efc9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c6f6a05b140 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c6f6a15f182 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c6f6a1424d2 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c6f6a13b179 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c6f6a1432bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c6f6a17cffc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c6f6a17cdd2 - core[df12db4294e9bfd3]::result::unwrap_failed
[INFO] [stdout]   12:     0x5c6f6a008b2c - <rumq_client[38c58c527444fe9a]::eventloop::broker::Broker>::new::{closure#0}
[INFO] [stdout]   13:     0x5c6f6a004e70 - rumq_client[38c58c527444fe9a]::eventloop::test::connection_should_timeout_on_time::{closure#0}::{closure#0}
[INFO] [stdout]   14:     0x5c6f69fee0fe - <tokio[4a3b5a5108b76c30]::runtime::task::harness::Harness<rumq_client[38c58c527444fe9a]::eventloop::test::connection_should_timeout_on_time::{closure#0}::{closure#0}, alloc[2182bb758b4b3781]::sync::Arc<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Shared>>>::poll
[INFO] [stdout]   15:     0x5c6f6a00efa2 - <tokio[4a3b5a5108b76c30]::macros::scoped_tls::ScopedKey<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Context>>::set::<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::connection_should_timeout_on_time::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>::{closure#0}, ()>
[INFO] [stdout]   16:     0x5c6f6a04c502 - tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter::<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::connection_should_timeout_on_time::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>
[INFO] [stdout]   17:     0x5c6f6a01f61f - tokio[4a3b5a5108b76c30]::runtime::context::enter::<<tokio[4a3b5a5108b76c30]::runtime::Runtime>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::connection_should_timeout_on_time::{closure#0}>::{closure#0}, ()>
[INFO] [stdout]   18:     0x5c6f6a031aff - rumq_client[38c58c527444fe9a]::eventloop::test::connection_should_timeout_on_time
[INFO] [stdout]   19:     0x5c6f6a02c1b9 - <rumq_client[38c58c527444fe9a]::eventloop::test::connection_should_timeout_on_time::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   20:     0x5c6f6a04e42b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   21:     0x5c6f6a05ba95 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   22:     0x5c6f6a055354 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   23:     0x5c6f6a05ebf2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   24:     0x5c6f6a1661e9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   25:     0x750241ec8dfa - <unknown>
[INFO] [stdout]   26:     0x750241f5c3d4 - clone
[INFO] [stdout]   27:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] thread 'eventloop::test::connection_should_timeout_on_time' (2342) panicked at src/eventloop.rs:341:23:
[INFO] [stdout] assertion failed: false
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5c6f6a166c81 - <<std[70759c8f55707aa4]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[df12db4294e9bfd3]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5c6f6a17c89a - core[df12db4294e9bfd3]::fmt::write
[INFO] [stdout]    2:     0x5c6f6a16b72c - <alloc[2182bb758b4b3781]::vec::Vec<u8> as core[df12db4294e9bfd3]::io::write::Write>::write_fmt
[INFO] [stdout]    3:     0x5c6f6a142426 - std[70759c8f55707aa4]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5c6f6a15efc9 - std[70759c8f55707aa4]::panicking::default_hook
[INFO] [stdout]    5:     0x5c6f6a05b140 - test[9d35eded1c95d3be]::test_main_inner::<test[9d35eded1c95d3be]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5c6f6a15f182 - std[70759c8f55707aa4]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5c6f6a142504 - std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5c6f6a13b179 - std[70759c8f55707aa4]::sys::backtrace::__rust_end_short_backtrace::<std[70759c8f55707aa4]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5c6f6a1432bd - __rustc[8fa7c3cbc660c2b3]::rust_begin_unwind
[INFO] [stdout]   10:     0x5c6f6a17cffc - core[df12db4294e9bfd3]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5c6f6a17cfc2 - core[df12db4294e9bfd3]::panicking::panic
[INFO] [stdout]   12:     0x5c6f69ffc83c - rumq_client[38c58c527444fe9a]::eventloop::test::connection_should_timeout_on_time::{closure#0}
[INFO] [stdout]   13:     0x5c6f6a00ee87 - <tokio[4a3b5a5108b76c30]::macros::scoped_tls::ScopedKey<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::Context>>::set::<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::connection_should_timeout_on_time::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>::{closure#0}, ()>
[INFO] [stdout]   14:     0x5c6f6a04c502 - tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::enter::<<tokio[4a3b5a5108b76c30]::runtime::basic_scheduler::BasicScheduler<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::connection_should_timeout_on_time::{closure#0}>::{closure#0}, (), tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::time::driver::Driver<tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>, tokio[4a3b5a5108b76c30]::park::either::Either<tokio[4a3b5a5108b76c30]::io::driver::Driver, tokio[4a3b5a5108b76c30]::park::thread::ParkThread>>>
[INFO] [stdout]   15:     0x5c6f6a01f61f - tokio[4a3b5a5108b76c30]::runtime::context::enter::<<tokio[4a3b5a5108b76c30]::runtime::Runtime>::block_on<rumq_client[38c58c527444fe9a]::eventloop::test::connection_should_timeout_on_time::{closure#0}>::{closure#0}, ()>
[INFO] [stdout]   16:     0x5c6f6a031aff - rumq_client[38c58c527444fe9a]::eventloop::test::connection_should_timeout_on_time
[INFO] [stdout]   17:     0x5c6f6a02c1b9 - <rumq_client[38c58c527444fe9a]::eventloop::test::connection_should_timeout_on_time::{closure#0} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   18:     0x5c6f6a04e42b - test[9d35eded1c95d3be]::__rust_begin_short_backtrace::<core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>, fn() -> core[df12db4294e9bfd3]::result::Result<(), alloc[2182bb758b4b3781]::string::String>>
[INFO] [stdout]   19:     0x5c6f6a05ba95 - test[9d35eded1c95d3be]::run_test::{closure#0}
[INFO] [stdout]   20:     0x5c6f6a055354 - std[70759c8f55707aa4]::sys::backtrace::__rust_begin_short_backtrace::<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>
[INFO] [stdout]   21:     0x5c6f6a05ebf2 - <std[70759c8f55707aa4]::thread::lifecycle::spawn_unchecked<test[9d35eded1c95d3be]::run_test::{closure#1}, ()>::{closure#1} as core[df12db4294e9bfd3]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   22:     0x5c6f6a1661e9 - <std[70759c8f55707aa4]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   23:     0x750241ec8dfa - <unknown>
[INFO] [stdout]   24:     0x750241f5c3d4 - clone
[INFO] [stdout]   25:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     eventloop::test::connection_should_timeout_on_time
[INFO] [stdout]     eventloop::test::detects_halfopen_connections_in_the_second_ping_request
[INFO] [stdout]     eventloop::test::idle_connection_triggers_pings_on_time
[INFO] [stdout]     eventloop::test::reconnection_resends_unacked_packets_from_the_previous_connection_before_sending_current_connection_requests
[INFO] [stdout]     eventloop::test::reconnection_resumes_from_the_previous_state
[INFO] [stdout]     eventloop::test::requests_are_blocked_after_max_inflight_queue_size
[INFO] [stdout]     eventloop::test::requests_are_recovered_after_inflight_queue_size_falls_below_max
[INFO] [stdout]     eventloop::test::some_incoming_and_no_outgoing_packets_should_trigger_pings_on_time
[INFO] [stdout]     eventloop::test::some_outgoing_and_no_incoming_packets_should_trigger_pings_on_time
[INFO] [stdout]     eventloop::test::throttled_requests_works_with_correct_delays_between_requests
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 13 passed; 10 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.01s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "96210b8e766c29397cdd6f8661a2d12a6a1f444e28c89a71c81a69cefc5116ea", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "96210b8e766c29397cdd6f8661a2d12a6a1f444e28c89a71c81a69cefc5116ea", kill_on_drop: false }`
[INFO] [stdout] 96210b8e766c29397cdd6f8661a2d12a6a1f444e28c89a71c81a69cefc5116ea
