[INFO] fetching crate links_blocking 0.2.12... [INFO] testing links_blocking-0.2.12 against 1.90.0 for beta-1.91-3 [INFO] extracting crate links_blocking 0.2.12 into /workspace/builds/worker-3-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-3-tc1/source/Cargo.toml [INFO] validating manifest of crates.io crate links_blocking 0.2.12 on toolchain 1.90.0 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.90.0" "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" "+1.90.0" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 151 packages to latest compatible versions [INFO] [stderr] Adding criterion v0.5.1 (available: v0.7.0) [INFO] [stderr] Adding rand v0.8.5 (available: v0.9.2) [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.90.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Downloading crates ... [INFO] [stderr] Downloaded byteserde_types v0.6.2 [INFO] [stderr] Downloaded byteserde_derive v0.6.2 [INFO] [stderr] Downloaded byteserde v0.6.2 [INFO] [stderr] Downloaded links_core v0.2.12 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] c8749a03e96ca70dfa2168042adcde89c87bb0cff9e6523141436a7db43c4938 [INFO] running `Command { std: "docker" "start" "-a" "c8749a03e96ca70dfa2168042adcde89c87bb0cff9e6523141436a7db43c4938", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "c8749a03e96ca70dfa2168042adcde89c87bb0cff9e6523141436a7db43c4938", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "c8749a03e96ca70dfa2168042adcde89c87bb0cff9e6523141436a7db43c4938", kill_on_drop: false }` [INFO] [stdout] c8749a03e96ca70dfa2168042adcde89c87bb0cff9e6523141436a7db43c4938 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 44ab9f0af5a030b620fccd59c57dce0987508d767b3319438d2de58c3b05d73e [INFO] running `Command { std: "docker" "start" "-a" "44ab9f0af5a030b620fccd59c57dce0987508d767b3319438d2de58c3b05d73e", kill_on_drop: false }` [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 0.87s [INFO] running `Command { std: "docker" "inspect" "44ab9f0af5a030b620fccd59c57dce0987508d767b3319438d2de58c3b05d73e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "44ab9f0af5a030b620fccd59c57dce0987508d767b3319438d2de58c3b05d73e", kill_on_drop: false }` [INFO] [stdout] 44ab9f0af5a030b620fccd59c57dce0987508d767b3319438d2de58c3b05d73e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 0c0c6257621ee925e7761175a3a1189dff15911f6f5a23d9ba30e39420f5ad34 [INFO] running `Command { std: "docker" "start" "-a" "0c0c6257621ee925e7761175a3a1189dff15911f6f5a23d9ba30e39420f5ad34", kill_on_drop: false }` [INFO] [stderr] Compiling regex-automata v0.4.11 [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling anstream v0.6.20 [INFO] [stderr] Compiling syn v2.0.106 [INFO] [stderr] Compiling serde_json v1.0.145 [INFO] [stderr] Compiling colored v2.2.0 [INFO] [stderr] Compiling clap_builder v4.5.48 [INFO] [stderr] Compiling num-format v0.4.4 [INFO] [stderr] Compiling rand v0.8.5 [INFO] [stderr] Compiling tokio v1.47.1 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling clap v4.5.48 [INFO] [stderr] Compiling regex v1.11.3 [INFO] [stderr] Compiling env_filter v0.1.3 [INFO] [stderr] Compiling env_logger v0.11.8 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling byteserde_derive v0.6.2 [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 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 26.53s [INFO] running `Command { std: "docker" "inspect" "0c0c6257621ee925e7761175a3a1189dff15911f6f5a23d9ba30e39420f5ad34", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "0c0c6257621ee925e7761175a3a1189dff15911f6f5a23d9ba30e39420f5ad34", kill_on_drop: false }` [INFO] [stdout] 0c0c6257621ee925e7761175a3a1189dff15911f6f5a23d9ba30e39420f5ad34 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-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=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-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:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+1.90.0" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] e88ea5e229ece5407ed5fe6ed5dad4b6d55bff4f47a7b3fd6b8d1128b97ad012 [INFO] running `Command { std: "docker" "start" "-a" "e88ea5e229ece5407ed5fe6ed5dad4b6d55bff4f47a7b3fd6b8d1128b97ad012", kill_on_drop: false }` [INFO] [stderr] Finished `test` profile [unoptimized + debuginfo] target(s) in 0.16s [INFO] [stderr] Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/links_blocking-299319511e26448a) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stderr] 2025-10-01T10:42:27.943588047Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2025-10-01T10:42:27.944054877Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #0, clt: Clt127.0.0.1:29168), CltTestMessenger, 128> [INFO] [stderr] 2025-10-01T10:42:27.944181117Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #1, clt: Clt127.0.0.1:29168), CltTestMessenger, 128> [INFO] [stderr] 2025-10-01T10:42:27.944297157Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #2, clt: Clt127.0.0.1:29168), CltTestMessenger, 128> [INFO] [stderr] 2025-10-01T10:42:27.944396407Z 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:29168), CltTestMessenger, 128> will be dropped" } [INFO] [stderr] 2025-10-01T10:42:27.944527467Z 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] 2025-10-01T10:42:27.944681817Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #3, clt: Clt127.0.0.1:29168), CltTestMessenger, 128> [INFO] [stderr] 2025-10-01T10:42:27.944779797Z 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:29168), CltTestMessenger, 128> will be dropped" } [INFO] [stderr] 2025-10-01T10:42:27.944910007Z 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] 2025-10-01T10:42:27.944991487Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test clt_pool: RoundRobinPool127.0.0.1:29168), CltTestMessenger, 128>,Clt127.0.0.1:29168), CltTestMessenger, 128>]> [INFO] [stderr] 2025-10-01T10:42:27.945094847Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc_pool: RoundRobinPool,Clt]> [INFO] [stdout] test connect::pool::test::test_svcpool_cltpool_connected ... ok [INFO] [stderr] 2025-10-01T10:42:27.945220487Z 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] 2025-10-01T10:42:27.945324237Z 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] 2025-10-01T10:42:27.944689777Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test svc: Svc, RoundRobinPool> [INFO] [stdout] test connect::svc::test::test_svc_clt_connected ... ok [INFO] [stderr] 2025-10-01T10:42:27.946794876Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test clt: Clt127.0.0.1:64202), CltTestMessenger, 128> [INFO] [stderr] 2025-10-01T10:42:27.946896347Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test svc: Svc, RoundRobinPool]>> [INFO] [stderr] 2025-10-01T10:42:27.947006276Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- PRE SPLIT --------- [INFO] [stderr] 2025-10-01T10:42:27.947103876Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:60570->127.0.0.1:64202) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-10-01T10:42:27.947262276Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:64202<-127.0.0.1:60570) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-10-01T10:42:27.947393096Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- SVC SPLIT POOL --------- [INFO] [stderr] 2025-10-01T10:42:27.947508036Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:60570->127.0.0.1:64202) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-10-01T10:42:27.947683707Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:64202<-127.0.0.1:60570) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-10-01T10:42:27.947818136Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- CLT SPLIT DIRECT --------- [INFO] [stderr] 2025-10-01T10:42:27.947897546Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:60570->127.0.0.1:64202) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-10-01T10:42:27.948024016Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:64202<-127.0.0.1:60570) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-10-01T10:42:27.948150986Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- CLT DROP RANDOM HALF --------- [INFO] [stderr] 2025-10-01T10:42:27.948211026Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test dropping clt_recv [INFO] [stderr] 2025-10-01T10:42:27.948318906Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test clt_send err: Initiator(unittest@127.0.0.1:60570->127.0.0.1:64202) FrameWriter::write_frame caused by: [Broken pipe (os error 32)] [INFO] [stderr] 2025-10-01T10:42:27.948383966Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- SVC RECV/SEND SHOULD FAIL CLT DROPS HALF --------- [INFO] [stderr] 2025-10-01T10:42:27.948451946Z 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] 2025-10-01T10:42:27.948541776Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test pool_recver opt: None [INFO] [stderr] 2025-10-01T10:42:27.948657426Z 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:64202<-127.0.0.1:60570) FrameWriter::write_frame caused by: [Broken pipe (os error 32)]) [INFO] [stderr] 2025-10-01T10:42:27.951932546Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test send_frame: [INFO] [stderr] 0000: ba 84 af 7b 04 a3 3f af eb 34 04 3b 92 be 1c 46 | º . ¯ { . £ ? ¯ ë 4 . ; . ¾ . F [INFO] [stderr] 0001: a7 62 4d 66 7b 12 d5 b3 35 9c 10 03 d0 a6 c0 dd | § b M f { . Õ ³ 5 . . . Ð ¦ À Ý [INFO] [stderr] 0002: 44 11 07 45 c3 76 b5 f3 b8 04 81 0a 62 a5 b2 89 | D . . E Ã v µ ó ¸ . . . b ¥ ² . [INFO] [stderr] 0003: 00 3d ba 84 19 ce ea f8 d7 96 7d 6a 66 dd fc dd | . = º . . Î ê ø × . } j f Ý ü Ý [INFO] [stderr] 0004: b7 67 e5 fc ac 8f fc 42 bd 96 1e 55 69 43 43 2e | · g å ü ¬ . ü B ½ . . U i C C . [INFO] [stderr] 0005: 2c 6c be 44 52 d7 21 cc 8b d0 30 32 b1 52 a7 b6 | , l ¾ D R × . Ì . Ð 0 2 ± R § ¶ [INFO] [stderr] 0006: 64 9b 03 89 66 bc dc 10 eb 5a 2e 99 44 45 9f f5 | d . . . f ¼ Ü . ë Z . . D E . õ [INFO] [stderr] 0007: 8b af 83 aa 7d 09 a1 66 74 b4 17 b3 1a 7e a0 65 | . ¯ . ª } . ¡ f t ´ . ³ . . . e [INFO] [stderr] [INFO] [stderr] 2025-10-01T10:42:27.957903345Z INFO (connect::svc::test::test_svc_not_connected ) links_blocking::connect::svc::test svc: Svc, RoundRobinPool> [INFO] [stdout] test connect::svc::test::test_svc_not_connected ... ok [INFO] [stdout] test connect::clt::test::test_clt_not_connected ... ok [INFO] [stderr] 2025-10-01T10:42:27.994805401Z 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:31430 } connect timeout: 50ms" }) [INFO] [stderr] 2025-10-01T10:42:28.044474304Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test Initiator(unittest@127.0.0.1:47292->127.0.0.1:47695) MessageSender connected [INFO] [stderr] 2025-10-01T10:42:28.044481435Z INFO (Thread-Svc ) links_blocking::core::messenger::test Acceptor(unittest@127.0.0.1:47695<-127.0.0.1:47292) MessageSender connected [INFO] [stderr] 2025-10-01T10:42:28.054020454Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test clt: FrameWriter { Initiator(unittest@127.0.0.1:45736->127.0.0.1:27945), addr: connected, peer: connected, fd: 8 } [INFO] [stderr] 2025-10-01T10:42:28.054046894Z INFO (Thread-Svc ) links_blocking::core::framer::test svc: reader: FrameReader> { Acceptor(unittest@127.0.0.1:27945<-127.0.0.1:45736), addr: connected, peer: connected, fd: 10 } [INFO] [stderr] 2025-10-01T10:42:28.372031044Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test dropping clt_writer [INFO] [stderr] 2025-10-01T10:42:28.372670195Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test clt_reader.read_frame() opt: None [INFO] [stderr] 2025-10-01T10:42:28.416467109Z INFO (Thread-Svc ) links_blocking::core::framer::test svc: read_frame is None, client closed connection [INFO] [stderr] 2025-10-01T10:42:28.425674148Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test frame_send_count: 100,000, frame_recv_count: 100,000 [INFO] [stderr] 2025-10-01T10:42:28.425861918Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test per send elapsed: 3.178µs, total elapsed: 317.84303ms [INFO] [stdout] test core::framer::test::test_reader ... ok [INFO] [stderr] 2025-10-01T10:42:31.290537248Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test dropping clt_recver [INFO] [stderr] 2025-10-01T10:42:31.290715278Z INFO (Thread-Svc ) links_blocking::core::messenger::test Acceptor { name: "unittest", local: 127.0.0.1:47695, peer: Some(127.0.0.1:47292) } MessageRecver Connection Closed by Client [INFO] [stderr] 2025-10-01T10:42:31.290957648Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test clt_sender.send(): Initiator(unittest@127.0.0.1:47292->127.0.0.1:47695) FrameWriter::write_frame caused by: [Broken pipe (os error 32)] [INFO] [stderr] 2025-10-01T10:42:31.291106038Z 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] 2025-10-01T10:42:31.291207298Z 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] 2025-10-01T10:42:31.291291978Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test per round trip elapsed: 32.458µs, total elapsed: 3.245871633s [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.35s [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::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.14s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "e88ea5e229ece5407ed5fe6ed5dad4b6d55bff4f47a7b3fd6b8d1128b97ad012", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "e88ea5e229ece5407ed5fe6ed5dad4b6d55bff4f47a7b3fd6b8d1128b97ad012", kill_on_drop: false }` [INFO] [stdout] e88ea5e229ece5407ed5fe6ed5dad4b6d55bff4f47a7b3fd6b8d1128b97ad012