[INFO] fetching crate crossfire 2.0.25... [INFO] testing crossfire-2.0.25 against try#b83b707f97d809763b7861afa7638871f3339a33 for pr-145838-1 [INFO] extracting crate crossfire 2.0.25 into /workspace/builds/worker-5-tc2/source [INFO] started tweaking crates.io crate crossfire 2.0.25 [INFO] finished tweaking crates.io crate crossfire 2.0.25 [INFO] tweaked toml for crates.io crate crossfire 2.0.25 written to /workspace/builds/worker-5-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate crossfire 2.0.25 on toolchain b83b707f97d809763b7861afa7638871f3339a33 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+b83b707f97d809763b7861afa7638871f3339a33" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] crate crates.io crate crossfire 2.0.25 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" "+b83b707f97d809763b7861afa7638871f3339a33" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Blocking waiting for file lock on package cache [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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" "+b83b707f97d809763b7861afa7638871f3339a33" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 27caa175be65468dd912b457e413b36d636d6956d47cdceb2cf62d7e5c806874 [INFO] running `Command { std: "docker" "start" "-a" "27caa175be65468dd912b457e413b36d636d6956d47cdceb2cf62d7e5c806874", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "27caa175be65468dd912b457e413b36d636d6956d47cdceb2cf62d7e5c806874", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "27caa175be65468dd912b457e413b36d636d6956d47cdceb2cf62d7e5c806874", kill_on_drop: false }` [INFO] [stdout] 27caa175be65468dd912b457e413b36d636d6956d47cdceb2cf62d7e5c806874 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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" "+b83b707f97d809763b7861afa7638871f3339a33" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] c701136718b68bcf9da69a21d4cc2e27f06559021a44d22b08a440b37116c097 [INFO] running `Command { std: "docker" "start" "-a" "c701136718b68bcf9da69a21d4cc2e27f06559021a44d22b08a440b37116c097", kill_on_drop: false }` [INFO] [stderr] Compiling crossbeam-utils v0.8.21 [INFO] [stderr] Compiling futures-macro v0.3.31 [INFO] [stderr] Compiling once_cell v1.21.3 [INFO] [stderr] Compiling enum_dispatch v0.3.13 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling crossbeam-epoch v0.9.18 [INFO] [stderr] Compiling crossbeam-channel v0.5.15 [INFO] [stderr] Compiling crossbeam-queue v0.3.12 [INFO] [stderr] Compiling crossbeam-deque v0.8.6 [INFO] [stderr] Compiling crossbeam v0.8.4 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling crossfire v2.0.25 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 7.04s [INFO] running `Command { std: "docker" "inspect" "c701136718b68bcf9da69a21d4cc2e27f06559021a44d22b08a440b37116c097", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c701136718b68bcf9da69a21d4cc2e27f06559021a44d22b08a440b37116c097", kill_on_drop: false }` [INFO] [stdout] c701136718b68bcf9da69a21d4cc2e27f06559021a44d22b08a440b37116c097 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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" "+b83b707f97d809763b7861afa7638871f3339a33" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 3e1aa51f21ccb4e548a689ae45f6dd413ade517a057edef19264d1abaaaf886b [INFO] running `Command { std: "docker" "start" "-a" "3e1aa51f21ccb4e548a689ae45f6dd413ade517a057edef19264d1abaaaf886b", kill_on_drop: false }` [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling value-bag v1.11.1 [INFO] [stderr] Compiling nix v0.30.1 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling getrandom v0.3.3 [INFO] [stderr] Compiling syn v2.0.106 [INFO] [stderr] Compiling miniz_oxide v0.8.9 [INFO] [stderr] Compiling crc32fast v1.5.0 [INFO] [stderr] Compiling parking_lot v0.12.4 [INFO] [stderr] Compiling rstest_macros v0.26.1 [INFO] [stderr] Compiling regex-automata v0.4.10 [INFO] [stderr] Compiling half v2.6.0 [INFO] [stderr] Compiling signal-hook v0.3.18 [INFO] [stderr] Compiling ciborium-io v0.2.2 [INFO] [stderr] Compiling event-listener v5.4.1 [INFO] [stderr] Compiling signal-hook-registry v1.4.6 [INFO] [stderr] Compiling relative-path v1.9.3 [INFO] [stderr] Compiling rand_core v0.9.3 [INFO] [stderr] Compiling event-listener-strategy v0.5.4 [INFO] [stderr] Compiling bpaf v0.9.20 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling ciborium-ll v0.2.2 [INFO] [stderr] Compiling nanorand v0.7.0 [INFO] [stderr] Compiling log v0.4.27 [INFO] [stderr] Compiling rand_chacha v0.9.0 [INFO] [stderr] Compiling anes v0.2.1 [INFO] [stderr] Compiling exitcode v1.1.2 [INFO] [stderr] Compiling async-channel v2.5.0 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling flume v0.11.1 [INFO] [stderr] Compiling kanal v0.1.1 [INFO] [stderr] Compiling flate2 v1.1.2 [INFO] [stderr] Compiling backtrace v0.3.75 [INFO] [stderr] Compiling rand v0.9.2 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling chrono v0.4.41 [INFO] [stderr] Compiling fail v0.5.1 [INFO] [stderr] Compiling io-buffer v1.0.3 [INFO] [stderr] Compiling ring-file v0.1.2 [INFO] [stderr] Compiling file-rotate v0.8.0 [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 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 rstest v0.26.1 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling crossfire v2.0.24 [INFO] [stderr] Compiling crossfire v2.0.25 (/opt/rustwide/workdir) [INFO] [stderr] Compiling captains-log v0.8.6 [INFO] [stderr] Compiling ciborium v0.2.2 [INFO] [stderr] Compiling serde_json v1.0.143 [INFO] [stderr] Compiling criterion2 v3.0.2 [INFO] [stdout] warning: struct `SpuriousTx` is never constructed [INFO] [stdout] --> src/tests/test_async.rs:908:8 [INFO] [stdout] | [INFO] [stdout] 908 | 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:939:8 [INFO] [stdout] | [INFO] [stdout] 939 | struct SpuriousRx { [INFO] [stdout] | ^^^^^^^^^^ [INFO] [stdout] [INFO] [stdout] [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 50.25s [INFO] running `Command { std: "docker" "inspect" "3e1aa51f21ccb4e548a689ae45f6dd413ade517a057edef19264d1abaaaf886b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3e1aa51f21ccb4e548a689ae45f6dd413ade517a057edef19264d1abaaaf886b", kill_on_drop: false }` [INFO] [stdout] 3e1aa51f21ccb4e548a689ae45f6dd413ade517a057edef19264d1abaaaf886b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-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" "+b83b707f97d809763b7861afa7638871f3339a33" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 09fad370e9463ec444e39547d987648d39e0fc26cedd2b920aa895804390b2f9 [INFO] running `Command { std: "docker" "start" "-a" "09fad370e9463ec444e39547d987648d39e0fc26cedd2b920aa895804390b2f9", kill_on_drop: false }` [INFO] [stderr] warning: struct `SpuriousTx` is never constructed [INFO] [stderr] --> src/tests/test_async.rs:908:8 [INFO] [stderr] | [INFO] [stderr] 908 | 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:939:8 [INFO] [stderr] | [INFO] [stderr] 939 | 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.21s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/crossfire-d0afd11091c1f089) [INFO] [stdout] [INFO] [stdout] running 281 tests [INFO] [stderr] [2025-09-01 08:07:37.582001][INFO][test_async.rs:224] <<< test_basic_bounded_1_thread (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.582518][INFO][test_async.rs:22] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.581582][INFO][test_async.rs:224] <<< test_basic_bounded_1_thread (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.582666][INFO][test_async.rs:233] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.582681][INFO][test_async.rs:22] >>> test_basic_bounded_empty_full_drop_rx return () in 35µs >>> [INFO] [stderr] [2025-09-01 08:07:37.581792][INFO][test_async.rs:224] <<< test_basic_bounded_1_thread (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.582786][INFO][test_async.rs:233] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.582309][INFO][test_async.rs:22] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.583240][INFO][test_async.rs:22] >>> test_basic_bounded_empty_full_drop_rx return () in 13.87µs >>> [INFO] [stderr] [2025-09-01 08:07:37.583407][INFO][test_async.rs:233] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.583375][INFO][test_async.rs:47] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.583464][INFO][test_async.rs:47] >>> test_basic_bounded_empty_full_drop_tx return () in 8.38µs >>> [INFO] [stderr] [2025-09-01 08:07:37.583438][INFO][test_async.rs:350] <<< test_basic_bounded_recv_after_sender_close (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.583543][INFO][test_async.rs:365] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.583582][INFO][test_async.rs:47] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.583664][INFO][test_async.rs:47] >>> test_basic_bounded_empty_full_drop_tx return () in 8.33µs >>> [INFO] [stdout] test collections::tests::test_weak_cell ... ok [INFO] [stderr] [2025-09-01 08:07:37.584321][INFO][test_async.rs:350] <<< test_basic_bounded_recv_after_sender_close (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_rx::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:37.584409][INFO][test_async.rs:365] 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-01 08:07:37.584741][INFO][test_async.rs:47] <<< test_basic_bounded_empty_full_drop_tx (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-01 08:07:37.584844][INFO][test_async.rs:47] >>> test_basic_bounded_empty_full_drop_tx return () in 14.05µs >>> [INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_tx::case_3 ... ok [INFO] [stderr] [2025-09-01 08:07:37.585097][INFO][test_async.rs:174] <<< test_basic_bounded_rx_drop (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stdout] test collections::tests::test_locked_queue ... ok [INFO] [stderr] [2025-09-01 08:07:37.585194][INFO][test_async.rs:182] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_tx::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:37.585157][INFO][test_async.rs:72] <<< test_basic_compile_bounded_empty_full () enter <<< [INFO] [stdout] test tests::test_async::test_basic_compile_bounded_empty_full ... ok [INFO] [stderr] [2025-09-01 08:07:37.585255][INFO][test_async.rs:72] >>> test_basic_compile_bounded_empty_full return () in 18.52µs >>> [INFO] [stderr] [2025-09-01 08:07:37.585764][INFO][test_async.rs:1051] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.585862][INFO][test_async.rs:1062] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.586468][INFO][test_async.rs:1051] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.586559][INFO][test_async.rs:1062] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.595251][INFO][test_async.rs:350] <<< test_basic_bounded_recv_after_sender_close (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.595379][INFO][test_async.rs:1051] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.595477][INFO][test_async.rs:1062] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.595513][INFO][test_async.rs:365] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.597425][INFO][test_async.rs:1051] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stdout] test locked_waker::tests::test_waker ... ok [INFO] [stderr] [2025-09-01 08:07:37.597697][INFO][test_async.rs:1051] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.597787][INFO][test_async.rs:1062] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.598488][INFO][test_async.rs:1062] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.602212][INFO][test_async.rs:22] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.602420][INFO][test_async.rs:1051] <<< test_basic_into_stream_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_bounded_empty_full_drop_rx::case_3 ... ok [INFO] [stderr] [2025-09-01 08:07:37.602528][INFO][test_async.rs:1062] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.602948][INFO][test_async.rs:22] >>> test_basic_bounded_empty_full_drop_rx return () in 17.75µs >>> [INFO] [stderr] [2025-09-01 08:07:37.603594][INFO][test_async.rs:480] <<< test_basic_send_timeout_async (setup_log = (), _channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.603780][INFO][test_async.rs:480] >>> test_basic_send_timeout_async return () in 1.1µs >>> [INFO] [stdout] test tests::test_async::test_basic_send_timeout_async::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:37.604094][INFO][test_async.rs:480] <<< test_basic_send_timeout_async (setup_log = (), _channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.604192][INFO][test_async.rs:480] >>> test_basic_send_timeout_async return () in 810ns >>> [INFO] [stdout] test tests::test_async::test_basic_send_timeout_async::case_2 ... ok [INFO] [stderr] [2025-09-01 08:07:37.661103][INFO][test_async.rs:480] <<< test_basic_send_timeout_async (setup_log = (), _channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.661285][INFO][test_async.rs:480] >>> test_basic_send_timeout_async return () in 1.369µs >>> [INFO] [stderr] [2025-09-01 08:07:37.662004][INFO][test_async.rs:412] <<< test_basic_timeout_recv_async_waker (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.662130][INFO][test_async.rs:412] >>> test_basic_timeout_recv_async_waker return () in 1.3µs >>> [INFO] [stderr] [2025-09-01 08:07:37.662314][INFO][test_async.rs:174] <<< test_basic_bounded_rx_drop (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.662403][INFO][test_async.rs:182] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.665375][INFO][test_async.rs:350] >>> test_basic_bounded_recv_after_sender_close return () in 80.972112ms >>> [INFO] [stderr] [2025-09-01 08:07:37.666255][INFO][test_async.rs:412] <<< test_basic_timeout_recv_async_waker (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.666338][INFO][test_async.rs:412] >>> test_basic_timeout_recv_async_waker return () in 1.13µs >>> [INFO] [stderr] [2025-09-01 08:07:37.666582][INFO][test_async.rs:269] <<< test_basic_unbounded_1_thread (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.666652][INFO][test_async.rs:280] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_send_timeout_async::case_3 ... ok [INFO] [stdout] test tests::test_async::test_basic_timeout_recv_async_waker::case_1 ... ok [INFO] [stdout] test tests::test_async::test_basic_bounded_recv_after_sender_close::case_2 ... ok [INFO] [stdout] test tests::test_async::test_basic_timeout_recv_async_waker::case_3 ... ok [INFO] [stderr] [2025-09-01 08:07:37.669296][INFO][test_async.rs:174] <<< test_basic_bounded_rx_drop (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.669394][INFO][test_async.rs:182] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.673660][INFO][test_async.rs:1051] >>> test_basic_into_stream_1_1 return () in 87.797211ms >>> [INFO] [stderr] [2025-09-01 08:07:37.674494][INFO][test_async.rs:412] <<< test_basic_timeout_recv_async_waker (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.674592][INFO][test_async.rs:412] >>> test_basic_timeout_recv_async_waker return () in 720ns >>> [INFO] [stderr] [2025-09-01 08:07:37.802668][INFO][test_async.rs:191] try to send after rx dropped [INFO] [stderr] [2025-09-01 08:07:37.802773][INFO][test_async.rs:194] dropped tx [INFO] [stderr] [2025-09-01 08:07:37.805265][INFO][test_async.rs:269] <<< test_basic_unbounded_1_thread (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.805387][INFO][test_async.rs:280] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.806286][INFO][test_async.rs:269] <<< test_basic_unbounded_1_thread (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.806367][INFO][test_async.rs:280] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.806699][INFO][test_async.rs:1051] >>> test_basic_into_stream_1_1 return () in 211.219149ms >>> [INFO] [stderr] [2025-09-01 08:07:37.807496][INFO][test_async.rs:1051] >>> test_basic_into_stream_1_1 return () in 209.710029ms >>> [INFO] [stderr] [2025-09-01 08:07:37.808141][INFO][test_async.rs:350] >>> test_basic_bounded_recv_after_sender_close return () in 224.612288ms >>> [INFO] [stderr] [2025-09-01 08:07:37.808425][INFO][test_async.rs:174] >>> test_basic_bounded_rx_drop return () in 146.024265ms >>> [INFO] [stderr] [2025-09-01 08:07:37.812376][INFO][test_async.rs:313] <<< test_basic_unbounded_idle_select (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.812468][INFO][test_async.rs:325] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.813014][INFO][test_async.rs:313] <<< test_basic_unbounded_idle_select (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.813555][INFO][test_async.rs:325] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.815449][INFO][test_async.rs:313] <<< test_basic_unbounded_idle_select (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.815537][INFO][test_async.rs:325] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_3 ... ok [INFO] [stdout] test tests::test_async::test_basic_timeout_recv_async_waker::case_2 ... ok [INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_5 ... ok [INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_6 ... ok [INFO] [stdout] test tests::test_async::test_basic_bounded_recv_after_sender_close::case_1 ... ok [INFO] [stdout] test tests::test_async::test_basic_bounded_rx_drop::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:37.816771][INFO][test_async.rs:382] <<< test_basic_unbounded_recv_after_sender_close (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.816935][INFO][test_async.rs:1051] >>> test_basic_into_stream_1_1 return () in 230.375997ms >>> [INFO] [stderr] [2025-09-01 08:07:37.817059][INFO][test_async.rs:396] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.817426][INFO][test_async.rs:382] <<< test_basic_unbounded_recv_after_sender_close (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_4 ... ok [INFO] [stderr] [2025-09-01 08:07:37.817619][INFO][test_async.rs:396] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.818182][INFO][test_async.rs:191] try to send after rx dropped [INFO] [stderr] [2025-09-01 08:07:37.818230][INFO][test_async.rs:194] dropped tx [INFO] [stderr] [2025-09-01 08:07:37.820393][INFO][test_async.rs:1051] >>> test_basic_into_stream_1_1 return () in 221.906458ms >>> [INFO] [stderr] [2025-09-01 08:07:37.828096][INFO][test_async.rs:1051] >>> test_basic_into_stream_1_1 return () in 225.564357ms >>> [INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_2 ... ok [INFO] [stdout] test tests::test_async::test_basic_into_stream_1_1::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:37.828583][INFO][test_async.rs:382] <<< test_basic_unbounded_recv_after_sender_close (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_bounded_recv_after_sender_close::case_3 ... ok [INFO] [stderr] [2025-09-01 08:07:37.828887][INFO][test_async.rs:396] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_bounded_rx_drop::case_2 ... ok [INFO] [stderr] [2025-09-01 08:07:37.829650][INFO][test_async.rs:350] >>> test_basic_bounded_recv_after_sender_close return () in 234.185927ms >>> [INFO] [stderr] [2025-09-01 08:07:37.830507][INFO][test_async.rs:174] >>> test_basic_bounded_rx_drop return () in 245.313326ms >>> [INFO] [stderr] [2025-09-01 08:07:37.832106][INFO][test_async.rs:191] try to send after rx dropped [INFO] [stderr] [2025-09-01 08:07:37.832142][INFO][test_async.rs:194] dropped tx [INFO] [stderr] [2025-09-01 08:07:37.832858][INFO][test_async.rs:446] <<< test_basic_unbounded_recv_timeout_async (setup_log = (), _channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.833001][INFO][test_async.rs:446] >>> test_basic_unbounded_recv_timeout_async return () in 1.9µs >>> [INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_timeout_async::case_2 ... ok [INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_timeout_async::case_3 ... ok [INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_timeout_async::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:37.835224][INFO][test_async.rs:446] <<< test_basic_unbounded_recv_timeout_async (setup_log = (), _channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.835350][INFO][test_async.rs:446] >>> test_basic_unbounded_recv_timeout_async return () in 1.591µs >>> [INFO] [stderr] [2025-09-01 08:07:37.835486][INFO][test_async.rs:446] <<< test_basic_unbounded_recv_timeout_async (setup_log = (), _channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.835573][INFO][test_async.rs:446] >>> test_basic_unbounded_recv_timeout_async return () in 960ns >>> [INFO] [stderr] [2025-09-01 08:07:37.846501][INFO][test_async.rs:382] >>> test_basic_unbounded_recv_after_sender_close return () in 28.992647ms >>> [INFO] [stderr] [2025-09-01 08:07:37.848075][INFO][test_async.rs:382] >>> test_basic_unbounded_recv_after_sender_close return () in 19.342118ms >>> [INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_after_sender_close::case_2 ... ok [INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_after_sender_close::case_3 ... ok [INFO] [stdout] test tests::test_async::test_basic_bounded_rx_drop::case_3 ... ok [INFO] [stdout] test tests::test_async::test_basic_unbounded_recv_after_sender_close::case_1 ... ok [INFO] [stdout] test tests::test_async::test_conversion ... ok [INFO] [stderr] [2025-09-01 08:07:37.848250][INFO][test_async.rs:199] <<< test_basic_unbounded_rx_drop (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.848273][INFO][test_async.rs:199] <<< test_basic_unbounded_rx_drop (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.848386][INFO][test_async.rs:207] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.848386][INFO][test_async.rs:207] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.849041][INFO][test_async.rs:174] >>> test_basic_bounded_rx_drop return () in 179.648182ms >>> [INFO] [stderr] [2025-09-01 08:07:37.849561][INFO][test_async.rs:382] >>> test_basic_unbounded_recv_after_sender_close return () in 32.633357ms >>> [INFO] [stderr] [2025-09-01 08:07:37.849703][INFO][test_async.rs:199] <<< test_basic_unbounded_rx_drop (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.849796][INFO][test_async.rs:207] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.850661][INFO][test_async.rs:216] try to send after rx dropped [INFO] [stderr] [2025-09-01 08:07:37.850692][INFO][test_async.rs:219] dropped tx [INFO] [stderr] [2025-09-01 08:07:37.852415][INFO][test_async.rs:659] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.852510][INFO][test_async.rs:680] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.852516][INFO][test_async.rs:659] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.852590][INFO][test_async.rs:680] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.853302][INFO][test_async.rs:659] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.853393][INFO][test_async.rs:680] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.854426][INFO][test_async.rs:659] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (AsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.854521][INFO][test_async.rs:680] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.856243][INFO][test_async.rs:199] >>> test_basic_unbounded_rx_drop return () in 7.855059ms >>> [INFO] [stderr] [2025-09-01 08:07:37.856297][INFO][test_async.rs:216] try to send after rx dropped [INFO] [stderr] [2025-09-01 08:07:37.856454][INFO][test_async.rs:219] dropped tx [INFO] [stderr] [2025-09-01 08:07:37.856590][INFO][test_async.rs:216] try to send after rx dropped [INFO] [stderr] [2025-09-01 08:07:37.856622][INFO][test_async.rs:219] dropped tx [INFO] [stdout] test tests::test_async::test_basic_unbounded_rx_drop::case_3 ... ok [INFO] [stderr] [2025-09-01 08:07:37.858857][INFO][test_async.rs:659] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.858957][INFO][test_async.rs:680] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.860657][INFO][test_async.rs:199] >>> test_basic_unbounded_rx_drop return () in 12.273739ms >>> [INFO] [stderr] [2025-09-01 08:07:37.862444][INFO][test_async.rs:199] >>> test_basic_unbounded_rx_drop return () in 12.648239ms >>> [INFO] [stdout] test tests::test_async::test_basic_unbounded_rx_drop::case_2 ... ok [INFO] [stderr] [2025-09-01 08:07:37.862687][INFO][test_async.rs:659] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_basic_unbounded_rx_drop::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:37.862774][INFO][test_async.rs:680] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_03 ... ok [INFO] [stderr] [2025-09-01 08:07:37.863870][INFO][test_async.rs:659] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_07 ... ok [INFO] [stderr] [2025-09-01 08:07:37.863951][INFO][test_async.rs:680] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_04 ... ok [INFO] [stderr] [2025-09-01 08:07:37.871525][INFO][test_async.rs:659] >>> test_pressure_bounded_async_1_1 return () in 17.000769ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_08 ... ok [INFO] [stderr] [2025-09-01 08:07:37.871962][INFO][test_async.rs:659] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.872105][INFO][test_async.rs:680] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.882451][INFO][test_async.rs:659] >>> test_pressure_bounded_async_1_1 return () in 18.496279ms >>> [INFO] [stderr] [2025-09-01 08:07:37.882915][INFO][test_async.rs:659] >>> test_pressure_bounded_async_1_1 return () in 29.522467ms >>> [INFO] [stderr] [2025-09-01 08:07:37.883273][INFO][test_async.rs:659] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.883409][INFO][test_async.rs:680] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.884402][INFO][test_async.rs:659] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.884486][INFO][test_async.rs:680] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.887732][INFO][test_async.rs:659] >>> test_pressure_bounded_async_1_1 return () in 15.626628ms >>> [INFO] [stderr] [2025-09-01 08:07:37.888065][INFO][test_async.rs:659] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.888203][INFO][test_async.rs:680] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.910109][INFO][test_async.rs:659] >>> test_pressure_bounded_async_1_1 return () in 21.905808ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_11 ... ok [INFO] [stderr] [2025-09-01 08:07:37.919442][INFO][test_async.rs:659] <<< test_pressure_bounded_async_1_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.919635][INFO][test_async.rs:680] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.936356][INFO][test_async.rs:659] >>> test_pressure_bounded_async_1_1 return () in 16.721759ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_12 ... ok [INFO] [stderr] [2025-09-01 08:07:37.936765][INFO][test_async.rs:765] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100, rx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.936893][INFO][test_async.rs:783] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:37.949234][INFO][test_async.rs:659] >>> test_pressure_bounded_async_1_1 return () in 96.64171ms >>> [INFO] [stderr] [2025-09-01 08:07:37.949784][INFO][test_async.rs:765] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 10, rx_count = 100) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_02 ... ok [INFO] [stderr] [2025-09-01 08:07:37.949918][INFO][test_async.rs:783] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_06 ... ok [INFO] [stderr] [2025-09-01 08:07:37.952364][INFO][test_async.rs:659] >>> test_pressure_bounded_async_1_1 return () in 89.590931ms >>> [INFO] [stderr] [2025-09-01 08:07:37.952915][INFO][test_async.rs:765] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 300, rx_count = 300) enter <<< [INFO] [stderr] [2025-09-01 08:07:37.953109][INFO][test_async.rs:783] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_10 ... ok [INFO] [stderr] [2025-09-01 08:07:38.141056][INFO][test_async.rs:659] >>> test_pressure_bounded_async_1_1 return () in 256.565215ms >>> [INFO] [stderr] [2025-09-01 08:07:38.141445][INFO][test_async.rs:765] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 10, rx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:07:38.141585][INFO][test_async.rs:783] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:38.274685][INFO][test_async.rs:659] >>> test_pressure_bounded_async_1_1 return () in 415.723839ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_05 ... ok [INFO] [stderr] [2025-09-01 08:07:38.276527][INFO][test_async.rs:659] >>> test_pressure_bounded_async_1_1 return () in 424.014058ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_01 ... ok [INFO] [stderr] [2025-09-01 08:07:38.277364][INFO][test_async.rs:765] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100, rx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:07:38.277506][INFO][test_async.rs:783] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_04 ... ok [INFO] [stderr] [2025-09-01 08:07:38.292250][INFO][test_async.rs:765] >>> test_pressure_bounded_async_multi return () in 150.664195ms >>> [INFO] [stderr] [2025-09-01 08:07:38.295144][INFO][test_async.rs:765] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 300, rx_count = 300) enter <<< [INFO] [stderr] [2025-09-01 08:07:38.295255][INFO][test_async.rs:783] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:38.316224][INFO][test_async.rs:765] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 10, rx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:07:38.318524][INFO][test_async.rs:783] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:38.584691][INFO][test_async.rs:659] >>> test_pressure_bounded_async_1_1 return () in 701.27978ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_1_1::case_09 ... ok [INFO] [stderr] [2025-09-01 08:07:38.588139][INFO][test_async.rs:765] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 10, rx_count = 10) enter <<< [INFO] [stdout] test tests::test_async::test_basic_bounded_1_thread::case_2 ... ok [INFO] [stderr] [2025-09-01 08:07:38.588329][INFO][test_async.rs:783] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:38.626825][INFO][test_async.rs:224] >>> test_basic_bounded_1_thread return () in 1.044039147s >>> [INFO] [stderr] [2025-09-01 08:07:38.634225][INFO][test_async.rs:765] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100, rx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:07:38.634358][INFO][test_async.rs:783] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:38.645174][INFO][test_async.rs:765] >>> test_pressure_bounded_async_multi return () in 56.849614ms >>> [INFO] [stderr] [2025-09-01 08:07:38.647942][INFO][test_async.rs:765] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 10, rx_count = 100) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_08 ... ok [INFO] [stderr] [2025-09-01 08:07:38.648167][INFO][test_async.rs:783] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:38.801389][INFO][test_async.rs:765] >>> test_pressure_bounded_async_multi return () in 482.864912ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_06 ... ok [INFO] [stderr] [2025-09-01 08:07:38.802104][INFO][test_async.rs:765] <<< test_pressure_bounded_async_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 300, rx_count = 300) enter <<< [INFO] [stderr] [2025-09-01 08:07:38.802342][INFO][test_async.rs:783] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:38.826197][INFO][test_async.rs:224] >>> test_basic_bounded_1_thread return () in 1.243545298s >>> [INFO] [stdout] test tests::test_async::test_basic_bounded_1_thread::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:38.842316][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:07:38.842504][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:38.850489][INFO][test_async.rs:269] >>> test_basic_unbounded_1_thread return () in 1.044117357s >>> [INFO] [stdout] test tests::test_async::test_basic_unbounded_1_thread::case_3 ... ok [INFO] [stderr] [2025-09-01 08:07:38.850914][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:07:38.851051][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:38.855014][INFO][test_async.rs:269] >>> test_basic_unbounded_1_thread return () in 1.049630987s >>> [INFO] [stdout] test tests::test_async::test_basic_unbounded_1_thread::case_2 ... ok [INFO] [stderr] [2025-09-01 08:07:38.855626][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 300) enter <<< [INFO] [stderr] [2025-09-01 08:07:38.855739][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:38.857144][INFO][test_async.rs:224] >>> test_basic_bounded_1_thread return () in 1.273741025s >>> [INFO] [stdout] test tests::test_async::test_basic_bounded_1_thread::case_3 ... ok [INFO] [stderr] [2025-09-01 08:07:38.865407][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:07:38.865520][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:38.873399][INFO][test_async.rs:765] >>> test_pressure_bounded_async_multi return () in 225.234398ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_10 ... ok [INFO] [stderr] [2025-09-01 08:07:38.900201][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:07:38.900357][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:39.036870][INFO][test_async.rs:765] >>> test_pressure_bounded_async_multi return () in 1.086951623s >>> [INFO] [stderr] [2025-09-01 08:07:39.044572][INFO][test_async.rs:269] >>> test_basic_unbounded_1_thread return () in 1.377920234s >>> [INFO] [stderr] [2025-09-01 08:07:39.218228][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 300) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_02 ... ok [INFO] [stderr] [2025-09-01 08:07:39.220063][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_basic_unbounded_1_thread::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:39.220210][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:07:39.220324][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_09 ... ok [INFO] [stderr] [2025-09-01 08:07:39.340985][INFO][test_async.rs:765] >>> test_pressure_bounded_async_multi return () in 706.62452ms >>> [INFO] [stderr] [2025-09-01 08:07:39.341467][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:07:39.341668][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:39.979428][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 759.098875ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_07 ... ok [INFO] [stderr] [2025-09-01 08:07:39.985221][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, AsyncRx), tx_count = 300) enter <<< [INFO] [stderr] [2025-09-01 08:07:39.985391][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:40.246749][INFO][test_async.rs:346] waker rx 0 [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_05 ... ok [INFO] [stderr] [2025-09-01 08:07:40.269688][INFO][test_async.rs:765] >>> test_pressure_bounded_async_multi return () in 1.992181993s >>> [INFO] [stdout] test tests::test_async::test_basic_unbounded_idle_select::case_2 ... ok [INFO] [stderr] [2025-09-01 08:07:40.271673][INFO][test_async.rs:313] >>> test_basic_unbounded_idle_select return () in 2.459206227s >>> [INFO] [stderr] [2025-09-01 08:07:40.336226][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:07:40.336394][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:40.359233][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:07:40.359415][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:40.374672][INFO][test_async.rs:346] waker rx 1 [INFO] [stderr] [2025-09-01 08:07:40.376943][INFO][test_async.rs:313] >>> test_basic_unbounded_idle_select return () in 2.563388057s >>> [INFO] [stdout] test tests::test_async::test_basic_unbounded_idle_select::case_3 ... ok [INFO] [stderr] [2025-09-01 08:07:40.377450][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 300) enter <<< [INFO] [stderr] [2025-09-01 08:07:40.377571][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:40.545138][INFO][test_async.rs:765] >>> test_pressure_bounded_async_multi return () in 2.608243463s >>> [INFO] [stderr] [2025-09-01 08:07:40.549321][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 10) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_01 ... ok [INFO] [stderr] [2025-09-01 08:07:40.549454][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:40.742274][INFO][test_async.rs:346] waker rx 0 [INFO] [stdout] test tests::test_async::test_basic_unbounded_idle_select::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:40.765467][INFO][test_async.rs:313] >>> test_basic_unbounded_idle_select return () in 2.949926329s >>> [INFO] [stderr] [2025-09-01 08:07:40.766725][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:07:40.766880][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:41.878904][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 3.013381692s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_04 ... ok [INFO] [stderr] [2025-09-01 08:07:41.882935][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 300) enter <<< [INFO] [stderr] [2025-09-01 08:07:41.883061][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_11 ... ok [INFO] [stderr] [2025-09-01 08:07:42.016293][INFO][test_async.rs:765] >>> test_pressure_bounded_async_multi return () in 3.213951573s >>> [INFO] [stderr] [2025-09-01 08:07:42.016877][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:07:42.034131][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_03 ... ok [INFO] [stderr] [2025-09-01 08:07:42.271639][INFO][test_async.rs:765] >>> test_pressure_bounded_async_multi return () in 4.318530314s >>> [INFO] [stderr] [2025-09-01 08:07:42.272065][INFO][test_async.rs:705] <<< test_pressure_bounded_async_multi_1 (setup_log = (), channel = (MAsyncTx, MAsyncRx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:07:42.272228][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:42.425340][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 391.215471ms >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_16 ... ok [INFO] [stderr] [2025-09-01 08:07:42.428199][INFO][test_async.rs:705] <<< 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-01 08:07:42.438110][INFO][test_async.rs:730] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_13 ... ok [INFO] [stderr] [2025-09-01 08:07:42.445005][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 3.602504545s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_mixed_async_blocking_conversion::case_2 ... ok [INFO] [stderr] [2025-09-01 08:07:42.450277][INFO][test_async.rs:825] <<< 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_3 ... ok [INFO] [stderr] [2025-09-01 08:07:42.450495][INFO][test_async.rs:834] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:42.479232][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 1.929777869s >>> [INFO] [stderr] [2025-09-01 08:07:42.479672][INFO][test_async.rs:825] <<< test_pressure_bounded_mixed_async_blocking_conversion (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:42.479803][INFO][test_async.rs:834] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:42.524395][INFO][test_async.rs:825] >>> test_pressure_bounded_mixed_async_blocking_conversion return () in 44.590806ms >>> [INFO] [stderr] [2025-09-01 08:07:42.524891][INFO][test_async.rs:825] <<< test_pressure_bounded_mixed_async_blocking_conversion (setup_log = (), channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:42.525096][INFO][test_async.rs:834] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:42.554433][INFO][test_async.rs:825] >>> test_pressure_bounded_mixed_async_blocking_conversion return () in 29.351657ms >>> [INFO] [stderr] [2025-09-01 08:07:42.555367][INFO][test_async.rs:540] <<< test_pressure_bounded_timeout_async (setup_log = (), _channel = (MAsyncTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:42.555492][INFO][test_async.rs:540] >>> test_pressure_bounded_timeout_async return () in 860ns >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_timeout_async::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:42.597207][INFO][test_async.rs:1083] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 2) enter <<< [INFO] [stderr] [2025-09-01 08:07:42.597392][INFO][test_async.rs:1096] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_mixed_async_blocking_conversion::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:42.837374][INFO][test_async.rs:825] >>> test_pressure_bounded_mixed_async_blocking_conversion return () in 386.878992ms >>> [INFO] [stderr] [2025-09-01 08:07:42.838486][INFO][test_async.rs:1083] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 4) enter <<< [INFO] [stderr] [2025-09-01 08:07:42.838614][INFO][test_async.rs:1096] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:42.971371][INFO][test_async.rs:765] >>> test_pressure_bounded_async_multi return () in 4.676114449s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi::case_07 ... ok [INFO] [stderr] [2025-09-01 08:07:42.971784][INFO][test_async.rs:1083] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:07:42.971899][INFO][test_async.rs:1096] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_10 ... ok [INFO] [stderr] [2025-09-01 08:07:45.300180][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 4.9637825s >>> [INFO] [stderr] [2025-09-01 08:07:45.300882][INFO][test_async.rs:1083] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 3) enter <<< [INFO] [stderr] [2025-09-01 08:07:45.302307][INFO][test_async.rs:1096] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:45.617263][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 6.27559131s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_08 ... ok [INFO] [stderr] [2025-09-01 08:07:45.617905][INFO][test_async.rs:1083] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 30) enter <<< [INFO] [stderr] [2025-09-01 08:07:45.618253][INFO][test_async.rs:1096] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_4 ... ok [INFO] [stderr] [2025-09-01 08:07:45.838333][INFO][test_async.rs:1083] >>> test_pressure_stream_multi return () in 536.026967ms >>> [INFO] [stderr] [2025-09-01 08:07:45.839351][INFO][test_async.rs:1083] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 2) enter <<< [INFO] [stderr] [2025-09-01 08:07:45.839539][INFO][test_async.rs:1096] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_6 ... ok [INFO] [stderr] [2025-09-01 08:07:46.033256][INFO][test_async.rs:1083] >>> test_pressure_stream_multi return () in 193.714371ms >>> [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_7 ... ok [INFO] [stderr] [2025-09-01 08:07:46.033826][INFO][test_async.rs:1083] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 4) enter <<< [INFO] [stderr] [2025-09-01 08:07:46.033993][INFO][test_async.rs:1096] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:46.188194][INFO][test_async.rs:1083] >>> test_pressure_stream_multi return () in 154.201205ms >>> [INFO] [stderr] [2025-09-01 08:07:46.188667][INFO][test_async.rs:1083] <<< test_pressure_stream_multi (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 50) enter <<< [INFO] [stderr] [2025-09-01 08:07:46.188808][INFO][test_async.rs:1096] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:46.488009][INFO][test_async.rs:1083] >>> test_pressure_stream_multi return () in 299.199951ms >>> [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_8 ... ok [INFO] [stderr] [2025-09-01 08:07:46.491219][INFO][test_async.rs:1123] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 2) enter <<< [INFO] [stderr] [2025-09-01 08:07:46.491405][INFO][test_async.rs:1136] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_5 ... ok [INFO] [stderr] [2025-09-01 08:07:46.571602][INFO][test_async.rs:1083] >>> test_pressure_stream_multi return () in 953.351806ms >>> [INFO] [stderr] [2025-09-01 08:07:46.572195][INFO][test_async.rs:1123] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 4) enter <<< [INFO] [stderr] [2025-09-01 08:07:46.572347][INFO][test_async.rs:1136] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:46.932931][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 4.6606984s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_17 ... ok [INFO] [stderr] [2025-09-01 08:07:46.934073][INFO][test_async.rs:1123] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:07:46.934257][INFO][test_async.rs:1136] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_3 ... ok [INFO] [stderr] [2025-09-01 08:07:47.458796][INFO][test_async.rs:1083] >>> test_pressure_stream_multi return () in 4.486892947s >>> [INFO] [stderr] [2025-09-01 08:07:47.459361][INFO][test_async.rs:1123] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 3) enter <<< [INFO] [stderr] [2025-09-01 08:07:47.459499][INFO][test_async.rs:1136] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:47.702173][INFO][test_async.rs:1083] >>> test_pressure_stream_multi return () in 4.86355557s >>> [INFO] [stderr] [2025-09-01 08:07:47.704339][INFO][test_async.rs:1123] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 30) enter <<< [INFO] [stderr] [2025-09-01 08:07:47.704533][INFO][test_async.rs:1136] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_2 ... ok [INFO] [stderr] [2025-09-01 08:07:49.324296][INFO][test_async.rs:1083] >>> test_pressure_stream_multi return () in 6.726903387s >>> [INFO] [stdout] test tests::test_async::test_pressure_stream_multi::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:49.324990][INFO][test_async.rs:1123] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 2) enter <<< [INFO] [stderr] [2025-09-01 08:07:49.325147][INFO][test_async.rs:1136] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_05 ... ok [INFO] [stderr] [2025-09-01 08:07:51.187161][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 12.286800718s >>> [INFO] [stderr] [2025-09-01 08:07:51.187711][INFO][test_async.rs:1123] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 4) enter <<< [INFO] [stderr] [2025-09-01 08:07:51.187866][INFO][test_async.rs:1136] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:51.700475][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 10.933592461s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_14 ... ok [INFO] [stderr] [2025-09-01 08:07:51.701052][INFO][test_async.rs:1123] <<< test_pressure_stream_multi_idle (setup_log = (), channel = (MAsyncTx, MAsyncRx), rx_count = 50) enter <<< [INFO] [stderr] [2025-09-01 08:07:51.701911][INFO][test_async.rs:1136] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_09 ... ok [INFO] [stderr] [2025-09-01 08:07:53.543697][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 13.558303883s >>> [INFO] [stdout] test tests::test_async::test_spurious_sink ... ok [INFO] [stderr] [2025-09-01 08:07:53.544272][INFO][test_async.rs:969] <<< test_spurious_sink (setup_log = ()) enter <<< [INFO] [stdout] test tests::test_async::test_spurious_stream ... ok [INFO] [stderr] [2025-09-01 08:07:53.544405][INFO][test_async.rs:969] >>> test_spurious_sink return () in 111ns >>> [INFO] [stderr] [2025-09-01 08:07:53.544700][INFO][test_async.rs:1010] <<< test_spurious_stream (setup_log = ()) enter <<< [INFO] [stderr] [2025-09-01 08:07:53.544967][INFO][test_async.rs:1010] >>> test_spurious_stream return () in 40ns >>> [INFO] [stderr] [2025-09-01 08:07:53.545223][INFO][test_async.rs:90] <<< test_sync () enter <<< [INFO] [stderr] [2025-09-01 08:07:53.545319][INFO][test_async.rs:94] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:54.806504][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 12.36839408s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_18 ... ok [INFO] [stderr] [2025-09-01 08:07:54.807130][INFO][test_async_blocking.rs:80] <<< test_basic_1_tx_async_1_rx_blocking (setup_log = (), channel = (AsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:54.807400][INFO][test_async_blocking.rs:109] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:55.083457][INFO][test_async.rs:1123] >>> test_pressure_stream_multi_idle return () in 8.592051383s >>> [INFO] [stderr] [2025-09-01 08:07:55.085314][INFO][test_async_blocking.rs:80] <<< test_basic_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:55.085495][INFO][test_async_blocking.rs:109] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:55.204454][INFO][test_async.rs:1123] >>> test_pressure_stream_multi_idle return () in 8.632102698s >>> [INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_2 ... ok [INFO] [stderr] [2025-09-01 08:07:55.204998][INFO][test_async_blocking.rs:80] <<< test_basic_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:55.205304][INFO][test_async_blocking.rs:109] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:55.463468][INFO][test_async_blocking.rs:80] >>> test_basic_1_tx_async_1_rx_blocking return () in 656.185715ms >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_1_tx_async_1_rx_blocking::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:55.464199][INFO][test_async_blocking.rs:16] <<< 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_1 ... ok [INFO] [stderr] [2025-09-01 08:07:55.464396][INFO][test_async_blocking.rs:16] >>> test_basic_bounded_empty_full_drop_rx return () in 12.48µs >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_rx::case_2 ... ok [INFO] [stderr] [2025-09-01 08:07:55.464623][INFO][test_async_blocking.rs:16] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MAsyncTx, Rx)) enter <<< [INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_rx::case_3 ... ok [INFO] [stderr] [2025-09-01 08:07:55.464729][INFO][test_async_blocking.rs:16] >>> test_basic_bounded_empty_full_drop_rx return () in 9.25µs >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_tx::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:55.464944][INFO][test_async_blocking.rs:16] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MAsyncTx, MRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:55.465050][INFO][test_async_blocking.rs:16] >>> test_basic_bounded_empty_full_drop_rx return () in 9.01µs >>> [INFO] [stderr] [2025-09-01 08:07:55.465271][INFO][test_async_blocking.rs:39] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (AsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:55.465367][INFO][test_async_blocking.rs:39] >>> test_basic_bounded_empty_full_drop_tx return () in 7.31µs >>> [INFO] [stderr] [2025-09-01 08:07:55.497369][INFO][test_async_blocking.rs:39] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MAsyncTx, Rx)) enter <<< [INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_tx::case_2 ... ok [INFO] [stderr] [2025-09-01 08:07:55.497792][INFO][test_async_blocking.rs:39] >>> test_basic_bounded_empty_full_drop_tx return () in 13.04µs >>> [INFO] [stderr] [2025-09-01 08:07:55.498112][INFO][test_async_blocking.rs:39] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MAsyncTx, MRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:55.498216][INFO][test_async_blocking.rs:39] >>> test_basic_bounded_empty_full_drop_tx return () in 9.13µs >>> [INFO] [stderr] [2025-09-01 08:07:55.498421][INFO][test_async_blocking.rs:62] <<< test_basic_compile_bounded_empty_full () enter <<< [INFO] [stdout] test tests::test_async_blocking::test_basic_bounded_empty_full_drop_tx::case_3 ... ok [INFO] [stderr] [2025-09-01 08:07:55.498626][INFO][test_async_blocking.rs:62] >>> test_basic_compile_bounded_empty_full return () in 25.04µs >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_compile_bounded_empty_full ... ok [INFO] [stderr] [2025-09-01 08:07:55.499065][INFO][test_async_blocking.rs:171] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 8) enter <<< [INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_3 ... ok [INFO] [stderr] [2025-09-01 08:07:55.499547][INFO][test_async_blocking.rs:204] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:55.532039][INFO][test_async.rs:1123] >>> test_pressure_stream_multi_idle return () in 8.597783921s >>> [INFO] [stderr] [2025-09-01 08:07:55.532573][INFO][test_async_blocking.rs:171] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:07:55.532809][INFO][test_async_blocking.rs:204] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:55.776408][INFO][test_async_blocking.rs:80] >>> test_basic_1_tx_async_1_rx_blocking return () in 690.962691ms >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_1_tx_async_1_rx_blocking::case_2 ... ok [INFO] [stderr] [2025-09-01 08:07:55.776857][INFO][test_async_blocking.rs:171] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 1000) enter <<< [INFO] [stderr] [2025-09-01 08:07:55.777946][INFO][test_async_blocking.rs:204] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_basic_1_tx_async_1_rx_blocking::case_3 ... ok [INFO] [stderr] [2025-09-01 08:07:55.853633][INFO][test_async_blocking.rs:80] >>> test_basic_1_tx_async_1_rx_blocking return () in 648.402386ms >>> [INFO] [stderr] [2025-09-01 08:07:55.854856][INFO][test_async_blocking.rs:171] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 8) enter <<< [INFO] [stderr] [2025-09-01 08:07:55.855091][INFO][test_async_blocking.rs:204] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:56.029852][INFO][test_async.rs:1123] >>> test_pressure_stream_multi_idle return () in 8.570352174s >>> [INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_4 ... ok [INFO] [stderr] [2025-09-01 08:07:56.050419][INFO][test_async_blocking.rs:171] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:07:56.050726][INFO][test_async_blocking.rs:204] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:56.209483][INFO][test_async_blocking.rs:171] >>> test_basic_multi_tx_async_1_rx_blocking return () in 710.06483ms >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_1 ... ok [INFO] [stderr] [2025-09-01 08:07:56.209933][INFO][test_async_blocking.rs:171] <<< test_basic_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 1000) enter <<< [INFO] [stderr] [2025-09-01 08:07:56.210944][INFO][test_async_blocking.rs:204] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_5 ... ok [INFO] [stderr] [2025-09-01 08:07:56.262475][INFO][test_async.rs:1123] >>> test_pressure_stream_multi_idle return () in 8.557942835s >>> [INFO] [stderr] [2025-09-01 08:07:56.262982][INFO][test_async_blocking.rs:228] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (AsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:56.263210][INFO][test_async_blocking.rs:263] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:56.378383][INFO][test_async_blocking.rs:171] >>> test_basic_multi_tx_async_1_rx_blocking return () in 845.640357ms >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_2 ... ok [INFO] [stderr] [2025-09-01 08:07:56.378898][INFO][test_async_blocking.rs:228] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (AsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:56.379201][INFO][test_async_blocking.rs:263] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_4 ... ok [INFO] [stderr] [2025-09-01 08:07:56.539457][INFO][test_async_blocking.rs:171] >>> test_basic_multi_tx_async_1_rx_blocking return () in 684.421652ms >>> [INFO] [stderr] [2025-09-01 08:07:56.539907][INFO][test_async_blocking.rs:228] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (AsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:56.540776][INFO][test_async_blocking.rs:263] 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-01 08:07:56.847759][INFO][test_async_blocking.rs:171] >>> test_basic_multi_tx_async_1_rx_blocking return () in 797.123411ms >>> [INFO] [stderr] [2025-09-01 08:07:56.863315][INFO][test_async_blocking.rs:228] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (AsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:56.863591][INFO][test_async_blocking.rs:263] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_03 ... ok [INFO] [stderr] [2025-09-01 08:07:57.574095][INFO][test_async_blocking.rs:228] >>> test_pressure_1_tx_async_1_rx_blocking return () in 1.034002168s >>> [INFO] [stderr] [2025-09-01 08:07:57.574563][INFO][test_async_blocking.rs:228] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:57.575543][INFO][test_async_blocking.rs:263] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:57.915141][INFO][test_async.rs:1123] >>> test_pressure_stream_multi_idle return () in 8.589990632s >>> [INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_6 ... ok [INFO] [stderr] [2025-09-01 08:07:57.915661][INFO][test_async_blocking.rs:228] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:57.915869][INFO][test_async_blocking.rs:263] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:58.250503][INFO][test_async_blocking.rs:228] >>> test_pressure_1_tx_async_1_rx_blocking return () in 1.386987863s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_04 ... ok [INFO] [stderr] [2025-09-01 08:07:58.251254][INFO][test_async_blocking.rs:228] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:58.251515][INFO][test_async_blocking.rs:263] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:59.075004][INFO][test_async_blocking.rs:228] >>> test_pressure_1_tx_async_1_rx_blocking return () in 823.539769ms >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_07 ... ok [INFO] [stderr] [2025-09-01 08:07:59.077329][INFO][test_async_blocking.rs:228] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:59.077600][INFO][test_async_blocking.rs:263] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:07:59.556273][INFO][test_async.rs:90] >>> test_sync return () in 6.010953857s >>> [INFO] [stdout] test tests::test_async::test_sync ... ok [INFO] [stderr] [2025-09-01 08:07:59.556903][INFO][test_async_blocking.rs:228] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:59.558149][INFO][test_async_blocking.rs:263] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_7 ... ok [INFO] [stderr] [2025-09-01 08:07:59.998524][INFO][test_async.rs:1123] >>> test_pressure_stream_multi_idle return () in 8.810657921s >>> [INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_6 ... ok [INFO] [stderr] [2025-09-01 08:07:59.999169][INFO][test_async_blocking.rs:228] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx)) enter <<< [INFO] [stderr] [2025-09-01 08:07:59.999506][INFO][test_async_blocking.rs:263] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:00.028338][INFO][test_async_blocking.rs:171] >>> test_basic_multi_tx_async_1_rx_blocking return () in 3.817459423s >>> [INFO] [stderr] [2025-09-01 08:08:00.031466][INFO][test_async_blocking.rs:228] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:00.033492][INFO][test_async_blocking.rs:263] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_08 ... ok [INFO] [stderr] [2025-09-01 08:08:00.450463][INFO][test_async_blocking.rs:228] >>> test_pressure_1_tx_async_1_rx_blocking return () in 1.372931785s >>> [INFO] [stdout] test tests::test_async::test_pressure_stream_multi_idle::case_8 ... ok [INFO] [stderr] [2025-09-01 08:08:00.450978][INFO][test_async_blocking.rs:228] <<< test_pressure_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:00.451168][INFO][test_async_blocking.rs:263] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:00.495852][INFO][test_async.rs:1123] >>> test_pressure_stream_multi_idle return () in 8.793941383s >>> [INFO] [stderr] [2025-09-01 08:08:00.496540][INFO][test_async_blocking.rs:276] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 8) enter <<< [INFO] [stderr] [2025-09-01 08:08:00.497537][INFO][test_async_blocking.rs:307] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:01.885514][INFO][test_async_blocking.rs:228] >>> test_pressure_1_tx_async_1_rx_blocking return () in 1.434382679s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_12 ... ok [INFO] [stderr] [2025-09-01 08:08:01.886153][INFO][test_async_blocking.rs:276] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 10) enter <<< [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_11 ... ok [INFO] [stderr] [2025-09-01 08:08:01.886331][INFO][test_async_blocking.rs:307] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:02.014457][INFO][test_async_blocking.rs:228] >>> test_pressure_1_tx_async_1_rx_blocking return () in 1.982698835s >>> [INFO] [stderr] [2025-09-01 08:08:02.014937][INFO][test_async_blocking.rs:276] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:08:02.015128][INFO][test_async_blocking.rs:307] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_basic_multi_tx_async_1_rx_blocking::case_3 ... ok [INFO] [stderr] [2025-09-01 08:08:02.386949][INFO][test_async_blocking.rs:171] >>> test_basic_multi_tx_async_1_rx_blocking return () in 6.609932458s >>> [INFO] [stderr] [2025-09-01 08:08:02.387677][INFO][test_async_blocking.rs:276] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx), tx_count = 200) enter <<< [INFO] [stderr] [2025-09-01 08:08:02.387864][INFO][test_async_blocking.rs:307] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:03.382612][INFO][test_async_blocking.rs:228] >>> test_pressure_1_tx_async_1_rx_blocking return () in 5.466809521s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_06 ... ok [INFO] [stderr] [2025-09-01 08:08:03.383441][INFO][test_async_blocking.rs:276] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 8) enter <<< [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_02 ... ok [INFO] [stderr] [2025-09-01 08:08:03.383671][INFO][test_async_blocking.rs:307] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:03.421361][INFO][test_async_blocking.rs:228] >>> test_pressure_1_tx_async_1_rx_blocking return () in 7.042230475s >>> [INFO] [stderr] [2025-09-01 08:08:03.422191][INFO][test_async_blocking.rs:276] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:08:03.422368][INFO][test_async_blocking.rs:307] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:04.177530][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 23.81811283s >>> [INFO] [stderr] [2025-09-01 08:08:04.178254][INFO][test_async_blocking.rs:276] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:08:04.178480][INFO][test_async_blocking.rs:307] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_11 ... ok [INFO] [stderr] [2025-09-01 08:08:04.958308][INFO][test_async_blocking.rs:228] >>> test_pressure_1_tx_async_1_rx_blocking return () in 4.958897951s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_10 ... ok [INFO] [stderr] [2025-09-01 08:08:04.959046][INFO][test_async_blocking.rs:276] <<< test_pressure_multi_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 200) enter <<< [INFO] [stderr] [2025-09-01 08:08:04.959478][INFO][test_async_blocking.rs:307] 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-01 08:08:05.853042][INFO][test_async_blocking.rs:276] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 5.356378181s >>> [INFO] [stderr] [2025-09-01 08:08:05.853796][INFO][test_async_blocking.rs:330] <<< test_pressure_multi_tx_async_multi_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 8, rx_count = 8) enter <<< [INFO] [stderr] [2025-09-01 08:08:05.854425][INFO][test_async_blocking.rs:363] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:07.086781][INFO][test_async_blocking.rs:330] >>> test_pressure_multi_tx_async_multi_rx_blocking return () in 1.232724108s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_multi_rx_blocking::case_1 ... ok [INFO] [stderr] [2025-09-01 08:08:07.089230][INFO][test_async_blocking.rs:330] <<< test_pressure_multi_tx_async_multi_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 100, rx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:08:07.116865][INFO][test_async_blocking.rs:363] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:07.257412][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 25.374349757s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_15 ... ok [INFO] [stderr] [2025-09-01 08:08:07.257839][INFO][test_async_blocking.rs:330] <<< test_pressure_multi_tx_async_multi_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx), tx_count = 10, rx_count = 300) enter <<< [INFO] [stderr] [2025-09-01 08:08:07.415852][INFO][test_async_blocking.rs:363] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:07.956748][INFO][test_async_blocking.rs:276] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 6.070454552s >>> [INFO] [stderr] [2025-09-01 08:08:07.957851][INFO][test_async_blocking.rs:330] <<< 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_2 ... ok [INFO] [stderr] [2025-09-01 08:08:08.228652][INFO][test_async_blocking.rs:363] run with tokio multi thread [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_06 ... ok [INFO] [stderr] [2025-09-01 08:08:09.612133][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 30.392067511s >>> [INFO] [stderr] [2025-09-01 08:08:09.640327][INFO][test_async_blocking.rs:122] <<< test_timeout_1_tx_async_1_rx_blocking (setup_log = (), channel = (AsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:09.640652][INFO][test_async_blocking.rs:155] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_timeout_1_tx_async_1_rx_blocking::case_1 ... ok [INFO] [stderr] [2025-09-01 08:08:11.061968][INFO][test_async_blocking.rs:122] >>> test_timeout_1_tx_async_1_rx_blocking return () in 1.42140485s >>> [INFO] [stderr] [2025-09-01 08:08:11.067228][INFO][test_async_blocking.rs:122] <<< test_timeout_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:11.067440][INFO][test_async_blocking.rs:155] 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-01 08:08:12.313786][INFO][test_async_blocking.rs:122] >>> test_timeout_1_tx_async_1_rx_blocking return () in 1.246398097s >>> [INFO] [stderr] [2025-09-01 08:08:12.329271][INFO][test_async_blocking.rs:122] <<< test_timeout_1_tx_async_1_rx_blocking (setup_log = (), channel = (MAsyncTx, MRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:12.329569][INFO][test_async_blocking.rs:155] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_7 ... ok [INFO] [stderr] [2025-09-01 08:08:12.884610][INFO][test_async_blocking.rs:276] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 8.706184011s >>> [INFO] [stderr] [2025-09-01 08:08:12.888312][INFO][test_blocking_async.rs:103] <<< test_basic_1_tx_blocking_1_rx_async (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:12.890427][INFO][test_blocking_async.rs:128] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:13.243185][INFO][test_async_blocking.rs:276] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 9.859566317s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_5 ... ok [INFO] [stderr] [2025-09-01 08:08:13.243964][INFO][test_blocking_async.rs:103] <<< test_basic_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:13.245382][INFO][test_blocking_async.rs:128] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:13.354449][INFO][test_async_blocking.rs:122] >>> test_timeout_1_tx_async_1_rx_blocking return () in 1.024958929s >>> [INFO] [stderr] [2025-09-01 08:08:13.355335][INFO][test_blocking_async.rs:103] <<< test_basic_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:13.356153][INFO][test_blocking_async.rs:128] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_timeout_1_tx_async_1_rx_blocking::case_3 ... ok [INFO] [stdout] test tests::test_blocking_async::test_basic_1_tx_blocking_1_rx_async::case_1 ... ok [INFO] [stderr] [2025-09-01 08:08:13.904180][INFO][test_blocking_async.rs:103] >>> test_basic_1_tx_blocking_1_rx_async return () in 1.01567441s >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_rx::case_1 ... ok [INFO] [stderr] [2025-09-01 08:08:13.904684][INFO][test_blocking_async.rs:18] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_rx::case_2 ... ok [INFO] [stderr] [2025-09-01 08:08:13.904835][INFO][test_blocking_async.rs:18] >>> test_basic_bounded_empty_full_drop_rx return () in 14.3µs >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_rx::case_3 ... ok [INFO] [stderr] [2025-09-01 08:08:13.905221][INFO][test_blocking_async.rs:18] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_tx::case_1 ... ok [INFO] [stderr] [2025-09-01 08:08:13.905305][INFO][test_blocking_async.rs:18] >>> test_basic_bounded_empty_full_drop_rx return () in 8.12µs >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_tx::case_2 ... ok [INFO] [stderr] [2025-09-01 08:08:13.905639][INFO][test_blocking_async.rs:18] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_basic_bounded_empty_full_drop_tx::case_3 ... ok [INFO] [stderr] [2025-09-01 08:08:13.905720][INFO][test_blocking_async.rs:18] >>> test_basic_bounded_empty_full_drop_rx return () in 8.9µs >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_compile_bounded_empty_full ... ok [INFO] [stderr] [2025-09-01 08:08:13.905928][INFO][test_blocking_async.rs:41] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_basic_unbounded_empty_drop_tx::case_1 ... ok [INFO] [stderr] [2025-09-01 08:08:13.906010][INFO][test_blocking_async.rs:41] >>> test_basic_bounded_empty_full_drop_tx return () in 8.83µs >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_unbounded_empty_drop_tx::case_2 ... ok [INFO] [stderr] [2025-09-01 08:08:13.906267][INFO][test_blocking_async.rs:41] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_basic_unbounded_empty_drop_tx::case_3 ... ok [INFO] [stderr] [2025-09-01 08:08:13.906351][INFO][test_blocking_async.rs:41] >>> test_basic_bounded_empty_full_drop_tx return () in 8.43µs >>> [INFO] [stderr] [2025-09-01 08:08:13.906551][INFO][test_blocking_async.rs:41] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:13.906628][INFO][test_blocking_async.rs:41] >>> test_basic_bounded_empty_full_drop_tx return () in 7.91µs >>> [INFO] [stderr] [2025-09-01 08:08:13.906807][INFO][test_blocking_async.rs:85] <<< test_basic_compile_bounded_empty_full () enter <<< [INFO] [stderr] [2025-09-01 08:08:13.906910][INFO][test_blocking_async.rs:85] >>> test_basic_compile_bounded_empty_full return () in 28.66µs >>> [INFO] [stderr] [2025-09-01 08:08:13.907261][INFO][test_blocking_async.rs:64] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:13.907347][INFO][test_blocking_async.rs:64] >>> test_basic_unbounded_empty_drop_tx return () in 13.25µs >>> [INFO] [stderr] [2025-09-01 08:08:13.907521][INFO][test_blocking_async.rs:64] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:13.907604][INFO][test_blocking_async.rs:64] >>> test_basic_unbounded_empty_drop_tx return () in 8.25µs >>> [INFO] [stderr] [2025-09-01 08:08:13.907811][INFO][test_blocking_async.rs:64] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:13.907891][INFO][test_blocking_async.rs:64] >>> test_basic_unbounded_empty_drop_tx return () in 8.19µs >>> [INFO] [stderr] [2025-09-01 08:08:13.908296][INFO][test_blocking_async.rs:193] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:13.908799][INFO][test_blocking_async.rs:214] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:14.251974][INFO][test_blocking_async.rs:103] >>> test_basic_1_tx_blocking_1_rx_async return () in 1.00780205s >>> [INFO] [stdout] test tests::test_blocking_async::test_basic_1_tx_blocking_1_rx_async::case_2 ... ok [INFO] [stderr] [2025-09-01 08:08:14.255456][INFO][test_blocking_async.rs:193] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:14.257023][INFO][test_blocking_async.rs:214] run with tokio multi thread [INFO] [stdout] test tests::test_blocking_async::test_basic_1_tx_blocking_1_rx_async::case_3 ... ok [INFO] [stderr] [2025-09-01 08:08:14.419057][INFO][test_blocking_async.rs:103] >>> test_basic_1_tx_blocking_1_rx_async return () in 1.063579595s >>> [INFO] [stderr] [2025-09-01 08:08:14.461330][INFO][test_blocking_async.rs:193] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:14.462285][INFO][test_blocking_async.rs:214] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:20.319013][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 41.467958929s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_02 ... ok [INFO] [stderr] [2025-09-01 08:08:20.319590][INFO][test_blocking_async.rs:193] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:20.319792][INFO][test_blocking_async.rs:214] run with tokio multi thread [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_4 ... ok [INFO] [stderr] [2025-09-01 08:08:20.475768][INFO][test_blocking_async.rs:193] >>> test_pressure_1_tx_blocking_1_rx_async return () in 156.028294ms >>> [INFO] [stderr] [2025-09-01 08:08:20.476307][INFO][test_blocking_async.rs:193] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:20.478753][INFO][test_blocking_async.rs:214] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:20.699407][INFO][test_blocking_async.rs:193] >>> test_pressure_1_tx_blocking_1_rx_async return () in 222.941248ms >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_5 ... ok [INFO] [stderr] [2025-09-01 08:08:20.700172][INFO][test_blocking_async.rs:193] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:20.701201][INFO][test_blocking_async.rs:214] run with tokio multi thread [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_6 ... ok [INFO] [stderr] [2025-09-01 08:08:20.916498][INFO][test_blocking_async.rs:193] >>> test_pressure_1_tx_blocking_1_rx_async return () in 216.167419ms >>> [INFO] [stderr] [2025-09-01 08:08:20.927516][INFO][test_blocking_async.rs:193] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:20.928763][INFO][test_blocking_async.rs:214] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:21.403733][INFO][test_blocking_async.rs:193] >>> test_pressure_1_tx_blocking_1_rx_async return () in 475.023844ms >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_7 ... ok [INFO] [stderr] [2025-09-01 08:08:21.404270][INFO][test_blocking_async.rs:193] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:21.405009][INFO][test_blocking_async.rs:214] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:22.752926][INFO][test_blocking_async.rs:193] >>> test_pressure_1_tx_blocking_1_rx_async return () in 1.347969847s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_8 ... ok [INFO] [stderr] [2025-09-01 08:08:22.753501][INFO][test_blocking_async.rs:193] <<< test_pressure_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:08:22.754911][INFO][test_blocking_async.rs:214] run with tokio multi thread [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_9 ... ok [INFO] [stderr] [2025-09-01 08:08:22.978643][INFO][test_blocking_async.rs:193] >>> test_pressure_1_tx_blocking_1_rx_async return () in 224.997188ms >>> [INFO] [stderr] [2025-09-01 08:08:22.989257][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:08:22.991856][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:24.178619][INFO][test_blocking_async.rs:193] >>> test_pressure_1_tx_blocking_1_rx_async return () in 9.922982721s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_2 ... ok [INFO] [stderr] [2025-09-01 08:08:24.179335][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:08:24.195606][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:25.843407][INFO][test_blocking_async.rs:193] >>> test_pressure_1_tx_blocking_1_rx_async return () in 11.935030993s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_1 ... ok [INFO] [stderr] [2025-09-01 08:08:25.892263][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 1000) enter <<< [INFO] [stderr] [2025-09-01 08:08:26.802211][INFO][test_blocking_async.rs:281] 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-01 08:08:27.656676][INFO][test_async_blocking.rs:330] >>> test_pressure_multi_tx_async_multi_rx_blocking return () in 20.398698108s >>> [INFO] [stderr] [2025-09-01 08:08:27.657457][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:08:27.658100][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:27.922323][INFO][test_blocking_async.rs:193] >>> test_pressure_1_tx_blocking_1_rx_async return () in 13.460132112s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_1_tx_blocking_1_rx_async::case_3 ... ok [INFO] [stderr] [2025-09-01 08:08:27.922887][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:08:27.966455][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:29.897057][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 2.239355929s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_04 ... ok [INFO] [stderr] [2025-09-01 08:08:29.898571][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 1000) enter <<< [INFO] [stderr] [2025-09-01 08:08:30.181318][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_01 ... ok [INFO] [stderr] [2025-09-01 08:08:33.437967][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 10.448489009s >>> [INFO] [stderr] [2025-09-01 08:08:33.442778][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:08:33.446889][INFO][test_blocking_async.rs:281] 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_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_05 ... ok [INFO] [stderr] [2025-09-01 08:08:40.001819][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 12.078774688s >>> [INFO] [stderr] [2025-09-01 08:08:40.004351][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:08:40.074666][INFO][test_blocking_async.rs:281] run with tokio multi thread [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-01 08:08:41.859728][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 8.416822859s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_07 ... ok [INFO] [stderr] [2025-09-01 08:08:41.871117][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 1000) enter <<< [INFO] [stderr] [2025-09-01 08:08:42.736670][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:08:50.089989][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 25.910467853s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_02 ... ok [INFO] [stderr] [2025-09-01 08:08:50.090532][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:08:50.091254][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_01 has been running for over 60 seconds [INFO] [stderr] [2025-09-01 08:08:57.033706][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 6.943050635s >>> [INFO] [stderr] [2025-09-01 08:08:57.077530][INFO][test_blocking_async.rs:231] <<< 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_10 ... ok [INFO] [stderr] [2025-09-01 08:08:57.177887][INFO][test_blocking_async.rs:281] run with tokio multi thread [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_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_09 has been running for over 60 seconds [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_3 has been running for over 60 seconds [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_4 has been running for over 60 seconds [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_6 has been running for over 60 seconds [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_08 ... ok [INFO] [stderr] [2025-09-01 08:09:03.994147][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 23.989599213s >>> [INFO] [stderr] [2025-09-01 08:09:03.996219][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 1000) enter <<< [INFO] [stderr] [2025-09-01 08:09:04.496946][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_8 has been running for over 60 seconds [INFO] [stderr] [2025-09-01 08:09:06.736673][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 9.658962667s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_11 ... ok [INFO] [stderr] [2025-09-01 08:09:06.789685][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:09:06.790222][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_multi_rx_blocking::case_2 has been running for over 60 seconds [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_multi_rx_blocking::case_4 has been running for over 60 seconds [INFO] [stderr] [2025-09-01 08:09:09.479457][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 2.689571715s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_13 ... ok [INFO] [stderr] [2025-09-01 08:09:09.547390][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:09:10.231957][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:09:11.875507][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 2.327939531s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_14 ... ok [INFO] [stderr] [2025-09-01 08:09:11.940742][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx), tx_count = 1000) enter <<< [INFO] [stderr] [2025-09-01 08:09:12.963843][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_05 ... ok [INFO] [stderr] [2025-09-01 08:09:13.066309][INFO][test_async_blocking.rs:228] >>> test_pressure_1_tx_async_1_rx_blocking return () in 75.491590501s >>> [INFO] [stderr] [2025-09-01 08:09:13.066729][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:09:13.067210][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_15 ... ok [INFO] [stderr] [2025-09-01 08:09:14.974823][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 3.007951523s >>> [INFO] [stderr] [2025-09-01 08:09:14.979288][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100) enter <<< [INFO] [stderr] [2025-09-01 08:09:15.376629][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_16 ... ok [INFO] [stderr] [2025-09-01 08:09:15.582465][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 2.515589332s >>> [INFO] [stderr] [2025-09-01 08:09:15.582950][INFO][test_blocking_async.rs:231] <<< test_pressure_tx_multi_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 1000) enter <<< [INFO] [stderr] [2025-09-01 08:09:16.417713][INFO][test_blocking_async.rs:281] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:09:18.867333][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 3.887871207s >>> [INFO] [stderr] [2025-09-01 08:09:19.325342][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 10, rx_count = 10) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_17 ... ok [INFO] [stderr] [2025-09-01 08:09:19.455197][INFO][test_blocking_async.rs:348] run with tokio multi thread [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_18 ... ok [INFO] [stderr] [2025-09-01 08:09:19.788465][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 4.205252905s >>> [INFO] [stderr] [2025-09-01 08:09:19.823227][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100, rx_count = 20) enter <<< [INFO] [stderr] [2025-09-01 08:09:19.919006][INFO][test_blocking_async.rs:348] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:09:20.147076][INFO][test_async_blocking.rs:228] >>> test_pressure_1_tx_async_1_rx_blocking return () in 80.589029418s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_09 ... ok [INFO] [stderr] [2025-09-01 08:09:20.147542][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 300, rx_count = 200) enter <<< [INFO] [stderr] [2025-09-01 08:09:20.659186][INFO][test_blocking_async.rs:348] run with tokio multi thread [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_multi_rx_blocking::case_2 ... ok [INFO] [stderr] [2025-09-01 08:09:20.699317][INFO][test_async_blocking.rs:330] >>> test_pressure_multi_tx_async_multi_rx_blocking return () in 73.609935387s >>> [INFO] [stderr] [2025-09-01 08:09:20.704060][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 10, rx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:09:20.705269][INFO][test_blocking_async.rs:348] run with tokio multi thread [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_03 has been running for over 60 seconds [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_02 ... ok [INFO] [stderr] [2025-09-01 08:09:29.395089][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 9.571684725s >>> [INFO] [stderr] [2025-09-01 08:09:29.397280][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100, rx_count = 20) enter <<< [INFO] [stderr] [2025-09-01 08:09:29.458859][INFO][test_blocking_async.rs:348] run with tokio multi thread [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_06 has been running for over 60 seconds [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_04 ... ok [INFO] [stderr] [2025-09-01 08:09:30.040669][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 9.336445268s >>> [INFO] [stderr] [2025-09-01 08:09:30.042745][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 300, rx_count = 200) enter <<< [INFO] [stderr] [2025-09-01 08:09:30.498361][INFO][test_blocking_async.rs:348] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:09:30.972813][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 10.825148772s >>> [INFO] [stderr] [2025-09-01 08:09:30.974209][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 10, rx_count = 200) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_03 ... ok [INFO] [stderr] [2025-09-01 08:09:30.983921][INFO][test_blocking_async.rs:348] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:09:31.556277][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 12.181094308s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_01 ... ok [INFO] [stderr] [2025-09-01 08:09:31.569257][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100, rx_count = 200) enter <<< [INFO] [stderr] [2025-09-01 08:09:31.752784][INFO][test_blocking_async.rs:348] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:09:34.291857][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 2.722407362s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_08 ... ok [INFO] [stderr] [2025-09-01 08:09:34.360393][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 300, rx_count = 500) enter <<< [INFO] [stderr] [2025-09-01 08:09:34.965103][INFO][test_blocking_async.rs:348] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:09:36.049721][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 5.075339869s >>> [INFO] [stderr] [2025-09-01 08:09:36.054555][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 30, rx_count = 1000) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_07 ... ok [INFO] [stderr] [2025-09-01 08:09:36.085594][INFO][test_blocking_async.rs:348] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:09:37.420109][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 8.022640268s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_05 ... ok [INFO] [stderr] [2025-09-01 08:09:37.445211][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 10, rx_count = 10) enter <<< [INFO] [stderr] [2025-09-01 08:09:37.446299][INFO][test_blocking_async.rs:348] run with tokio multi thread [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_11 ... ok [INFO] [stderr] [2025-09-01 08:09:39.540482][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 2.095094183s >>> [INFO] [stderr] [2025-09-01 08:09:39.540866][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100, rx_count = 20) enter <<< [INFO] [stderr] [2025-09-01 08:09:40.145599][INFO][test_blocking_async.rs:348] 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-01 08:09:40.697161][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 4.642362112s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_06 ... ok [INFO] [stderr] [2025-09-01 08:09:40.706657][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 10.663798268s >>> [INFO] [stderr] [2025-09-01 08:09:40.706964][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 300, rx_count = 200) enter <<< [INFO] [stderr] [2025-09-01 08:09:40.720209][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 10, rx_count = 200) enter <<< [INFO] [stderr] [2025-09-01 08:09:40.963895][INFO][test_blocking_async.rs:348] 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-01 08:09:41.031879][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 1.490900263s >>> [INFO] [stderr] [2025-09-01 08:09:41.033342][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 100, rx_count = 200) enter <<< [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] [stderr] [2025-09-01 08:09:43.385458][INFO][test_blocking_async.rs:348] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:09:43.478005][INFO][test_blocking_async.rs:348] 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-01 08:09:44.037463][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 3.317118263s >>> [INFO] [stderr] [2025-09-01 08:09:44.038500][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 300, rx_count = 500) enter <<< [INFO] [stderr] [2025-09-01 08:09:44.516599][INFO][test_blocking_async.rs:348] 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-01 08:09:44.690814][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 3.983733917s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_06 ... ok [INFO] [stderr] [2025-09-01 08:09:44.701069][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 74.802264039s >>> [INFO] [stderr] [2025-09-01 08:09:44.701732][INFO][test_blocking_async.rs:298] <<< test_pressure_tx_multi_blocking_multi_rx_async (setup_log = (), channel = (MTx, MAsyncRx), tx_count = 30, rx_count = 1000) enter <<< [INFO] [stderr] [2025-09-01 08:09:44.729644][INFO][test_blocking_async.rs:348] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:09:44.731889][INFO][test_blocking_async.rs:147] <<< test_timeout_1_tx_blocking_1_rx_async (setup_log = (), channel = (Tx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:44.880002][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 3.84650097s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_15 ... ok [INFO] [stderr] [2025-09-01 08:09:44.891215][INFO][test_blocking_async.rs:147] <<< test_timeout_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, AsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:44.931273][INFO][test_blocking_async.rs:172] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:09:44.991660][INFO][test_blocking_async.rs:172] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:09:45.106923][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 1.068306275s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_16 ... ok [INFO] [stderr] [2025-09-01 08:09:45.120449][INFO][test_blocking_async.rs:147] <<< test_timeout_1_tx_blocking_1_rx_async (setup_log = (), channel = (MTx, MAsyncRx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:45.220915][INFO][test_blocking_async.rs:172] run with tokio multi thread [INFO] [stderr] [2025-09-01 08:09:45.283202][INFO][test_blocking_async.rs:147] >>> test_timeout_1_tx_blocking_1_rx_async return () in 391.801711ms >>> [INFO] [stdout] test tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_2 ... ok [INFO] [stderr] [2025-09-01 08:09:45.283841][INFO][test_blocking_context.rs:157] <<< test_basic_bounded_1_thread (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:45.446970][INFO][test_blocking_async.rs:147] >>> test_timeout_1_tx_blocking_1_rx_async return () in 326.364608ms >>> [INFO] [stdout] test tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_3 ... ok [INFO] [stderr] [2025-09-01 08:09:45.447588][INFO][test_blocking_context.rs:157] <<< test_basic_bounded_1_thread (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:45.603066][INFO][test_async_blocking.rs:228] >>> test_pressure_1_tx_async_1_rx_blocking return () in 109.339900932s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_1_tx_async_1_rx_blocking::case_01 ... ok [INFO] [stderr] [2025-09-01 08:09:45.603638][INFO][test_blocking_context.rs:157] <<< test_basic_bounded_1_thread (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:45.741386][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 1.039465877s >>> [INFO] [stderr] [2025-09-01 08:09:45.756947][INFO][test_blocking_async.rs:298] >>> test_pressure_tx_multi_blocking_multi_rx_async return () in 11.396398766s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_17 ... ok [INFO] [stderr] [2025-09-01 08:09:45.757431][INFO][test_blocking_context.rs:16] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_multi_rx_async::case_09 ... ok [INFO] [stderr] [2025-09-01 08:09:45.757565][INFO][test_blocking_context.rs:16] >>> test_basic_bounded_empty_full_drop_rx return () in 15.211µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_rx::case_1 ... ok [INFO] [stderr] [2025-09-01 08:09:45.757897][INFO][test_blocking_context.rs:16] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_rx::case_2 ... ok [INFO] [stderr] [2025-09-01 08:09:45.757993][INFO][test_blocking_context.rs:16] >>> test_basic_bounded_empty_full_drop_rx return () in 10.789µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_rx::case_3 ... ok [INFO] [stderr] [2025-09-01 08:09:45.758671][INFO][test_blocking_context.rs:16] <<< test_basic_bounded_empty_full_drop_rx (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_tx::case_1 ... ok [INFO] [stderr] [2025-09-01 08:09:45.758762][INFO][test_blocking_context.rs:16] >>> test_basic_bounded_empty_full_drop_rx return () in 9.7µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_tx::case_2 ... ok [INFO] [stderr] [2025-09-01 08:09:45.758971][INFO][test_blocking_context.rs:53] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_empty_full_drop_tx::case_3 ... ok [INFO] [stderr] [2025-09-01 08:09:45.759109][INFO][test_blocking_context.rs:53] >>> test_basic_bounded_empty_full_drop_tx return () in 32.52µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_1 ... ok [INFO] [stderr] [2025-09-01 08:09:45.759551][INFO][test_blocking_context.rs:53] <<< test_basic_bounded_empty_full_drop_tx (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_2 ... ok [INFO] [stderr] [2025-09-01 08:09:45.759643][INFO][test_blocking_context.rs:53] >>> test_basic_bounded_empty_full_drop_tx return () in 11.25µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_3 ... ok [INFO] [stderr] [2025-09-01 08:09:45.759896][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_recv_after_sender_close::case_4 ... ok [INFO] [stderr] [2025-09-01 08:09:45.759983][INFO][test_blocking_context.rs:53] >>> test_basic_bounded_empty_full_drop_tx return () in 10.86µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_recv_after_sender_close::case_5 ... ok [INFO] [stderr] [2025-09-01 08:09:45.763167][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_6 ... ok [INFO] [stderr] [2025-09-01 08:09:45.763264][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 9.81µs >>> [INFO] [stderr] [2025-09-01 08:09:45.763379][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:45.763459][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 7.33µs >>> [INFO] [stderr] [2025-09-01 08:09:45.764162][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:45.764336][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 14.28µs >>> [INFO] [stderr] [2025-09-01 08:09:45.764408][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:45.764503][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 13.67µs >>> [INFO] [stderr] [2025-09-01 08:09:45.764792][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:45.764892][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 10.32µs >>> [INFO] [stderr] [2025-09-01 08:09:45.765514][INFO][test_blocking_context.rs:246] <<< test_basic_recv_after_sender_close (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:45.765529][INFO][test_blocking_context.rs:203] <<< test_basic_unbounded_1_thread (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:45.765630][INFO][test_blocking_context.rs:246] >>> test_basic_recv_after_sender_close return () in 16.11µs >>> [INFO] [stderr] [2025-09-01 08:09:45.766261][INFO][test_blocking_context.rs:203] <<< test_basic_unbounded_1_thread (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:46.465297][INFO][test_blocking_context.rs:157] >>> test_basic_bounded_1_thread return () in 1.01756222s >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_1_thread::case_2 ... ok [INFO] [stderr] [2025-09-01 08:09:46.477356][INFO][test_blocking_context.rs:203] <<< test_basic_unbounded_1_thread (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_1_thread::case_1 ... ok [INFO] [stderr] [2025-09-01 08:09:46.506395][INFO][test_blocking_context.rs:157] >>> test_basic_bounded_1_thread return () in 1.22242567s >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_rx::case_1 ... ok [INFO] [stderr] [2025-09-01 08:09:46.506754][INFO][test_blocking_context.rs:90] <<< test_basic_unbounded_empty_drop_rx (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:46.506911][INFO][test_blocking_context.rs:90] >>> test_basic_unbounded_empty_drop_rx return () in 16.78µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_rx::case_2 ... ok [INFO] [stderr] [2025-09-01 08:09:46.507318][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_rx::case_3 ... ok [INFO] [stderr] [2025-09-01 08:09:46.507536][INFO][test_blocking_context.rs:90] >>> test_basic_unbounded_empty_drop_rx return () in 21.6µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_tx::case_1 ... ok [INFO] [stderr] [2025-09-01 08:09:46.508325][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_2 ... ok [INFO] [stderr] [2025-09-01 08:09:46.508510][INFO][test_blocking_context.rs:90] >>> test_basic_unbounded_empty_drop_rx return () in 22.7µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_empty_drop_tx::case_3 ... ok [INFO] [stderr] [2025-09-01 08:09:46.516532][INFO][test_blocking_context.rs:124] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_conversion ... ok [INFO] [stderr] [2025-09-01 08:09:46.516649][INFO][test_blocking_context.rs:124] >>> test_basic_unbounded_empty_drop_tx return () in 12.52µs >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_bounded_1_thread::case_3 ... ok [INFO] [stderr] [2025-09-01 08:09:46.516816][INFO][test_blocking_context.rs:124] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:46.516911][INFO][test_blocking_context.rs:124] >>> test_basic_unbounded_empty_drop_tx return () in 10.35µs >>> [INFO] [stderr] [2025-09-01 08:09:46.517174][INFO][test_blocking_context.rs:124] <<< test_basic_unbounded_empty_drop_tx (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:46.517264][INFO][test_blocking_context.rs:124] >>> test_basic_unbounded_empty_drop_tx return () in 11.109µs >>> [INFO] [stderr] [2025-09-01 08:09:46.517730][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:46.607348][INFO][test_blocking_context.rs:157] >>> test_basic_bounded_1_thread return () in 1.003590331s >>> [INFO] [stderr] [2025-09-01 08:09:46.611345][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:46.770191][INFO][test_blocking_context.rs:203] >>> test_basic_unbounded_1_thread return () in 1.00382542s >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_1_thread::case_2 ... ok [INFO] [stderr] [2025-09-01 08:09:46.791386][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:46.809211][INFO][test_blocking_context.rs:203] >>> test_basic_unbounded_1_thread return () in 1.043593897s >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_1_thread::case_1 ... ok [INFO] [stderr] [2025-09-01 08:09:46.810599][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (Tx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:47.550297][INFO][test_blocking_context.rs:203] >>> test_basic_unbounded_1_thread return () in 1.072715314s >>> [INFO] [stdout] test tests::test_blocking_context::test_basic_unbounded_1_thread::case_3 ... ok [INFO] [stderr] [2025-09-01 08:09:47.648215][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:48.691893][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 1.881159945s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_04 ... ok [INFO] [stderr] [2025-09-01 08:09:48.701204][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:50.083946][ERROR][test_blocking_async.rs:169] panic occur: panicked at src/tests/test_blocking_async.rs:169:9: [INFO] [stderr] assertion failed: tx.send_timeout(10, Duration::from_millis(200)).is_ok() [INFO] [stderr] trace: 0: captains_log::log_impl::log_panic [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:340:14 [INFO] [stderr] 1: captains_log::log_impl::panic_and_exit_hook [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:352:5 [INFO] [stderr] core::ops::function::Fn::call [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/ops/function.rs:80:5 [INFO] [stderr] 2: as core::ops::function::Fn>::call [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/alloc/src/boxed.rs:1999:9 [INFO] [stderr] std::panicking::panic_with_hook [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:842:13 [INFO] [stderr] 3: std::panicking::panic_handler::{{closure}} [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:700:13 [INFO] [stderr] 4: std::sys::backtrace::__rust_end_short_backtrace [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/sys/backtrace.rs:174:18 [INFO] [stderr] 5: __rustc::rust_begin_unwind [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:698:5 [INFO] [stderr] 6: core::panicking::panic_fmt [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/panicking.rs:75:14 [INFO] [stderr] 7: core::panicking::panic [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/panicking.rs:145:5 [INFO] [stderr] 8: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::{{closure}}::{{closure}} [INFO] [stderr] at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:169:9 [INFO] [stderr] 9: std::sys::backtrace::__rust_begin_short_backtrace [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/sys/backtrace.rs:158:18 [INFO] [stderr] 10: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}} [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/thread/mod.rs:559:17 [INFO] [stderr] 11: as core::ops::function::FnOnce<()>>::call_once [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stderr] 12: std::panicking::catch_unwind::do_call [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:590:40 [INFO] [stderr] 13: __rust_try [INFO] [stderr] 14: std::panicking::catch_unwind [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:553:19 [INFO] [stderr] std::panic::catch_unwind [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panic.rs:359:14 [INFO] [stderr] std::thread::Builder::spawn_unchecked_::{{closure}} [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/thread/mod.rs:557:30 [INFO] [stderr] 15: core::ops::function::FnOnce::call_once{{vtable.shim}} [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/ops/function.rs:253:5 [INFO] [stderr] 16: as core::ops::function::FnOnce>::call_once [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/alloc/src/boxed.rs:1985:9 [INFO] [stderr] std::sys::pal::unix::thread::Thread::new::thread_start [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stderr] 17: [INFO] [stderr] 18: clone [INFO] [stderr] [INFO] [stderr] [2025-09-01 08:09:50.105749][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 3.256628968s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_03 ... ok [INFO] [stderr] [2025-09-01 08:09:50.130238][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, Rx)) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1 ... FAILED [INFO] [stderr] [2025-09-01 08:09:51.107255][ERROR][test_blocking_async.rs:182] panic occur: panicked at src/tests/test_blocking_async.rs:182:21: [INFO] [stderr] error receiving on an empty and disconnected channel [INFO] [stderr] trace: 0: captains_log::log_impl::log_panic [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:340:14 [INFO] [stderr] 1: captains_log::log_impl::panic_and_exit_hook [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:352:5 [INFO] [stderr] core::ops::function::Fn::call [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/ops/function.rs:80:5 [INFO] [stderr] 2: as core::ops::function::Fn>::call [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/alloc/src/boxed.rs:1999:9 [INFO] [stderr] std::panicking::panic_with_hook [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:842:13 [INFO] [stderr] 3: std::panicking::panic_handler::{{closure}} [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:707:13 [INFO] [stderr] 4: std::sys::backtrace::__rust_end_short_backtrace [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/sys/backtrace.rs:174:18 [INFO] [stderr] 5: __rustc::rust_begin_unwind [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:698:5 [INFO] [stderr] 6: core::panicking::panic_fmt [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/panicking.rs:75:14 [INFO] [stderr] 7: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::{{closure}}::{{closure}} [INFO] [stderr] at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:182:21 [INFO] [stderr] 8: tokio::runtime::park::CachedParkThread::block_on::{{closure}} [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/park.rs:285:71 [INFO] [stderr] 9: tokio::task::coop::with_budget [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:167:5 [INFO] [stderr] tokio::task::coop::budget [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:133:5 [INFO] [stderr] tokio::runtime::park::CachedParkThread::block_on [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/park.rs:285:31 [INFO] [stderr] 10: tokio::runtime::context::blocking::BlockingRegionGuard::block_on [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/blocking.rs:66:14 [INFO] [stderr] 11: tokio::runtime::scheduler::multi_thread::MultiThread::block_on::{{closure}} [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/multi_thread/mod.rs:87:22 [INFO] [stderr] 12: tokio::runtime::context::runtime::enter_runtime [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/runtime.rs:65:16 [INFO] [stderr] 13: tokio::runtime::scheduler::multi_thread::MultiThread::block_on [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/multi_thread/mod.rs:86:9 [INFO] [stderr] 14: tokio::runtime::runtime::Runtime::block_on_inner [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:358:50 [INFO] [stderr] 15: tokio::runtime::runtime::Runtime::block_on [INFO] [stderr] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:330:18 [INFO] [stderr] 16: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::{{closure}} [INFO] [stderr] at /opt/rustwide/workdir/src/tests/common.rs:19:46 [INFO] [stderr] 17: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async [INFO] [stderr] at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:154:3 [INFO] [stderr] 18: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1 [INFO] [stderr] at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:148:1 [INFO] [stderr] 19: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1::{{closure}} [INFO] [stderr] at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:148:10 [INFO] [stderr] 20: core::ops::function::FnOnce::call_once [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/ops/function.rs:253:5 [INFO] [stderr] 21: core::ops::function::FnOnce::call_once [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/ops/function.rs:253:5 [INFO] [stderr] test::__rust_begin_short_backtrace [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/test/src/lib.rs:663:18 [INFO] [stderr] 22: test::run_test_in_process::{{closure}} [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/test/src/lib.rs:686:74 [INFO] [stderr] as core::ops::function::FnOnce<()>>::call_once [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stderr] std::panicking::catch_unwind::do_call [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:590:40 [INFO] [stderr] std::panicking::catch_unwind [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:553:19 [INFO] [stderr] std::panic::catch_unwind [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panic.rs:359:14 [INFO] [stderr] test::run_test_in_process [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/test/src/lib.rs:686:27 [INFO] [stderr] test::run_test::{{closure}} [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/test/src/lib.rs:607:43 [INFO] [stderr] 23: test::run_test::{{closure}} [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/test/src/lib.rs:637:41 [INFO] [stderr] std::sys::backtrace::__rust_begin_short_backtrace [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/sys/backtrace.rs:158:18 [INFO] [stderr] 24: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}} [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/thread/mod.rs:559:17 [INFO] [stderr] as core::ops::function::FnOnce<()>>::call_once [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stderr] std::panicking::catch_unwind::do_call [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:590:40 [INFO] [stderr] std::panicking::catch_unwind [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:553:19 [INFO] [stderr] std::panic::catch_unwind [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panic.rs:359:14 [INFO] [stderr] std::thread::Builder::spawn_unchecked_::{{closure}} [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/thread/mod.rs:557:30 [INFO] [stderr] core::ops::function::FnOnce::call_once{{vtable.shim}} [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/ops/function.rs:253:5 [INFO] [stderr] 25: as core::ops::function::FnOnce>::call_once [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/alloc/src/boxed.rs:1985:9 [INFO] [stderr] std::sys::pal::unix::thread::Thread::new::thread_start [INFO] [stderr] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stderr] 26: [INFO] [stderr] 27: clone [INFO] [stderr] [INFO] [stderr] [2025-09-01 08:09:51.154330][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-01 08:09:52.143254][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 2.012843191s >>> [INFO] [stderr] [2025-09-01 08:09:52.143666][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stderr] [2025-09-01 08:09:52.624508][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 1.470011975s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_08 ... ok [INFO] [stderr] [2025-09-01 08:09:52.628363][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_02 ... ok [INFO] [stderr] [2025-09-01 08:09:54.639631][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 8.028137858s >>> [INFO] [stderr] [2025-09-01 08:09:54.639984][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_06 ... ok [INFO] [stderr] [2025-09-01 08:09:56.398765][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 7.69677599s >>> [INFO] [stderr] [2025-09-01 08:09:56.399299][INFO][test_blocking_context.rs:282] <<< test_pressure_bounded_blocking_1_1 (setup_log = (), _channel = (MTx, MRx)) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_11 ... ok [INFO] [stderr] [2025-09-01 08:09:57.617450][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 2.977336056s >>> [INFO] [stderr] [2025-09-01 08:09:57.627218][INFO][test_blocking_context.rs:391] <<< 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-01 08:09:58.431187][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 2.03098359s >>> [INFO] [stderr] [2025-09-01 08:09:58.431802][INFO][test_blocking_context.rs:391] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 16, rx_count = 2) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_10 ... ok [INFO] [stderr] [2025-09-01 08:10:00.961418][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 8.332892208s >>> [INFO] [stderr] [2025-09-01 08:10:00.962406][INFO][test_blocking_context.rs:391] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 2, rx_count = 16) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_12 has been running for over 60 seconds [INFO] [stderr] [2025-09-01 08:10:10.145434][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 88.27408297s >>> [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_09 ... ok [INFO] [stderr] [2025-09-01 08:10:10.173059][INFO][test_blocking_context.rs:391] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 2, rx_count = 2) enter <<< [INFO] [stderr] [2025-09-01 08:10:11.293936][INFO][test_blocking_context.rs:391] >>> test_pressure_bounded_blocking_multi return () in 10.331385641s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_03 ... ok [INFO] [stderr] [2025-09-01 08:10:11.303419][INFO][test_blocking_context.rs:391] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 13, rx_count = 2) enter <<< [INFO] [stderr] [2025-09-01 08:10:20.768027][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 114.875551795s >>> [INFO] [stderr] [2025-09-01 08:10:20.954792][INFO][test_blocking_async.rs:231] >>> test_pressure_tx_multi_blocking_1_rx_async return () in 76.958388507s >>> [INFO] [stderr] [2025-09-01 08:10:21.149227][INFO][test_blocking_context.rs:391] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 3, rx_count = 10) enter <<< [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_03 ... ok [INFO] [stdout] test tests::test_blocking_async::test_pressure_tx_multi_blocking_1_rx_async::case_12 ... ok [INFO] [stderr] [2025-09-01 08:10:21.283066][INFO][test_blocking_context.rs:391] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 3, rx_count = 3) enter <<< [INFO] [stderr] [2025-09-01 08:10:25.863834][INFO][test_blocking_context.rs:391] >>> test_pressure_bounded_blocking_multi return () in 4.580590638s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_07 ... ok [INFO] [stderr] [2025-09-01 08:10:25.864300][INFO][test_blocking_context.rs:391] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 8, rx_count = 3) enter <<< [INFO] [stderr] [2025-09-01 08:10:26.233157][INFO][test_blocking_context.rs:391] >>> test_pressure_bounded_blocking_multi return () in 16.048265407s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_04 ... ok [INFO] [stderr] [2025-09-01 08:10:26.238241][INFO][test_blocking_context.rs:391] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 3, rx_count = 8) enter <<< [INFO] [stderr] [2025-09-01 08:10:33.051755][INFO][test_blocking_context.rs:391] >>> test_pressure_bounded_blocking_multi return () in 6.803588569s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_09 ... ok [INFO] [stderr] [2025-09-01 08:10:33.052247][INFO][test_blocking_context.rs:391] <<< test_pressure_bounded_blocking_multi (setup_log = (), _channel = (MTx, MRx), tx_count = 5, rx_count = 5) enter <<< [INFO] [stderr] [2025-09-01 08:10:38.873756][INFO][test_blocking_context.rs:391] >>> test_pressure_bounded_blocking_multi return () in 5.821305516s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_10 ... ok [INFO] [stderr] [2025-09-01 08:10:38.874357][INFO][test_blocking_context.rs:329] <<< 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_06 ... ok [INFO] [stderr] [2025-09-01 08:10:38.936849][INFO][test_blocking_context.rs:391] >>> test_pressure_bounded_blocking_multi return () in 17.73801684s >>> [INFO] [stderr] [2025-09-01 08:10:38.938225][INFO][test_blocking_context.rs:329] <<< 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_08 ... ok [INFO] [stderr] [2025-09-01 08:10:43.013736][INFO][test_blocking_context.rs:391] >>> test_pressure_bounded_blocking_multi return () in 17.149315558s >>> [INFO] [stderr] [2025-09-01 08:10:43.014197][INFO][test_blocking_context.rs:329] <<< 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_1_1::case_01 has been running for over 60 seconds [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_03 ... ok [INFO] [stderr] [2025-09-01 08:10:47.029008][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 188.173268893s >>> [INFO] [stderr] [2025-09-01 08:10:47.035312][INFO][test_blocking_context.rs:329] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 16) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_05 has been running for over 60 seconds [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_01 ... ok [INFO] [stderr] [2025-09-01 08:10:49.215866][INFO][test_blocking_context.rs:391] >>> test_pressure_bounded_blocking_multi return () in 51.58846804s >>> [INFO] [stderr] [2025-09-01 08:10:49.221220][INFO][test_blocking_context.rs:329] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 4) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_01 ... ok [INFO] [stderr] [2025-09-01 08:10:50.409503][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 11.534975981s >>> [INFO] [stderr] [2025-09-01 08:10:50.410210][INFO][test_blocking_context.rs:329] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 7) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_09 has been running for over 60 seconds [INFO] [stderr] [2025-09-01 08:10:56.867122][INFO][test_async_blocking.rs:276] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 173.444798786s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_6 ... ok [INFO] [stderr] [2025-09-01 08:10:56.868627][INFO][test_blocking_context.rs:329] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 12) enter <<< [INFO] [stderr] [2025-09-01 08:10:57.597592][INFO][test_async_blocking.rs:276] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 175.582528686s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_3 ... ok [INFO] [stderr] [2025-09-01 08:10:57.621619][INFO][test_blocking_context.rs:329] <<< 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_02 has been running for over 60 seconds [INFO] [stderr] [2025-09-01 08:10:59.393722][INFO][test_async.rs:705] >>> test_pressure_bounded_async_multi_1 return () in 199.016150044s >>> [INFO] [stdout] test tests::test_async::test_pressure_bounded_async_multi_1::case_12 ... ok [INFO] [stderr] [2025-09-01 08:10:59.398827][INFO][test_blocking_context.rs:329] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 9) enter <<< [INFO] [stderr] [2025-09-01 08:11:00.024524][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 72.376149269s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_05 ... ok [INFO] [stderr] [2025-09-01 08:11:00.024990][INFO][test_blocking_context.rs:329] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, Rx), tx_count = 13) enter <<< [INFO] [stderr] [2025-09-01 08:11:02.926220][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 23.987825143s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_02 ... ok [INFO] [stderr] [2025-09-01 08:11:02.931474][INFO][test_blocking_context.rs:329] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 2) enter <<< [INFO] [stderr] [2025-09-01 08:11:05.025830][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 7.40405156s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_08 ... ok [INFO] [stderr] [2025-09-01 08:11:05.026487][INFO][test_blocking_context.rs:329] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 5) enter <<< [INFO] [stderr] [2025-09-01 08:11:06.653727][INFO][test_blocking_context.rs:391] >>> test_pressure_bounded_blocking_multi return () in 55.350125669s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_05 ... ok [INFO] [stderr] [2025-09-01 08:11:06.654072][INFO][test_blocking_context.rs:329] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 15) enter <<< [INFO] [stderr] [2025-09-01 08:11:07.867048][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 18.64567752s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_05 ... ok [INFO] [stderr] [2025-09-01 08:11:07.867414][INFO][test_blocking_context.rs:329] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 3) enter <<< [INFO] [stderr] [2025-09-01 08:11:08.037645][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 81.519837297s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_01 ... ok [INFO] [stderr] [2025-09-01 08:11:08.046877][INFO][test_blocking_context.rs:329] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 7) enter <<< [INFO] [stderr] [2025-09-01 08:11:10.205384][INFO][test_blocking_context.rs:282] >>> test_pressure_bounded_blocking_1_1 return () in 78.061459918s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_1_1::case_09 ... ok [INFO] [stderr] [2025-09-01 08:11:10.207294][INFO][test_blocking_context.rs:329] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 16) enter <<< [INFO] [stderr] [2025-09-01 08:11:12.805267][INFO][test_blocking_context.rs:391] >>> test_pressure_bounded_blocking_multi return () in 74.373325192s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi::case_02 ... ok [INFO] [stderr] [2025-09-01 08:11:12.807192][INFO][test_blocking_context.rs:329] <<< test_pressure_bounded_blocking_multi_1 (setup_log = (), _channel = (MTx, MRx), tx_count = 2) enter <<< [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_06 ... ok [INFO] [stderr] [2025-09-01 08:11:16.748546][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 26.338225731s >>> [INFO] [stderr] [2025-09-01 08:11:16.749478][INFO][test_blocking_context.rs:329] <<< 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_17 ... ok [INFO] [stderr] [2025-09-01 08:11:18.198124][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 5.390821688s >>> [INFO] [stderr] [2025-09-01 08:11:18.199385][INFO][test_blocking_context.rs:329] <<< 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_11 ... ok [INFO] [stderr] [2025-09-01 08:11:23.862125][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 20.930502525s >>> [INFO] [stderr] [2025-09-01 08:11:23.862454][INFO][test_blocking_context.rs:451] <<< 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_09 ... ok [INFO] [stderr] [2025-09-01 08:11:24.236912][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 24.837948469s >>> [INFO] [stderr] [2025-09-01 08:11:27.026173][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 19.158605569s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_14 ... ok [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_12 ... ok [INFO] [stderr] [2025-09-01 08:11:35.921679][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 30.895081492s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_03 ... ok [INFO] [stderr] [2025-09-01 08:11:40.754432][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 57.740111073s >>> [INFO] [stderr] [2025-09-01 08:11:42.646959][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 34.599835346s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_15 ... ok [INFO] [stderr] [2025-09-01 08:11:45.980869][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 29.231251425s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_18 ... ok [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_04 has been running for over 60 seconds [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_4 ... ok [INFO] [stderr] [2025-09-01 08:11:48.104789][INFO][test_async_blocking.rs:276] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 225.716976068s >>> [INFO] [stderr] [2025-09-01 08:11:50.705190][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 50.680053739s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_10 ... ok [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_multi_rx_blocking::case_4 ... ok [INFO] [stderr] [2025-09-01 08:11:53.652345][INFO][test_async_blocking.rs:330] >>> test_pressure_multi_tx_async_multi_rx_blocking return () in 225.694280961s >>> [INFO] [stderr] [2025-09-01 08:11:55.915343][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 59.046580574s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_07 ... ok [INFO] [stderr] [2025-09-01 08:11:56.274510][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 38.075027073s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_19 ... ok [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_16 ... ok [INFO] [stderr] [2025-09-01 08:11:56.779044][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 46.571624875s >>> [INFO] [stderr] [2025-09-01 08:11:58.527910][INFO][test_async_blocking.rs:276] >>> test_pressure_multi_tx_async_1_rx_blocking return () in 233.568567894s >>> [INFO] [stdout] test tests::test_async_blocking::test_pressure_multi_tx_async_1_rx_blocking::case_8 ... ok [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_04 ... ok [INFO] [stderr] [2025-09-01 08:11:59.107845][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 72.072355609s >>> [INFO] [stderr] [2025-09-01 08:12:03.904255][INFO][test_blocking_context.rs:329] >>> test_pressure_bounded_blocking_multi_1 return () in 57.250059991s >>> [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_blocking_multi_1::case_13 ... ok [INFO] [stdout] test tests::test_blocking_context::test_pressure_bounded_timeout_blocking::case_1 ... ok [INFO] [stderr] [2025-09-01 08:12:07.391982][INFO][test_blocking_context.rs:451] >>> test_pressure_bounded_timeout_blocking return () in 43.529405454s >>> [INFO] [stdout] [INFO] [stderr] error: test failed, to rerun pass `--lib` [INFO] [stdout] failures: [INFO] [stdout] [INFO] [stdout] ---- tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1 stdout ---- [INFO] [stdout] log sinks opened [INFO] [stdout] panic occur: panicked at src/tests/test_blocking_async.rs:169:9: [INFO] [stdout] assertion failed: tx.send_timeout(10, Duration::from_millis(200)).is_ok() at Some(Location { file: "src/tests/test_blocking_async.rs", line: 169, column: 9 }) [INFO] [stdout] trace: 0: captains_log::log_impl::log_panic [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:340:14 [INFO] [stdout] 1: captains_log::log_impl::panic_and_exit_hook [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:352:5 [INFO] [stdout] core::ops::function::Fn::call [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/ops/function.rs:80:5 [INFO] [stdout] 2: as core::ops::function::Fn>::call [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] std::panicking::panic_with_hook [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:842:13 [INFO] [stdout] 3: std::panicking::panic_handler::{{closure}} [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:700:13 [INFO] [stdout] 4: std::sys::backtrace::__rust_end_short_backtrace [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 5: __rustc::rust_begin_unwind [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:698:5 [INFO] [stdout] 6: core::panicking::panic_fmt [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/panicking.rs:75:14 [INFO] [stdout] 7: core::panicking::panic [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/panicking.rs:145:5 [INFO] [stdout] 8: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::{{closure}}::{{closure}} [INFO] [stdout] at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:169:9 [INFO] [stdout] 9: std::sys::backtrace::__rust_begin_short_backtrace [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 10: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}} [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] 11: as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] 12: std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:590:40 [INFO] [stdout] 13: __rust_try [INFO] [stdout] 14: std::panicking::catch_unwind [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:553:19 [INFO] [stdout] std::panic::catch_unwind [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panic.rs:359:14 [INFO] [stdout] std::thread::Builder::spawn_unchecked_::{{closure}} [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] 15: core::ops::function::FnOnce::call_once{{vtable.shim}} [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 16: as core::ops::function::FnOnce>::call_once [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] std::sys::pal::unix::thread::Thread::new::thread_start [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 17: [INFO] [stdout] 18: clone [INFO] [stdout] panic occur: panicked at src/tests/test_blocking_async.rs:182:21: [INFO] [stdout] error receiving on an empty and disconnected channel at Some(Location { file: "src/tests/test_blocking_async.rs", line: 182, column: 21 }) [INFO] [stdout] trace: 0: captains_log::log_impl::log_panic [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:340:14 [INFO] [stdout] 1: captains_log::log_impl::panic_and_exit_hook [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/captains-log-0.8.6/src/log_impl.rs:352:5 [INFO] [stdout] core::ops::function::Fn::call [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/ops/function.rs:80:5 [INFO] [stdout] 2: as core::ops::function::Fn>::call [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/alloc/src/boxed.rs:1999:9 [INFO] [stdout] std::panicking::panic_with_hook [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:842:13 [INFO] [stdout] 3: std::panicking::panic_handler::{{closure}} [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:707:13 [INFO] [stdout] 4: std::sys::backtrace::__rust_end_short_backtrace [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/sys/backtrace.rs:174:18 [INFO] [stdout] 5: __rustc::rust_begin_unwind [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:698:5 [INFO] [stdout] 6: core::panicking::panic_fmt [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/panicking.rs:75:14 [INFO] [stdout] 7: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::{{closure}}::{{closure}} [INFO] [stdout] at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:182:21 [INFO] [stdout] 8: tokio::runtime::park::CachedParkThread::block_on::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/park.rs:285:71 [INFO] [stdout] 9: tokio::task::coop::with_budget [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:167:5 [INFO] [stdout] tokio::task::coop::budget [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:133:5 [INFO] [stdout] tokio::runtime::park::CachedParkThread::block_on [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/park.rs:285:31 [INFO] [stdout] 10: tokio::runtime::context::blocking::BlockingRegionGuard::block_on [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/blocking.rs:66:14 [INFO] [stdout] 11: tokio::runtime::scheduler::multi_thread::MultiThread::block_on::{{closure}} [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/multi_thread/mod.rs:87:22 [INFO] [stdout] 12: tokio::runtime::context::runtime::enter_runtime [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/runtime.rs:65:16 [INFO] [stdout] 13: tokio::runtime::scheduler::multi_thread::MultiThread::block_on [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/multi_thread/mod.rs:86:9 [INFO] [stdout] 14: tokio::runtime::runtime::Runtime::block_on_inner [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:358:50 [INFO] [stdout] 15: tokio::runtime::runtime::Runtime::block_on [INFO] [stdout] at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:330:18 [INFO] [stdout] 16: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::{{closure}} [INFO] [stdout] at /opt/rustwide/workdir/src/tests/common.rs:19:46 [INFO] [stdout] 17: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async [INFO] [stdout] at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:154:3 [INFO] [stdout] 18: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1 [INFO] [stdout] at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:148:1 [INFO] [stdout] 19: crossfire::tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1::{{closure}} [INFO] [stdout] at /opt/rustwide/workdir/src/tests/test_blocking_async.rs:148:10 [INFO] [stdout] 20: core::ops::function::FnOnce::call_once [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 21: core::ops::function::FnOnce::call_once [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/ops/function.rs:253:5 [INFO] [stdout] test::__rust_begin_short_backtrace [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/test/src/lib.rs:663:18 [INFO] [stdout] 22: test::run_test_in_process::{{closure}} [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/test/src/lib.rs:686:74 [INFO] [stdout] as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:590:40 [INFO] [stdout] std::panicking::catch_unwind [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:553:19 [INFO] [stdout] std::panic::catch_unwind [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panic.rs:359:14 [INFO] [stdout] test::run_test_in_process [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/test/src/lib.rs:686:27 [INFO] [stdout] test::run_test::{{closure}} [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/test/src/lib.rs:607:43 [INFO] [stdout] 23: test::run_test::{{closure}} [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/test/src/lib.rs:637:41 [INFO] [stdout] std::sys::backtrace::__rust_begin_short_backtrace [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/sys/backtrace.rs:158:18 [INFO] [stdout] 24: std::thread::Builder::spawn_unchecked_::{{closure}}::{{closure}} [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/thread/mod.rs:559:17 [INFO] [stdout] as core::ops::function::FnOnce<()>>::call_once [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/panic/unwind_safe.rs:272:9 [INFO] [stdout] std::panicking::catch_unwind::do_call [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:590:40 [INFO] [stdout] std::panicking::catch_unwind [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panicking.rs:553:19 [INFO] [stdout] std::panic::catch_unwind [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/panic.rs:359:14 [INFO] [stdout] std::thread::Builder::spawn_unchecked_::{{closure}} [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/thread/mod.rs:557:30 [INFO] [stdout] core::ops::function::FnOnce::call_once{{vtable.shim}} [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/core/src/ops/function.rs:253:5 [INFO] [stdout] 25: as core::ops::function::FnOnce>::call_once [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/alloc/src/boxed.rs:1985:9 [INFO] [stdout] std::sys::pal::unix::thread::Thread::new::thread_start [INFO] [stdout] at /rustc/b83b707f97d809763b7861afa7638871f3339a33/library/std/src/sys/pal/unix/thread.rs:118:17 [INFO] [stdout] 26: [INFO] [stdout] 27: clone [INFO] [stdout] [INFO] [stdout] [INFO] [stdout] failures: [INFO] [stdout] tests::test_blocking_async::test_timeout_1_tx_blocking_1_rx_async::case_1 [INFO] [stdout] [INFO] [stdout] test result: FAILED. 280 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 269.81s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "09fad370e9463ec444e39547d987648d39e0fc26cedd2b920aa895804390b2f9", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "09fad370e9463ec444e39547d987648d39e0fc26cedd2b920aa895804390b2f9", kill_on_drop: false }` [INFO] [stdout] 09fad370e9463ec444e39547d987648d39e0fc26cedd2b920aa895804390b2f9