[INFO] fetching crate wl-proxy 0.1.0...
[INFO] testing wl-proxy-0.1.0 against 1.93.0 for beta-1.94-1
[INFO] extracting crate wl-proxy 0.1.0 into /workspace/builds/worker-0-tc1/source
[INFO] started tweaking crates.io crate wl-proxy 0.1.0
[INFO] finished tweaking crates.io crate wl-proxy 0.1.0
[INFO] tweaked toml for crates.io crate wl-proxy 0.1.0 written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate wl-proxy 0.1.0 on toolchain 1.93.0
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.93.0" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate wl-proxy 0.1.0 already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.93.0" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded phf v0.13.1
[INFO] [stderr]   Downloaded phf_generator v0.13.1
[INFO] [stderr]   Downloaded phf_macros v0.13.1
[INFO] [stderr]   Downloaded run-on-drop v1.0.0
[INFO] [stderr]   Downloaded uapi-proc v0.0.5
[INFO] [stderr]   Downloaded linearize-derive v0.2.1
[INFO] [stderr]   Downloaded debug-fn v1.0.0
[INFO] [stderr]   Downloaded error_reporter v1.0.0
[INFO] [stderr]   Downloaded isnt v0.2.0
[INFO] [stderr]   Downloaded uapi v0.2.13
[INFO] [stderr]   Downloaded linearize v0.1.5
[INFO] [stderr]   Downloaded phf_shared v0.13.1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+1.93.0" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] a382cc2807b38761c470684473b17bc804af9590cf53f1d68d5d6055e5524eab
[INFO] running `Command { std: "docker" "start" "-a" "a382cc2807b38761c470684473b17bc804af9590cf53f1d68d5d6055e5524eab", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "a382cc2807b38761c470684473b17bc804af9590cf53f1d68d5d6055e5524eab", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "a382cc2807b38761c470684473b17bc804af9590cf53f1d68d5d6055e5524eab", kill_on_drop: false }`
[INFO] [stdout] a382cc2807b38761c470684473b17bc804af9590cf53f1d68d5d6055e5524eab
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+1.93.0" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 08e0140727f499c76ab515626cc889f1f223dd7f252c8eec57c8ad1c137692a1
[INFO] running `Command { std: "docker" "start" "-a" "08e0140727f499c76ab515626cc889f1f223dd7f252c8eec57c8ad1c137692a1", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.178
[INFO] [stderr]    Compiling find-msvc-tools v0.1.5
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling syn v1.0.109
[INFO] [stderr]    Compiling phf_shared v0.13.1
[INFO] [stderr]    Compiling linearize v0.1.5
[INFO] [stderr]    Compiling error_reporter v1.0.0
[INFO] [stderr]    Compiling isnt v0.2.0
[INFO] [stderr]    Compiling run-on-drop v1.0.0
[INFO] [stderr]    Compiling debug-fn v1.0.0
[INFO] [stderr]    Compiling syn v2.0.111
[INFO] [stderr]    Compiling cc v1.2.49
[INFO] [stderr]    Compiling phf_generator v0.13.1
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling uapi v0.2.13
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling thiserror-impl v2.0.17
[INFO] [stderr]    Compiling phf_macros v0.13.1
[INFO] [stderr]    Compiling linearize-derive v0.2.1
[INFO] [stderr]    Compiling regex v1.12.2
[INFO] [stderr]    Compiling phf v0.13.1
[INFO] [stderr]    Compiling uapi-proc v0.0.5
[INFO] [stderr]    Compiling thiserror v2.0.17
[INFO] [stderr]    Compiling wl-proxy v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 9.96s
[INFO] running `Command { std: "docker" "inspect" "08e0140727f499c76ab515626cc889f1f223dd7f252c8eec57c8ad1c137692a1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "08e0140727f499c76ab515626cc889f1f223dd7f252c8eec57c8ad1c137692a1", kill_on_drop: false }`
[INFO] [stdout] 08e0140727f499c76ab515626cc889f1f223dd7f252c8eec57c8ad1c137692a1
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+1.93.0" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 48158441ce6114278a1bc5dfa613ae4ffe9d6a52fc2a521345374b48dfc8247f
[INFO] running `Command { std: "docker" "start" "-a" "48158441ce6114278a1bc5dfa613ae4ffe9d6a52fc2a521345374b48dfc8247f", kill_on_drop: false }`
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling jiff v0.2.16
[INFO] [stderr]    Compiling regex v1.12.2
[INFO] [stderr]    Compiling uapi-proc v0.0.5
[INFO] [stderr]    Compiling uapi v0.2.13
[INFO] [stderr]    Compiling wl-proxy v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]    Compiling env_logger v0.11.8
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 12.60s
[INFO] running `Command { std: "docker" "inspect" "48158441ce6114278a1bc5dfa613ae4ffe9d6a52fc2a521345374b48dfc8247f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "48158441ce6114278a1bc5dfa613ae4ffe9d6a52fc2a521345374b48dfc8247f", kill_on_drop: false }`
[INFO] [stdout] 48158441ce6114278a1bc5dfa613ae4ffe9d6a52fc2a521345374b48dfc8247f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:98afbf2d16093dec2546ff8915fddc74e65664aa03fc460b3712b1c2c54a33e4" "/opt/rustwide/cargo-home/bin/cargo" "+1.93.0" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] bc9a89897b886ef215a7138d4b937da170adcac852f8967acde0d23985230a0d
[INFO] running `Command { std: "docker" "start" "-a" "bc9a89897b886ef215a7138d4b937da170adcac852f8967acde0d23985230a0d", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.10s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/wl_proxy-48ac2ba83fc77681)
[INFO] [stdout] 
[INFO] [stdout] running 103 tests
[INFO] [stderr] [1237697.463] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237697.578] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237697.663] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237697.709] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237697.748] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237697.794] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237699.128] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237699.623] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237699.702] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237699.744] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237699.793] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237699.764] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237699.852] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237699.856] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237699.883] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237699.901] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237699.922] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237699.971] {client} server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [1237699.990] {client} server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#5)
[INFO] [stderr] [1237700.014] {proxy } client#1    -> wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [1237700.093] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237700.097] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237700.124] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237700.191] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237700.229] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237700.265] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237700.308] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237700.372] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237700.435] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [2026-01-28T11:46:18Z ERROR wl_proxy::state] Could not handle client message: could not handle a wl_display#1.65535 message: unknown message id 65535
[INFO] [stderr] [1237700.764] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237700.784] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237700.812] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237700.827] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237700.840] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237700.853] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237700.900] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237700.917] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237701.055] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237701.074] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237701.102] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237701.116] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237701.130] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237701.143] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237701.873] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [2026-01-28T11:46:18Z ERROR wl_proxy::state] Could not handle client message: could not read a message: message has a supposed length 65535 > 4096
[INFO] [stderr] [1237702.127] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237705.477] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237705.563] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237705.603] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237705.647] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237706.488] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stdout] test client::tests::disconnected ... ok
[INFO] [stderr] [1237706.545] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stdout] test endpoint::tests::dispatch_destroyed ... ok
[INFO] [stderr] [1237706.582] {client} server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stdout] test endpoint::tests::invalid_message ... ok
[INFO] [stderr] [1237706.591] {client} server      <= wlproxy_test#3.echo_object(echo: wlproxy_test_object_echo#5, object: unknown#4)
[INFO] [stdout] test client::tests::unset_handler ... ok
[INFO] [stderr] [1237706.602] {client} server      <= wl_display#1.sync(callback: wl_callback#6)
[INFO] [stdout] test client::tests::objects ... ok
[INFO] [stderr] [1237706.598] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test fixed::tests::from_i32 ... ok
[INFO] [stderr] [1237706.626] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237706.634] {proxy } client#1    -> wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [1237706.649] {proxy } server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [1237706.654] {proxy } client#1    -> wlproxy_test#3.echo_object(echo: wlproxy_test_object_echo#5, object: unknown#4)
[INFO] [stderr] [1237706.663] {proxy } server      <= wlproxy_test#3.echo_object(echo: wlproxy_test_object_echo#5, object: unknown#4)
[INFO] [stderr] [1237706.668] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [1237706.675] {proxy } server      <= wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [1237706.717] {server} client#1    -> wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [1237706.736] {server} client#1    -> wlproxy_test#3.echo_object(echo: wlproxy_test_object_echo#5, object: unknown#4)
[INFO] [stderr] [1237706.745] {server} client#1    <= wlproxy_test_object_echo#5.object(obj: unknown#4)
[INFO] [stdout] test fixed::tests::display ... ok
[INFO] [stderr] [1237708.574] {server} client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237708.604] {server} client#1    -> wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [1237708.622] {server} client#1    <= wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [1237708.628] {server} client#1    <= wl_display#1.delete_id(id: 6)
[INFO] [stderr] [1237708.683] {proxy } server      -> wlproxy_test_object_echo#5.object(obj: unknown#4)
[INFO] [stderr] [1237708.696] {proxy } client#1    <= wlproxy_test_object_echo#5.object(obj: unknown#4)
[INFO] [stderr] [1237708.715] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237708.723] {proxy } client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237708.730] {proxy } server      -> wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [1237708.733] {proxy } client#1    <= wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [1237708.737] {proxy } server      -> wl_display#1.delete_id(id: 6)
[INFO] [stderr] [1237708.742] {proxy } client#1    <= wl_display#1.delete_id(id: 6)
[INFO] [stderr] [1237708.766] {client} server      -> wlproxy_test_object_echo#5.object(obj: unknown#4)
[INFO] [stderr] [1237708.771] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237708.777] {client} server      -> wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [1237708.780] {client} server      -> wl_display#1.delete_id(id: 6)
[INFO] [stdout] test endpoint::tests::test ... ok
[INFO] [stdout] test fixed::tests::from_ints ... ok
[INFO] [stdout] test fixed::tests::from_f32 ... ok
[INFO] [stdout] test fixed::tests::one_two ... ok
[INFO] [stdout] test fixed::tests::test_binops ... ok
[INFO] [stdout] test fixed::tests::test_shiftops ... ok
[INFO] [stdout] test fixed::tests::minima ... ok
[INFO] [stdout] test fixed::tests::test_unops ... ok
[INFO] [stdout] test fixed::tests::from_i64 ... ok
[INFO] [stdout] test fixed::tests::to_i32_ceil ... ok
[INFO] [stdout] test fixed::tests::to_f32 ... ok
[INFO] [stdout] test endpoint::tests::lookup ... ok
[INFO] [stdout] test fixed::tests::to_i32_floor ... ok
[INFO] [stdout] test fixed::tests::to_i32_zero ... ok
[INFO] [stdout] test fixed::tests::to_i32_nearest ... ok
[INFO] [stdout] test global_mapper::tests::test ... ok
[INFO] [stdout] test global_mapper::tests::test_default ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_global_remove ... ok
[INFO] [stderr] [2026-01-28T11:46:18Z WARN  wl_proxy::global_mapper] Could not add synthetic global: the message handler is already borrowed
[INFO] [stderr] [2026-01-28T11:46:18Z WARN  wl_proxy::global_mapper] Could not handle client bind: the message handler is already borrowed
[INFO] [stdout] test global_mapper::tests::test_add_synthetic_global_error ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_bind ... ok
[INFO] [stderr] [2026-01-28T11:46:18Z WARN  wl_proxy::global_mapper] Could not handle server global: the message handler is already borrowed
[INFO] [stderr] [2026-01-28T11:46:18Z WARN  wl_proxy::global_mapper] Client sent wl_registry.bind for name 999 but not such global exists
[INFO] [stderr] [2026-01-28T11:46:18Z WARN  wl_proxy::global_mapper] Could not handle server global remove: the message handler is already borrowed
[INFO] [stderr] [2026-01-28T11:46:18Z WARN  wl_proxy::global_mapper] Could not remove synthetic global: the message handler is already borrowed
[INFO] [stdout] test global_mapper::tests::test_forward_bind_error ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_global ... ok
[INFO] [stderr] [2026-01-28T11:46:18Z WARN  wl_proxy::global_mapper] Server sent wl_registry.global_remove for name 999 but no such global exists
[INFO] [stdout] test fixed::tests::wire ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_global_error ... ok
[INFO] [stdout] test global_mapper::tests::test_remove_synthetic_global ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_global_remove_error ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_bind_nonexistent ... ok
[INFO] [stdout] test global_mapper::tests::test_forward_global_remove_ignored ... ok
[INFO] [stdout] test global_mapper::tests::test_ignore_global ... ok
[INFO] [stdout] test global_mapper::tests::test_remove_synthetic_global_error ... ok
[INFO] [stdout] test global_mapper::tests::test_try_add_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_nonexistent ... 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_global_error ... ok
[INFO] [stderr] [2026-01-28T11:46:18Z WARN  wl_proxy::global_mapper] Server sent wl_registry.global_remove for name 999 but no such global exists
[INFO] [stderr] [2026-01-28T11:46:18Z 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_try_forward_global ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_global_remove_ignored ... 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] [stderr] [1237718.868] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237718.900] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237718.933] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237718.943] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237718.950] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237718.963] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stdout] test global_mapper::tests::test_try_remove_synthetic_global ... ok
[INFO] [stderr] [1237719.275] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237719.303] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237719.336] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237719.346] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237719.355] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237719.378] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stdout] test handler::tests::clone ... ok
[INFO] [stdout] test global_mapper::tests::test_try_remove_synthetic_global_error ... ok
[INFO] [stdout] test handler::tests::borrow ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_global_remove_nonexistent ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_bind_nonexistent ... ok
[INFO] [stdout] test handler::tests::map ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_bind_error ... ok
[INFO] [stdout] test handler::tests::replace ... ok
[INFO] [stdout] test object::tests::client ... ok
[INFO] [stdout] test object::tests::client_id ... ok
[INFO] [stdout] test global_mapper::tests::test_try_forward_bind_synthetic ... ok
[INFO] [stdout] test object::tests::duplicate_client_id ... ok
[INFO] [stdout] test object::tests::request_without_server ... ok
[INFO] [stdout] test object::tests::get_handler ... ok
[INFO] [stdout] test object::tests::double_send ... ok
[INFO] [stdout] test object::tests::duplicate_generated_client_id ... ok
[INFO] [stdout] test object::tests::client_object_with_server_id ... ok
[INFO] [stderr] [1237720.612] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237720.634] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237720.664] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237720.774] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237720.787] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237720.798] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237720.813] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237720.819] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237720.823] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237720.826] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237720.831] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237720.845] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237720.847] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237720.851] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237720.862] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237720.875] {client} server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [1237720.876] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237720.883] {client} server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [1237720.886] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237720.885] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237720.899] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237720.900] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237720.906] {proxy } client#1    -> wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [1237720.919] {proxy } server      <= wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [1237720.920] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237720.918] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237720.924] {proxy } client#1    -> wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [1237720.935] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237720.948] {client} server      <= wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#4)
[INFO] [stderr] [1237720.954] {client} server      <= wlproxy_test_non_forward#4.echo()
[INFO] [stderr] [1237720.962] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237720.986] {proxy } client#1    -> wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#4)
[INFO] [stderr] [1237720.997] {proxy } server      <= wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#4)
[INFO] [stderr] [1237721.003] {proxy } client#1    -> wlproxy_test_non_forward#4.echo()
[INFO] [stderr] [1237721.006] {proxy } server      <= wlproxy_test_non_forward#4.echo()
[INFO] [stderr] [1237721.010] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237721.017] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [2026-01-28T11:46:18Z 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] [1237721.051] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237721.062] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237721.074] {server} client#1    -> wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#4)
[INFO] [stderr] [1237721.086] {server} client#1    -> wlproxy_test_non_forward#4.echo()
[INFO] [stderr] [1237721.091] {server} client#1    <= wlproxy_test_non_forward#4.echoed()
[INFO] [stderr] [1237721.100] {server} client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237721.104] {server} client#1    -> wlproxy_test#3.create_dummy(id: wlproxy_test_dummy#4)
[INFO] [stderr] [1237721.108] {server} client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237721.114] {server} client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237721.155] {proxy } server      -> wlproxy_test_non_forward#4.echoed()
[INFO] [stderr] [1237721.160] {proxy } client#1    <= wlproxy_test_non_forward#4.echoed()
[INFO] [stderr] [1237721.175] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237721.178] {proxy } client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237721.193] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237721.199] {proxy } client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237720.674] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237721.224] {client} server      -> wlproxy_test_non_forward#4.echoed()
[INFO] [stderr] [1237721.228] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237721.227] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237721.231] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237721.247] {client} server      <= wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#5)
[INFO] [stderr] [1237721.251] {client} server      <= wlproxy_test_non_forward#5.echo()
[INFO] [stderr] [1237721.263] {client} server      <= wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [1237721.279] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237721.283] {proxy } client#1    -> wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#5)
[INFO] [stderr] [1237721.292] {proxy } client#1    -> wlproxy_test_non_forward#5.echo()
[INFO] [stderr] [1237721.296] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [1237721.296] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237721.303] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237721.328] {server} client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237721.335] {server} client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237721.340] {server} client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237721.345] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237721.386] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237721.386] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237721.390] {proxy } client#1    <= wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [1237721.396] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237721.397] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237721.401] {proxy } client#1    <= wl_display#1.delete_id(id: 6)
[INFO] [stderr] [1237721.409] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#4294967295)
[INFO] [stderr] [1237721.425] {client} server      -> wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [1237721.429] {client} server      -> wl_display#1.delete_id(id: 6)
[INFO] [stderr] [1237721.445] {client} server      <= wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#6)
[INFO] [stderr] [1237721.413] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237721.450] {client} server      <= wlproxy_test_non_forward#6.echo()
[INFO] [stderr] [1237721.460] {client} server      <= wl_display#1.sync(callback: wl_callback#7)
[INFO] [stderr] [1237721.481] {proxy } client#1    -> wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#6)
[INFO] [stderr] [1237721.490] {proxy } client#1    -> wlproxy_test_non_forward#6.echo()
[INFO] [stderr] [1237721.836] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237721.859] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237721.889] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237721.900] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237721.915] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237721.934] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237721.946] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237721.963] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237721.993] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237722.004] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237722.013] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237722.028] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237722.058] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237722.068] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237722.100] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237722.117] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237722.141] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237722.154] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237722.158] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237722.165] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237722.189] {server} client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237722.201] {server} client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237722.210] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237722.218] {server} client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237722.224] {server} client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237722.228] {server} client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237722.263] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237722.267] {proxy } client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237722.283] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237722.289] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237722.296] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237722.298] {proxy } client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237722.303] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237722.308] {proxy } client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237722.358] {client} server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237722.363] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237722.368] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237722.371] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237722.566] {proxy } client#1    <= wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4278190080)
[INFO] [stderr] [2026-01-28T11:46:18Z 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] [2026-01-28T11:46:18Z WARN  wl_proxy::protocol_helpers] Could not forward a wlproxy_test_non_forward.echo message: the receiver has no server id
[INFO] [stderr] [1237722.681] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237722.681] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#7)
[INFO] [stderr] [1237722.700] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237722.703] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237722.742] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237722.759] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237722.773] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237722.794] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237722.806] {server} client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237722.816] {server} client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237722.823] {server} client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237722.846] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237722.852] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237722.864] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237722.868] {proxy } client#1    <= wl_callback#7.done(callback_data: 0)
[INFO] [stderr] [1237722.874] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237722.879] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237722.871] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237722.887] {proxy } client#1    <= wl_display#1.delete_id(id: 7)
[INFO] [stderr] [1237722.893] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237722.904] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237722.912] {client} server      -> wl_callback#7.done(callback_data: 0)
[INFO] [stderr] [1237722.916] {client} server      -> wl_display#1.delete_id(id: 7)
[INFO] [stderr] [1237722.917] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237722.931] {client} server      <= wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#7)
[INFO] [stderr] [1237722.936] {client} server      <= wlproxy_test_non_forward#7.echo()
[INFO] [stderr] [1237722.952] {client} server      <= wl_display#1.sync(callback: wl_callback#8)
[INFO] [stderr] [1237723.044] {proxy } client#1    -> wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#7)
[INFO] [stderr] [1237723.063] {proxy } server      <= wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#5)
[INFO] [stderr] [1237723.069] {proxy } client#1    -> wlproxy_test_non_forward#7.echo()
[INFO] [stderr] [1237723.072] {proxy } server      <= wlproxy_test_non_forward#5.echo()
[INFO] [stderr] [1237723.076] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#8)
[INFO] [stderr] [1237723.087] {proxy } server      <= wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [1237723.124] {server} client#1    -> wlproxy_test#3.create_non_forward(id: wlproxy_test_non_forward#5)
[INFO] [stderr] [1237723.122] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237723.132] {server} client#1    -> wlproxy_test_non_forward#5.echo()
[INFO] [stderr] [1237723.135] {server} client#1    <= wlproxy_test_non_forward#5.echoed()
[INFO] [stderr] [1237723.136] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237723.140] {server} client#1    -> wl_display#1.sync(callback: wl_callback#6)
[INFO] [stderr] [1237723.145] {server} client#1    <= wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [1237723.145] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237723.151] {server} client#1    <= wl_display#1.delete_id(id: 6)
[INFO] [stderr] [1237723.160] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237723.179] {proxy } server      -> wlproxy_test_non_forward#5.echoed()
[INFO] [stderr] [1237723.183] {proxy } client#1    <= wlproxy_test_non_forward#7.echoed()
[INFO] [stderr] [1237723.187] {proxy } server      -> wl_callback#6.done(callback_data: 0)
[INFO] [stderr] [1237723.190] {proxy } client#1    <= wl_callback#8.done(callback_data: 0)
[INFO] [stderr] [1237723.195] {proxy } server      -> wl_display#1.delete_id(id: 6)
[INFO] [stderr] [1237723.200] {proxy } client#1    <= wl_display#1.delete_id(id: 8)
[INFO] [stderr] [1237723.218] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237723.222] {client} server      -> wlproxy_test_non_forward#7.echoed()
[INFO] [stderr] [1237723.225] {client} server      -> wl_callback#8.done(callback_data: 0)
[INFO] [stderr] [1237723.229] {client} server      -> wl_display#1.delete_id(id: 8)
[INFO] [stderr] [1237723.232] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237723.255] {client} server      <= wlproxy_test#3.send_object()
[INFO] [stderr] [1237723.266] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237723.318] {proxy } client#1    -> wlproxy_test#3.send_object()
[INFO] [stderr] [1237723.329] {proxy } server      <= wlproxy_test#3.send_object()
[INFO] [stderr] [1237723.334] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237723.348] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237723.449] {server} client#1    -> wlproxy_test#3.send_object()
[INFO] [stderr] [1237723.474] {server} client#1    <= wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4278190080)
[INFO] [stderr] [1237723.558] {server} client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237723.574] {server} client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237723.580] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237723.646] {proxy } server      -> wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4278190080)
[INFO] [stderr] [1237723.662] {proxy } client#1    <= wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4278190080)
[INFO] [stderr] [1237723.679] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237723.688] {proxy } client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237723.693] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237723.698] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237723.751] {client} server      -> wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4278190080)
[INFO] [stderr] [1237723.766] {client} server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237723.770] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237723.783] {client} server      <= wlproxy_test_server_sent#4278190080.send_destroy()
[INFO] [stderr] [1237723.821] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237723.850] {proxy } client#1    -> wlproxy_test_server_sent#4278190080.send_destroy()
[INFO] [stderr] [1237723.860] {proxy } server      <= wlproxy_test_server_sent#4278190080.send_destroy()
[INFO] [stderr] [1237723.865] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237723.873] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237723.919] {server} client#1    -> wlproxy_test_server_sent#4278190080.send_destroy()
[INFO] [stderr] [1237723.929] {server} client#1    <= wlproxy_test_server_sent#4278190080.destroyed()
[INFO] [stderr] [1237723.940] {server} client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237723.947] {server} client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237723.952] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237724.282] {proxy } server      -> wlproxy_test_server_sent#4278190080.destroyed()
[INFO] [stderr] [1237724.295] {proxy } client#1    <= wlproxy_test_server_sent#4278190080.destroyed()
[INFO] [stderr] [1237724.306] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237724.308] {proxy } client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237724.313] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237724.318] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237724.376] {client} server      -> wlproxy_test_server_sent#4278190080.destroyed()
[INFO] [stderr] [1237724.388] {client} server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237724.391] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237724.566] {server} client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237724.599] {server} client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237724.612] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237724.686] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237724.700] {proxy } client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237724.709] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237724.717] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237724.762] {client} server      -> wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#50)
[INFO] [stdout] test fixed::tests::debug ... ok
[INFO] [stderr] [1237737.789] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237737.852] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237737.884] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237737.898] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237737.900] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237737.910] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237737.963] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237737.976] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237737.985] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237737.986] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237738.010] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237738.063] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237738.092] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237738.003] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237738.173] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stdout] test object::tests::forward ... ok
[INFO] [stderr] [1237738.344] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test object::tests::destroyed_client ... ok
[INFO] [stderr] [1237738.684] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test poll::tests::test ... ok
[INFO] [stderr] [1237738.713] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stdout] test object::tests::server_sent ... ok
[INFO] [stderr] [1237738.742] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test object::tests::server_destroyed ... ok
[INFO] [stderr] [1237738.743] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test state::tests::destructor ... ok
[INFO] [stderr] [1237738.754] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test object::tests::version ... ok
[INFO] [stderr] [1237738.762] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stdout] test object::tests::state ... ok
[INFO] [stderr] [1237738.767] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stdout] test state::tests::destroyed_readable ... ok
[INFO] [stderr] [1237738.781] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stdout] test state::tests::remote_destructor ... ok
[INFO] [stderr] [1237738.796] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test state::tests::add_client ... ok
[INFO] [stderr] [1237738.807] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test poll::tests::many ... ok
[INFO] [stderr] [1237738.811] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test state::tests::recursive_dispatch ... ok
[INFO] [stderr] [1237738.816] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stdout] test trans::tests::edge_header ... ok
[INFO] [stderr] [1237738.823] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stdout] test trans::tests::fd ... ok
[INFO] [stderr] [1237738.831] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stdout] test trans::tests::echo_fd ... ok
[INFO] [stderr] [1237738.930] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stdout] test trans::tests::large_message ... ok
[INFO] [stderr] [1237738.943] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237738.968] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237738.976] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237738.983] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237738.994] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237739.021] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237739.030] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237739.315] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237739.337] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237739.453] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237739.497] {server} client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237739.543] {server} client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237739.569] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237739.607] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237739.611] {proxy } client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237739.625] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237739.630] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237739.661] {client} server      -> wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4294967295)
[INFO] [stderr] [1237739.676] {client} server      -> wlproxy_test#3.sent_object(echo: wlproxy_test_server_sent#4294967295)
[INFO] [stderr] [1237739.772] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237739.811] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237739.843] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237739.901] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237739.922] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237739.937] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237740.316] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237740.559] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237740.567] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237740.584] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237740.743] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237740.831] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237740.857] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237740.993] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237741.001] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237741.041] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237741.099] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237741.106] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237741.118] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237741.134] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237741.218] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237741.293] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237741.313] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237741.349] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237741.458] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237741.496] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237741.513] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237741.651] {server} client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237741.790] {server} client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237741.930] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237742.181] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237742.223] {proxy } client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237742.281] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237742.344] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237742.366] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237742.389] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237742.398] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237742.409] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237742.435] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237742.450] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237742.515] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237742.574] {client} server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237742.591] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237742.631] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0)
[INFO] [stderr] [1237742.672] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237742.736] {proxy } client#1    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0)
[INFO] [stderr] [1237742.739] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237742.786] {proxy } server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0)
[INFO] [stderr] [1237742.825] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237742.861] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237742.907] {server} client#1    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0)
[INFO] [stderr] [1237742.956] {server} client#1    <= wlproxy_test_array_echo#4.array(array: 0x0)
[INFO] [stderr] [1237742.994] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237743.051] {server} client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237743.094] {server} client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237743.117] {server} client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237743.172] {proxy } server      -> wlproxy_test_array_echo#4.array(array: 0x0)
[INFO] [stderr] [1237743.209] {proxy } client#1    <= wlproxy_test_array_echo#4.array(array: 0x0)
[INFO] [stderr] [1237743.242] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237743.267] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237743.290] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237743.323] {proxy } client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237743.346] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237743.368] {proxy } client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237743.438] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237743.448] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x0)
[INFO] [stderr] [1237743.453] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237743.458] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237743.462] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237743.485] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01)
[INFO] [stderr] [1237743.545] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237743.623] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237743.695] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237743.712] {proxy } client#1    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01)
[INFO] [stderr] [1237743.728] {proxy } server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01)
[INFO] [stderr] [1237743.765] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237743.766] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237743.780] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237743.794] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237743.813] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237743.858] {server} client#1    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01)
[INFO] [stderr] [1237743.868] {server} client#1    <= wlproxy_test_array_echo#4.array(array: 0x01)
[INFO] [stderr] [1237743.875] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237743.882] {server} client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237743.889] {server} client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237743.894] {server} client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237743.923] {proxy } server      -> wlproxy_test_array_echo#4.array(array: 0x01)
[INFO] [stderr] [1237743.955] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237743.959] {proxy } client#1    <= wlproxy_test_array_echo#4.array(array: 0x01)
[INFO] [stderr] [1237743.982] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237744.012] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237744.012] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237744.025] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237744.033] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237744.041] {client} server      <= wlproxy_test#3.echo_fd(echo: wlproxy_test_fd_echo#4, fd1: 5, fd2: 6)
[INFO] [stderr] [1237744.055] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237744.057] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237744.075] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237744.117] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237744.120] {client} server      <= wlproxy_test#3.recv_fd(fd: 51)
[INFO] [stderr] [1237744.126] {client} server      <= wlproxy_test#3.recv_fd(fd: 51)
[INFO] [stderr] [1237744.136] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237744.141] {proxy } client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237744.179] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237744.186] {proxy } client#1    -> wlproxy_test#3.recv_fd(fd: 55)
[INFO] [stderr] [1237744.191] {proxy } server      <= wlproxy_test#3.recv_fd(fd: 55)
[INFO] [stderr] [1237744.215] {proxy } client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237744.220] {proxy } client#1    -> wlproxy_test#3.recv_fd(fd: 56)
[INFO] [stderr] [1237744.223] {proxy } server      <= wlproxy_test#3.recv_fd(fd: 56)
[INFO] [stderr] [1237744.221] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237744.228] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237744.236] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237744.241] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237744.302] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x01)
[INFO] [stderr] [1237744.316] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237744.347] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237744.383] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237744.411] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237744.418] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237744.418] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237744.431] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237744.435] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237744.440] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237744.449] {server} client#1    -> wlproxy_test#3.recv_fd(fd: 25)
[INFO] [stderr] [1237744.456] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237744.462] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237744.483] {server} client#1    -> wlproxy_test#3.recv_fd(fd: 25)
[INFO] [stderr] [1237744.484] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237744.496] {server} client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237744.508] {server} client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237744.541] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237744.563] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102)
[INFO] [stderr] [1237744.576] {proxy } client#1    -> wlproxy_test#3.echo_fd(echo: wlproxy_test_fd_echo#4, fd1: 25, fd2: 41)
[INFO] [stderr] [1237744.586] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237744.593] {proxy } server      <= wlproxy_test#3.echo_fd(echo: wlproxy_test_fd_echo#4, fd1: 25, fd2: 41)
[INFO] [stderr] [1237744.600] {proxy } client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237744.604] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237744.613] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237744.609] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237744.624] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237744.647] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237744.650] {client} server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237744.656] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237744.692] {server} client#1    -> wlproxy_test#3.echo_fd(echo: wlproxy_test_fd_echo#4, fd1: 25, fd2: 41)
[INFO] [stderr] [1237744.693] {proxy } client#1    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102)
[INFO] [stderr] [1237744.708] {proxy } server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102)
[INFO] [stderr] [1237744.714] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237744.721] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237744.744] {server} client#1    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102)
[INFO] [stderr] [1237744.759] {server} client#1    <= wlproxy_test_fd_echo#4.fd(fd1: 25, fd2: 41)
[INFO] [stderr] [1237744.798] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237744.808] {server} client#1    <= wlproxy_test_array_echo#4.array(array: 0x0102)
[INFO] [stderr] [1237744.818] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237744.833] {server} client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237744.841] {server} client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237744.865] {server} client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237744.874] {server} client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237744.886] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237744.880] {server} client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237744.900] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237744.904] {server} client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237744.947] {proxy } server      -> wlproxy_test_array_echo#4.array(array: 0x0102)
[INFO] [stderr] [1237744.979] {proxy } client#1    <= wlproxy_test_array_echo#4.array(array: 0x0102)
[INFO] [stderr] [2026-01-28T11:46:18Z ERROR wl_proxy::state] Could not handle client message: could not read a message: message has a supposed length 65535 > 4096
[INFO] [stderr] [1237745.013] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237745.022] {proxy } server      -> wlproxy_test_fd_echo#4.fd(fd1: 25, fd2: 41)
[INFO] [stderr] [1237745.025] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237745.028] {proxy } client#1    <= wlproxy_test_fd_echo#4.fd(fd1: 25, fd2: 41)
[INFO] [stderr] [1237745.032] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237745.035] {proxy } client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237745.038] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237745.039] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237745.044] {proxy } client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237745.044] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237745.052] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237745.055] {proxy } client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237745.060] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237745.065] {proxy } client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237745.071] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x0102)
[INFO] [stderr] [1237745.132] {client} server      -> wlproxy_test_fd_echo#4.fd(fd1: 25, fd2: 41)
[INFO] [stderr] [1237745.150] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237745.156] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237745.160] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237745.179] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237745.209] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237745.232] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237745.275] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x010203)
[INFO] [stderr] [1237745.332] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237745.381] {proxy } client#1    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x010203)
[INFO] [stderr] [1237745.432] {proxy } server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x010203)
[INFO] [stderr] [1237745.459] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237745.493] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237745.576] {server} client#1    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x010203)
[INFO] [stderr] [1237745.631] {server} client#1    <= wlproxy_test_array_echo#4.array(array: 0x010203)
[INFO] [stderr] [1237745.663] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237745.698] {server} client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237745.764] {server} client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237745.791] {server} client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237745.858] {proxy } server      -> wlproxy_test_array_echo#4.array(array: 0x010203)
[INFO] [stderr] [1237745.896] {proxy } client#1    <= wlproxy_test_array_echo#4.array(array: 0x010203)
[INFO] [stderr] [1237745.924] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237745.950] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237745.978] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237746.012] {proxy } client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237746.038] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237746.071] {proxy } client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237746.121] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x010203)
[INFO] [stderr] [1237746.161] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237746.230] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237746.273] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237746.327] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01020304)
[INFO] [stderr] [1237746.379] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237746.434] {proxy } client#1    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01020304)
[INFO] [stderr] [1237746.480] {proxy } server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01020304)
[INFO] [stderr] [1237746.517] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237746.581] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237746.656] {server} client#1    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x01020304)
[INFO] [stderr] [1237746.708] {server} client#1    <= wlproxy_test_array_echo#4.array(array: 0x01020304)
[INFO] [stderr] [1237746.737] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237746.768] {server} client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237746.802] {server} client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237746.825] {server} client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237746.879] {proxy } server      -> wlproxy_test_array_echo#4.array(array: 0x01020304)
[INFO] [stderr] [1237746.897] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237746.923] {proxy } client#1    <= wlproxy_test_array_echo#4.array(array: 0x01020304)
[INFO] [stderr] [1237746.952] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237746.976] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237747.005] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237747.028] {proxy } client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237747.061] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237747.086] {proxy } client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237747.135] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x01020304)
[INFO] [stderr] [1237747.138] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237747.173] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237747.200] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237747.220] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237747.265] {client} server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102030405)
[INFO] [stderr] [1237747.310] {client} server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237747.363] {proxy } client#1    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102030405)
[INFO] [stderr] [1237747.397] {proxy } server      <= wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102030405)
[INFO] [stderr] [1237747.421] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237747.447] {proxy } server      <= wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237747.499] {server} client#1    -> wlproxy_test#3.echo_array(echo: wlproxy_test_array_echo#4, array: 0x0102030405)
[INFO] [stderr] [1237747.511] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237747.538] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237747.563] {server} client#1    <= wlproxy_test_array_echo#4.array(array: 0x0102030405)
[INFO] [stderr] [1237747.589] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237747.621] {server} client#1    -> wl_display#1.sync(callback: wl_callback#5)
[INFO] [stderr] [1237747.652] {server} client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237747.684] {server} client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237747.750] {proxy } server      -> wlproxy_test_array_echo#4.array(array: 0x0102030405)
[INFO] [stderr] [1237747.701] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237747.775] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237747.797] {proxy } client#1    <= wlproxy_test_array_echo#4.array(array: 0x0102030405)
[INFO] [stderr] [1237747.821] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237747.845] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237747.882] {proxy } server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237747.902] {proxy } client#1    <= wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237747.924] {proxy } server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237747.945] {proxy } client#1    <= wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237748.009] {client} server      -> wlproxy_test_array_echo#4.array(array: 0x0102030405)
[INFO] [stderr] [1237748.049] {client} server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237748.082] {client} server      -> wl_callback#5.done(callback_data: 0)
[INFO] [stderr] [1237748.116] {client} server      -> wl_display#1.delete_id(id: 5)
[INFO] [stderr] [1237748.191] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237748.280] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237748.391] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237748.589] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [2026-01-28T11:46:18Z 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] [stderr] [1237748.624] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237748.657] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237748.677] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237748.686] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237748.701] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237748.731] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237748.752] {server} client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237748.765] {proxy } client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237748.767] {server} client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237748.779] {proxy } server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237749.071] {client} server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237749.087] {client} server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237749.118] {proxy } client#1    -> wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237749.127] {proxy } server      <= wl_display#1.get_registry(registry: wl_registry#2)
[INFO] [stderr] [1237749.134] {proxy } client#1    -> wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237749.147] {proxy } server      <= wl_registry#2.bind(name: 0, id: wlproxy_test#3 (version: 1))
[INFO] [stderr] [1237749.279] {client} server      <= wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [2026-01-28T11:46:18Z ERROR wl_proxy::state] Could not handle client message: could not read a message: message has a supposed length 4 < 8
[INFO] [stderr] [1237750.837] {server} client#1    -> wl_display#1.sync(callback: wl_callback#4)
[INFO] [stderr] [1237750.858] {server} client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237750.868] {server} client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237754.674] {proxy } server      -> wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237754.684] {proxy } client#1    <= wl_callback#4.done(callback_data: 0)
[INFO] [stderr] [1237754.706] {proxy } server      -> wl_display#1.delete_id(id: 4)
[INFO] [stderr] [1237754.714] {proxy } client#1    <= wl_display#1.delete_id(id: 4)
[INFO] [stdout] test trans::tests::array ... ok
[INFO] [stdout] test trans::tests::not_word_size ... ok
[INFO] [stdout] test trans::tests::small_message ... ok
[INFO] [stdout] test state::tests::count_hops ... ok
[INFO] [stdout] test utils::stack::tests::stack ... ok
[INFO] [stdout] test state::tests::closed_client ... ok
[INFO] [stdout] test utils::stash::tests::test ... ok
[INFO] [stdout] test utils::free_list::tests::test ... ok
[INFO] [stdout] test acceptor::tests::test ... FAILED
[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] [stdout] test object::tests::duplicate_server_id - should panic ... ok
[INFO] [stdout] test object::tests::wrong_downcast - should panic ... ok
[INFO] [stdout] test state::tests::acceptor ... FAILED
[INFO] [stdout] test object::tests::invalid_server_id - should panic ... ok
[INFO] [stdout] test utils::free_list::tests::release_out_of_bounds - 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' (25) 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:     0x5a1aed1b1732 - std::backtrace_rs::backtrace::libunwind::trace::h90e89fac33585832
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5a1aed1b1732 - std::backtrace_rs::backtrace::trace_unsynchronized::heebcf2380a8e7359
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5a1aed1b1732 - std::sys::backtrace::_print_fmt::hdf3e2a22d2f810db
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:68:9
[INFO] [stdout]    3:     0x5a1aed1b1732 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::hac1d885928ba8582
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:38:26
[INFO] [stdout]    4:     0x5a1aed1c3aa7 - core::fmt::rt::Argument::fmt::h6901eb7c19abebe8
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5a1aed1c3aa7 - core::fmt::write::h83ebb4d32483be9e
[INFO] [stdout]    6:     0x5a1aed17ea46 - std::io::default_write_fmt::h751a79cdae495f10
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5a1aed17ea46 - std::io::Write::write_fmt::h9ef514f8700ff7b2
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5a1aed18f3c9 - std::sys::backtrace::BacktraceLock::print::hcc2e1a3ac996c911
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:41:9
[INFO] [stdout]    9:     0x5a1aed18f3c9 - std::panicking::default_hook::{{closure}}::h8e9c4d1276f0925f
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5a1aed18f261 - std::panicking::default_hook::h2b2078d38b534dfb
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5a1aed14ecee - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h39d051e7dedac6a7
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x5a1aed14ecee - test::test_main_with_exit_callback::{{closure}}::h4b005fe75814c302
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5a1aed18f642 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hd426070722c13cbf
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x5a1aed18f642 - std::panicking::panic_with_hook::h39b739724e701bfd
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5a1aed18f488 - std::panicking::panic_handler::{{closure}}::he540c4833054e458
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5a1aed18aa59 - std::sys::backtrace::__rust_end_short_backtrace::hfa179d89deec8aed
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:176:18
[INFO] [stdout]   17:     0x5a1aed1737ad - __rustc[d131491b17107b07]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5a1aed1cc2bc - core::panicking::panic_fmt::ha564519d657d9c46
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5a1aed1cb872 - core::result::unwrap_failed::h23873bb39ca9a844
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x5a1aece7803a - core::result::Result<T,E>::unwrap::h1034dab4b0259e23
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/result.rs:1233:23
[INFO] [stdout]   21:     0x5a1aece7803a - wl_proxy::acceptor::tests::test::hf38c7fcd1af812f5
[INFO] [stdout]                                at /opt/rustwide/workdir/src/acceptor/tests.rs:9:46
[INFO] [stdout]   22:     0x5a1aece787e7 - wl_proxy::acceptor::tests::test::{{closure}}::h3c15e18c456efc47
[INFO] [stdout]                                at /opt/rustwide/workdir/src/acceptor/tests.rs:8:10
[INFO] [stdout]   23:     0x5a1aece3aff6 - core::ops::function::FnOnce::call_once::h88f15c837e23fc3e
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5a1aed14eb1b - core::ops::function::FnOnce::call_once::h0217b30cfdee2e67
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5a1aed14eb1b - test::__rust_begin_short_backtrace::h6d98714a1e28f556
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:663:18
[INFO] [stdout]   26:     0x5a1aed1624aa - test::run_test_in_process::{{closure}}::he2c70d2634d3a3cc
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:686:74
[INFO] [stdout]   27:     0x5a1aed1624aa - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h40a0a825bcf17fdd
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   28:     0x5a1aed1624aa - std::panicking::catch_unwind::do_call::h6c8eb5dd6e7b5975
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x5a1aed1624aa - std::panicking::catch_unwind::h55455eac2972ad1e
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x5a1aed1624aa - std::panic::catch_unwind::h0025465150a5e558
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x5a1aed1624aa - test::run_test_in_process::hb61e682f9b630fbc
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:686:27
[INFO] [stdout]   32:     0x5a1aed1624aa - test::run_test::{{closure}}::hc2c2a7a38ab176e2
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:607:43
[INFO] [stdout]   33:     0x5a1aed13c744 - test::run_test::{{closure}}::ha91867b85cd2a911
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:637:41
[INFO] [stdout]   34:     0x5a1aed13c744 - std::sys::backtrace::__rust_begin_short_backtrace::ha00a9fe02049c339
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:160:18
[INFO] [stdout]   35:     0x5a1aed140072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::hd629fba9153dfb55
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/thread/lifecycle.rs:92:13
[INFO] [stdout]   36:     0x5a1aed140072 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h4189018816a5c5d9
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   37:     0x5a1aed140072 - std::panicking::catch_unwind::do_call::hf9fcdbca3c2e9877
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x5a1aed140072 - std::panicking::catch_unwind::h97b92d88631c7c64
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x5a1aed140072 - std::panic::catch_unwind::ha77cdbb68e725e5e
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x5a1aed140072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::h511f05bc1a132253
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/thread/lifecycle.rs:90:26
[INFO] [stdout]   41:     0x5a1aed140072 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h6b68b31e9b685cb8
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x5a1aed1860ef - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h22a27d165e4e871d
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   43:     0x5a1aed1860ef - std::sys::thread::unix::Thread::new::thread_start::h45cc87bb053add0f
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x708970e06aa4 - <unknown>
[INFO] [stdout]   45:     0x708970e93a64 - clone
[INFO] [stdout]   46:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] ---- state::tests::acceptor stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'state::tests::acceptor' (137) panicked at src/state/tests.rs:107:49:
[INFO] [stdout] called `Result::unwrap()` on an `Err` value: StateError(CreateAcceptor(AcceptorError(XrdNotSet)))
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5a1aed1b1732 - std::backtrace_rs::backtrace::libunwind::trace::h90e89fac33585832
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5a1aed1b1732 - std::backtrace_rs::backtrace::trace_unsynchronized::heebcf2380a8e7359
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5a1aed1b1732 - std::sys::backtrace::_print_fmt::hdf3e2a22d2f810db
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:68:9
[INFO] [stdout]    3:     0x5a1aed1b1732 - <std::sys::backtrace::BacktraceLock::print::DisplayBacktrace as core::fmt::Display>::fmt::hac1d885928ba8582
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:38:26
[INFO] [stdout]    4:     0x5a1aed1c3aa7 - core::fmt::rt::Argument::fmt::h6901eb7c19abebe8
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5a1aed1c3aa7 - core::fmt::write::h83ebb4d32483be9e
[INFO] [stdout]    6:     0x5a1aed17ea46 - std::io::default_write_fmt::h751a79cdae495f10
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/io/mod.rs:639:11
[INFO] [stdout]    7:     0x5a1aed17ea46 - std::io::Write::write_fmt::h9ef514f8700ff7b2
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/io/mod.rs:1994:13
[INFO] [stdout]    8:     0x5a1aed18f3c9 - std::sys::backtrace::BacktraceLock::print::hcc2e1a3ac996c911
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:41:9
[INFO] [stdout]    9:     0x5a1aed18f3c9 - std::panicking::default_hook::{{closure}}::h8e9c4d1276f0925f
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5a1aed18f261 - std::panicking::default_hook::h2b2078d38b534dfb
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5a1aed14ecee - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::h39d051e7dedac6a7
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   12:     0x5a1aed14ecee - test::test_main_with_exit_callback::{{closure}}::h4b005fe75814c302
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:145:21
[INFO] [stdout]   13:     0x5a1aed18f642 - <alloc::boxed::Box<F,A> as core::ops::function::Fn<Args>>::call::hd426070722c13cbf
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/alloc/src/boxed.rs:2220:9
[INFO] [stdout]   14:     0x5a1aed18f642 - std::panicking::panic_with_hook::h39b739724e701bfd
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5a1aed18f488 - std::panicking::panic_handler::{{closure}}::he540c4833054e458
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5a1aed18aa59 - std::sys::backtrace::__rust_end_short_backtrace::hfa179d89deec8aed
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:176:18
[INFO] [stdout]   17:     0x5a1aed1737ad - __rustc[d131491b17107b07]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5a1aed1cc2bc - core::panicking::panic_fmt::ha564519d657d9c46
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5a1aed1cb872 - core::result::unwrap_failed::h23873bb39ca9a844
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x5a1aece4dd4f - core::result::Result<T,E>::unwrap::h7376bfd61d5af2f2
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/result.rs:1233:23
[INFO] [stdout]   21:     0x5a1aece4dd4f - wl_proxy::state::tests::acceptor::hd147d4e8d79d60fb
[INFO] [stdout]                                at /opt/rustwide/workdir/src/state/tests.rs:107:49
[INFO] [stdout]   22:     0x5a1aece4e397 - wl_proxy::state::tests::acceptor::{{closure}}::hd3497128aa9cc911
[INFO] [stdout]                                at /opt/rustwide/workdir/src/state/tests.rs:92:14
[INFO] [stdout]   23:     0x5a1aece3b4a6 - core::ops::function::FnOnce::call_once::ha5fc02de5eb8215d
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   24:     0x5a1aed14eb1b - core::ops::function::FnOnce::call_once::h0217b30cfdee2e67
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   25:     0x5a1aed14eb1b - test::__rust_begin_short_backtrace::h6d98714a1e28f556
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:663:18
[INFO] [stdout]   26:     0x5a1aed1624aa - test::run_test_in_process::{{closure}}::he2c70d2634d3a3cc
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:686:74
[INFO] [stdout]   27:     0x5a1aed1624aa - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h40a0a825bcf17fdd
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   28:     0x5a1aed1624aa - std::panicking::catch_unwind::do_call::h6c8eb5dd6e7b5975
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:581:40
[INFO] [stdout]   29:     0x5a1aed1624aa - std::panicking::catch_unwind::h55455eac2972ad1e
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:544:19
[INFO] [stdout]   30:     0x5a1aed1624aa - std::panic::catch_unwind::h0025465150a5e558
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panic.rs:359:14
[INFO] [stdout]   31:     0x5a1aed1624aa - test::run_test_in_process::hb61e682f9b630fbc
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:686:27
[INFO] [stdout]   32:     0x5a1aed1624aa - test::run_test::{{closure}}::hc2c2a7a38ab176e2
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:607:43
[INFO] [stdout]   33:     0x5a1aed13c744 - test::run_test::{{closure}}::ha91867b85cd2a911
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/test/src/lib.rs:637:41
[INFO] [stdout]   34:     0x5a1aed13c744 - std::sys::backtrace::__rust_begin_short_backtrace::ha00a9fe02049c339
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/backtrace.rs:160:18
[INFO] [stdout]   35:     0x5a1aed140072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::{{closure}}::hd629fba9153dfb55
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/thread/lifecycle.rs:92:13
[INFO] [stdout]   36:     0x5a1aed140072 - <core::panic::unwind_safe::AssertUnwindSafe<F> as core::ops::function::FnOnce<()>>::call_once::h4189018816a5c5d9
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/panic/unwind_safe.rs:274:9
[INFO] [stdout]   37:     0x5a1aed140072 - std::panicking::catch_unwind::do_call::hf9fcdbca3c2e9877
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:581:40
[INFO] [stdout]   38:     0x5a1aed140072 - std::panicking::catch_unwind::h97b92d88631c7c64
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panicking.rs:544:19
[INFO] [stdout]   39:     0x5a1aed140072 - std::panic::catch_unwind::ha77cdbb68e725e5e
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/panic.rs:359:14
[INFO] [stdout]   40:     0x5a1aed140072 - std::thread::lifecycle::spawn_unchecked::{{closure}}::h511f05bc1a132253
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/thread/lifecycle.rs:90:26
[INFO] [stdout]   41:     0x5a1aed140072 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h6b68b31e9b685cb8
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   42:     0x5a1aed1860ef - <alloc::boxed::Box<F,A> as core::ops::function::FnOnce<Args>>::call_once::h22a27d165e4e871d
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/alloc/src/boxed.rs:2206:9
[INFO] [stdout]   43:     0x5a1aed1860ef - std::sys::thread::unix::Thread::new::thread_start::h45cc87bb053add0f
[INFO] [stdout]                                at /rustc/254b59607d4417e9dffbc307138ae5c86280fe4c/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   44:     0x708970e06aa4 - <unknown>
[INFO] [stdout]   45:     0x708970e93a64 - 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. 101 passed; 2 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.21s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `--lib`
[INFO] running `Command { std: "docker" "inspect" "bc9a89897b886ef215a7138d4b937da170adcac852f8967acde0d23985230a0d", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "bc9a89897b886ef215a7138d4b937da170adcac852f8967acde0d23985230a0d", kill_on_drop: false }`
[INFO] [stdout] bc9a89897b886ef215a7138d4b937da170adcac852f8967acde0d23985230a0d
