[INFO] fetching crate wl-proxy 0.1.2...
[INFO] testing wl-proxy-0.1.2 against try#dec9417b8611e34e787a3e4c37686b5131f9e5c5 for pr-154210-1
[INFO] extracting crate wl-proxy 0.1.2 into /workspace/builds/worker-4-tc2/source
[INFO] started tweaking crates.io crate wl-proxy 0.1.2
[INFO] finished tweaking crates.io crate wl-proxy 0.1.2
[INFO] tweaked toml for crates.io crate wl-proxy 0.1.2 written to /workspace/builds/worker-4-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate wl-proxy 0.1.2 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 wl-proxy 0.1.2 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] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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] c6dc0d66485adafae5ea6994dd74e6fd9a312a8797f38995dda56cfce6e116c7
[INFO] running `Command { std: "docker" "start" "-a" "c6dc0d66485adafae5ea6994dd74e6fd9a312a8797f38995dda56cfce6e116c7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "c6dc0d66485adafae5ea6994dd74e6fd9a312a8797f38995dda56cfce6e116c7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c6dc0d66485adafae5ea6994dd74e6fd9a312a8797f38995dda56cfce6e116c7", kill_on_drop: false }`
[INFO] [stdout] c6dc0d66485adafae5ea6994dd74e6fd9a312a8797f38995dda56cfce6e116c7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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] 08cd2c796292865c4c494303d4bc3ecd479d06f5d7ad7785ca3fd8fb63ebf4b5
[INFO] running `Command { std: "docker" "start" "-a" "08cd2c796292865c4c494303d4bc3ecd479d06f5d7ad7785ca3fd8fb63ebf4b5", kill_on_drop: false }`
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stderr]    Compiling phf_shared v0.13.1
[INFO] [stderr]    Compiling linearize v0.1.5
[INFO] [stderr]    Compiling debug-fn v1.0.0
[INFO] [stderr]    Compiling uapi v0.2.13
[INFO] [stderr]    Compiling isnt v0.2.0
[INFO] [stderr]    Compiling error_reporter v1.0.0
[INFO] [stderr]    Compiling run-on-drop v1.0.0
[INFO] [stderr]    Compiling syn v2.0.111
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling phf_generator v0.13.1
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling regex v1.12.2
[INFO] [stderr]    Compiling thiserror-impl v2.0.17
[INFO] [stderr]    Compiling linearize-derive v0.2.1
[INFO] [stderr]    Compiling phf_macros v0.13.1
[INFO] [stderr]    Compiling uapi-proc v0.0.5
[INFO] [stderr]    Compiling phf v0.13.1
[INFO] [stderr]    Compiling thiserror v2.0.17
[INFO] [stderr]    Compiling wl-proxy v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 11.71s
[INFO] running `Command { std: "docker" "inspect" "08cd2c796292865c4c494303d4bc3ecd479d06f5d7ad7785ca3fd8fb63ebf4b5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "08cd2c796292865c4c494303d4bc3ecd479d06f5d7ad7785ca3fd8fb63ebf4b5", kill_on_drop: false }`
[INFO] [stdout] 08cd2c796292865c4c494303d4bc3ecd479d06f5d7ad7785ca3fd8fb63ebf4b5
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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] 15543f529778fd86f175afb783bf4bbc91877eb80aa04829307ab228f81e8a5d
[INFO] running `Command { std: "docker" "start" "-a" "15543f529778fd86f175afb783bf4bbc91877eb80aa04829307ab228f81e8a5d", kill_on_drop: false }`
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling jiff v0.2.16
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling regex v1.12.2
[INFO] [stderr]    Compiling uapi-proc v0.0.5
[INFO] [stderr]    Compiling env_filter v0.1.4
[INFO] [stderr]    Compiling uapi v0.2.13
[INFO] [stderr]    Compiling env_logger v0.11.8
[INFO] [stderr]    Compiling wl-proxy v0.1.2 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 17.49s
[INFO] running `Command { std: "docker" "inspect" "15543f529778fd86f175afb783bf4bbc91877eb80aa04829307ab228f81e8a5d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "15543f529778fd86f175afb783bf4bbc91877eb80aa04829307ab228f81e8a5d", kill_on_drop: false }`
[INFO] [stdout] 15543f529778fd86f175afb783bf4bbc91877eb80aa04829307ab228f81e8a5d
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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] ec717094867f21740dc33f5deb94a66d8678f54008ade15e0077a09c04f9cbe8
[INFO] running `Command { std: "docker" "start" "-a" "ec717094867f21740dc33f5deb94a66d8678f54008ade15e0077a09c04f9cbe8", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/wl_proxy-c279e5984628a866)
[INFO] [stdout] 
[INFO] [stdout] running 111 tests
[INFO] [stderr] [ 322018.088] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322018.581] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322018.647] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322018.665] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322018.681] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322018.700] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322018.708] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322018.724] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322018.732] {client} server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [ 322018.739] {client} server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#5)
[INFO] [stderr] [ 322018.758] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322018.759] {proxy } client#2    -> wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [ 322018.770] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322018.783] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322018.795] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322018.833] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322018.845] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322018.874] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322018.905] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322018.961] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322018.975] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322018.994] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322019.013] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322019.037] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322019.170] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [2026-04-10T11:02:55Z ERROR wl_proxy::state] Could not handle client message: could not handle a wl_display#1.65535 message: unknown message id 65535
[INFO] [stderr] [ 322019.201] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322019.232] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322019.242] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322019.260] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322019.283] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322019.311] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322019.326] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322019.458] {client} server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [ 322019.479] {client} server      <= wlproxy_test#3.echo_object(echo: wlproxy_test_object_echo#5, object: unknown#4)
[INFO] [stderr] [ 322019.487] {client} server      <= wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [ 322019.516] {proxy } client#2    -> wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [ 322019.538] {proxy } server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [ 322019.545] {proxy } client#2    -> wlproxy_test#3.echo_object(echo: wlproxy_test_object_echo#5, object: unknown#4)
[INFO] [stderr] [ 322019.564] {proxy } server      <= wlproxy_test#3.echo_object(echo: wlproxy_test_object_echo#5, object: unknown#4)
[INFO] [stderr] [ 322019.569] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [ 322019.576] {proxy } server      <= wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [ 322019.601] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322019.624] {server} client#2    -> wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [ 322019.627] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322019.638] {server} client#2    -> wlproxy_test#3.echo_object(echo: wlproxy_test_object_echo#5, object: unknown#4)
[INFO] [stderr] [ 322019.646] {server} client#2    <= wlproxy_test_object_echo#5.object(obj: unknown#4)
[INFO] [stderr] [ 322019.659] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322019.668] {server} client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322019.679] {server} client#2    -> wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [ 322019.681] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322019.685] {server} client#2    <= wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [ 322019.690] {server} client#2    <= wl_display#1.delete_id(id: 6)
[INFO] [stderr] [ 322019.697] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322019.722] {proxy } server      -> wlproxy_test_object_echo#5.object(obj: unknown#4)
[INFO] [stderr] [ 322019.724] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322019.726] {proxy } client#2    <= wlproxy_test_object_echo#5.object(obj: unknown#4)
[INFO] [stderr] [ 322019.762] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322019.768] {proxy } client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322019.774] {proxy } server      -> wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [ 322019.778] {proxy } client#2    <= wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [ 322019.781] {proxy } server      -> wl_display#1.delete_id(id: 6)
[INFO] [stderr] [ 322019.785] {proxy } client#2    <= wl_display#1.delete_id(id: 6)
[INFO] [stderr] [ 322019.807] {client} server      -> wlproxy_test_object_echo#5.object(obj: unknown#4)
[INFO] [stderr] [ 322019.820] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322019.825] {client} server      -> wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [ 322019.829] {client} server      -> wl_display#1.delete_id(id: 6)
[INFO] [stderr] [ 322024.973] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322025.010] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322025.055] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322025.064] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322025.073] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322025.088] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322025.116] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [2026-04-10T11:02:55Z ERROR wl_proxy::state] Could not handle client message: could not read a message: message has a supposed length 65535 > 4096
[INFO] [stdout] test fixed::tests::debug ... ok
[INFO] [stdout] test fixed::tests::display ... ok
[INFO] [stdout] test endpoint::tests::dispatch_destroyed ... ok
[INFO] [stdout] test fixed::tests::from_ints ... ok
[INFO] [stdout] test client::tests::unset_handler ... ok
[INFO] [stdout] test endpoint::tests::invalid_message ... ok
[INFO] [stderr] [ 322037.858] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322037.951] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322038.034] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322038.091] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322038.135] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322038.178] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [2026-04-10T11:02:55Z WARN  wl_proxy::global_mapper] Could not add synthetic global: the message handler is already borrowed
[INFO] [stdout] test client::tests::disconnected ... ok
[INFO] [stderr] [2026-04-10T11:02:55Z WARN  wl_proxy::global_mapper] Could not handle client bind: the message handler is already borrowed
[INFO] [stdout] test endpoint::tests::lookup ... ok
[INFO] [stdout] test fixed::tests::from_f32 ... ok
[INFO] [stdout] test endpoint::tests::test ... ok
[INFO] [stdout] test fixed::tests::minima ... ok
[INFO] [stdout] test fixed::tests::one_two ... ok
[INFO] [stdout] test fixed::tests::test_shiftops ... ok
[INFO] [stdout] test fixed::tests::test_unops ... ok
[INFO] [stdout] test fixed::tests::to_f32 ... ok
[INFO] [stdout] test client::tests::objects ... ok
[INFO] [stdout] test fixed::tests::test_binops ... ok
[INFO] [stdout] test fixed::tests::from_i64 ... ok
[INFO] [stdout] test fixed::tests::from_i32 ... ok
[INFO] [stdout] test fixed::tests::to_i32_floor ... ok
[INFO] [stdout] test global_mapper::tests::test ... ok
[INFO] [stdout] test fixed::tests::to_i32_nearest ... ok
[INFO] [stdout] test global_mapper::tests::test_add_synthetic_global_error ... ok
[INFO] [stdout] test fixed::tests::to_i32_zero ... ok
[INFO] [stdout] test fixed::tests::wire ... ok
[INFO] [stdout] test global_mapper::tests::test_default ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_bind ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_bind_error ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_global ... ok
[INFO] [stderr] [2026-04-10T11:02:55Z WARN  wl_proxy::global_mapper] Server sent wl_registry.global_remove for name 999 but no such global exists
[INFO] [stderr] [2026-04-10T11:02:55Z WARN  wl_proxy::global_mapper] Client sent wl_registry.bind for name 999 but not such global exists
[INFO] [stdout] test global_mapper::tests::test_forward_global_remove ... ok
[INFO] [stdout] test global_mapper::tests::test_ignore_global ... ok
[INFO] [stdout] test global_mapper::tests::test_remove_synthetic_global ... ok
[INFO] [stdout] test global_mapper::tests::test_try_add_synthetic_global_error ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_global_remove_ignored ... ok
[INFO] [stdout] test fixed::tests::to_i32_ceil ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_global_remove_nonexistent ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_bind_nonexistent ... ok
[INFO] [stderr] [2026-04-10T11:02:55Z WARN  wl_proxy::global_mapper] Could not remove synthetic global: the message handler is already borrowed
[INFO] [stderr] [2026-04-10T11:02:55Z WARN  wl_proxy::global_mapper] Could not handle server global remove: the message handler is already borrowed
[INFO] [stderr] [2026-04-10T11:02:55Z WARN  wl_proxy::global_mapper] Could not handle server global: the message handler is already borrowed
[INFO] [stderr] [2026-04-10T11:02:55Z WARN  wl_proxy::global_mapper] Client sent wl_registry.bind for name 999 but not such global exists
[INFO] [stdout] test global_mapper::tests::test_remove_synthetic_global_error ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_global_error ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_global_remove_error ... ok
[INFO] [stdout] test global_mapper::tests::test_try_add_synthetic_global ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_bind_synthetic ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_bind ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_bind_error ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_bind_nonexistent ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_bind_synthetic ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_global ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_global_error ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_global_remove ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_global_remove_error ... ok
[INFO] [stdout] test global_mapper::tests::test_try_remove_synthetic_global ... ok
[INFO] [stdout] test global_mapper::tests::test_try_remove_synthetic_global_error ... ok
[INFO] [stderr] [2026-04-10T11:02:55Z WARN  wl_proxy::global_mapper] Server sent wl_registry.global_remove for name 999 but no such global exists
[INFO] [stderr] [ 322063.232] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322063.275] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322063.315] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322063.324] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322063.347] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322063.376] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322063.409] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322063.433] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322063.459] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322063.469] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322063.480] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322063.486] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322063.522] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322063.532] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322063.543] {server} client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322063.552] {server} client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322063.567] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322063.575] {server} client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322063.579] {server} client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322063.584] {server} client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stdout] test handler::tests::borrow ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_global_remove_nonexistent ... ok
[INFO] [stdout] test handler::tests::clone ... ok
[INFO] [stdout] test handler::tests::map ... ok
[INFO] [stdout] test object::tests::destroyed_client ... ok
[INFO] [stderr] [ 322063.704] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322063.726] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322063.765] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322063.775] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322063.779] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322063.782] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322063.796] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322063.793] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322063.835] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322063.848] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322063.856] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322064.014] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322064.027] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322064.055] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322064.064] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322064.071] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322064.085] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322064.123] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322064.144] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#4294967295)
[INFO] [stderr] [ 322064.144] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322064.150] {proxy } client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322064.164] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322064.171] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322064.178] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322064.180] {proxy } client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322064.185] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322064.189] {proxy } client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322064.212] {client} server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322064.216] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322064.220] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322064.224] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [2026-04-10T11:02:55Z ERROR wl_proxy::state] Could not handle client message: could not handle a wl_display#1.sync message: could not assign client id 4294967295 to argument callback: the id 4294967295 is too large to be a client id
[INFO] [stderr] [ 322064.703] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322064.743] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322064.794] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322064.806] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322064.824] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322064.842] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322064.888] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322064.917] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322064.930] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322065.134] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322065.147] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322065.159] {server} client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322065.171] {server} client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322065.188] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322065.336] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322065.351] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322065.377] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322065.386] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322065.393] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322065.406] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322065.469] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322065.475] {proxy } client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322065.482] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322065.488] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stdout] test object::tests::client_object_with_server_id ... ok
[INFO] [stderr] [ 322065.514] {client} server      -> wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4294967295)
[INFO] [stderr] [ 322065.525] {client} server      -> wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4294967295)
[INFO] [stderr] [ 322065.626] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322065.654] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322065.667] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322065.702] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322065.713] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322065.725] {server} client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322065.737] {server} client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322065.756] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322065.799] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322065.803] {proxy } client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322065.808] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322065.813] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322065.835] {client} server      -> wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#50)
[INFO] [stderr] [ 322066.070] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322066.157] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322066.230] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322066.249] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322066.497] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322066.561] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322066.576] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322066.607] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322066.617] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322066.640] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322066.655] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322066.846] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322066.859] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322066.891] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322066.901] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322066.908] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322066.922] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322066.958] {client} server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [ 322066.964] {client} server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [ 322066.977] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322066.996] {proxy } client#2    -> wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [ 322066.999] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322067.009] {proxy } server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [ 322067.015] {proxy } client#2    -> wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [2026-04-10T11:02:55Z ERROR wl_proxy::state] Could not handle client message: could not handle a wlproxy_test#3.create_dummy message: could not assign client id 4 to argument id: the client id 4 is already in use
[INFO] [stderr] [ 322067.234] {server} client#2    -> wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [ 322067.586] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322067.762] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322067.787] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322067.816] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322067.833] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322067.841] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322067.855] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322067.889] {proxy } client#2    <= wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4278190080)
[INFO] [stderr] [ 322067.822] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test object::tests::double_send ... ok
[INFO] [stderr] [ 322068.827] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322069.048] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322069.174] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test object::tests::client_id ... ok
[INFO] [stderr] [ 322069.313] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322069.348] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322069.381] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322069.401] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322069.436] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322069.461] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322069.956] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322069.957] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322069.988] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322070.017] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322070.025] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322070.033] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322070.045] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322070.080] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322070.096] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322070.235] {client} server      <= wlproxy_test#3.send_object()
[INFO] [stderr] [ 322070.259] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322070.288] {proxy } client#2    -> wlproxy_test#3.send_object()
[INFO] [stderr] [ 322070.301] {proxy } server      <= wlproxy_test#3.send_object()
[INFO] [stderr] [ 322070.306] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322070.317] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322070.362] {server} client#2    -> wlproxy_test#3.send_object()
[INFO] [stderr] [ 322070.385] {server} client#2    <= wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4278190080)
[INFO] [stderr] [ 322070.400] {server} client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322070.407] {server} client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322070.413] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322070.475] {proxy } server      -> wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4278190080)
[INFO] [stderr] [ 322070.494] {proxy } client#2    <= wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4278190080)
[INFO] [stderr] [ 322070.521] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322070.533] {proxy } client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322070.538] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322070.544] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322070.567] {client} server      -> wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4278190080)
[INFO] [stderr] [ 322070.583] {client} server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322070.587] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322070.600] {client} server      <= wlproxy_test_server_sent#4278190080.send_destroy()
[INFO] [stderr] [ 322070.616] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322070.636] {proxy } client#2    -> wlproxy_test_server_sent#4278190080.send_destroy()
[INFO] [stderr] [ 322070.665] {proxy } server      <= wlproxy_test_server_sent#4278190080.send_destroy()
[INFO] [stderr] [ 322070.670] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322070.677] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322070.709] {server} client#2    -> wlproxy_test_server_sent#4278190080.send_destroy()
[INFO] [stderr] [ 322070.722] {server} client#2    <= wlproxy_test_server_sent#4278190080.destroyed()
[INFO] [stderr] [ 322070.731] {server} client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322070.738] {server} client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322070.743] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322070.776] {proxy } server      -> wlproxy_test_server_sent#4278190080.destroyed()
[INFO] [stderr] [ 322070.789] {proxy } client#2    <= wlproxy_test_server_sent#4278190080.destroyed()
[INFO] [stderr] [ 322070.797] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322070.800] {proxy } client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322070.805] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322070.810] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322070.830] {client} server      -> wlproxy_test_server_sent#4278190080.destroyed()
[INFO] [stderr] [ 322070.843] {client} server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322070.847] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stdout] test object::tests::get_handler ... ok
[INFO] [stdout] test object::tests::duplicate_client_id ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_global_remove_ignored ... ok
[INFO] [stdout] test object::tests::duplicate_generated_client_id ... ok
[INFO] [stdout] test object::tests::request_without_server ... ok
[INFO] [stdout] test object::tests::server_destroyed ... ok
[INFO] [stderr] [ 322076.569] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test object::tests::server_sent ... ok
[INFO] [stderr] [ 322076.602] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stdout] test object::tests::client ... ok
[INFO] [stderr] [ 322076.640] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test handler::tests::replace ... ok
[INFO] [stderr] [ 322076.649] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test poll::tests::edge_trigger ... ok
[INFO] [stderr] [ 322076.658] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322076.673] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322076.703] {client} server      <= wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#4)
[INFO] [stderr] [ 322076.708] {client} server      <= wlproxy_test_non_forward#4.echo()
[INFO] [stderr] [ 322076.714] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322076.716] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322076.730] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322076.737] {proxy } client#2    -> wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#4)
[INFO] [stderr] [ 322076.748] {proxy } server      <= wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#4)
[INFO] [stderr] [ 322076.754] {proxy } client#2    -> wlproxy_test_non_forward#4.echo()
[INFO] [stderr] [ 322076.758] {proxy } server      <= wlproxy_test_non_forward#4.echo()
[INFO] [stderr] [ 322076.761] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322076.768] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322076.802] {server} client#2    -> wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#4)
[INFO] [stderr] [ 322076.816] {server} client#2    -> wlproxy_test_non_forward#4.echo()
[INFO] [stderr] [ 322076.820] {server} client#2    <= wlproxy_test_non_forward#4.echoed()
[INFO] [stderr] [ 322076.836] {server} client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322076.842] {server} client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322076.848] {server} client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322076.895] {proxy } server      -> wlproxy_test_non_forward#4.echoed()
[INFO] [stderr] [ 322076.899] {proxy } client#2    <= wlproxy_test_non_forward#4.echoed()
[INFO] [stderr] [ 322076.907] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322076.910] {proxy } client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322076.914] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322076.920] {proxy } client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322076.940] {client} server      -> wlproxy_test_non_forward#4.echoed()
[INFO] [stderr] [ 322076.944] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322076.947] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322076.962] {client} server      <= wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#5)
[INFO] [stderr] [ 322076.965] {client} server      <= wlproxy_test_non_forward#5.echo()
[INFO] [stderr] [ 322076.970] {client} server      <= wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [ 322076.996] {proxy } client#2    -> wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#5)
[INFO] [stderr] [ 322077.003] {proxy } client#2    -> wlproxy_test_non_forward#5.echo()
[INFO] [stderr] [ 322077.007] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [ 322077.013] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322077.036] {server} client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322077.040] {server} client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322077.046] {server} client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322077.072] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322077.075] {proxy } client#2    <= wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [ 322077.079] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322077.083] {proxy } client#2    <= wl_display#1.delete_id(id: 6)
[INFO] [stderr] [ 322077.099] {client} server      -> wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [ 322077.103] {client} server      -> wl_display#1.delete_id(id: 6)
[INFO] [stderr] [ 322077.115] {client} server      <= wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#6)
[INFO] [stderr] [ 322077.118] {client} server      <= wlproxy_test_non_forward#6.echo()
[INFO] [stderr] [ 322077.126] {client} server      <= wl_display#1.sync(callback: wl_callback#7)
[INFO] [stderr] [ 322077.144] {proxy } client#2    -> wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#6)
[INFO] [stderr] [ 322077.150] {proxy } client#2    -> wlproxy_test_non_forward#6.echo()
[INFO] [stderr] [2026-04-10T11:02:55Z WARN  wl_proxy::protocol_helpers] Could not forward a wlproxy_test_non_forward.echo message: the receiver has no server id
[INFO] [stderr] [ 322077.241] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#7)
[INFO] [stderr] [ 322077.264] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322077.287] {server} client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322077.292] {server} client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322077.297] {server} client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322077.324] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322077.327] {proxy } client#2    <= wl_callback#7.done(callback_data: 0)
[INFO] [stderr] [ 322077.331] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322077.335] {proxy } client#2    <= wl_display#1.delete_id(id: 7)
[INFO] [stderr] [ 322077.351] {client} server      -> wl_callback#7.done(callback_data: 0)
[INFO] [stderr] [ 322077.354] {client} server      -> wl_display#1.delete_id(id: 7)
[INFO] [stderr] [ 322077.366] {client} server      <= wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#7)
[INFO] [stderr] [ 322077.369] {client} server      <= wlproxy_test_non_forward#7.echo()
[INFO] [stderr] [ 322077.376] {client} server      <= wl_display#1.sync(callback: wl_callback#8)
[INFO] [stderr] [ 322077.391] {proxy } client#2    -> wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#7)
[INFO] [stderr] [ 322077.397] {proxy } server      <= wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#5)
[INFO] [stderr] [ 322077.401] {proxy } client#2    -> wlproxy_test_non_forward#7.echo()
[INFO] [stderr] [ 322077.403] {proxy } server      <= wlproxy_test_non_forward#5.echo()
[INFO] [stderr] [ 322077.406] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#8)
[INFO] [stderr] [ 322077.431] {proxy } server      <= wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [ 322077.454] {server} client#2    -> wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#5)
[INFO] [stderr] [ 322077.461] {server} client#2    -> wlproxy_test_non_forward#5.echo()
[INFO] [stderr] [ 322077.463] {server} client#2    <= wlproxy_test_non_forward#5.echoed()
[INFO] [stderr] [ 322077.467] {server} client#2    -> wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [ 322077.472] {server} client#2    <= wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [ 322077.476] {server} client#2    <= wl_display#1.delete_id(id: 6)
[INFO] [stderr] [ 322077.502] {proxy } server      -> wlproxy_test_non_forward#5.echoed()
[INFO] [stderr] [ 322077.505] {proxy } client#2    <= wlproxy_test_non_forward#7.echoed()
[INFO] [stderr] [ 322077.508] {proxy } server      -> wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [ 322077.511] {proxy } client#2    <= wl_callback#8.done(callback_data: 0)
[INFO] [stderr] [ 322077.514] {proxy } server      -> wl_display#1.delete_id(id: 6)
[INFO] [stderr] [ 322077.519] {proxy } client#2    <= wl_display#1.delete_id(id: 8)
[INFO] [stderr] [ 322077.535] {client} server      -> wlproxy_test_non_forward#7.echoed()
[INFO] [stderr] [ 322077.538] {client} server      -> wl_callback#8.done(callback_data: 0)
[INFO] [stderr] [ 322077.541] {client} server      -> wl_display#1.delete_id(id: 8)
[INFO] [stdout] test poll::tests::test ... ok
[INFO] [stdout] test object::tests::forward ... ok
[INFO] [stdout] test state::tests::add_client ... ok
[INFO] [stdout] test state::tests::destroyed_readable ... ok
[INFO] [stdout] test state::tests::destructor ... ok
[INFO] [stdout] test state::tests::closed_client ... ok
[INFO] [stdout] test state::tests::remote_destructor ... ok
[INFO] [stdout] test state::tests::suspend1 ... ok
[INFO] [stderr] [ 322094.652] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322094.686] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322094.744] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322094.755] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322094.771] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322094.783] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322094.790] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322094.806] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322094.823] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322094.839] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322094.848] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322094.849] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322094.868] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322094.881] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322094.902] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322094.914] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322094.916] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322094.924] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322094.926] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322094.942] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322094.973] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322094.984] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322095.033] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322095.078] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322095.098] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322095.123] {server} client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322095.135] {server} client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322095.167] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322095.199] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322095.212] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322095.227] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322095.232] {proxy } client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322095.241] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322095.257] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322096.453] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322096.483] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322096.516] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322096.539] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322096.549] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322096.574] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322096.604] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322096.629] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322096.633] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322096.650] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322096.653] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322096.672] {server} client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322096.688] {server} client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322096.706] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322096.800] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322096.830] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322096.856] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322096.875] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322096.897] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322096.918] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322097.025] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322097.052] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322097.075] {proxy } client#3    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322097.092] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#4)
[INFO] [stderr] [ 322097.097] {proxy } client#3    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322097.107] {proxy } server      <= wl_registry#4.bind(name: 0, id: wlproxy_test#5 (version: 1))
[INFO] [stderr] [ 322097.122] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x61)
[INFO] [stderr] [ 322097.130] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#5, array: 0x62)
[INFO] [stderr] [ 322097.147] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#6, array: 0x63)
[INFO] [stderr] [ 322097.155] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#7, array: 0x64)
[INFO] [stderr] [ 322097.161] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x78)
[INFO] [stderr] [ 322097.177] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#5, array: 0x79)
[INFO] [stderr] [ 322097.219] {proxy } client#3    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x78)
[INFO] [stderr] [ 322097.239] {proxy } client#3    <= wlproxy_test_array_echo#4.array(array: 0x78)
[INFO] [stderr] [ 322097.253] {proxy } client#3    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#5, array: 0x79)
[INFO] [stderr] [ 322097.266] {proxy } client#3    <= wlproxy_test_array_echo#5.array(array: 0x79)
[INFO] [stderr] [ 322097.275] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x61)
[INFO] [stderr] [ 322097.282] {proxy } client#2    <= wlproxy_test_array_echo#4.array(array: 0x61)
[INFO] [stderr] [ 322097.310] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x61)
[INFO] [stderr] [ 322097.330] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x78)
[INFO] [stderr] [ 322097.333] {client} server      -> wlproxy_test_array_echo#5.array(array: 0x79)
[INFO] [stderr] [ 322097.344] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#6, array: 0x7a)
[INFO] [stderr] [ 322097.361] {proxy } client#3    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#6, array: 0x7a)
[INFO] [stderr] [ 322097.375] {proxy } client#3    <= wlproxy_test_array_echo#6.array(array: 0x7a)
[INFO] [stderr] [ 322097.392] {client} server      -> wlproxy_test_array_echo#6.array(array: 0x7a)
[INFO] [stderr] [ 322097.413] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#5, array: 0x62)
[INFO] [stderr] [ 322097.430] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322097.438] {proxy } client#2    <= wlproxy_test_array_echo#5.array(array: 0x62)
[INFO] [stderr] [ 322097.450] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322097.462] {client} server      -> wlproxy_test_array_echo#5.array(array: 0x62)
[INFO] [stderr] [ 322097.478] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#6, array: 0x63)
[INFO] [stderr] [ 322097.486] {proxy } client#2    <= wlproxy_test_array_echo#6.array(array: 0x63)
[INFO] [stderr] [ 322097.490] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322097.492] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#7, array: 0x64)
[INFO] [stderr] [ 322097.498] {proxy } client#2    <= wlproxy_test_array_echo#7.array(array: 0x64)
[INFO] [stderr] [ 322097.499] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322097.518] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322097.521] {client} server      -> wlproxy_test_array_echo#6.array(array: 0x63)
[INFO] [stderr] [ 322097.532] {client} server      -> wlproxy_test_array_echo#7.array(array: 0x64)
[INFO] [stderr] [ 322097.540] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322097.563] {proxy } client#2    <= wl_display#1.error(object_id: unknown#3, code: 2, message: "abcd")
[INFO] [stderr] [ 322097.583] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322097.594] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322097.602] {client} server      -> wl_display#1.error(object_id: unknown#3, code: 2, message: "abcd")
[INFO] [stderr] [ 322097.761] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322097.777] {proxy } client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322097.787] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322097.793] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322097.830] {client} server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322097.845] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322098.877] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322098.906] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322098.937] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322098.958] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322098.976] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322099.004] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322099.027] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x61)
[INFO] [stderr] [ 322099.048] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#5, array: 0x62)
[INFO] [stderr] [ 322099.054] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#6, array: 0x63)
[INFO] [stderr] [ 322099.063] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#7, array: 0x64)
[INFO] [stderr] [ 322099.101] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x61)
[INFO] [stderr] [ 322099.117] {proxy } client#2    <= wlproxy_test_array_echo#4.array(array: 0x61)
[INFO] [stderr] [ 322099.153] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x61)
[INFO] [stderr] [ 322099.177] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#5, array: 0x62)
[INFO] [stderr] [ 322099.184] {proxy } client#2    <= wlproxy_test_array_echo#5.array(array: 0x62)
[INFO] [stderr] [ 322099.225] {client} server      -> wlproxy_test_array_echo#5.array(array: 0x62)
[INFO] [stderr] [ 322099.242] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#6, array: 0x63)
[INFO] [stderr] [ 322099.250] {proxy } client#2    <= wlproxy_test_array_echo#6.array(array: 0x63)
[INFO] [stderr] [ 322099.275] {client} server      -> wlproxy_test_array_echo#6.array(array: 0x63)
[INFO] [stderr] [ 322099.297] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#7, array: 0x64)
[INFO] [stderr] [ 322099.309] {proxy } client#2    <= wlproxy_test_array_echo#7.array(array: 0x64)
[INFO] [stdout] test state::tests::display_error ... ok
[INFO] [stderr] [ 322101.937] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322101.975] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322102.015] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322102.037] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322102.049] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322102.078] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322102.106] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x61)
[INFO] [stderr] [ 322102.125] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#5, array: 0x62)
[INFO] [stderr] [ 322102.132] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#6, array: 0x63)
[INFO] [stderr] [ 322102.142] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#7, array: 0x64)
[INFO] [stderr] [ 322102.185] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x61)
[INFO] [stderr] [ 322102.197] {proxy } client#2    <= wlproxy_test_array_echo#4.array(array: 0x61)
[INFO] [stderr] [ 322102.245] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x61)
[INFO] [stderr] [ 322102.271] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#5, array: 0x62)
[INFO] [stderr] [ 322102.280] {proxy } client#2    <= wlproxy_test_array_echo#5.array(array: 0x62)
[INFO] [stderr] [ 322102.287] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#6, array: 0x63)
[INFO] [stderr] [ 322102.293] {proxy } client#2    <= wlproxy_test_array_echo#6.array(array: 0x63)
[INFO] [stderr] [ 322102.299] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#7, array: 0x64)
[INFO] [stderr] [ 322102.309] {proxy } client#2    <= wlproxy_test_array_echo#7.array(array: 0x64)
[INFO] [stderr] [ 322104.409] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322104.445] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322104.485] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322104.494] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322104.506] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322104.506] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322104.516] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322104.520] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322104.547] {client} server      <= wlproxy_test#3.echo_fd(echo: wlproxy_test_fd_echo#4, fd1: 3, fd2: 12)
[INFO] [stderr] [ 322104.556] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322104.558] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322104.565] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322104.590] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322104.599] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322104.608] {proxy } client#2    -> wlproxy_test#3.echo_fd(echo: wlproxy_test_fd_echo#4, fd1: 86, fd2: 87)
[INFO] [stderr] [ 322104.610] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322104.622] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322104.627] {proxy } server      <= wlproxy_test#3.echo_fd(echo: wlproxy_test_fd_echo#4, fd1: 86, fd2: 87)
[INFO] [stderr] [ 322104.634] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322104.641] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322104.648] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322104.656] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322104.683] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x61)
[INFO] [stderr] [ 322104.722] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x61)
[INFO] [stderr] [ 322104.751] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322104.784] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322104.815] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322104.824] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322104.841] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322104.854] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322104.878] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322104.885] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322104.920] {client} server      <= wlproxy_test#3.count_hops(id: wlproxy_test_hops#4)
[INFO] [stderr] [ 322104.928] {client} server      <= wlproxy_test#3.count_hops(id: wlproxy_test_hops#5)
[INFO] [stderr] [ 322104.954] {proxy } client#2    -> wlproxy_test#3.count_hops(id: wlproxy_test_hops#4)
[INFO] [stderr] [ 322104.966] {proxy } server      <= wlproxy_test#3.count_hops(id: wlproxy_test_hops#4)
[INFO] [stderr] [ 322104.971] {proxy } client#2    -> wlproxy_test#3.count_hops(id: wlproxy_test_hops#5)
[INFO] [stderr] [ 322104.978] {proxy } server      <= wlproxy_test#3.count_hops(id: wlproxy_test_hops#5)
[INFO] [stderr] [ 322105.002] {server} client#2    -> wlproxy_test#3.count_hops(id: wlproxy_test_hops#4)
[INFO] [stderr] [ 322105.012] {server} client#2    <= wlproxy_test_hops#4.count(count: 1)
[INFO] [stderr] [ 322105.045] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322105.052] {server} client#2    -> wlproxy_test#3.count_hops(id: wlproxy_test_hops#5)
[INFO] [stderr] [ 322105.057] {server} client#2    <= wlproxy_test_hops#5.count(count: 1)
[INFO] [stderr] [ 322105.061] {server} client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322105.064] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322105.081] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322105.095] {proxy } server      -> wlproxy_test_hops#4.count(count: 1)
[INFO] [stderr] [ 322105.099] {proxy } client#2    <= wlproxy_test_hops#4.count(count: 2)
[INFO] [stderr] [ 322105.110] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322105.119] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322105.126] {client} server      -> wlproxy_test_hops#4.count(count: 2)
[INFO] [stderr] [ 322105.127] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322105.140] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322105.168] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0)
[INFO] [stderr] [ 322105.176] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322105.199] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0)
[INFO] [stderr] [2026-04-10T11:02:55Z INFO  wl_proxy::state::tests] unsuspend
[INFO] [stderr] [ 322105.210] {proxy } server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0)
[INFO] [stderr] [ 322105.216] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322105.219] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322105.222] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322105.226] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322105.234] {proxy } server      -> wlproxy_test_hops#5.count(count: 1)
[INFO] [stderr] [ 322105.237] {proxy } client#2    <= wlproxy_test_hops#5.count(count: 2)
[INFO] [stderr] [ 322105.256] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322105.261] {client} server      -> wlproxy_test_hops#5.count(count: 2)
[INFO] [stderr] [ 322106.466] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322106.496] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322106.512] {server} client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0)
[INFO] [stderr] [ 322106.524] {server} client#2    <= wlproxy_test_array_echo#4.array(array: 0x0)
[INFO] [stdout] test state::tests::recursive_dispatch ... ok
[INFO] [stdout] test state::tests::count_hops ... ok
[INFO] [stdout] test state::tests::suspend2 ... ok
[INFO] [stdout] test poll::tests::many ... ok
[INFO] [stdout] test state::tests::suspend3 ... ok
[INFO] [stdout] test acceptor::tests::test ... FAILED
[INFO] [stdout] test state::tests::suspend6 ... ok
[INFO] [stderr] [ 322106.554] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322106.567] {server} client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322106.574] {server} client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322106.579] {server} client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322106.655] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322106.655] {proxy } server      -> wlproxy_test_array_echo#4.array(array: 0x0)
[INFO] [stderr] [ 322106.662] {proxy } client#2    <= wlproxy_test_array_echo#4.array(array: 0x0)
[INFO] [stderr] [ 322106.666] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322106.673] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322106.679] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322106.686] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322106.690] {proxy } client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322106.694] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322106.693] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322106.698] {proxy } client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322106.701] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322106.708] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322106.721] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322106.723] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x0)
[INFO] [stderr] [ 322106.726] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322106.729] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322106.729] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322106.734] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322106.738] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322106.738] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322106.749] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322106.753] {client} server      <= wlproxy_test#3.recv_fd(fd: 106)
[INFO] [stderr] [ 322106.758] {client} server      <= wlproxy_test#3.recv_fd(fd: 106)
[INFO] [stderr] [ 322106.757] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01)
[INFO] [stderr] [ 322106.761] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322106.766] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322106.767] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322106.769] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322106.775] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322106.780] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322106.787] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322106.789] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01)
[INFO] [stderr] [ 322106.790] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322106.797] {proxy } server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01)
[INFO] [stderr] [ 322106.800] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322106.803] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322106.806] {proxy } client#2    -> wlproxy_test#3.recv_fd(fd: 107)
[INFO] [stderr] [ 322106.809] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322106.810] {proxy } server      <= wlproxy_test#3.recv_fd(fd: 107)
[INFO] [stderr] [ 322106.812] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x61)
[INFO] [stderr] [ 322106.815] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322106.819] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#5, array: 0x62)
[INFO] [stderr] [ 322106.837] {proxy } client#2    -> wlproxy_test#3.recv_fd(fd: 107)
[INFO] [stderr] [ 322106.839] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x61)
[INFO] [stderr] [ 322106.841] {proxy } server      <= wlproxy_test#3.recv_fd(fd: 107)
[INFO] [stderr] [ 322106.847] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322106.848] {server} client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01)
[INFO] [stderr] [ 322106.850] {proxy } client#2    <= wlproxy_test_array_echo#4.array(array: 0x61)
[INFO] [stderr] [ 322106.855] {server} client#2    <= wlproxy_test_array_echo#4.array(array: 0x01)
[INFO] [stderr] [ 322106.855] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322106.857] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322106.861] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322106.868] {server} client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322106.868] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322106.873] {server} client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322106.878] {server} client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322106.881] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x61)
[INFO] [stderr] [ 322106.897] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322106.908] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322106.913] {proxy } server      -> wlproxy_test_array_echo#4.array(array: 0x01)
[INFO] [stderr] [ 322106.917] {proxy } client#2    <= wlproxy_test_array_echo#4.array(array: 0x01)
[INFO] [stderr] [ 322106.919] {server} client#2    -> wlproxy_test#3.recv_fd(fd: 107)
[INFO] [stderr] [ 322106.923] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322106.928] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322106.934] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322106.937] {proxy } client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322106.938] {server} client#2    -> wlproxy_test#3.recv_fd(fd: 107)
[INFO] [stderr] [ 322106.941] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322106.943] {server} client#2    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322106.946] {proxy } client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322106.954] {server} client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322106.968] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x01)
[INFO] [stderr] [ 322106.977] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322106.982] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322106.987] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322106.990] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.006] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102)
[INFO] [stderr] [ 322107.013] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322107.013] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.017] {proxy } client#2    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322107.024] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.029] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.032] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102)
[INFO] [stderr] [ 322107.039] {proxy } server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102)
[INFO] [stderr] [ 322107.045] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.051] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.051] {client} server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [ 322107.055] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.086] {server} client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102)
[INFO] [stderr] [ 322107.095] {server} client#2    <= wlproxy_test_array_echo#4.array(array: 0x0102)
[INFO] [stderr] [ 322107.101] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.107] {server} client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.121] {server} client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.127] {server} client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.270] {proxy } server      -> wlproxy_test_array_echo#4.array(array: 0x0102)
[INFO] [stderr] [ 322107.277] {proxy } client#2    <= wlproxy_test_array_echo#4.array(array: 0x0102)
[INFO] [stderr] [ 322107.284] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.290] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.296] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.299] {proxy } client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.303] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.308] {proxy } client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.330] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x0102)
[INFO] [stderr] [ 322107.334] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.339] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.350] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.367] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x010203)
[INFO] [stderr] [ 322107.376] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.396] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x010203)
[INFO] [stderr] [ 322107.404] {proxy } server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x010203)
[INFO] [stderr] [ 322107.409] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.432] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.463] {server} client#2    -> wlproxy_test#3.echo_fd(echo: wlproxy_test_fd_echo#4, fd1: 36, fd2: 55)
[INFO] [stderr] [ 322107.465] {server} client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x010203)
[INFO] [stderr] [ 322107.472] {server} client#2    <= wlproxy_test_array_echo#4.array(array: 0x010203)
[INFO] [stderr] [ 322107.478] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.479] {server} client#2    <= wlproxy_test_fd_echo#4.fd(fd1: 36, fd2: 55)
[INFO] [stderr] [ 322107.484] {server} client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.490] {server} client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.494] {server} client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.505] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.512] {server} client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.518] {server} client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.523] {server} client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.522] {proxy } server      -> wlproxy_test_array_echo#4.array(array: 0x010203)
[INFO] [stderr] [ 322107.526] {proxy } client#2    <= wlproxy_test_array_echo#4.array(array: 0x010203)
[INFO] [stderr] [ 322107.532] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.537] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.543] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.546] {proxy } client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.550] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.554] {proxy } client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.574] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x010203)
[INFO] [stderr] [ 322107.580] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.585] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.588] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.603] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01020304)
[INFO] [stderr] [ 322107.611] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.629] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01020304)
[INFO] [stderr] [ 322107.636] {proxy } server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01020304)
[INFO] [stderr] [ 322107.641] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.647] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.676] {server} client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01020304)
[INFO] [stderr] [ 322107.685] {server} client#2    <= wlproxy_test_array_echo#4.array(array: 0x01020304)
[INFO] [stderr] [ 322107.691] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.696] {server} client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.701] {server} client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.705] {server} client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.738] {proxy } server      -> wlproxy_test_array_echo#4.array(array: 0x01020304)
[INFO] [stderr] [ 322107.742] {proxy } client#2    <= wlproxy_test_array_echo#4.array(array: 0x01020304)
[INFO] [stderr] [ 322107.748] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.752] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.758] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.760] {proxy } client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.764] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.768] {proxy } client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.787] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x01020304)
[INFO] [stderr] [ 322107.792] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.796] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.799] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.813] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102030405)
[INFO] [stderr] [ 322107.820] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.837] {proxy } client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102030405)
[INFO] [stderr] [ 322107.844] {proxy } server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102030405)
[INFO] [stderr] [ 322107.850] {proxy } client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.855] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.876] {server} client#2    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102030405)
[INFO] [stderr] [ 322107.883] {server} client#2    <= wlproxy_test_array_echo#4.array(array: 0x0102030405)
[INFO] [stderr] [ 322107.889] {server} client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.894] {server} client#2    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [ 322107.899] {server} client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.903] {server} client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.931] {proxy } server      -> wlproxy_test_array_echo#4.array(array: 0x0102030405)
[INFO] [stderr] [ 322107.935] {proxy } client#2    <= wlproxy_test_array_echo#4.array(array: 0x0102030405)
[INFO] [stderr] [ 322107.940] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.945] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322107.950] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.953] {proxy } client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322107.957] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.961] {proxy } client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322107.989] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x0102030405)
[INFO] [stderr] [ 322107.995] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322108.000] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322108.003] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322109.893] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322109.931] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322109.964] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322109.986] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322110.001] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322110.030] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322110.068] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [2026-04-10T11:02:55Z ERROR wl_proxy::state] Could not handle client message: could not read a message: message has a supposed length 65535 > 4096
[INFO] [stdout] test trans::tests::edge_header ... ok
[INFO] [stdout] test state::tests::suspend4 ... ok
[INFO] [stdout] test state::tests::suspend5 ... ok
[INFO] [stdout] test trans::tests::fd ... ok
[INFO] [stdout] test object::tests::version ... ok
[INFO] [stdout] test trans::tests::array ... ok
[INFO] [stderr] [ 322111.489] {proxy } server      -> wlproxy_test_fd_echo#4.fd(fd1: 30, fd2: 35)
[INFO] [stderr] [ 322111.509] {proxy } client#2    <= wlproxy_test_fd_echo#4.fd(fd1: 30, fd2: 35)
[INFO] [stderr] [ 322111.520] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322111.527] {proxy } client#2    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322111.534] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322111.537] {proxy } client#2    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322111.542] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322111.546] {proxy } client#2    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [ 322111.593] {client} server      -> wlproxy_test_fd_echo#4.fd(fd1: 30, fd2: 35)
[INFO] [stderr] [ 322111.618] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [ 322111.624] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [ 322111.627] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stdout] test trans::tests::large_message ... ok
[INFO] [stdout] test trans::tests::echo_fd ... ok
[INFO] [stdout] test utils::stack::tests::stack ... ok
[INFO] [stdout] test fixed::tests::maximum - should panic ... ok
[INFO] [stdout] test fixed::tests::minimum - should panic ... ok
[INFO] [stdout] test handler::tests::multi_borrow - should panic ... ok
[INFO] [stderr] [ 322120.235] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322120.273] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322120.317] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322120.328] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322120.347] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322120.365] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322120.401] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [ 322120.429] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322120.441] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [2026-04-10T11:02:55Z ERROR wl_proxy::state] Could not handle client message: could not read a message: message has a supposed length 4 < 8
[INFO] [stderr] [ 322121.390] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322121.409] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322121.456] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322121.466] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322121.482] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322121.495] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322122.541] {server} client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322122.756] {server} client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322124.484] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [2026-04-10T11:02:55Z ERROR wl_proxy::state] Could not handle client message: could not read a message: message has a supposed length 9 that is not a multiple of 4
[INFO] [stdout] test trans::tests::small_message ... ok
[INFO] [stdout] test utils::free_list::tests::test ... ok
[INFO] [stdout] test utils::stash::tests::test ... ok
[INFO] [stdout] test object::tests::duplicate_server_id - should panic ... ok
[INFO] [stdout] test object::tests::invalid_server_id - should panic ... ok
[INFO] [stdout] test trans::tests::not_word_size ... ok
[INFO] [stderr] [ 322139.825] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322139.851] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322139.890] {proxy } client#2    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322139.898] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [ 322139.906] {proxy } client#2    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [ 322139.919] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stdout] test state::tests::acceptor ... FAILED
[INFO] [stdout] test object::tests::state ... ok
[INFO] [stdout] test utils::free_list::tests::release_out_of_bounds - should panic ... ok
[INFO] [stdout] test object::tests::wrong_downcast - should panic ... ok
[INFO] [stdout] test state::tests::many_events ... ok
[INFO] [stdout] test trans::tests::many_messages_with_fd ... ok
[INFO] [stdout] test trans::tests::many_messages ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- acceptor::tests::test stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'acceptor::tests::test' (17) panicked at src/acceptor/tests.rs:9:46:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: AcceptorError(XrdNotSet)
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x6037b93c818a - std[e72de78501789eb0]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x6037b93c818a - std[e72de78501789eb0]::backtrace_rs::backtrace::trace_unsynchronized::<std[e72de78501789eb0]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x6037b93c818a - std[e72de78501789eb0]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x6037b93c818a - <<std[e72de78501789eb0]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6aaeda2c7ad07a48]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x6037b93e01ba - <core[6aaeda2c7ad07a48]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x6037b93e01ba - core[6aaeda2c7ad07a48]::fmt::write
[INFO] [stdout]    6:     0x6037b93ce182 - std[e72de78501789eb0]::io::default_write_fmt::<alloc[28d2dab30c1dc666]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x6037b93ce182 - <alloc[28d2dab30c1dc666]::vec::Vec<u8> as std[e72de78501789eb0]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x6037b93a70ef - <std[e72de78501789eb0]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x6037b93a70ef - std[e72de78501789eb0]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x6037b93c05e9 - std[e72de78501789eb0]::panicking::default_hook
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x6037b9357a1c - <alloc[28d2dab30c1dc666]::boxed::Box<dyn for<'a, 'b> core[6aaeda2c7ad07a48]::ops::function::Fn<(&'a std[e72de78501789eb0]::panic::PanicHookInfo<'b>,), Output = ()> + core[6aaeda2c7ad07a48]::marker::Sync + core[6aaeda2c7ad07a48]::marker::Send> as core[6aaeda2c7ad07a48]::ops::function::Fn<(&std[e72de78501789eb0]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x6037b9357a1c - test[4f12b380f1fb68c5]::test_main_with_exit_callback::<test[4f12b380f1fb68c5]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x6037b93c07a2 - <alloc[28d2dab30c1dc666]::boxed::Box<dyn for<'a, 'b> core[6aaeda2c7ad07a48]::ops::function::Fn<(&'a std[e72de78501789eb0]::panic::PanicHookInfo<'b>,), Output = ()> + core[6aaeda2c7ad07a48]::marker::Sync + core[6aaeda2c7ad07a48]::marker::Send> as core[6aaeda2c7ad07a48]::ops::function::Fn<(&std[e72de78501789eb0]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x6037b93c07a2 - std[e72de78501789eb0]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x6037b93a71a8 - std[e72de78501789eb0]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x6037b939ea99 - std[e72de78501789eb0]::sys::backtrace::__rust_end_short_backtrace::<std[e72de78501789eb0]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x6037b93a7fdd - __rustc[78a4cf188af856fc]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x6037b93e0a2c - core[6aaeda2c7ad07a48]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x6037b93e0772 - core[6aaeda2c7ad07a48]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x6037b904000a - <core[6aaeda2c7ad07a48]::result::Result<alloc[28d2dab30c1dc666]::rc::Rc<wl_proxy[73cea7ffe7aa05be]::acceptor::Acceptor>, wl_proxy[73cea7ffe7aa05be]::acceptor::AcceptorError>>::unwrap
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/result.rs:1233:23
[INFO] [stdout]   21:     0x6037b904000a - wl_proxy[73cea7ffe7aa05be]::acceptor::tests::test
[INFO] [stdout]                                at /opt/rustwide/workdir/src/acceptor/tests.rs:9:46
[INFO] [stdout]   22:     0x6037b9033497 - wl_proxy[73cea7ffe7aa05be]::acceptor::tests::test::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/acceptor/tests.rs:8:10
[INFO] [stdout]   23:     0x6037b900e4a6 - <wl_proxy[73cea7ffe7aa05be]::acceptor::tests::test::{closure#0} as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x6037b934bafb - <fn() -> core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x6037b934bafb - test[4f12b380f1fb68c5]::__rust_begin_short_backtrace::<core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>, fn() -> core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:663:18
[INFO] [stdout]   26:     0x6037b93584eb - test[4f12b380f1fb68c5]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:686:74
[INFO] [stdout]   27:     0x6037b93584eb - <core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<test[4f12b380f1fb68c5]::run_test_in_process::{closure#0}> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x6037b93584eb - std[e72de78501789eb0]::panicking::catch_unwind::do_call::<core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<test[4f12b380f1fb68c5]::run_test_in_process::{closure#0}>, core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x6037b93584eb - std[e72de78501789eb0]::panicking::catch_unwind::<core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>, core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<test[4f12b380f1fb68c5]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x6037b93584eb - std[e72de78501789eb0]::panic::catch_unwind::<core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<test[4f12b380f1fb68c5]::run_test_in_process::{closure#0}>, core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x6037b93584eb - test[4f12b380f1fb68c5]::run_test_in_process
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:686:27
[INFO] [stdout]   32:     0x6037b93584eb - test[4f12b380f1fb68c5]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:607:43
[INFO] [stdout]   33:     0x6037b9353c04 - test[4f12b380f1fb68c5]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:637:41
[INFO] [stdout]   34:     0x6037b9353c04 - std[e72de78501789eb0]::sys::backtrace::__rust_begin_short_backtrace::<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x6037b935b0f2 - std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked::<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   36:     0x6037b935b0f2 - <core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x6037b935b0f2 - std[e72de78501789eb0]::panicking::catch_unwind::do_call::<core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x6037b935b0f2 - std[e72de78501789eb0]::panicking::catch_unwind::<(), core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x6037b935b0f2 - std[e72de78501789eb0]::panic::catch_unwind::<core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x6037b935b0f2 - std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked::<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   41:     0x6037b935b0f2 - <std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1} as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x6037b93c7a9f - <alloc[28d2dab30c1dc666]::boxed::Box<dyn core[6aaeda2c7ad07a48]::ops::function::FnOnce<(), Output = ()> + core[6aaeda2c7ad07a48]::marker::Send> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   43:     0x6037b93c7a9f - <std[e72de78501789eb0]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x725ca878eaa4 - <unknown>
[INFO] [stdout]   45:     0x725ca881ba64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- state::tests::acceptor stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'state::tests::acceptor' (130) panicked at src/state/tests.rs:114:49:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: StateError(CreateAcceptor(AcceptorError(XrdNotSet)))
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x6037b93c818a - std[e72de78501789eb0]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x6037b93c818a - std[e72de78501789eb0]::backtrace_rs::backtrace::trace_unsynchronized::<std[e72de78501789eb0]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x6037b93c818a - std[e72de78501789eb0]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x6037b93c818a - <<std[e72de78501789eb0]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[6aaeda2c7ad07a48]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x6037b93e01ba - <core[6aaeda2c7ad07a48]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x6037b93e01ba - core[6aaeda2c7ad07a48]::fmt::write
[INFO] [stdout]    6:     0x6037b93ce182 - std[e72de78501789eb0]::io::default_write_fmt::<alloc[28d2dab30c1dc666]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x6037b93ce182 - <alloc[28d2dab30c1dc666]::vec::Vec<u8> as std[e72de78501789eb0]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x6037b93a70ef - <std[e72de78501789eb0]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x6037b93a70ef - std[e72de78501789eb0]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x6037b93c05e9 - std[e72de78501789eb0]::panicking::default_hook
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x6037b9357a1c - <alloc[28d2dab30c1dc666]::boxed::Box<dyn for<'a, 'b> core[6aaeda2c7ad07a48]::ops::function::Fn<(&'a std[e72de78501789eb0]::panic::PanicHookInfo<'b>,), Output = ()> + core[6aaeda2c7ad07a48]::marker::Sync + core[6aaeda2c7ad07a48]::marker::Send> as core[6aaeda2c7ad07a48]::ops::function::Fn<(&std[e72de78501789eb0]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   12:     0x6037b9357a1c - test[4f12b380f1fb68c5]::test_main_with_exit_callback::<test[4f12b380f1fb68c5]::test_main::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x6037b93c07a2 - <alloc[28d2dab30c1dc666]::boxed::Box<dyn for<'a, 'b> core[6aaeda2c7ad07a48]::ops::function::Fn<(&'a std[e72de78501789eb0]::panic::PanicHookInfo<'b>,), Output = ()> + core[6aaeda2c7ad07a48]::marker::Sync + core[6aaeda2c7ad07a48]::marker::Send> as core[6aaeda2c7ad07a48]::ops::function::Fn<(&std[e72de78501789eb0]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2254:9
[INFO] [stdout]   14:     0x6037b93c07a2 - std[e72de78501789eb0]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x6037b93a71a8 - std[e72de78501789eb0]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x6037b939ea99 - std[e72de78501789eb0]::sys::backtrace::__rust_end_short_backtrace::<std[e72de78501789eb0]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x6037b93a7fdd - __rustc[78a4cf188af856fc]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x6037b93e0a2c - core[6aaeda2c7ad07a48]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x6037b93e0772 - core[6aaeda2c7ad07a48]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x6037b9039e0f - <core[6aaeda2c7ad07a48]::result::Result<alloc[28d2dab30c1dc666]::rc::Rc<wl_proxy[73cea7ffe7aa05be]::acceptor::Acceptor>, wl_proxy[73cea7ffe7aa05be]::state::StateError>>::unwrap
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/result.rs:1233:23
[INFO] [stdout]   21:     0x6037b9039e0f - wl_proxy[73cea7ffe7aa05be]::state::tests::acceptor
[INFO] [stdout]                                at /opt/rustwide/workdir/src/state/tests.rs:114:49
[INFO] [stdout]   22:     0x6037b9032da7 - wl_proxy[73cea7ffe7aa05be]::state::tests::acceptor::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/src/state/tests.rs:99:14
[INFO] [stdout]   23:     0x6037b900da66 - <wl_proxy[73cea7ffe7aa05be]::state::tests::acceptor::{closure#0} as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x6037b934bafb - <fn() -> core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x6037b934bafb - test[4f12b380f1fb68c5]::__rust_begin_short_backtrace::<core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>, fn() -> core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:663:18
[INFO] [stdout]   26:     0x6037b93584eb - test[4f12b380f1fb68c5]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:686:74
[INFO] [stdout]   27:     0x6037b93584eb - <core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<test[4f12b380f1fb68c5]::run_test_in_process::{closure#0}> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   28:     0x6037b93584eb - std[e72de78501789eb0]::panicking::catch_unwind::do_call::<core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<test[4f12b380f1fb68c5]::run_test_in_process::{closure#0}>, core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x6037b93584eb - std[e72de78501789eb0]::panicking::catch_unwind::<core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>, core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<test[4f12b380f1fb68c5]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x6037b93584eb - std[e72de78501789eb0]::panic::catch_unwind::<core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<test[4f12b380f1fb68c5]::run_test_in_process::{closure#0}>, core[6aaeda2c7ad07a48]::result::Result<(), alloc[28d2dab30c1dc666]::string::String>>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x6037b93584eb - test[4f12b380f1fb68c5]::run_test_in_process
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:686:27
[INFO] [stdout]   32:     0x6037b93584eb - test[4f12b380f1fb68c5]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:607:43
[INFO] [stdout]   33:     0x6037b9353c04 - test[4f12b380f1fb68c5]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/test/src/lib.rs:637:41
[INFO] [stdout]   34:     0x6037b9353c04 - std[e72de78501789eb0]::sys::backtrace::__rust_begin_short_backtrace::<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   35:     0x6037b935b0f2 - std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked::<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/lifecycle.rs:91:13
[INFO] [stdout]   36:     0x6037b935b0f2 - <core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   37:     0x6037b935b0f2 - std[e72de78501789eb0]::panicking::catch_unwind::do_call::<core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x6037b935b0f2 - std[e72de78501789eb0]::panicking::catch_unwind::<(), core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x6037b935b0f2 - std[e72de78501789eb0]::panic::catch_unwind::<core[6aaeda2c7ad07a48]::panic::unwind_safe::AssertUnwindSafe<std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x6037b935b0f2 - std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked::<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/thread/lifecycle.rs:89:26
[INFO] [stdout]   41:     0x6037b935b0f2 - <std[e72de78501789eb0]::thread::lifecycle::spawn_unchecked<test[4f12b380f1fb68c5]::run_test::{closure#1}, ()>::{closure#1} as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x6037b93c7a9f - <alloc[28d2dab30c1dc666]::boxed::Box<dyn core[6aaeda2c7ad07a48]::ops::function::FnOnce<(), Output = ()> + core[6aaeda2c7ad07a48]::marker::Send> as core[6aaeda2c7ad07a48]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/alloc/src/boxed.rs:2240:9
[INFO] [stdout]   43:     0x6037b93c7a9f - <std[e72de78501789eb0]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/dec9417b8611e34e787a3e4c37686b5131f9e5c5/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x725ca878eaa4 - <unknown>
[INFO] [stdout]   45:     0x725ca881ba64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     acceptor::tests::test
[INFO] [stdout]     state::tests::acceptor
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 109 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.72s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "ec717094867f21740dc33f5deb94a66d8678f54008ade15e0077a09c04f9cbe8", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "ec717094867f21740dc33f5deb94a66d8678f54008ade15e0077a09c04f9cbe8", kill_on_drop: false }`
[INFO] [stdout] ec717094867f21740dc33f5deb94a66d8678f54008ade15e0077a09c04f9cbe8