[INFO] fetching crate crossfire 2.0.26...
[INFO] testing crossfire-2.0.26 against master#ad85bc524b1ad696e42061ad8338d382dffbdbe5 for pr-146237
[INFO] extracting crate crossfire 2.0.26 into /workspace/builds/worker-7-tc1/source
[INFO] started tweaking crates.io crate crossfire 2.0.26
[INFO] finished tweaking crates.io crate crossfire 2.0.26
[INFO] tweaked toml for crates.io crate crossfire 2.0.26 written to /workspace/builds/worker-7-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate crossfire 2.0.26 on toolchain ad85bc524b1ad696e42061ad8338d382dffbdbe5
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate crossfire 2.0.26 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" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded kanal v0.1.1
[INFO] [stderr]   Downloaded anes v0.2.1
[INFO] [stderr]   Downloaded io-buffer v1.0.3
[INFO] [stderr]   Downloaded file-rotate v0.8.0
[INFO] [stderr]   Downloaded captains-log-helper v0.3.1
[INFO] [stderr]   Downloaded crossfire v2.0.24
[INFO] [stderr]   Downloaded captains-log v0.8.6
[INFO] [stderr]   Downloaded criterion2 v3.0.2
[INFO] [stderr]   Downloaded fail v0.5.1
[INFO] [stderr]   Downloaded ring-file v0.1.2
[INFO] [stderr]   Downloaded bpaf v0.9.20
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] f0ebaf1b5ae610e26a2c767513fefaef567a810edde1fb3e2d054d99348b907c
[INFO] running `Command { std: "docker" "start" "-a" "f0ebaf1b5ae610e26a2c767513fefaef567a810edde1fb3e2d054d99348b907c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "f0ebaf1b5ae610e26a2c767513fefaef567a810edde1fb3e2d054d99348b907c", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "f0ebaf1b5ae610e26a2c767513fefaef567a810edde1fb3e2d054d99348b907c", kill_on_drop: false }`
[INFO] [stdout] f0ebaf1b5ae610e26a2c767513fefaef567a810edde1fb3e2d054d99348b907c
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] d9cb006987675b51d6c04b8329a37368d905428e5716b956c3806a75c6e91d0f
[INFO] running `Command { std: "docker" "start" "-a" "d9cb006987675b51d6c04b8329a37368d905428e5716b956c3806a75c6e91d0f", kill_on_drop: false }`
[INFO] [stderr]    Compiling futures-sink v0.3.31
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling parking_lot_core v0.9.11
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling crossbeam-queue v0.3.12
[INFO] [stderr]    Compiling crossbeam-deque v0.8.6
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling parking_lot v0.12.4
[INFO] [stderr]    Compiling crossbeam v0.8.4
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling enum_dispatch v0.3.13
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling crossfire v2.0.26 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 9.16s
[INFO] running `Command { std: "docker" "inspect" "d9cb006987675b51d6c04b8329a37368d905428e5716b956c3806a75c6e91d0f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d9cb006987675b51d6c04b8329a37368d905428e5716b956c3806a75c6e91d0f", kill_on_drop: false }`
[INFO] [stdout] d9cb006987675b51d6c04b8329a37368d905428e5716b956c3806a75c6e91d0f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e098b8b4ad4ac58c9e4df12d292384ea6c9bfb606ae5f3887444071169ba6f54
[INFO] running `Command { std: "docker" "start" "-a" "e098b8b4ad4ac58c9e4df12d292384ea6c9bfb606ae5f3887444071169ba6f54", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling getrandom v0.2.16
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling value-bag v1.11.1
[INFO] [stderr]    Compiling nix v0.30.1
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling indexmap v2.11.0
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling bitflags v2.9.3
[INFO] [stderr]    Compiling parking_lot v0.12.4
[INFO] [stderr]    Compiling concurrent-queue v2.5.0
[INFO] [stderr]    Compiling half v2.6.0
[INFO] [stderr]    Compiling signal-hook v0.3.18
[INFO] [stderr]    Compiling chrono v0.4.41
[INFO] [stderr]    Compiling flate2 v1.1.2
[INFO] [stderr]    Compiling object v0.36.7
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling event-listener v5.4.1
[INFO] [stderr]    Compiling signal-hook-registry v1.4.6
[INFO] [stderr]    Compiling rand_chacha v0.3.1
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling log v0.4.27
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling nanorand v0.7.0
[INFO] [stderr]    Compiling spin v0.9.8
[INFO] [stderr]    Compiling rand v0.8.5
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling crossbeam v0.8.4
[INFO] [stderr]    Compiling event-listener-strategy v0.5.4
[INFO] [stderr]    Compiling anes v0.2.1
[INFO] [stderr]    Compiling exitcode v1.1.2
[INFO] [stderr]    Compiling bpaf v0.9.20
[INFO] [stderr]    Compiling flume v0.11.1
[INFO] [stderr]    Compiling kanal v0.1.1
[INFO] [stderr]    Compiling async-channel v2.5.0
[INFO] [stderr]    Compiling toml_edit v0.22.27
[INFO] [stderr]    Compiling file-rotate v0.8.0
[INFO] [stderr]    Compiling fail v0.5.1
[INFO] [stderr]    Compiling regex-automata v0.4.10
[INFO] [stderr]    Compiling io-buffer v1.0.3
[INFO] [stderr]    Compiling ring-file v0.1.2
[INFO] [stderr]    Compiling proc-macro-crate v3.3.0
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling serde_derive v1.0.219
[INFO] [stderr]    Compiling enum_dispatch v0.3.13
[INFO] [stderr]    Compiling tokio-macros v2.5.0
[INFO] [stderr]    Compiling captains-log-helper v0.3.1
[INFO] [stderr]    Compiling backtrace v0.3.75
[INFO] [stderr]    Compiling regex v1.11.2
[INFO] [stderr]    Compiling tokio v1.47.1
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling rstest_macros v0.26.1
[INFO] [stderr]    Compiling serde v1.0.219
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling rstest v0.26.1
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling crossfire v2.0.24
[INFO] [stderr]    Compiling crossfire v2.0.26 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling captains-log v0.8.6
[INFO] [stderr]    Compiling serde_json v1.0.143
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling criterion2 v3.0.2
[INFO] [stdout] warning: struct `SpuriousTx` is never constructed
[INFO] [stdout]    --> src/tests/test_async.rs:945:8
[INFO] [stdout]     |
[INFO] [stdout] 945 | struct SpuriousTx {
[INFO] [stdout]     |        ^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: struct `SpuriousRx` is never constructed
[INFO] [stdout]    --> src/tests/test_async.rs:976:8
[INFO] [stdout]     |
[INFO] [stdout] 976 | struct SpuriousRx {
[INFO] [stdout]     |        ^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 32.95s
[INFO] running `Command { std: "docker" "inspect" "e098b8b4ad4ac58c9e4df12d292384ea6c9bfb606ae5f3887444071169ba6f54", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e098b8b4ad4ac58c9e4df12d292384ea6c9bfb606ae5f3887444071169ba6f54", kill_on_drop: false }`
[INFO] [stdout] e098b8b4ad4ac58c9e4df12d292384ea6c9bfb606ae5f3887444071169ba6f54
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:7ad1b28ee6f5f7f699f6cf7015098d6ccdd96d6f2d78dd06228f5b4c9faf309c" "/opt/rustwide/cargo-home/bin/cargo" "+ad85bc524b1ad696e42061ad8338d382dffbdbe5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 60ac783048ce385ad3fd204bab50cd55105d565cdae467cb444a95d10ff47aba
[INFO] running `Command { std: "docker" "start" "-a" "60ac783048ce385ad3fd204bab50cd55105d565cdae467cb444a95d10ff47aba", kill_on_drop: false }`
[INFO] [stderr] warning: struct `SpuriousTx` is never constructed
[INFO] [stderr]    --> src/tests/test_async.rs:945:8
[INFO] [stderr]     |
[INFO] [stderr] 945 | struct SpuriousTx {
[INFO] [stderr]     |        ^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: struct `SpuriousRx` is never constructed
[INFO] [stderr]    --> src/tests/test_async.rs:976:8
[INFO] [stderr]     |
[INFO] [stderr] 976 | struct SpuriousRx {
[INFO] [stderr]     |        ^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: `crossfire` (lib test) generated 2 warnings
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/crossfire-57457c4b267ea3c2)
[INFO] [stdout] 
[INFO] [stdout] running 280 tests
[INFO] [stdout] test collections::tests::test_weak_cell ... ok
[INFO] [stderr] [2025-09-11 04:43:35.568432][INFO][test_async.rs:19] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<<
[INFO] [stdout] test collections::tests::test_locked_queue ... ok
[INFO] [stderr] [2025-09-11 04:43:35.569169][INFO][test_async.rs:357] <<< test_basic_bounded_recv_after_sender_close (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stdout] test locked_waker::tests::test_waker ... ok
[INFO] [stderr] [2025-09-11 04:43:35.569246][INFO][test_async.rs:372] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_rx::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.569243][INFO][test_async.rs:19] >>> test_basic_bounded_empty_full_drop_rx return () in 17.19µs >>>
[INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_tx::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.568778][INFO][test_async.rs:44] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.569286][INFO][test_async.rs:44] >>> test_basic_bounded_empty_full_drop_tx return () in 17.93µs >>>
[INFO] [stderr] [2025-09-11 04:43:35.569225][INFO][test_async.rs:171] <<< test_basic_bounded_rx_drop (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.569311][INFO][test_async.rs:179] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.569259][INFO][test_async.rs:171] <<< test_basic_bounded_rx_drop (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.569341][INFO][test_async.rs:179] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.568422][INFO][test_async.rs:221] <<< test_basic_bounded_1_thread (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.569385][INFO][test_async.rs:230] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.568313][INFO][test_async.rs:221] <<< test_basic_bounded_1_thread (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.569433][INFO][test_async.rs:230] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.569404][INFO][test_async.rs:171] <<< test_basic_bounded_rx_drop (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.569575][INFO][test_async.rs:179] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.569572][INFO][test_async.rs:1088] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.569476][INFO][test_async.rs:69] <<< test_basic_compile_bounded_empty_full () enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.569646][INFO][test_async.rs:1099] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.569669][INFO][test_async.rs:69] >>> test_basic_compile_bounded_empty_full return () in 20.29µs >>>
[INFO] [stderr] [2025-09-11 04:43:35.568589][INFO][test_async.rs:19] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.568416][INFO][test_async.rs:19] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.568263][INFO][test_async.rs:221] <<< test_basic_bounded_1_thread (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.568989][INFO][test_async.rs:357] <<< test_basic_bounded_recv_after_sender_close (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.569798][INFO][test_async.rs:230] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.569805][INFO][test_async.rs:19] >>> test_basic_bounded_empty_full_drop_rx return () in 8.79µs >>>
[INFO] [stderr] [2025-09-11 04:43:35.569815][INFO][test_async.rs:372] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.568831][INFO][test_async.rs:44] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.569889][INFO][test_async.rs:19] >>> test_basic_bounded_empty_full_drop_rx return () in 156.59µs >>>
[INFO] [stderr] [2025-09-11 04:43:35.569876][INFO][test_async.rs:44] >>> test_basic_bounded_empty_full_drop_tx return () in 7.12µs >>>
[INFO] [stderr] [2025-09-11 04:43:35.568779][INFO][test_async.rs:44] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.569062][INFO][test_async.rs:357] <<< test_basic_bounded_recv_after_sender_close (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.570226][INFO][test_async.rs:372] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.570220][INFO][test_async.rs:44] >>> test_basic_bounded_empty_full_drop_tx return () in 10.12µs >>>
[INFO] [stdout] test tests::test_async::test_basic_compile_bounded_empty_full ... ok
[INFO] [stderr] [2025-09-11 04:43:35.571239][INFO][test_async.rs:1088] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.571315][INFO][test_async.rs:1099] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.571759][INFO][test_async.rs:1088] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.571947][INFO][test_async.rs:1099] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_rx::case_1 ... ok
[INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_tx::case_3 ... ok
[INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_rx::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.574597][INFO][test_async.rs:1088] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_tx::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.574695][INFO][test_async.rs:1099] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_basic_send_timeout_async::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.574642][INFO][test_async.rs:490] <<< test_basic_send_timeout_async (setup_log = (), _channel = (AsyncTx, AsyncRx)) enter <<<
[INFO] [stdout] test tests::test_async::test_basic_send_timeout_async::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.574731][INFO][test_async.rs:490] >>> test_basic_send_timeout_async return () in 480ns >>>
[INFO] [stderr] [2025-09-11 04:43:35.574910][INFO][test_async.rs:490] <<< test_basic_send_timeout_async (setup_log = (), _channel = (MAsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.574990][INFO][test_async.rs:490] >>> test_basic_send_timeout_async return () in 829ns >>>
[INFO] [stderr] [2025-09-11 04:43:35.575431][INFO][test_async.rs:490] <<< test_basic_send_timeout_async (setup_log = (), _channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stdout] test tests::test_async::test_basic_send_timeout_async::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.575536][INFO][test_async.rs:490] >>> test_basic_send_timeout_async return () in 660ns >>>
[INFO] [stderr] [2025-09-11 04:43:35.576539][INFO][test_async.rs:1088] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.576562][INFO][test_async.rs:1088] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.576636][INFO][test_async.rs:1099] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.576667][INFO][test_async.rs:1099] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.576356][INFO][test_async.rs:422] <<< test_basic_timeout_recv_async_waker (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.577741][INFO][test_async.rs:422] >>> test_basic_timeout_recv_async_waker return () in 1.18µs >>>
[INFO] [stdout] test tests::test_async::test_basic_timeout_recv_async_waker::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.583916][INFO][test_async.rs:422] <<< test_basic_timeout_recv_async_waker (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.584030][INFO][test_async.rs:422] >>> test_basic_timeout_recv_async_waker return () in 1.45µs >>>
[INFO] [stdout] test tests::test_async::test_basic_timeout_recv_async_waker::case_2 ... ok
[INFO] [stdout] test tests::test_async::test_basic_timeout_recv_async_waker::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.584367][INFO][test_async.rs:422] <<< test_basic_timeout_recv_async_waker (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.584456][INFO][test_async.rs:422] >>> test_basic_timeout_recv_async_waker return () in 700ns >>>
[INFO] [stderr] [2025-09-11 04:43:35.584849][INFO][test_async.rs:266] <<< test_basic_unbounded_1_thread (setup_log = (), channel = (Tx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.584934][INFO][test_async.rs:277] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.591636][INFO][test_async.rs:188] try to send after rx dropped
[INFO] [stderr] [2025-09-11 04:43:35.591707][INFO][test_async.rs:191] dropped tx
[INFO] [stderr] [2025-09-11 04:43:35.592188][INFO][test_async.rs:357] >>> test_basic_bounded_recv_after_sender_close return () in 21.968217ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.595954][INFO][test_async.rs:188] try to send after rx dropped
[INFO] [stderr] [2025-09-11 04:43:35.596960][INFO][test_async.rs:191] dropped tx
[INFO] [stdout] test tests::test_async::test_basic_bounded_recv_after_sender_close::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.597713][INFO][test_async.rs:357] >>> test_basic_bounded_recv_after_sender_close return () in 27.903796ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.599315][INFO][test_async.rs:171] >>> test_basic_bounded_rx_drop return () in 29.740976ms >>>
[INFO] [stdout] test tests::test_async::test_basic_bounded_recv_after_sender_close::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.601139][INFO][test_async.rs:266] <<< test_basic_unbounded_1_thread (setup_log = (), channel = (MTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.601291][INFO][test_async.rs:277] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.601615][INFO][test_async.rs:357] >>> test_basic_bounded_recv_after_sender_close return () in 32.380495ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.603812][INFO][test_async.rs:266] <<< test_basic_unbounded_1_thread (setup_log = (), channel = (MTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.603969][INFO][test_async.rs:277] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.605685][INFO][test_async.rs:1088] >>> test_basic_into_stream_1_1 return () in 30.987585ms >>>
[INFO] [stdout] test tests::test_async::test_basic_bounded_rx_drop::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.606158][INFO][test_async.rs:1088] >>> test_basic_into_stream_1_1 return () in 29.521186ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.606423][INFO][test_async.rs:310] <<< test_basic_unbounded_idle_select (setup_log = (), channel = (Tx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.606518][INFO][test_async.rs:188] try to send after rx dropped
[INFO] [stderr] [2025-09-11 04:43:35.606548][INFO][test_async.rs:332] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.606560][INFO][test_async.rs:191] dropped tx
[INFO] [stderr] [2025-09-11 04:43:35.606715][INFO][test_async.rs:171] >>> test_basic_bounded_rx_drop return () in 37.404195ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.610410][INFO][test_async.rs:1088] >>> test_basic_into_stream_1_1 return () in 39.093414ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.611480][INFO][test_async.rs:1088] >>> test_basic_into_stream_1_1 return () in 39.533534ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.612112][INFO][test_async.rs:1088] >>> test_basic_into_stream_1_1 return () in 42.465754ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.612370][INFO][test_async.rs:171] >>> test_basic_bounded_rx_drop return () in 43.029754ms >>>
[INFO] [stdout] test tests::test_async::test_basic_bounded_recv_after_sender_close::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.613157][INFO][test_async.rs:1088] >>> test_basic_into_stream_1_1 return () in 36.490435ms >>>
[INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_6 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.613622][INFO][test_async.rs:310] <<< test_basic_unbounded_idle_select (setup_log = (), channel = (MTx, MAsyncRx)) enter <<<
[INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_5 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.613751][INFO][test_async.rs:332] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_basic_bounded_rx_drop::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.613768][INFO][test_async.rs:389] <<< test_basic_unbounded_recv_after_sender_close (setup_log = (), channel = (MTx, AsyncRx)) enter <<<
[INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.613494][INFO][test_async.rs:310] <<< test_basic_unbounded_idle_select (setup_log = (), channel = (MTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.613944][INFO][test_async.rs:332] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.613956][INFO][test_async.rs:406] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.613893][INFO][test_async.rs:389] <<< test_basic_unbounded_recv_after_sender_close (setup_log = (), channel = (MTx, MAsyncRx)) enter <<<
[INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.614096][INFO][test_async.rs:406] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.614240][INFO][test_async.rs:456] <<< test_basic_unbounded_recv_timeout_async (setup_log = (), _channel = (MTx, AsyncRx)) enter <<<
[INFO] [stdout] test tests::test_async::test_basic_bounded_rx_drop::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.614534][INFO][test_async.rs:456] >>> test_basic_unbounded_recv_timeout_async return () in 840ns >>>
[INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_4 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.614611][INFO][test_async.rs:456] <<< test_basic_unbounded_recv_timeout_async (setup_log = (), _channel = (MTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.614702][INFO][test_async.rs:456] >>> test_basic_unbounded_recv_timeout_async return () in 651ns >>>
[INFO] [stderr] [2025-09-11 04:43:35.614930][INFO][test_async.rs:389] <<< test_basic_unbounded_recv_after_sender_close (setup_log = (), channel = (Tx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.614912][INFO][test_async.rs:456] <<< test_basic_unbounded_recv_timeout_async (setup_log = (), _channel = (Tx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.615195][INFO][test_async.rs:406] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.615276][INFO][test_async.rs:456] >>> test_basic_unbounded_recv_timeout_async return () in 1µs >>>
[INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_timeout_async::case_2 ... ok
[INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_timeout_async::case_3 ... ok
[INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_timeout_async::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.617087][INFO][test_async.rs:196] <<< test_basic_unbounded_rx_drop (setup_log = (), channel = (Tx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.617183][INFO][test_async.rs:204] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.617630][INFO][test_async.rs:196] <<< test_basic_unbounded_rx_drop (setup_log = (), channel = (MTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.617752][INFO][test_async.rs:196] <<< test_basic_unbounded_rx_drop (setup_log = (), channel = (MTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.617889][INFO][test_async.rs:204] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.617939][INFO][test_async.rs:204] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_conversion ... ok
[INFO] [stderr] [2025-09-11 04:43:35.620901][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.621008][INFO][test_async.rs:693] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.624982][INFO][test_async.rs:389] >>> test_basic_unbounded_recv_after_sender_close return () in 10.999148ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.625315][INFO][test_async.rs:213] try to send after rx dropped
[INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_after_sender_close::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.625484][INFO][test_async.rs:216] dropped tx
[INFO] [stderr] [2025-09-11 04:43:35.625852][INFO][test_async.rs:389] >>> test_basic_unbounded_recv_after_sender_close return () in 11.995558ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.626287][INFO][test_async.rs:213] try to send after rx dropped
[INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_after_sender_close::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.626343][INFO][test_async.rs:216] dropped tx
[INFO] [stderr] [2025-09-11 04:43:35.626797][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.627475][INFO][test_async.rs:693] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.628147][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.628660][INFO][test_async.rs:196] >>> test_basic_unbounded_rx_drop return () in 10.771418ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.628892][INFO][test_async.rs:693] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_basic_unbounded_rx_drop::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.630946][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.631057][INFO][test_async.rs:693] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.632176][INFO][test_async.rs:389] >>> test_basic_unbounded_recv_after_sender_close return () in 17.094398ms >>>
[INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_after_sender_close::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.632569][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.632655][INFO][test_async.rs:693] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.634380][INFO][test_async.rs:196] >>> test_basic_unbounded_rx_drop return () in 17.196448ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.636750][INFO][test_async.rs:213] try to send after rx dropped
[INFO] [stderr] [2025-09-11 04:43:35.637270][INFO][test_async.rs:216] dropped tx
[INFO] [stdout] test tests::test_async::test_basic_unbounded_rx_drop::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.640222][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.640472][INFO][test_async.rs:693] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.643045][INFO][test_async.rs:196] >>> test_basic_unbounded_rx_drop return () in 25.106246ms >>>
[INFO] [stdout] test tests::test_async::test_basic_unbounded_rx_drop::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.647030][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.647163][INFO][test_async.rs:693] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.653697][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 6.532289ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_7 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.654151][INFO][test_async.rs:782] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 5, rx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.654363][INFO][test_async.rs:808] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.657033][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 29.559475ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.657570][INFO][test_async.rs:782] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100, rx_count = 10) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.657679][INFO][test_async.rs:808] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.685245][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 44.770633ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.689392][INFO][test_async.rs:782] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 10, rx_count = 100) enter <<<
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_6 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.689537][INFO][test_async.rs:808] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_01 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.825803][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 171.437794ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.826310][INFO][test_async.rs:782] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 300, rx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.826425][INFO][test_async.rs:808] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.839618][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 210.724589ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.840034][INFO][test_async.rs:782] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 5, rx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.840137][INFO][test_async.rs:808] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.859575][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 226.916196ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_5 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.859874][INFO][test_async.rs:782] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100, rx_count = 10) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.859989][INFO][test_async.rs:808] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_05 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.926695][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 86.555847ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_03 ... ok
[INFO] [stderr] [2025-09-11 04:43:35.927104][INFO][test_async.rs:782] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 10, rx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.927407][INFO][test_async.rs:808] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:35.940316][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 250.777793ms >>>
[INFO] [stderr] [2025-09-11 04:43:35.942657][INFO][test_async.rs:782] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 300, rx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:43:35.964261][INFO][test_async.rs:808] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:36.186070][INFO][test_async.rs:353] waker rx 1
[INFO] [stderr] [2025-09-11 04:43:36.194904][INFO][test_async.rs:353] waker rx 0
[INFO] [stderr] [2025-09-11 04:43:36.208800][INFO][test_async.rs:310] >>> test_basic_unbounded_idle_select return () in 595.047231ms >>>
[INFO] [stderr] [2025-09-11 04:43:36.209963][INFO][test_async.rs:310] >>> test_basic_unbounded_idle_select return () in 603.41413ms >>>
[INFO] [stdout] test tests::test_async::test_basic_unbounded_idle_select::case_3 ... ok
[INFO] [stdout] test tests::test_async::test_basic_unbounded_idle_select::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:36.210607][INFO][test_async.rs:782] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 5, rx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:43:36.210728][INFO][test_async.rs:808] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:36.210683][INFO][test_async.rs:782] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100, rx_count = 10) enter <<<
[INFO] [stderr] [2025-09-11 04:43:36.210851][INFO][test_async.rs:808] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:36.219533][INFO][test_async.rs:353] waker rx 0
[INFO] [stderr] [2025-09-11 04:43:36.220562][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 293.155106ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_07 ... ok
[INFO] [stderr] [2025-09-11 04:43:36.223620][INFO][test_async.rs:782] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 10, rx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:43:36.223764][INFO][test_async.rs:808] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:36.253340][INFO][test_async.rs:310] >>> test_basic_unbounded_idle_select return () in 639.400684ms >>>
[INFO] [stdout] test tests::test_async::test_basic_unbounded_idle_select::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:36.274786][INFO][test_async.rs:782] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 300, rx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:43:36.274931][INFO][test_async.rs:808] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:36.407601][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 183.833342ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_11 ... ok
[INFO] [stderr] [2025-09-11 04:43:36.409911][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:43:36.410128][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:36.420685][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 799.6768ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:36.422609][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:43:36.422739][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:36.448407][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 237.679644ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_09 ... ok
[INFO] [stderr] [2025-09-11 04:43:36.452072][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:43:36.452231][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_basic_bounded_1_thread::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:36.595005][INFO][test_async.rs:221] >>> test_basic_bounded_1_thread return () in 1.025568447s >>>
[INFO] [stderr] [2025-09-11 04:43:36.603736][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:43:36.603870][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:36.605883][INFO][test_async.rs:221] >>> test_basic_bounded_1_thread return () in 1.036498005s >>>
[INFO] [stderr] [2025-09-11 04:43:36.608147][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:43:36.608237][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_basic_bounded_1_thread::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:36.623043][INFO][test_async.rs:266] >>> test_basic_unbounded_1_thread return () in 1.038106465s >>>
[INFO] [stdout] test tests::test_async::test_basic_unbounded_1_thread::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:36.631395][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:43:36.631579][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_basic_unbounded_1_thread::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:36.768772][INFO][test_async.rs:266] >>> test_basic_unbounded_1_thread return () in 1.167478906s >>>
[INFO] [stderr] [2025-09-11 04:43:36.769363][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 10) enter <<<
[INFO] [stderr] [2025-09-11 04:43:36.769567][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:36.770436][INFO][test_async.rs:221] >>> test_basic_bounded_1_thread return () in 1.200638581s >>>
[INFO] [stdout] test tests::test_async::test_basic_bounded_1_thread::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:36.775707][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:43:36.776372][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:37.285540][INFO][test_async.rs:266] >>> test_basic_unbounded_1_thread return () in 1.681568719s >>>
[INFO] [stdout] test tests::test_async::test_basic_unbounded_1_thread::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:37.286122][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:43:37.286271][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:37.496529][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 1.865468721s >>>
[INFO] [stderr] [2025-09-11 04:43:37.504656][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 900.784905ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_4 ... ok
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_04 ... ok
[INFO] [stderr] [2025-09-11 04:43:37.516837][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:43:37.516980][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:37.518056][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:43:37.518157][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:37.602095][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 832.526656ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_07 ... ok
[INFO] [stderr] [2025-09-11 04:43:37.644227][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 1.986546083s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_02 ... ok
[INFO] [stderr] [2025-09-11 04:43:37.644657][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:43:37.644803][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_10 ... ok
[INFO] [stderr] [2025-09-11 04:43:37.695685][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 300) enter <<<
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_06 ... ok
[INFO] [stderr] [2025-09-11 04:43:37.695836][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:37.769075][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 1.558221858s >>>
[INFO] [stderr] [2025-09-11 04:43:37.773398][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:43:37.773521][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:37.779389][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 1.919402023s >>>
[INFO] [stderr] [2025-09-11 04:43:37.784118][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:43:37.784212][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:38.617921][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 973.115335ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_13 ... ok
[INFO] [stderr] [2025-09-11 04:43:38.618273][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:43:38.618395][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_16 ... ok
[INFO] [stderr] [2025-09-11 04:43:38.871729][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 253.331113ms >>>
[INFO] [stderr] [2025-09-11 04:43:38.874864][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:43:38.874990][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_12 ... ok
[INFO] [stderr] [2025-09-11 04:43:39.193689][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 2.918756094s >>>
[INFO] [stderr] [2025-09-11 04:43:39.199634][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:43:39.199771][INFO][test_async.rs:749] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:39.855786][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 3.891528059s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_08 ... ok
[INFO] [stderr] [2025-09-11 04:43:39.856570][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 3.446443475s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_01 ... ok
[INFO] [stderr] [2025-09-11 04:43:39.857235][INFO][test_async.rs:853] <<< test_pressure_bounded_mixed_async_blocking_conversion (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:39.859108][INFO][test_async.rs:862] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:39.929643][INFO][test_async.rs:853] <<< test_pressure_bounded_mixed_async_blocking_conversion (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:39.930151][INFO][test_async.rs:862] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:39.962117][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 4.135690182s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_04 ... ok
[INFO] [stderr] [2025-09-11 04:43:39.963444][INFO][test_async.rs:853] <<< test_pressure_bounded_mixed_async_blocking_conversion (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:39.964376][INFO][test_async.rs:862] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:39.977266][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 2.459109073s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_10 ... ok
[INFO] [stderr] [2025-09-11 04:43:39.979971][INFO][test_async.rs:550] <<< test_pressure_bounded_timeout_async (setup_log = (), _channel = (MAsyncTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:39.980081][INFO][test_async.rs:550] >>> test_pressure_bounded_timeout_async return () in 790ns >>>
[INFO] [stderr] [2025-09-11 04:43:39.982704][INFO][test_async.rs:853] >>> test_pressure_bounded_mixed_async_blocking_conversion return () in 123.598012ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_timeout_async::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:39.983845][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 2) enter <<<
[INFO] [stderr] [2025-09-11 04:43:39.986432][INFO][test_async.rs:1140] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_bounded_mixed_async_blocking_conversion::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:39.989020][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 4) enter <<<
[INFO] [stderr] [2025-09-11 04:43:39.989120][INFO][test_async.rs:1140] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:39.996585][INFO][test_async.rs:853] >>> test_pressure_bounded_mixed_async_blocking_conversion return () in 32.210126ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_mixed_async_blocking_conversion::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:39.997541][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 10) enter <<<
[INFO] [stderr] [2025-09-11 04:43:39.998090][INFO][test_async.rs:1140] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:40.208308][INFO][test_async.rs:853] >>> test_pressure_bounded_mixed_async_blocking_conversion return () in 278.156848ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_mixed_async_blocking_conversion::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:40.209620][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 3) enter <<<
[INFO] [stderr] [2025-09-11 04:43:40.209746][INFO][test_async.rs:1140] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_4 ... ok
[INFO] [stderr] [2025-09-11 04:43:40.328036][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 118.287532ms >>>
[INFO] [stderr] [2025-09-11 04:43:40.328944][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 30) enter <<<
[INFO] [stderr] [2025-09-11 04:43:40.329102][INFO][test_async.rs:1140] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:40.517814][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 188.709092ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_5 ... ok
[INFO] [stderr] [2025-09-11 04:43:40.519094][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 2) enter <<<
[INFO] [stderr] [2025-09-11 04:43:40.519240][INFO][test_async.rs:1140] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:40.535548][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 16.308668ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_6 ... ok
[INFO] [stderr] [2025-09-11 04:43:40.536023][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 4) enter <<<
[INFO] [stderr] [2025-09-11 04:43:40.536175][INFO][test_async.rs:1140] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:40.545120][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 8.944939ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_7 ... ok
[INFO] [stderr] [2025-09-11 04:43:40.545420][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 50) enter <<<
[INFO] [stderr] [2025-09-11 04:43:40.545555][INFO][test_async.rs:1140] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:40.582868][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 37.312374ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_8 ... ok
[INFO] [stderr] [2025-09-11 04:43:40.583923][INFO][test_async.rs:1168] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 2) enter <<<
[INFO] [stderr] [2025-09-11 04:43:40.584070][INFO][test_async.rs:1188] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:40.632643][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 643.519953ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:40.634597][INFO][test_async.rs:1168] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 4) enter <<<
[INFO] [stderr] [2025-09-11 04:43:40.635175][INFO][test_async.rs:1188] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:40.635230][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 637.141424ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:40.636681][INFO][test_async.rs:1168] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 10) enter <<<
[INFO] [stderr] [2025-09-11 04:43:40.636786][INFO][test_async.rs:1188] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:40.649620][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 663.185891ms >>>
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:40.651671][INFO][test_async.rs:1168] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 3) enter <<<
[INFO] [stderr] [2025-09-11 04:43:40.651799][INFO][test_async.rs:1188] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:41.947312][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 4.173788336s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_14 ... ok
[INFO] [stderr] [2025-09-11 04:43:41.947721][INFO][test_async.rs:1168] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 30) enter <<<
[INFO] [stderr] [2025-09-11 04:43:41.947801][INFO][test_async.rs:1188] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:42.026304][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 3.151312139s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_17 ... ok
[INFO] [stderr] [2025-09-11 04:43:42.026888][INFO][test_async.rs:1168] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 2) enter <<<
[INFO] [stderr] [2025-09-11 04:43:42.027101][INFO][test_async.rs:1188] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:42.497852][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 5.88961078s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_05 ... ok
[INFO] [stderr] [2025-09-11 04:43:42.498168][INFO][test_async.rs:1168] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 4) enter <<<
[INFO] [stderr] [2025-09-11 04:43:42.499095][INFO][test_async.rs:1188] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:42.978021][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 6.201647253s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_08 ... ok
[INFO] [stderr] [2025-09-11 04:43:42.984784][INFO][test_async.rs:1168] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 50) enter <<<
[INFO] [stderr] [2025-09-11 04:43:42.985462][INFO][test_async.rs:1188] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:43.411451][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 1.463646701s >>>
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_5 ... ok
[INFO] [stderr] [2025-09-11 04:43:43.412322][INFO][test_async.rs:1006] <<< test_spurious_sink (setup_log = ()) enter <<<
[INFO] [stdout] test tests::test_async::test_spurious_sink ... ok
[INFO] [stderr] [2025-09-11 04:43:43.412419][INFO][test_async.rs:1006] >>> test_spurious_sink return () in 50ns >>>
[INFO] [stdout] test tests::test_async::test_spurious_stream ... ok
[INFO] [stderr] [2025-09-11 04:43:43.412750][INFO][test_async.rs:1047] <<< test_spurious_stream (setup_log = ()) enter <<<
[INFO] [stderr] [2025-09-11 04:43:43.412837][INFO][test_async.rs:1047] >>> test_spurious_stream return () in 50ns >>>
[INFO] [stderr] [2025-09-11 04:43:43.413215][INFO][test_async.rs:87] <<< test_sync () enter <<<
[INFO] [stderr] [2025-09-11 04:43:43.413301][INFO][test_async.rs:91] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_8 ... ok
[INFO] [stderr] [2025-09-11 04:43:43.910931][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 925.467092ms >>>
[INFO] [stderr] [2025-09-11 04:43:43.911317][INFO][test_async_blocking.rs:78] <<< test_basic_1_tx_async_1_rx_blocking (setup_log = (), channel = (AsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:43.912561][INFO][test_async_blocking.rs:107] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:44.575641][INFO][test_async_blocking.rs:78] >>> test_basic_1_tx_async_1_rx_blocking return () in 663.11391ms >>>
[INFO] [stdout] test tests::test_async_blocking::test_basic_1_tx_async_1_rx_blocking::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:44.576232][INFO][test_async_blocking.rs:78] <<< test_basic_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:44.576445][INFO][test_async_blocking.rs:107] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:45.007853][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 4.371064127s >>>
[INFO] [stderr] [2025-09-11 04:43:45.083657][INFO][test_async_blocking.rs:78] <<< test_basic_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:45.083864][INFO][test_async_blocking.rs:107] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_basic_1_tx_async_1_rx_blocking::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:45.309630][INFO][test_async_blocking.rs:78] >>> test_basic_1_tx_async_1_rx_blocking return () in 733.22804ms >>>
[INFO] [stderr] [2025-09-11 04:43:45.310012][INFO][test_async_blocking.rs:14] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (AsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:45.310170][INFO][test_async_blocking.rs:14] >>> test_basic_bounded_empty_full_drop_rx return () in 9.951µs >>>
[INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_rx::case_1 ... ok
[INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_rx::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:45.310446][INFO][test_async_blocking.rs:14] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MAsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:45.310578][INFO][test_async_blocking.rs:14] >>> test_basic_bounded_empty_full_drop_rx return () in 10.249µs >>>
[INFO] [stderr] [2025-09-11 04:43:45.310776][INFO][test_async_blocking.rs:14] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MAsyncTx, MRx)) enter <<<
[INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_rx::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:45.310876][INFO][test_async_blocking.rs:14] >>> test_basic_bounded_empty_full_drop_rx return () in 9.209µs >>>
[INFO] [stderr] [2025-09-11 04:43:45.311108][INFO][test_async_blocking.rs:37] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (AsyncTx, Rx)) enter <<<
[INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_tx::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:45.311223][INFO][test_async_blocking.rs:37] >>> test_basic_bounded_empty_full_drop_tx return () in 17.3µs >>>
[INFO] [stderr] [2025-09-11 04:43:45.311419][INFO][test_async_blocking.rs:37] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MAsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:45.311534][INFO][test_async_blocking.rs:37] >>> test_basic_bounded_empty_full_drop_tx return () in 9.36µs >>>
[INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_tx::case_2 ... ok
[INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_tx::case_3 ... ok
[INFO] [stdout] test tests::test_async_blocking::test_basic_compile_bounded_empty_full ... ok
[INFO] [stderr] [2025-09-11 04:43:45.311751][INFO][test_async_blocking.rs:37] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MAsyncTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:45.311846][INFO][test_async_blocking.rs:37] >>> test_basic_bounded_empty_full_drop_tx return () in 7.33µs >>>
[INFO] [stderr] [2025-09-11 04:43:45.312154][INFO][test_async_blocking.rs:60] <<< test_basic_compile_bounded_empty_full () enter <<<
[INFO] [stderr] [2025-09-11 04:43:45.312267][INFO][test_async_blocking.rs:60] >>> test_basic_compile_bounded_empty_full return () in 21.97µs >>>
[INFO] [stderr] [2025-09-11 04:43:45.312450][INFO][test_async_blocking.rs:169] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:43:45.312599][INFO][test_async_blocking.rs:218] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_basic_1_tx_async_1_rx_blocking::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:45.739087][INFO][test_async_blocking.rs:78] >>> test_basic_1_tx_async_1_rx_blocking return () in 655.275702ms >>>
[INFO] [stderr] [2025-09-11 04:43:45.739659][INFO][test_async_blocking.rs:169] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 8) enter <<<
[INFO] [stderr] [2025-09-11 04:43:45.739870][INFO][test_async_blocking.rs:218] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:45.986616][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 674.05219ms >>>
[INFO] [stderr] [2025-09-11 04:43:45.986920][INFO][test_async_blocking.rs:169] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:43:45.987077][INFO][test_async_blocking.rs:218] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:46.458240][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 718.425993ms >>>
[INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:46.458660][INFO][test_async_blocking.rs:169] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 1000) enter <<<
[INFO] [stderr] [2025-09-11 04:43:46.459356][INFO][test_async_blocking.rs:218] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:46.662812][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 9.376539608s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_09 ... ok
[INFO] [stderr] [2025-09-11 04:43:46.663190][INFO][test_async_blocking.rs:169] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:43:46.663340][INFO][test_async_blocking.rs:218] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:46.966019][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 978.981704ms >>>
[INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:46.966369][INFO][test_async_blocking.rs:169] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 8) enter <<<
[INFO] [stderr] [2025-09-11 04:43:46.967283][INFO][test_async_blocking.rs:218] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:47.357737][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 694.430686ms >>>
[INFO] [stderr] [2025-09-11 04:43:47.358078][INFO][test_async_blocking.rs:169] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:43:47.358784][INFO][test_async_blocking.rs:218] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_5 ... ok
[INFO] [stderr] [2025-09-11 04:43:47.874890][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 907.876764ms >>>
[INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_6 ... ok
[INFO] [stderr] [2025-09-11 04:43:47.876218][INFO][test_async_blocking.rs:169] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 1000) enter <<<
[INFO] [stderr] [2025-09-11 04:43:47.876409][INFO][test_async_blocking.rs:218] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_7 ... ok
[INFO] [stderr] [2025-09-11 04:43:48.534414][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 1.176208355s >>>
[INFO] [stderr] [2025-09-11 04:43:48.539724][INFO][test_async_blocking.rs:242] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (AsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:48.541252][INFO][test_async_blocking.rs:285] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_sync ... ok
[INFO] [stderr] [2025-09-11 04:43:49.473685][INFO][test_async.rs:87] >>> test_sync return () in 6.060381064s >>>
[INFO] [stderr] [2025-09-11 04:43:49.474048][INFO][test_async_blocking.rs:242] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (AsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:49.474201][INFO][test_async_blocking.rs:285] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:50.147000][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 10.947226384s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_18 ... ok
[INFO] [stderr] [2025-09-11 04:43:50.147488][INFO][test_async_blocking.rs:242] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (AsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:50.148391][INFO][test_async_blocking.rs:285] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_4 ... ok
[INFO] [stderr] [2025-09-11 04:43:50.516724][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 4.057941133s >>>
[INFO] [stderr] [2025-09-11 04:43:50.517156][INFO][test_async_blocking.rs:242] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (AsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:50.517353][INFO][test_async_blocking.rs:285] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:51.103804][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 586.538852ms >>>
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_04 ... ok
[INFO] [stderr] [2025-09-11 04:43:51.104136][INFO][test_async_blocking.rs:242] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:51.104296][INFO][test_async_blocking.rs:285] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:51.626611][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 10.991435077s >>>
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:51.671701][INFO][test_async_blocking.rs:242] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:51.672837][INFO][test_async_blocking.rs:285] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_03 ... ok
[INFO] [stderr] [2025-09-11 04:43:52.103740][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 1.956128057s >>>
[INFO] [stderr] [2025-09-11 04:43:52.104558][INFO][test_async_blocking.rs:242] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:52.105654][INFO][test_async_blocking.rs:285] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:53.135408][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 5.259045544s >>>
[INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_8 ... ok
[INFO] [stderr] [2025-09-11 04:43:53.136725][INFO][test_async_blocking.rs:242] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:53.136879][INFO][test_async_blocking.rs:285] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_02 ... ok
[INFO] [stderr] [2025-09-11 04:43:53.271401][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 3.797231002s >>>
[INFO] [stderr] [2025-09-11 04:43:53.274608][INFO][test_async_blocking.rs:242] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:53.274770][INFO][test_async_blocking.rs:285] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:53.444002][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 10.944904624s >>>
[INFO] [stderr] [2025-09-11 04:43:53.445678][INFO][test_async_blocking.rs:242] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:53.445835][INFO][test_async_blocking.rs:285] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:53.699173][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 562.326636ms >>>
[INFO] [stderr] [2025-09-11 04:43:53.715944][INFO][test_async_blocking.rs:242] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:53.716149][INFO][test_async_blocking.rs:285] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_7 ... ok
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_08 ... ok
[INFO] [stderr] [2025-09-11 04:43:54.183947][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 2.079271539s >>>
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_07 ... ok
[INFO] [stderr] [2025-09-11 04:43:54.184335][INFO][test_async_blocking.rs:242] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:43:54.185083][INFO][test_async_blocking.rs:285] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_12 ... ok
[INFO] [stderr] [2025-09-11 04:43:54.961623][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 777.177824ms >>>
[INFO] [stderr] [2025-09-11 04:43:54.961975][INFO][test_async_blocking.rs:298] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:43:54.962762][INFO][test_async_blocking.rs:335] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:55.124136][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 17.339921179s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_15 ... ok
[INFO] [stderr] [2025-09-11 04:43:55.125614][INFO][test_async_blocking.rs:298] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 10) enter <<<
[INFO] [stderr] [2025-09-11 04:43:55.125817][INFO][test_async_blocking.rs:335] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:55.267406][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 304.683394ms >>>
[INFO] [stderr] [2025-09-11 04:43:55.267745][INFO][test_async_blocking.rs:298] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:43:55.267947][INFO][test_async_blocking.rs:335] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:55.389159][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 14.737357217s >>>
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_4 ... ok
[INFO] [stderr] [2025-09-11 04:43:55.389630][INFO][test_async_blocking.rs:298] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 200) enter <<<
[INFO] [stderr] [2025-09-11 04:43:55.390534][INFO][test_async_blocking.rs:335] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:43:55.536020][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 410.252819ms >>>
[INFO] [stderr] [2025-09-11 04:43:55.536382][INFO][test_async_blocking.rs:298] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:43:55.536648][INFO][test_async_blocking.rs:335] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:55.759743][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 2.043648575s >>>
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_11 ... ok
[INFO] [stderr] [2025-09-11 04:43:55.761705][INFO][test_async_blocking.rs:298] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:43:55.761871][INFO][test_async_blocking.rs:335] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_5 ... ok
[INFO] [stderr] [2025-09-11 04:43:55.853116][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 316.543983ms >>>
[INFO] [stderr] [2025-09-11 04:43:55.854740][INFO][test_async_blocking.rs:298] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 10) enter <<<
[INFO] [stderr] [2025-09-11 04:43:55.854924][INFO][test_async_blocking.rs:335] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:56.474243][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 619.358777ms >>>
[INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_7 ... ok
[INFO] [stderr] [2025-09-11 04:43:56.474565][INFO][test_async_blocking.rs:298] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 200) enter <<<
[INFO] [stderr] [2025-09-11 04:43:56.476454][INFO][test_async_blocking.rs:335] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:56.971876][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 20.340294491s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_06 ... ok
[INFO] [stderr] [2025-09-11 04:43:57.000771][INFO][test_async_blocking.rs:358] <<< test_pressure_multi_tx_async_multi_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 5, rx_count = 5) enter <<<
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_06 ... ok
[INFO] [stderr] [2025-09-11 04:43:57.001049][INFO][test_async_blocking.rs:397] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_multi_rx_blocking::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:43:57.133969][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 5.461356853s >>>
[INFO] [stderr] [2025-09-11 04:43:57.134928][INFO][test_async_blocking.rs:358] <<< test_pressure_multi_tx_async_multi_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 100, rx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:43:57.147565][INFO][test_async_blocking.rs:358] >>> test_pressure_multi_tx_async_multi_rx_blocking return () in 146.679578ms >>>
[INFO] [stderr] [2025-09-11 04:43:57.147916][INFO][test_async_blocking.rs:358] <<< test_pressure_multi_tx_async_multi_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 10, rx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:43:57.157544][INFO][test_async_blocking.rs:397] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:57.229174][INFO][test_async_blocking.rs:397] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:43:58.427263][INFO][test_async_blocking.rs:358] >>> test_pressure_multi_tx_async_multi_rx_blocking return () in 1.279241889s >>>
[INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_multi_rx_blocking::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:43:58.428684][INFO][test_async_blocking.rs:358] <<< test_pressure_multi_tx_async_multi_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 300, rx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:43:58.566269][INFO][test_async_blocking.rs:397] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:00.731291][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 5.463420544s >>>
[INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:44:00.731817][INFO][test_async_blocking.rs:120] <<< test_timeout_1_tx_async_1_rx_blocking (setup_log = (), channel = (AsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:00.732017][INFO][test_async_blocking.rs:153] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_10 ... ok
[INFO] [stderr] [2025-09-11 04:44:01.178613][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 7.732814374s >>>
[INFO] [stderr] [2025-09-11 04:44:01.179610][INFO][test_async_blocking.rs:120] <<< test_timeout_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:01.180182][INFO][test_async_blocking.rs:153] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:01.567635][INFO][test_async_blocking.rs:120] >>> test_timeout_1_tx_async_1_rx_blocking return () in 835.670755ms >>>
[INFO] [stdout] test tests::test_async_blocking::test_timeout_1_tx_async_1_rx_blocking::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:44:01.567977][INFO][test_async_blocking.rs:120] <<< test_timeout_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:01.568121][INFO][test_async_blocking.rs:153] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_6 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.027830][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 6.265995254s >>>
[INFO] [stdout] test tests::test_async_blocking::test_timeout_1_tx_async_1_rx_blocking::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.028171][INFO][test_blocking_async.rs:99] <<< test_basic_1_tx_blocking_1_rx_async (setup_log = (), channel = (Tx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:02.028324][INFO][test_blocking_async.rs:124] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:02.052698][INFO][test_async_blocking.rs:120] >>> test_timeout_1_tx_async_1_rx_blocking return () in 872.551499ms >>>
[INFO] [stderr] [2025-09-11 04:44:02.053026][INFO][test_blocking_async.rs:99] <<< test_basic_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:02.053191][INFO][test_blocking_async.rs:124] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:02.416970][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 21.832896787s >>>
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.417467][INFO][test_blocking_async.rs:99] <<< test_basic_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:02.418117][INFO][test_blocking_async.rs:124] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:02.626395][INFO][test_async_blocking.rs:120] >>> test_timeout_1_tx_async_1_rx_blocking return () in 1.058308151s >>>
[INFO] [stdout] test tests::test_async_blocking::test_timeout_1_tx_async_1_rx_blocking::case_3 ... ok
[INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_rx::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.688628][INFO][test_blocking_async.rs:14] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (Tx, AsyncRx)) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_rx::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.688800][INFO][test_blocking_async.rs:14] >>> test_basic_bounded_empty_full_drop_rx return () in 11.91µs >>>
[INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_rx::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.689224][INFO][test_blocking_async.rs:14] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MTx, AsyncRx)) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_tx::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.689323][INFO][test_blocking_async.rs:14] >>> test_basic_bounded_empty_full_drop_rx return () in 8.9µs >>>
[INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_tx::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.689623][INFO][test_blocking_async.rs:14] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MTx, MAsyncRx)) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_tx::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.689727][INFO][test_blocking_async.rs:14] >>> test_basic_bounded_empty_full_drop_rx return () in 9.51µs >>>
[INFO] [stdout] test tests::test_blocking_async::test_basic_compile_bounded_empty_full ... ok
[INFO] [stderr] [2025-09-11 04:44:02.689990][INFO][test_blocking_async.rs:37] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (Tx, AsyncRx)) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_basic_unbounded_empty_drop_tx::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.690070][INFO][test_blocking_async.rs:37] >>> test_basic_bounded_empty_full_drop_tx return () in 7.94µs >>>
[INFO] [stdout] test tests::test_blocking_async::test_basic_unbounded_empty_drop_tx::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.690330][INFO][test_blocking_async.rs:37] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MTx, AsyncRx)) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_basic_unbounded_empty_drop_tx::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.690410][INFO][test_blocking_async.rs:37] >>> test_basic_bounded_empty_full_drop_tx return () in 7.65µs >>>
[INFO] [stdout] test tests::test_blocking_async::test_basic_1_tx_blocking_1_rx_async::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.690654][INFO][test_blocking_async.rs:37] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MTx, MAsyncRx)) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_basic_1_tx_blocking_1_rx_async::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:44:02.690746][INFO][test_blocking_async.rs:37] >>> test_basic_bounded_empty_full_drop_tx return () in 8.03µs >>>
[INFO] [stderr] [2025-09-11 04:44:02.690963][INFO][test_blocking_async.rs:81] <<< test_basic_compile_bounded_empty_full () enter <<<
[INFO] [stderr] [2025-09-11 04:44:02.691050][INFO][test_blocking_async.rs:81] >>> test_basic_compile_bounded_empty_full return () in 15.37µs >>>
[INFO] [stderr] [2025-09-11 04:44:02.691278][INFO][test_blocking_async.rs:60] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), channel = (Tx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:02.691357][INFO][test_blocking_async.rs:60] >>> test_basic_unbounded_empty_drop_tx return () in 11.92µs >>>
[INFO] [stderr] [2025-09-11 04:44:02.691578][INFO][test_blocking_async.rs:60] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), channel = (MTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:02.691682][INFO][test_blocking_async.rs:60] >>> test_basic_unbounded_empty_drop_tx return () in 8.41µs >>>
[INFO] [stderr] [2025-09-11 04:44:02.691999][INFO][test_blocking_async.rs:60] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), channel = (MTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:02.692075][INFO][test_blocking_async.rs:60] >>> test_basic_unbounded_empty_drop_tx return () in 8.349µs >>>
[INFO] [stderr] [2025-09-11 04:44:02.692261][INFO][test_blocking_async.rs:189] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (Tx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:02.692713][INFO][test_blocking_async.rs:218] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:03.031511][INFO][test_blocking_async.rs:99] >>> test_basic_1_tx_blocking_1_rx_async return () in 1.00321024s >>>
[INFO] [stderr] [2025-09-11 04:44:03.031912][INFO][test_blocking_async.rs:189] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:03.032129][INFO][test_blocking_async.rs:218] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:03.061663][INFO][test_blocking_async.rs:99] >>> test_basic_1_tx_blocking_1_rx_async return () in 1.008523269s >>>
[INFO] [stderr] [2025-09-11 04:44:03.062004][INFO][test_blocking_async.rs:189] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:03.062206][INFO][test_blocking_async.rs:218] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_multi_rx_blocking::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:44:03.474781][INFO][test_async_blocking.rs:358] >>> test_pressure_multi_tx_async_multi_rx_blocking return () in 6.339623563s >>>
[INFO] [stderr] [2025-09-11 04:44:03.475235][INFO][test_blocking_async.rs:189] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (Tx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:03.476492][INFO][test_blocking_async.rs:218] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:03.573926][INFO][test_blocking_async.rs:99] >>> test_basic_1_tx_blocking_1_rx_async return () in 1.156331317s >>>
[INFO] [stdout] test tests::test_blocking_async::test_basic_1_tx_blocking_1_rx_async::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:44:03.574383][INFO][test_blocking_async.rs:189] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:03.575638][INFO][test_blocking_async.rs:218] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_6 ... ok
[INFO] [stderr] [2025-09-11 04:44:05.411645][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 23.384543005s >>>
[INFO] [stderr] [2025-09-11 04:44:05.479749][INFO][test_blocking_async.rs:189] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:05.479960][INFO][test_blocking_async.rs:218] run with tokio multi thread
[INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_4 ... ok
[INFO] [stderr] [2025-09-11 04:44:06.339243][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 2.863892272s >>>
[INFO] [stderr] [2025-09-11 04:44:06.347456][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 2.772968605s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_5 ... ok
[INFO] [stderr] [2025-09-11 04:44:06.348770][INFO][test_blocking_async.rs:189] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:06.349368][INFO][test_blocking_async.rs:189] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (Tx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:06.349540][INFO][test_blocking_async.rs:218] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:06.351539][INFO][test_blocking_async.rs:218] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:07.556422][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 2.07651775s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_6 ... ok
[INFO] [stderr] [2025-09-11 04:44:07.581662][INFO][test_blocking_async.rs:189] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:07.581870][INFO][test_blocking_async.rs:218] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:07.886054][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 1.53650746s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_7 ... ok
[INFO] [stderr] [2025-09-11 04:44:07.888671][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 5) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_8 ... ok
[INFO] [stderr] [2025-09-11 04:44:07.891674][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:07.922224][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 1.573329955s >>>
[INFO] [stderr] [2025-09-11 04:44:07.922933][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:44:07.946351][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_11 ... ok
[INFO] [stderr] [2025-09-11 04:44:08.082958][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 30.565974952s >>>
[INFO] [stderr] [2025-09-11 04:44:08.083323][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 200) enter <<<
[INFO] [stderr] [2025-09-11 04:44:08.152446][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:08.365906][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 477.108469ms >>>
[INFO] [stderr] [2025-09-11 04:44:08.387948][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 10) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_01 ... ok
[INFO] [stderr] [2025-09-11 04:44:08.388384][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:08.807556][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 419.461598ms >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_04 ... ok
[INFO] [stderr] [2025-09-11 04:44:08.807942][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:44:08.865977][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:10.195833][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 2.614024929s >>>
[INFO] [stderr] [2025-09-11 04:44:10.196767][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 200) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_9 ... ok
[INFO] [stderr] [2025-09-11 04:44:10.256545][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_8 ... ok
[INFO] [stderr] [2025-09-11 04:44:12.195644][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 15.72096782s >>>
[INFO] [stderr] [2025-09-11 04:44:12.195981][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:44:12.196274][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:12.948155][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 752.071578ms >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_07 ... ok
[INFO] [stderr] [2025-09-11 04:44:12.948641][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:44:12.984209][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:13.612383][INFO][test_async_blocking.rs:358] >>> test_pressure_multi_tx_async_multi_rx_blocking return () in 15.18356141s >>>
[INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_multi_rx_blocking::case_4 ... ok
[INFO] [stderr] [2025-09-11 04:44:13.642647][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:44:13.778978][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:14.022835][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 6.099773938s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_02 ... ok
[INFO] [stderr] [2025-09-11 04:44:14.104669][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:44:14.104988][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:14.653105][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 548.292488ms >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_10 ... ok
[INFO] [stderr] [2025-09-11 04:44:14.672407][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:44:14.776073][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_4 ... ok
[INFO] [stderr] [2025-09-11 04:44:14.983426][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 19.593681222s >>>
[INFO] [stderr] [2025-09-11 04:44:15.045791][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 200) enter <<<
[INFO] [stderr] [2025-09-11 04:44:15.116744][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_05 ... ok
[INFO] [stderr] [2025-09-11 04:44:19.028897][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 10.220809722s >>>
[INFO] [stderr] [2025-09-11 04:44:19.056826][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:44:19.070195][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_13 ... ok
[INFO] [stderr] [2025-09-11 04:44:19.317132][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 260.141071ms >>>
[INFO] [stderr] [2025-09-11 04:44:19.336762][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 100) enter <<<
[INFO] [stderr] [2025-09-11 04:44:20.578921][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:22.448720][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 3.111827805s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_14 ... ok
[INFO] [stderr] [2025-09-11 04:44:22.450140][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:44:22.756270][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 12.559218583s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_06 ... ok
[INFO] [stderr] [2025-09-11 04:44:22.834122][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 6) enter <<<
[INFO] [stderr] [2025-09-11 04:44:22.862873][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:22.971149][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 8.29858675s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_11 ... ok
[INFO] [stderr] [2025-09-11 04:44:22.971899][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_16 ... ok
[INFO] [stderr] [2025-09-11 04:44:23.073879][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 239.598895ms >>>
[INFO] [stderr] [2025-09-11 04:44:23.075780][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:44:23.788978][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:24.480179][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:24.771176][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 11.822414963s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_08 ... ok
[INFO] [stderr] [2025-09-11 04:44:24.866619][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 5, rx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:44:25.120610][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:25.460133][INFO][test_blocking_async.rs:283] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:25.770057][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 49.347316034s >>>
[INFO] [stderr] [2025-09-11 04:44:25.795662][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100, rx_count = 20) enter <<<
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_02 ... ok
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_01 ... ok
[INFO] [stderr] [2025-09-11 04:44:25.830224][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_17 ... ok
[INFO] [stderr] [2025-09-11 04:44:25.872628][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 1.00586543s >>>
[INFO] [stderr] [2025-09-11 04:44:25.952569][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 2.980537615s >>>
[INFO] [stderr] [2025-09-11 04:44:25.952930][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 10, rx_count = 10) enter <<<
[INFO] [stderr] [2025-09-11 04:44:25.953315][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:25.975618][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 300, rx_count = 200) enter <<<
[INFO] [stderr] [2025-09-11 04:44:26.130588][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_04 ... ok
[INFO] [stderr] [2025-09-11 04:44:26.961755][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 1.008711299s >>>
[INFO] [stderr] [2025-09-11 04:44:27.071715][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100, rx_count = 20) enter <<<
[INFO] [stderr] [2025-09-11 04:44:27.078151][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:32.839355][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 10.388900167s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_15 ... ok
[INFO] [stderr] [2025-09-11 04:44:32.839797][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 300, rx_count = 200) enter <<<
[INFO] [stderr] [2025-09-11 04:44:32.975114][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:33.312363][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 7.516552247s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_02 ... ok
[INFO] [stderr] [2025-09-11 04:44:33.312899][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 10, rx_count = 200) enter <<<
[INFO] [stderr] [2025-09-11 04:44:33.313371][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:33.432190][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 6.360321789s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_05 ... ok
[INFO] [stderr] [2025-09-11 04:44:33.531084][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 218.036068ms >>>
[INFO] [stderr] [2025-09-11 04:44:33.531900][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100, rx_count = 200) enter <<<
[INFO] [stderr] [2025-09-11 04:44:33.538105][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 300, rx_count = 300) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_07 ... ok
[INFO] [stderr] [2025-09-11 04:44:33.689740][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_18 ... ok
[INFO] [stderr] [2025-09-11 04:44:33.735489][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 10.653454398s >>>
[INFO] [stderr] [2025-09-11 04:44:33.736011][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 30, rx_count = 500) enter <<<
[INFO] [stderr] [2025-09-11 04:44:33.746797][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:33.826940][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:34.286824][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 550.671407ms >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_10 ... ok
[INFO] [stderr] [2025-09-11 04:44:34.287242][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 5, rx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:44:34.293577][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_11 ... ok
[INFO] [stderr] [2025-09-11 04:44:34.408356][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 120.997302ms >>>
[INFO] [stderr] [2025-09-11 04:44:34.409727][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100, rx_count = 20) enter <<<
[INFO] [stderr] [2025-09-11 04:44:34.514099][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:35.356971][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 934.910831ms >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_12 ... ok
[INFO] [stderr] [2025-09-11 04:44:35.520638][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 300, rx_count = 200) enter <<<
[INFO] [stderr] [2025-09-11 04:44:36.231215][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_03 has been running for over 60 seconds
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_12 has been running for over 60 seconds
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_13 ... ok
[INFO] [stderr] [2025-09-11 04:44:39.095204][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 3.574417456s >>>
[INFO] [stderr] [2025-09-11 04:44:39.095711][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 10, rx_count = 200) enter <<<
[INFO] [stderr] [2025-09-11 04:44:39.096116][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:39.900054][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 804.20747ms >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_14 ... ok
[INFO] [stderr] [2025-09-11 04:44:39.999638][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100, rx_count = 200) enter <<<
[INFO] [stderr] [2025-09-11 04:44:40.442999][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:42.390870][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 2.391077382s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_15 ... ok
[INFO] [stderr] [2025-09-11 04:44:42.397628][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 300, rx_count = 300) enter <<<
[INFO] [stderr] [2025-09-11 04:44:43.503424][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:44.852411][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 2.454619234s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_16 ... ok
[INFO] [stderr] [2025-09-11 04:44:44.890640][INFO][test_blocking_async.rs:302] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 30, rx_count = 500) enter <<<
[INFO] [stderr] [2025-09-11 04:44:44.891742][INFO][test_blocking_async.rs:350] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:44.932093][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 11.400026636s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_08 ... ok
[INFO] [stderr] [2025-09-11 04:44:45.127060][INFO][test_blocking_async.rs:143] <<< test_timeout_1_tx_blocking_1_rx_async (setup_log = (), channel = (Tx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:45.339749][INFO][test_blocking_async.rs:168] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:45.382375][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 491.603356ms >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_17 ... ok
[INFO] [stderr] [2025-09-11 04:44:45.422622][INFO][test_blocking_async.rs:143] <<< test_timeout_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:45.623816][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 19.648046463s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_03 ... ok
[INFO] [stderr] [2025-09-11 04:44:45.633022][INFO][test_blocking_async.rs:168] run with tokio multi thread
[INFO] [stderr] [2025-09-11 04:44:45.648778][INFO][test_blocking_async.rs:143] <<< test_timeout_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:45.709640][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 30.663669357s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_12 ... ok
[INFO] [stderr] [2025-09-11 04:44:45.710205][INFO][test_blocking_context.rs:157] <<< test_basic_bounded_1_thread (setup_log = (), _channel = (Tx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:45.749194][INFO][test_blocking_async.rs:168] run with tokio multi thread
[INFO] [stdout] test tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:44:46.072977][INFO][test_blocking_async.rs:143] >>> test_timeout_1_tx_blocking_1_rx_async return () in 632.438975ms >>>
[INFO] [stderr] [2025-09-11 04:44:46.074840][INFO][test_blocking_context.rs:157] <<< test_basic_bounded_1_thread (setup_log = (), _channel = (MTx, Rx)) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_1_thread::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:44:46.710745][INFO][test_blocking_context.rs:157] >>> test_basic_bounded_1_thread return () in 1.000340531s >>>
[INFO] [stderr] [2025-09-11 04:44:46.711323][INFO][test_blocking_context.rs:157] <<< test_basic_bounded_1_thread (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_1_thread::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:44:47.206798][INFO][test_blocking_context.rs:157] >>> test_basic_bounded_1_thread return () in 1.13180797s >>>
[INFO] [stderr] [2025-09-11 04:44:47.270631][INFO][test_blocking_context.rs:16] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), _channel = (Tx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:47.270810][INFO][test_blocking_context.rs:16] >>> test_basic_bounded_empty_full_drop_rx return () in 16.65µs >>>
[INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_rx::case_1 ... ok
[INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_rx::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:44:47.420770][INFO][test_blocking_context.rs:16] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), _channel = (MTx, Rx)) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_rx::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:44:47.425549][INFO][test_blocking_context.rs:16] >>> test_basic_bounded_empty_full_drop_rx return () in 16.37µs >>>
[INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_tx::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:44:47.430605][INFO][test_blocking_context.rs:16] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_tx::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:44:47.432561][INFO][test_blocking_context.rs:16] >>> test_basic_bounded_empty_full_drop_rx return () in 15.23µs >>>
[INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_tx::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:44:47.434068][INFO][test_blocking_context.rs:53] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), _channel = (Tx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:47.434179][INFO][test_blocking_context.rs:53] >>> test_basic_bounded_empty_full_drop_tx return () in 9.611µs >>>
[INFO] [stderr] [2025-09-11 04:44:47.436591][INFO][test_blocking_context.rs:53] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), _channel = (MTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:47.436773][INFO][test_blocking_context.rs:53] >>> test_basic_bounded_empty_full_drop_tx return () in 11.58µs >>>
[INFO] [stderr] [2025-09-11 04:44:47.438826][INFO][test_blocking_context.rs:53] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:47.438930][INFO][test_blocking_context.rs:53] >>> test_basic_bounded_empty_full_drop_tx return () in 8.93µs >>>
[INFO] [stderr] [2025-09-11 04:44:47.441591][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (Tx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:47.441700][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 14.391µs >>>
[INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:44:47.496783][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (MTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:47.496946][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 15.32µs >>>
[INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:44:47.498741][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:47.498912][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 13.84µs >>>
[INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_3 ... ok
[INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_4 ... ok
[INFO] [stderr] [2025-09-11 04:44:47.505219][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (Tx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:47.505388][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 14.57µs >>>
[INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_5 ... ok
[INFO] [stderr] [2025-09-11 04:44:47.505653][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (MTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:47.505854][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 8.89µs >>>
[INFO] [stderr] [2025-09-11 04:44:47.532972][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:47.533196][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 15.57µs >>>
[INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_6 ... ok
[INFO] [stderr] [2025-09-11 04:44:47.533764][INFO][test_blocking_context.rs:203] <<< test_basic_unbounded_1_thread (setup_log = (), _channel = (Tx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:47.712089][INFO][test_blocking_context.rs:157] >>> test_basic_bounded_1_thread return () in 1.0006265s >>>
[INFO] [stderr] [2025-09-11 04:44:47.728285][INFO][test_blocking_context.rs:203] <<< test_basic_unbounded_1_thread (setup_log = (), _channel = (MTx, Rx)) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_1_thread::case_3 ... ok
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_01 has been running for over 60 seconds
[INFO] [stderr] [2025-09-11 04:44:48.534985][INFO][test_blocking_context.rs:203] >>> test_basic_unbounded_1_thread return () in 1.000885321s >>>
[INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_1_thread::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:44:48.543671][INFO][test_blocking_context.rs:203] <<< test_basic_unbounded_1_thread (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:48.730550][INFO][test_blocking_context.rs:203] >>> test_basic_unbounded_1_thread return () in 1.00213007s >>>
[INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_1_thread::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:44:48.735099][INFO][test_blocking_context.rs:90] <<< test_basic_unbounded_empty_drop_rx (setup_log = (), _channel = (Tx, Rx)) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_rx::case_1 ... ok
[INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_rx::case_2 ... ok
[INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_rx::case_3 ... ok
[INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_tx::case_1 ... ok
[INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_tx::case_2 ... ok
[INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_tx::case_3 ... ok
[INFO] [stdout] test tests::test_blocking_context::test_conversion ... ok
[INFO] [stderr] [2025-09-11 04:44:48.737900][INFO][test_blocking_context.rs:90] >>> test_basic_unbounded_empty_drop_rx return () in 17.56µs >>>
[INFO] [stderr] [2025-09-11 04:44:48.738672][INFO][test_blocking_context.rs:90] <<< test_basic_unbounded_empty_drop_rx (setup_log = (), _channel = (MTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:48.738806][INFO][test_blocking_context.rs:90] >>> test_basic_unbounded_empty_drop_rx return () in 10.15µs >>>
[INFO] [stderr] [2025-09-11 04:44:48.740651][INFO][test_blocking_context.rs:90] <<< test_basic_unbounded_empty_drop_rx (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:48.740769][INFO][test_blocking_context.rs:90] >>> test_basic_unbounded_empty_drop_rx return () in 9.08µs >>>
[INFO] [stderr] [2025-09-11 04:44:48.742658][INFO][test_blocking_context.rs:124] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), _channel = (Tx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:48.742765][INFO][test_blocking_context.rs:124] >>> test_basic_unbounded_empty_drop_tx return () in 11.55µs >>>
[INFO] [stderr] [2025-09-11 04:44:48.744651][INFO][test_blocking_context.rs:124] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), _channel = (MTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:48.744752][INFO][test_blocking_context.rs:124] >>> test_basic_unbounded_empty_drop_tx return () in 10.51µs >>>
[INFO] [stderr] [2025-09-11 04:44:48.746649][INFO][test_blocking_context.rs:124] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:48.746753][INFO][test_blocking_context.rs:124] >>> test_basic_unbounded_empty_drop_tx return () in 10.72µs >>>
[INFO] [stderr] [2025-09-11 04:44:48.748806][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (Tx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:49.544199][INFO][test_blocking_context.rs:203] >>> test_basic_unbounded_1_thread return () in 1.00029491s >>>
[INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_1_thread::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:44:49.545622][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (Tx, Rx)) enter <<<
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_05 has been running for over 60 seconds
[INFO] [stderr] [2025-09-11 04:44:51.118138][ERROR][test_blocking_async.rs:164] panic occur: panicked at src/tests/test_blocking_async.rs:164:9:
[INFO] [stderr] assertion failed: tx.send_timeout(10, Duration::from_millis(100)).is_err()
[INFO] [stderr] trace:    0: captains_log::log_impl::log_panic
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:340:14
[INFO] [stderr]    1: captains_log::log_impl::panic_and_exit_hook
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:352:5
[INFO] [stderr]       core::ops::function::Fn::call
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:80:5
[INFO] [stderr]    2: <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1999:9
[INFO] [stderr]       std::panicking::panic_with_hook
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:842:13
[INFO] [stderr]    3: std::panicking::panic_handler::{{closure}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:700:13
[INFO] [stderr]    4: std::sys::backtrace::__rust_end_short_backtrace
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:174:18
[INFO] [stderr]    5: __rustc::rust_begin_unwind
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:698:5
[INFO] [stderr]    6: core::panicking::panic_fmt
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panicking.rs:75:14
[INFO] [stderr]    7: core::panicking::panic
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panicking.rs:145:5
[INFO] [stderr]    8: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::{{closure}}::{{closure}}
[INFO] [stderr]              at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:164:9
[INFO] [stderr]    9: std::sys::backtrace::__rust_begin_short_backtrace
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:158:18
[INFO] [stderr]   10: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:559:17
[INFO] [stderr]   11: <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stderr]   12: std::panicking::catch_unwind::do_call
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40
[INFO] [stderr]   13: __rust_try
[INFO] [stderr]   14: std::panicking::catch_unwind
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19
[INFO] [stderr]       std::panic::catch_unwind
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14
[INFO] [stderr]       std::thread::Builder::spawn_unchecked_::{{closure}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:557:30
[INFO] [stderr]   15: core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5
[INFO] [stderr]   16: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1985:9
[INFO] [stderr]       std::sys::pal::unix::thread::Thread::new::thread_start
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/pal/unix/thread.rs:118:17
[INFO] [stderr]   17: <unknown>
[INFO] [stderr]   18: clone
[INFO] [stderr] 
[INFO] [stderr] [2025-09-11 04:44:51.127803][ERROR][test_blocking_async.rs:165] panic occur: panicked at src/tests/test_blocking_async.rs:165:9:
[INFO] [stderr] assertion failed: tx.send_timeout(10, Duration::from_millis(200)).is_ok()
[INFO] [stderr] trace:    0: captains_log::log_impl::log_panic
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:340:14
[INFO] [stderr]    1: captains_log::log_impl::panic_and_exit_hook
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:352:5
[INFO] [stderr]       core::ops::function::Fn::call
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:80:5
[INFO] [stderr]    2: <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1999:9
[INFO] [stderr]       std::panicking::panic_with_hook
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:842:13
[INFO] [stderr]    3: std::panicking::panic_handler::{{closure}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:700:13
[INFO] [stderr]    4: std::sys::backtrace::__rust_end_short_backtrace
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:174:18
[INFO] [stderr]    5: __rustc::rust_begin_unwind
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:698:5
[INFO] [stderr]    6: core::panicking::panic_fmt
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panicking.rs:75:14
[INFO] [stderr]    7: core::panicking::panic
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panicking.rs:145:5
[INFO] [stderr]    8: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::{{closure}}::{{closure}}
[INFO] [stderr]              at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:165:9
[INFO] [stderr]    9: std::sys::backtrace::__rust_begin_short_backtrace
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:158:18
[INFO] [stderr]   10: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:559:17
[INFO] [stderr]   11: <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stderr]   12: std::panicking::catch_unwind::do_call
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40
[INFO] [stderr]   13: __rust_try
[INFO] [stderr]   14: std::panicking::catch_unwind
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19
[INFO] [stderr]       std::panic::catch_unwind
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14
[INFO] [stderr]       std::thread::Builder::spawn_unchecked_::{{closure}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:557:30
[INFO] [stderr]   15: core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5
[INFO] [stderr]   16: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1985:9
[INFO] [stderr]       std::sys::pal::unix::thread::Thread::new::thread_start
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/pal/unix/thread.rs:118:17
[INFO] [stderr]   17: <unknown>
[INFO] [stderr]   18: clone
[INFO] [stderr] 
[INFO] [stdout] test tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:44:51.146003][INFO][test_blocking_async.rs:143] >>> test_timeout_1_tx_blocking_1_rx_async return () in 5.497099879s >>>
[INFO] [stderr] [2025-09-11 04:44:51.178645][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (Tx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:51.313356][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 18.473441739s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_06 ... ok
[INFO] [stderr] [2025-09-11 04:44:51.314153][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (Tx, Rx)) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_09 ... ok
[INFO] [stderr] [2025-09-11 04:44:51.937472][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 18.39922613s >>>
[INFO] [stderr] [2025-09-11 04:44:51.941611][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, Rx)) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_04 ... ok
[INFO] [stderr] [2025-09-11 04:44:52.497116][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 1.182796263s >>>
[INFO] [stderr] [2025-09-11 04:44:52.497431][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, Rx)) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1 ... FAILED
[INFO] [stderr] [2025-09-11 04:44:52.674909][ERROR][test_blocking_async.rs:178] panic occur: panicked at src/tests/test_blocking_async.rs:178:21:
[INFO] [stderr] error receiving on an empty and disconnected channel
[INFO] [stderr] trace:    0: captains_log::log_impl::log_panic
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:340:14
[INFO] [stderr]    1: captains_log::log_impl::panic_and_exit_hook
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:352:5
[INFO] [stderr]       core::ops::function::Fn::call
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:80:5
[INFO] [stderr]    2: <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1999:9
[INFO] [stderr]       std::panicking::panic_with_hook
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:842:13
[INFO] [stderr]    3: std::panicking::panic_handler::{{closure}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:707:13
[INFO] [stderr]    4: std::sys::backtrace::__rust_end_short_backtrace
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:174:18
[INFO] [stderr]    5: __rustc::rust_begin_unwind
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:698:5
[INFO] [stderr]    6: core::panicking::panic_fmt
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panicking.rs:75:14
[INFO] [stderr]    7: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::{{closure}}::{{closure}}
[INFO] [stderr]              at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:178:21
[INFO] [stderr]    8: tokio::runtime::park::CachedParkThread::block_on::{{closure}}
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/park.rs:285:71
[INFO] [stderr]    9: tokio::task::coop::with_budget
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:167:5
[INFO] [stderr]       tokio::task::coop::budget
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:133:5
[INFO] [stderr]       tokio::runtime::park::CachedParkThread::block_on
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/park.rs:285:31
[INFO] [stderr]   10: tokio::runtime::context::blocking::BlockingRegionGuard::block_on
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/blocking.rs:66:14
[INFO] [stderr]   11: tokio::runtime::scheduler::multi_thread::MultiThread::block_on::{{closure}}
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/multi_thread/mod.rs:87:22
[INFO] [stderr]   12: tokio::runtime::context::runtime::enter_runtime
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/runtime.rs:65:16
[INFO] [stderr]   13: tokio::runtime::scheduler::multi_thread::MultiThread::block_on
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/multi_thread/mod.rs:86:9
[INFO] [stderr]   14: tokio::runtime::runtime::Runtime::block_on_inner
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:358:50
[INFO] [stderr]   15: tokio::runtime::runtime::Runtime::block_on
[INFO] [stderr]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:330:18
[INFO] [stderr]   16: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::{{closure}}
[INFO] [stderr]              at /opt/rustwide/workdir/src/tests/common.rs:24:46
[INFO] [stderr]   17: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async
[INFO] [stderr]              at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:150:3
[INFO] [stderr]   18: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1
[INFO] [stderr]              at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:144:1
[INFO] [stderr]   19: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1::{{closure}}
[INFO] [stderr]              at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:144:10
[INFO] [stderr]   20: core::ops::function::FnOnce::call_once
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5
[INFO] [stderr]   21: core::ops::function::FnOnce::call_once
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5
[INFO] [stderr]       test::__rust_begin_short_backtrace
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:663:18
[INFO] [stderr]   22: test::run_test_in_process::{{closure}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:686:74
[INFO] [stderr]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stderr]       std::panicking::catch_unwind::do_call
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40
[INFO] [stderr]       std::panicking::catch_unwind
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19
[INFO] [stderr]       std::panic::catch_unwind
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14
[INFO] [stderr]       test::run_test_in_process
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:686:27
[INFO] [stderr]       test::run_test::{{closure}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:607:43
[INFO] [stderr]   23: test::run_test::{{closure}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:637:41
[INFO] [stderr]       std::sys::backtrace::__rust_begin_short_backtrace
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:158:18
[INFO] [stderr]   24: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:559:17
[INFO] [stderr]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stderr]       std::panicking::catch_unwind::do_call
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40
[INFO] [stderr]       std::panicking::catch_unwind
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19
[INFO] [stderr]       std::panic::catch_unwind
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14
[INFO] [stderr]       std::thread::Builder::spawn_unchecked_::{{closure}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:557:30
[INFO] [stderr]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5
[INFO] [stderr]   25: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1985:9
[INFO] [stderr]       std::sys::pal::unix::thread::Thread::new::thread_start
[INFO] [stderr]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/pal/unix/thread.rs:118:17
[INFO] [stderr]   26: <unknown>
[INFO] [stderr]   27: clone
[INFO] [stderr] 
[INFO] [stderr] [2025-09-11 04:44:52.689911][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, Rx)) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_03 ... ok
[INFO] [stderr] [2025-09-11 04:44:52.802437][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 1.623607347s >>>
[INFO] [stderr] [2025-09-11 04:44:52.803158][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, Rx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:53.035907][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 64.49469636s >>>
[INFO] [stderr] [2025-09-11 04:44:53.041620][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_01 ... ok
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_09 has been running for over 60 seconds
[INFO] [stderr] [2025-09-11 04:44:54.088266][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 1.284925688s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_08 ... ok
[INFO] [stderr] [2025-09-11 04:44:54.088767][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:54.160298][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 4.61456506s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_02 ... ok
[INFO] [stderr] [2025-09-11 04:44:54.160731][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:44:54.895425][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 2.205378081s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_07 ... ok
[INFO] [stderr] [2025-09-11 04:44:54.898252][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_11 ... ok
[INFO] [stderr] [2025-09-11 04:44:55.428126][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 1.267241201s >>>
[INFO] [stderr] [2025-09-11 04:44:55.429123][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 2, rx_count = 2) enter <<<
[INFO] [stderr] [2025-09-11 04:44:55.691680][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 52.999345268s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:44:55.784815][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 16, rx_count = 2) enter <<<
[INFO] [stderr] [2025-09-11 04:44:56.150904][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 1.249198014s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_12 ... ok
[INFO] [stderr] [2025-09-11 04:44:56.151218][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 2, rx_count = 16) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_03 ... ok
[INFO] [stderr] [2025-09-11 04:44:56.767585][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 616.236538ms >>>
[INFO] [stderr] [2025-09-11 04:44:56.767968][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 2, rx_count = 2) enter <<<
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_09 ... ok
[INFO] [stderr] [2025-09-11 04:44:57.163271][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 63.888540031s >>>
[INFO] [stderr] [2025-09-11 04:44:57.166773][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 13, rx_count = 2) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_04 ... ok
[INFO] [stderr] [2025-09-11 04:44:57.415747][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 647.657214ms >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_06 ... ok
[INFO] [stderr] [2025-09-11 04:44:57.420027][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 4.922106064s >>>
[INFO] [stderr] [2025-09-11 04:44:57.420413][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 3, rx_count = 3) enter <<<
[INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_05 ... ok
[INFO] [stderr] [2025-09-11 04:44:57.521657][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 3, rx_count = 10) enter <<<
[INFO] [stderr] [2025-09-11 04:44:57.588645][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 66.484391853s >>>
[INFO] [stderr] [2025-09-11 04:44:57.589044][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 8, rx_count = 3) enter <<<
[INFO] [stderr] [2025-09-11 04:44:57.593558][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 172.998534ms >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_07 ... ok
[INFO] [stderr] [2025-09-11 04:44:57.601489][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 3, rx_count = 8) enter <<<
[INFO] [stderr] [2025-09-11 04:44:57.665867][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 2.236499366s >>>
[INFO] [stderr] [2025-09-11 04:44:57.779711][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 257.897531ms >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_01 ... ok
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_06 ... ok
[INFO] [stderr] [2025-09-11 04:44:57.806424][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 3) enter <<<
[INFO] [stderr] [2025-09-11 04:44:57.806942][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 5, rx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:44:58.158646][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 557.023297ms >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_09 ... ok
[INFO] [stderr] [2025-09-11 04:44:58.159318][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 5) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_10 ... ok
[INFO] [stderr] [2025-09-11 04:44:58.333254][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 526.152432ms >>>
[INFO] [stderr] [2025-09-11 04:44:58.337480][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 10) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_05 ... ok
[INFO] [stderr] [2025-09-11 04:44:58.786773][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 1.619844758s >>>
[INFO] [stderr] [2025-09-11 04:44:58.923809][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 1.33466427s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_08 ... ok
[INFO] [stderr] [2025-09-11 04:44:58.924707][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 4) enter <<<
[INFO] [stderr] [2025-09-11 04:44:58.966632][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 16) enter <<<
[INFO] [stderr] [2025-09-11 04:44:59.048532][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 45.405717984s >>>
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_09 ... ok
[INFO] [stderr] [2025-09-11 04:44:59.051495][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 7) enter <<<
[INFO] [stderr] [2025-09-11 04:44:59.152726][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 1.345936279s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_01 ... ok
[INFO] [stderr] [2025-09-11 04:44:59.153061][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 12) enter <<<
[INFO] [stderr] [2025-09-11 04:44:59.319094][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 1.159661866s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_02 ... ok
[INFO] [stderr] [2025-09-11 04:44:59.361665][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 3) enter <<<
[INFO] [stderr] [2025-09-11 04:44:59.822318][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 438.781894ms >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_08 ... ok
[INFO] [stderr] [2025-09-11 04:44:59.891231][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 5.802310323s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_10 ... ok
[INFO] [stderr] [2025-09-11 04:44:59.892730][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 841.043425ms >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_06 ... ok
[INFO] [stderr] [2025-09-11 04:44:59.902656][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 13) enter <<<
[INFO] [stderr] [2025-09-11 04:44:59.932697][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 2) enter <<<
[INFO] [stderr] [2025-09-11 04:44:59.933062][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 9) enter <<<
[INFO] [stderr] [2025-09-11 04:45:00.734486][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 1.80968237s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_05 ... ok
[INFO] [stderr] [2025-09-11 04:45:00.802646][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 5) enter <<<
[INFO] [stderr] [2025-09-11 04:45:02.281492][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 6.49655068s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_02 ... ok
[INFO] [stderr] [2025-09-11 04:45:02.281784][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 15) enter <<<
[INFO] [stderr] [2025-09-11 04:45:02.776409][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 2.843241526s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_09 ... ok
[INFO] [stderr] [2025-09-11 04:45:02.777027][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 3) enter <<<
[INFO] [stderr] [2025-09-11 04:45:02.932460][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 3.029618907s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_10 ... ok
[INFO] [stderr] [2025-09-11 04:45:02.999466][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 7) enter <<<
[INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_2 has been running for over 60 seconds
[INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_3 has been running for over 60 seconds
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_07 ... ok
[INFO] [stderr] [2025-09-11 04:45:03.332047][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 4.178888716s >>>
[INFO] [stderr] [2025-09-11 04:45:03.332398][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 16) enter <<<
[INFO] [stderr] [2025-09-11 04:45:03.922706][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 1.145489388s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_14 ... ok
[INFO] [stderr] [2025-09-11 04:45:03.954637][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 2) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_15 ... ok
[INFO] [stderr] [2025-09-11 04:45:04.236740][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 1.236280045s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_11 ... ok
[INFO] [stderr] [2025-09-11 04:45:04.237138][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 8) enter <<<
[INFO] [stderr] [2025-09-11 04:45:04.313862][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 4.380978085s >>>
[INFO] [stderr] [2025-09-11 04:45:04.314202][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 16) enter <<<
[INFO] [stderr] [2025-09-11 04:45:04.597819][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 56.514387543s >>>
[INFO] [stderr] [2025-09-11 04:45:04.600474][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 645.686933ms >>>
[INFO] [stderr] [2025-09-11 04:45:04.607130][INFO][test_blocking_context.rs:479] <<< test_pressure_bounded_timeout_blocking (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_17 ... ok
[INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_03 ... ok
[INFO] [stderr] [2025-09-11 04:45:04.624314][INFO][test_blocking_context.rs:479] <<< test_pressure_bounded_timeout_blocking (setup_log = (), _channel = (MTx, MRx)) enter <<<
[INFO] [stderr] [2025-09-11 04:45:04.640611][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 6.302991488s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_03 ... ok
[INFO] [stderr] [2025-09-11 04:45:05.156843][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 4.354037119s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_12 ... ok
[INFO] [stderr] [2025-09-11 04:45:05.265458][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 1.028227517s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_18 ... ok
[INFO] [stderr] [2025-09-11 04:45:06.751460][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 2.437157596s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_19 ... ok
[INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_3 ... ok
[INFO] [stderr] [2025-09-11 04:45:06.944972][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 63.882857173s >>>
[INFO] [stderr] [2025-09-11 04:45:07.102176][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 4.82028834s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_13 ... ok
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_16 ... ok
[INFO] [stderr] [2025-09-11 04:45:07.327568][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 3.995039703s >>>
[INFO] [stderr] [2025-09-11 04:45:07.931057][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 8.96426303s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_04 ... ok
[INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_2 ... ok
[INFO] [stderr] [2025-09-11 04:45:08.557187][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 65.525116837s >>>
[INFO] [stderr] [2025-09-11 04:45:12.758225][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 19.716482224s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_09 ... ok
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_01 ... ok
[INFO] [stderr] [2025-09-11 04:45:13.846762][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 25.097863579s >>>
[INFO] [stderr] [2025-09-11 04:45:14.643700][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 22.701957577s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_05 ... ok
[INFO] [stderr] [2025-09-11 04:45:17.807265][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 101.355030762s >>>
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_03 ... ok
[INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_12 ... ok
[INFO] [stderr] [2025-09-11 04:45:20.987033][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 103.291194743s >>>
[INFO] [stderr] [2025-09-11 04:45:27.657223][INFO][test_blocking_context.rs:479] >>> test_pressure_bounded_timeout_blocking return () in 23.032757248s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_timeout_blocking::case_1 ... ok
[INFO] [stderr] [2025-09-11 04:45:29.000633][INFO][test_blocking_context.rs:479] >>> test_pressure_bounded_timeout_blocking return () in 24.393138264s >>>
[INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_timeout_blocking::case_2 ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1 stdout ----
[INFO] [stdout] log sinks opened
[INFO] [stdout] panic occur: panicked at src/tests/test_blocking_async.rs:165:9:
[INFO] [stdout] assertion failed: tx.send_timeout(10, Duration::from_millis(200)).is_ok() at Some(Location { file: "src/tests/test_blocking_async.rs", line: 165, column: 9 })
[INFO] [stdout] trace:    0: captains_log::log_impl::log_panic
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:340:14
[INFO] [stdout]    1: captains_log::log_impl::panic_and_exit_hook
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:352:5
[INFO] [stdout]       core::ops::function::Fn::call
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:80:5
[INFO] [stdout]    2: <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1999:9
[INFO] [stdout]       std::panicking::panic_with_hook
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:842:13
[INFO] [stdout]    3: std::panicking::panic_handler::{{closure}}
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:700:13
[INFO] [stdout]    4: std::sys::backtrace::__rust_end_short_backtrace
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:174:18
[INFO] [stdout]    5: __rustc::rust_begin_unwind
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:698:5
[INFO] [stdout]    6: core::panicking::panic_fmt
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panicking.rs:75:14
[INFO] [stdout]    7: core::panicking::panic
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panicking.rs:145:5
[INFO] [stdout]    8: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::{{closure}}::{{closure}}
[INFO] [stdout]              at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:165:9
[INFO] [stdout]    9: std::sys::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:158:18
[INFO] [stdout]   10: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:559:17
[INFO] [stdout]   11: <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   12: std::panicking::catch_unwind::do_call
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40
[INFO] [stdout]   13: __rust_try
[INFO] [stdout]   14: std::panicking::catch_unwind
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14
[INFO] [stdout]       std::thread::Builder::spawn_unchecked_::{{closure}}
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:557:30
[INFO] [stdout]   15: core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5
[INFO] [stdout]   16: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1985:9
[INFO] [stdout]       std::sys::pal::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/pal/unix/thread.rs:118:17
[INFO] [stdout]   17: <unknown>
[INFO] [stdout]   18: clone
[INFO] [stdout] panic occur: panicked at src/tests/test_blocking_async.rs:178:21:
[INFO] [stdout] error receiving on an empty and disconnected channel at Some(Location { file: "src/tests/test_blocking_async.rs", line: 178, column: 21 })
[INFO] [stdout] trace:    0: captains_log::log_impl::log_panic
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:340:14
[INFO] [stdout]    1: captains_log::log_impl::panic_and_exit_hook
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:352:5
[INFO] [stdout]       core::ops::function::Fn::call
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:80:5
[INFO] [stdout]    2: <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1999:9
[INFO] [stdout]       std::panicking::panic_with_hook
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:842:13
[INFO] [stdout]    3: std::panicking::panic_handler::{{closure}}
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:707:13
[INFO] [stdout]    4: std::sys::backtrace::__rust_end_short_backtrace
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:174:18
[INFO] [stdout]    5: __rustc::rust_begin_unwind
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:698:5
[INFO] [stdout]    6: core::panicking::panic_fmt
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panicking.rs:75:14
[INFO] [stdout]    7: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::{{closure}}::{{closure}}
[INFO] [stdout]              at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:178:21
[INFO] [stdout]    8: tokio::runtime::park::CachedParkThread::block_on::{{closure}}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/park.rs:285:71
[INFO] [stdout]    9: tokio::task::coop::with_budget
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:167:5
[INFO] [stdout]       tokio::task::coop::budget
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:133:5
[INFO] [stdout]       tokio::runtime::park::CachedParkThread::block_on
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/park.rs:285:31
[INFO] [stdout]   10: tokio::runtime::context::blocking::BlockingRegionGuard::block_on
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/blocking.rs:66:14
[INFO] [stdout]   11: tokio::runtime::scheduler::multi_thread::MultiThread::block_on::{{closure}}
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/multi_thread/mod.rs:87:22
[INFO] [stdout]   12: tokio::runtime::context::runtime::enter_runtime
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   13: tokio::runtime::scheduler::multi_thread::MultiThread::block_on
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/multi_thread/mod.rs:86:9
[INFO] [stdout]   14: tokio::runtime::runtime::Runtime::block_on_inner
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:358:50
[INFO] [stdout]   15: tokio::runtime::runtime::Runtime::block_on
[INFO] [stdout]              at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:330:18
[INFO] [stdout]   16: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::{{closure}}
[INFO] [stdout]              at /opt/rustwide/workdir/src/tests/common.rs:24:46
[INFO] [stdout]   17: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async
[INFO] [stdout]              at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:150:3
[INFO] [stdout]   18: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1
[INFO] [stdout]              at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:144:1
[INFO] [stdout]   19: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1::{{closure}}
[INFO] [stdout]              at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:144:10
[INFO] [stdout]   20: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5
[INFO] [stdout]   21: core::ops::function::FnOnce::call_once
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5
[INFO] [stdout]       test::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:663:18
[INFO] [stdout]   22: test::run_test_in_process::{{closure}}
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:686:74
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]       std::panicking::catch_unwind::do_call
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40
[INFO] [stdout]       std::panicking::catch_unwind
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14
[INFO] [stdout]       test::run_test_in_process
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:686:27
[INFO] [stdout]       test::run_test::{{closure}}
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:607:43
[INFO] [stdout]   23: test::run_test::{{closure}}
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/test/src/lib.rs:637:41
[INFO] [stdout]       std::sys::backtrace::__rust_begin_short_backtrace
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/backtrace.rs:158:18
[INFO] [stdout]   24: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}}
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:559:17
[INFO] [stdout]       <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]       std::panicking::catch_unwind::do_call
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:590:40
[INFO] [stdout]       std::panicking::catch_unwind
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panicking.rs:553:19
[INFO] [stdout]       std::panic::catch_unwind
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/panic.rs:359:14
[INFO] [stdout]       std::thread::Builder::spawn_unchecked_::{{closure}}
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/thread/mod.rs:557:30
[INFO] [stdout]       core::ops::function::FnOnce::call_once{{vtable.shim}}
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/core/src/ops/function.rs:253:5
[INFO] [stdout]   25: <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/alloc/src/boxed.rs:1985:9
[INFO] [stdout]       std::sys::pal::unix::thread::Thread::new::thread_start
[INFO] [stdout]              at /rustc/ad85bc524b1ad696e42061ad8338d382dffbdbe5/library/std/src/sys/pal/unix/thread.rs:118:17
[INFO] [stdout]   26: <unknown>
[INFO] [stdout]   27: clone
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 279 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 113.43s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "60ac783048ce385ad3fd204bab50cd55105d565cdae467cb444a95d10ff47aba", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "60ac783048ce385ad3fd204bab50cd55105d565cdae467cb444a95d10ff47aba", kill_on_drop: false }`
[INFO] [stdout] 60ac783048ce385ad3fd204bab50cd55105d565cdae467cb444a95d10ff47aba