[INFO] fetching crate tehuti-mock 0.23.1...
[INFO] testing tehuti-mock-0.23.1 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate tehuti-mock 0.23.1 into /workspace/builds/worker-3-tc2/source
[INFO] started tweaking crates.io crate tehuti-mock 0.23.1
[INFO] finished tweaking crates.io crate tehuti-mock 0.23.1
[INFO] tweaked toml for crates.io crate tehuti-mock 0.23.1 written to /workspace/builds/worker-3-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate tehuti-mock 0.23.1 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate tehuti-mock 0.23.1 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.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:31:12
[INFO] [stderr]    |
[INFO] [stderr] 31 | homepage = "https://github.com/PsichiX/tehuti"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 35 | repository = "https://github.com/PsichiX/tehuti"
[INFO] [stderr]    |              -----------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `tehuti-mock` (manifest) generated 1 warning
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc2/target:/opt/rustwide/target:rw,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" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] da73f50d1e7d5b0896b69e99b2f01f64145fc82ec4c4aca289ae3020955167f5
[INFO] running `Command { std: "docker" "start" "da73f50d1e7d5b0896b69e99b2f01f64145fc82ec4c4aca289ae3020955167f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "da73f50d1e7d5b0896b69e99b2f01f64145fc82ec4c4aca289ae3020955167f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "da73f50d1e7d5b0896b69e99b2f01f64145fc82ec4c4aca289ae3020955167f5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "da73f50d1e7d5b0896b69e99b2f01f64145fc82ec4c4aca289ae3020955167f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "da73f50d1e7d5b0896b69e99b2f01f64145fc82ec4c4aca289ae3020955167f5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:31:12
[INFO] [stderr]    |
[INFO] [stderr] 31 | homepage = "https://github.com/PsichiX/tehuti"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 35 | repository = "https://github.com/PsichiX/tehuti"
[INFO] [stderr]    |              -----------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `tehuti-mock` (manifest) generated 1 warning
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling rust_decimal v1.42.0
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling rustc_version v0.4.1
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling hash32 v0.2.1
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling spin v0.9.8
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling log v0.4.29
[INFO] [stderr]    Compiling chacha20 v0.10.0
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling smawk v0.3.2
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling uuid v1.23.1
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling unicode-linebreak v0.1.5
[INFO] [stderr]    Compiling seahash v4.1.0
[INFO] [stderr]    Compiling leb128 v0.2.6
[INFO] [stderr]    Compiling rand v0.10.1
[INFO] [stderr]    Compiling heapless v0.7.17
[INFO] [stderr]    Compiling flume v0.12.0
[INFO] [stderr]    Compiling textwrap v0.16.2
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling thiserror-impl v2.0.18
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling cobs v0.3.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling typid v1.1.2
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling postcard v1.1.3
[INFO] [stderr]    Compiling tehuti v0.23.1
[INFO] [stderr]    Compiling tehuti-mock v0.23.1 (/opt/rustwide/workdir)
[INFO] [stderr] warning: unused dependency `textwrap`
[INFO] [stderr]  --> Cargo.toml:7:15
[INFO] [stderr]   |
[INFO] [stderr] 7 | [dependencies.textwrap]
[INFO] [stderr]   |               ^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `textwrap`
[INFO] [stderr] warning: `tehuti-mock` (manifest) generated 1 warning
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 18.27s
[INFO] running `Command { std: "docker" "inspect" "da73f50d1e7d5b0896b69e99b2f01f64145fc82ec4c4aca289ae3020955167f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "da73f50d1e7d5b0896b69e99b2f01f64145fc82ec4c4aca289ae3020955167f5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:31:12
[INFO] [stderr]    |
[INFO] [stderr] 31 | homepage = "https://github.com/PsichiX/tehuti"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 35 | repository = "https://github.com/PsichiX/tehuti"
[INFO] [stderr]    |              -----------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `tehuti-mock` (manifest) generated 1 warning
[INFO] [stderr]    Compiling tehuti-mock v0.23.1 (/opt/rustwide/workdir)
[INFO] [stderr] warning: unused dependency `textwrap`
[INFO] [stderr]  --> Cargo.toml:7:15
[INFO] [stderr]   |
[INFO] [stderr] 7 | [dependencies.textwrap]
[INFO] [stderr]   |               ^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `textwrap`
[INFO] [stderr] warning: `tehuti-mock` (manifest) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.03s
[INFO] running `Command { std: "docker" "inspect" "da73f50d1e7d5b0896b69e99b2f01f64145fc82ec4c4aca289ae3020955167f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-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" "-w" "/opt/rustwide/workdir" "--user" "0:0" "da73f50d1e7d5b0896b69e99b2f01f64145fc82ec4c4aca289ae3020955167f5" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] warning: `package.homepage` is redundant with `package.repository`
[INFO] [stderr]   --> Cargo.toml:31:12
[INFO] [stderr]    |
[INFO] [stderr] 31 | homepage = "https://github.com/PsichiX/tehuti"
[INFO] [stderr]    |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr] ...
[INFO] [stderr] 35 | repository = "https://github.com/PsichiX/tehuti"
[INFO] [stderr]    |              -----------------------------------
[INFO] [stderr]    |
[INFO] [stderr]    = note: `cargo::redundant_homepage` is set to `warn` by default
[INFO] [stderr] help: consider removing `package.homepage`
[INFO] [stderr] warning: `tehuti-mock` (manifest) generated 1 warning
[INFO] [stderr] warning: unused dependency `textwrap`
[INFO] [stderr]  --> Cargo.toml:7:15
[INFO] [stderr]   |
[INFO] [stderr] 7 | [dependencies.textwrap]
[INFO] [stderr]   |               ^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the dependency on `textwrap`
[INFO] [stderr] warning: `tehuti-mock` (manifest) generated 1 warning
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.10s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/tehuti-mock/e7d78cd36980ac04/out/tehuti_mock-e7d78cd36980ac04)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test middleware::tests::packet_loss_drops_when_chance_is_one ... ok
[INFO] [stdout] test middleware::tests::packet_duplicate_adds_expected_number_of_copies ... ok
[INFO] [stdout] test middleware::tests::packet_latency_uses_base_delay_when_jitter_is_zero ... ok
[INFO] [stdout] [2m2026-10-06T19:42:31.814850Z[0m [35mTRACE[0m [2mtehuti::peer[0m[2m:[0m Registering Peer Role id PeerRoleId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.815110Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network started in thread ThreadId(11)
[INFO] [stdout] [2m2026-10-06T19:42:31.815179Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network port 'a' created
[INFO] [stdout] test middleware::tests::packet_loss_is_directional ... ok
[INFO] [stdout] [2m2026-10-06T19:42:31.816382Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network started in thread ThreadId(9)
[INFO] [stdout] [2m2026-10-06T19:42:31.816445Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network port 'a' created
[INFO] [stdout] [2m2026-10-06T19:42:31.818373Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network started in thread ThreadId(10)
[INFO] [stdout] [2m2026-10-06T19:42:31.818400Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network port 'a' created
[INFO] [stdout] [2m2026-10-06T19:42:31.819493Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine started in thread ThreadId(12)
[INFO] [stdout] [2m2026-10-06T19:42:31.819538Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting opened: Mock Meeting
[INFO] [stdout] [2m2026-10-06T19:42:31.820465Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting registering engine: EngineId(10287324344070525406)
[INFO] [stdout] [2m2026-10-06T19:42:31.822555Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine started in thread ThreadId(14)
[INFO] [stdout] [2m2026-10-06T19:42:31.822594Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting opened: Mock Meeting
[INFO] [stdout] [2m2026-10-06T19:42:31.823703Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting registering engine: EngineId(10287324344070525406)
[INFO] [stdout] [2m2026-10-06T19:42:31.826341Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network port 'b' created
[INFO] [stdout] [2m2026-10-06T19:42:31.827359Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network port 'b' created
[INFO] [stdout] [2m2026-10-06T19:42:31.830534Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine started in thread ThreadId(18)
[INFO] [stdout] [2m2026-10-06T19:42:31.830570Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting opened: Mock Meeting
[INFO] [stdout] [2m2026-10-06T19:42:31.831037Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting registering engine: EngineId(10287324344070525406)
[INFO] [stdout] [2m2026-10-06T19:42:31.833370Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine started in thread ThreadId(17)
[INFO] [stdout] [2m2026-10-06T19:42:31.833422Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting opened: Mock Meeting
[INFO] [stdout] [2m2026-10-06T19:42:31.838412Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting handle user event: PeerCreate(PeerId(0), PeerRoleId(0))
[INFO] [stdout] [2m2026-10-06T19:42:31.838499Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting peer created: PeerInfo { peer_id: PeerId(0), role_id: PeerRoleId(0), remote: false }
[INFO] [stdout] [2m2026-10-06T19:42:31.838553Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting created peer PeerId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.838568Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) sending create peer PeerId(0) with role PeerRoleId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.838634Z[0m [35mTRACE[0m [2mtehuti::peer[0m[2m:[0m Peer PeerId(0) drop without destroying
[INFO] [stdout] [2m2026-10-06T19:42:31.838691Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network received frame Control(CreatePeer(PeerId(0), PeerRoleId(0))) from node 'a'
[INFO] [stdout] [2m2026-10-06T19:42:31.838727Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network send frame Control(CreatePeer(PeerId(0), PeerRoleId(0))) to node 'b'
[INFO] [stdout] [2m2026-10-06T19:42:31.842378Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting handle user event: PeerCreate(PeerId(0), PeerRoleId(0))
[INFO] [stdout] [2m2026-10-06T19:42:31.842448Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting peer created: PeerInfo { peer_id: PeerId(0), role_id: PeerRoleId(0), remote: false }
[INFO] [stdout] [2m2026-10-06T19:42:31.842501Z[0m [35mTRACE[0m [2mtehuti::peer[0m[2m:[0m Peer PeerId(0) drop without destroying
[INFO] [stdout] [2m2026-10-06T19:42:31.842544Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting created peer PeerId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.842567Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) sending create peer PeerId(0) with role PeerRoleId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.843342Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network received frame Control(CreatePeer(PeerId(0), PeerRoleId(0))) from node 'a'
[INFO] [stdout] [2m2026-10-06T19:42:31.843406Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network send frame Control(CreatePeer(PeerId(0), PeerRoleId(0))) to node 'b'
[INFO] [stdout] [2m2026-10-06T19:42:31.936069Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) got create peer PeerId(0) with role PeerRoleId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.936135Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting handle engine event: PeerJoined(EngineId(10287324344070525406), PeerId(0), PeerRoleId(0))
[INFO] [stdout] [2m2026-10-06T19:42:31.936204Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting peer joined: PeerInfo { peer_id: PeerId(0), role_id: PeerRoleId(0), remote: true }
[INFO] [stdout] [2m2026-10-06T19:42:31.936259Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting created peer PeerId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.936370Z[0m [35mTRACE[0m [2mtehuti::peer[0m[2m:[0m Peer PeerId(0) drop without destroying
[INFO] [stdout] [2m2026-10-06T19:42:31.946456Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) sending packet frame for peer PeerId(0) channel ChannelId(0). 23 bytes
[INFO] [stdout] [2m2026-10-06T19:42:31.948398Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine started in thread ThreadId(13)
[INFO] [stdout] [2m2026-10-06T19:42:31.948446Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting opened: Mock Meeting
[INFO] [stdout] [2m2026-10-06T19:42:31.950368Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network port 'b' created
[INFO] [stdout] [2m2026-10-06T19:42:31.950543Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting registering engine: EngineId(10287324344070525406)
[INFO] [stdout] [2m2026-10-06T19:42:31.950603Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) got create peer PeerId(0) with role PeerRoleId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.950629Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine started in thread ThreadId(22)
[INFO] [stdout] [2m2026-10-06T19:42:31.950643Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting handle engine event: PeerJoined(EngineId(10287324344070525406), PeerId(0), PeerRoleId(0))
[INFO] [stdout] [2m2026-10-06T19:42:31.950663Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting opened: Mock Meeting
[INFO] [stdout] [2m2026-10-06T19:42:31.950711Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting peer joined: PeerInfo { peer_id: PeerId(0), role_id: PeerRoleId(0), remote: true }
[INFO] [stdout] [2m2026-10-06T19:42:31.950764Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting created peer PeerId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.951358Z[0m [35mTRACE[0m [2mtehuti::peer[0m[2m:[0m Peer PeerId(0) drop without destroying
[INFO] [stdout] [2m2026-10-06T19:42:31.952555Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting registering engine: EngineId(10287324344070525406)
[INFO] [stdout] [2m2026-10-06T19:42:31.952608Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting handle user event: PeerCreate(PeerId(0), PeerRoleId(0))
[INFO] [stdout] [2m2026-10-06T19:42:31.952677Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting peer created: PeerInfo { peer_id: PeerId(0), role_id: PeerRoleId(0), remote: false }
[INFO] [stdout] [2m2026-10-06T19:42:31.952726Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting created peer PeerId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.952743Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) sending create peer PeerId(0) with role PeerRoleId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.952609Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting registering engine: EngineId(10287324344070525406)
[INFO] [stdout] [2m2026-10-06T19:42:31.959419Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) sending packet frame for peer PeerId(0) channel ChannelId(0). 28 bytes
[INFO] [stdout] [2m2026-10-06T19:42:31.966381Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network received frame Control(CreatePeer(PeerId(0), PeerRoleId(0))) from node 'a'
[INFO] [stdout] [2m2026-10-06T19:42:31.966466Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network send frame Control(CreatePeer(PeerId(0), PeerRoleId(0))) to node 'b'
[INFO] [stdout] [2m2026-10-06T19:42:31.968351Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) got create peer PeerId(0) with role PeerRoleId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.968409Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting handle engine event: PeerJoined(EngineId(10287324344070525406), PeerId(0), PeerRoleId(0))
[INFO] [stdout] [2m2026-10-06T19:42:31.968484Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting peer joined: PeerInfo { peer_id: PeerId(0), role_id: PeerRoleId(0), remote: true }
[INFO] [stdout] [2m2026-10-06T19:42:31.968520Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting created peer PeerId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.969213Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network received frame Packet(ProtocolPacketFrame { packet_id: 160843111778893572095968248705039320845, peer_id: PeerId(0), channel_id: ChannelId(0), channel_mode: ReliableOrdered, data: ProtocolPacketData { data (size): 28, recepients: [], sender: None } }) from node 'a'
[INFO] [stdout] [2m2026-10-06T19:42:31.969339Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network send frame Packet(ProtocolPacketFrame { packet_id: 160843111778893572095968248705039320845, peer_id: PeerId(0), channel_id: ChannelId(0), channel_mode: ReliableOrdered, data: ProtocolPacketData { data (size): 28, recepients: [], sender: None } }) to node 'b'
[INFO] [stdout] [2m2026-10-06T19:42:31.971378Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) got packet frame for peer PeerId(0) channel ChannelId(0): 28 bytes
[INFO] [stdout] [2m2026-10-06T19:42:31.972347Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine terminating in thread ThreadId(12)
[INFO] [stdout] [2m2026-10-06T19:42:31.972597Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine stopped in thread ThreadId(12)
[INFO] [stdout] [2m2026-10-06T19:42:31.972660Z[0m [35mTRACE[0m [2mtehuti::peer[0m[2m:[0m Peer PeerId(0) drop without destroying
[INFO] [stdout] [2m2026-10-06T19:42:31.972745Z[0m [35mTRACE[0m [2mtehuti::peer[0m[2m:[0m Peer PeerId(0) drop without destroying
[INFO] [stdout] [2m2026-10-06T19:42:31.973363Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) sending packet frame for peer PeerId(0) channel ChannelId(0). 14 bytes
[INFO] [stdout] [2m2026-10-06T19:42:31.977388Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine terminating in thread ThreadId(17)
[INFO] [stdout] [2m2026-10-06T19:42:31.977522Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine stopped in thread ThreadId(17)
[INFO] [stdout] [2m2026-10-06T19:42:31.982336Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network terminating in thread ThreadId(11)
[INFO] [stdout] [2m2026-10-06T19:42:31.982362Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network stopped in thread ThreadId(11)
[INFO] [stdout] [2m2026-10-06T19:42:31.985350Z[0m [33m WARN[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) is disconnected. Unregistering
[INFO] [stdout] [2m2026-10-06T19:42:31.985364Z[0m [33m WARN[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) is disconnected. Unregistering
[INFO] [stdout] [2m2026-10-06T19:42:31.985993Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting destroying peer PeerId(0) due to engine EngineId(10287324344070525406) disconnection
[INFO] [stdout] [2m2026-10-06T19:42:31.986664Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting handle engine event: PeerLeft(PeerId(0))
[INFO] [stdout] [2m2026-10-06T19:42:31.986874Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting peer left: PeerId(0)
[INFO] [stdout] [2m2026-10-06T19:42:31.987082Z[0m [31mERROR[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting encountered error: User event sender error: sending on a closed channel. Terminating
[INFO] [stdout] [2m2026-10-06T19:42:31.987264Z[0m [31mERROR[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting terminated with error: User event sender error: sending on a closed channel
[INFO] [stdout] [2m2026-10-06T19:42:31.987489Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting closed: Mock Meeting
[INFO] [stdout] test tests::test_mock ... ok
[INFO] [stdout] [2m2026-10-06T19:42:31.994403Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network received frame Packet(ProtocolPacketFrame { packet_id: 56703976568352393930269422064247873450, peer_id: PeerId(0), channel_id: ChannelId(0), channel_mode: ReliableOrdered, data: ProtocolPacketData { data (size): 14, recepients: [], sender: None } }) from node 'a'
[INFO] [stdout] [2m2026-10-06T19:42:31.994492Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network received frame Packet(ProtocolPacketFrame { packet_id: 56703976568352393930269422064247873450, peer_id: PeerId(0), channel_id: ChannelId(0), channel_mode: ReliableOrdered, data: ProtocolPacketData { data (size): 14, recepients: [], sender: None } }) from node 'a'
[INFO] [stdout] [2m2026-10-06T19:42:31.994549Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network send frame Packet(ProtocolPacketFrame { packet_id: 56703976568352393930269422064247873450, peer_id: PeerId(0), channel_id: ChannelId(0), channel_mode: ReliableOrdered, data: ProtocolPacketData { data (size): 14, recepients: [], sender: None } }) to node 'b'
[INFO] [stdout] [2m2026-10-06T19:42:31.994580Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network send frame Packet(ProtocolPacketFrame { packet_id: 56703976568352393930269422064247873450, peer_id: PeerId(0), channel_id: ChannelId(0), channel_mode: ReliableOrdered, data: ProtocolPacketData { data (size): 14, recepients: [], sender: None } }) to node 'b'
[INFO] [stdout] [2m2026-10-06T19:42:31.995039Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) got packet frame for peer PeerId(0) channel ChannelId(0): 14 bytes
[INFO] [stdout] [2m2026-10-06T19:42:31.995076Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) got packet frame for peer PeerId(0) channel ChannelId(0): 14 bytes
[INFO] [stdout] [2m2026-10-06T19:42:32.004387Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine terminating in thread ThreadId(13)
[INFO] [stdout] [2m2026-10-06T19:42:32.004439Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine stopped in thread ThreadId(13)
[INFO] [stdout] [2m2026-10-06T19:42:32.011049Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine terminating in thread ThreadId(22)
[INFO] [stdout] [2m2026-10-06T19:42:32.011106Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine stopped in thread ThreadId(22)
[INFO] [stdout] [2m2026-10-06T19:42:32.014382Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network terminating in thread ThreadId(10)
[INFO] [stdout] [2m2026-10-06T19:42:32.014444Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network stopped in thread ThreadId(10)
[INFO] [stdout] [2m2026-10-06T19:42:32.014479Z[0m [33m WARN[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) is disconnected. Unregistering
[INFO] [stdout] [2m2026-10-06T19:42:32.014529Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting destroying peer PeerId(0) due to engine EngineId(10287324344070525406) disconnection
[INFO] [stdout] [2m2026-10-06T19:42:32.014582Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting handle engine event: PeerLeft(PeerId(0))
[INFO] [stdout] [2m2026-10-06T19:42:32.014616Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting peer left: PeerId(0)
[INFO] [stdout] test tests::test_mock_with_duplicate_middleware ... ok
[INFO] [stdout] [2m2026-10-06T19:42:32.014658Z[0m [31mERROR[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting encountered error: User event sender error: sending on a closed channel. Terminating
[INFO] [stdout] [2m2026-10-06T19:42:32.014680Z[0m [31mERROR[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting terminated with error: User event sender error: sending on a closed channel
[INFO] [stdout] [2m2026-10-06T19:42:32.014701Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting closed: Mock Meeting
[INFO] [stdout] [2m2026-10-06T19:42:32.014812Z[0m [33m WARN[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) is disconnected. Unregistering
[INFO] [stdout] [2m2026-10-06T19:42:32.039366Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine terminating in thread ThreadId(14)
[INFO] [stdout] [2m2026-10-06T19:42:32.039434Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine stopped in thread ThreadId(14)
[INFO] [stdout] [2m2026-10-06T19:42:32.039584Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine terminating in thread ThreadId(18)
[INFO] [stdout] [2m2026-10-06T19:42:32.039631Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine stopped in thread ThreadId(18)
[INFO] [stdout] [2m2026-10-06T19:42:32.039755Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network terminating in thread ThreadId(9)
[INFO] [stdout] [2m2026-10-06T19:42:32.039772Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network stopped in thread ThreadId(9)
[INFO] [stdout] [2m2026-10-06T19:42:32.039798Z[0m [33m WARN[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) is disconnected. Unregistering
[INFO] [stdout] [2m2026-10-06T19:42:32.039825Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting destroying peer PeerId(0) due to engine EngineId(10287324344070525406) disconnection
[INFO] [stdout] [2m2026-10-06T19:42:32.039810Z[0m [33m WARN[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) is disconnected. Unregistering
[INFO] [stdout] [2m2026-10-06T19:42:32.039881Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting handle engine event: PeerLeft(PeerId(0))
[INFO] [stdout] [2m2026-10-06T19:42:32.039913Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting peer left: PeerId(0)
[INFO] [stdout] [2m2026-10-06T19:42:32.039933Z[0m [31mERROR[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting encountered error: User event sender error: sending on a closed channel. Terminating
[INFO] [stdout] [2m2026-10-06T19:42:32.039948Z[0m [31mERROR[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting terminated with error: User event sender error: sending on a closed channel
[INFO] [stdout] [2m2026-10-06T19:42:32.039966Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting closed: Mock Meeting
[INFO] [stdout] test tests::test_mock_with_loss_middleware ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.23s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests tehuti_mock
[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] running `Command { std: "docker" "inspect" "da73f50d1e7d5b0896b69e99b2f01f64145fc82ec4c4aca289ae3020955167f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "da73f50d1e7d5b0896b69e99b2f01f64145fc82ec4c4aca289ae3020955167f5", kill_on_drop: false }`
[INFO] [stdout] da73f50d1e7d5b0896b69e99b2f01f64145fc82ec4c4aca289ae3020955167f5
