[INFO] fetching crate links_blocking 0.2.12... [INFO] testing links_blocking-0.2.12 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-1 [INFO] extracting crate links_blocking 0.2.12 into /workspace/builds/worker-0-tc2/source [INFO] started tweaking crates.io crate links_blocking 0.2.12 [INFO] finished tweaking crates.io crate links_blocking 0.2.12 [INFO] tweaked toml for crates.io crate links_blocking 0.2.12 written to /workspace/builds/worker-0-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate links_blocking 0.2.12 on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }` [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 129 packages to latest compatible versions [INFO] [stderr] Adding criterion v0.5.1 (available: v0.8.2) [INFO] [stderr] Adding rand v0.8.5 (available: v0.10.0) [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 6d88e4729c0b28d610b3ddd5a6ba4c5ea156079764acbafad919977713f477a7 [INFO] running `Command { std: "docker" "start" "-a" "6d88e4729c0b28d610b3ddd5a6ba4c5ea156079764acbafad919977713f477a7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "6d88e4729c0b28d610b3ddd5a6ba4c5ea156079764acbafad919977713f477a7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "6d88e4729c0b28d610b3ddd5a6ba4c5ea156079764acbafad919977713f477a7", kill_on_drop: false }` [INFO] [stdout] 6d88e4729c0b28d610b3ddd5a6ba4c5ea156079764acbafad919977713f477a7 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 82d7478b868b253299c66090bac1193a1ea6755db03883f2e92681ed4f9b5658 [INFO] running `Command { std: "docker" "start" "-a" "82d7478b868b253299c66090bac1193a1ea6755db03883f2e92681ed4f9b5658", kill_on_drop: false }` [INFO] [stderr] Compiling byteserde v0.6.2 [INFO] [stderr] Compiling chrono v0.4.44 [INFO] [stderr] Compiling links_core v0.2.12 [INFO] [stderr] Compiling links_blocking v0.2.12 (/opt/rustwide/workdir) [INFO] [stderr] Finished `dev` profile [unoptimized + debuginfo] target(s) in 2.71s [INFO] running `Command { std: "docker" "inspect" "82d7478b868b253299c66090bac1193a1ea6755db03883f2e92681ed4f9b5658", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "82d7478b868b253299c66090bac1193a1ea6755db03883f2e92681ed4f9b5658", kill_on_drop: false }` [INFO] [stdout] 82d7478b868b253299c66090bac1193a1ea6755db03883f2e92681ed4f9b5658 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 76a6ecc82c82a155b1b8a6b3c66b31286192a8b485ceca002d3516e31d1a4385 [INFO] running `Command { std: "docker" "start" "-a" "76a6ecc82c82a155b1b8a6b3c66b31286192a8b485ceca002d3516e31d1a4385", kill_on_drop: false }` [INFO] [stderr] Compiling zerocopy v0.8.48 [INFO] [stderr] Compiling zerocopy-derive v0.8.48 [INFO] [stderr] Compiling regex-automata v0.4.14 [INFO] [stderr] Compiling futures-core v0.3.32 [INFO] [stderr] Compiling futures-sink v0.3.32 [INFO] [stderr] Compiling either v1.15.0 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling anstream v1.0.0 [INFO] [stderr] Compiling byteserde_derive v0.6.2 [INFO] [stderr] Compiling jiff v0.2.23 [INFO] [stderr] Compiling chrono v0.4.44 [INFO] [stderr] Compiling num-format v0.4.4 [INFO] [stderr] Compiling colored v2.2.0 [INFO] [stderr] Compiling clap_builder v4.6.0 [INFO] [stderr] Compiling serde_json v1.0.149 [INFO] [stderr] Compiling plotters v0.3.7 [INFO] [stderr] Compiling tokio v1.51.1 [INFO] [stderr] Compiling futures-channel v0.3.32 [INFO] [stderr] Compiling itertools v0.10.5 [INFO] [stderr] Compiling futures-util v0.3.32 [INFO] [stderr] Compiling rayon v1.11.0 [INFO] [stderr] Compiling tinytemplate v1.2.1 [INFO] [stderr] Compiling byteserde_types v0.6.2 [INFO] [stderr] Compiling criterion-plot v0.5.0 [INFO] [stderr] Compiling clap v4.6.0 [INFO] [stderr] Compiling regex v1.12.3 [INFO] [stderr] Compiling env_filter v1.0.1 [INFO] [stderr] Compiling futures-executor v0.3.32 [INFO] [stderr] Compiling futures v0.3.32 [INFO] [stderr] Compiling env_logger v0.11.10 [INFO] [stderr] Compiling ppv-lite86 v0.2.21 [INFO] [stderr] Compiling half v2.7.1 [INFO] [stderr] Compiling ciborium-ll v0.2.2 [INFO] [stderr] Compiling rand_chacha v0.3.1 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling ciborium v0.2.2 [INFO] [stderr] Compiling links_core v0.2.12 [INFO] [stderr] Compiling criterion v0.5.1 [INFO] [stderr] Compiling links_blocking v0.2.12 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 18.75s [INFO] running `Command { std: "docker" "inspect" "76a6ecc82c82a155b1b8a6b3c66b31286192a8b485ceca002d3516e31d1a4385", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "76a6ecc82c82a155b1b8a6b3c66b31286192a8b485ceca002d3516e31d1a4385", kill_on_drop: false }` [INFO] [stdout] 76a6ecc82c82a155b1b8a6b3c66b31286192a8b485ceca002d3516e31d1a4385 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 37ee9849fc7b6ca7f7bd41cd3f5c43a388e73651049ffd4ea599b46374225d49 [INFO] running `Command { std: "docker" "start" "-a" "37ee9849fc7b6ca7f7bd41cd3f5c43a388e73651049ffd4ea599b46374225d49", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.11s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/links_blocking-cfdd529cffe0999b) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stderr] 2026-04-10T09:19:45.335776128Z INFO (connect::svc::test::test_svc_clt_connected) links_blocking::connect::svc::test svc: Svc, RoundRobinPool> [INFO] [stdout] test connect::svc::test::test_svc_not_connected ... ok [INFO] [stderr] 2026-04-10T09:19:45.335878008Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2026-04-10T09:19:45.336047378Z INFO (connect::svc::test::test_svc_not_connected ) links_blocking::connect::svc::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2026-04-10T09:19:45.336086118Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test send_frame: [INFO] [stderr] 0000: 20 47 b8 3b 3d a5 33 fc 4d 50 c1 e3 55 ae 48 8c | . G ¸ ; = ¥ 3 ü M P Á ã U ® H . [INFO] [stderr] 0001: ac 7d 12 e7 73 c7 08 6f 32 d4 59 bf a6 62 b6 68 | ¬ } . ç s Ç . o 2 Ô Y ¿ ¦ b ¶ h [INFO] [stderr] 0002: 24 a2 43 6e 37 79 78 f8 82 9d a5 e5 87 65 b4 e7 | $ ¢ C n 7 y x ø . . ¥ å . e ´ ç [INFO] [stderr] 0003: b0 eb fb 9f e8 06 18 ae 77 6f 91 43 42 98 f3 d2 | ° ë û . è . . ® w o . C B . ó Ò [INFO] [stderr] 0004: 0e 66 e2 e8 bc c3 19 bb 77 0d 3e eb 93 89 51 39 | . f â è ¼ Ã . » w . > ë . . Q 9 [INFO] [stderr] 0005: 66 77 fd 54 9c 1d 36 e5 1f 9f 05 38 c5 71 7f a1 | f w ý T . . 6 å . . . 8 Å q . ¡ [INFO] [stderr] 0006: d7 d5 b1 aa d8 8e 20 bb fb e2 62 d5 80 19 17 85 | × Õ ± ª Ø . . » û â b Õ . . . . [INFO] [stderr] 0007: 22 98 60 a8 0b 09 ec a5 60 e4 05 b8 ee 2f 00 76 | " . ` ¨ . . ì ¥ ` ä . ¸ î / . v [INFO] [stderr] [INFO] [stderr] 2026-04-10T09:19:45.337225808Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test clt: Clt127.0.0.1:57906), CltTestMessenger, 128> [INFO] [stderr] 2026-04-10T09:19:45.338294088Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:57906<-127.0.0.1:48962) Accepted [INFO] [stderr] 2026-04-10T09:19:45.338371808Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test svc: Svc, RoundRobinPool]>> [INFO] [stderr] 2026-04-10T09:19:45.338472928Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- PRE SPLIT --------- [INFO] [stderr] 2026-04-10T09:19:45.338564958Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:48962->127.0.0.1:57906) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:45.339301058Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:57906<-127.0.0.1:48962) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:45.340769768Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- SVC SPLIT POOL --------- [INFO] [stderr] 2026-04-10T09:19:45.340901698Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:48962->127.0.0.1:57906) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:45.341059788Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:57906<-127.0.0.1:48962) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:45.341189518Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- CLT SPLIT DIRECT --------- [INFO] [stderr] 2026-04-10T09:19:45.341275438Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:48962->127.0.0.1:57906) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:45.341419608Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:57906<-127.0.0.1:48962) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:45.341549728Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- CLT DROP RANDOM HALF --------- [INFO] [stderr] 2026-04-10T09:19:45.341603658Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test dropping clt_recv [INFO] [stderr] 2026-04-10T09:19:45.339305688Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #0, clt: Clt127.0.0.1:32022), CltTestMessenger, 128> [INFO] [stderr] 2026-04-10T09:19:45.341792408Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:32022<-127.0.0.1:37352) Accepted [INFO] [stderr] 2026-04-10T09:19:45.341968398Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #1, clt: Clt127.0.0.1:32022), CltTestMessenger, 128> [INFO] [stderr] 2026-04-10T09:19:45.341679228Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:48962->127.0.0.1:57906), addr: connected, peer: connected, fd: 9 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.343076628Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:48962->127.0.0.1:57906), addr: connected, peer: connected, fd: 8 }::shutdown how: Write, reason: write_frame error [INFO] [stderr] 2026-04-10T09:19:45.343338158Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test clt_send err: Initiator(unittest@127.0.0.1:48962->127.0.0.1:57906) FrameWriter::write_frame caused by: [Broken pipe (os error 32)] [INFO] [stderr] 2026-04-10T09:19:45.343518478Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:32022<-127.0.0.1:37366) Accepted [INFO] [stderr] 2026-04-10T09:19:45.343624958Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- SVC RECV/SEND SHOULD FAIL CLT DROPS HALF --------- [INFO] [stderr] 2026-04-10T09:19:45.344380628Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #2, clt: Clt127.0.0.1:32022), CltTestMessenger, 128> [INFO] [stderr] 2026-04-10T09:19:45.344452008Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:57906<-127.0.0.1:48962), addr: connected, peer: disconnected, fd: 13 }::shutdown how: Write, reason: read_frame EOF [INFO] [stderr] 2026-04-10T09:19:45.344762418Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:37382->127.0.0.1:32022), addr: connected, peer: connected, fd: 21 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.345033548Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::pool recver: CltRecver is dead and will be dropped, connection reset by peer. recvers: RoundRobinPool [INFO] [stderr] 2026-04-10T09:19:45.345396058Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:37382->127.0.0.1:32022), addr: connected, peer: connected, fd: 20 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.345849858Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test clt_pool_err: Custom { kind: OutOfMemory, error: "RoundRobinPool at max capacity: 2, element: Clt127.0.0.1:32022), CltTestMessenger, 128> will be dropped" } [INFO] [stderr] 2026-04-10T09:19:45.345864248Z DEBUG (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt Initiator(unittest@pending->127.0.0.1:42727) connection failed. e: Os { code: 111, kind: ConnectionRefused, message: "Connection refused" } [INFO] [stderr] 2026-04-10T09:19:45.345924268Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:57906<-127.0.0.1:48962), addr: connected, peer: disconnected, fd: 13 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.346048528Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:32022<-127.0.0.1:37382) Accepted [INFO] [stderr] 2026-04-10T09:19:45.346369758Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:32022<-127.0.0.1:37382), addr: connected, peer: disconnected, fd: 13 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.346254938Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test pool_recver opt: None [INFO] [stderr] 2026-04-10T09:19:45.346553218Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:57906<-127.0.0.1:48962), addr: connected, peer: disconnected, fd: 12 }::shutdown while disconnected how: Write, reason: write_frame error [INFO] [stderr] 2026-04-10T09:19:45.346696358Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:57906<-127.0.0.1:48962), addr: connected, peer: disconnected, fd: 12 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.346810078Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test pool_sender err: sender: CltSender is dead and will be dropped, senders: RoundRobinPool. error: (Acceptor(unittest@127.0.0.1:57906<-127.0.0.1:48962) FrameWriter::write_frame caused by: [Broken pipe (os error 32)]) [INFO] [stderr] 2026-04-10T09:19:45.346926178Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:48962->127.0.0.1:57906), addr: connected, peer: disconnected, fd: 8 }::shutdown while disconnected how: Both, reason: drop [INFO] [stdout] test connect::svc::test::test_svc_clt_connected ... ok [INFO] [stderr] 2026-04-10T09:19:45.346472348Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:32022<-127.0.0.1:37382), addr: connected, peer: disconnected, fd: 20 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.348143688Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc_pool_err: Custom { kind: OutOfMemory, error: "RoundRobinPool at max capacity: 2, element: Clt will be dropped" } [INFO] [stderr] 2026-04-10T09:19:45.348312397Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #3, clt: Clt127.0.0.1:32022), CltTestMessenger, 128> [INFO] [stderr] 2026-04-10T09:19:45.348420817Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:37394->127.0.0.1:32022), addr: connected, peer: connected, fd: 8 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.348528068Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:37394->127.0.0.1:32022), addr: connected, peer: connected, fd: 3 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.348619328Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test clt_pool_err: Custom { kind: OutOfMemory, error: "RoundRobinPool at max capacity: 2, element: Clt127.0.0.1:32022), CltTestMessenger, 128> will be dropped" } [INFO] [stderr] 2026-04-10T09:19:45.349609057Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:32022<-127.0.0.1:37394) Accepted [INFO] [stderr] 2026-04-10T09:19:45.350246568Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:32022<-127.0.0.1:37394), addr: connected, peer: disconnected, fd: 8 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.350470468Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:32022<-127.0.0.1:37394), addr: connected, peer: disconnected, fd: 3 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.351216228Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc_pool_err: Custom { kind: OutOfMemory, error: "RoundRobinPool at max capacity: 2, element: Clt will be dropped" } [INFO] [stderr] 2026-04-10T09:19:45.351566038Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test clt_pool: RoundRobinPool127.0.0.1:32022), CltTestMessenger, 128>,Clt127.0.0.1:32022), CltTestMessenger, 128>]> [INFO] [stderr] 2026-04-10T09:19:45.352022377Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc_pool: RoundRobinPool,Clt]> [INFO] [stderr] 2026-04-10T09:19:45.352738397Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test clt_msg: Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:45.352894217Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc_msg: Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:45.353117108Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:37352->127.0.0.1:32022), addr: connected, peer: connected, fd: 14 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.353239457Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:37352->127.0.0.1:32022), addr: connected, peer: connected, fd: 5 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.353417857Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:37366->127.0.0.1:32022), addr: connected, peer: connected, fd: 18 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.354187648Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:37366->127.0.0.1:32022), addr: connected, peer: connected, fd: 17 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.354478028Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:32022<-127.0.0.1:37352), addr: connected, peer: disconnected, fd: 16 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.355321408Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:32022<-127.0.0.1:37352), addr: connected, peer: disconnected, fd: 15 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.355850208Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:32022<-127.0.0.1:37366), addr: connected, peer: disconnected, fd: 9 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.356106897Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:32022<-127.0.0.1:37366), addr: connected, peer: disconnected, fd: 19 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.356650157Z DEBUG (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt Initiator(unittest@pending->127.0.0.1:42727) connection failed. e: Os { code: 111, kind: ConnectionRefused, message: "Connection refused" } [INFO] [stdout] test connect::pool::test::test_svcpool_cltpool_connected ... ok [INFO] [stderr] 2026-04-10T09:19:45.368671447Z DEBUG (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt Initiator(unittest@pending->127.0.0.1:42727) connection failed. e: Os { code: 111, kind: ConnectionRefused, message: "Connection refused" } [INFO] [stderr] 2026-04-10T09:19:45.379023887Z DEBUG (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt Initiator(unittest@pending->127.0.0.1:42727) connection failed. e: Os { code: 111, kind: ConnectionRefused, message: "Connection refused" } [INFO] [stdout] test connect::clt::test::test_clt_not_connected ... ok [INFO] [stderr] 2026-04-10T09:19:45.389372277Z DEBUG (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt Initiator(unittest@pending->127.0.0.1:42727) connection failed. e: Os { code: 111, kind: ConnectionRefused, message: "Connection refused" } [INFO] [stderr] 2026-04-10T09:19:45.389523227Z INFO (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt::test res: Err(Custom { kind: TimedOut, error: "Initiator { name: \"unittest\", local: None, peer: 127.0.0.1:42727 } connect timeout: 50ms" }) [INFO] [stderr] 2026-04-10T09:19:45.435934556Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test Initiator(unittest@127.0.0.1:52516->127.0.0.1:23925) MessageSender connected [INFO] [stderr] 2026-04-10T09:19:45.435960246Z INFO (Thread-Svc ) links_blocking::core::messenger::test Acceptor(unittest@127.0.0.1:23925<-127.0.0.1:52516) MessageSender connected [INFO] [stderr] 2026-04-10T09:19:45.438109746Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test clt: FrameWriter { Initiator(unittest@127.0.0.1:38996->127.0.0.1:49050), addr: connected, peer: connected, fd: 8 } [INFO] [stderr] 2026-04-10T09:19:45.438124086Z INFO (Thread-Svc ) links_blocking::core::framer::test svc: reader: FrameReader> { Acceptor(unittest@127.0.0.1:49050<-127.0.0.1:38996), addr: connected, peer: connected, fd: 12 } [INFO] [stderr] 2026-04-10T09:19:45.633382302Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test dropping clt_reader [INFO] [stderr] 2026-04-10T09:19:45.634060891Z DEBUG (core::framer::test::test_reader ) links_blocking::core::framer FrameReader> { Initiator(unittest@127.0.0.1:38996->127.0.0.1:49050), addr: connected, peer: connected, fd: 9 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.635034182Z DEBUG (core::framer::test::test_reader ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:38996->127.0.0.1:49050), addr: connected, peer: connected, fd: 8 }::shutdown how: Write, reason: write_frame error [INFO] [stderr] 2026-04-10T09:19:45.635176382Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test clt_writer.write_frame() err: Initiator(unittest@127.0.0.1:38996->127.0.0.1:49050) FrameWriter::write_frame caused by: [Broken pipe (os error 32)] [INFO] [stderr] 2026-04-10T09:19:45.722357929Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameReader> { Acceptor(unittest@127.0.0.1:49050<-127.0.0.1:38996), addr: connected, peer: disconnected, fd: 12 }::shutdown how: Write, reason: read_frame EOF [INFO] [stderr] 2026-04-10T09:19:45.722906949Z INFO (Thread-Svc ) links_blocking::core::framer::test svc: read_frame is None, client closed connection [INFO] [stderr] 2026-04-10T09:19:45.723017560Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:49050<-127.0.0.1:38996), addr: connected, peer: disconnected, fd: 11 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.723153900Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameReader> { Acceptor(unittest@127.0.0.1:49050<-127.0.0.1:38996), addr: connected, peer: disconnected, fd: 12 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:45.723991469Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test frame_send_count: 100,000, frame_recv_count: 100,000 [INFO] [stderr] 2026-04-10T09:19:45.724101700Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test per send elapsed: 1.951µs, total elapsed: 195.127026ms [INFO] [stderr] 2026-04-10T09:19:45.724492020Z DEBUG (core::framer::test::test_reader ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:38996->127.0.0.1:49050), addr: connected, peer: disconnected, fd: 8 }::shutdown while disconnected how: Both, reason: drop [INFO] [stdout] test core::framer::test::test_reader ... ok [INFO] [stderr] 2026-04-10T09:19:49.225013775Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test dropping clt_recver [INFO] [stderr] 2026-04-10T09:19:49.225140905Z DEBUG (core::messenger::test::test_messenger ) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:52516->127.0.0.1:23925), addr: connected, peer: connected, fd: 4 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:49.225202265Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:23925<-127.0.0.1:52516), addr: connected, peer: disconnected, fd: 5 }::shutdown how: Write, reason: read_frame EOF [INFO] [stderr] 2026-04-10T09:19:49.225364145Z INFO (Thread-Svc ) links_blocking::core::messenger::test Acceptor { name: "unittest", local: 127.0.0.1:23925, peer: Some(127.0.0.1:52516) } MessageRecver Connection Closed by Client [INFO] [stderr] 2026-04-10T09:19:49.225264495Z DEBUG (core::messenger::test::test_messenger ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:52516->127.0.0.1:23925), addr: connected, peer: disconnected, fd: 3 }::shutdown while disconnected how: Write, reason: write_frame error [INFO] [stderr] 2026-04-10T09:19:49.225507465Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:23925<-127.0.0.1:52516), addr: connected, peer: disconnected, fd: 7 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:49.225648075Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test clt_sender.send(): Initiator(unittest@127.0.0.1:52516->127.0.0.1:23925) FrameWriter::write_frame caused by: [Broken pipe (os error 32)] [INFO] [stderr] 2026-04-10T09:19:49.225756265Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:23925<-127.0.0.1:52516), addr: connected, peer: disconnected, fd: 5 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:49.226105565Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test clt_msg_sent_count: 100,000, clt_msg_recv_count: 100,000 [INFO] [stderr] 2026-04-10T09:19:49.226214175Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test svc_msg_sent_count: 100,000, svc_msg_recv_count: 100,000 [INFO] [stderr] 2026-04-10T09:19:49.226278245Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test per round trip elapsed: 37.889µs, total elapsed: 3.788922129s [INFO] [stderr] 2026-04-10T09:19:49.226381045Z DEBUG (core::messenger::test::test_messenger ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:52516->127.0.0.1:23925), addr: connected, peer: disconnected, fd: 3 }::shutdown while disconnected how: Both, reason: drop [INFO] [stdout] test core::messenger::test::test_messenger ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.89s [INFO] [stdout] [INFO] [stderr] Doc-tests links_blocking [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test src/connect/pool.rs - connect::pool::CltsPool (line 19) - compile ... ok [INFO] [stdout] test src/connect/pool.rs - connect::pool::PoolCltAcceptor (line 375) - compile ... ok [INFO] [stdout] test src/core/messenger.rs - core::messenger (line 8) - compile ... ok [INFO] [stdout] test src/core/framer.rs - core::framer (line 8) ... ok [INFO] [stdout] test src/connect/pool.rs - connect::pool::CltRecversPool (line 156) ... ok [INFO] [stdout] test src/connect/pool.rs - connect::pool::CltSendersPool (line 268) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.67s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "37ee9849fc7b6ca7f7bd41cd3f5c43a388e73651049ffd4ea599b46374225d49", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "37ee9849fc7b6ca7f7bd41cd3f5c43a388e73651049ffd4ea599b46374225d49", kill_on_drop: false }` [INFO] [stdout] 37ee9849fc7b6ca7f7bd41cd3f5c43a388e73651049ffd4ea599b46374225d49