[INFO] fetching crate links_blocking 0.2.12... [INFO] testing links_blocking-0.2.12 against master#562dee4820c458d823175268e41601d4c060588a for pr-154210-1 [INFO] extracting crate links_blocking 0.2.12 into /workspace/builds/worker-0-tc1/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-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate links_blocking 0.2.12 on toolchain 562dee4820c458d823175268e41601d4c060588a [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "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" "+562dee4820c458d823175268e41601d4c060588a" "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" "+562dee4820c458d823175268e41601d4c060588a" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded links_core v0.2.12 [INFO] [stderr] Downloaded byteserde v0.6.2 [INFO] [stderr] Downloaded byteserde_derive v0.6.2 [INFO] [stderr] Downloaded byteserde_types v0.6.2 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 7e2e99fb2edd176521d9f6c774ebe29e8bae413098db237b3221797442d336b7 [INFO] running `Command { std: "docker" "start" "-a" "7e2e99fb2edd176521d9f6c774ebe29e8bae413098db237b3221797442d336b7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "7e2e99fb2edd176521d9f6c774ebe29e8bae413098db237b3221797442d336b7", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7e2e99fb2edd176521d9f6c774ebe29e8bae413098db237b3221797442d336b7", kill_on_drop: false }` [INFO] [stdout] 7e2e99fb2edd176521d9f6c774ebe29e8bae413098db237b3221797442d336b7 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 95590fb3415bffc12c85bca4365ec2cdef8802955cf1763d0fb304ee5ce3ab71 [INFO] running `Command { std: "docker" "start" "-a" "95590fb3415bffc12c85bca4365ec2cdef8802955cf1763d0fb304ee5ce3ab71", 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.33s [INFO] running `Command { std: "docker" "inspect" "95590fb3415bffc12c85bca4365ec2cdef8802955cf1763d0fb304ee5ce3ab71", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "95590fb3415bffc12c85bca4365ec2cdef8802955cf1763d0fb304ee5ce3ab71", kill_on_drop: false }` [INFO] [stdout] 95590fb3415bffc12c85bca4365ec2cdef8802955cf1763d0fb304ee5ce3ab71 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 4fbc26f2d248d408ad5357d276af49e26413ad4b662b1031fed92e3d84fc929b [INFO] running `Command { std: "docker" "start" "-a" "4fbc26f2d248d408ad5357d276af49e26413ad4b662b1031fed92e3d84fc929b", kill_on_drop: false }` [INFO] [stderr] Compiling zerocopy v0.8.48 [INFO] [stderr] Compiling futures-core v0.3.32 [INFO] [stderr] Compiling futures-sink v0.3.32 [INFO] [stderr] Compiling regex-automata v0.4.14 [INFO] [stderr] Compiling either v1.15.0 [INFO] [stderr] Compiling anstream v1.0.0 [INFO] [stderr] Compiling zerocopy-derive v0.8.48 [INFO] [stderr] Compiling byteserde_derive v0.6.2 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling jiff v0.2.23 [INFO] [stderr] Compiling serde_json v1.0.149 [INFO] [stderr] Compiling clap_builder v4.6.0 [INFO] [stderr] Compiling num-format v0.4.4 [INFO] [stderr] Compiling colored v2.2.0 [INFO] [stderr] Compiling chrono v0.4.44 [INFO] [stderr] Compiling plotters v0.3.7 [INFO] [stderr] Compiling futures-channel v0.3.32 [INFO] [stderr] Compiling tokio v1.51.1 [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 ciborium v0.2.2 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling criterion v0.5.1 [INFO] [stderr] Compiling links_core v0.2.12 [INFO] [stderr] Compiling links_blocking v0.2.12 (/opt/rustwide/workdir) [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 23.79s [INFO] running `Command { std: "docker" "inspect" "4fbc26f2d248d408ad5357d276af49e26413ad4b662b1031fed92e3d84fc929b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "4fbc26f2d248d408ad5357d276af49e26413ad4b662b1031fed92e3d84fc929b", kill_on_drop: false }` [INFO] [stdout] 4fbc26f2d248d408ad5357d276af49e26413ad4b662b1031fed92e3d84fc929b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+562dee4820c458d823175268e41601d4c060588a" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] ebb4e482ec782627ccf6f0da31ec6c38878d8d5a3729d3f7ce9a4d34e5c6d72f [INFO] running `Command { std: "docker" "start" "-a" "ebb4e482ec782627ccf6f0da31ec6c38878d8d5a3729d3f7ce9a4d34e5c6d72f", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.17s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/links_blocking-cfdd529cffe0999b) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test connect::svc::test::test_svc_not_connected ... ok [INFO] [stderr] 2026-04-10T09:19:15.089836017Z INFO (connect::svc::test::test_svc_clt_connected) links_blocking::connect::svc::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2026-04-10T09:19:15.090060817Z INFO (connect::svc::test::test_svc_clt_connected) links_blocking::connect::svc::test clt: Clt127.0.0.1:19805), CltTestMessenger, 128> [INFO] [stderr] 2026-04-10T09:19:15.090151527Z DEBUG (connect::svc::test::test_svc_clt_connected) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:19805<-127.0.0.1:41460) Accepted [INFO] [stderr] 2026-04-10T09:19:15.090210077Z INFO (connect::svc::test::test_svc_clt_connected) links_blocking::connect::svc::test svc: Svc, RoundRobinPool]>> [INFO] [stderr] 2026-04-10T09:19:15.090299247Z INFO (connect::svc::test::test_svc_clt_connected) links_blocking::connect::svc::test --------- PRE SPLIT --------- [INFO] [stderr] 2026-04-10T09:19:15.090380907Z INFO (connect::svc::test::test_svc_clt_connected) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:41460->127.0.0.1:19805) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:15.090521777Z INFO (connect::svc::test::test_svc_clt_connected) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:19805<-127.0.0.1:41460) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:15.090662697Z INFO (connect::svc::test::test_svc_clt_connected) links_blocking::connect::svc::test --------- SVC SPLIT POOL --------- [INFO] [stderr] 2026-04-10T09:19:15.090785407Z INFO (connect::svc::test::test_svc_clt_connected) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:41460->127.0.0.1:19805) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:15.090973237Z INFO (connect::svc::test::test_svc_clt_connected) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:19805<-127.0.0.1:41460) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:15.090387117Z INFO (connect::svc::test::test_svc_not_connected) links_blocking::connect::svc::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2026-04-10T09:19:15.092874207Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2026-04-10T09:19:15.092996567Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test send_frame: [INFO] [stderr] 0000: 85 ce 5c fb 5d 00 97 f3 a0 3a fe f7 8a 81 84 52 | . Î \ û ] . . ó . : þ ÷ . . . R [INFO] [stderr] 0001: 38 01 9f 29 09 18 7e f3 9f 66 18 f8 70 a7 ed 0e | 8 . . ) . . . ó . f . ø p § í . [INFO] [stderr] 0002: e5 03 d2 3d d6 8c 1a 5f 4a 7e 4b 7c a7 f0 36 ea | å . Ò = Ö . . _ J . K | § ð 6 ê [INFO] [stderr] 0003: 82 56 ff 03 e8 25 d3 8d 6b 61 b6 64 a8 c2 cc 8b | . V ÿ . è % Ó . k a ¶ d ¨ Â Ì . [INFO] [stderr] 0004: 13 89 04 de b4 df b3 33 4c 7e 71 71 e1 1d c9 51 | . . . Þ ´ ß ³ 3 L . q q á . É Q [INFO] [stderr] 0005: df ba 18 65 75 7a 32 88 f5 04 b0 ec 2a b3 c6 f2 | ß º . e u z 2 . õ . ° ì * ³ Æ ò [INFO] [stderr] 0006: 82 f3 d4 b0 22 00 a7 57 5d 58 1d 73 a4 82 d9 85 | . ó Ô ° " . § W ] X . s ¤ . Ù . [INFO] [stderr] 0007: 38 62 2b 2e 87 e4 bd 22 d0 de ef f4 79 34 31 af | 8 b + . . ä ½ " Ð Þ ï ô y 4 1 ¯ [INFO] [stderr] [INFO] [stderr] 2026-04-10T09:19:15.093088817Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #0, clt: Clt127.0.0.1:57096), CltTestMessenger, 128> [INFO] [stderr] 2026-04-10T09:19:15.095734067Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:57096<-127.0.0.1:51670) Accepted [INFO] [stderr] 2026-04-10T09:19:15.095885507Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #1, clt: Clt127.0.0.1:57096), CltTestMessenger, 128> [INFO] [stderr] 2026-04-10T09:19:15.095965287Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:57096<-127.0.0.1:51686) Accepted [INFO] [stderr] 2026-04-10T09:19:15.096078727Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #2, clt: Clt127.0.0.1:57096), CltTestMessenger, 128> [INFO] [stderr] 2026-04-10T09:19:15.096174247Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:51692->127.0.0.1:57096), addr: connected, peer: connected, fd: 22 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.096272747Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:51692->127.0.0.1:57096), addr: connected, peer: connected, fd: 21 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.096359657Z 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:57096), CltTestMessenger, 128> will be dropped" } [INFO] [stderr] 2026-04-10T09:19:15.096456367Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:57096<-127.0.0.1:51692) Accepted [INFO] [stderr] 2026-04-10T09:19:15.096547257Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:57096<-127.0.0.1:51692), addr: connected, peer: disconnected, fd: 22 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.096654007Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:57096<-127.0.0.1:51692), addr: connected, peer: disconnected, fd: 21 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.096750617Z 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:15.096896557Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #3, clt: Clt127.0.0.1:57096), CltTestMessenger, 128> [INFO] [stderr] 2026-04-10T09:19:15.096985067Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:51696->127.0.0.1:57096), addr: connected, peer: connected, fd: 22 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.097079217Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:51696->127.0.0.1:57096), addr: connected, peer: connected, fd: 21 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.097162287Z 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:57096), CltTestMessenger, 128> will be dropped" } [INFO] [stderr] 2026-04-10T09:19:15.097252547Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:57096<-127.0.0.1:51696) Accepted [INFO] [stderr] 2026-04-10T09:19:15.097350907Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:57096<-127.0.0.1:51696), addr: connected, peer: disconnected, fd: 22 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.097435787Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:57096<-127.0.0.1:51696), addr: connected, peer: disconnected, fd: 21 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.097520937Z 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:15.097610537Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test clt_pool: RoundRobinPool127.0.0.1:57096), CltTestMessenger, 128>,Clt127.0.0.1:57096), CltTestMessenger, 128>]> [INFO] [stderr] 2026-04-10T09:19:15.097738407Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc_pool: RoundRobinPool,Clt]> [INFO] [stderr] 2026-04-10T09:19:15.097880557Z 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:15.097981627Z 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:15.098092627Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:51670->127.0.0.1:57096), addr: connected, peer: connected, fd: 12 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.098184417Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:51670->127.0.0.1:57096), addr: connected, peer: connected, fd: 11 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.098276017Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:51686->127.0.0.1:57096), addr: connected, peer: connected, fd: 18 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.098370737Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:51686->127.0.0.1:57096), addr: connected, peer: connected, fd: 17 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.098494937Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:57096<-127.0.0.1:51670), addr: connected, peer: disconnected, fd: 16 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.098587887Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:57096<-127.0.0.1:51670), addr: connected, peer: disconnected, fd: 15 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.101678267Z DEBUG (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt Initiator(unittest@pending->127.0.0.1:23892) connection failed. e: Os { code: 111, kind: ConnectionRefused, message: "Connection refused" } [INFO] [stderr] 2026-04-10T09:19:15.114826416Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:57096<-127.0.0.1:51686), addr: connected, peer: disconnected, fd: 20 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.114996976Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:57096<-127.0.0.1:51686), addr: connected, peer: disconnected, fd: 19 }::shutdown while disconnected how: Both, reason: drop [INFO] [stdout] test connect::pool::test::test_svcpool_cltpool_connected ... ok [INFO] [stderr] 2026-04-10T09:19:15.128760576Z DEBUG (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt Initiator(unittest@pending->127.0.0.1:23892) 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:15.139121436Z DEBUG (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt Initiator(unittest@pending->127.0.0.1:23892) connection failed. e: Os { code: 111, kind: ConnectionRefused, message: "Connection refused" } [INFO] [stderr] 2026-04-10T09:19:15.139267816Z 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:23892 } connect timeout: 50ms" }) [INFO] [stderr] 2026-04-10T09:19:15.200806445Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test clt: FrameWriter { Initiator(unittest@127.0.0.1:54998->127.0.0.1:56379), addr: connected, peer: connected, fd: 8 } [INFO] [stderr] 2026-04-10T09:19:15.201011305Z INFO (Thread-Svc ) links_blocking::core::framer::test svc: reader: FrameReader> { Acceptor(unittest@127.0.0.1:56379<-127.0.0.1:54998), addr: connected, peer: connected, fd: 12 } [INFO] [stderr] 2026-04-10T09:19:15.205807774Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test Initiator(unittest@127.0.0.1:36620->127.0.0.1:49560) MessageSender connected [INFO] [stderr] 2026-04-10T09:19:15.091109947Z INFO (connect::svc::test::test_svc_clt_connected) links_blocking::connect::svc::test --------- CLT SPLIT DIRECT --------- [INFO] [stderr] 2026-04-10T09:19:15.238405644Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:41460->127.0.0.1:19805) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:15.238584364Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:19805<-127.0.0.1:41460) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-04-10T09:19:15.238712974Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- CLT DROP RANDOM HALF --------- [INFO] [stderr] 2026-04-10T09:19:15.238766394Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test dropping clt_send [INFO] [stderr] 2026-04-10T09:19:15.238819904Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:41460->127.0.0.1:19805), addr: connected, peer: connected, fd: 4 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.238902534Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:41460->127.0.0.1:19805), addr: connected, peer: connected, fd: 5 }::shutdown how: Write, reason: read_frame EOF [INFO] [stderr] 2026-04-10T09:19:15.238987904Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test clt_recv opt: None [INFO] [stderr] 2026-04-10T09:19:15.239039894Z 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:15.239117064Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:19805<-127.0.0.1:41460), addr: connected, peer: disconnected, fd: 7 }::shutdown how: Write, reason: read_frame EOF [INFO] [stderr] 2026-04-10T09:19:15.239213784Z 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:15.239308104Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:19805<-127.0.0.1:41460), addr: connected, peer: disconnected, fd: 7 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.239407264Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test pool_recver opt: None [INFO] [stderr] 2026-04-10T09:19:15.239458944Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:19805<-127.0.0.1:41460), addr: connected, peer: disconnected, fd: 6 }::shutdown while disconnected how: Write, reason: write_frame error [INFO] [stderr] 2026-04-10T09:19:15.239561794Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:19805<-127.0.0.1:41460), addr: connected, peer: disconnected, fd: 6 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.239677814Z 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:19805<-127.0.0.1:41460) FrameWriter::write_frame caused by: [Broken pipe (os error 32)]) [INFO] [stderr] 2026-04-10T09:19:15.240379334Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:41460->127.0.0.1:19805), addr: connected, peer: disconnected, fd: 5 }::shutdown while disconnected how: Both, reason: drop [INFO] [stdout] test connect::svc::test::test_svc_clt_connected ... ok [INFO] [stderr] 2026-04-10T09:19:15.269614103Z INFO (Thread-Svc ) links_blocking::core::messenger::test Acceptor(unittest@127.0.0.1:49560<-127.0.0.1:36620) MessageSender connected [INFO] [stderr] 2026-04-10T09:19:15.551000027Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test dropping clt_reader [INFO] [stderr] 2026-04-10T09:19:15.553684767Z DEBUG (core::framer::test::test_reader ) links_blocking::core::framer FrameReader> { Initiator(unittest@127.0.0.1:54998->127.0.0.1:56379), addr: connected, peer: connected, fd: 11 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.553875077Z DEBUG (core::framer::test::test_reader ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:54998->127.0.0.1:56379), addr: connected, peer: connected, fd: 8 }::shutdown how: Write, reason: write_frame error [INFO] [stderr] 2026-04-10T09:19:15.554007497Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test clt_writer.write_frame() err: Initiator(unittest@127.0.0.1:54998->127.0.0.1:56379) FrameWriter::write_frame caused by: [Broken pipe (os error 32)] [INFO] [stderr] 2026-04-10T09:19:15.605285576Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameReader> { Acceptor(unittest@127.0.0.1:56379<-127.0.0.1:54998), addr: connected, peer: disconnected, fd: 12 }::shutdown how: Write, reason: read_frame EOF [INFO] [stdout] test core::framer::test::test_reader ... ok [INFO] [stderr] 2026-04-10T09:19:15.605439136Z INFO (Thread-Svc ) links_blocking::core::framer::test svc: read_frame is None, client closed connection [INFO] [stderr] 2026-04-10T09:19:15.605498886Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:56379<-127.0.0.1:54998), addr: connected, peer: disconnected, fd: 14 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.605601576Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameReader> { Acceptor(unittest@127.0.0.1:56379<-127.0.0.1:54998), addr: connected, peer: disconnected, fd: 12 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:15.605820696Z 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:15.605900316Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test per send elapsed: 3.498µs, total elapsed: 349.839632ms [INFO] [stderr] 2026-04-10T09:19:15.605964066Z DEBUG (core::framer::test::test_reader ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:54998->127.0.0.1:56379), addr: connected, peer: disconnected, fd: 8 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:18.639494892Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test dropping clt_recver [INFO] [stderr] 2026-04-10T09:19:18.639724992Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:49560<-127.0.0.1:36620), addr: connected, peer: disconnected, fd: 3 }::shutdown how: Write, reason: read_frame EOF [INFO] [stdout] test core::messenger::test::test_messenger ... ok [INFO] [stderr] 2026-04-10T09:19:18.639876072Z DEBUG (core::messenger::test::test_messenger ) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:36620->127.0.0.1:49560), addr: connected, peer: disconnected, fd: 16 }::shutdown how: Both, reason: drop [INFO] [stdout] [INFO] [stderr] 2026-04-10T09:19:18.640016992Z INFO (Thread-Svc ) links_blocking::core::messenger::test Acceptor { name: "unittest", local: 127.0.0.1:49560, peer: Some(127.0.0.1:36620) } MessageRecver Connection Closed by Client [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.55s [INFO] [stderr] 2026-04-10T09:19:18.640176812Z DEBUG (core::messenger::test::test_messenger ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:36620->127.0.0.1:49560), addr: connected, peer: disconnected, fd: 15 }::shutdown while disconnected how: Write, reason: write_frame error [INFO] [stdout] [INFO] [stderr] 2026-04-10T09:19:18.640325532Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:49560<-127.0.0.1:36620), addr: connected, peer: disconnected, fd: 10 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:18.640444632Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:49560<-127.0.0.1:36620), addr: connected, peer: disconnected, fd: 3 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-04-10T09:19:18.640717561Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test clt_sender.send(): Initiator(unittest@127.0.0.1:36620->127.0.0.1:49560) FrameWriter::write_frame caused by: [Broken pipe (os error 32)] [INFO] [stderr] 2026-04-10T09:19:18.640847772Z 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:18.640950581Z 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:18.641048081Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test per round trip elapsed: 34.335µs, total elapsed: 3.433561628s [INFO] [stderr] 2026-04-10T09:19:18.641154781Z DEBUG (core::messenger::test::test_messenger ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:36620->127.0.0.1:49560), addr: connected, peer: disconnected, fd: 15 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] Doc-tests links_blocking [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test src/connect/pool.rs - connect::pool::PoolCltAcceptor (line 375) - compile ... ok [INFO] [stdout] test src/connect/pool.rs - connect::pool::CltsPool (line 19) - 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::CltSendersPool (line 268) ... ok [INFO] [stdout] test src/connect/pool.rs - connect::pool::CltRecversPool (line 156) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.03s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "ebb4e482ec782627ccf6f0da31ec6c38878d8d5a3729d3f7ce9a4d34e5c6d72f", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "ebb4e482ec782627ccf6f0da31ec6c38878d8d5a3729d3f7ce9a4d34e5c6d72f", kill_on_drop: false }` [INFO] [stdout] ebb4e482ec782627ccf6f0da31ec6c38878d8d5a3729d3f7ce9a4d34e5c6d72f