[INFO] fetching crate redpine 0.3.0...
[INFO] testing redpine-0.3.0 against 1.98.0-beta.8+cargoflags=--release for beta-release-1.99-2
[INFO] extracting crate redpine 0.3.0 into /workspace/builds/worker-6-tc1/source
[INFO] started tweaking crates.io crate redpine 0.3.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate redpine 0.3.0
[INFO] tweaked toml for crates.io crate redpine 0.3.0 written to /workspace/builds/worker-6-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate redpine 0.3.0 on toolchain 1.98.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.98.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate redpine 0.3.0 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.98.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded siphasher v1.0.0
[INFO] [stderr]   Downloaded polling v3.2.0
[INFO] [stderr]   Downloaded core_affinity v0.8.1
[INFO] [stderr]   Downloaded errno v0.3.5
[INFO] [stderr]   Downloaded hermit-abi v0.3.3
[INFO] [stderr]   Downloaded concurrent-queue v2.3.0
[INFO] [stderr]   Downloaded bitflags v2.4.1
[INFO] [stderr]   Downloaded rustix v0.38.20
[INFO] [stderr]   Downloaded libc v0.2.149
[INFO] [stderr]   Downloaded linux-raw-sys v0.4.10
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-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:8683fc1fc2eb5c9ac98e0d076ab094b2ffac7f99da555d2b6a2e27f346de2ec7" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 36741bb4a00451b3ef8b369fa499f11bfc8638754d15958582b3cb14d78640c3
[INFO] running `Command { std: "docker" "start" "36741bb4a00451b3ef8b369fa499f11bfc8638754d15958582b3cb14d78640c3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "36741bb4a00451b3ef8b369fa499f11bfc8638754d15958582b3cb14d78640c3", 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" "36741bb4a00451b3ef8b369fa499f11bfc8638754d15958582b3cb14d78640c3" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "36741bb4a00451b3ef8b369fa499f11bfc8638754d15958582b3cb14d78640c3", 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" "36741bb4a00451b3ef8b369fa499f11bfc8638754d15958582b3cb14d78640c3" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.149
[INFO] [stderr]    Compiling rustix v0.38.20
[INFO] [stderr]    Compiling bitflags v2.4.1
[INFO] [stderr]    Compiling pin-project-lite v0.2.13
[INFO] [stderr]    Compiling tracing-core v0.1.32
[INFO] [stderr]    Compiling linux-raw-sys v0.4.10
[INFO] [stderr]    Compiling siphasher v1.0.0
[INFO] [stderr]    Compiling tracing v0.1.40
[INFO] [stderr]    Compiling getrandom v0.2.10
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling polling v3.2.0
[INFO] [stderr]    Compiling redpine v0.3.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 8.43s
[INFO] running `Command { std: "docker" "inspect" "36741bb4a00451b3ef8b369fa499f11bfc8638754d15958582b3cb14d78640c3", 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" "36741bb4a00451b3ef8b369fa499f11bfc8638754d15958582b3cb14d78640c3" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.149
[INFO] [stderr]    Compiling md5 v0.7.0
[INFO] [stderr]    Compiling getrandom v0.2.10
[INFO] [stderr]    Compiling num_cpus v1.16.0
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling core_affinity v0.8.1
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling redpine v0.3.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: value assigned to `read_idx` is never read
[INFO] [stdout]    --> tests/common/util.rs:100:17
[INFO] [stdout]     |
[INFO] [stdout] 100 |                 read_idx += 1;
[INFO] [stdout]     |                 ^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = help: maybe it is overwritten before being read?
[INFO] [stdout]     = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `read_idx` is never read
[INFO] [stdout]    --> tests/common/util.rs:100:17
[INFO] [stdout]     |
[INFO] [stdout] 100 |                 read_idx += 1;
[INFO] [stdout]     |                 ^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = help: maybe it is overwritten before being read?
[INFO] [stdout]     = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `read_idx` is never read
[INFO] [stdout]    --> tests/common/util.rs:100:17
[INFO] [stdout]     |
[INFO] [stdout] 100 |                 read_idx += 1;
[INFO] [stdout]     |                 ^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = help: maybe it is overwritten before being read?
[INFO] [stdout]     = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `read_idx` is never read
[INFO] [stdout]    --> tests/common/util.rs:100:17
[INFO] [stdout]     |
[INFO] [stdout] 100 |                 read_idx += 1;
[INFO] [stdout]     |                 ^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = help: maybe it is overwritten before being read?
[INFO] [stdout]     = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: value assigned to `read_idx` is never read
[INFO] [stdout]    --> tests/common/util.rs:100:17
[INFO] [stdout]     |
[INFO] [stdout] 100 |                 read_idx += 1;
[INFO] [stdout]     |                 ^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = help: maybe it is overwritten before being read?
[INFO] [stdout]     = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 10.23s
[INFO] running `Command { std: "docker" "inspect" "36741bb4a00451b3ef8b369fa499f11bfc8638754d15958582b3cb14d78640c3", 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" "36741bb4a00451b3ef8b369fa499f11bfc8638754d15958582b3cb14d78640c3" "/opt/rustwide/cargo-home/bin/cargo" "+1.98.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr] warning: value assigned to `read_idx` is never read
[INFO] [stderr]    --> tests/common/util.rs:100:17
[INFO] [stderr]     |
[INFO] [stderr] 100 |                 read_idx += 1;
[INFO] [stderr]     |                 ^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = help: maybe it is overwritten before being read?
[INFO] [stderr]     = note: `#[warn(unused_assignments)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: `redpine` (test "single_client_connect_disconnect") generated 1 warning
[INFO] [stderr] warning: `redpine` (test "multi_client_connect_disconnect") generated 1 warning (1 duplicate)
[INFO] [stderr] warning: `redpine` (test "send_while_blocking") generated 1 warning (1 duplicate)
[INFO] [stderr] warning: `redpine` (test "multi_client_transfer") generated 1 warning (1 duplicate)
[INFO] [stderr] warning: `redpine` (test "single_client_transfer") generated 1 warning (1 duplicate)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.05s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/redpine-ef7c1b901fa2b114)
[INFO] [stdout] 
[INFO] [stdout] running 29 tests
[INFO] [stdout] test buffer::reliable::tests::send_fragmentation ... ok
[INFO] [stdout] test endpoint::cc::tests::basic ... ok
[INFO] [stdout] test buffer::unreliable::tests::send_fragmentation ... ok
[INFO] [stdout] test buffer::reliable::tests::resend ... ok
[INFO] [stdout] test buffer::reliable::tests::resend_resync ... ok
[INFO] [stdout] test buffer::reliable::tests::invalid_acks ... ok
[INFO] [stdout] test endpoint::prio::tests::simple ... ok
[INFO] [stdout] test endpoint::prio::tests::maxed_counters ... ok
[INFO] [stdout] test endpoint::prio::tests::single_channels ... ok
[INFO] [stdout] test endpoint::segment_rx::tests::dup_rejection ... ok
[INFO] [stdout] test endpoint::segment_rx::tests::sequential_receive ... ok
[INFO] [stdout] test endpoint::segment_rx::tests::nonsequential_receive ... ok
[INFO] [stdout] test endpoint::segment_tx::tests::bad_nonce_verification ... ok
[INFO] [stdout] test endpoint::segment_rx::tests::skips ... ok
[INFO] [stdout] test endpoint::segment_tx::tests::full_window_nonce_verification ... ok
[INFO] [stdout] test endpoint::segment_tx::tests::send_receive_window ... ok
[INFO] [stdout] test endpoint::segment_tx::tests::drop_detection ... ok
[INFO] [stdout] test frame::serial::crc::tests::basic ... ok
[INFO] [stdout] test endpoint::tests::tx_state ... ok
[INFO] [stdout] test timer_queue::tests::test_expired_timer_removed ... ok
[INFO] [stdout] test timer_queue::tests::test_no_trigger_if_test_returns_false ... ok
[INFO] [stdout] test timer_queue::tests::test_trigger_callback_called ... ok
[INFO] [stdout] test frame::serial::tests::stream_data ... ok
[INFO] [stdout] test timer_queue::tests::test_limit_respected ... ok
[INFO] [stdout] test frame::serial::crc::tests::zero_nonzero_crc ... ok
[INFO] [stdout] test frame::serial::crc::tests::random ... ok
[INFO] [stdout] test endpoint::segment_rx::tests::beyond_window ... ok
[INFO] [stdout] test endpoint::segment_tx::tests::ideal_nonce_verification ... ok
[INFO] [stdout] test buffer::reliable::tests::ack_advancement ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 29 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/client.rs (/opt/rustwide/target/release/deps/client-b85bbc1db60f8a7d)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/server.rs (/opt/rustwide/target/release/deps/server-7179ad118fb0e0d7)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/multi_client_connect_disconnect.rs (/opt/rustwide/target/release/deps/multi_client_connect_disconnect-872061d11ddf055f)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test multi_client_connect_disconnect ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- multi_client_connect_disconnect stdout ----
[INFO] [stdout] client 11 connected
[INFO] [stdout] client 14 connected
[INFO] [stdout] client 17 connected
[INFO] [stdout] client 15 connected
[INFO] [stdout] client 1 connected
[INFO] [stdout] client 62 connected
[INFO] [stdout] client 54 connected
[INFO] [stdout] client 53 connected
[INFO] [stdout] client 63 connected
[INFO] [stdout] client 56 connected
[INFO] [stdout] client 50 connected
[INFO] [stdout] client 57 connected
[INFO] [stdout] client 49 connected
[INFO] [stdout] client 37 connected
[INFO] [stdout] client 59 connected
[INFO] [stdout] client 43 connected
[INFO] [stdout] client 30 connected
[INFO] [stdout] client 51 connected
[INFO] [stdout] client 46 connected
[INFO] [stdout] client 41 connected
[INFO] [stdout] client 47 connected
[INFO] [stdout] client 48 connected
[INFO] [stdout] client 35 connected
[INFO] [stdout] client 18 connected
[INFO] [stdout] client 44 connected
[INFO] [stdout] client 42 connected
[INFO] [stdout] client 36 connected
[INFO] [stdout] client 25 connected
[INFO] [stdout] client 10 connected
[INFO] [stdout] client 32 connected
[INFO] [stdout] client 20 connected
[INFO] [stdout] client 27 connected
[INFO] [stdout] client 38 connected
[INFO] [stdout] client 26 connected
[INFO] [stdout] client 33 connected
[INFO] [stdout] client 23 connected
[INFO] [stdout] client 29 connected
[INFO] [stdout] client 21 connected
[INFO] [stdout] client 55 connected
[INFO] [stdout] client 40 connected
[INFO] [stdout] client 58 connected
[INFO] [stdout] client 13 connected
[INFO] [stdout] client 28 connected
[INFO] [stdout] client 19 connected
[INFO] [stdout] client 34 connected
[INFO] [stdout] client 16 connected
[INFO] [stdout] client 60 connected
[INFO] [stdout] client 45 connected
[INFO] [stdout] client 39 connected
[INFO] [stdout] client 22 connected
[INFO] [stdout] client 52 connected
[INFO] [stdout] client 61 connected
[INFO] [stdout] client 31 connected
[INFO] [stdout] client 7 connected
[INFO] [stdout] client 24 connected
[INFO] [stdout] 
[INFO] [stdout] thread '<unnamed>' (961) panicked at tests/common/basic_trial.rs:164:9:
[INFO] [stdout] timed out waiting for connect
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5b6296909491 - <<std[97b98282bc616a66]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[832e9a552fc17241]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5b629691e31a - core[832e9a552fc17241]::fmt::write
[INFO] [stdout]    2:     0x5b629690dc4c - <alloc[3ecab9f82c41dc7d]::vec::Vec<u8> as std[97b98282bc616a66]::io::Write>::write_fmt
[INFO] [stdout]    3:     0x5b62968e6b76 - std[97b98282bc616a66]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5b6296900f49 - std[97b98282bc616a66]::panicking::default_hook
[INFO] [stdout]    5:     0x5b629689b5f0 - test[39766f3d73431624]::test_main_inner::<test[39766f3d73431624]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5b6296901102 - std[97b98282bc616a66]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5b62968e6c54 - std[97b98282bc616a66]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5b62968de4c9 - std[97b98282bc616a66]::sys::backtrace::__rust_end_short_backtrace::<std[97b98282bc616a66]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5b62968e7a0d - __rustc[e3d8617bb01b7f69]::rust_begin_unwind
[INFO] [stdout]   10:     0x5b629691ea4c - core[832e9a552fc17241]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5b6296873e80 - multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run_client
[INFO] [stdout]   12:     0x5b6296877ada - std[97b98282bc616a66]::sys::backtrace::__rust_begin_short_backtrace::<multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run::{closure#1}, ()>
[INFO] [stdout]   13:     0x5b6296870f43 - <std[97b98282bc616a66]::thread::lifecycle::spawn_unchecked<multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run::{closure#1}, ()>::{closure#1} as core[832e9a552fc17241]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   14:     0x5b629690872f - <std[97b98282bc616a66]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   15:     0x70407b8f307a - <unknown>
[INFO] [stdout]   16:     0x70407b986534 - clone
[INFO] [stdout]   17:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] thread '<unnamed>' (964) panicked at tests/common/basic_trial.rs:164:9:
[INFO] [stdout] timed out waiting for connect
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5b6296909491 - <<std[97b98282bc616a66]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[832e9a552fc17241]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5b629691e31a - core[832e9a552fc17241]::fmt::write
[INFO] [stdout]    2:     0x5b629690dc4c - <alloc[3ecab9f82c41dc7d]::vec::Vec<u8> as std[97b98282bc616a66]::io::Write>::write_fmt
[INFO] [stdout]    3:     0x5b62968e6b76 - std[97b98282bc616a66]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5b6296900f49 - std[97b98282bc616a66]::panicking::default_hook
[INFO] [stdout]    5:     0x5b629689b5f0 - test[39766f3d73431624]::test_main_inner::<test[39766f3d73431624]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5b6296901102 - std[97b98282bc616a66]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5b62968e6c54 - std[97b98282bc616a66]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5b62968de4c9 - std[97b98282bc616a66]::sys::backtrace::__rust_end_short_backtrace::<std[97b98282bc616a66]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5b62968e7a0d - __rustc[e3d8617bb01b7f69]::rust_begin_unwind
[INFO] [stdout]   10:     0x5b629691ea4c - core[832e9a552fc17241]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5b6296873e80 - multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run_client
[INFO] [stdout]   12:     0x5b6296877ada - std[97b98282bc616a66]::sys::backtrace::__rust_begin_short_backtrace::<multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run::{closure#1}, ()>
[INFO] [stdout]   13:     0x5b6296870f43 - <std[97b98282bc616a66]::thread::lifecycle::spawn_unchecked<multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run::{closure#1}, ()>::{closure#1} as core[832e9a552fc17241]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   14:     0x5b629690872f - <std[97b98282bc616a66]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   15:     0x70407b8f307a - <unknown>
[INFO] [stdout]   16:     0x70407b986534 - clone
[INFO] [stdout]   17:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] thread '<unnamed>' (959) panicked at tests/common/basic_trial.rs:164:9:
[INFO] [stdout] timed out waiting for connect
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5b6296909491 - <<std[97b98282bc616a66]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[832e9a552fc17241]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5b629691e31a - core[832e9a552fc17241]::fmt::write
[INFO] [stdout]    2:     0x5b629690dc4c - <alloc[3ecab9f82c41dc7d]::vec::Vec<u8> as std[97b98282bc616a66]::io::Write>::write_fmt
[INFO] [stdout]    3:     0x5b62968e6b76 - std[97b98282bc616a66]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5b6296900f49 - std[97b98282bc616a66]::panicking::default_hook
[INFO] [stdout]    5:     0x5b629689b5f0 - test[39766f3d73431624]::test_main_inner::<test[39766f3d73431624]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5b6296901102 - std[97b98282bc616a66]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5b62968e6c54 - std[97b98282bc616a66]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5b62968de4c9 - std[97b98282bc616a66]::sys::backtrace::__rust_end_short_backtrace::<std[97b98282bc616a66]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5b62968e7a0d - __rustc[e3d8617bb01b7f69]::rust_begin_unwind
[INFO] [stdout]   10:     0x5b629691ea4c - core[832e9a552fc17241]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5b6296873e80 - multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run_client
[INFO] [stdout]   12:     0x5b6296877ada - std[97b98282bc616a66]::sys::backtrace::__rust_begin_short_backtrace::<multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run::{closure#1}, ()>
[INFO] [stdout]   13:     0x5b6296870f43 - <std[97b98282bc616a66]::thread::lifecycle::spawn_unchecked<multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run::{closure#1}, ()>::{closure#1} as core[832e9a552fc17241]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   14:     0x5b629690872f - <std[97b98282bc616a66]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   15:     0x70407b8f307a - <unknown>
[INFO] [stdout]   16:     0x70407b986534 - clone
[INFO] [stdout]   17:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] thread '<unnamed>' (962) panicked at tests/common/basic_trial.rs:164:9:
[INFO] [stdout] timed out waiting for connect
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5b6296909491 - <<std[97b98282bc616a66]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[832e9a552fc17241]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5b629691e31a - core[832e9a552fc17241]::fmt::write
[INFO] [stdout]    2:     0x5b629690dc4c - <alloc[3ecab9f82c41dc7d]::vec::Vec<u8> as std[97b98282bc616a66]::io::Write>::write_fmt
[INFO] [stdout]    3:     0x5b62968e6b76 - std[97b98282bc616a66]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5b6296900f49 - std[97b98282bc616a66]::panicking::default_hook
[INFO] [stdout]    5:     0x5b629689b5f0 - test[39766f3d73431624]::test_main_inner::<test[39766f3d73431624]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5b6296901102 - std[97b98282bc616a66]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5b62968e6c54 - std[97b98282bc616a66]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5b62968de4c9 - std[97b98282bc616a66]::sys::backtrace::__rust_end_short_backtrace::<std[97b98282bc616a66]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5b62968e7a0d - __rustc[e3d8617bb01b7f69]::rust_begin_unwind
[INFO] [stdout]   10:     0x5b629691ea4c - core[832e9a552fc17241]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5b6296873e80 - multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run_client
[INFO] [stdout]   12:     0x5b6296877ada - std[97b98282bc616a66]::sys::backtrace::__rust_begin_short_backtrace::<multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run::{closure#1}, ()>
[INFO] [stdout]   13:     0x5b6296870f43 - <std[97b98282bc616a66]::thread::lifecycle::spawn_unchecked<multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run::{closure#1}, ()>::{closure#1} as core[832e9a552fc17241]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   14:     0x5b629690872f - <std[97b98282bc616a66]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   15:     0x70407b8f307a - <unknown>
[INFO] [stdout]   16:     0x70407b986534 - clone
[INFO] [stdout]   17:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] thread '<unnamed>' (967) panicked at tests/common/basic_trial.rs:164:9:
[INFO] [stdout] timed out waiting for connect
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5b6296909491 - <<std[97b98282bc616a66]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[832e9a552fc17241]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5b629691e31a - core[832e9a552fc17241]::fmt::write
[INFO] [stdout]    2:     0x5b629690dc4c - <alloc[3ecab9f82c41dc7d]::vec::Vec<u8> as std[97b98282bc616a66]::io::Write>::write_fmt
[INFO] [stdout]    3:     0x5b62968e6b76 - std[97b98282bc616a66]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5b6296900f49 - std[97b98282bc616a66]::panicking::default_hook
[INFO] [stdout]    5:     0x5b629689b5f0 - test[39766f3d73431624]::test_main_inner::<test[39766f3d73431624]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5b6296901102 - std[97b98282bc616a66]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5b62968e6c54 - std[97b98282bc616a66]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5b62968de4c9 - std[97b98282bc616a66]::sys::backtrace::__rust_end_short_backtrace::<std[97b98282bc616a66]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5b62968e7a0d - __rustc[e3d8617bb01b7f69]::rust_begin_unwind
[INFO] [stdout]   10:     0x5b629691ea4c - core[832e9a552fc17241]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5b6296873e80 - multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run_client
[INFO] [stdout]   12:     0x5b6296877ada - std[97b98282bc616a66]::sys::backtrace::__rust_begin_short_backtrace::<multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run::{closure#1}, ()>
[INFO] [stdout]   13:     0x5b6296870f43 - <std[97b98282bc616a66]::thread::lifecycle::spawn_unchecked<multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run::{closure#1}, ()>::{closure#1} as core[832e9a552fc17241]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   14:     0x5b629690872f - <std[97b98282bc616a66]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   15:     0x70407b8f307a - <unknown>
[INFO] [stdout]   16:     0x70407b986534 - clone
[INFO] [stdout]   17:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] thread '<unnamed>' (968) panicked at tests/common/basic_trial.rs:164:9:
[INFO] [stdout] timed out waiting for connect
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5b6296909491 - <<std[97b98282bc616a66]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[832e9a552fc17241]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5b629691e31a - core[832e9a552fc17241]::fmt::write
[INFO] [stdout]    2:     0x5b629690dc4c - <alloc[3ecab9f82c41dc7d]::vec::Vec<u8> as std[97b98282bc616a66]::io::Write>::write_fmt
[INFO] [stdout]    3:     0x5b62968e6b76 - std[97b98282bc616a66]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5b6296900f49 - std[97b98282bc616a66]::panicking::default_hook
[INFO] [stdout]    5:     0x5b629689b5f0 - test[39766f3d73431624]::test_main_inner::<test[39766f3d73431624]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5b6296901102 - std[97b98282bc616a66]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5b62968e6c54 - std[97b98282bc616a66]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5b62968de4c9 - std[97b98282bc616a66]::sys::backtrace::__rust_end_short_backtrace::<std[97b98282bc616a66]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5b62968e7a0d - __rustc[e3d8617bb01b7f69]::rust_begin_unwind
[INFO] [stdout]   10:     0x5b629691ea4c - core[832e9a552fc17241]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5b6296873e80 - multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run_client
[INFO] [stdout]   12:     0x5b6296877ada - std[97b98282bc616a66]::sys::backtrace::__rust_begin_short_backtrace::<multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run::{closure#1}, ()>
[INFO] [stdout]   13:     0x5b6296870f43 - <std[97b98282bc616a66]::thread::lifecycle::spawn_unchecked<multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run::{closure#1}, ()>::{closure#1} as core[832e9a552fc17241]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   14:     0x5b629690872f - <std[97b98282bc616a66]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   15:     0x70407b8f307a - <unknown>
[INFO] [stdout]   16:     0x70407b986534 - clone
[INFO] [stdout]   17:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] thread 'multi_client_connect_disconnect' (957) panicked at tests/common/basic_trial.rs:371:30:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: Any { .. }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5b6296909491 - <<std[97b98282bc616a66]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[832e9a552fc17241]::fmt::Display>::fmt
[INFO] [stdout]    1:     0x5b629691e31a - core[832e9a552fc17241]::fmt::write
[INFO] [stdout]    2:     0x5b629690dc4c - <alloc[3ecab9f82c41dc7d]::vec::Vec<u8> as std[97b98282bc616a66]::io::Write>::write_fmt
[INFO] [stdout]    3:     0x5b62968e6b76 - std[97b98282bc616a66]::panicking::default_hook::{closure#0}
[INFO] [stdout]    4:     0x5b6296900f49 - std[97b98282bc616a66]::panicking::default_hook
[INFO] [stdout]    5:     0x5b629689b5f0 - test[39766f3d73431624]::test_main_inner::<test[39766f3d73431624]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]    6:     0x5b6296901102 - std[97b98282bc616a66]::panicking::panic_with_hook
[INFO] [stdout]    7:     0x5b62968e6c22 - std[97b98282bc616a66]::panicking::panic_handler::{closure#0}
[INFO] [stdout]    8:     0x5b62968de4c9 - std[97b98282bc616a66]::sys::backtrace::__rust_end_short_backtrace::<std[97b98282bc616a66]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]    9:     0x5b62968e7a0d - __rustc[e3d8617bb01b7f69]::rust_begin_unwind
[INFO] [stdout]   10:     0x5b629691ea4c - core[832e9a552fc17241]::panicking::panic_fmt
[INFO] [stdout]   11:     0x5b629691e822 - core[832e9a552fc17241]::result::unwrap_failed
[INFO] [stdout]   12:     0x5b6296876a38 - multi_client_connect_disconnect[b62203fbd8172ce2]::common::basic_trial::run
[INFO] [stdout]   13:     0x5b6296878123 - <multi_client_connect_disconnect[b62203fbd8172ce2]::multi_client_connect_disconnect::{closure#0} as core[832e9a552fc17241]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]   14:     0x5b629688e92b - test[39766f3d73431624]::__rust_begin_short_backtrace::<core[832e9a552fc17241]::result::Result<(), alloc[3ecab9f82c41dc7d]::string::String>, fn() -> core[832e9a552fc17241]::result::Result<(), alloc[3ecab9f82c41dc7d]::string::String>>
[INFO] [stdout]   15:     0x5b629689bf75 - test[39766f3d73431624]::run_test::{closure#0}
[INFO] [stdout]   16:     0x5b6296896a24 - std[97b98282bc616a66]::sys::backtrace::__rust_begin_short_backtrace::<test[39766f3d73431624]::run_test::{closure#1}, ()>
[INFO] [stdout]   17:     0x5b629689f0c2 - <std[97b98282bc616a66]::thread::lifecycle::spawn_unchecked<test[39766f3d73431624]::run_test::{closure#1}, ()>::{closure#1} as core[832e9a552fc17241]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]   18:     0x5b629690872f - <std[97b98282bc616a66]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]   19:     0x70407b8f307a - <unknown>
[INFO] [stdout]   20:     0x70407b986534 - clone
[INFO] [stdout]   21:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     multi_client_connect_disconnect
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.00s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test multi_client_connect_disconnect`
[INFO] running `Command { std: "docker" "inspect" "36741bb4a00451b3ef8b369fa499f11bfc8638754d15958582b3cb14d78640c3", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "36741bb4a00451b3ef8b369fa499f11bfc8638754d15958582b3cb14d78640c3", kill_on_drop: false }`
[INFO] [stdout] 36741bb4a00451b3ef8b369fa499f11bfc8638754d15958582b3cb14d78640c3
