[INFO] fetching crate links_blocking 0.2.12... [INFO] testing links_blocking-0.2.12 against master#d933cf483edf1605142ac6899ff32536c0ad8b22 for pr-150933 [INFO] extracting crate links_blocking 0.2.12 into /workspace/builds/worker-7-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-7-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate links_blocking 0.2.12 on toolchain d933cf483edf1605142ac6899ff32536c0ad8b22 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "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" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "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" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded byteserde_derive v0.6.2 [INFO] [stderr] Downloaded jiff-static v0.2.23 [INFO] [stderr] Downloaded jiff v0.2.23 [INFO] [stderr] Downloaded links_core v0.2.12 [INFO] [stderr] Downloaded byteserde_types v0.6.2 [INFO] [stderr] Downloaded byteserde v0.6.2 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] bc8303eaa60c395dba3c71733c1eb97b761209a5fab9895c4932dd099aa42136 [INFO] running `Command { std: "docker" "start" "-a" "bc8303eaa60c395dba3c71733c1eb97b761209a5fab9895c4932dd099aa42136", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "bc8303eaa60c395dba3c71733c1eb97b761209a5fab9895c4932dd099aa42136", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "bc8303eaa60c395dba3c71733c1eb97b761209a5fab9895c4932dd099aa42136", kill_on_drop: false }` [INFO] [stdout] bc8303eaa60c395dba3c71733c1eb97b761209a5fab9895c4932dd099aa42136 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] c9f371655ed437a4d7d0ad37493961ab9ca6d0c664d410a6854895be6387ddec [INFO] running `Command { std: "docker" "start" "-a" "c9f371655ed437a4d7d0ad37493961ab9ca6d0c664d410a6854895be6387ddec", kill_on_drop: false }` [INFO] [stderr] Compiling slab v0.4.12 [INFO] [stderr] Compiling byteserde v0.6.2 [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 1.31s [INFO] running `Command { std: "docker" "inspect" "c9f371655ed437a4d7d0ad37493961ab9ca6d0c664d410a6854895be6387ddec", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c9f371655ed437a4d7d0ad37493961ab9ca6d0c664d410a6854895be6387ddec", kill_on_drop: false }` [INFO] [stdout] c9f371655ed437a4d7d0ad37493961ab9ca6d0c664d410a6854895be6387ddec [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 49793fefa08e6ea4ef1d06ca33b5a559c42a492201ed12b5fd09cc389cf62dc8 [INFO] running `Command { std: "docker" "start" "-a" "49793fefa08e6ea4ef1d06ca33b5a559c42a492201ed12b5fd09cc389cf62dc8", kill_on_drop: false }` [INFO] [stderr] Compiling syn v2.0.117 [INFO] [stderr] Compiling zerocopy v0.8.40 [INFO] [stderr] Compiling aho-corasick v1.1.4 [INFO] [stderr] Compiling regex-syntax v0.8.10 [INFO] [stderr] Compiling futures-sink v0.3.32 [INFO] [stderr] Compiling futures-core v0.3.32 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling futures-io v0.3.32 [INFO] [stderr] Compiling getrandom v0.2.17 [INFO] [stderr] Compiling futures-task v0.3.32 [INFO] [stderr] Compiling jiff v0.2.23 [INFO] [stderr] Compiling clap_builder v4.5.60 [INFO] [stderr] Compiling num-format v0.4.4 [INFO] [stderr] Compiling serde_json v1.0.149 [INFO] [stderr] Compiling itertools v0.10.5 [INFO] [stderr] Compiling rayon v1.11.0 [INFO] [stderr] Compiling is-terminal v0.4.17 [INFO] [stderr] Compiling futures-channel v0.3.32 [INFO] [stderr] Compiling tokio v1.50.0 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling futures-util v0.3.32 [INFO] [stderr] Compiling criterion-plot v0.5.0 [INFO] [stderr] Compiling chrono v0.4.44 [INFO] [stderr] Compiling plotters v0.3.7 [INFO] [stderr] Compiling regex-automata v0.4.14 [INFO] [stderr] Compiling clap v4.5.60 [INFO] [stderr] Compiling zerocopy-derive v0.8.40 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling byteserde_derive v0.6.2 [INFO] [stderr] Compiling futures-executor v0.3.32 [INFO] [stderr] Compiling futures v0.3.32 [INFO] [stderr] Compiling regex v1.12.3 [INFO] [stderr] Compiling env_filter v1.0.0 [INFO] [stderr] Compiling env_logger v0.11.9 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling byteserde_types v0.6.2 [INFO] [stderr] Compiling tinytemplate v1.2.1 [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 ciborium v0.2.2 [INFO] [stderr] Compiling rand_chacha v0.3.1 [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 33.19s [INFO] running `Command { std: "docker" "inspect" "49793fefa08e6ea4ef1d06ca33b5a559c42a492201ed12b5fd09cc389cf62dc8", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "49793fefa08e6ea4ef1d06ca33b5a559c42a492201ed12b5fd09cc389cf62dc8", kill_on_drop: false }` [INFO] [stdout] 49793fefa08e6ea4ef1d06ca33b5a559c42a492201ed12b5fd09cc389cf62dc8 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:cfa559dde23f44738f9990739aaa3487d8df3af37eb5df0572f7cf76004a604a" "/opt/rustwide/cargo-home/bin/cargo" "+d933cf483edf1605142ac6899ff32536c0ad8b22" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 3185e66c383b2392e4a835829c6dcbe2f1b5cd84c1b120dc33cc8ae5c4280415 [INFO] running `Command { std: "docker" "start" "-a" "3185e66c383b2392e4a835829c6dcbe2f1b5cd84c1b120dc33cc8ae5c4280415", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.18s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/links_blocking-33d8c635010e6018) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stderr] 2026-03-07T11:27:27.610322249Z INFO (connect::svc::test::test_svc_clt_connected) links_blocking::connect::svc::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2026-03-07T11:27:27.610617359Z INFO (connect::svc::test::test_svc_clt_connected) links_blocking::connect::svc::test clt: Clt127.0.0.1:44094), CltTestMessenger, 128> [INFO] [stderr] 2026-03-07T11:27:27.612767918Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:44094<-127.0.0.1:46194) Accepted [INFO] [stderr] 2026-03-07T11:27:27.612891958Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test svc: Svc, RoundRobinPool]>> [INFO] [stderr] 2026-03-07T11:27:27.613039678Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- PRE SPLIT --------- [INFO] [stderr] 2026-03-07T11:27:27.613180469Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:46194->127.0.0.1:44094) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-03-07T11:27:27.613376898Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:44094<-127.0.0.1:46194) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-03-07T11:27:27.613541798Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- SVC SPLIT POOL --------- [INFO] [stderr] 2026-03-07T11:27:27.613679229Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:46194->127.0.0.1:44094) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-03-07T11:27:27.613905909Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:44094<-127.0.0.1:46194) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-03-07T11:27:27.614069738Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- CLT SPLIT DIRECT --------- [INFO] [stderr] 2026-03-07T11:27:27.614196109Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:46194->127.0.0.1:44094) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-03-07T11:27:27.614388718Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:44094<-127.0.0.1:46194) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2026-03-07T11:27:27.614556938Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- CLT DROP RANDOM HALF --------- [INFO] [stderr] 2026-03-07T11:27:27.614641409Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test dropping clt_recv [INFO] [stderr] 2026-03-07T11:27:27.614769838Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:46194->127.0.0.1:44094), addr: connected, peer: connected, fd: 5 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.614917418Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:46194->127.0.0.1:44094), addr: connected, peer: connected, fd: 4 }::shutdown how: Write, reason: write_frame error [INFO] [stderr] 2026-03-07T11:27:27.615060798Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test clt_send err: Initiator(unittest@127.0.0.1:46194->127.0.0.1:44094) FrameWriter::write_frame caused by: [Broken pipe (os error 32)] [INFO] [stderr] 2026-03-07T11:27:27.615177189Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- SVC RECV/SEND SHOULD FAIL CLT DROPS HALF --------- [INFO] [stderr] 2026-03-07T11:27:27.615278438Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:44094<-127.0.0.1:46194), addr: connected, peer: disconnected, fd: 9 }::shutdown how: Write, reason: read_frame EOF [INFO] [stderr] 2026-03-07T11:27:27.615414828Z 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-03-07T11:27:27.615552568Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:44094<-127.0.0.1:46194), addr: connected, peer: disconnected, fd: 9 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.617747748Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test pool_recver opt: None [INFO] [stdout] test connect::svc::test::test_svc_clt_connected ... ok [INFO] [stderr] 2026-03-07T11:27:27.617869358Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:44094<-127.0.0.1:46194), addr: connected, peer: disconnected, fd: 8 }::shutdown while disconnected how: Write, reason: write_frame error [INFO] [stderr] 2026-03-07T11:27:27.618025678Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:44094<-127.0.0.1:46194), addr: connected, peer: disconnected, fd: 8 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.618182118Z 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:44094<-127.0.0.1:46194) FrameWriter::write_frame caused by: [Broken pipe (os error 32)]) [INFO] [stderr] 2026-03-07T11:27:27.618350938Z DEBUG (connect::svc::test::test_svc_clt_connected ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:46194->127.0.0.1:44094), addr: connected, peer: disconnected, fd: 4 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.611997349Z INFO (connect::svc::test::test_svc_not_connected) links_blocking::connect::svc::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2026-03-07T11:27:27.612029369Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2026-03-07T11:27:27.612095098Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test send_frame: [INFO] [stdout] test connect::svc::test::test_svc_not_connected ... ok [INFO] [stderr] 0000: 1d ba c1 e6 66 ef 0c 87 9a f0 6d 77 49 4e ef 9a | . º Á æ f ï . . . ð m w I N ï . [INFO] [stderr] 0001: 99 7e e7 3c 7f da 05 bc 3e 62 68 71 6a 58 62 14 | . . ç < . Ú . ¼ > b h q j X b . [INFO] [stderr] 0002: 96 ef 32 44 7e 58 99 cc 18 a6 50 53 65 f7 f2 d6 | . ï 2 D . X . Ì . ¦ P S e ÷ ò Ö [INFO] [stderr] 0003: ef f3 27 5d ff bf 77 eb c3 ae 05 77 68 86 9f f1 | ï ó ' ] ÿ ¿ w ë Ã ® . w h . . ñ [INFO] [stderr] 0004: 06 01 80 dc 7b 7f 45 94 f5 6d 7a 5e 16 fe e3 88 | . . . Ü { . E . õ m z ^ . þ ã . [INFO] [stderr] 0005: ed ce c0 f7 f8 bf 6d 28 51 c7 20 54 43 ee bd 3d | í Î À ÷ ø ¿ m ( Q Ç . T C î ½ = [INFO] [stderr] 0006: d7 b5 5b 0a 23 1d e6 e4 48 30 ad 0f 0c 85 f5 db | × µ [ . # . æ ä H 0 ­ . . . õ Û [INFO] [stderr] 0007: 20 e9 72 7c a7 e2 c1 ac a7 35 ab 85 3a d9 5b ff | . é r | § â Á ¬ § 5 « . : Ù [ ÿ [INFO] [stderr] [INFO] [stderr] 2026-03-07T11:27:27.620133258Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #0, clt: Clt127.0.0.1:42122), CltTestMessenger, 128> [INFO] [stderr] 2026-03-07T11:27:27.620528928Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:42122<-127.0.0.1:39854) Accepted [INFO] [stderr] 2026-03-07T11:27:27.620773958Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #1, clt: Clt127.0.0.1:42122), CltTestMessenger, 128> [INFO] [stderr] 2026-03-07T11:27:27.620975678Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:42122<-127.0.0.1:39870) Accepted [INFO] [stderr] 2026-03-07T11:27:27.621186098Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #2, clt: Clt127.0.0.1:42122), CltTestMessenger, 128> [INFO] [stderr] 2026-03-07T11:27:27.621375478Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:39872->127.0.0.1:42122), addr: connected, peer: connected, fd: 13 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.621567488Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:39872->127.0.0.1:42122), addr: connected, peer: connected, fd: 12 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.621768648Z 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:42122), CltTestMessenger, 128> will be dropped" } [INFO] [stderr] 2026-03-07T11:27:27.622880288Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:42122<-127.0.0.1:39872) Accepted [INFO] [stderr] 2026-03-07T11:27:27.622899828Z DEBUG (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt Initiator(unittest@pending->127.0.0.1:36210) connection failed. e: Os { code: 111, kind: ConnectionRefused, message: "Connection refused" } [INFO] [stderr] 2026-03-07T11:27:27.623190068Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:42122<-127.0.0.1:39872), addr: connected, peer: disconnected, fd: 13 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.623534818Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:42122<-127.0.0.1:39872), addr: connected, peer: disconnected, fd: 12 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.623724258Z 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-03-07T11:27:27.623939108Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #3, clt: Clt127.0.0.1:42122), CltTestMessenger, 128> [INFO] [stderr] 2026-03-07T11:27:27.624082748Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:39882->127.0.0.1:42122), addr: connected, peer: connected, fd: 13 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.624213838Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:39882->127.0.0.1:42122), addr: connected, peer: connected, fd: 12 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.624354178Z 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:42122), CltTestMessenger, 128> will be dropped" } [INFO] [stderr] 2026-03-07T11:27:27.624500748Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::svc Acceptor(unittest@127.0.0.1:42122<-127.0.0.1:39882) Accepted [INFO] [stderr] 2026-03-07T11:27:27.624641678Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:42122<-127.0.0.1:39882), addr: connected, peer: disconnected, fd: 13 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.624810728Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:42122<-127.0.0.1:39882), addr: connected, peer: disconnected, fd: 12 }::shutdown while disconnected how: Both, reason: drop [INFO] [stdout] test connect::pool::test::test_svcpool_cltpool_connected ... ok [INFO] [stderr] 2026-03-07T11:27:27.624924148Z 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-03-07T11:27:27.625021468Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test clt_pool: RoundRobinPool127.0.0.1:42122), CltTestMessenger, 128>,Clt127.0.0.1:42122), CltTestMessenger, 128>]> [INFO] [stderr] 2026-03-07T11:27:27.625143408Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc_pool: RoundRobinPool,Clt]> [INFO] [stderr] 2026-03-07T11:27:27.625299008Z 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-03-07T11:27:27.625412398Z 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-03-07T11:27:27.625534228Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:39854->127.0.0.1:42122), addr: connected, peer: connected, fd: 4 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.625632258Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:39854->127.0.0.1:42122), addr: connected, peer: connected, fd: 3 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.625763618Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:39870->127.0.0.1:42122), addr: connected, peer: connected, fd: 9 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.625858248Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:39870->127.0.0.1:42122), addr: connected, peer: connected, fd: 8 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.625971638Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:42122<-127.0.0.1:39854), addr: connected, peer: disconnected, fd: 6 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.626060878Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:42122<-127.0.0.1:39854), addr: connected, peer: disconnected, fd: 5 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.626172868Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:42122<-127.0.0.1:39870), addr: connected, peer: disconnected, fd: 11 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.626265328Z DEBUG (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:42122<-127.0.0.1:39870), addr: connected, peer: disconnected, fd: 10 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:27.636753647Z DEBUG (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt Initiator(unittest@pending->127.0.0.1:36210) connection failed. e: Os { code: 111, kind: ConnectionRefused, message: "Connection refused" } [INFO] [stderr] 2026-03-07T11:27:27.647130616Z DEBUG (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt Initiator(unittest@pending->127.0.0.1:36210) connection failed. e: Os { code: 111, kind: ConnectionRefused, message: "Connection refused" } [INFO] [stderr] 2026-03-07T11:27:27.657516855Z DEBUG (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt Initiator(unittest@pending->127.0.0.1:36210) connection failed. e: Os { code: 111, kind: ConnectionRefused, message: "Connection refused" } [INFO] [stderr] 2026-03-07T11:27:27.687899493Z DEBUG (connect::clt::test::test_clt_not_connected ) links_blocking::connect::clt Initiator(unittest@pending->127.0.0.1:36210) connection failed. e: Os { code: 111, kind: ConnectionRefused, message: "Connection refused" } [INFO] [stderr] 2026-03-07T11:27:27.688050133Z 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:36210 } connect timeout: 50ms" }) [INFO] [stdout] test connect::clt::test::test_clt_not_connected ... ok [INFO] [stderr] 2026-03-07T11:27:27.721891861Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test clt: FrameWriter { Initiator(unittest@127.0.0.1:56210->127.0.0.1:37550), addr: connected, peer: connected, fd: 3 } [INFO] [stderr] 2026-03-07T11:27:27.725762581Z INFO (Thread-Svc ) links_blocking::core::framer::test svc: reader: FrameReader> { Acceptor(unittest@127.0.0.1:37550<-127.0.0.1:56210), addr: connected, peer: connected, fd: 5 } [INFO] [stderr] 2026-03-07T11:27:27.858890482Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test Initiator(unittest@127.0.0.1:54308->127.0.0.1:25466) MessageSender connected [INFO] [stderr] 2026-03-07T11:27:27.859091882Z INFO (Thread-Svc ) links_blocking::core::messenger::test Acceptor(unittest@127.0.0.1:25466<-127.0.0.1:54308) MessageSender connected [INFO] [stderr] 2026-03-07T11:27:27.999790912Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test dropping clt_reader [INFO] [stderr] 2026-03-07T11:27:27.999906163Z DEBUG (core::framer::test::test_reader ) links_blocking::core::framer FrameReader> { Initiator(unittest@127.0.0.1:56210->127.0.0.1:37550), addr: connected, peer: connected, fd: 4 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:28.000074912Z DEBUG (core::framer::test::test_reader ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:56210->127.0.0.1:37550), addr: connected, peer: connected, fd: 3 }::shutdown how: Write, reason: write_frame error [INFO] [stderr] 2026-03-07T11:27:28.000205683Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test clt_writer.write_frame() err: Initiator(unittest@127.0.0.1:56210->127.0.0.1:37550) FrameWriter::write_frame caused by: [Broken pipe (os error 32)] [INFO] [stderr] 2026-03-07T11:27:28.073242778Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameReader> { Acceptor(unittest@127.0.0.1:37550<-127.0.0.1:56210), addr: connected, peer: disconnected, fd: 5 }::shutdown how: Write, reason: read_frame EOF [INFO] [stderr] 2026-03-07T11:27:28.073509067Z INFO (Thread-Svc ) links_blocking::core::framer::test svc: read_frame is None, client closed connection [INFO] [stderr] 2026-03-07T11:27:28.073651498Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:37550<-127.0.0.1:56210), addr: connected, peer: disconnected, fd: 13 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:28.073835547Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameReader> { Acceptor(unittest@127.0.0.1:37550<-127.0.0.1:56210), addr: connected, peer: disconnected, fd: 5 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:28.074100678Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test frame_send_count: 100,000, frame_recv_count: 100,000 [INFO] [stderr] 2026-03-07T11:27:28.074312218Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test per send elapsed: 2.777µs, total elapsed: 277.731032ms [INFO] [stderr] 2026-03-07T11:27:28.074400587Z DEBUG (core::framer::test::test_reader ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:56210->127.0.0.1:37550), addr: connected, peer: disconnected, fd: 3 }::shutdown while disconnected how: Both, reason: drop [INFO] [stdout] test core::framer::test::test_reader ... ok [INFO] [stderr] 2026-03-07T11:27:31.150469141Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test dropping clt_recver [INFO] [stderr] 2026-03-07T11:27:31.150598821Z DEBUG (core::messenger::test::test_messenger ) links_blocking::core::framer FrameReader { Initiator(unittest@127.0.0.1:54308->127.0.0.1:25466), addr: connected, peer: connected, fd: 9 }::shutdown how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:31.150747111Z DEBUG (core::messenger::test::test_messenger ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:54308->127.0.0.1:25466), addr: connected, peer: connected, fd: 8 }::shutdown how: Write, reason: write_frame error [INFO] [stderr] 2026-03-07T11:27:31.150858761Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test clt_sender.send(): Initiator(unittest@127.0.0.1:54308->127.0.0.1:25466) FrameWriter::write_frame caused by: [Broken pipe (os error 32)] [INFO] [stderr] 2026-03-07T11:27:31.150984071Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:25466<-127.0.0.1:54308), addr: connected, peer: disconnected, fd: 10 }::shutdown how: Write, reason: read_frame EOF [INFO] [stderr] 2026-03-07T11:27:31.151089131Z INFO (Thread-Svc ) links_blocking::core::messenger::test Acceptor { name: "unittest", local: 127.0.0.1:25466, peer: Some(127.0.0.1:54308) } MessageRecver Connection Closed by Client [INFO] [stderr] 2026-03-07T11:27:31.151180120Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameWriter { Acceptor(unittest@127.0.0.1:25466<-127.0.0.1:54308), addr: connected, peer: disconnected, fd: 7 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:31.151277040Z DEBUG (Thread-Svc ) links_blocking::core::framer FrameReader { Acceptor(unittest@127.0.0.1:25466<-127.0.0.1:54308), addr: connected, peer: disconnected, fd: 10 }::shutdown while disconnected how: Both, reason: drop [INFO] [stderr] 2026-03-07T11:27:31.151469140Z 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-03-07T11:27:31.151535691Z 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-03-07T11:27:31.151602160Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test per round trip elapsed: 32.912µs, total elapsed: 3.291251569s [INFO] [stderr] 2026-03-07T11:27:31.151665271Z DEBUG (core::messenger::test::test_messenger ) links_blocking::core::framer FrameWriter { Initiator(unittest@127.0.0.1:54308->127.0.0.1:25466), addr: connected, peer: disconnected, fd: 8 }::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.56s [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/core/messenger.rs - core::messenger (line 8) - compile ... ok [INFO] [stdout] test src/connect/pool.rs - connect::pool::PoolCltAcceptor (line 375) - 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.36s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "3185e66c383b2392e4a835829c6dcbe2f1b5cd84c1b120dc33cc8ae5c4280415", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "3185e66c383b2392e4a835829c6dcbe2f1b5cd84c1b120dc33cc8ae5c4280415", kill_on_drop: false }` [INFO] [stdout] 3185e66c383b2392e4a835829c6dcbe2f1b5cd84c1b120dc33cc8ae5c4280415