[INFO] fetching crate crossfire 2.0.26... [INFO] testing crossfire-2.0.26 against try#d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6 for pr-146237 [INFO] extracting crate crossfire 2.0.26 into /workspace/builds/worker-7-tc2/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-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate crossfire 2.0.26 on toolchain d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "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" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 0464a569ea8e384b7f0c5483dd7d570b8c2211749002a3e2a3638278d5b7108d [INFO] running `Command { std: "docker" "start" "-a" "0464a569ea8e384b7f0c5483dd7d570b8c2211749002a3e2a3638278d5b7108d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "0464a569ea8e384b7f0c5483dd7d570b8c2211749002a3e2a3638278d5b7108d", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "0464a569ea8e384b7f0c5483dd7d570b8c2211749002a3e2a3638278d5b7108d", kill_on_drop: false }` [INFO] [stdout] 0464a569ea8e384b7f0c5483dd7d570b8c2211749002a3e2a3638278d5b7108d [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] aa5e6daa58ed55cf9c4ef2540e738a82d0a63e5f0cee6e3e7ca176dd2a798385 [INFO] running `Command { std: "docker" "start" "-a" "aa5e6daa58ed55cf9c4ef2540e738a82d0a63e5f0cee6e3e7ca176dd2a798385", kill_on_drop: false }` [INFO] [stderr] Compiling futures-sink v0.3.31 [INFO] [stderr] Compiling syn v2.0.106 [INFO] [stderr] Compiling once_cell v1.21.3 [INFO] [stderr] Compiling crossbeam-channel v0.5.15 [INFO] [stderr] Compiling parking_lot_core v0.9.11 [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 7.14s [INFO] running `Command { std: "docker" "inspect" "aa5e6daa58ed55cf9c4ef2540e738a82d0a63e5f0cee6e3e7ca176dd2a798385", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "aa5e6daa58ed55cf9c4ef2540e738a82d0a63e5f0cee6e3e7ca176dd2a798385", kill_on_drop: false }` [INFO] [stdout] aa5e6daa58ed55cf9c4ef2540e738a82d0a63e5f0cee6e3e7ca176dd2a798385 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 7c6249056c3b95f66c1cd1ed13e1b5561d10b8dd25736d2cc191390ff9eff8ab [INFO] running `Command { std: "docker" "start" "-a" "7c6249056c3b95f66c1cd1ed13e1b5561d10b8dd25736d2cc191390ff9eff8ab", kill_on_drop: false }` [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling nix v0.30.1 [INFO] [stderr] Compiling value-bag v1.11.1 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling crossbeam-queue v0.3.12 [INFO] [stderr] Compiling crossbeam-channel v0.5.15 [INFO] [stderr] Compiling indexmap v2.11.0 [INFO] [stderr] Compiling parking_lot_core v0.9.11 [INFO] [stderr] Compiling syn v2.0.106 [INFO] [stderr] Compiling rand_core v0.9.3 [INFO] [stderr] Compiling aho-corasick v1.1.3 [INFO] [stderr] Compiling bitflags v2.9.3 [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 event-listener v5.4.1 [INFO] [stderr] Compiling flate2 v1.1.2 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling parking_lot v0.12.4 [INFO] [stderr] Compiling ciborium-ll v0.2.2 [INFO] [stderr] Compiling object v0.36.7 [INFO] [stderr] Compiling signal-hook-registry v1.4.6 [INFO] [stderr] Compiling nanorand v0.7.0 [INFO] [stderr] Compiling spin v0.9.8 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling crossbeam v0.8.4 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling event-listener-strategy v0.5.4 [INFO] [stderr] Compiling bpaf v0.9.20 [INFO] [stderr] Compiling anes v0.2.1 [INFO] [stderr] Compiling arc-swap v1.7.1 [INFO] [stderr] Compiling toml_edit v0.22.27 [INFO] [stderr] Compiling exitcode v1.1.2 [INFO] [stderr] Compiling rand v0.9.2 [INFO] [stderr] Compiling async-channel v2.5.0 [INFO] [stderr] Compiling flume v0.11.1 [INFO] [stderr] Compiling kanal v0.1.1 [INFO] [stderr] Compiling regex-automata v0.4.10 [INFO] [stderr] Compiling fail v0.5.1 [INFO] [stderr] Compiling file-rotate v0.8.0 [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 backtrace v0.3.75 [INFO] [stderr] Compiling regex v1.11.2 [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 rstest_macros v0.26.1 [INFO] [stderr] Compiling tokio v1.47.1 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling serde v1.0.219 [INFO] [stderr] Compiling futures-executor v0.3.31 [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 rstest v0.26.1 [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 39.39s [INFO] running `Command { std: "docker" "inspect" "7c6249056c3b95f66c1cd1ed13e1b5561d10b8dd25736d2cc191390ff9eff8ab", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7c6249056c3b95f66c1cd1ed13e1b5561d10b8dd25736d2cc191390ff9eff8ab", kill_on_drop: false }` [INFO] [stdout] 7c6249056c3b95f66c1cd1ed13e1b5561d10b8dd25736d2cc191390ff9eff8ab [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/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" "+d79d87d4c0f7f0d0785ad6e493cf8cf718ed89a6" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] afab1269152030cd7ebdfb5bef0bb80d8bc17bae1c56f394af856215ac6dc0da [INFO] running `Command { std: "docker" "start" "-a" "afab1269152030cd7ebdfb5bef0bb80d8bc17bae1c56f394af856215ac6dc0da", kill_on_drop: false }` [INFO] [stderr] warning: struct `SpuriousTx` is never constructed [INFO] [stdout] [INFO] [stderr] --> src/tests/test_async.rs:945:8 [INFO] [stdout] running 280 tests [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.22s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/crossfire-57457c4b267ea3c2) [INFO] [stderr] [2025-09-11 04:46:19.163208][INFO][test_async.rs:19] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.162475][INFO][test_async.rs:221] <<< test_basic_bounded_1_thread (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.162430][INFO][test_async.rs:221] <<< test_basic_bounded_1_thread (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.163288][INFO][test_async.rs:230] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.163293][INFO][test_async.rs:19] >>> test_basic_bounded_empty_full_drop_rx return () in 15.45µs >>> [INFO] [stderr] [2025-09-11 04:46:19.163292][INFO][test_async.rs:230] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.163126][INFO][test_async.rs:44] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.163396][INFO][test_async.rs:44] >>> test_basic_bounded_empty_full_drop_tx return () in 7.5µs >>> [INFO] [stderr] [2025-09-11 04:46:19.162894][INFO][test_async.rs:19] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.163496][INFO][test_async.rs:19] >>> test_basic_bounded_empty_full_drop_rx return () in 7.91µs >>> [INFO] [stderr] [2025-09-11 04:46:19.163001][INFO][test_async.rs:19] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.163610][INFO][test_async.rs:19] >>> test_basic_bounded_empty_full_drop_rx return () in 7.67µs >>> [INFO] [stderr] [2025-09-11 04:46:19.162867][INFO][test_async.rs:44] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.163745][INFO][test_async.rs:44] >>> test_basic_bounded_empty_full_drop_tx return () in 9.38µs >>> [INFO] [stderr] [2025-09-11 04:46:19.163883][INFO][test_async.rs:357] <<< test_basic_bounded_recv_after_sender_close (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stdout] test collections::tests::test_locked_queue ... ok [INFO] [stderr] [2025-09-11 04:46:19.163997][INFO][test_async.rs:372] run with tokio multi thread [INFO] [stdout] test collections::tests::test_weak_cell ... ok [INFO] [stderr] [2025-09-11 04:46:19.162732][INFO][test_async.rs:44] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stdout] test locked_waker::tests::test_waker ... ok [INFO] [stderr] [2025-09-11 04:46:19.164286][INFO][test_async.rs:44] >>> test_basic_bounded_empty_full_drop_tx return () in 9.7µs >>> [INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_rx::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:19.167792][INFO][test_async.rs:171] <<< test_basic_bounded_rx_drop (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_tx::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:19.167913][INFO][test_async.rs:179] 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:46:19.168289][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_rx::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:19.168368][INFO][test_async.rs:1099] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_tx::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:19.168351][INFO][test_async.rs:171] <<< test_basic_bounded_rx_drop (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_tx::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:19.168432][INFO][test_async.rs:179] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.168808][INFO][test_async.rs:1088] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.168877][INFO][test_async.rs:1099] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.169102][INFO][test_async.rs:1088] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.169187][INFO][test_async.rs:1099] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.170075][INFO][test_async.rs:221] <<< test_basic_bounded_1_thread (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.170162][INFO][test_async.rs:230] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.171026][INFO][test_async.rs:171] <<< test_basic_bounded_rx_drop (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.171115][INFO][test_async.rs:179] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.171103][INFO][test_async.rs:357] <<< test_basic_bounded_recv_after_sender_close (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.171192][INFO][test_async.rs:372] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.171549][INFO][test_async.rs:1088] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.171651][INFO][test_async.rs:1099] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.171891][INFO][test_async.rs:69] <<< test_basic_compile_bounded_empty_full () enter <<< [INFO] [stderr] [2025-09-11 04:46:19.172013][INFO][test_async.rs:69] >>> test_basic_compile_bounded_empty_full return () in 22.09µs >>> [INFO] [stderr] [2025-09-11 04:46:19.172215][INFO][test_async.rs:1088] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.172290][INFO][test_async.rs:1099] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_compile_bounded_empty_full ... ok [INFO] [stderr] [2025-09-11 04:46:19.176072][INFO][test_async.rs:1088] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.176187][INFO][test_async.rs:1099] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.176673][INFO][test_async.rs:490] <<< test_basic_send_timeout_async (setup_log = (), _channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.176754][INFO][test_async.rs:490] >>> test_basic_send_timeout_async return () in 740ns >>> [INFO] [stderr] [2025-09-11 04:46:19.176888][INFO][test_async.rs:357] <<< test_basic_bounded_recv_after_sender_close (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.176988][INFO][test_async.rs:372] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_send_timeout_async::case_1 ... ok [INFO] [stdout] test tests::test_async::test_basic_send_timeout_async::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:19.181343][INFO][test_async.rs:490] <<< test_basic_send_timeout_async (setup_log = (), _channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.181462][INFO][test_async.rs:490] >>> test_basic_send_timeout_async return () in 980ns >>> [INFO] [stderr] [2025-09-11 04:46:19.181670][INFO][test_async.rs:490] <<< test_basic_send_timeout_async (setup_log = (), _channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.181757][INFO][test_async.rs:490] >>> test_basic_send_timeout_async return () in 880ns >>> [INFO] [stdout] test tests::test_async::test_basic_send_timeout_async::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:19.190402][INFO][test_async.rs:422] <<< test_basic_timeout_recv_async_waker (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.190559][INFO][test_async.rs:422] >>> test_basic_timeout_recv_async_waker return () in 1.24µs >>> [INFO] [stdout] test tests::test_async::test_basic_timeout_recv_async_waker::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:19.198727][INFO][test_async.rs:422] <<< test_basic_timeout_recv_async_waker (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.198904][INFO][test_async.rs:422] >>> test_basic_timeout_recv_async_waker return () in 1.27µs >>> [INFO] [stderr] [2025-09-11 04:46:19.236022][INFO][test_async.rs:188] try to send after rx dropped [INFO] [stderr] [2025-09-11 04:46:19.236104][INFO][test_async.rs:191] dropped tx [INFO] [stderr] [2025-09-11 04:46:19.239253][INFO][test_async.rs:357] >>> test_basic_bounded_recv_after_sender_close return () in 75.259939ms >>> [INFO] [stderr] [2025-09-11 04:46:19.239629][INFO][test_async.rs:188] try to send after rx dropped [INFO] [stderr] [2025-09-11 04:46:19.239682][INFO][test_async.rs:191] dropped tx [INFO] [stderr] [2025-09-11 04:46:19.239794][INFO][test_async.rs:171] >>> test_basic_bounded_rx_drop return () in 71.36079ms >>> [INFO] [stderr] [2025-09-11 04:46:19.245969][INFO][test_async.rs:1088] >>> test_basic_into_stream_1_1 return () in 77.091269ms >>> [INFO] [stderr] [2025-09-11 04:46:19.246451][INFO][test_async.rs:1088] >>> test_basic_into_stream_1_1 return () in 74.160729ms >>> [INFO] [stderr] [2025-09-11 04:46:19.246918][INFO][test_async.rs:1088] >>> test_basic_into_stream_1_1 return () in 75.264039ms >>> [INFO] [stdout] test tests::test_async::test_basic_timeout_recv_async_waker::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:19.247542][INFO][test_async.rs:357] >>> test_basic_bounded_recv_after_sender_close return () in 70.561889ms >>> [INFO] [stdout] test tests::test_async::test_basic_bounded_recv_after_sender_close::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:19.250055][INFO][test_async.rs:1088] >>> test_basic_into_stream_1_1 return () in 81.686278ms >>> [INFO] [stderr] [2025-09-11 04:46:19.250229][INFO][test_async.rs:188] try to send after rx dropped [INFO] [stderr] [2025-09-11 04:46:19.250266][INFO][test_async.rs:191] dropped tx [INFO] [stderr] [2025-09-11 04:46:19.252904][INFO][test_async.rs:171] >>> test_basic_bounded_rx_drop return () in 81.788868ms >>> [INFO] [stderr] [2025-09-11 04:46:19.253404][INFO][test_async.rs:357] >>> test_basic_bounded_recv_after_sender_close return () in 82.221548ms >>> [INFO] [stderr] [2025-09-11 04:46:19.253838][INFO][test_async.rs:1088] >>> test_basic_into_stream_1_1 return () in 84.651108ms >>> [INFO] [stderr] [2025-09-11 04:46:19.256301][INFO][test_async.rs:1088] >>> test_basic_into_stream_1_1 return () in 80.111028ms >>> [INFO] [stderr] [2025-09-11 04:46:19.256759][INFO][test_async.rs:422] <<< test_basic_timeout_recv_async_waker (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_bounded_rx_drop::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:19.256864][INFO][test_async.rs:422] >>> test_basic_timeout_recv_async_waker return () in 880ns >>> [INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_5 ... ok [INFO] [stderr] [2025-09-11 04:46:19.256777][INFO][test_async.rs:266] <<< test_basic_unbounded_1_thread (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:19.256840][INFO][test_async.rs:266] <<< test_basic_unbounded_1_thread (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:19.256903][INFO][test_async.rs:277] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_bounded_recv_after_sender_close::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:19.256910][INFO][test_async.rs:277] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_6 ... ok [INFO] [stderr] [2025-09-11 04:46:19.257017][INFO][test_async.rs:310] <<< test_basic_unbounded_idle_select (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_bounded_rx_drop::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:19.257086][INFO][test_async.rs:332] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_bounded_recv_after_sender_close::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:19.257446][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_1 ... ok [INFO] [stderr] [2025-09-11 04:46:19.257546][INFO][test_async.rs:332] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_4 ... ok [INFO] [stderr] [2025-09-11 04:46:19.257624][INFO][test_async.rs:266] <<< test_basic_unbounded_1_thread (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_timeout_recv_async_waker::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:19.257701][INFO][test_async.rs:277] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.258052][INFO][test_async.rs:389] <<< test_basic_unbounded_recv_after_sender_close (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.258240][INFO][test_async.rs:406] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.256955][INFO][test_async.rs:310] <<< test_basic_unbounded_idle_select (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.258603][INFO][test_async.rs:332] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.260588][INFO][test_async.rs:389] <<< test_basic_unbounded_recv_after_sender_close (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.260879][INFO][test_async.rs:389] <<< test_basic_unbounded_recv_after_sender_close (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.263704][INFO][test_async.rs:406] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.261062][INFO][test_async.rs:406] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.264563][INFO][test_async.rs:456] <<< test_basic_unbounded_recv_timeout_async (setup_log = (), _channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.264789][INFO][test_async.rs:456] <<< test_basic_unbounded_recv_timeout_async (setup_log = (), _channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.264882][INFO][test_async.rs:456] >>> test_basic_unbounded_recv_timeout_async return () in 860ns >>> [INFO] [stderr] [2025-09-11 04:46:19.265174][INFO][test_async.rs:456] >>> test_basic_unbounded_recv_timeout_async return () in 1.52µs >>> [INFO] [stderr] [2025-09-11 04:46:19.266215][INFO][test_async.rs:196] <<< test_basic_unbounded_rx_drop (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_timeout_async::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:19.266314][INFO][test_async.rs:204] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.266245][INFO][test_async.rs:456] <<< test_basic_unbounded_recv_timeout_async (setup_log = (), _channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.266418][INFO][test_async.rs:456] >>> test_basic_unbounded_recv_timeout_async return () in 880ns >>> [INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_timeout_async::case_1 ... ok [INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_timeout_async::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:19.267181][INFO][test_async.rs:196] <<< test_basic_unbounded_rx_drop (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.267267][INFO][test_async.rs:204] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.267253][INFO][test_async.rs:196] <<< test_basic_unbounded_rx_drop (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.267331][INFO][test_async.rs:204] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.267672][INFO][test_async.rs:171] >>> test_basic_bounded_rx_drop return () in 99.759195ms >>> [INFO] [stdout] test tests::test_async::test_basic_bounded_rx_drop::case_3 ... ok [INFO] [stdout] test tests::test_async::test_conversion ... ok [INFO] [stderr] [2025-09-11 04:46:19.268984][INFO][test_async.rs:213] try to send after rx dropped [INFO] [stderr] [2025-09-11 04:46:19.269026][INFO][test_async.rs:216] dropped tx [INFO] [stderr] [2025-09-11 04:46:19.271854][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.272010][INFO][test_async.rs:693] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.272701][INFO][test_async.rs:196] >>> test_basic_unbounded_rx_drop return () in 5.37052ms >>> [INFO] [stderr] [2025-09-11 04:46:19.277947][INFO][test_async.rs:389] >>> test_basic_unbounded_recv_after_sender_close return () in 17.202178ms >>> [INFO] [stdout] test tests::test_async::test_basic_unbounded_rx_drop::case_3 ... ok [INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_after_sender_close::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:19.278241][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.278216][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.278368][INFO][test_async.rs:693] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.278368][INFO][test_async.rs:693] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.283433][INFO][test_async.rs:213] try to send after rx dropped [INFO] [stderr] [2025-09-11 04:46:19.283521][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:46:19.285933][INFO][test_async.rs:196] >>> test_basic_unbounded_rx_drop return () in 19.619117ms >>> [INFO] [stderr] [2025-09-11 04:46:19.286490][INFO][test_async.rs:213] try to send after rx dropped [INFO] [stderr] [2025-09-11 04:46:19.286565][INFO][test_async.rs:216] dropped tx [INFO] [stderr] [2025-09-11 04:46:19.286960][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.287087][INFO][test_async.rs:693] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.292142][INFO][test_async.rs:196] >>> test_basic_unbounded_rx_drop return () in 24.871866ms >>> [INFO] [stderr] [2025-09-11 04:46:19.297390][INFO][test_async.rs:389] >>> test_basic_unbounded_recv_after_sender_close return () in 33.799335ms >>> [INFO] [stdout] test tests::test_async::test_basic_unbounded_rx_drop::case_2 ... ok [INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_after_sender_close::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:19.301295][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:19.301425][INFO][test_async.rs:693] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.302050][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_after_sender_close::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:19.302145][INFO][test_async.rs:693] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:19.303143][INFO][test_async.rs:389] >>> test_basic_unbounded_recv_after_sender_close return () in 45.016113ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_7 ... ok [INFO] [stderr] [2025-09-11 04:46:19.306026][INFO][test_async.rs:679] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_6 ... ok [INFO] [stderr] [2025-09-11 04:46:19.306132][INFO][test_async.rs:693] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.311780][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 33.412335ms >>> [INFO] [stderr] [2025-09-11 04:46:19.312585][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:46:19.312726][INFO][test_async.rs:808] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.320433][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 14.299768ms >>> [INFO] [stderr] [2025-09-11 04:46:19.320761][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:46:19.320884][INFO][test_async.rs:808] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.329194][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 27.767636ms >>> [INFO] [stderr] [2025-09-11 04:46:19.337831][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:46:19.337966][INFO][test_async.rs:808] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_5 ... ok [INFO] [stderr] [2025-09-11 04:46:19.451353][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 149.205188ms >>> [INFO] [stderr] [2025-09-11 04:46:19.453623][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:46:19.453753][INFO][test_async.rs:808] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.633697][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 320.968832ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_01 ... ok [INFO] [stderr] [2025-09-11 04:46:19.634060][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:46:19.634232][INFO][test_async.rs:808] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.650790][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 372.420674ms >>> [INFO] [stderr] [2025-09-11 04:46:19.651918][INFO][test_async.rs:782] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100, rx_count = 10) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:19.652068][INFO][test_async.rs:808] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.675538][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 41.303744ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_05 ... ok [INFO] [stderr] [2025-09-11 04:46:19.676929][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:46:19.677444][INFO][test_async.rs:808] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.754937][INFO][test_async.rs:353] waker rx 0 [INFO] [stderr] [2025-09-11 04:46:19.764229][INFO][test_async.rs:353] waker rx 1 [INFO] [stderr] [2025-09-11 04:46:19.772387][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 485.299987ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_4 ... ok [INFO] [stderr] [2025-09-11 04:46:19.772696][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:46:19.772812][INFO][test_async.rs:808] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_unbounded_idle_select::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:19.780014][INFO][test_async.rs:310] >>> test_basic_unbounded_idle_select return () in 522.926432ms >>> [INFO] [stderr] [2025-09-11 04:46:19.781750][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:46:19.782385][INFO][test_async.rs:808] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.783180][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 445.211133ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_03 ... ok [INFO] [stderr] [2025-09-11 04:46:19.783629][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:46:19.783772][INFO][test_async.rs:808] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.794812][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 117.368283ms >>> [INFO] [stderr] [2025-09-11 04:46:19.812568][INFO][test_async.rs:353] waker rx 0 [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_07 ... ok [INFO] [stdout] test tests::test_async::test_basic_unbounded_idle_select::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:19.890758][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_basic_unbounded_idle_select::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:19.890915][INFO][test_async.rs:808] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.892560][INFO][test_async.rs:310] >>> test_basic_unbounded_idle_select return () in 635.014425ms >>> [INFO] [stderr] [2025-09-11 04:46:19.892925][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:46:19.893086][INFO][test_async.rs:808] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:19.894906][INFO][test_async.rs:310] >>> test_basic_unbounded_idle_select return () in 636.304635ms >>> [INFO] [stderr] [2025-09-11 04:46:19.920724][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:46:19.920882][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:20.220804][INFO][test_async.rs:221] >>> test_basic_bounded_1_thread return () in 1.057521792s >>> [INFO] [stdout] test tests::test_async::test_basic_bounded_1_thread::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:20.261587][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 479.200739ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_09 ... ok [INFO] [stderr] [2025-09-11 04:46:20.262411][INFO][test_async.rs:221] >>> test_basic_bounded_1_thread return () in 1.099130286s >>> [INFO] [stdout] test tests::test_async::test_basic_bounded_1_thread::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:20.262995][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 372.080565ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_11 ... ok [INFO] [stderr] [2025-09-11 04:46:20.263965][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 100) enter <<< [INFO] [stdout] test tests::test_async::test_basic_bounded_1_thread::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:20.264082][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:20.266096][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:46:20.266177][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:20.270578][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:46:20.270656][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:20.279563][INFO][test_async.rs:221] >>> test_basic_bounded_1_thread return () in 1.109401264s >>> [INFO] [stderr] [2025-09-11 04:46:20.280655][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:46:20.280804][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:20.283615][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:46:20.283709][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:20.287537][INFO][test_async.rs:266] >>> test_basic_unbounded_1_thread return () in 1.030629236s >>> [INFO] [stderr] [2025-09-11 04:46:20.310131][INFO][test_async.rs:266] >>> test_basic_unbounded_1_thread return () in 1.053229133s >>> [INFO] [stderr] [2025-09-11 04:46:20.331426][INFO][test_async.rs:266] >>> test_basic_unbounded_1_thread return () in 1.073722799s >>> [INFO] [stderr] [2025-09-11 04:46:20.369624][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 10) enter <<< [INFO] [stdout] test tests::test_async::test_basic_unbounded_1_thread::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:20.369787][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_unbounded_1_thread::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:20.370078][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 300) enter <<< [INFO] [stdout] test tests::test_async::test_basic_unbounded_1_thread::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:20.370165][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:20.370612][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:46:20.370700][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:20.926547][INFO][test_async.rs:679] >>> test_pressure_bounded_async_1_1 return () in 1.654535473s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:20.985378][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:46:20.985560][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_07 ... ok [INFO] [stderr] [2025-09-11 04:46:21.142632][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 772.843614ms >>> [INFO] [stderr] [2025-09-11 04:46:21.143012][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:46:21.143144][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:21.245686][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 961.974726ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_04 ... ok [INFO] [stderr] [2025-09-11 04:46:21.248357][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:46:21.255219][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:46:21.617999][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 1.834226426s >>> [INFO] [stderr] [2025-09-11 04:46:21.618536][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:46:21.619129][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:21.850815][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 2.529928562s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_02 ... ok [INFO] [stderr] [2025-09-11 04:46:21.851776][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:46:21.857411][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:22.385538][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 2.733468151s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_06 ... ok [INFO] [stderr] [2025-09-11 04:46:22.386645][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_1::case_01 ... ok [INFO] [stderr] [2025-09-11 04:46:22.386781][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:22.408979][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 2.488097268s >>> [INFO] [stderr] [2025-09-11 04:46:22.409278][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:46:22.409389][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:22.567007][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 947.875868ms >>> [INFO] [stderr] [2025-09-11 04:46:22.630327][INFO][test_async.rs:718] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_13 ... ok [INFO] [stderr] [2025-09-11 04:46:22.630447][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:22.761536][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 352.145207ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_16 ... ok [INFO] [stderr] [2025-09-11 04:46:22.767398][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:46:22.767523][INFO][test_async.rs:749] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_04 ... ok [INFO] [stderr] [2025-09-11 04:46:23.765923][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 4.312168275s >>> [INFO] [stderr] [2025-09-11 04:46:23.772781][INFO][test_async.rs:853] <<< test_pressure_bounded_mixed_async_blocking_conversion (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:23.772912][INFO][test_async.rs:862] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:24.189713][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 4.296624438s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_12 ... ok [INFO] [stderr] [2025-09-11 04:46:24.190610][INFO][test_async.rs:853] <<< test_pressure_bounded_mixed_async_blocking_conversion (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_bounded_mixed_async_blocking_conversion::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:24.192109][INFO][test_async.rs:862] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_mixed_async_blocking_conversion::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:24.282604][INFO][test_async.rs:853] >>> test_pressure_bounded_mixed_async_blocking_conversion return () in 509.690834ms >>> [INFO] [stderr] [2025-09-11 04:46:24.283049][INFO][test_async.rs:853] <<< test_pressure_bounded_mixed_async_blocking_conversion (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:24.283190][INFO][test_async.rs:862] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:24.303989][INFO][test_async.rs:853] >>> test_pressure_bounded_mixed_async_blocking_conversion return () in 20.798087ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_mixed_async_blocking_conversion::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:24.318049][INFO][test_async.rs:853] >>> test_pressure_bounded_mixed_async_blocking_conversion return () in 125.938991ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_08 ... ok [INFO] [stderr] [2025-09-11 04:46:24.318841][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 2) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_bounded_timeout_async::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:24.318986][INFO][test_async.rs:1140] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_08 ... ok [INFO] [stderr] [2025-09-11 04:46:24.323880][INFO][test_async.rs:782] >>> test_pressure_bounded_async_multi return () in 4.55106544s >>> [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_4 ... ok [INFO] [stderr] [2025-09-11 04:46:24.324141][INFO][test_async.rs:550] <<< test_pressure_bounded_timeout_async (setup_log = (), _channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:24.324247][INFO][test_async.rs:550] >>> test_pressure_bounded_timeout_async return () in 800ns >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_10 ... ok [INFO] [stderr] [2025-09-11 04:46:24.324275][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 4) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_6 ... ok [INFO] [stderr] [2025-09-11 04:46:24.324355][INFO][test_async.rs:1140] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_5 ... ok [INFO] [stderr] [2025-09-11 04:46:24.324605][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 10) enter <<< [INFO] [stderr] [2025-09-11 04:46:24.324738][INFO][test_async.rs:1140] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:24.511531][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 4.140827861s >>> [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_7 ... ok [INFO] [stderr] [2025-09-11 04:46:24.512041][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 3) enter <<< [INFO] [stderr] [2025-09-11 04:46:24.512130][INFO][test_async.rs:1140] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:24.596799][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 84.666188ms >>> [INFO] [stderr] [2025-09-11 04:46:24.597235][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 30) enter <<< [INFO] [stderr] [2025-09-11 04:46:24.597346][INFO][test_async.rs:1140] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:24.633619][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 314.630902ms >>> [INFO] [stderr] [2025-09-11 04:46:24.635625][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 2) enter <<< [INFO] [stderr] [2025-09-11 04:46:24.635755][INFO][test_async.rs:1140] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:24.712823][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 3.727262353s >>> [INFO] [stderr] [2025-09-11 04:46:24.713903][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 4) enter <<< [INFO] [stderr] [2025-09-11 04:46:24.714707][INFO][test_async.rs:1140] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:24.724238][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 88.482877ms >>> [INFO] [stderr] [2025-09-11 04:46:24.742655][INFO][test_async.rs:1120] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 50) enter <<< [INFO] [stderr] [2025-09-11 04:46:24.742800][INFO][test_async.rs:1140] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:24.749372][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 152.025627ms >>> [INFO] [stderr] [2025-09-11 04:46:24.750826][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 36.120515ms >>> [INFO] [stderr] [2025-09-11 04:46:24.751063][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:46:24.752799][INFO][test_async.rs:1188] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:24.763572][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:46:24.763792][INFO][test_async.rs:1188] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:24.765897][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 23.094727ms >>> [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_8 ... ok [INFO] [stderr] [2025-09-11 04:46:24.767443][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:46:24.767573][INFO][test_async.rs:1188] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:24.808137][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 483.398497ms >>> [INFO] [stderr] [2025-09-11 04:46:24.808586][INFO][test_async.rs:1168] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 3) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:24.808706][INFO][test_async.rs:1188] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:24.819931][INFO][test_async.rs:1120] >>> test_pressure_stream_multi return () in 495.572836ms >>> [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:24.820337][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:46:24.821622][INFO][test_async.rs:1188] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:26.350942][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 1.529318872s >>> [INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_5 ... ok [INFO] [stderr] [2025-09-11 04:46:26.352734][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:46:26.352850][INFO][test_async.rs:1188] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_17 ... ok [INFO] [stderr] [2025-09-11 04:46:26.512806][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 3.882355709s >>> [INFO] [stderr] [2025-09-11 04:46:26.513178][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:46:26.513287][INFO][test_async.rs:1188] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:26.729408][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 6.465323774s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_05 ... ok [INFO] [stderr] [2025-09-11 04:46:26.729784][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:46:26.729894][INFO][test_async.rs:1188] 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:46:27.629903][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 900.007176ms >>> [INFO] [stderr] [2025-09-11 04:46:27.630273][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:46:27.630568][INFO][test_async.rs:1006] >>> test_spurious_sink return () in 60ns >>> [INFO] [stdout] test tests::test_async::test_spurious_stream ... ok [INFO] [stderr] [2025-09-11 04:46:27.630715][INFO][test_async.rs:1047] <<< test_spurious_stream (setup_log = ()) enter <<< [INFO] [stderr] [2025-09-11 04:46:27.630789][INFO][test_async.rs:1047] >>> test_spurious_stream return () in 49ns >>> [INFO] [stderr] [2025-09-11 04:46:27.631752][INFO][test_async.rs:87] <<< test_sync () enter <<< [INFO] [stderr] [2025-09-11 04:46:27.631836][INFO][test_async.rs:91] 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:46:29.195744][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 4.428168558s >>> [INFO] [stderr] [2025-09-11 04:46:29.196313][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:46:29.196591][INFO][test_async_blocking.rs:107] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:29.826224][INFO][test_async_blocking.rs:78] >>> test_basic_1_tx_async_1_rx_blocking return () in 629.765966ms >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_1_tx_async_1_rx_blocking::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:29.855651][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:46:29.855816][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:46:30.487679][INFO][test_async_blocking.rs:78] >>> test_basic_1_tx_async_1_rx_blocking return () in 631.901605ms >>> [INFO] [stderr] [2025-09-11 04:46:30.488481][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:46:30.488669][INFO][test_async_blocking.rs:107] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:31.124468][INFO][test_async_blocking.rs:78] >>> test_basic_1_tx_async_1_rx_blocking return () in 635.842454ms >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_1_tx_async_1_rx_blocking::case_3 ... ok [INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_rx::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:31.124840][INFO][test_async_blocking.rs:14] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (AsyncTx, Rx)) enter <<< [INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_rx::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:31.124976][INFO][test_async_blocking.rs:14] >>> test_basic_bounded_empty_full_drop_rx return () in 9.46µs >>> [INFO] [stderr] [2025-09-11 04:46:31.125193][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:46:31.125286][INFO][test_async_blocking.rs:14] >>> test_basic_bounded_empty_full_drop_rx return () in 9.03µs >>> [INFO] [stderr] [2025-09-11 04:46:31.125642][INFO][test_async_blocking.rs:14] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MAsyncTx, MRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:31.125824][INFO][test_async_blocking.rs:14] >>> test_basic_bounded_empty_full_drop_rx return () in 13.5µs >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_rx::case_3 ... ok [INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_tx::case_1 ... ok [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:46:31.127749][INFO][test_async_blocking.rs:37] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (AsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:31.128185][INFO][test_async_blocking.rs:37] >>> test_basic_bounded_empty_full_drop_tx return () in 8.56µs >>> [INFO] [stderr] [2025-09-11 04:46:31.128419][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:46:31.129564][INFO][test_async_blocking.rs:37] >>> test_basic_bounded_empty_full_drop_tx return () in 10.31µs >>> [INFO] [stderr] [2025-09-11 04:46:31.131581][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:46:31.131700][INFO][test_async_blocking.rs:37] >>> test_basic_bounded_empty_full_drop_tx return () in 9.74µs >>> [INFO] [stderr] [2025-09-11 04:46:31.131978][INFO][test_async_blocking.rs:60] <<< test_basic_compile_bounded_empty_full () enter <<< [INFO] [stderr] [2025-09-11 04:46:31.132073][INFO][test_async_blocking.rs:60] >>> test_basic_compile_bounded_empty_full return () in 11.94µs >>> [INFO] [stderr] [2025-09-11 04:46:31.132465][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:46:31.132605][INFO][test_async_blocking.rs:218] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_09 ... ok [INFO] [stderr] [2025-09-11 04:46:31.552104][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 11.181936338s >>> [INFO] [stderr] [2025-09-11 04:46:31.552555][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:46:31.552717][INFO][test_async_blocking.rs:218] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:31.845197][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 712.632794ms >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:31.845662][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:46:31.845846][INFO][test_async_blocking.rs:218] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_14 ... ok [INFO] [stderr] [2025-09-11 04:46:31.958701][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 10.101290131s >>> [INFO] [stderr] [2025-09-11 04:46:31.963821][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:46:31.964023][INFO][test_async_blocking.rs:218] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:32.215589][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 662.912451ms >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:32.215908][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:46:32.216120][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:46:32.904815][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 688.785427ms >>> [INFO] [stderr] [2025-09-11 04:46:32.905150][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:46:32.905314][INFO][test_async_blocking.rs:218] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:32.986990][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 1.141186239s >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:32.987382][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:46:32.987607][INFO][test_async_blocking.rs:218] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:33.646128][INFO][test_async.rs:87] >>> test_sync return () in 6.014290302s >>> [INFO] [stdout] test tests::test_async::test_sync ... ok [INFO] [stderr] [2025-09-11 04:46:33.646836][INFO][test_async_blocking.rs:169] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 1000) enter <<< [INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_6 ... ok [INFO] [stderr] [2025-09-11 04:46:33.646973][INFO][test_async_blocking.rs:218] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:33.653907][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 748.646118ms >>> [INFO] [stderr] [2025-09-11 04:46:33.654663][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:46:33.654862][INFO][test_async_blocking.rs:285] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_18 ... ok [INFO] [stderr] [2025-09-11 04:46:33.746377][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 10.97885174s >>> [INFO] [stderr] [2025-09-11 04:46:33.746717][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:46:33.746927][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_7 ... ok [INFO] [stderr] [2025-09-11 04:46:33.954871][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 967.317605ms >>> [INFO] [stderr] [2025-09-11 04:46:33.955384][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:46:33.955612][INFO][test_async_blocking.rs:285] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:35.154489][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 1.198924771s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_03 ... ok [INFO] [stderr] [2025-09-11 04:46:35.159667][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:46:35.159882][INFO][test_async_blocking.rs:285] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:35.833737][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 11.069944286s >>> [INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:35.835680][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:46:35.835868][INFO][test_async_blocking.rs:285] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:35.913919][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 754.089288ms >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_04 ... ok [INFO] [stderr] [2025-09-11 04:46:35.914289][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:46:35.915578][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] [stderr] [2025-09-11 04:46:37.681219][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 11.167930011s >>> [INFO] [stderr] [2025-09-11 04:46:37.681590][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:46:37.681732][INFO][test_async_blocking.rs:285] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:38.031544][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 6.067572173s >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_4 ... ok [INFO] [stderr] [2025-09-11 04:46:38.032027][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:46:38.032191][INFO][test_async_blocking.rs:285] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:38.503754][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 4.75692494s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_02 ... ok [INFO] [stderr] [2025-09-11 04:46:38.504154][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:46:38.504344][INFO][test_async_blocking.rs:285] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:39.055452][INFO][test_async_blocking.rs:169] >>> test_basic_multi_tx_async_1_rx_blocking return () in 5.408518572s >>> [INFO] [stderr] [2025-09-11 04:46:39.078935][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:46:39.079148][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_8 ... 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:46:39.153976][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 1.121822362s >>> [INFO] [stderr] [2025-09-11 04:46:39.154340][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:46:39.154560][INFO][test_async_blocking.rs:285] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:39.276760][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 1.595065352s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_07 ... ok [INFO] [stderr] [2025-09-11 04:46:39.277133][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:46:39.277788][INFO][test_async_blocking.rs:285] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_4 ... ok [INFO] [stderr] [2025-09-11 04:46:39.578589][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 14.769881003s >>> [INFO] [stderr] [2025-09-11 04:46:39.580704][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:46:39.580849][INFO][test_async_blocking.rs:335] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:40.184668][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 907.420904ms >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_12 ... ok [INFO] [stderr] [2025-09-11 04:46:40.185086][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:46:40.185659][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:46:40.439985][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 859.175582ms >>> [INFO] [stderr] [2025-09-11 04:46:40.440339][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:46:40.440578][INFO][test_async_blocking.rs:335] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:40.585469][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 20.304663835s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_06 ... ok [INFO] [stderr] [2025-09-11 04:46:40.586623][INFO][test_async_blocking.rs:298] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 200) enter <<< [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:40.586766][INFO][test_async_blocking.rs:335] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:40.792313][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 607.108369ms >>> [INFO] [stderr] [2025-09-11 04:46:40.792722][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:46:40.792868][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:46:41.274304][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 481.471608ms >>> [INFO] [stderr] [2025-09-11 04:46:41.274671][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:46:41.274858][INFO][test_async_blocking.rs:335] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:42.062884][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 2.908434185s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_11 ... ok [INFO] [stderr] [2025-09-11 04:46:42.063297][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:46:42.064347][INFO][test_async_blocking.rs:335] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_06 ... ok [INFO] [stderr] [2025-09-11 04:46:42.241131][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 6.326724545s >>> [INFO] [stderr] [2025-09-11 04:46:42.241532][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:46:42.241665][INFO][test_async_blocking.rs:335] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:43.503389][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 1.439954334s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_7 ... ok [INFO] [stderr] [2025-09-11 04:46:43.503773][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] [stderr] [2025-09-11 04:46:43.504039][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:46:43.554359][INFO][test_async_blocking.rs:358] >>> test_pressure_multi_tx_async_multi_rx_blocking return () in 50.479363ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_15 ... ok [INFO] [stderr] [2025-09-11 04:46:43.554767][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:46:43.578744][INFO][test_async_blocking.rs:397] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:43.885760][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 21.498978457s >>> [INFO] [stderr] [2025-09-11 04:46:43.886107][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:46:44.004678][INFO][test_async_blocking.rs:397] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:44.458617][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 3.183810595s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_6 ... ok [INFO] [stderr] [2025-09-11 04:46:44.459740][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] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:44.616096][INFO][test_async_blocking.rs:397] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:44.626686][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 4.186227284s >>> [INFO] [stderr] [2025-09-11 04:46:44.628201][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:46:44.628355][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:46:44.956623][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 5.877545371s >>> [INFO] [stderr] [2025-09-11 04:46:44.957620][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:46:44.958760][INFO][test_async_blocking.rs:153] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:45.166139][INFO][test_async_blocking.rs:358] >>> test_pressure_multi_tx_async_multi_rx_blocking return () in 1.611248219s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_multi_rx_blocking::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:45.168483][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:46:45.168699][INFO][test_async_blocking.rs:153] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_multi_rx_blocking::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:45.505051][INFO][test_async_blocking.rs:358] >>> test_pressure_multi_tx_async_multi_rx_blocking return () in 1.618835698s >>> [INFO] [stderr] [2025-09-11 04:46:45.505439][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:46:45.505624][INFO][test_blocking_async.rs:124] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:45.718289][INFO][test_async_blocking.rs:120] >>> test_timeout_1_tx_async_1_rx_blocking return () in 1.089975077s >>> [INFO] [stdout] test tests::test_async_blocking::test_timeout_1_tx_async_1_rx_blocking::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:45.720204][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:46:45.720417][INFO][test_blocking_async.rs:124] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_timeout_1_tx_async_1_rx_blocking::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:45.809515][INFO][test_async_blocking.rs:120] >>> test_timeout_1_tx_async_1_rx_blocking return () in 851.771063ms >>> [INFO] [stderr] [2025-09-11 04:46:45.822678][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:46:45.822883][INFO][test_blocking_async.rs:124] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:46.304619][INFO][test_async_blocking.rs:120] >>> test_timeout_1_tx_async_1_rx_blocking return () in 1.135972441s >>> [INFO] [stdout] test tests::test_async_blocking::test_timeout_1_tx_async_1_rx_blocking::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:46.360885][INFO][test_blocking_async.rs:14] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:46.609650][INFO][test_blocking_async.rs:14] >>> test_basic_bounded_empty_full_drop_rx return () in 20.53µs >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_rx::case_1 ... ok [INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_rx::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:46.657636][INFO][test_blocking_async.rs:14] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:46.659160][INFO][test_blocking_async.rs:14] >>> test_basic_bounded_empty_full_drop_rx return () in 9.85µs >>> [INFO] [stderr] [2025-09-11 04:46:46.674622][INFO][test_blocking_async.rs:14] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:46.674789][INFO][test_blocking_async.rs:14] >>> test_basic_bounded_empty_full_drop_rx return () in 11.15µs >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_rx::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:46.747780][INFO][test_blocking_async.rs:99] >>> test_basic_1_tx_blocking_1_rx_async return () in 1.027472426s >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_1_tx_blocking_1_rx_async::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:46.794612][INFO][test_blocking_async.rs:37] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:46.806630][INFO][test_blocking_async.rs:37] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:46.806773][INFO][test_blocking_async.rs:37] >>> test_basic_bounded_empty_full_drop_tx return () in 12.729µs >>> [INFO] [stderr] [2025-09-11 04:46:46.831896][INFO][test_blocking_async.rs:99] >>> test_basic_1_tx_blocking_1_rx_async return () in 1.326310822s >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_tx::case_1 ... ok [INFO] [stdout] test tests::test_blocking_async::test_basic_1_tx_blocking_1_rx_async::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:46.833193][INFO][test_blocking_async.rs:81] <<< test_basic_compile_bounded_empty_full () enter <<< [INFO] [stderr] [2025-09-11 04:46:46.833462][INFO][test_blocking_async.rs:81] >>> test_basic_compile_bounded_empty_full return () in 19.62µs >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_compile_bounded_empty_full ... ok [INFO] [stderr] [2025-09-11 04:46:46.860614][INFO][test_blocking_async.rs:60] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:46.860757][INFO][test_blocking_async.rs:60] >>> test_basic_unbounded_empty_drop_tx return () in 12.44µs >>> [INFO] [stderr] [2025-09-11 04:46:46.865611][INFO][test_blocking_async.rs:37] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:46.865740][INFO][test_blocking_async.rs:37] >>> test_basic_bounded_empty_full_drop_tx return () in 10.58µs >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_unbounded_empty_drop_tx::case_1 ... ok [INFO] [stderr] [2025-09-11 04:46:46.907628][INFO][test_blocking_async.rs:60] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:46.915580][INFO][test_blocking_async.rs:37] >>> test_basic_bounded_empty_full_drop_tx return () in 13.72µs >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_tx::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:46.921682][INFO][test_blocking_async.rs:60] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-11 04:46:46.921830][INFO][test_blocking_async.rs:60] >>> test_basic_unbounded_empty_drop_tx return () in 15.53µs >>> [INFO] [stderr] [2025-09-11 04:46:46.930712][INFO][test_blocking_async.rs:60] >>> test_basic_unbounded_empty_drop_tx return () in 15.02µs >>> [INFO] [stderr] [2025-09-11 04:46:46.951922][INFO][test_blocking_async.rs:99] >>> test_basic_1_tx_blocking_1_rx_async return () in 1.129097912s >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_unbounded_empty_drop_tx::case_3 ... ok [INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_tx::case_2 ... ok [INFO] [stdout] test tests::test_blocking_async::test_basic_1_tx_blocking_1_rx_async::case_3 ... ok [INFO] [stderr] [2025-09-11 04:46:47.019637][INFO][test_blocking_async.rs:189] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_basic_unbounded_empty_drop_tx::case_2 ... ok [INFO] [stderr] [2025-09-11 04:46:47.019849][INFO][test_blocking_async.rs:218] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:47.024740][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:46:47.024903][INFO][test_blocking_async.rs:218] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:47.027671][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:46:47.027832][INFO][test_blocking_async.rs:218] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:47.027978][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:46:47.028115][INFO][test_blocking_async.rs:218] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:47.801233][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 23.048432946s >>> [INFO] [stderr] [2025-09-11 04:46:47.812653][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:46:47.812827][INFO][test_blocking_async.rs:218] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_1 ... ok [INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_6 ... ok [INFO] [stderr] [2025-09-11 04:46:48.834790][INFO][test_async.rs:1168] >>> test_pressure_stream_multi_idle return () in 22.48193836s >>> [INFO] [stderr] [2025-09-11 04:46:48.873756][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:46:48.873945][INFO][test_blocking_async.rs:218] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:50.515618][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 3.495825287s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_4 ... ok [INFO] [stderr] [2025-09-11 04:46:50.516377][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:46:50.516563][INFO][test_blocking_async.rs:218] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:52.070823][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 4.258030224s >>> [INFO] [stderr] [2025-09-11 04:46:52.094736][INFO][test_blocking_async.rs:189] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_5 ... ok [INFO] [stderr] [2025-09-11 04:46:52.094902][INFO][test_blocking_async.rs:218] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:53.258594][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 4.384694775s >>> [INFO] [stderr] [2025-09-11 04:46:53.273710][INFO][test_blocking_async.rs:189] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_6 ... ok [INFO] [stderr] [2025-09-11 04:46:53.275519][INFO][test_blocking_async.rs:218] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:53.555461][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 3.038935906s >>> [INFO] [stderr] [2025-09-11 04:46:53.573633][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_7 ... ok [INFO] [stderr] [2025-09-11 04:46:53.573919][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:53.856000][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 1.761138097s >>> [INFO] [stderr] [2025-09-11 04:46:53.886544][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 100) 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:46:53.936160][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_02 ... ok [INFO] [stderr] [2025-09-11 04:46:54.333096][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 34.066916159s >>> [INFO] [stderr] [2025-09-11 04:46:54.333581][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:46:54.370866][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:54.768513][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 1.194738102s >>> [INFO] [stderr] [2025-09-11 04:46:54.777283][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:46:54.777733][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:54.778451][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 1.504614585s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_9 ... ok [INFO] [stderr] [2025-09-11 04:46:54.780922][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:46:54.807072][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:55.608056][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 830.636076ms >>> [INFO] [stderr] [2025-09-11 04:46:55.630773][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_tx_multi_blocking_1_rx_async::case_04 ... ok [INFO] [stderr] [2025-09-11 04:46:55.729038][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:46:58.787080][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 16.545445467s >>> [INFO] [stderr] [2025-09-11 04:46:58.787726][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:46:58.788053][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:46:59.582254][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 794.381111ms >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_07 ... ok [INFO] [stderr] [2025-09-11 04:46:59.582641][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:46:59.596463][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:47:00.735678][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 5.95465739s >>> [INFO] [stderr] [2025-09-11 04:47:00.751820][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:47:00.842911][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:03.773707][INFO][test_async_blocking.rs:298] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 23.186973145s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_4 ... ok [INFO] [stderr] [2025-09-11 04:47:03.774092][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:47:03.774384][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_10 ... ok [INFO] [stderr] [2025-09-11 04:47:04.200914][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 426.690686ms >>> [INFO] [stderr] [2025-09-11 04:47:04.201441][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:47:04.222245][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:08.242242][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 8.659453216s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_08 ... ok [INFO] [stderr] [2025-09-11 04:47:08.269694][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:47:08.314462][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_11 ... ok [INFO] [stderr] [2025-09-11 04:47:08.875048][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 4.672694382s >>> [INFO] [stderr] [2025-09-11 04:47:08.875888][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:47:08.878908][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:08.923894][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 47.885563ms >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_13 ... ok [INFO] [stderr] [2025-09-11 04:47:08.924288][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:47:09.026800][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:09.891789][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 48.748643694s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_11 ... ok [INFO] [stderr] [2025-09-11 04:47:09.892158][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:47:10.124877][INFO][test_async_blocking.rs:358] >>> test_pressure_multi_tx_async_multi_rx_blocking return () in 25.665025355s >>> [INFO] [stderr] [2025-09-11 04:47:10.146973][INFO][test_blocking_async.rs:235] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 6) enter <<< [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_multi_rx_blocking::case_4 ... ok [INFO] [stderr] [2025-09-11 04:47:10.147423][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:10.422443][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 275.328909ms >>> [INFO] [stderr] [2025-09-11 04:47:10.436622][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:47:10.852143][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:11.052957][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:11.340972][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 2.416562939s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_14 ... ok [INFO] [stderr] [2025-09-11 04:47:11.352554][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:47:12.395706][INFO][test_blocking_async.rs:283] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:13.089068][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 17.45815516s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_06 ... ok [INFO] [stderr] [2025-09-11 04:47:13.089479][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:47:13.089747][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:47:13.168876][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 2.732110192s >>> [INFO] [stderr] [2025-09-11 04:47:13.169317][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:47:13.193312][INFO][test_blocking_async.rs:350] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:13.480458][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 390.851272ms >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_01 ... ok [INFO] [stderr] [2025-09-11 04:47:13.481161][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:47:13.585641][INFO][test_blocking_async.rs:350] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:13.832325][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 19.945641899s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_02 ... ok [INFO] [stderr] [2025-09-11 04:47:13.842598][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:47:13.870076][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:47:15.274279][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 1.431541786s >>> [INFO] [stderr] [2025-09-11 04:47:15.275320][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:47:15.314781][INFO][test_blocking_async.rs:350] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:15.628673][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 4.275970541s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_18 ... ok [INFO] [stderr] [2025-09-11 04:47:15.629121][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:47:15.704172][INFO][test_blocking_async.rs:350] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:16.566360][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 6.674097573s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_15 ... ok [INFO] [stderr] [2025-09-11 04:47:16.588025][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:47:16.589440][INFO][test_blocking_async.rs:350] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:17.712309][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 1.124063842s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_07 ... ok [INFO] [stderr] [2025-09-11 04:47:17.712878][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:47:17.779379][INFO][test_blocking_async.rs:350] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:18.514052][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 5.344606991s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_02 ... ok [INFO] [stderr] [2025-09-11 04:47:18.514477][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:47:18.611030][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] [stderr] [2025-09-11 04:47:24.927518][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 7.214500312s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_08 ... ok [INFO] [stderr] [2025-09-11 04:47:24.928480][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:47:24.935645][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_10 ... ok [INFO] [stderr] [2025-09-11 04:47:25.546091][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 617.476938ms >>> [INFO] [stderr] [2025-09-11 04:47:25.546530][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:47:25.548590][INFO][test_blocking_async.rs:350] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:25.629551][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 82.806908ms >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_11 ... ok [INFO] [stderr] [2025-09-11 04:47:25.630161][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:47:25.870817][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_12 ... ok [INFO] [stderr] [2025-09-11 04:47:26.771377][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 1.14112141s >>> [INFO] [stderr] [2025-09-11 04:47:26.826632][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:47:27.250187][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 11.97475732s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_05 ... ok [INFO] [stderr] [2025-09-11 04:47:27.436304][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:47:27.549651][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_14 ... ok [INFO] [stderr] [2025-09-11 04:47:27.699712][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 263.262031ms >>> [INFO] [stderr] [2025-09-11 04:47:27.700673][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:47:28.953332][INFO][test_blocking_async.rs:350] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:29.278126][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_15 ... ok [INFO] [stderr] [2025-09-11 04:47:29.639140][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 1.93836233s >>> [INFO] [stderr] [2025-09-11 04:47:29.676630][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:47:30.692769][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_13 ... ok [INFO] [stderr] [2025-09-11 04:47:30.851966][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 4.025184799s >>> [INFO] [stderr] [2025-09-11 04:47:30.857097][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:47:30.864096][INFO][test_blocking_async.rs:350] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:31.151393][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 294.169816ms >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_17 ... ok [INFO] [stderr] [2025-09-11 04:47:31.162852][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:47:31.263132][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_1 ... ok [INFO] [stderr] [2025-09-11 04:47:31.466578][INFO][test_blocking_async.rs:143] >>> test_timeout_1_tx_blocking_1_rx_async return () in 303.606215ms >>> [INFO] [stderr] [2025-09-11 04:47:31.468323][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:47:31.576780][INFO][test_blocking_async.rs:168] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:31.788771][INFO][test_blocking_async.rs:143] >>> test_timeout_1_tx_blocking_1_rx_async return () in 319.145322ms >>> [INFO] [stdout] test tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_2 ... ok [INFO] [stderr] [2025-09-11 04:47:31.789223][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:47:31.858276][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 2.181504583s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_16 ... ok [INFO] [stderr] [2025-09-11 04:47:31.858916][INFO][test_blocking_context.rs:157] <<< test_basic_bounded_1_thread (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:31.907069][INFO][test_blocking_async.rs:168] run with tokio multi thread [INFO] [stderr] [2025-09-11 04:47:32.156849][INFO][test_blocking_async.rs:143] >>> test_timeout_1_tx_blocking_1_rx_async return () in 367.510705ms >>> [INFO] [stdout] test tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_3 ... ok [INFO] [stderr] [2025-09-11 04:47:32.157239][INFO][test_blocking_context.rs:157] <<< test_basic_bounded_1_thread (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:32.464568][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 24.194712424s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_12 ... ok [INFO] [stderr] [2025-09-11 04:47:32.535641][INFO][test_blocking_context.rs:157] <<< test_basic_bounded_1_thread (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:32.865581][INFO][test_blocking_context.rs:157] >>> test_basic_bounded_1_thread return () in 1.00497355s >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_1_thread::case_1 ... ok [INFO] [stderr] [2025-09-11 04:47:32.905673][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:47:32.914717][INFO][test_blocking_context.rs:16] >>> test_basic_bounded_empty_full_drop_rx return () in 12.891µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_rx::case_1 ... ok [INFO] [stderr] [2025-09-11 04:47:32.919524][INFO][test_blocking_context.rs:16] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:32.919927][INFO][test_blocking_context.rs:16] >>> test_basic_bounded_empty_full_drop_rx return () in 8.89µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_rx::case_2 ... ok [INFO] [stderr] [2025-09-11 04:47:32.930399][INFO][test_blocking_context.rs:16] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:32.930554][INFO][test_blocking_context.rs:16] >>> test_basic_bounded_empty_full_drop_rx return () in 10.59µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_rx::case_3 ... ok [INFO] [stderr] [2025-09-11 04:47:32.936865][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:47:32.936983][INFO][test_blocking_context.rs:53] >>> test_basic_bounded_empty_full_drop_tx return () in 11.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:47:32.939230][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:47:32.941109][INFO][test_blocking_context.rs:53] >>> test_basic_bounded_empty_full_drop_tx return () in 9.45µs >>> [INFO] [stderr] [2025-09-11 04:47:32.941895][INFO][test_blocking_context.rs:53] <<< test_basic_bounded_empty_full_drop_tx (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:47:32.941993][INFO][test_blocking_context.rs:53] >>> test_basic_bounded_empty_full_drop_tx return () in 9.46µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_tx::case_3 ... ok [INFO] [stderr] [2025-09-11 04:47:32.942272][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_1 ... ok [INFO] [stderr] [2025-09-11 04:47:32.942356][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 9.569µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_2 ... ok [INFO] [stderr] [2025-09-11 04:47:32.942624][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_3 ... ok [INFO] [stderr] [2025-09-11 04:47:32.942706][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 9.7µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_4 ... ok [INFO] [stderr] [2025-09-11 04:47:32.942970][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_5 ... ok [INFO] [stderr] [2025-09-11 04:47:32.943044][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 8.03µs >>> [INFO] [stderr] [2025-09-11 04:47:32.943334][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:32.943412][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 11.65µs >>> [INFO] [stderr] [2025-09-11 04:47:32.943655][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:32.943739][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 8.94µs >>> [INFO] [stderr] [2025-09-11 04:47:32.943994][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:32.944066][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 8.5µs >>> [INFO] [stderr] [2025-09-11 04:47:32.944371][INFO][test_blocking_context.rs:203] <<< test_basic_unbounded_1_thread (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_6 ... ok [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_1_thread::case_2 ... ok [INFO] [stderr] [2025-09-11 04:47:33.157586][INFO][test_blocking_context.rs:157] >>> test_basic_bounded_1_thread return () in 1.00024747s >>> [INFO] [stderr] [2025-09-11 04:47:33.158295][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] [stderr] [2025-09-11 04:47:33.545794][INFO][test_blocking_context.rs:157] >>> test_basic_bounded_1_thread return () in 1.005267069s >>> [INFO] [stderr] [2025-09-11 04:47:33.547304][INFO][test_blocking_context.rs:203] <<< test_basic_unbounded_1_thread (setup_log = (), _channel = (MTx, MRx)) enter <<< [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] [stdout] test tests::test_blocking_context::test_basic_unbounded_1_thread::case_1 ... ok [INFO] [stderr] [2025-09-11 04:47:34.007418][INFO][test_blocking_context.rs:203] >>> test_basic_unbounded_1_thread return () in 1.062978951s >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_rx::case_1 ... ok [INFO] [stderr] [2025-09-11 04:47:34.007767][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_2 ... ok [INFO] [stderr] [2025-09-11 04:47:34.007967][INFO][test_blocking_context.rs:90] >>> test_basic_unbounded_empty_drop_rx return () in 14.39µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_rx::case_3 ... ok [INFO] [stderr] [2025-09-11 04:47:34.008256][INFO][test_blocking_context.rs:90] <<< test_basic_unbounded_empty_drop_rx (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_tx::case_1 ... ok [INFO] [stderr] [2025-09-11 04:47:34.008371][INFO][test_blocking_context.rs:90] >>> test_basic_unbounded_empty_drop_rx return () in 8.82µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_tx::case_2 ... ok [INFO] [stderr] [2025-09-11 04:47:34.008632][INFO][test_blocking_context.rs:90] <<< test_basic_unbounded_empty_drop_rx (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_tx::case_3 ... ok [INFO] [stderr] [2025-09-11 04:47:34.008764][INFO][test_blocking_context.rs:90] >>> test_basic_unbounded_empty_drop_rx return () in 8.53µs >>> [INFO] [stdout] test tests::test_blocking_context::test_conversion ... ok [INFO] [stderr] [2025-09-11 04:47:34.009141][INFO][test_blocking_context.rs:124] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:34.009292][INFO][test_blocking_context.rs:124] >>> test_basic_unbounded_empty_drop_tx return () in 10.54µs >>> [INFO] [stderr] [2025-09-11 04:47:34.009684][INFO][test_blocking_context.rs:124] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:34.009834][INFO][test_blocking_context.rs:124] >>> test_basic_unbounded_empty_drop_tx return () in 9.51µs >>> [INFO] [stderr] [2025-09-11 04:47:34.010163][INFO][test_blocking_context.rs:124] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:34.010312][INFO][test_blocking_context.rs:124] >>> test_basic_unbounded_empty_drop_tx return () in 8.97µs >>> [INFO] [stderr] [2025-09-11 04:47:34.010925][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:34.160810][INFO][test_blocking_context.rs:203] >>> test_basic_unbounded_1_thread return () in 1.00238281s >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_1_thread::case_2 ... ok [INFO] [stderr] [2025-09-11 04:47:34.161742][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:34.425782][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 20.94375591s >>> [INFO] [stderr] [2025-09-11 04:47:34.426259][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_03 ... ok [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_1_thread::case_3 ... ok [INFO] [stderr] [2025-09-11 04:47:34.570793][INFO][test_blocking_context.rs:203] >>> test_basic_unbounded_1_thread return () in 1.017235118s >>> [INFO] [stderr] [2025-09-11 04:47:34.653449][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:35.611756][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 19.982494133s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_06 ... ok [INFO] [stderr] [2025-09-11 04:47:35.615047][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, 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] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_04 ... ok [INFO] [stderr] [2025-09-11 04:47:36.277798][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 1.624094937s >>> [INFO] [stderr] [2025-09-11 04:47:36.278038][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_pressure_tx_multi_blocking_multi_rx_async::case_09 ... ok [INFO] [stderr] [2025-09-11 04:47:36.797238][INFO][test_blocking_async.rs:302] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 18.282631137s >>> [INFO] [stderr] [2025-09-11 04:47:36.801061][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_pressure_tx_multi_blocking_1_rx_async::case_03 ... ok [INFO] [stderr] [2025-09-11 04:47:37.738046][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 43.404350273s >>> [INFO] [stderr] [2025-09-11 04:47:37.739070][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_07 ... ok [INFO] [stderr] [2025-09-11 04:47:38.218665][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 1.417490618s >>> [INFO] [stderr] [2025-09-11 04:47:38.220411][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_03 ... ok [INFO] [stderr] [2025-09-11 04:47:38.484027][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 4.057628663s >>> [INFO] [stderr] [2025-09-11 04:47:38.484431][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_09 has been running for over 60 seconds [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_08 ... ok [INFO] [stderr] [2025-09-11 04:47:38.940002][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 1.196062581s >>> [INFO] [stderr] [2025-09-11 04:47:38.942684][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:39.115397][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 65.460616627s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_01 ... ok [INFO] [stderr] [2025-09-11 04:47:39.120649][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:39.928276][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 5.764712168s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_02 ... ok [INFO] [stderr] [2025-09-11 04:47:39.955292][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_blocking_context::test_pressure_bounded_blocking_1_1::case_12 ... ok [INFO] [stderr] [2025-09-11 04:47:40.055255][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 934.45448ms >>> [INFO] [stderr] [2025-09-11 04:47:40.060387][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:47:40.385488][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 1.442665985s >>> [INFO] [stderr] [2025-09-11 04:47:40.550334][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_1_1::case_11 ... ok [INFO] [stderr] [2025-09-11 04:47:40.782628][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 4.504494777s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_06 ... ok [INFO] [stderr] [2025-09-11 04:47:40.782975][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:47:41.336256][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 553.176877ms >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_04 ... ok [INFO] [stderr] [2025-09-11 04:47:41.427857][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 62.923561555s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_09 ... ok [INFO] [stderr] [2025-09-11 04:47:41.428528][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 3, rx_count = 10) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_03 ... ok [INFO] [stderr] [2025-09-11 04:47:41.498767][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 948.282239ms >>> [INFO] [stderr] [2025-09-11 04:47:41.499482][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 3, rx_count = 3) enter <<< [INFO] [stderr] [2025-09-11 04:47:41.550653][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 13, rx_count = 2) enter <<< [INFO] [stderr] [2025-09-11 04:47:41.719810][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 220.205318ms >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_07 ... ok [INFO] [stderr] [2025-09-11 04:47:41.720191][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:47:42.209466][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 780.821493ms >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_06 ... ok [INFO] [stderr] [2025-09-11 04:47:42.209776][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:47:42.479794][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 759.470656ms >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_08 ... ok [INFO] [stderr] [2025-09-11 04:47:42.480067][INFO][test_blocking_context.rs:403] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 5, rx_count = 5) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_09 ... ok [INFO] [stderr] [2025-09-11 04:47:42.695897][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 486.018708ms >>> [INFO] [stderr] [2025-09-11 04:47:42.697617][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 3) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_10 ... ok [INFO] [stderr] [2025-09-11 04:47:42.884816][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 404.649279ms >>> [INFO] [stderr] [2025-09-11 04:47:42.885161][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_01 ... ok [INFO] [stderr] [2025-09-11 04:47:43.349688][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 3.393578603s >>> [INFO] [stderr] [2025-09-11 04:47:43.349998][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_02 ... ok [INFO] [stderr] [2025-09-11 04:47:43.796342][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 3.734331942s >>> [INFO] [stderr] [2025-09-11 04:47:43.796724][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:47:44.127383][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 1.242111495s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_02 ... ok [INFO] [stderr] [2025-09-11 04:47:44.161819][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:47:44.530554][INFO][test_blocking_context.rs:403] >>> test_pressure_bounded_blocking_multi return () in 2.979752515s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_05 ... ok [INFO] [stderr] [2025-09-11 04:47:44.650534][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:47:45.258113][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 2.560353868s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_01 ... ok [INFO] [stderr] [2025-09-11 04:47:45.258479][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 12) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_10 ... ok [INFO] [stderr] [2025-09-11 04:47:45.314180][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 6.828566549s >>> [INFO] [stderr] [2025-09-11 04:47:45.314532][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 3) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_08 ... ok [INFO] [stderr] [2025-09-11 04:47:45.815106][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 500.464915ms >>> [INFO] [stderr] [2025-09-11 04:47:45.815393][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 9) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_06 ... ok [INFO] [stderr] [2025-09-11 04:47:45.971695][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 1.320925522s >>> [INFO] [stderr] [2025-09-11 04:47:45.972010][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:47:46.490432][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 59.462664464s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_2 ... ok [INFO] [stderr] [2025-09-11 04:47:46.492688][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_async::test_pressure_1_tx_blocking_1_rx_async::case_1 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] [stderr] [2025-09-11 04:47:47.320884][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 1.505360266s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_09 ... ok [INFO] [stderr] [2025-09-11 04:47:47.327344][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:47:47.560572][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 3.763732108s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_04 ... ok [INFO] [stderr] [2025-09-11 04:47:47.561979][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 15) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_07 ... ok [INFO] [stderr] [2025-09-11 04:47:48.143130][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 2.884518828s >>> [INFO] [stderr] [2025-09-11 04:47:48.143695][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:47:48.172309][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 61.144257691s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_3 ... ok [INFO] [stderr] [2025-09-11 04:47:48.173893][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 7) enter <<< [INFO] [stderr] [2025-09-11 04:47:48.427820][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 4.265865833s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_05 ... ok [INFO] [stderr] [2025-09-11 04:47:48.430672][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:47:48.514232][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 1.186011273s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_12 ... ok [INFO] [stderr] [2025-09-11 04:47:48.514524][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:47:48.529032][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 385.227002ms >>> [INFO] [stderr] [2025-09-11 04:47:48.529525][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 8) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_14 ... ok [INFO] [stderr] [2025-09-11 04:47:48.835161][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 320.539112ms >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_17 ... ok [INFO] [stderr] [2025-09-11 04:47:48.844875][INFO][test_blocking_context.rs:335] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 16) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_10 ... ok [INFO] [stderr] [2025-09-11 04:47:49.043790][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 3.071666221s >>> [INFO] [stderr] [2025-09-11 04:47:49.045010][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_15 ... ok [INFO] [stderr] [2025-09-11 04:47:49.272842][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 1.098821366s >>> [INFO] [stderr] [2025-09-11 04:47:49.273178][INFO][test_blocking_context.rs:479] <<< test_pressure_bounded_timeout_blocking (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stderr] [2025-09-11 04:47:49.368399][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 2.87560777s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_11 ... ok [INFO] [stderr] [2025-09-11 04:47:49.835935][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 6.48582276s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_03 ... ok [INFO] [stderr] [2025-09-11 04:47:49.889823][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 1.360002317s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_18 ... ok [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_05 ... ok [INFO] [stderr] [2025-09-11 04:47:50.137410][INFO][test_async_blocking.rs:242] >>> test_pressure_1_tx_async_1_rx_blocking return () in 74.301603265s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_19 ... ok [INFO] [stderr] [2025-09-11 04:47:50.147841][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 1.302812155s >>> [INFO] [stderr] [2025-09-11 04:47:51.220794][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 2.789772774s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_16 ... ok [INFO] [stderr] [2025-09-11 04:47:52.095554][INFO][test_blocking_context.rs:335] >>> test_pressure_bounded_blocking_multi_1 return () in 4.533453283s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_13 ... ok [INFO] [stderr] [2025-09-11 04:47:55.590306][INFO][test_blocking_async.rs:189] >>> test_pressure_1_tx_blocking_1_rx_async return () in 68.565439953s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_1 ... ok [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_09 ... ok [INFO] [stderr] [2025-09-11 04:47:57.053734][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 18.833190475s >>> [INFO] [stderr] [2025-09-11 04:47:57.486280][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 23.475199311s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_01 ... ok [INFO] [stderr] [2025-09-11 04:47:59.904647][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 24.276109202s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_05 ... ok [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_09 has been running for over 60 seconds [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_timeout_blocking::case_1 ... ok [INFO] [stderr] [2025-09-11 04:48:10.972138][INFO][test_blocking_context.rs:479] >>> test_pressure_bounded_timeout_blocking return () in 21.927024063s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_timeout_blocking::case_2 ... ok [INFO] [stderr] [2025-09-11 04:48:11.730254][INFO][test_blocking_context.rs:479] >>> test_pressure_bounded_timeout_blocking return () in 22.456934803s >>> [INFO] [stderr] [2025-09-11 04:48:16.994158][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 116.723497655s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_03 ... ok [INFO] [stderr] [2025-09-11 04:48:19.860900][INFO][test_async.rs:718] >>> test_pressure_bounded_async_multi_1 return () in 118.605680914s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_12 ... ok [INFO] [stderr] [2025-09-11 04:48:24.517189][INFO][test_blocking_async.rs:235] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 83.765244851s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_09 ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 280 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 125.36s [INFO] [stdout] [INFO] [stderr] Doc-tests crossfire [INFO] [stdout] [INFO] [stdout] running 13 tests [INFO] [stdout] test src/async_rx.rs - async_rx::AsyncRx (line 45) - compile fail ... ok [INFO] [stdout] test src/mpsc.rs - mpsc (line 25) - compile fail ... ok [INFO] [stdout] test src/blocking_rx.rs - blocking_rx::Rx (line 33) - compile fail ... ok [INFO] [stdout] test src/mpsc.rs - mpsc (line 10) ... ok [INFO] [stdout] test src/async_tx.rs - async_tx::AsyncTx (line 45) - compile fail ... ok [INFO] [stdout] test src/spsc.rs - spsc (line 25) - compile fail ... ok [INFO] [stdout] test src/blocking_tx.rs - blocking_tx::Tx (line 33) - compile fail ... ok [INFO] [stdout] test src/spsc.rs - spsc (line 10) ... ok [INFO] [stdout] test src/async_tx.rs - async_tx::AsyncTx (line 30) ... ok [INFO] [stdout] test src/blocking_tx.rs - blocking_tx::Tx (line 20) ... ok [INFO] [stdout] test src/async_rx.rs - async_rx::AsyncRx (line 30) ... ok [INFO] [stdout] test src/blocking_rx.rs - blocking_rx::Rx (line 20) ... ok [INFO] [stdout] test src/lib.rs - (line 139) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.08s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "afab1269152030cd7ebdfb5bef0bb80d8bc17bae1c56f394af856215ac6dc0da", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "afab1269152030cd7ebdfb5bef0bb80d8bc17bae1c56f394af856215ac6dc0da", kill_on_drop: false }` [INFO] [stdout] afab1269152030cd7ebdfb5bef0bb80d8bc17bae1c56f394af856215ac6dc0da