[INFO] fetching crate links_blocking 0.2.12... [INFO] testing links_blocking-0.2.12 against try#c2e32f1c9652b13ed99608599c1e855462f421f3 for pr-146098-8 [INFO] extracting crate links_blocking 0.2.12 into /workspace/builds/worker-0-tc2/source [INFO] started tweaking crates.io crate links_blocking 0.2.12 [INFO] finished tweaking crates.io crate links_blocking 0.2.12 [INFO] tweaked toml for crates.io crate links_blocking 0.2.12 written to /workspace/builds/worker-0-tc2/source/Cargo.toml [INFO] validating manifest of crates.io crate links_blocking 0.2.12 on toolchain c2e32f1c9652b13ed99608599c1e855462f421f3 [INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+c2e32f1c9652b13ed99608599c1e855462f421f3" "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" "+c2e32f1c9652b13ed99608599c1e855462f421f3" "generate-lockfile" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] [stderr] Updating crates.io index [INFO] [stderr] Locking 130 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" "+c2e32f1c9652b13ed99608599c1e855462f421f3" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }` [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c2e32f1c9652b13ed99608599c1e855462f421f3" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }` [INFO] [stdout] 67557ea91405dc7883a722c00181d6567e3b2904f8bef7bed40f53e054df5bc4 [INFO] running `Command { std: "docker" "start" "-a" "67557ea91405dc7883a722c00181d6567e3b2904f8bef7bed40f53e054df5bc4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "inspect" "67557ea91405dc7883a722c00181d6567e3b2904f8bef7bed40f53e054df5bc4", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "67557ea91405dc7883a722c00181d6567e3b2904f8bef7bed40f53e054df5bc4", kill_on_drop: false }` [INFO] [stdout] 67557ea91405dc7883a722c00181d6567e3b2904f8bef7bed40f53e054df5bc4 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c2e32f1c9652b13ed99608599c1e855462f421f3" "build" "--frozen" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] 19ad0b3d35bf691ccdb96d4d83dfd35463a4bd548c0277a99940ca010ecf6212 [INFO] running `Command { std: "docker" "start" "-a" "19ad0b3d35bf691ccdb96d4d83dfd35463a4bd548c0277a99940ca010ecf6212", kill_on_drop: false }` [INFO] [stderr] Compiling autocfg v1.5.0 [INFO] [stderr] Compiling scopeguard v1.2.0 [INFO] [stderr] Compiling bytes v1.10.1 [INFO] [stderr] Compiling iana-time-zone v0.1.64 [INFO] [stderr] Compiling slab v0.4.11 [INFO] [stderr] Compiling log v0.4.28 [INFO] [stderr] Compiling lock_api v0.4.14 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling spin v0.9.8 [INFO] [stderr] Compiling byteserde v0.6.2 [INFO] [stderr] Compiling chrono v0.4.42 [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 4.72s [INFO] running `Command { std: "docker" "inspect" "19ad0b3d35bf691ccdb96d4d83dfd35463a4bd548c0277a99940ca010ecf6212", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "19ad0b3d35bf691ccdb96d4d83dfd35463a4bd548c0277a99940ca010ecf6212", kill_on_drop: false }` [INFO] [stdout] 19ad0b3d35bf691ccdb96d4d83dfd35463a4bd548c0277a99940ca010ecf6212 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c2e32f1c9652b13ed99608599c1e855462f421f3" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }` [INFO] [stdout] bde1b5d06f6bfa7c5c4a594d7d6e5f1030ece060e5d2822a9fe96ec3b299c413 [INFO] running `Command { std: "docker" "start" "-a" "bde1b5d06f6bfa7c5c4a594d7d6e5f1030ece060e5d2822a9fe96ec3b299c413", kill_on_drop: false }` [INFO] [stderr] Compiling proc-macro2 v1.0.103 [INFO] [stderr] Compiling quote v1.0.42 [INFO] [stderr] Compiling memchr v2.7.6 [INFO] [stderr] Compiling zerocopy v0.8.27 [INFO] [stderr] Compiling cfg-if v1.0.4 [INFO] [stderr] Compiling serde_core v1.0.228 [INFO] [stderr] Compiling serde v1.0.228 [INFO] [stderr] Compiling crossbeam-utils v0.8.21 [INFO] [stderr] Compiling anstyle v1.0.13 [INFO] [stderr] Compiling regex-syntax v0.8.8 [INFO] [stderr] Compiling itoa v1.0.15 [INFO] [stderr] Compiling utf8parse v0.2.2 [INFO] [stderr] Compiling pin-project-lite v0.2.16 [INFO] [stderr] Compiling num-traits v0.2.19 [INFO] [stderr] Compiling futures-core v0.3.31 [INFO] [stderr] Compiling futures-sink v0.3.31 [INFO] [stderr] Compiling pin-utils v0.1.0 [INFO] [stderr] Compiling getrandom v0.2.16 [INFO] [stderr] Compiling anstyle-parse v0.2.7 [INFO] [stderr] Compiling futures-io v0.3.31 [INFO] [stderr] Compiling futures-channel v0.3.31 [INFO] [stderr] Compiling log v0.4.28 [INFO] [stderr] Compiling either v1.15.0 [INFO] [stderr] Compiling anstyle-query v1.1.5 [INFO] [stderr] Compiling serde_json v1.0.145 [INFO] [stderr] Compiling futures-task v0.3.31 [INFO] [stderr] Compiling colorchoice v1.0.4 [INFO] [stderr] Compiling rayon-core v1.13.0 [INFO] [stderr] Compiling is_terminal_polyfill v1.70.2 [INFO] [stderr] Compiling plotters-backend v0.3.7 [INFO] [stderr] Compiling lazy_static v1.5.0 [INFO] [stderr] Compiling anstream v0.6.21 [INFO] [stderr] Compiling rand_core v0.6.4 [INFO] [stderr] Compiling arrayvec v0.7.6 [INFO] [stderr] Compiling ciborium-io v0.2.2 [INFO] [stderr] Compiling ryu v1.0.20 [INFO] [stderr] Compiling clap_lex v0.7.6 [INFO] [stderr] Compiling jiff v0.2.16 [INFO] [stderr] Compiling crossbeam-epoch v0.9.18 [INFO] [stderr] Compiling itertools v0.10.5 [INFO] [stderr] Compiling colored v2.2.0 [INFO] [stderr] Compiling aho-corasick v1.1.4 [INFO] [stderr] Compiling futures-util v0.3.31 [INFO] [stderr] Compiling clap_builder v4.5.51 [INFO] [stderr] Compiling plotters-svg v0.3.7 [INFO] [stderr] Compiling same-file v1.0.6 [INFO] [stderr] Compiling chrono v0.4.42 [INFO] [stderr] Compiling num-format v0.4.4 [INFO] [stderr] Compiling cast v0.3.0 [INFO] [stderr] Compiling syn v2.0.110 [INFO] [stderr] Compiling walkdir v2.5.0 [INFO] [stderr] Compiling crossbeam-deque v0.8.6 [INFO] [stderr] Compiling plotters v0.3.7 [INFO] [stderr] Compiling tokio v1.48.0 [INFO] [stderr] Compiling is-terminal v0.4.17 [INFO] [stderr] Compiling once_cell v1.21.3 [INFO] [stderr] Compiling oorandom v11.1.5 [INFO] [stderr] Compiling anes v0.1.6 [INFO] [stderr] Compiling rayon v1.11.0 [INFO] [stderr] Compiling regex-automata v0.4.13 [INFO] [stderr] Compiling criterion-plot v0.5.0 [INFO] [stderr] Compiling clap v4.5.51 [INFO] [stderr] Compiling futures-executor v0.3.31 [INFO] [stderr] Compiling futures v0.3.31 [INFO] [stderr] Compiling zerocopy-derive v0.8.27 [INFO] [stderr] Compiling serde_derive v1.0.228 [INFO] [stderr] Compiling byteserde_derive v0.6.2 [INFO] [stderr] Compiling regex v1.12.2 [INFO] [stderr] Compiling env_filter v0.1.4 [INFO] [stderr] Compiling env_logger v0.11.8 [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 byteserde_types v0.6.2 [INFO] [stderr] Compiling tinytemplate v1.2.1 [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 28.02s [INFO] running `Command { std: "docker" "inspect" "bde1b5d06f6bfa7c5c4a594d7d6e5f1030ece060e5d2822a9fe96ec3b299c413", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "bde1b5d06f6bfa7c5c4a594d7d6e5f1030ece060e5d2822a9fe96ec3b299c413", kill_on_drop: false }` [INFO] [stdout] bde1b5d06f6bfa7c5c4a594d7d6e5f1030ece060e5d2822a9fe96ec3b299c413 [INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:4848fb76d95f26979359cc7e45710b1dbc8f3acb7aeedee7c460d7702230f228" "/opt/rustwide/cargo-home/bin/cargo" "+c2e32f1c9652b13ed99608599c1e855462f421f3" "test" "--frozen", kill_on_drop: false }` [INFO] [stdout] 491a65d9e4f5f664c2ba66709c84c47927d3044a234703768bb3968907b2d8aa [INFO] running `Command { std: "docker" "start" "-a" "491a65d9e4f5f664c2ba66709c84c47927d3044a234703768bb3968907b2d8aa", 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-48a519f191b21dbe) [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stderr] 2025-11-13T19:52:12.651752186Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2025-11-13T19:52:12.651776256Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2025-11-13T19:52:12.653343365Z INFO (connect::svc::test::test_svc_not_connected ) links_blocking::connect::svc::test svc: Svc, RoundRobinPool> [INFO] [stderr] 2025-11-13T19:52:12.653420635Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test clt: Clt127.0.0.1:2684), CltTestMessenger, 128> [INFO] [stderr] 2025-11-13T19:52:12.653355665Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #0, clt: Clt127.0.0.1:50973), CltTestMessenger, 128> [INFO] [stderr] 2025-11-13T19:52:12.653682635Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #1, clt: Clt127.0.0.1:50973), CltTestMessenger, 128> [INFO] [stderr] 2025-11-13T19:52:12.653823205Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #2, clt: Clt127.0.0.1:50973), CltTestMessenger, 128> [INFO] [stderr] 2025-11-13T19:52:12.653851705Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test send_frame: [INFO] [stderr] 0000: 54 a5 c5 fa 86 10 79 d6 17 13 57 ad 81 5f 78 4a | T ¥ Å ú . . y Ö . . W ­ . _ x J [INFO] [stderr] 0001: e6 20 ba 0b 95 15 3c 96 12 50 7f b9 02 70 59 0c | æ . º . . . < . . P . ¹ . p Y . [INFO] [stderr] 0002: b5 bb 66 16 c7 96 42 52 d1 41 05 bc 98 52 c7 aa | µ » f . Ç . B R Ñ A . ¼ . R Ç ª [INFO] [stderr] 0003: 06 ee 9c 92 8b 83 93 38 98 32 49 41 fc 48 19 46 | . î . . . . . 8 . 2 I A ü H . F [INFO] [stderr] 0004: 9c 95 5a 76 51 43 57 ad d8 7a cd 68 e1 53 bc f1 | . . Z v Q C W ­ Ø z Í h á S ¼ ñ [INFO] [stderr] 0005: 2f 18 ce 21 f8 09 d7 77 27 2b 4c 9c 1e ca 16 11 | / . Î . ø . × w ' + L . . Ê . . [INFO] [stderr] 0006: 22 f6 fb 4f e3 a6 01 14 11 31 07 99 80 c8 6f df | " ö û O ã ¦ . . . 1 . . . È o ß [INFO] [stderr] 0007: 8d 07 88 f5 98 c4 b5 d6 07 18 b7 be f1 2d 0d 0e | . . . õ . Ä µ Ö . . · ¾ ñ - . . [INFO] [stderr] [INFO] [stderr] 2025-11-13T19:52:12.653541605Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test svc: Svc, RoundRobinPool]>> [INFO] [stderr] 2025-11-13T19:52:12.654266665Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- PRE SPLIT --------- [INFO] [stderr] 2025-11-13T19:52:12.654380745Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:50380->127.0.0.1:2684) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-11-13T19:52:12.654546255Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:2684<-127.0.0.1:50380) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-11-13T19:52:12.654687415Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- SVC SPLIT POOL --------- [INFO] [stdout] test connect::svc::test::test_svc_not_connected ... ok [INFO] [stderr] 2025-11-13T19:52:12.654814365Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:50380->127.0.0.1:2684) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-11-13T19:52:12.654977695Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:2684<-127.0.0.1:50380) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-11-13T19:52:12.655158175Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- CLT SPLIT DIRECT --------- [INFO] [stderr] 2025-11-13T19:52:12.655270915Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_sent Initiator(unittest@127.0.0.1:50380->127.0.0.1:2684) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stdout] test connect::svc::test::test_svc_clt_connected ... ok [INFO] [stderr] 2025-11-13T19:52:12.655440695Z INFO (connect::svc::test::test_svc_clt_connected ) links_core::callbacks::logger LoggerCallback::on_recv Acceptor(unittest@127.0.0.1:2684<-127.0.0.1:50380) Dbg(CltTestMsgDebug { ty: ConstCharAscii<49>('1'), text: StringAsciiFixed<127, 32, true>(" Hello Frm Client Msg") }) [INFO] [stderr] 2025-11-13T19:52:12.655572455Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- CLT DROP RANDOM HALF --------- [INFO] [stderr] 2025-11-13T19:52:12.655630145Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test dropping clt_recv [INFO] [stderr] 2025-11-13T19:52:12.655712575Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test clt_send err: Initiator(unittest@127.0.0.1:50380->127.0.0.1:2684) FrameWriter::write_frame caused by: [Broken pipe (os error 32)] [INFO] [stderr] 2025-11-13T19:52:12.655794435Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test --------- SVC RECV/SEND SHOULD FAIL CLT DROPS HALF --------- [INFO] [stderr] 2025-11-13T19:52:12.655882445Z 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-11-13T19:52:12.655989115Z INFO (connect::svc::test::test_svc_clt_connected ) links_blocking::connect::svc::test pool_recver opt: None [INFO] [stderr] 2025-11-13T19:52:12.656092705Z 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:2684<-127.0.0.1:50380) FrameWriter::write_frame caused by: [Broken pipe (os error 32)]) [INFO] [stderr] 2025-11-13T19:52:12.653940285Z 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:50973), CltTestMessenger, 128> will be dropped" } [INFO] [stderr] 2025-11-13T19:52:12.657510015Z 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-11-13T19:52:12.657672575Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test #3, clt: Clt127.0.0.1:50973), CltTestMessenger, 128> [INFO] [stderr] 2025-11-13T19:52:12.657770744Z 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:50973), CltTestMessenger, 128> will be dropped" } [INFO] [stderr] 2025-11-13T19:52:12.658164184Z 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-11-13T19:52:12.658272695Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test clt_pool: RoundRobinPool127.0.0.1:50973), CltTestMessenger, 128>,Clt127.0.0.1:50973), CltTestMessenger, 128>]> [INFO] [stderr] 2025-11-13T19:52:12.658384395Z INFO (connect::pool::test::test_svcpool_cltpool_connected) links_blocking::connect::pool::test svc_pool: RoundRobinPool,Clt]> [INFO] [stderr] 2025-11-13T19:52:12.658529244Z 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-11-13T19:52:12.658642744Z 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] [stdout] test connect::pool::test::test_svcpool_cltpool_connected ... ok [INFO] [stderr] 2025-11-13T19:52:12.706753256Z 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:15937 } connect timeout: 50ms" }) [INFO] [stdout] test connect::clt::test::test_clt_not_connected ... ok [INFO] [stderr] 2025-11-13T19:52:12.753915077Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test Initiator(unittest@127.0.0.1:34184->127.0.0.1:5436) MessageSender connected [INFO] [stderr] 2025-11-13T19:52:12.753921227Z INFO (Thread-Svc ) links_blocking::core::messenger::test Acceptor(unittest@127.0.0.1:5436<-127.0.0.1:34184) MessageSender connected [INFO] [stderr] 2025-11-13T19:52:12.756603717Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test clt: FrameWriter { Initiator(unittest@127.0.0.1:60960->127.0.0.1:33278), addr: connected, peer: connected, fd: 8 } [INFO] [stderr] 2025-11-13T19:52:12.756624147Z INFO (Thread-Svc ) links_blocking::core::framer::test svc: reader: FrameReader> { Acceptor(unittest@127.0.0.1:33278<-127.0.0.1:60960), addr: connected, peer: connected, fd: 10 } [INFO] [stderr] 2025-11-13T19:52:12.983134806Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test dropping clt_reader [INFO] [stderr] 2025-11-13T19:52:12.983346386Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test clt_writer.write_frame() err: Initiator(unittest@127.0.0.1:60960->127.0.0.1:33278) FrameWriter::write_frame caused by: [Broken pipe (os error 32)] [INFO] [stderr] 2025-11-13T19:52:13.016382091Z INFO (Thread-Svc ) links_blocking::core::framer::test svc: read_frame is None, client closed connection [INFO] [stderr] 2025-11-13T19:52:13.016713051Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test frame_send_count: 100,000, frame_recv_count: 100,000 [INFO] [stderr] 2025-11-13T19:52:13.016830050Z INFO (core::framer::test::test_reader ) links_blocking::core::framer::test per send elapsed: 2.263µs, total elapsed: 226.35454ms [INFO] [stdout] test core::framer::test::test_reader ... ok [INFO] [stderr] 2025-11-13T19:52:16.140348922Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test dropping clt_sender [INFO] [stderr] 2025-11-13T19:52:16.140482672Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test clt_recver.recv(): None [INFO] [stdout] test core::messenger::test::test_messenger ... ok [INFO] [stderr] 2025-11-13T19:52:16.140619622Z INFO (Thread-Svc ) links_blocking::core::messenger::test Acceptor { name: "unittest", local: 127.0.0.1:5436, peer: Some(127.0.0.1:34184) } MessageRecver Connection Closed by Client [INFO] [stdout] [INFO] [stderr] 2025-11-13T19:52:16.140820172Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test clt_msg_sent_count: 100,000, clt_msg_recv_count: 100,000 [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.49s [INFO] [stderr] 2025-11-13T19:52:16.140892402Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test svc_msg_sent_count: 100,000, svc_msg_recv_count: 100,000 [INFO] [stdout] [INFO] [stderr] 2025-11-13T19:52:16.140961572Z INFO (core::messenger::test::test_messenger ) links_blocking::core::messenger::test per round trip elapsed: 33.86µs, total elapsed: 3.386096565s [INFO] [stderr] Doc-tests links_blocking [INFO] [stdout] [INFO] [stdout] running 6 tests [INFO] [stdout] test src/connect/pool.rs - connect::pool::CltsPool (line 19) - compile ... ok [INFO] [stdout] test src/connect/pool.rs - connect::pool::PoolCltAcceptor (line 375) - compile ... ok [INFO] [stdout] test src/core/messenger.rs - core::messenger (line 8) - compile ... ok [INFO] [stdout] test src/core/framer.rs - core::framer (line 8) ... ok [INFO] [stdout] test src/connect/pool.rs - connect::pool::CltRecversPool (line 156) ... ok [INFO] [stdout] test src/connect/pool.rs - connect::pool::CltSendersPool (line 268) ... ok [INFO] [stdout] [INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.81s [INFO] [stdout] [INFO] running `Command { std: "docker" "inspect" "491a65d9e4f5f664c2ba66709c84c47927d3044a234703768bb3968907b2d8aa", kill_on_drop: false }` [INFO] running `Command { std: "docker" "rm" "-f" "491a65d9e4f5f664c2ba66709c84c47927d3044a234703768bb3968907b2d8aa", kill_on_drop: false }` [INFO] [stdout] 491a65d9e4f5f664c2ba66709c84c47927d3044a234703768bb3968907b2d8aa