[INFO] fetching crate aggligator 0.9.9...
[INFO] testing aggligator-0.9.9 against 1.94.0 for beta-1.95-1
[INFO] extracting crate aggligator 0.9.9 into /workspace/builds/worker-2-tc1/source
[INFO] started tweaking crates.io crate aggligator 0.9.9
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate aggligator 0.9.9
[INFO] tweaked toml for crates.io crate aggligator 0.9.9 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate aggligator 0.9.9 on toolchain 1.94.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.94.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate aggligator 0.9.9 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.94.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded wasip2 v1.0.0+wasi-0.2.4
[INFO] [stderr]   Downloaded atomic_refcell v0.1.13
[INFO] [stderr]   Downloaded find-msvc-tools v0.1.1
[INFO] [stderr]   Downloaded cc v1.2.36
[INFO] [stderr]   Downloaded test-log v0.2.18
[INFO] [stderr]   Downloaded test-log-macros v0.2.18
[INFO] [stderr]   Downloaded minicov v0.3.7
[INFO] [stderr]   Downloaded wit-bindgen v0.45.1
[INFO] [stderr]   Downloaded wasi v0.14.5+wasi-0.2.4
[INFO] [stderr]   Downloaded wasm-bindgen-test v0.3.51
[INFO] [stderr]   Downloaded wasm-bindgen-test-macro v0.3.51
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.94.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] ed319ae2411a1333002e25dadc7a55a1f272fc4840f536ea7d1918137e9868af
[INFO] running `Command { std: "docker" "start" "-a" "ed319ae2411a1333002e25dadc7a55a1f272fc4840f536ea7d1918137e9868af", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "ed319ae2411a1333002e25dadc7a55a1f272fc4840f536ea7d1918137e9868af", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ed319ae2411a1333002e25dadc7a55a1f272fc4840f536ea7d1918137e9868af", kill_on_drop: false }`
[INFO] [stdout] ed319ae2411a1333002e25dadc7a55a1f272fc4840f536ea7d1918137e9868af
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.94.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e8beda7fd9a627b54895b3d913f14ce5a0a34fbd6c6e26a907b08c5272a2d942
[INFO] running `Command { std: "docker" "start" "-a" "e8beda7fd9a627b54895b3d913f14ce5a0a34fbd6c6e26a907b08c5272a2d942", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.101
[INFO] [stderr]    Compiling unicode-ident v1.0.19
[INFO] [stderr]    Compiling cfg-if v1.0.3
[INFO] [stderr]    Compiling libc v0.2.175
[INFO] [stderr]    Compiling getrandom v0.3.3
[INFO] [stderr]    Compiling zerocopy v0.8.27
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling curve25519-dalek v4.1.3
[INFO] [stderr]    Compiling futures-io v0.3.31
[INFO] [stderr]    Compiling memchr v2.7.5
[INFO] [stderr]    Compiling subtle v2.6.1
[INFO] [stderr]    Compiling bytes v1.10.1
[INFO] [stderr]    Compiling tracing-core v0.1.34
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling crc32fast v1.5.0
[INFO] [stderr]    Compiling atomic_refcell v0.1.13
[INFO] [stderr]    Compiling quote v1.0.40
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling rand_xoshiro v0.7.0
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling tokio-macros v2.5.0
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling zeroize_derive v1.4.2
[INFO] [stderr]    Compiling curve25519-dalek-derive v0.1.1
[INFO] [stderr]    Compiling tracing-attributes v0.1.30
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling zeroize v1.8.1
[INFO] [stderr]    Compiling tokio v1.47.1
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling tracing v0.1.41
[INFO] [stderr]    Compiling x25519-dalek v2.0.1
[INFO] [stderr]    Compiling tokio-util v0.7.16
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling aggligator v0.9.9 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 36.82s
[INFO] running `Command { std: "docker" "inspect" "e8beda7fd9a627b54895b3d913f14ce5a0a34fbd6c6e26a907b08c5272a2d942", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e8beda7fd9a627b54895b3d913f14ce5a0a34fbd6c6e26a907b08c5272a2d942", kill_on_drop: false }`
[INFO] [stdout] e8beda7fd9a627b54895b3d913f14ce5a0a34fbd6c6e26a907b08c5272a2d942
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.94.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c52daec7e61d76c15613cbba2b39ad67ac425fa1ff2ca45871c4e737eb4aaace
[INFO] running `Command { std: "docker" "start" "-a" "c52daec7e61d76c15613cbba2b39ad67ac425fa1ff2ca45871c4e737eb4aaace", kill_on_drop: false }`
[INFO] [stderr]    Compiling regex-syntax v0.8.6
[INFO] [stderr]    Compiling tokio v1.47.1
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling test-log-macros v0.2.18
[INFO] [stderr]    Compiling regex-automata v0.4.10
[INFO] [stderr]    Compiling tokio-util v0.7.16
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling aggligator v0.9.9 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.20
[INFO] [stderr]    Compiling test-log v0.2.18
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 32.84s
[INFO] running `Command { std: "docker" "inspect" "c52daec7e61d76c15613cbba2b39ad67ac425fa1ff2ca45871c4e737eb4aaace", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c52daec7e61d76c15613cbba2b39ad67ac425fa1ff2ca45871c4e737eb4aaace", kill_on_drop: false }`
[INFO] [stdout] c52daec7e61d76c15613cbba2b39ad67ac425fa1ff2ca45871c4e737eb4aaace
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1.94.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 2f5cddf3125ba1255d498387734a6f50b8d836971eb6148b6c7fe570b911f35b
[INFO] running `Command { std: "docker" "start" "-a" "2f5cddf3125ba1255d498387734a6f50b8d836971eb6148b6c7fe570b911f35b", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.15s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/aggligator-d0a1319f98619f16)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/multi_link.rs (/opt/rustwide/target/debug/deps/multi_link-ac49f4643d42d2d4)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test ten_x_hundert_kb_per_s ... ok
[INFO] [stdout] test ten_x_all_failed_link ... ok
[INFO] [stdout] test ten_x_failed_link ... ok
[INFO] [stdout] test ten_x_link_timeout ... ok
[INFO] [stdout] test five_x_unlimited_multi_thread ... ok
[INFO] [stdout] test five_x_blocked ... ok
[INFO] [stdout] test ten_x_paused_link ... ok
[INFO] [stdout] test five_x_unlimited_current_thread has been running for over 60 seconds
[INFO] [stdout] test five_x_very_high_latency has been running for over 60 seconds
[INFO] [stdout] test forceful_termination has been running for over 60 seconds
[INFO] [stdout] test forceful_termination ... FAILED
[INFO] [stdout] test five_x_unlimited_current_thread ... ok
[INFO] [stdout] test five_x_very_high_latency ... FAILED
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- forceful_termination stdout ----
[INFO] [stdout] server: starting
[INFO] [stdout] server: obtaining listener
[INFO] [stdout] server: adding incoming link 0
[INFO] [stdout] client: starting outgoing link
[INFO] [stdout] client: adding outgoing link 0
[INFO] [stdout] client: adding outgoing link 1
[INFO] [stdout] client: adding outgoing link 2
[INFO] [stdout] client: adding outgoing link 3
[INFO] [stdout] client: adding outgoing link 4
[INFO] [stdout] server: adding incoming link 1
[INFO] [stdout] server: adding incoming link 2
[INFO] [stdout] server: adding incoming link 3
[INFO] [stdout] server: adding incoming link 4
[INFO] [stdout] server: getting incoming connection
[INFO] [stdout] server: links of incoming connection: ["0", "1", "2", "3", "4"]
[INFO] [stdout] server: accepting incoming connection
[INFO] [stdout] 2026-03-11T00:44:36.604769Z  INFO aggligator::connection{conn_id=2c6174 dir=incoming}: aggligator::agg::task: added new link link_id=18cb7d
[INFO] [stdout] 2026-03-11T00:44:36.604856Z  INFO aggligator::connection{conn_id=2c6174 dir=incoming}: aggligator::agg::task: added new link link_id=cde4ba
[INFO] [stdout] 2026-03-11T00:44:36.604912Z  INFO aggligator::connection{conn_id=2c6174 dir=incoming}: aggligator::agg::task: added new link link_id=74728e
[INFO] [stdout] 2026-03-11T00:44:36.604969Z  INFO aggligator::connection{conn_id=2c6174 dir=incoming}: aggligator::agg::task: added new link link_id=54958b
[INFO] [stdout] 2026-03-11T00:44:36.605014Z  INFO aggligator::connection{conn_id=2c6174 dir=incoming}: aggligator::agg::task: added new link link_id=c37dd0
[INFO] [stdout] server: waiting for links
[INFO] [stdout] server: sending and receiving test data
[INFO] [stdout] server: maximum send size is 9999999
[INFO] [stdout] server: sending 0
[INFO] [stdout] server: recving 0
[INFO] [stdout] 2026-03-11T00:44:37.606252Z  INFO aggligator::connection{conn_id=2c6174 dir=outgoing}: aggligator::agg::task: added new link link_id=974b3c
[INFO] [stdout] 2026-03-11T00:44:37.606330Z  INFO aggligator::connection{conn_id=2c6174 dir=outgoing}: aggligator::agg::task: added new link link_id=ffa802
[INFO] [stdout] 2026-03-11T00:44:37.606393Z  INFO aggligator::connection{conn_id=2c6174 dir=outgoing}: aggligator::agg::task: added new link link_id=19add4
[INFO] [stdout] ********* test channel was blocked for 0.94 s and last tried 0.94 s ago
[INFO] [stdout] 2026-03-11T00:44:38.755850Z  INFO aggligator::connection{conn_id=2c6174 dir=outgoing}: aggligator::agg::task: added new link link_id=a9572e
[INFO] [stdout] ********* test channel was blocked for 0.63 s and last tried 0.63 s ago
[INFO] [stdout] ********* test channel was blocked for 0.75 s and last tried 0.75 s ago
[INFO] [stdout] ********* test channel was blocked for 0.21 s and last tried 0.21 s ago
[INFO] [stdout] client: waiting for links
[INFO] [stdout] client: checking link info
[INFO] [stdout] client: links of outgoing connection: [Link { id: 974b3c, conn_id: 2c6174, direction: Outgoing, tag: "0" }, Link { id: ffa802, conn_id: 2c6174, direction: Outgoing, tag: "1" }, Link { id: 19add4, conn_id: 2c6174, direction: Outgoing, tag: "2" }, Link { id: a9572e, conn_id: 2c6174, direction: Outgoing, tag: "4" }, Link { id: 8616a1, conn_id: 2c6174, direction: Outgoing, tag: "3" }]
[INFO] [stdout] client: establishing connection
[INFO] [stdout] client: sending and receiving test data
[INFO] [stdout] client: sending 0
[INFO] [stdout] client: recving 0
[INFO] [stdout] 2026-03-11T00:44:40.056829Z  INFO aggligator::connection{conn_id=2c6174 dir=outgoing}: aggligator::agg::task: added new link link_id=8616a1
[INFO] [stdout] ********* test channel was blocked for 1.32 s and last tried 1.32 s ago
[INFO] [stdout] server: sending 100
[INFO] [stdout] server: sending 200
[INFO] [stdout] server: sending 300
[INFO] [stdout] server: sending 400
[INFO] [stdout] server: sending 500
[INFO] [stdout] server: sending 600
[INFO] [stdout] server: sending 700
[INFO] [stdout] server: sending 800
[INFO] [stdout] server: sending 900
[INFO] [stdout] server: sending 1000
[INFO] [stdout] client: recving 100
[INFO] [stdout] server: sending 1100
[INFO] [stdout] server: sending 1200
[INFO] [stdout] server: sending 1300
[INFO] [stdout] client: recving 200
[INFO] [stdout] server: sending 1400
[INFO] [stdout] server: sending 1500
[INFO] [stdout] server: sending 1600
[INFO] [stdout] server: sending 1700
[INFO] [stdout] client: recving 300
[INFO] [stdout] server: sending 1800
[INFO] [stdout] server: sending 1900
[INFO] [stdout] server: sending 2000
[INFO] [stdout] server: sending 2100
[INFO] [stdout] client: recving 400
[INFO] [stdout] server: sending 2200
[INFO] [stdout] server: sending 2300
[INFO] [stdout] server: sending 2400
[INFO] [stdout] server: sending 2500
[INFO] [stdout] client: recving 500
[INFO] [stdout] client: recving 600
[INFO] [stdout] client: recving 700
[INFO] [stdout] client: recving 800
[INFO] [stdout] client: recving 900
[INFO] [stdout] client: recving 1000
[INFO] [stdout] client: recving 1100
[INFO] [stdout] client: recving 1200
[INFO] [stdout] client: recving 1300
[INFO] [stdout] client: recving 1400
[INFO] [stdout] client: recving 1500
[INFO] [stdout] client: recving 1600
[INFO] [stdout] client: recving 1700
[INFO] [stdout] client: recving 1800
[INFO] [stdout] client: recving 1900
[INFO] [stdout] client: recving 2000
[INFO] [stdout] client: recving 2100
[INFO] [stdout] client: recving 2200
[INFO] [stdout] client: recving 2300
[INFO] [stdout] client: recving 2400
[INFO] [stdout] 2026-03-11T00:45:06.614310Z  WARN aggligator::connection{conn_id=2c6174 dir=incoming}: aggligator::agg::task: removing link due to unconfirmed timeout link_id=18cb7d
[INFO] [stdout] 2026-03-11T00:45:06.652852Z  WARN aggligator::connection{conn_id=2c6174 dir=incoming}: aggligator::agg::task: removing link due to unconfirmed timeout link_id=54958b
[INFO] [stdout] 2026-03-11T00:45:06.653215Z  WARN aggligator::connection{conn_id=2c6174 dir=outgoing}: aggligator::agg::task: disconnecting link due to IO error link_id=8616a1 err=broken pipe
[INFO] [stdout] 2026-03-11T00:45:06.653382Z  WARN aggligator::connection{conn_id=2c6174 dir=outgoing}: aggligator::agg::task: disconnecting link due to IO error link_id=974b3c err=broken pipe
[INFO] [stdout] client: sending 100
[INFO] [stdout] client: sending 200
[INFO] [stdout] client: sending 300
[INFO] [stdout] client: sending 400
[INFO] [stdout] client: sending 500
[INFO] [stdout] client: sending 600
[INFO] [stdout] client: sending 700
[INFO] [stdout] server: sending 2600
[INFO] [stdout] server: sending 2700
[INFO] [stdout] server: sending 2800
[INFO] [stdout] server: sending 2900
[INFO] [stdout] server: recving 100
[INFO] [stdout] client: sending 800
[INFO] [stdout] server: sending 3000
[INFO] [stdout] server: sending 3100
[INFO] [stdout] server: sending 3200
[INFO] [stdout] server: recving 200
[INFO] [stdout] server: recving 300
[INFO] [stdout] server: sending 3300
[INFO] [stdout] server: sending 3400
[INFO] [stdout] server: sending 3500
[INFO] [stdout] server: sending 3600
[INFO] [stdout] server: sending 3700
[INFO] [stdout] server: recving 400
[INFO] [stdout] server: sending 3800
[INFO] [stdout] server: sending 3900
[INFO] [stdout] server: sending 4000
[INFO] [stdout] server: sending 4100
[INFO] [stdout] server: sending 4200
[INFO] [stdout] server: sending 4300
[INFO] [stdout] server: sending 4400
[INFO] [stdout] server: sending 4500
[INFO] [stdout] server: sending 4600
[INFO] [stdout] server: recving 500
[INFO] [stdout] server: recving 600
[INFO] [stdout] server: sending 4700
[INFO] [stdout] server: sending 4800
[INFO] [stdout] server: sending 4900
[INFO] [stdout] server: sending 5000
[INFO] [stdout] server: recving 700
[INFO] [stdout] server: recving 800
[INFO] [stdout] client: sending 900
[INFO] [stdout] client: sending 1000
[INFO] [stdout] client: sending 1100
[INFO] [stdout] client: sending 1200
[INFO] [stdout] client: recving 2500
[INFO] [stdout] client: sending 1300
[INFO] [stdout] client: recving 2600
[INFO] [stdout] client: sending 1400
[INFO] [stdout] server: recving 900
[INFO] [stdout] server: recving 1000
[INFO] [stdout] client: sending 1500
[INFO] [stdout] client: recving 2700
[INFO] [stdout] server: recving 1100
[INFO] [stdout] server: recving 1200
[INFO] [stdout] client: sending 1600
[INFO] [stdout] client: sending 1700
[INFO] [stdout] client: sending 1800
[INFO] [stdout] server: recving 1300
[INFO] [stdout] server: recving 1400
[INFO] [stdout] client: sending 1900
[INFO] [stdout] client: recving 2800
[INFO] [stdout] client: sending 2000
[INFO] [stdout] server: recving 1500
[INFO] [stdout] client: sending 2100
[INFO] [stdout] client: recving 2900
[INFO] [stdout] client: recving 3000
[INFO] [stdout] server: recving 1600
[INFO] [stdout] client: sending 2200
[INFO] [stdout] server: sending 5100
[INFO] [stdout] server: sending 5200
[INFO] [stdout] server: sending 5300
[INFO] [stdout] server: sending 5400
[INFO] [stdout] server: recving 1700
[INFO] [stdout] server: sending 5500
[INFO] [stdout] server: recving 1800
[INFO] [stdout] client: sending 2300
[INFO] [stdout] server: recving 1900
[INFO] [stdout] client: sending 2400
[INFO] [stdout] server: recving 2000
[INFO] [stdout] server: recving 2100
[INFO] [stdout] server: recving 2200
[INFO] [stdout] client: sending 2500
[INFO] [stdout] client: recving 3100
[INFO] [stdout] client: sending 2600
[INFO] [stdout] client: sending 2700
[INFO] [stdout] client: sending 2800
[INFO] [stdout] client: recving 3200
[INFO] [stdout] client: sending 2900
[INFO] [stdout] server: recving 2300
[INFO] [stdout] client: sending 3000
[INFO] [stdout] server: recving 2400
[INFO] [stdout] server: recving 2500
[INFO] [stdout] client: sending 3100
[INFO] [stdout] server: recving 2600
[INFO] [stdout] client: sending 3200
[INFO] [stdout] client: recving 3300
[INFO] [stdout] server: recving 2700
[INFO] [stdout] client: sending 3300
[INFO] [stdout] server: sending 5600
[INFO] [stdout] server: sending 5700
[INFO] [stdout] server: recving 2800
[INFO] [stdout] server: recving 2900
[INFO] [stdout] client: sending 3400
[INFO] [stdout] client: recving 3400
[INFO] [stdout] server: recving 3000
[INFO] [stdout] client: sending 3500
[INFO] [stdout] server: recving 3100
[INFO] [stdout] server: recving 3200
[INFO] [stdout] client: sending 3600
[INFO] [stdout] server: recving 3300
[INFO] [stdout] client: sending 3700
[INFO] [stdout] client: recving 3500
[INFO] [stdout] server: recving 3400
[INFO] [stdout] client: sending 3800
[INFO] [stdout] client: sending 3900
[INFO] [stdout] client: sending 4000
[INFO] [stdout] client: recving 3600
[INFO] [stdout] server: recving 3500
[INFO] [stdout] client: sending 4100
[INFO] [stdout] server: sending 5800
[INFO] [stdout] server: recving 3600
[INFO] [stdout] client: sending 4200
[INFO] [stdout] server: sending 5900
[INFO] [stdout] server: recving 3700
[INFO] [stdout] client: sending 4300
[INFO] [stdout] server: recving 3800
[INFO] [stdout] client: sending 4400
[INFO] [stdout] server: recving 3900
[INFO] [stdout] server: recving 4000
[INFO] [stdout] client: sending 4500
[INFO] [stdout] client: sending 4600
[INFO] [stdout] server: recving 4100
[INFO] [stdout] client: sending 4700
[INFO] [stdout] client: sending 4800
[INFO] [stdout] client: sending 4900
[INFO] [stdout] client: recving 3700
[INFO] [stdout] server: recving 4200
[INFO] [stdout] client: sending 5000
[INFO] [stdout] client: sending 5100
[INFO] [stdout] client: sending 5200
[INFO] [stdout] server: recving 4300
[INFO] [stdout] server: recving 4400
[INFO] [stdout] client: sending 5300
[INFO] [stdout] server: recving 4500
[INFO] [stdout] client: sending 5400
[INFO] [stdout] client: recving 3800
[INFO] [stdout] client: sending 5500
[INFO] [stdout] server: recving 4600
[INFO] [stdout] client: sending 5600
[INFO] [stdout] server: recving 4700
[INFO] [stdout] server: recving 4800
[INFO] [stdout] client: sending 5700
[INFO] [stdout] client: recving 3900
[INFO] [stdout] client: sending 5800
[INFO] [stdout] server: sending 6000
[INFO] [stdout] server: recving 4900
[INFO] [stdout] server: sending 6100
[INFO] [stdout] server: sending 6200
[INFO] [stdout] server: recving 5000
[INFO] [stdout] server: recving 5100
[INFO] [stdout] client: sending 5900
[INFO] [stdout] client: recving 4000
[INFO] [stdout] server: recving 5200
[INFO] [stdout] server: recving 5300
[INFO] [stdout] server: recving 5400
[INFO] [stdout] server: recving 5500
[INFO] [stdout] server: recving 5600
[INFO] [stdout] client: sending 6000
[INFO] [stdout] server: sending 6300
[INFO] [stdout] server: sending 6400
[INFO] [stdout] server: recving 5700
[INFO] [stdout] client: sending 6100
[INFO] [stdout] server: recving 5800
[INFO] [stdout] client: sending 6200
[INFO] [stdout] server: recving 5900
[INFO] [stdout] client: sending 6300
[INFO] [stdout] client: sending 6400
[INFO] [stdout] client: sending 6500
[INFO] [stdout] client: sending 6600
[INFO] [stdout] client: recving 4100
[INFO] [stdout] server: recving 6000
[INFO] [stdout] client: sending 6700
[INFO] [stdout] client: sending 6800
[INFO] [stdout] client: sending 6900
[INFO] [stdout] client: sending 7000
[INFO] [stdout] server: recving 6100
[INFO] [stdout] client: sending 7100
[INFO] [stdout] client: sending 7200
[INFO] [stdout] server: recving 6200
[INFO] [stdout] client: sending 7300
[INFO] [stdout] server: recving 6300
[INFO] [stdout] server: recving 6400
[INFO] [stdout] client: sending 7400
[INFO] [stdout] client: sending 7500
[INFO] [stdout] server: recving 6500
[INFO] [stdout] server: recving 6600
[INFO] [stdout] server: recving 6700
[INFO] [stdout] client: sending 7600
[INFO] [stdout] client: sending 7700
[INFO] [stdout] client: recving 4200
[INFO] [stdout] server: recving 6800
[INFO] [stdout] server: recving 6900
[INFO] [stdout] server: recving 7000
[INFO] [stdout] server: recving 7100
[INFO] [stdout] client: sending 7800
[INFO] [stdout] client: recving 4300
[INFO] [stdout] server: recving 7200
[INFO] [stdout] client: sending 7900
[INFO] [stdout] client: sending 8000
[INFO] [stdout] server: recving 7300
[INFO] [stdout] server: recving 7400
[INFO] [stdout] server: recving 7500
[INFO] [stdout] client: sending 8100
[INFO] [stdout] client: sending 8200
[INFO] [stdout] client: recving 4400
[INFO] [stdout] client: recving 4500
[INFO] [stdout] client: sending 8300
[INFO] [stdout] server: sending 6500
[INFO] [stdout] server: sending 6600
[INFO] [stdout] server: sending 6700
[INFO] [stdout] server: recving 7600
[INFO] [stdout] server: recving 7700
[INFO] [stdout] client: sending 8400
[INFO] [stdout] client: sending 8500
[INFO] [stdout] server: recving 7800
[INFO] [stdout] client: sending 8600
[INFO] [stdout] server: recving 7900
[INFO] [stdout] client: sending 8700
[INFO] [stdout] client: recving 4600
[INFO] [stdout] server: recving 8000
[INFO] [stdout] client: sending 8800
[INFO] [stdout] server: recving 8100
[INFO] [stdout] client: sending 8900
[INFO] [stdout] server: sending 6800
[INFO] [stdout] server: sending 6900
[INFO] [stdout] client: sending 9000
[INFO] [stdout] client: recving 4700
[INFO] [stdout] server: recving 8200
[INFO] [stdout] server: recving 8300
[INFO] [stdout] server: recving 8400
[INFO] [stdout] client: sending 9100
[INFO] [stdout] client: sending 9200
[INFO] [stdout] server: recving 8500
[INFO] [stdout] server: recving 8600
[INFO] [stdout] client: sending 9300
[INFO] [stdout] server: sending 7000
[INFO] [stdout] server: sending 7100
[INFO] [stdout] server: recving 8700
[INFO] [stdout] server: sending 7200
[INFO] [stdout] server: recving 8800
[INFO] [stdout] server: recving 8900
[INFO] [stdout] client: sending 9400
[INFO] [stdout] client: sending 9500
[INFO] [stdout] server: recving 9000
[INFO] [stdout] client: sending 9600
[INFO] [stdout] client: recving 4800
[INFO] [stdout] client: sending 9700
[INFO] [stdout] client: sending 9800
[INFO] [stdout] server: recving 9100
[INFO] [stdout] server: recving 9200
[INFO] [stdout] client: sending 9900
[INFO] [stdout] client: recving 4900
[INFO] [stdout] server: recving 9300
[INFO] [stdout] client: sending 10000
[INFO] [stdout] client: recving 5000
[INFO] [stdout] client: forcefully terminating connection
[INFO] [stdout] 2026-03-11T00:45:31.108548Z  INFO aggligator::connection{conn_id=2c6174 dir=outgoing}: aggligator::agg::task: forceful connection termination by local request
[INFO] [stdout] client: got expected error: TaskTerminated
[INFO] [stdout] client: got expected error: TaskTerminated
[INFO] [stdout] client: measured speed is 0.0 and expected speed is 4000000
[INFO] [stdout] client: dropping sender
[INFO] [stdout] client: waiting for termination notification
[INFO] [stdout] server: recving 9400
[INFO] [stdout] server: recving 9500
[INFO] [stdout] server: recving 9600
[INFO] [stdout] server: recving 9700
[INFO] [stdout] server: recving 9800
[INFO] [stdout] server: sending 7300
[INFO] [stdout] 2026-03-11T00:45:32.113093Z  INFO aggligator::connection{conn_id=2c6174 dir=incoming}: aggligator::agg::task: forceful connection termination by remote endpoint
[INFO] [stdout] 2026-03-11T00:45:32.113483Z  INFO aggligator::connection{conn_id=2c6174 dir=incoming}: aggligator::agg::task: return=Err(Terminated)
[INFO] [stdout] server: recving 9900
[INFO] [stdout] server: got expected error: TaskTerminated
[INFO] [stdout] server: got expected error: TaskTerminated
[INFO] [stdout] server: measured speed is 0.0 and expected speed is 4000000
[INFO] [stdout] server: link status 0: Some(UnconfirmedTimeout)
[INFO] [stdout] 
[INFO] [stdout] thread 'forceful_termination' (22) panicked at tests/multi_link.rs:188:17:
[INFO] [stdout] assertion failed: matches!(link.disconnect_reason(), Some(DisconnectReason::TaskTerminated))
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x649b0bf1f5a2 - std::backtrace_rs::backtrace::libunwind::trace::hff358a60abf734fc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x649b0bf1f5a2 - std::backtrace_rs::backtrace::trace_unsynchronized::h3b121f916dd95ec6
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x649b0bf1f5a2 - std::sys::backtrace::_print_fmt::hde0a62ded68798e9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x649b0bf1f5a2 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::h93773fc827e3113d
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x649b0bf30dba - core::fmt::rt::Argument::fmt::h01eff69902dad97f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x649b0bf30dba - core::fmt::write::hed7b5c73d82ecb7c
[INFO] [stdout]    6:     0x649b0beedad6 - std::io::default_write_fmt::h2f696ff5b8bbaa4b
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x649b0beedad6 - std::io::Write::write_fmt::h5e66814db8a9cfce
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x649b0befe739 - std::sys::backtrace::BacktraceLock::print::h8b1d6fcc5a56d1a3
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x649b0befe739 - std::panicking::default_hook::{{closure}}::h2be84df4f189ae36
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x649b0befe5d1 - std::panicking::default_hook::hf0ea8939246f43a9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x649b0bc38b0e - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h02d35e3888986252
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x649b0bc38b0e - test::test_main_with_exit_callback::{{closure}}::h939640a27d647173
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x649b0befea62 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hc9df09ff391af3e7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x649b0befea62 - std::panicking::panic_with_hook::hb4bd9ac1123582a0
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x649b0befe82a - std::panicking::panic_handler::{{closure}}::hde00dd15f5637fe2
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:691:13
[INFO] [stdout]   16:     0x649b0bef9b09 - std::sys::backtrace::__rust_end_short_backtrace::hb72197fa777c1785
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x649b0bee28fd - __rustc[4425a7e20b4c8619]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x649b0bf39fbc - core::panicking::panic_fmt::ha59b517dd231f4da
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x649b0bf39f82 - core::panicking::panic::hbff2745fd4c5afec
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panicking.rs:150:5
[INFO] [stdout]   20:     0x649b0bbd4282 - multi_link::multi_link_test::{{closure}}::{{closure}}::h05d98a1709f9070f
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/multi_link.rs:188:17
[INFO] [stdout]   21:     0x649b0bbcdf4d - <futures_util::future::maybe_done::MaybeDone<Fut> as core::future::future::Future>::poll::ha77ff14ed96f060f
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/futures-util-0.3.31/src/future/maybe_done.rs:95:60
[INFO] [stdout]   22:     0x649b0bbd95f9 - multi_link::multi_link_test::{{closure}}::{{closure}}::h86933a07892592f5
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/futures-util-0.3.31/src/async_await/join_mod.rs:95:13
[INFO] [stdout]   23:     0x649b0bb79c88 - <futures_util::future::poll_fn::PollFn<F> as core::future::future::Future>::poll::haedadc4a221cb649
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/futures-util-0.3.31/src/future/poll_fn.rs:56:9
[INFO] [stdout]   24:     0x649b0bbd1b7f - multi_link::multi_link_test::{{closure}}::hd70a5e09bbb05e92
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/multi_link.rs:384:5
[INFO] [stdout]   25:     0x649b0bbdf418 - multi_link::forceful_termination::{{closure}}::h5352f25b83714c59
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/multi_link.rs:624:88
[INFO] [stdout]   26:     0x649b0bba8112 - <core::pin::Pin<P> as core::future::future::Future>::poll::he7e25cbe4a5935e0
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/future/future.rs:133:9
[INFO] [stdout]   27:     0x649b0bbc703d - tokio::runtime::park::CachedParkThread::block_on::{{closure}}::h5dee93f0f5e3a4ed
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/park.rs:285:71
[INFO] [stdout]   28:     0x649b0bbc6a92 - tokio::task::coop::with_budget::hc8aab95b9bae8934
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:167:5
[INFO] [stdout]   29:     0x649b0bbc6a92 - tokio::task::coop::budget::h7785709f8ec4682f
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:133:5
[INFO] [stdout]   30:     0x649b0bbc6a92 - tokio::runtime::park::CachedParkThread::block_on::h53a9f01d5d225481
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/park.rs:285:31
[INFO] [stdout]   31:     0x649b0bbb7ef4 - tokio::runtime::context::blocking::BlockingRegionGuard::block_on::ha5fee8d49cbc7896
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/blocking.rs:66:14
[INFO] [stdout]   32:     0x649b0bb79348 - tokio::runtime::scheduler::multi_thread::MultiThread::block_on::{{closure}}::ha5dd432e8a211f48
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/multi_thread/mod.rs:87:22
[INFO] [stdout]   33:     0x649b0bc0773b - tokio::runtime::context::runtime::enter_runtime::ha9c353f0ab70b56a
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   34:     0x649b0bb79314 - tokio::runtime::scheduler::multi_thread::MultiThread::block_on::h50f228d1b5e9ee11
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/multi_thread/mod.rs:86:9
[INFO] [stdout]   35:     0x649b0bb946b4 - tokio::runtime::runtime::Runtime::block_on_inner::haa5ea2210be66dd7
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:358:50
[INFO] [stdout]   36:     0x649b0bb948ac - tokio::runtime::runtime::Runtime::block_on::haa1d87250ffa2936
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:330:18
[INFO] [stdout]   37:     0x649b0bbdeab1 - multi_link::forceful_termination::hd71db99be70f7d67
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/multi_link.rs:601:33
[INFO] [stdout]   38:     0x649b0bbdeb47 - multi_link::forceful_termination::{{closure}}::h0433c6056b178590
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/multi_link.rs:601:32
[INFO] [stdout]   39:     0x649b0bb1e396 - core::ops::function::FnOnce::call_once::h84700ddf89f23c78
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   40:     0x649b0bc388cb - core::ops::function::FnOnce::call_once::hddb3cd395c36bfbb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   41:     0x649b0bc388cb - test::__rust_begin_short_backtrace::h0b6a7601d9750bfa
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:663:18
[INFO] [stdout]   42:     0x649b0bc4c2da - test::run_test_in_process::{{closure}}::h444209903f00b347
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:74
[INFO] [stdout]   43:     0x649b0bc4c2da - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hfb3ba8f46e537649
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   44:     0x649b0bc4c2da - std::panicking::catch_unwind::do_call::h1672a45911fcf9dc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   45:     0x649b0bc4c2da - std::panicking::catch_unwind::h0fadaee22787a6dd
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   46:     0x649b0bc4c2da - std::panic::catch_unwind::hc47ad190f2c8e188
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   47:     0x649b0bc4c2da - test::run_test_in_process::hcd7faaf934f29999
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:27
[INFO] [stdout]   48:     0x649b0bc4c2da - test::run_test::{{closure}}::hddc4550da4871867
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:607:43
[INFO] [stdout]   49:     0x649b0bc26734 - test::run_test::{{closure}}::h98ae26689f13ed94
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:637:41
[INFO] [stdout]   50:     0x649b0bc26734 - std::sys::backtrace::__rust_begin_short_backtrace::h3ec4d1f21ad44588
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   51:     0x649b0bc2a0d2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::hd8bf2c73d479939f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   52:     0x649b0bc2a0d2 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h3b4248f358af2491
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   53:     0x649b0bc2a0d2 - std::panicking::catch_unwind::do_call::hda10a64f4b8daebe
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   54:     0x649b0bc2a0d2 - std::panicking::catch_unwind::h659e5ee8336ec8c7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   55:     0x649b0bc2a0d2 - std::panic::catch_unwind::h9d4d66a538912c18
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   56:     0x649b0bc2a0d2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::h7557906bb51d79f1
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   57:     0x649b0bc2a0d2 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9e460d1d7fc83d35
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   58:     0x649b0bef4d9f - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h61282da819d64ea9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   59:     0x649b0bef4d9f - std::sys::thread::unix::Thread::new::thread_start::h982f9ea829d1b5fb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   60:     0x754b23a18aa4 - <unknown>
[INFO] [stdout]   61:     0x754b23aa5a64 - clone
[INFO] [stdout]   62:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- five_x_very_high_latency stdout ----
[INFO] [stdout] server: starting
[INFO] [stdout] server: obtaining listener
[INFO] [stdout] server: adding incoming link 0
[INFO] [stdout] client: starting outgoing link
[INFO] [stdout] client: adding outgoing link 0
[INFO] [stdout] client: adding outgoing link 1
[INFO] [stdout] client: adding outgoing link 2
[INFO] [stdout] client: adding outgoing link 3
[INFO] [stdout] client: adding outgoing link 4
[INFO] [stdout] server: adding incoming link 1
[INFO] [stdout] server: adding incoming link 2
[INFO] [stdout] server: adding incoming link 3
[INFO] [stdout] server: adding incoming link 4
[INFO] [stdout] server: getting incoming connection
[INFO] [stdout] server: links of incoming connection: ["0", "1", "2", "3", "4"]
[INFO] [stdout] server: accepting incoming connection
[INFO] [stdout] server: waiting for links
[INFO] [stdout] server: sending and receiving test data
[INFO] [stdout] server: maximum send size is 9999999
[INFO] [stdout] server: sending 0
[INFO] [stdout] server: recving 0
[INFO] [stdout] 2026-03-11T00:44:36.615289Z  INFO aggligator::connection{conn_id=706b2f dir=incoming}: aggligator::agg::task: added new link link_id=868dcb
[INFO] [stdout] 2026-03-11T00:44:36.615330Z  INFO aggligator::connection{conn_id=706b2f dir=incoming}: aggligator::agg::task: added new link link_id=c69102
[INFO] [stdout] 2026-03-11T00:44:36.615383Z  INFO aggligator::connection{conn_id=706b2f dir=incoming}: aggligator::agg::task: added new link link_id=cf96c6
[INFO] [stdout] 2026-03-11T00:44:36.615451Z  INFO aggligator::connection{conn_id=706b2f dir=incoming}: aggligator::agg::task: added new link link_id=b1c985
[INFO] [stdout] 2026-03-11T00:44:36.615519Z  INFO aggligator::connection{conn_id=706b2f dir=incoming}: aggligator::agg::task: added new link link_id=8d0b17
[INFO] [stdout] 2026-03-11T00:44:37.616505Z  INFO aggligator::connection{conn_id=706b2f dir=outgoing}: aggligator::agg::task: added new link link_id=698847
[INFO] [stdout] ********* test channel was blocked for 0.32 s and last tried 0.32 s ago
[INFO] [stdout] 2026-03-11T00:44:37.927297Z  INFO aggligator::connection{conn_id=706b2f dir=outgoing}: aggligator::agg::task: added new link link_id=cfc19a
[INFO] [stdout] 2026-03-11T00:44:37.927393Z  INFO aggligator::connection{conn_id=706b2f dir=outgoing}: aggligator::agg::task: added new link link_id=81c637
[INFO] [stdout] ********* test channel was blocked for 0.57 s and last tried 0.57 s ago
[INFO] [stdout] client: waiting for links
[INFO] [stdout] 2026-03-11T00:44:39.014413Z  INFO aggligator::connection{conn_id=706b2f dir=outgoing}: aggligator::agg::task: added new link link_id=567e4c
[INFO] [stdout] 2026-03-11T00:44:39.014512Z  INFO aggligator::connection{conn_id=706b2f dir=outgoing}: aggligator::agg::task: added new link link_id=a5b87c
[INFO] [stdout] client: checking link info
[INFO] [stdout] client: links of outgoing connection: [Link { id: 698847, conn_id: 706b2f, direction: Outgoing, tag: "0" }, Link { id: cfc19a, conn_id: 706b2f, direction: Outgoing, tag: "1" }, Link { id: 81c637, conn_id: 706b2f, direction: Outgoing, tag: "2" }, Link { id: 567e4c, conn_id: 706b2f, direction: Outgoing, tag: "4" }, Link { id: a5b87c, conn_id: 706b2f, direction: Outgoing, tag: "3" }]
[INFO] [stdout] client: establishing connection
[INFO] [stdout] client: sending and receiving test data
[INFO] [stdout] client: sending 0
[INFO] [stdout] client: recving 0
[INFO] [stdout] ********* test channel was blocked for 1.95 s and last tried 1.95 s ago
[INFO] [stdout] 2026-03-11T00:45:06.616690Z  WARN aggligator::connection{conn_id=706b2f dir=incoming}: aggligator::agg::task: removing link due to unconfirmed timeout link_id=868dcb
[INFO] [stdout] 2026-03-11T00:45:06.616858Z  WARN aggligator::connection{conn_id=706b2f dir=incoming}: aggligator::agg::task: removing link due to unconfirmed timeout link_id=c69102
[INFO] [stdout] 2026-03-11T00:45:06.616976Z  WARN aggligator::connection{conn_id=706b2f dir=incoming}: aggligator::agg::task: removing link due to unconfirmed timeout link_id=cf96c6
[INFO] [stdout] 2026-03-11T00:45:06.617043Z  WARN aggligator::connection{conn_id=706b2f dir=incoming}: aggligator::agg::task: removing link due to unconfirmed timeout link_id=b1c985
[INFO] [stdout] 2026-03-11T00:45:06.617134Z  WARN aggligator::connection{conn_id=706b2f dir=incoming}: aggligator::agg::task: removing link due to unconfirmed timeout link_id=8d0b17
[INFO] [stdout] 2026-03-11T00:45:06.795685Z  WARN aggligator::connection{conn_id=706b2f dir=outgoing}: aggligator::agg::task: disconnecting link due to IO error link_id=567e4c err=broken pipe
[INFO] [stdout] 2026-03-11T00:45:06.892188Z  WARN aggligator::connection{conn_id=706b2f dir=outgoing}: aggligator::agg::task: disconnecting link due to IO error link_id=81c637 err=broken pipe
[INFO] [stdout] 2026-03-11T00:45:07.071981Z  WARN aggligator::connection{conn_id=706b2f dir=outgoing}: aggligator::agg::task: disconnecting link due to IO error link_id=a5b87c err=broken pipe
[INFO] [stdout] 2026-03-11T00:45:07.662026Z  WARN aggligator::connection{conn_id=706b2f dir=outgoing}: aggligator::agg::task: removing link due to unconfirmed timeout link_id=698847
[INFO] [stdout] 2026-03-11T00:45:08.128496Z  WARN aggligator::connection{conn_id=706b2f dir=outgoing}: aggligator::agg::task: removing link due to unconfirmed timeout link_id=cfc19a
[INFO] [stdout] 2026-03-11T00:46:06.616372Z  WARN aggligator::connection{conn_id=706b2f dir=incoming}: aggligator::agg::task: disconnecting because no links are available for too long
[INFO] [stdout] 2026-03-11T00:46:06.616533Z  INFO aggligator::connection{conn_id=706b2f dir=incoming}: aggligator::agg::task: return=Err(NoLinksTimeout)
[INFO] [stdout] 
[INFO] [stdout] thread 'five_x_very_high_latency' (21) panicked at tests/test_data/mod.rs:186:14:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: AllLinksFailed
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x649b0bf1f5a2 - std::backtrace_rs::backtrace::libunwind::trace::hff358a60abf734fc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x649b0bf1f5a2 - std::backtrace_rs::backtrace::trace_unsynchronized::h3b121f916dd95ec6
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x649b0bf1f5a2 - std::sys::backtrace::_print_fmt::hde0a62ded68798e9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x649b0bf1f5a2 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::h93773fc827e3113d
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x649b0bf30dba - core::fmt::rt::Argument::fmt::h01eff69902dad97f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x649b0bf30dba - core::fmt::write::hed7b5c73d82ecb7c
[INFO] [stdout]    6:     0x649b0beedad6 - std::io::default_write_fmt::h2f696ff5b8bbaa4b
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x649b0beedad6 - std::io::Write::write_fmt::h5e66814db8a9cfce
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x649b0befe739 - std::sys::backtrace::BacktraceLock::print::h8b1d6fcc5a56d1a3
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x649b0befe739 - std::panicking::default_hook::{{closure}}::h2be84df4f189ae36
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x649b0befe5d1 - std::panicking::default_hook::hf0ea8939246f43a9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x649b0bc38b0e - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h02d35e3888986252
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x649b0bc38b0e - test::test_main_with_exit_callback::{{closure}}::h939640a27d647173
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x649b0befea62 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hc9df09ff391af3e7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x649b0befea62 - std::panicking::panic_with_hook::hb4bd9ac1123582a0
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x649b0befe7f8 - std::panicking::panic_handler::{{closure}}::hde00dd15f5637fe2
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x649b0bef9b09 - std::sys::backtrace::__rust_end_short_backtrace::hb72197fa777c1785
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x649b0bee28fd - __rustc[4425a7e20b4c8619]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x649b0bf39fbc - core::panicking::panic_fmt::ha59b517dd231f4da
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x649b0bf39562 - core::result::unwrap_failed::hf2d1f30a3ac850fc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x649b0bb69acb - core::result::Result<T,E>::unwrap::hbd5e936959ca2cc9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/result.rs:1233:23
[INFO] [stdout]   21:     0x649b0bb69acb - multi_link::test_data::send_and_verify::{{closure}}::hb3bbbb0d1ac1b797
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/test_data/mod.rs:186:14
[INFO] [stdout]   22:     0x649b0bbd3e17 - multi_link::multi_link_test::{{closure}}::{{closure}}::h05d98a1709f9070f
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/multi_link.rs:164:10
[INFO] [stdout]   23:     0x649b0bbcdf4d - <futures_util::future::maybe_done::MaybeDone<Fut> as core::future::future::Future>::poll::ha77ff14ed96f060f
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/futures-util-0.3.31/src/future/maybe_done.rs:95:60
[INFO] [stdout]   24:     0x649b0bbd95f9 - multi_link::multi_link_test::{{closure}}::{{closure}}::h86933a07892592f5
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/futures-util-0.3.31/src/async_await/join_mod.rs:95:13
[INFO] [stdout]   25:     0x649b0bb79c88 - <futures_util::future::poll_fn::PollFn<F> as core::future::future::Future>::poll::haedadc4a221cb649
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/futures-util-0.3.31/src/future/poll_fn.rs:56:9
[INFO] [stdout]   26:     0x649b0bbd1b7f - multi_link::multi_link_test::{{closure}}::hd70a5e09bbb05e92
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/multi_link.rs:384:5
[INFO] [stdout]   27:     0x649b0bbe2420 - multi_link::five_x_very_high_latency::{{closure}}::h910da6eaf6c808ba
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/multi_link.rs:438:81
[INFO] [stdout]   28:     0x649b0bba8112 - <core::pin::Pin<P> as core::future::future::Future>::poll::he7e25cbe4a5935e0
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/future/future.rs:133:9
[INFO] [stdout]   29:     0x649b0bbc703d - tokio::runtime::park::CachedParkThread::block_on::{{closure}}::h5dee93f0f5e3a4ed
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/park.rs:285:71
[INFO] [stdout]   30:     0x649b0bbc6a92 - tokio::task::coop::with_budget::hc8aab95b9bae8934
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:167:5
[INFO] [stdout]   31:     0x649b0bbc6a92 - tokio::task::coop::budget::h7785709f8ec4682f
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/task/coop/mod.rs:133:5
[INFO] [stdout]   32:     0x649b0bbc6a92 - tokio::runtime::park::CachedParkThread::block_on::h53a9f01d5d225481
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/park.rs:285:31
[INFO] [stdout]   33:     0x649b0bbb7ef4 - tokio::runtime::context::blocking::BlockingRegionGuard::block_on::ha5fee8d49cbc7896
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/blocking.rs:66:14
[INFO] [stdout]   34:     0x649b0bb79348 - tokio::runtime::scheduler::multi_thread::MultiThread::block_on::{{closure}}::ha5dd432e8a211f48
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/multi_thread/mod.rs:87:22
[INFO] [stdout]   35:     0x649b0bc0773b - tokio::runtime::context::runtime::enter_runtime::ha9c353f0ab70b56a
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   36:     0x649b0bb79314 - tokio::runtime::scheduler::multi_thread::MultiThread::block_on::h50f228d1b5e9ee11
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/scheduler/multi_thread/mod.rs:86:9
[INFO] [stdout]   37:     0x649b0bb946b4 - tokio::runtime::runtime::Runtime::block_on_inner::haa5ea2210be66dd7
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:358:50
[INFO] [stdout]   38:     0x649b0bb948ac - tokio::runtime::runtime::Runtime::block_on::haa1d87250ffa2936
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.47.1/src/runtime/runtime.rs:330:18
[INFO] [stdout]   39:     0x649b0bbe1ac1 - multi_link::five_x_very_high_latency::h8e3de119073fa9c4
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/multi_link.rs:415:37
[INFO] [stdout]   40:     0x649b0bbe1b57 - multi_link::five_x_very_high_latency::{{closure}}::h66fac60f07c07686
[INFO] [stdout]                                at /opt/rustwide/workdir/tests/multi_link.rs:415:36
[INFO] [stdout]   41:     0x649b0bb1e416 - core::ops::function::FnOnce::call_once::h8b3210d22a394147
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x649b0bc388cb - core::ops::function::FnOnce::call_once::hddb3cd395c36bfbb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   43:     0x649b0bc388cb - test::__rust_begin_short_backtrace::h0b6a7601d9750bfa
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:663:18
[INFO] [stdout]   44:     0x649b0bc4c2da - test::run_test_in_process::{{closure}}::h444209903f00b347
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:74
[INFO] [stdout]   45:     0x649b0bc4c2da - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::hfb3ba8f46e537649
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   46:     0x649b0bc4c2da - std::panicking::catch_unwind::do_call::h1672a45911fcf9dc
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   47:     0x649b0bc4c2da - std::panicking::catch_unwind::h0fadaee22787a6dd
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   48:     0x649b0bc4c2da - std::panic::catch_unwind::hc47ad190f2c8e188
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   49:     0x649b0bc4c2da - test::run_test_in_process::hcd7faaf934f29999
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:686:27
[INFO] [stdout]   50:     0x649b0bc4c2da - test::run_test::{{closure}}::hddc4550da4871867
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:607:43
[INFO] [stdout]   51:     0x649b0bc26734 - test::run_test::{{closure}}::h98ae26689f13ed94
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/test/src/lib.rs:637:41
[INFO] [stdout]   52:     0x649b0bc26734 - std::sys::backtrace::__rust_begin_short_backtrace::h3ec4d1f21ad44588
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   53:     0x649b0bc2a0d2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::hd8bf2c73d479939f
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   54:     0x649b0bc2a0d2 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h3b4248f358af2491
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   55:     0x649b0bc2a0d2 - std::panicking::catch_unwind::do_call::hda10a64f4b8daebe
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:581:40
[INFO] [stdout]   56:     0x649b0bc2a0d2 - std::panicking::catch_unwind::h659e5ee8336ec8c7
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panicking.rs:544:19
[INFO] [stdout]   57:     0x649b0bc2a0d2 - std::panic::catch_unwind::h9d4d66a538912c18
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/panic.rs:359:14
[INFO] [stdout]   58:     0x649b0bc2a0d2 - std::thread::lifecycle::spawn_unchecked::{{closure}}::h7557906bb51d79f1
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   59:     0x649b0bc2a0d2 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h9e460d1d7fc83d35
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   60:     0x649b0bef4d9f - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h61282da819d64ea9
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   61:     0x649b0bef4d9f - std::sys::thread::unix::Thread::new::thread_start::h982f9ea829d1b5fb
[INFO] [stdout]                                at /rustc/4a4ef493e3a1488c6e321570238084b38948f6db/library/std/src/sys/thread/unix.rs:127:17
[INFO] [stdout]   62:     0x754b23a18aa4 - <unknown>
[INFO] [stdout]   63:     0x754b23aa5a64 - clone
[INFO] [stdout]   64:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     five_x_very_high_latency
[INFO] [stdout]     forceful_termination
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 8 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 100.07s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--test multi_link`
[INFO] running `Command { std: "docker" "inspect" "2f5cddf3125ba1255d498387734a6f50b8d836971eb6148b6c7fe570b911f35b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2f5cddf3125ba1255d498387734a6f50b8d836971eb6148b6c7fe570b911f35b", kill_on_drop: false }`
[INFO] [stdout] 2f5cddf3125ba1255d498387734a6f50b8d836971eb6148b6c7fe570b911f35b
