[INFO] fetching crate links_blocking 0.2.12... [INFO] testing links_blocking-0.2.12 against beta-2025-09-21 for beta-1.91-3 [INFO] extracting crate links_blocking 0.2.12 into /workspace/builds/worker-3-tc2/source [INFO] started tweaking crates.io crate links_blocking 0.2.12 [INFO] finished tweaking crates.io crate links_blocking 0.2.12 [INFO] tweaked toml for crates.io crate links_blocking 0.2.12 written to /workspace/builds/worker-3-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate links_blocking 0.2.12 on toolchain beta-2025-09-21 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+beta-2025-09-21" "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" "+beta-2025-09-21" "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" "+beta-2025-09-21" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:e90291280db7d1fac5b66fc6dad9f9662629e7365a55743daf9bdf73ebc4ea79" "/opt/rustwide/cargo-home/bin/cargo" "+beta-2025-09-21" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 9b97ed52df4ad1c4bdf805d387a03eefa349b3436400d75405aca11e3afd7b7b [INFO] running `Command { std: "docker" "start" "-a" "9b97ed52df4ad1c4bdf805d387a03eefa349b3436400d75405aca11e3afd7b7b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "9b97ed52df4ad1c4bdf805d387a03eefa349b3436400d75405aca11e3afd7b7b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "9b97ed52df4ad1c4bdf805d387a03eefa349b3436400d75405aca11e3afd7b7b", kill_on_drop: false }` [INFO] [stdout] 9b97ed52df4ad1c4bdf805d387a03eefa349b3436400d75405aca11e3afd7b7b [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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" "+beta-2025-09-21" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 7faa2265007d654aca46420710d533d23ec668ef0a2d91eefcd8cb9735c71676 [INFO] running `Command { std: "docker" "start" "-a" "7faa2265007d654aca46420710d533d23ec668ef0a2d91eefcd8cb9735c71676", 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 1.03s [INFO] running `Command { std: "docker" "inspect" "7faa2265007d654aca46420710d533d23ec668ef0a2d91eefcd8cb9735c71676", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "7faa2265007d654aca46420710d533d23ec668ef0a2d91eefcd8cb9735c71676", kill_on_drop: false }` [INFO] [stdout] 7faa2265007d654aca46420710d533d23ec668ef0a2d91eefcd8cb9735c71676 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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" "+beta-2025-09-21" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] b49486499bab72eb2f97a4996851805401c66315238cc37bfab18dcb51aa954e [INFO] running `Command { std: "docker" "start" "-a" "b49486499bab72eb2f97a4996851805401c66315238cc37bfab18dcb51aa954e", kill_on_drop: false }` [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling regex-automata v0.4.11 [INFO] [stderr] Compiling syn v2.0.106 [INFO] [stderr] Compiling anstream v0.6.20 [INFO] [stderr] Compiling num-format v0.4.4 [INFO] [stderr] Compiling colored v2.2.0 [INFO] [stderr] Compiling serde_json v1.0.145 [INFO] [stderr] Compiling clap_builder v4.5.48 [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 serde_derive v1.0.228 [INFO] [stderr] Compiling byteserde_derive v0.6.2 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling byteserde_types v0.6.2 [INFO] [stderr] Compiling ciborium v0.2.2 [INFO] [stderr] Compiling tinytemplate v1.2.1 [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 22.65s [INFO] running `Command { std: "docker" "inspect" "b49486499bab72eb2f97a4996851805401c66315238cc37bfab18dcb51aa954e", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "b49486499bab72eb2f97a4996851805401c66315238cc37bfab18dcb51aa954e", kill_on_drop: false }` [INFO] [stdout] b49486499bab72eb2f97a4996851805401c66315238cc37bfab18dcb51aa954e [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=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" "+beta-2025-09-21" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 0f30f4de6244aa590b7f578b9097ee987d57e0689edb5778416a9de6b910902b [INFO] running `Command { std: "docker" "start" "-a" "0f30f4de6244aa590b7f578b9097ee987d57e0689edb5778416a9de6b910902b", 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-feab4c022e184e1e) [INFO] [stderr] 2025-10-01T10:43:00.152428443Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2025-10-01T10:43:00.152714673Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #0, clt: Clt127.0.0.1:47647), CltTestMessenger, 128> [INFO] [stderr] 2025-10-01T10:43:00.152867043Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #1, clt: Clt127.0.0.1:47647), CltTestMessenger, 128> [INFO] [stderr] 2025-10-01T10:43:00.153012123Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #2, clt: Clt127.0.0.1:47647), CltTestMessenger, 128> [INFO] [stderr] 2025-10-01T10:43:00.152861543Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test send_frame: [INFO] [stderr] 0000: 5f 01 6f 9c a5 e8 f0 1a 0e de fa 1f ab fd 67 1d | _ . o . ¥ è ð . . Þ ú . « ý g . [INFO] [stderr] 0001: 80 d5 17 2a ec 4e 73 27 43 97 05 c2 aa 7d 4b ad | . Õ . * ì N s ' C . . Â ª } K ­ [INFO] [stderr] 0002: 70 7f 61 ef 31 57 f6 75 93 d4 b0 b0 9b 55 cf 0c | p . a ï 1 W ö u . Ô ° ° . U Ï . [INFO] [stderr] 0003: 32 0f e2 e9 e3 85 af 0f d1 99 89 eb 84 fc 43 0c | 2 . â é ã . ¯ . Ñ . . ë . ü C . [INFO] [stderr] 0004: 25 f9 15 0d 36 9e c8 8c 1a 7e 34 74 17 00 27 41 | % ù . . 6 . È . . . 4 t . . ' A [INFO] [stderr] 0005: e3 ef 5b f7 f3 7d df b6 f3 1d ea f3 90 fe b5 10 | ã ï [ ÷ ó } ß ¶ ó . ê ó . þ µ . [INFO] [stderr] 0006: 30 4f b1 62 e3 09 02 9b 31 69 5f dc 43 67 21 c2 | 0 O ± b ã . . . 1 i _ Ü C g . Â [INFO] [stderr] 0007: 5f 34 dc 62 af 33 df 10 39 96 d1 db 0c 43 68 83 | _ 4 Ü b ¯ 3 ß . 9 . Ñ Û . C h . [INFO] [stderr] [INFO] [stderr] 2025-10-01T10:43:00.153028403Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2025-10-01T10:43:00.152862383Z INFO (connect::svc::test::test_svc_not_connected ) links_blocking::connect::svc::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2025-10-01T10:43:00.153694953Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test clt: Clt127.0.0.1:54297), CltTestMessenger, 128> [INFO] [stderr] 2025-10-01T10:43:00.153846313Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test svc: Svc, RoundRobinPool]>> [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test connect::svc::test::test_svc_not_connected ... ok [INFO] [stderr] 2025-10-01T10:43:00.153133833Z 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:47647), CltTestMessenger, 128> will be dropped" } [INFO] [stderr] 2025-10-01T10:43:00.155705303Z 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:43:00.153998393Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- PRE SPLIT --------- [INFO] [stderr] 2025-10-01T10:43:00.155895863Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #3, clt: Clt127.0.0.1:47647), CltTestMessenger, 128> [INFO] [stderr] 2025-10-01T10:43:00.156002243Z 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:47647), CltTestMessenger, 128> will be dropped" } [INFO] [stderr] 2025-10-01T10:43:00.156120683Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:50428->127.0.0.1:54297) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-10-01T10:43:00.156158663Z 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:43:00.156446113Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test clt_pool: RoundRobinPool127.0.0.1:47647), CltTestMessenger, 128>,Clt127.0.0.1:47647), CltTestMessenger, 128>]> [INFO] [stderr] 2025-10-01T10:43:00.156578663Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc_pool: RoundRobinPool,Clt]> [INFO] [stderr] 2025-10-01T10:43:00.156438273Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:54297<-127.0.0.1:50428) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-10-01T10:43:00.160837332Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- SVC SPLIT POOL --------- [INFO] [stderr] 2025-10-01T10:43:00.156750953Z 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:43:00.161400642Z 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:43:00.161258202Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:50428->127.0.0.1:54297) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stdout] test connect::pool::test::test_svcpool_cltpool_connected ... ok [INFO] [stderr] 2025-10-01T10:43:00.162121742Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:54297<-127.0.0.1:50428) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-10-01T10:43:00.162649962Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- CLT SPLIT DIRECT --------- [INFO] [stderr] 2025-10-01T10:43:00.163228192Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:50428->127.0.0.1:54297) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-10-01T10:43:00.163507092Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:54297<-127.0.0.1:50428) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-10-01T10:43:00.163830342Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- CLT DROP RANDOM HALF --------- [INFO] [stderr] 2025-10-01T10:43:00.163950322Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test dropping clt_send [INFO] [stderr] 2025-10-01T10:43:00.164238632Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test clt_recv opt: None [INFO] [stderr] 2025-10-01T10:43:00.164670172Z 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:43:00.164960292Z 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:43:00.165231742Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test pool_recver opt: None [INFO] [stderr] 2025-10-01T10:43:00.165544382Z 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:54297<-127.0.0.1:50428) FrameWriter::write_frame caused by: [Broken pipe (os error 32)]) [INFO] [stdout] test connect::svc::test::test_svc_clt_connected ... ok [INFO] [stderr] 2025-10-01T10:43:00.204333787Z 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:60469 } connect timeout: 50ms" }) [INFO] [stdout] test connect::clt::test::test_clt_not_connected ... ok [INFO] [stderr] 2025-10-01T10:43:00.252753501Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test Initiator(unittest@127.0.0.1:40038->127.0.0.1:48444) MessageSender connected [INFO] [stderr] 2025-10-01T10:43:00.252770991Z INFO (Thread-Svc ) links_blocking::core::messenger::test Acceptor(unittest@127.0.0.1:48444<-127.0.0.1:40038) MessageSender connected [INFO] [stderr] 2025-10-01T10:43:00.253696001Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test clt: FrameWriter { Initiator(unittest@127.0.0.1:53320->127.0.0.1:6168), addr: connected, peer: connected, fd: 8 } [INFO] [stderr] 2025-10-01T10:43:00.253716241Z INFO (Thread-Svc ) links_blocking::core::framer::test svc: reader: FrameReader> { Acceptor(unittest@127.0.0.1:6168<-127.0.0.1:53320), addr: connected, peer: connected, fd: 10 } [INFO] [stderr] 2025-10-01T10:43:00.401926283Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test dropping clt_writer [INFO] [stderr] 2025-10-01T10:43:00.402899092Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test clt_reader.read_frame() opt: None [INFO] [stderr] 2025-10-01T10:43:00.439936838Z INFO (Thread-Svc ) links_blocking::core::framer::test svc: read_frame is None, client closed connection [INFO] [stderr] 2025-10-01T10:43:00.440693838Z 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:43:00.440777438Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test per send elapsed: 1.475µs, total elapsed: 147.512982ms [INFO] [stdout] test core::framer::test::test_reader ... ok [INFO] [stderr] 2025-10-01T10:43:03.791342359Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test dropping clt_recver [INFO] [stderr] 2025-10-01T10:43:03.791526879Z INFO (Thread-Svc ) links_blocking::core::messenger::test Acceptor { name: "unittest", local: 127.0.0.1:48444, peer: Some(127.0.0.1:40038) } MessageRecver Connection Closed by Client [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.64s [INFO] [stdout] [INFO] [stderr] 2025-10-01T10:43:03.791803679Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test clt_sender.send(): Initiator(unittest@127.0.0.1:40038->127.0.0.1:48444) FrameWriter::write_frame caused by: [Broken pipe (os error 32)] [INFO] [stderr] 2025-10-01T10:43:03.791919609Z 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:43:03.791987179Z 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:43:03.792050379Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test per round trip elapsed: 35.384µs, total elapsed: 3.538415808s [INFO] [stderr] Doc-tests links_blocking [INFO] [stdout] [INFO] [stdout] running 6 tests [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/connect/pool.rs - connect::pool::CltsPool (line 19) - 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 0.67s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "0f30f4de6244aa590b7f578b9097ee987d57e0689edb5778416a9de6b910902b", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "0f30f4de6244aa590b7f578b9097ee987d57e0689edb5778416a9de6b910902b", kill_on_drop: false }` [INFO] [stdout] 0f30f4de6244aa590b7f578b9097ee987d57e0689edb5778416a9de6b910902b