[INFO] fetching crate tehuti-mock 0.22.1...
[INFO] testing tehuti-mock-0.22.1 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-2
[INFO] extracting crate tehuti-mock 0.22.1 into /workspace/builds/worker-5-tc2/source
[INFO] started tweaking crates.io crate tehuti-mock 0.22.1
[INFO] finished tweaking crates.io crate tehuti-mock 0.22.1
[INFO] tweaked toml for crates.io crate tehuti-mock 0.22.1 written to /workspace/builds/worker-5-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate tehuti-mock 0.22.1 on toolchain dec9417b8611e34e787a3e4c37686b5131f9e5c5
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate tehuti-mock 0.22.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" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] afa07e4fd465c6abeed181c4bf0f469c686ec99a6bb5d109b2c56caf6902fd11
[INFO] running `Command { std: "docker" "start" "-a" "afa07e4fd465c6abeed181c4bf0f469c686ec99a6bb5d109b2c56caf6902fd11", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "afa07e4fd465c6abeed181c4bf0f469c686ec99a6bb5d109b2c56caf6902fd11", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "afa07e4fd465c6abeed181c4bf0f469c686ec99a6bb5d109b2c56caf6902fd11", kill_on_drop: false }`
[INFO] [stdout] afa07e4fd465c6abeed181c4bf0f469c686ec99a6bb5d109b2c56caf6902fd11
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 2a77dc591d48361f20892b72ddf7fee84086fda0a1bdfb2a880e7966650dc30b
[INFO] running `Command { std: "docker" "start" "-a" "2a77dc591d48361f20892b72ddf7fee84086fda0a1bdfb2a880e7966650dc30b", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling thiserror v2.0.18
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling rust_decimal v1.41.0
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling arrayvec v0.7.6
[INFO] [stderr]    Compiling heapless v0.7.17
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling unicode-linebreak v0.1.5
[INFO] [stderr]    Compiling leb128 v0.2.5
[INFO] [stderr]    Compiling smawk v0.3.2
[INFO] [stderr]    Compiling uuid v1.22.0
[INFO] [stderr]    Compiling rand v0.10.0
[INFO] [stderr]    Compiling flume v0.12.0
[INFO] [stderr]    Compiling textwrap v0.16.2
[INFO] [stderr]    Compiling tracing-log v0.2.0
[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.22.1
[INFO] [stderr]    Compiling tehuti-mock v0.22.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 19.74s
[INFO] running `Command { std: "docker" "inspect" "2a77dc591d48361f20892b72ddf7fee84086fda0a1bdfb2a880e7966650dc30b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "2a77dc591d48361f20892b72ddf7fee84086fda0a1bdfb2a880e7966650dc30b", kill_on_drop: false }`
[INFO] [stdout] 2a77dc591d48361f20892b72ddf7fee84086fda0a1bdfb2a880e7966650dc30b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] ec3ae26afdbed56fac09c95a072c4107af239b8e68943281084aab59d5fd04ad
[INFO] running `Command { std: "docker" "start" "-a" "ec3ae26afdbed56fac09c95a072c4107af239b8e68943281084aab59d5fd04ad", kill_on_drop: false }`
[INFO] [stderr]    Compiling tehuti-mock v0.22.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.26s
[INFO] running `Command { std: "docker" "inspect" "ec3ae26afdbed56fac09c95a072c4107af239b8e68943281084aab59d5fd04ad", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ec3ae26afdbed56fac09c95a072c4107af239b8e68943281084aab59d5fd04ad", kill_on_drop: false }`
[INFO] [stdout] ec3ae26afdbed56fac09c95a072c4107af239b8e68943281084aab59d5fd04ad
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+dec9417b8611e34e787a3e4c37686b5131f9e5c5" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 52d58c47c223e48c3ba35379c132fd78925021115deb964fe25442e62951a6ae
[INFO] running `Command { std: "docker" "start" "-a" "52d58c47c223e48c3ba35379c132fd78925021115deb964fe25442e62951a6ae", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.14s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/tehuti_mock-30323a09b9550df7)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] 2026-04-20T00:42:17.124351Z TRACE tehuti::peer: Registering Peer Role id PeerRoleId(0)
[INFO] [stdout] 2026-04-20T00:42:17.124832Z TRACE tehuti::mock::network: Mock network started in thread ThreadId(3)
[INFO] [stdout] 2026-04-20T00:42:17.124878Z TRACE tehuti::mock::network: Mock network port 'a' created
[INFO] [stdout] 2026-04-20T00:42:17.125114Z TRACE tehuti::mock::machine: Mock machine started in thread ThreadId(4)
[INFO] [stdout] 2026-04-20T00:42:17.125326Z TRACE tehuti::mock::meeting: Mock meeting 1 started in thread ThreadId(5)
[INFO] [stdout] 2026-04-20T00:42:17.125375Z TRACE tehuti::meeting: Meeting opened: 1-ThreadId(5)
[INFO] [stdout] 2026-04-20T00:42:17.125501Z TRACE tehuti::mock::network: Mock network port 'b' created
[INFO] [stdout] 2026-04-20T00:42:17.125683Z TRACE tehuti::mock::machine: Mock machine started in thread ThreadId(6)
[INFO] [stdout] 2026-04-20T00:42:17.125836Z TRACE tehuti::mock::meeting: Mock meeting 1 started in thread ThreadId(7)
[INFO] [stdout] 2026-04-20T00:42:17.125860Z TRACE tehuti::meeting: Meeting opened: 1-ThreadId(7)
[INFO] [stdout] 2026-04-20T00:42:17.125959Z TRACE tehuti::meeting: Meeting 1-ThreadId(5) handle user event: PeerCreate(PeerId(0), PeerRoleId(0))
[INFO] [stdout] 2026-04-20T00:42:17.126060Z TRACE tehuti::meeting: Meeting 1-ThreadId(5) peer created: PeerInfo { peer_id: PeerId(0), role_id: PeerRoleId(0), remote: false }
[INFO] [stdout] 2026-04-20T00:42:17.126115Z TRACE tehuti::mock::meeting: Mock meeting 1 created peer PeerId(0) in thread ThreadId(5)
[INFO] [stdout] 2026-04-20T00:42:17.126135Z TRACE tehuti::peer: Peer PeerId(0) drop without destroying
[INFO] [stdout] 2026-04-20T00:42:17.126178Z TRACE tehuti::mock::network: Mock network received frame Control(CreatePeer(PeerId(0), PeerRoleId(0))) from node 'a'
[INFO] [stdout] 2026-04-20T00:42:17.126209Z TRACE tehuti::mock::network: Mock network send frame Control(CreatePeer(PeerId(0), PeerRoleId(0))) to node 'b'
[INFO] [stdout] 2026-04-20T00:42:17.128067Z TRACE tehuti::meeting: Meeting 1-ThreadId(7) handle engine event: PeerJoined(EngineId(0), PeerId(0), PeerRoleId(0))
[INFO] [stdout] 2026-04-20T00:42:17.128140Z TRACE tehuti::meeting: Meeting 1-ThreadId(7) peer joined: PeerInfo { peer_id: PeerId(0), role_id: PeerRoleId(0), remote: true }
[INFO] [stdout] 2026-04-20T00:42:17.128194Z TRACE tehuti::mock::meeting: Mock meeting 1 created peer PeerId(0) in thread ThreadId(7)
[INFO] [stdout] 2026-04-20T00:42:17.128200Z TRACE tehuti::peer: Peer PeerId(0) drop without destroying
[INFO] [stdout] 2026-04-20T00:42:17.135078Z TRACE tehuti::mock::network: Mock network received frame Packet(ProtocolPacketFrame { peer_id: PeerId(0), channel_id: ChannelId(0), data: ProtocolPacketData { data (size): 28, recepients: [], sender: None } }) from node 'a'
[INFO] [stdout] 2026-04-20T00:42:17.135146Z TRACE tehuti::mock::network: Mock network send frame Packet(ProtocolPacketFrame { peer_id: PeerId(0), channel_id: ChannelId(0), data: ProtocolPacketData { data (size): 28, recepients: [], sender: None } }) to node 'b'
[INFO] [stdout] 2026-04-20T00:42:17.140096Z TRACE tehuti::mock::machine: Mock machine terminating in thread ThreadId(4)
[INFO] [stdout] 2026-04-20T00:42:17.144068Z TRACE tehuti::mock::machine: Mock machine stopped in thread ThreadId(4)
[INFO] [stdout] 2026-04-20T00:42:17.152109Z TRACE tehuti::mock::meeting: Mock meeting 1 terminating in thread ThreadId(5)
[INFO] [stdout] 2026-04-20T00:42:17.152248Z TRACE tehuti::mock::meeting: Mock meeting 1 stopped in thread ThreadId(5)
[INFO] [stdout] 2026-04-20T00:42:17.152325Z TRACE tehuti::meeting: Meeting closed: 1-ThreadId(5)
[INFO] [stdout] 2026-04-20T00:42:17.160063Z TRACE tehuti::mock::machine: Mock machine terminating in thread ThreadId(6)
[INFO] [stdout] 2026-04-20T00:42:17.160122Z TRACE tehuti::mock::machine: Mock machine stopped in thread ThreadId(6)
[INFO] [stdout] 2026-04-20T00:42:17.160156Z TRACE tehuti::mock::meeting: Mock meeting 1 terminating in thread ThreadId(7)
[INFO] [stdout] 2026-04-20T00:42:17.160173Z TRACE tehuti::mock::meeting: Mock meeting 1 stopped in thread ThreadId(7)
[INFO] [stdout] 2026-04-20T00:42:17.160208Z TRACE tehuti::meeting: Meeting closed: 1-ThreadId(7)
[INFO] [stdout] 2026-04-20T00:42:17.160649Z TRACE tehuti::mock::network: Mock network terminating in thread ThreadId(3)
[INFO] [stdout] 2026-04-20T00:42:17.160737Z TRACE tehuti::mock::network: Mock network stopped in thread ThreadId(3)
[INFO] [stderr]    Doc-tests tehuti_mock
[INFO] [stdout] test tests::test_mock ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[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" "52d58c47c223e48c3ba35379c132fd78925021115deb964fe25442e62951a6ae", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "52d58c47c223e48c3ba35379c132fd78925021115deb964fe25442e62951a6ae", kill_on_drop: false }`
[INFO] [stdout] 52d58c47c223e48c3ba35379c132fd78925021115deb964fe25442e62951a6ae