[INFO] fetching crate tehuti-mock 0.23.1...
[INFO] testing tehuti-mock-0.23.1 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate tehuti-mock 0.23.1 into /workspace/builds/worker-3-tc1/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-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate tehuti-mock 0.23.1 on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "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.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded smawk v0.3.2
[INFO] [stderr]   Downloaded textwrap v0.16.2
[INFO] [stderr]   Downloaded typid v1.1.2
[INFO] [stderr]   Downloaded unicode-linebreak v0.1.5
[INFO] [stderr]   Downloaded tehuti v0.23.1
[INFO] [stderr]   Downloaded web-sys v0.3.99
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-3-tc1/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] 27da63b96fe290ef7cf273ac15aa5ba968bdc04501a3453b68a5cc68f0c2da60
[INFO] running `Command { std: "docker" "start" "27da63b96fe290ef7cf273ac15aa5ba968bdc04501a3453b68a5cc68f0c2da60", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "27da63b96fe290ef7cf273ac15aa5ba968bdc04501a3453b68a5cc68f0c2da60", 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" "27da63b96fe290ef7cf273ac15aa5ba968bdc04501a3453b68a5cc68f0c2da60" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "27da63b96fe290ef7cf273ac15aa5ba968bdc04501a3453b68a5cc68f0c2da60", 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" "27da63b96fe290ef7cf273ac15aa5ba968bdc04501a3453b68a5cc68f0c2da60" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[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 log v0.4.29
[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 heapless v0.7.17
[INFO] [stderr]    Compiling futures-sink v0.3.32
[INFO] [stderr]    Compiling futures-core v0.3.32
[INFO] [stderr]    Compiling chacha20 v0.10.0
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling unicode-linebreak v0.1.5
[INFO] [stderr]    Compiling uuid v1.23.1
[INFO] [stderr]    Compiling leb128 v0.2.6
[INFO] [stderr]    Compiling smawk v0.3.2
[INFO] [stderr]    Compiling seahash v4.1.0
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling flume v0.12.0
[INFO] [stderr]    Compiling textwrap v0.16.2
[INFO] [stderr]    Compiling rand v0.10.1
[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 tracing v0.1.44
[INFO] [stderr]    Compiling cobs v0.3.0
[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]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 18.77s
[INFO] running `Command { std: "docker" "inspect" "27da63b96fe290ef7cf273ac15aa5ba968bdc04501a3453b68a5cc68f0c2da60", 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" "27da63b96fe290ef7cf273ac15aa5ba968bdc04501a3453b68a5cc68f0c2da60" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling tehuti-mock v0.23.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.91s
[INFO] running `Command { std: "docker" "inspect" "27da63b96fe290ef7cf273ac15aa5ba968bdc04501a3453b68a5cc68f0c2da60", 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" "27da63b96fe290ef7cf273ac15aa5ba968bdc04501a3453b68a5cc68f0c2da60" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.10s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/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] test middleware::tests::packet_loss_is_directional ... ok
[INFO] [stdout] test tests::test_mock_with_duplicate_middleware ... ok
[INFO] [stdout] [2m2026-10-06T19:42:07.625691Z[0m [35mTRACE[0m [2mtehuti::peer[0m[2m:[0m Registering Peer Role id PeerRoleId(0)
[INFO] [stdout] [2m2026-10-06T19:42:07.635459Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network started in thread ThreadId(19)
[INFO] [stdout] [2m2026-10-06T19:42:07.635523Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network port 'a' created
[INFO] [stdout] [2m2026-10-06T19:42:07.647448Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine started in thread ThreadId(20)
[INFO] [stdout] [2m2026-10-06T19:42:07.648349Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting opened: Mock Meeting
[INFO] [stdout] [2m2026-10-06T19:42:07.652353Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network port 'b' created
[INFO] [stdout] [2m2026-10-06T19:42:07.653452Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting registering engine: EngineId(10287324344070525406)
[INFO] [stdout] [2m2026-10-06T19:42:07.656448Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine started in thread ThreadId(22)
[INFO] [stdout] [2m2026-10-06T19:42:07.656517Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting opened: Mock Meeting
[INFO] [stdout] [2m2026-10-06T19:42:07.664365Z[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:07.664534Z[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:07.664610Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting created peer PeerId(0)
[INFO] [stdout] [2m2026-10-06T19:42:07.664656Z[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:07.665350Z[0m [35mTRACE[0m [2mtehuti::peer[0m[2m:[0m Peer PeerId(0) drop without destroying
[INFO] [stdout] [2m2026-10-06T19:42:07.665440Z[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:07.665484Z[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:07.667499Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting registering engine: EngineId(10287324344070525406)
[INFO] [stdout] [2m2026-10-06T19:42:07.667591Z[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:07.667642Z[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:07.667722Z[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:07.667788Z[0m [35mTRACE[0m [2mtehuti::peer[0m[2m:[0m Peer PeerId(0) drop without destroying
[INFO] [stdout] [2m2026-10-06T19:42:07.670381Z[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:07.670497Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network received frame Packet(ProtocolPacketFrame { packet_id: 297826099233504793212112864544106696966, 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:07.670575Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network send frame Packet(ProtocolPacketFrame { packet_id: 297826099233504793212112864544106696966, 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:07.676384Z[0m [35mTRACE[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting created peer PeerId(0)
[INFO] [stdout] [2m2026-10-06T19:42:07.676472Z[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:07.677417Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine terminating in thread ThreadId(20)
[INFO] [stdout] [2m2026-10-06T19:42:07.677481Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine stopped in thread ThreadId(20)
[INFO] [stdout] [2m2026-10-06T19:42:07.677614Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine terminating in thread ThreadId(22)
[INFO] [stdout] [2m2026-10-06T19:42:07.677649Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine stopped in thread ThreadId(22)
[INFO] [stdout] [2m2026-10-06T19:42:07.679342Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network terminating in thread ThreadId(19)
[INFO] [stdout] [2m2026-10-06T19:42:07.679411Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network stopped in thread ThreadId(19)
[INFO] [stdout] test tests::test_mock ... ok
[INFO] [stdout] [2m2026-10-06T19:42:07.681368Z[0m [33m WARN[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) is disconnected. Unregistering
[INFO] [stdout] [2m2026-10-06T19:42:07.694367Z[0m [33m WARN[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) is disconnected. Unregistering
[INFO] [stdout] [2m2026-10-06T19:42:07.694437Z[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:07.694514Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting handle engine event: PeerLeft(PeerId(0))
[INFO] [stdout] [2m2026-10-06T19:42:07.694554Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting peer left: PeerId(0)
[INFO] [stdout] [2m2026-10-06T19:42:07.694587Z[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:07.694606Z[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:07.694627Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting closed: Mock Meeting
[INFO] [stdout] [2m2026-10-06T19:42:07.715420Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine terminating in thread ThreadId(15)
[INFO] [stdout] [2m2026-10-06T19:42:07.715565Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine stopped in thread ThreadId(15)
[INFO] [stdout] [2m2026-10-06T19:42:07.728370Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine terminating in thread ThreadId(17)
[INFO] [stdout] [2m2026-10-06T19:42:07.728440Z[0m [35mTRACE[0m [2mtehuti::mock::machine[0m[2m:[0m Mock machine stopped in thread ThreadId(17)
[INFO] [stdout] [2m2026-10-06T19:42:07.728588Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network terminating in thread ThreadId(10)
[INFO] [stdout] [2m2026-10-06T19:42:07.728650Z[0m [35mTRACE[0m [2mtehuti::mock::network[0m[2m:[0m Mock network stopped in thread ThreadId(10)
[INFO] [stdout] [2m2026-10-06T19:42:07.728683Z[0m [33m WARN[0m [2mtehuti::engine::meeting[0m[2m:[0m Meeting engine EngineId(10287324344070525406) is disconnected. Unregistering
[INFO] [stdout] [2m2026-10-06T19:42:07.728724Z[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:07.728774Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting handle engine event: PeerLeft(PeerId(0))
[INFO] [stdout] [2m2026-10-06T19:42:07.728814Z[0m [35mTRACE[0m [2mtehuti::meeting[0m[2m:[0m Meeting Mock Meeting peer left: PeerId(0)
[INFO] [stdout] [2m2026-10-06T19:42:07.728835Z[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:07.728851Z[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:07.728870Z[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.21s
[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" "27da63b96fe290ef7cf273ac15aa5ba968bdc04501a3453b68a5cc68f0c2da60", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "27da63b96fe290ef7cf273ac15aa5ba968bdc04501a3453b68a5cc68f0c2da60", kill_on_drop: false }`
[INFO] [stdout] 27da63b96fe290ef7cf273ac15aa5ba968bdc04501a3453b68a5cc68f0c2da60
