[INFO] cloning repository https://github.com/emizuki/figma-dev-mcp
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/emizuki/figma-dev-mcp" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Femizuki%2Ffigma-dev-mcp", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Femizuki%2Ffigma-dev-mcp'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] a23ccdceeead9acfd53ed669e7ccac70593d68c0
[INFO] testing emizuki/figma-dev-mcp against 1.100.0-beta.1 for beta-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Femizuki%2Ffigma-dev-mcp" "/workspace/builds/worker-7-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc2/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-7-tc2/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/emizuki/figma-dev-mcp
[INFO] finished tweaking git repo https://github.com/emizuki/figma-dev-mcp
[INFO] tweaked toml for git repo https://github.com/emizuki/figma-dev-mcp written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/emizuki/figma-dev-mcp on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/emizuki/figma-dev-mcp already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr] warning: unused workspace dependency `async-trait`
[INFO] [stderr]  --> Cargo.toml:7:1
[INFO] [stderr]   |
[INFO] [stderr] 7 | async-trait = "0.1"
[INFO] [stderr]   | ^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_workspace_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the workspace dependency `async-trait`
[INFO] [stderr] warning: unused workspace dependency `base64`
[INFO] [stderr]  --> Cargo.toml:9:1
[INFO] [stderr]   |
[INFO] [stderr] 9 | base64 = "0.22"
[INFO] [stderr]   | ^^^^^^
[INFO] [stderr]   |
[INFO] [stderr] help: consider removing the workspace dependency `base64`
[INFO] [stderr] warning: unused workspace dependency `walkdir`
[INFO] [stderr]   --> Cargo.toml:15:1
[INFO] [stderr]    |
[INFO] [stderr] 15 | walkdir = "2"
[INFO] [stderr]    | ^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the workspace dependency `walkdir`
[INFO] [stderr] warning: workspace (manifest) generated 3 warnings
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 1e8e69b5f6f2a4a52b025497b57e46f1c3c23d418f71201f9edafc1304e0bf81
[INFO] running `Command { std: "docker" "start" "1e8e69b5f6f2a4a52b025497b57e46f1c3c23d418f71201f9edafc1304e0bf81", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1e8e69b5f6f2a4a52b025497b57e46f1c3c23d418f71201f9edafc1304e0bf81", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "1e8e69b5f6f2a4a52b025497b57e46f1c3c23d418f71201f9edafc1304e0bf81" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "1e8e69b5f6f2a4a52b025497b57e46f1c3c23d418f71201f9edafc1304e0bf81", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "1e8e69b5f6f2a4a52b025497b57e46f1c3c23d418f71201f9edafc1304e0bf81" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr] warning: unused workspace dependency `async-trait`
[INFO] [stderr]  --> Cargo.toml:7:1
[INFO] [stderr]   |
[INFO] [stderr] 7 | async-trait = "0.1"
[INFO] [stderr]   | ^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_workspace_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the workspace dependency `async-trait`
[INFO] [stderr] warning: unused workspace dependency `base64`
[INFO] [stderr]  --> Cargo.toml:9:1
[INFO] [stderr]   |
[INFO] [stderr] 9 | base64 = "0.22"
[INFO] [stderr]   | ^^^^^^
[INFO] [stderr]   |
[INFO] [stderr] help: consider removing the workspace dependency `base64`
[INFO] [stderr] warning: unused workspace dependency `walkdir`
[INFO] [stderr]   --> Cargo.toml:15:1
[INFO] [stderr]    |
[INFO] [stderr] 15 | walkdir = "2"
[INFO] [stderr]    | ^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the workspace dependency `walkdir`
[INFO] [stderr] warning: workspace (manifest) generated 3 warnings
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling libc v0.2.189
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling itoa v1.0.18
[INFO] [stderr]    Compiling pin-project-lite v0.2.17
[INFO] [stderr]    Compiling futures-core v0.3.34
[INFO] [stderr]    Compiling bytes v1.12.1
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling futures-sink v0.3.34
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling futures-io v0.3.34
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling futures-channel v0.3.34
[INFO] [stderr]    Compiling log v0.4.33
[INFO] [stderr]    Compiling futures-task v0.3.34
[INFO] [stderr]    Compiling zmij v1.0.23
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling rand_core v0.10.1
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling autocfg v1.5.1
[INFO] [stderr]    Compiling typenum v1.20.1
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling httparse v1.10.1
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling cpufeatures v0.3.0
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling ref-cast v1.0.26
[INFO] [stderr]    Compiling chacha20 v0.10.1
[INFO] [stderr]    Compiling tower-service v0.3.3
[INFO] [stderr]    Compiling ident_case v1.0.1
[INFO] [stderr]    Compiling const-oid v0.10.2
[INFO] [stderr]    Compiling dyn-clone v1.0.20
[INFO] [stderr]    Compiling data-encoding v2.11.1
[INFO] [stderr]    Compiling rmcp v3.0.0
[INFO] [stderr]    Compiling base64 v0.23.1
[INFO] [stderr]    Compiling regex-syntax v0.8.11
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling pastey v0.2.3
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling anyhow v1.0.104
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling http v1.5.0
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling thread_local v1.1.10
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling httpdate v1.0.3
[INFO] [stderr]    Compiling tower-layer v0.3.3
[INFO] [stderr]    Compiling atomic-waker v1.1.2
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling mime v0.3.17
[INFO] [stderr]    Compiling ryu v1.0.23
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling hybrid-array v0.4.14
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling clap_builder v4.6.6
[INFO] [stderr]    Compiling matchit v0.8.4
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.2.2
[INFO] [stderr]    Compiling socket2 v0.6.5
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling rand v0.10.2
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling regex-automata v0.4.18
[INFO] [stderr]    Compiling block-buffer v0.12.1
[INFO] [stderr]    Compiling crypto-common v0.2.2
[INFO] [stderr]    Compiling digest v0.11.3
[INFO] [stderr]    Compiling uuid v1.24.1
[INFO] [stderr]    Compiling serde_path_to_error v0.1.20
[INFO] [stderr]    Compiling sha1 v0.11.0
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling serde_derive_internals v0.30.0
[INFO] [stderr]    Compiling darling_core v0.24.0
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling futures-macro v0.3.34
[INFO] [stderr]    Compiling tokio-macros v2.7.2
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling thiserror-impl v2.0.20
[INFO] [stderr]    Compiling ref-cast-impl v1.0.26
[INFO] [stderr]    Compiling async-trait v0.1.92
[INFO] [stderr]    Compiling clap_derive v4.6.4
[INFO] [stderr]    Compiling schemars_derive v1.2.2
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling futures-util v0.3.34
[INFO] [stderr]    Compiling tungstenite v0.30.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling axum-core v0.5.6
[INFO] [stderr]    Compiling clap v4.6.6
[INFO] [stderr]    Compiling darling_macro v0.24.0
[INFO] [stderr]    Compiling darling v0.24.0
[INFO] [stderr]    Compiling rmcp-macros v3.1.2
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling futures-executor v0.3.34
[INFO] [stderr]    Compiling sse-stream v0.2.5
[INFO] [stderr]    Compiling futures v0.3.34
[INFO] [stderr]    Compiling schemars v1.2.2
[INFO] [stderr]    Compiling figma-dev-mcp-protocol v0.1.0 (/opt/rustwide/workdir/crates/protocol)
[INFO] [stderr]    Compiling tokio-util v0.7.19
[INFO] [stderr]    Compiling tokio-stream v0.1.19
[INFO] [stderr]    Compiling tokio-tungstenite v0.30.0
[INFO] [stderr]    Compiling hyper v1.8.1
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling axum v0.8.8
[INFO] [stderr]    Compiling figma-dev-mcp-broker v0.1.0 (/opt/rustwide/workdir/crates/broker)
[INFO] [stderr]    Compiling figma-dev-mcp-prompts v0.1.0 (/opt/rustwide/workdir/crates/prompts)
[INFO] [stderr]    Compiling figma-dev-mcp-tools v0.1.0 (/opt/rustwide/workdir/crates/tools)
[INFO] [stderr]    Compiling figma-dev-mcp v0.1.0 (/opt/rustwide/workdir/crates/figma-dev-mcp)
[INFO] [stderr]    Compiling figma-dev-mcp-tests v0.1.0 (/opt/rustwide/workdir/tests)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 39s
[INFO] running `Command { std: "docker" "inspect" "1e8e69b5f6f2a4a52b025497b57e46f1c3c23d418f71201f9edafc1304e0bf81", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "1e8e69b5f6f2a4a52b025497b57e46f1c3c23d418f71201f9edafc1304e0bf81" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr] warning: unused workspace dependency `async-trait`
[INFO] [stderr]  --> Cargo.toml:7:1
[INFO] [stderr]   |
[INFO] [stderr] 7 | async-trait = "0.1"
[INFO] [stderr]   | ^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_workspace_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the workspace dependency `async-trait`
[INFO] [stderr] warning: unused workspace dependency `base64`
[INFO] [stderr]  --> Cargo.toml:9:1
[INFO] [stderr]   |
[INFO] [stderr] 9 | base64 = "0.22"
[INFO] [stderr]   | ^^^^^^
[INFO] [stderr]   |
[INFO] [stderr] help: consider removing the workspace dependency `base64`
[INFO] [stderr] warning: unused workspace dependency `walkdir`
[INFO] [stderr]   --> Cargo.toml:15:1
[INFO] [stderr]    |
[INFO] [stderr] 15 | walkdir = "2"
[INFO] [stderr]    | ^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the workspace dependency `walkdir`
[INFO] [stderr] warning: workspace (manifest) generated 3 warnings
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling regex-syntax v0.8.11
[INFO] [stderr]    Compiling aho-corasick v1.1.5
[INFO] [stderr]    Compiling stable_deref_trait v1.2.1
[INFO] [stderr]    Compiling zerovec-derive v0.11.4
[INFO] [stderr]    Compiling displaydoc v0.2.7
[INFO] [stderr]    Compiling litemap v0.8.3
[INFO] [stderr]    Compiling writeable v0.6.4
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling utf8_iter v1.0.4
[INFO] [stderr]    Compiling version_check v0.9.5
[INFO] [stderr]    Compiling icu_properties_data v2.3.0
[INFO] [stderr]    Compiling zerocopy v0.8.56
[INFO] [stderr]    Compiling icu_normalizer_data v2.3.0
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling allocator-api2 v0.2.21
[INFO] [stderr]    Compiling borrow-or-share v0.2.4
[INFO] [stderr]    Compiling foldhash v0.2.0
[INFO] [stderr]    Compiling unicode-general-category v1.1.0
[INFO] [stderr]    Compiling fluent-uri v0.4.1
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling bytecount v0.6.9
[INFO] [stderr]    Compiling outref v0.5.2
[INFO] [stderr]    Compiling num-cmp v0.1.0
[INFO] [stderr]    Compiling micromap v0.3.0
[INFO] [stderr]    Compiling vsimd v0.8.0
[INFO] [stderr]    Compiling email_address v0.2.9
[INFO] [stderr]    Compiling hashbrown v0.17.1
[INFO] [stderr]    Compiling uuid-simd v0.8.0
[INFO] [stderr]    Compiling chrono v0.4.45
[INFO] [stderr]    Compiling num-integer v0.1.47
[INFO] [stderr]    Compiling num-complex v0.4.6
[INFO] [stderr]    Compiling num-bigint v0.4.8
[INFO] [stderr]    Compiling num-iter v0.1.46
[INFO] [stderr]    Compiling schemars v1.2.2
[INFO] [stderr]    Compiling regex-automata v0.4.18
[INFO] [stderr]    Compiling jsonschema-regex v0.49.9
[INFO] [stderr]    Compiling num-rational v0.4.2
[INFO] [stderr]    Compiling num v0.4.3
[INFO] [stderr]    Compiling fraction v0.15.4
[INFO] [stderr]    Compiling figma-dev-mcp-protocol v0.1.0 (/opt/rustwide/workdir/crates/protocol)
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling zerofrom-derive v0.1.7
[INFO] [stderr]    Compiling yoke-derive v0.8.2
[INFO] [stderr]    Compiling strum_macros v0.28.0
[INFO] [stderr]    Compiling zerofrom v0.1.8
[INFO] [stderr]    Compiling yoke v0.8.3
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling fancy-regex v0.19.0
[INFO] [stderr]    Compiling regex v1.13.1
[INFO] [stderr]    Compiling zerovec v0.11.7
[INFO] [stderr]    Compiling zerotrie v0.2.5
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling rmcp v3.0.0
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling axum-core v0.5.6
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling jsonschema-value v0.49.9
[INFO] [stderr]    Compiling referencing v0.49.9
[INFO] [stderr]    Compiling tinystr v0.8.4
[INFO] [stderr]    Compiling potential_utf v0.1.6
[INFO] [stderr]    Compiling icu_collections v2.3.0
[INFO] [stderr]    Compiling icu_locale_core v2.3.0
[INFO] [stderr]    Compiling strum v0.28.0
[INFO] [stderr]    Compiling icu_provider v2.3.0
[INFO] [stderr]    Compiling axum v0.8.8
[INFO] [stderr]    Compiling icu_properties v2.3.0
[INFO] [stderr]    Compiling icu_normalizer v2.3.0
[INFO] [stderr]    Compiling idna_adapter v1.2.2
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling jsonschema v0.49.9
[INFO] [stderr]    Compiling figma-dev-mcp-broker v0.1.0 (/opt/rustwide/workdir/crates/broker)
[INFO] [stderr]    Compiling figma-dev-mcp-prompts v0.1.0 (/opt/rustwide/workdir/crates/prompts)
[INFO] [stderr]    Compiling figma-dev-mcp-tools v0.1.0 (/opt/rustwide/workdir/crates/tools)
[INFO] [stderr]    Compiling figma-dev-mcp v0.1.0 (/opt/rustwide/workdir/crates/figma-dev-mcp)
[INFO] [stderr]    Compiling figma-dev-mcp-tests v0.1.0 (/opt/rustwide/workdir/tests)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2m 09s
[INFO] running `Command { std: "docker" "inspect" "1e8e69b5f6f2a4a52b025497b57e46f1c3c23d418f71201f9edafc1304e0bf81", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "1e8e69b5f6f2a4a52b025497b57e46f1c3c23d418f71201f9edafc1304e0bf81" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr] warning: unused workspace dependency `async-trait`
[INFO] [stderr]  --> Cargo.toml:7:1
[INFO] [stderr]   |
[INFO] [stderr] 7 | async-trait = "0.1"
[INFO] [stderr]   | ^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `cargo::unused_workspace_dependencies` is set to `warn` by default
[INFO] [stderr] help: consider removing the workspace dependency `async-trait`
[INFO] [stderr] warning: unused workspace dependency `base64`
[INFO] [stderr]  --> Cargo.toml:9:1
[INFO] [stderr]   |
[INFO] [stderr] 9 | base64 = "0.22"
[INFO] [stderr]   | ^^^^^^
[INFO] [stderr]   |
[INFO] [stderr] help: consider removing the workspace dependency `base64`
[INFO] [stderr] warning: unused workspace dependency `walkdir`
[INFO] [stderr]   --> Cargo.toml:15:1
[INFO] [stderr]    |
[INFO] [stderr] 15 | walkdir = "2"
[INFO] [stderr]    | ^^^^^^^
[INFO] [stderr]    |
[INFO] [stderr] help: consider removing the workspace dependency `walkdir`
[INFO] [stderr] warning: workspace (manifest) generated 3 warnings
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.47s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/figma-dev-mcp/4425e0f738869808/out/figma_dev_mcp-4425e0f738869808)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test logging::tests::bare_debug_enables_this_crate_family_and_keeps_rmcp_at_info ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/build/figma-dev-mcp/596f85bb2f99161b/out/figma_dev_mcp-596f85bb2f99161b)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/stdio_eras.rs (/opt/rustwide/target/debug/build/figma-dev-mcp/a2f36be6f3ab62d6/out/stdio_eras-a2f36be6f3ab62d6)
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test legacy_2025_11_25_initialize_and_lists_over_real_stdio ... ok
[INFO] [stdout] test modern_2026_07_28_discover_and_stateless_lists_over_real_stdio ... ok
[INFO] [stdout] test an_election_that_never_succeeds_still_answers_the_client ... ok
[INFO] [stdout] test an_uninitialized_follower_still_reopens_the_plugin_port ... ok
[INFO] [stdout] test killing_the_leader_lets_a_follower_reopen_the_plugin_port ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/figma-dev-mcp-broker/d2dac347d8932c02/out/figma_dev_mcp_broker-d2dac347d8932c02)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.99s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test supervisor::tests::a_permanently_failing_election_goes_quiet ... ok
[INFO] [stdout] test supervisor::tests::the_first_immediate_death_waits_the_old_floor ... ok
[INFO] [stdout] test supervisor::tests::an_ipv4_loopback_plugin_port_wants_the_same_port_on_ipv6 ... ok
[INFO] [stdout] test supervisor::tests::only_ipv4_loopback_has_a_companion ... ok
[INFO] [stdout] test supervisor::tests::the_companion_follows_an_ephemeral_port ... ok
[INFO] [stdout] test supervisor::tests::the_backoff_growth_phase_is_logged_in_full ... ok
[INFO] [stdout] test supervisor::tests::the_first_recycles_are_reported_in_full ... ok
[INFO] [stdout] test supervisor::tests::a_sustained_spin_goes_quiet ... ok
[INFO] [stdout] test supervisor::tests::the_recycle_delay_saturates_at_the_backoff_cap ... ok
[INFO] [stdout] test supervisor::tests::consecutive_immediate_deaths_double_the_delay ... ok
[INFO] [stdout] test supervisor::tests::a_sustained_spin_still_reports_periodically ... ok
[INFO] [stdout] test supervisor::tests::a_role_that_lived_a_healthy_life_re_elects_immediately ... ok
[INFO] [stdout] test supervisor::tests::the_recycle_delay_never_decreases ... ok
[INFO] [stdout] test supervisor::tests::a_stuck_election_still_reports_periodically ... ok
[INFO] [stdout] test supervisor::tests::a_healthy_role_outlives_the_longest_delay_the_supervisor_imposes ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/figma-dev-mcp-prompts/d4cf44761a5ed4c9/out/figma_dev_mcp_prompts-d4cf44761a5ed4c9)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/figma-dev-mcp-protocol/ac8dd7d7eb4a0c1a/out/figma_dev_mcp_protocol-ac8dd7d7eb4a0c1a)
[INFO] [stdout] 
[INFO] [stdout] running 8 tests
[INFO] [stdout] test rpc::tests::streaming_encoder_aborts_at_cap_without_serializing_the_remaining_items ... ok
[INFO] [stdout] test deferred::tests::the_running_estimate_equals_the_bytes_decode_produces ... ok
[INFO] [stdout] test error::error_code_all_tests::all_lists_each_member_once ... ok
[INFO] [stdout] test domain::common::tests::blend_mode_round_trips_and_stays_closed ... ok
[INFO] [stdout] test domain::common::tests::text_properties_round_trip_and_stay_closed ... ok
[INFO] [stdout] test deferred::tests::decodes_ceiling_check_refuses_an_oversized_field_set ... ok
[INFO] [stdout] test deferred::tests::an_object_encoding_to_exactly_the_ceiling_is_accepted ... ok
[INFO] [stdout] test deferred::tests::decode_raw_refuses_one_byte_over_the_ceiling_and_accepts_the_ceiling ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 8 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.98s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/figma-dev-mcp-tests/80ee52c402f7c461/out/figma_dev_mcp_tests-80ee52c402f7c461)
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/bin/conformance-server.rs (/opt/rustwide/target/debug/build/figma-dev-mcp-tests/82824f2177d2459e/out/conformance_server-82824f2177d2459e)
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running contracts/mod.rs (/opt/rustwide/target/debug/build/figma-dev-mcp-tests/305915d4c3b65f2f/out/contracts-305915d4c3b65f2f)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 101 tests
[INFO] [stdout] test a_hidden_node_is_refused_by_name_rather_than_reported_missing ... ok
[INFO] [stdout] test a_frame_declaring_exactly_the_envelope_ceiling_is_not_refused_for_its_size ... ok
[INFO] [stdout] test a_gradient_without_its_direction_or_opacity_is_rejected ... ok
[INFO] [stdout] test a_gradient_transform_belongs_only_to_gradients ... ok
[INFO] [stdout] test an_unsupported_effect_names_its_figma_type_and_needs_no_radius ... ok
[INFO] [stdout] test an_unsupported_paint_names_its_figma_type ... ok
[INFO] [stdout] test a_lone_surrogate_in_svg_source_is_refused_by_the_decoder ... ok
[INFO] [stdout] test an_svg_verdict_is_stated_and_matches_its_rule ... ok
[INFO] [stdout] test angular_and_diamond_gradients_are_first_class_paints ... ok
[INFO] [stdout] test an_input_asking_for_exactly_the_maximum_depth_is_accepted ... ok
[INFO] [stdout] test broker_and_rpc_enums_are_exhaustive_and_shape_locked ... ok
[INFO] [stdout] test both_ends_declare_the_same_wire_version ... ok
[INFO] [stdout] test an_unsafe_screenshot_asset_carries_its_rule_through_the_result ... ok
[INFO] [stdout] test component_property_values_round_trip_and_reject_unknown_kinds ... ok
[INFO] [stdout] test corner_radius_round_trips_and_stays_closed ... ok
[INFO] [stdout] test actual_wire_discriminators_accept_tag_last_and_reject_duplicate_or_unknown_fields ... ok
[INFO] [stdout] test error_code_all_is_the_set_the_enum_declares ... ok
[INFO] [stdout] test checked_in_wire_fixtures_round_trip_without_shape_drift ... ok
[INFO] [stdout] test error_catalog::the_generated_catalog_pairs_each_code_with_its_canonical_message ... ok
[INFO] [stdout] test error_catalog::the_plugin_error_catalog_is_generated_from_the_protocol ... ok
[INFO] [stdout] test both_detail_result_macro_arms_agree_on_every_shared_field ... ok
[INFO] [stdout] test fixed_limits_match_the_reviewed_ceiling ... ok
[INFO] [stdout] test allocation::outbound_validation_rejects_width_before_allocating_a_wide_auxiliary_stack ... ok
[INFO] [stdout] test bounded_collection_decoders_stop_at_max_plus_one_and_frame_encoding_is_stream_capped ... ok
[INFO] [stdout] test get_dev_mode_data_result_requires_visited_nodes ... ok
[INFO] [stdout] test every_svg_rejection_rule_round_trips_named_and_unnamed ... ok
[INFO] [stdout] test get_dev_mode_data_result_still_rejects_unknown_fields ... ok
[INFO] [stdout] test get_motion_result_still_rejects_unknown_fields ... ok
[INFO] [stdout] test get_reactions_result_still_rejects_unknown_fields ... ok
[INFO] [stdout] test get_dev_mode_data_result_round_trips_visited_nodes ... ok
[INFO] [stdout] test get_motion_result_round_trips_visited_nodes ... ok
[INFO] [stdout] test get_motion_result_requires_visited_nodes ... ok
[INFO] [stdout] test get_reactions_result_round_trips_visited_nodes ... ok
[INFO] [stdout] test hello_carries_explicit_session_identity_and_fresh_display_metadata ... ok
[INFO] [stdout] test layout_alignment_round_trips_and_rejects_unknown_values ... ok
[INFO] [stdout] test get_reactions_result_requires_visited_nodes ... ok
[INFO] [stdout] test gradient_and_image_paints_carry_opacity_and_direction ... ok
[INFO] [stdout] test modelled_effects_still_require_their_own_fields ... ok
[INFO] [stdout] test prompts_catalog::prompts_catalog_is_sorted_argumentless_and_publicly_cacheable ... ok
[INFO] [stdout] test public_scalar_boundaries_reject_invalid_inbound_and_outbound_values ... ok
[INFO] [stdout] test prompts_catalog::prompt_snapshot_is_stable ... ok
[INFO] [stdout] test boundary_decoders_reject_oversized_inputs_before_dispatch ... ok
[INFO] [stdout] test minimal_results_preserve_recursive_depth_and_reject_flat_summaries ... ok
[INFO] [stdout] test plugin_failures_cannot_supply_public_or_diagnostic_messages ... ok
[INFO] [stdout] test resources_catalog::reading_a_strategy_resource_returns_the_prompt_body_verbatim ... ok
[INFO] [stdout] test resources_catalog::resource_catalog_is_one_prefixed_uri_per_prompt_and_publicly_cacheable ... ok
[INFO] [stdout] test resources_catalog::uris_outside_the_strategy_prefix_do_not_resolve ... ok
[INFO] [stdout] test frontend_invocations_cannot_supply_plugin_request_ids ... ok
[INFO] [stdout] test nested_enum_fields_are_camel_case_and_input_bounds_are_schema_backed ... ok
[INFO] [stdout] test detail_results_are_discriminated_and_rich_nodes_are_schema_explicit ... ok
[INFO] [stdout] test reaction_overlay_settings_are_closed_optional_and_camel_case ... ok
[INFO] [stdout] test full_node_results_carry_populated_instance_properties_past_the_serializer ... ok
[INFO] [stdout] test response_accounting::modern_envelope_includes_result_type_and_legacy_omits_it ... ok
[INFO] [stdout] test response_accounting::rust_and_typescript_share_the_same_resource_constants ... ok
[INFO] [stdout] test outbound_node_collections_reject_wide_roots_and_children_without_auxiliary_growth ... ok
[INFO] [stdout] test amended_motion_contract_uses_seconds_keyed_maps_and_distinct_style_types ... ok
[INFO] [stdout] test response_accounting::utf8_and_json_escaping_count_serialized_bytes ... ok
[INFO] [stdout] test rpc_frames_are_length_prefixed_and_reject_oversize_before_body_read ... ok
[INFO] [stdout] test schemas_and_decoders_agree_on_utf8_byte_limits ... ok
[INFO] [stdout] test response_accounting::svg_source_is_counted_in_structured_text_and_image ... ok
[INFO] [stdout] test screenshot_schema_and_decoder_exclude_raster_scale_from_svg ... ok
[INFO] [stdout] test search_node_types_are_count_and_utf8_byte_bounded ... ok
[INFO] [stdout] test selector_schema_is_one_of_and_selection_is_literal_true ... ok
[INFO] [stdout] test allocation::actual_frame_decoder_stops_large_node_id_input_without_content_expansion ... ok
[INFO] [stdout] test stable_error_codes_are_exact_and_screaming_snake_case ... ok
[INFO] [stdout] test stroke_value_round_trips_and_rejects_unknown_fields ... ok
[INFO] [stdout] test nested_returned_value_collections_reject_more_than_the_result_limit ... ok
[INFO] [stdout] test a_wire_snapshot_change_must_be_a_deliberate_version_decision ... ok
[INFO] [stdout] test response_accounting::per_item_ceilings_reject_complete_assets_without_slicing ... ok
[INFO] [stdout] test svg_rejection_rules_stay_closed ... ok
[INFO] [stdout] test text_style_units_round_trip_and_reject_unknown_units ... ok
[INFO] [stdout] test structured_outputs::motion_success_fixture_uses_seconds_and_keyed_field_maps ... ok
[INFO] [stdout] test style_reference_carries_optional_name_and_stroke_kind ... ok
[INFO] [stdout] test read_operation_and_result_tags_are_closed_and_exact ... ok
[INFO] [stdout] test the_forest_results_decode_an_unresolved_entry_and_keep_it ... ok
[INFO] [stdout] test the_empty_bounds_code_round_trips_and_owns_its_message ... ok
[INFO] [stdout] test the_tool_error_no_longer_carries_an_svg_rule ... ok
[INFO] [stdout] test the_plugin_mirrors_every_svg_rejection_rule ... ok
[INFO] [stdout] test the_outbound_node_builder_accepts_a_tree_at_exactly_the_depth_ceiling ... ok
[INFO] [stdout] test screenshot_assets_enforce_wire_byte_and_raster_dimension_limits ... ok
[INFO] [stdout] test allocation::actual_plugin_result_decoder_bounds_wide_and_deep_tag_last_payloads ... ok
[INFO] [stdout] test tools_catalog::structured_image_content_preserves_the_compatibility_text_block ... ok
[INFO] [stdout] test structured_outputs::every_stable_tool_error_matches_the_tool_error_schema ... ok
[INFO] [stdout] test unknown_fields_are_rejected_at_nested_boundaries ... ok
[INFO] [stdout] test visited_nodes_reaches_every_per_node_output_schema ... ok
[INFO] [stdout] test wire_message_tags_are_closed_camel_case_and_exact ... ok
[INFO] [stdout] test tools_catalog::screenshot_schema_accepts_each_format_without_top_level_property_blocking ... ok
[INFO] [stdout] test tools_catalog::dropping_rust_widths_spares_formats_and_fields_that_carry_meaning ... ok
[INFO] [stdout] test tools_catalog::tools_catalog_is_complete_sorted_read_only_and_cacheable ... ok
[INFO] [stdout] test tools_catalog::schemas_carry_no_format_outside_a_validator_vocabulary ... ok
[INFO] [stdout] test tools_catalog::schema_snapshots_are_stable ... ok
[INFO] [stdout] test response_accounting::eight_mib_ceiling_counts_structured_and_compatibility_text ... ok
[INFO] [stdout] test structured_outputs::every_tool_has_a_success_fixture_that_matches_its_output_schema ... ok
[INFO] [stdout] test the_wire_read_path_takes_a_ceiling_frame_and_consumes_exactly_it ... ok
[INFO] [stdout] test response_accounting::batch_images_preserve_successes_and_never_slice_on_overflow ... ok
[INFO] [stdout] test every_read_result_family_rejects_an_oversized_top_level_collection ... ok
[INFO] [stdout] test response_accounting::plugin_and_rpc_frames_use_the_same_24_mib_check ... ok
[INFO] [stdout] test response_accounting::twenty_four_mib_envelope_uses_the_real_jsonrpc_wrapper ... ok
[INFO] [stdout] test response_accounting::a_raster_asset_at_exactly_the_per_item_ceiling_is_returned_whole ... ok
[INFO] [stdout] test response_accounting::max_svg_source_survives_preview_base64_and_is_charged_once ... ok
[INFO] [stdout] test recursive_results_enforce_depth_and_global_returned_node_budgets ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 101 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 9.60s
[INFO] [stdout] 
[INFO] [stderr]      Running integration/mod.rs (/opt/rustwide/target/debug/build/figma-dev-mcp-tests/1addf38cae5a0281/out/integration-1addf38cae5a0281)
[INFO] [stdout] 
[INFO] [stdout] running 111 tests
[INFO] [stdout] test conformance_adapter::conformance_scripts_pin_the_two_lifecycle_scenarios_not_the_full_suite ... ok
[INFO] [stdout] test conformance_adapter::production_crate_does_not_enable_http_transport_features ... ok
[INFO] [stdout] test election::concurrent_elections_produce_exactly_one_leader ... ok
[INFO] [stdout] test conformance_adapter::tests_crate_isolates_http_features_and_the_adapter_binary ... ok
[INFO] [stdout] test failover::a_call_made_before_the_first_election_succeeds_once_a_backend_arrives ... ok
[INFO] [stdout] test election::plugin_bind_failure_releases_the_frontend_listener ... ok
[INFO] [stdout] test design_system::public_contracts_default_style_source_and_alias_resolution ... ok
[INFO] [stdout] test election::one_process_owns_both_listeners_and_later_processes_follow ... ok
[INFO] [stdout] test failover::a_detached_client_fails_calls_after_the_deadline ... ok
[INFO] [stdout] test design_system::styles_and_variables_round_trip_through_server_broker_and_plugin ... ok
[INFO] [stdout] test dev_mode::dev_mode_reactions_and_motion_round_trip_through_server_broker_and_plugin ... ok
[INFO] [stdout] test failover::a_leading_supervisor_binds_both_ports ... ok
[INFO] [stdout] test components_fonts::components_and_fonts_round_trip_through_server_broker_and_plugin ... ok
[INFO] [stdout] test failover::a_remote_client_has_no_local_broker ... ok
[INFO] [stdout] test failover::a_leader_shuts_down_while_its_ipv6_retry_is_still_waiting ... ok
[INFO] [stdout] test all_tools::cancellation_and_disconnect_use_stable_tool_errors ... ok
[INFO] [stdout] test all_tools::inactivity_timeout_uses_the_stable_timeout_error ... ok
[INFO] [stdout] test failover::a_supervisor_built_unattached_elects_inside_supervise ... ok
[INFO] [stdout] test failover::an_unattached_client_still_fails_calls_retryably_after_the_deadline ... ok
[INFO] [stdout] test all_tools::queue_saturation_returns_retryable_limit_exceeded ... ok
[INFO] [stdout] test failover::cancelling_an_open_call_against_an_unattached_client_returns_promptly ... ok
[INFO] [stdout] test failover::dropping_an_open_call_aborts_its_watcher_unless_already_cancelled ... ok
[INFO] [stdout] test failover::cancelling_a_call_against_an_unattached_client_returns_promptly ... ok
[INFO] [stdout] test broker_restart::leader_restart_fails_inflight_work_and_accepts_a_fresh_session ... ok
[INFO] [stdout] test failover::a_follower_promotes_itself_when_the_leader_dies ... ok
[INFO] [stdout] test failover::a_leader_keeps_trying_the_ipv6_plugin_port_until_it_is_free ... ok
[INFO] [stdout] test failover::an_open_call_records_the_broker_that_opened_it ... ok
[INFO] [stdout] test failover::a_swapped_backend_does_not_steal_a_calls_cancellation ... ok
[INFO] [stdout] test failover::local_broker_resolves_through_the_swappable_cell ... ok
[INFO] [stdout] test failover::waiting_for_a_backend_does_not_outlast_the_deadline ... ok
[INFO] [stdout] test idle_lifetime::idle_shutdown_atomically_rejects_new_activity_after_grace ... ok
[INFO] [stdout] test idle_lifetime::activity_during_idle_grace_restarts_the_full_timer ... ok
[INFO] [stdout] test idle_lifetime::idle_shutdown_waits_until_all_frontend_leases_are_gone_for_the_full_grace ... ok
[INFO] [stdout] test failover::the_first_supervisor_leads_and_the_second_follows ... ok
[INFO] [stdout] test multi_client::broker_shutdown_interrupts_a_frontend_stalled_before_hello ... ok
[INFO] [stdout] test failover::frontend_client_closed_resolves_when_the_leader_goes_away ... ok
[INFO] [stdout] test all_tools::screenshot_partial_batch_keeps_successes_and_item_errors ... ok
[INFO] [stdout] test failover::a_leader_whose_broker_dies_re_elects_and_rebinds ... ok
[INFO] [stdout] test multi_client::frontend_handshake_rejects_protocol_mismatch_before_registering_lease ... ok
[INFO] [stdout] test multi_client::frontend_handshake_times_out_when_the_port_is_not_a_compatible_leader ... ok
[INFO] [stdout] test metadata::get_metadata_round_trips_through_server_broker_and_plugin ... ok
[INFO] [stdout] test failover::frontend_client_open_drop_governs_the_remote_watcher_on_both_branches ... ok
[INFO] [stdout] test idle_lifetime::a_live_plugin_blocks_idle_shutdown_and_disconnect_starts_a_new_grace ... ok
[INFO] [stdout] test multi_client::cancelling_a_frontend_call_cancels_the_matching_plugin_request ... ok
[INFO] [stdout] test multi_client::two_frontends_share_one_leader_registry ... ok
[INFO] [stderr] 2026-10-06T14:19:13.233904Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174001 request_id=b33e8f85-8dad-4da4-a345-98248fb0c483 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stdout] test multi_client::remote_broker_client_can_back_an_mcp_frontend ... ok
[INFO] [stderr] 2026-10-06T14:19:13.234298Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.235599Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=3ff841da-0e20-44cc-a878-6c7627117cdf in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.248555Z  INFO figma_dev_mcp_broker::supervisor: running the first broker election
[INFO] [stderr] 2026-10-06T14:19:13.248618Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=1
[INFO] [stderr] 2026-10-06T14:19:13.249076Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: WebSocket protocol error: Handshake not finished
[INFO] [stderr] 2026-10-06T14:19:13.249389Z  INFO figma_dev_mcp_broker::supervisor: entered broker role role="follower" leader_address="127.0.0.1:34715"
[INFO] [stderr] 2026-10-06T14:19:13.249837Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.250177Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stdout] test failover::exactly_one_of_two_orphans_takes_the_ports ... ok
[INFO] [stderr] 2026-10-06T14:19:13.250537Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.250830Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.251233Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.253044Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.253250Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=7f315af5-4e89-4181-aa73-e1cff66cb584 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.253428Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.253637Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.253791Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.258060Z DEBUG figma_dev_mcp_broker::rpc: frontend RPC connection closed error=duplicate active frontend RPC request
[INFO] [stdout] test multi_client::cancellation_is_scoped_to_one_concurrent_frontend_call ... ok
[INFO] [stderr] 2026-10-06T14:19:13.266744Z DEBUG figma_dev_mcp_broker::rpc: frontend RPC connection closed error=invalid JSON protocol frame: unknown variant `invalid`, expected `request` or `cancel` at line 1 column 17
[INFO] [stdout] test multi_client::duplicate_active_rpc_id_closes_frontend_and_cancels_owned_call ... ok
[INFO] [stderr] 2026-10-06T14:19:13.267040Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stdout] test multi_client::concurrent_frontend_calls_are_correlated_when_responses_arrive_out_of_order ... ok
[INFO] [stderr] 2026-10-06T14:19:13.267422Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.267627Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.267779Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.269202Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stdout] test prompts::prompts_list_is_sorted_argumentless_and_publicly_cacheable ... ok
[INFO] [stderr] 2026-10-06T14:19:13.270440Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: WebSocket protocol error: Connection reset without closing handshake
[INFO] [stderr] 2026-10-06T14:19:13.270814Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=19 item_count=1 text_bytes=854 envelope_bytes=1016 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.271634Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.271928Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.272262Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.272266Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.272601Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.272760Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.272765Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.273266Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stdout] test observability::tool_logs_are_schema_safe_and_stdout_stays_protocol_only ... ok
[INFO] [stderr] 2026-10-06T14:19:13.273753Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stdout] test multi_client::malformed_frame_cancels_an_active_frontend_call_before_closing ... ok
[INFO] [stderr] 2026-10-06T14:19:13.273792Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=48489d96-8c9a-40dd-99a5-b92080b1d4fa in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.275137Z  INFO serve_inner: rmcp::service: received notification notification=ProgressNotification(Notification { method: ProgressNotificationMethod, params: ProgressNotificationParam { progress_token: ProgressToken(Number(0)), progress: 3.0, total: Some(9.0), message: Some("reading"), meta: None }, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.277198Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.274293Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.277498Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.277674Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.277795Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.277827Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.278037Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.278067Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.278179Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.278333Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.278362Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=ebb3584c-69fb-404d-95ad-84b3d18c1220 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.278352Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.278432Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=6e2c99f5-1ed6-41fa-ac1e-2bd0fd9f5a38 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.280421Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=3 tool_name="get_selection" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=1 text_bytes=504 envelope_bytes=652 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.278723Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.284580Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.285254Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.285599Z  WARN serve_inner: rmcp::service: response error id=4 error=ErrorData { code: ErrorCode(-32602), message: "prompt 'design_strategy' not found", data: None }
[INFO] [stderr] 2026-10-06T14:19:13.286393Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.280397Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=2 tool_name="get_nodes" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=39 item_count=2 text_bytes=796 envelope_bytes=966 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.286516Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=9fc13d8e-e808-434e-8f4c-0683feed4ed8 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.286632Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.286715Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=f6fadb4b-ac88-4b95-bb85-d32240165f49 in_flight=2 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.286827Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.286904Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=cffc235b-5511-49e1-824b-ccb160175cf4 in_flight=3 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.286995Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.287067Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=a7baddc6-52d0-4b7d-b419-620f71c069b1 in_flight=4 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.287186Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.287262Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=96761ac3-f565-4934-9d13-6da1f6f1f2dc in_flight=4 queue_depth=1 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.287395Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.287481Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=e6340488-d5d7-44ec-bf80-0768ad82371b in_flight=4 queue_depth=2 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.287588Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.287605Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=plugin socket closed
[INFO] [stderr] 2026-10-06T14:19:13.287606Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.287687Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=263260e8-9288-4b23-b4c9-af70b60fd121 in_flight=4 queue_depth=3 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.287801Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=2a439c19-aabc-42e0-9879-b5c623746d91 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.287802Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.287798Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=9 item_count=1 text_bytes=172 envelope_bytes=291 error_code="CONNECTION_LOST"
[INFO] [stderr] 2026-10-06T14:19:13.287887Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=3caf3d9a-dfd6-4653-8bb4-61efc3895a5f in_flight=4 queue_depth=4 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.287907Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.288010Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=690e5884-c528-4ab9-9b97-6af19d62a9ca in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.288016Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.288096Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=b64aafe5-6e6d-4335-87a3-00de08eb09ae in_flight=4 queue_depth=5 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.288189Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=5a08cfd4-6d1d-4a10-be16-723bc15a386d in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.288212Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.288795Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=0 item_count=1 text_bytes=144 envelope_bytes=263 error_code="TIMEOUT"
[INFO] [stderr] 2026-10-06T14:19:13.289024Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=3 item_count=1 text_bytes=144 envelope_bytes=263 error_code="TIMEOUT"
[INFO] [stderr] 2026-10-06T14:19:13.289638Z DEBUG figma_dev_mcp_broker::ws: discarding late or foreign response socket_id=788b742e-7ff4-425a-baf8-91bbef745bf9 request_id=690e5884-c528-4ab9-9b97-6af19d62a9ca
[INFO] [stdout] test prompts::prompts_get_returns_one_user_text_message_and_rejects_unknown_names ... ok
[INFO] [stdout] test multi_client::leader_routes_explicit_calls_and_rejects_ambiguous_omissions ... ok
[INFO] [stdout] test multi_client::leader_loss_fails_in_flight_call_without_replay ... ok
[INFO] [stderr] 2026-10-06T14:19:13.300180Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.300368Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=2d5f55dc-fc03-490c-befd-c7264279ac14 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.301562Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=2 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.290047Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.302599Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=349a8c04-e790-42b2-9bb4-d85796e2a780 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.303417Z DEBUG figma_dev_mcp_broker::ws: discarding late or foreign response socket_id=81a99ccc-fc2c-4d2c-9750-651390111109 request_id=6e2c99f5-1ed6-41fa-ac1e-2bd0fd9f5a38
[INFO] [stderr] 2026-10-06T14:19:13.304262Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=2 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.288290Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=a716b6b1-aa1a-4a92-8ac8-09b90ad23364 in_flight=4 queue_depth=6 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stdout] test resource_control::disconnect_fails_inflight_and_late_responses_are_discarded ... ok
[INFO] [stderr] 2026-10-06T14:19:13.308536Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.291348Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.309352Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=9795a489-3092-4087-8ed1-e048a3153c90 in_flight=4 queue_depth=7 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.310484Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.310655Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=0f845765-3d3a-4671-b779-2556f5c57c30 in_flight=4 queue_depth=8 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.310836Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stdout] test resource_control::late_response_after_timeout_is_discarded ... ok
[INFO] [stderr] 2026-10-06T14:19:13.293080Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.312458Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=49ca65f3-6cbf-4e58-942f-d71e91410337 in_flight=4 queue_depth=9 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.312665Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.312750Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=692698e6-1f6e-4bb9-878f-9a8b3f246068 in_flight=4 queue_depth=10 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.312854Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.312932Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=7f567c3e-3eeb-4570-a83b-014267bf3ede in_flight=4 queue_depth=11 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.312925Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.313116Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.313202Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=953980e9-3e32-4b56-a61d-52c1af6656e2 in_flight=4 queue_depth=12 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.297967Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174001" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.313340Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.313366Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.313415Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174001 request_id=2c6e0dc4-a0a9-4237-b5a4-2fed54a0401d in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.313424Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=2aa38ab5-cc9d-4f87-b590-d259aed7e42e in_flight=4 queue_depth=13 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.313526Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.313596Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.313603Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=a325e108-7801-4882-8a8f-d13b846f76aa in_flight=4 queue_depth=14 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.313758Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.313841Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=6e1482a6-19d1-4aa4-8223-83c9dac85300 in_flight=4 queue_depth=15 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.313936Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.314009Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=37ca8366-afbd-468e-b725-038c9d94ef49 in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.314116Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.314324Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=21 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=0 item_count=1 text_bytes=186 envelope_bytes=306 error_code="LIMIT_EXCEEDED"
[INFO] [stderr] 2026-10-06T14:19:13.314498Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174001" duration_ms=0 item_count=2 text_bytes=808 envelope_bytes=978 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.314684Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.314782Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=0c8ddfe7-0ed4-412a-9745-3f3b4a61ad9d in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.314896Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.314960Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.314981Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=34ecbded-5018-4c98-be8a-8d6853008492 in_flight=2 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.315091Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.315178Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=1ac296d7-6876-4468-9c2e-e4882f1c551b in_flight=3 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.315247Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.309794Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.315440Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.315821Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=1
[INFO] [stderr] 2026-10-06T14:19:13.315972Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.316248Z  INFO figma_dev_mcp_broker::supervisor: entered broker role role="leader" plugin_address="127.0.0.1:35787" plugin_address_v6="[::1]:35787" frontend_address="127.0.0.1:40349"
[INFO] [stderr] 2026-10-06T14:19:13.316342Z  INFO figma_dev_mcp_broker::supervisor: installed a new broker backend role="leader"
[INFO] [stderr] 2026-10-06T14:19:13.316727Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.316874Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.317253Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=42 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.317929Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.309852Z  INFO figma_dev_mcp_broker::supervisor: running the first broker election
[INFO] [stderr] 2026-10-06T14:19:13.318426Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=1
[INFO] [stderr] 2026-10-06T14:19:13.318287Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=bcfdaf8f-77f8-4a1e-ac15-5d5850d68fdd in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.319139Z  INFO figma_dev_mcp_broker::supervisor: entered broker role role="leader" plugin_address="127.0.0.1:37975" plugin_address_v6="[::1]:37975" frontend_address="127.0.0.1:36947"
[INFO] [stdout] test resource_control::bounded_progress_is_forwarded_without_design_content ... ok
[INFO] [stderr] 2026-10-06T14:19:13.319205Z  INFO figma_dev_mcp_broker::supervisor: running the first broker election
[INFO] [stderr] 2026-10-06T14:19:13.319219Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=1
[INFO] [stderr] 2026-10-06T14:19:13.320778Z  INFO serve_inner: rmcp::service: received notification notification=CancelledNotification(Notification { method: CancelledNotificationMethod, params: CancelledNotificationParam { request_id: Some(Number(1)), reason: Some("user cancelled"), meta: None }, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.321145Z  INFO serve_inner: rmcp::service: cancelled id=1 reason="user cancelled"
[INFO] [stderr] 2026-10-06T14:19:13.321702Z  INFO serve_inner: rmcp::service: cancelled id=1 reason="user cancelled"
[INFO] [stderr] 2026-10-06T14:19:13.321993Z  INFO figma_dev_mcp_broker::supervisor: entered broker role role="follower" leader_address="127.0.0.1:36947"
[INFO] [stderr] 2026-10-06T14:19:13.322221Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=3 item_count=1 text_bytes=158 envelope_bytes=277 error_code="CANCELLED"
[INFO] [stderr] 2026-10-06T14:19:13.315290Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.323100Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=697ac09a-9b1b-4562-939f-c0bf90612101 in_flight=4 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.293571Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.325685Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.325981Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.326042Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.326147Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.326283Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=558a9aae-8158-416b-b0bb-04fe3e91f6fb in_flight=4 queue_depth=1 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.326519Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.326540Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.326836Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=36890dae-f503-44fe-b5b3-4db612ae410b in_flight=4 queue_depth=2 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.326794Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=0f41dac2-07cd-4cd7-88f3-0da0e6fabb5b in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.327104Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stdout] test resource_control::inactivity_deadline_is_fifteen_seconds_without_progress ... ok
[INFO] [stderr] 2026-10-06T14:19:13.327129Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="-" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.330378Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=2
[INFO] [stderr] 2026-10-06T14:19:13.330602Z  WARN figma_dev_mcp_broker::supervisor: broker election failed error=failed to bind the plugin WebSocket listener: Address already in use (os error 98) attempt=2
[INFO] [stderr] 2026-10-06T14:19:13.330650Z  WARN figma_dev_mcp_broker::supervisor: retrying election attempt=2 delay_ms=200
[INFO] [stderr] 2026-10-06T14:19:13.330732Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="list_files" connection_id="-" duration_ms=0 item_count=1 text_bytes=942 envelope_bytes=1112 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.327843Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.330867Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.335864Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.336663Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.336763Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.337035Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.337216Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.337392Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.337787Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="-" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.328799Z  INFO serve_inner: rmcp::service: received notification notification=ProgressNotification(Notification { method: ProgressNotificationMethod, params: ProgressNotificationParam { progress_token: ProgressToken(Number(0)), progress: 1.0, total: Some(12.0), message: Some("reading"), meta: None }, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.343884Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="list_files" connection_id="-" duration_ms=0 item_count=1 text_bytes=946 envelope_bytes=1116 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.347091Z  INFO serve_inner: rmcp::service: task cancelled
[INFO] [stderr] 2026-10-06T14:19:13.330114Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: WebSocket protocol error: Connection reset without closing handshake
[INFO] [stderr] 2026-10-06T14:19:13.330935Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=079980e7-4a2e-469c-8f56-98f905dd10e0 in_flight=4 queue_depth=3 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.347616Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=3 tool_name="get_design_context" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=59 item_count=1 text_bytes=504 envelope_bytes=652 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.348061Z  INFO serve_inner: rmcp::service: received notification notification=CancelledNotification(Notification { method: CancelledNotificationMethod, params: CancelledNotificationParam { request_id: Some(Number(7)), reason: Some("drop queued work"), meta: None }, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.348132Z  INFO serve_inner: rmcp::service: cancelled id=7 reason="drop queued work"
[INFO] [stderr] 2026-10-06T14:19:13.348253Z  INFO serve_inner: rmcp::service: cancelled id=7 reason="drop queued work"
[INFO] [stderr] 2026-10-06T14:19:13.339876Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.336250Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.348484Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=7 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=17 item_count=1 text_bytes=158 envelope_bytes=277 error_code="CANCELLED"
[INFO] [stderr] 2026-10-06T14:19:13.349093Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.349460Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.349589Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=34 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.349607Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.349982Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174002" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.350102Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174002 request_id=5e07dad8-371d-4f92-ab55-9b9afbadd400 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.345622Z  INFO serve_inner: rmcp::service: received notification notification=ProgressNotification(Notification { method: ProgressNotificationMethod, params: ProgressNotificationParam { progress_token: ProgressToken(Number(0)), progress: 2.0, total: Some(12.0), message: Some("reading"), meta: None }, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.350782Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stdout] test navigation::navigation_tools_round_trip_through_server_broker_and_plugin ... ok
[INFO] [stderr] 2026-10-06T14:19:13.351131Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.351251Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.347848Z  INFO serve_inner: rmcp::service: serve finished quit_reason=Cancelled
[INFO] [stderr] 2026-10-06T14:19:13.351500Z  INFO serve_inner: rmcp::service: input stream terminated
[INFO] [stderr] 2026-10-06T14:19:13.351549Z  INFO serve_inner: rmcp::service: serve finished quit_reason=Closed
[INFO] [stderr] 2026-10-06T14:19:13.351640Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174001" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.351740Z  WARN serve_inner: rmcp::service: response error id=1 error=ErrorData { code: ErrorCode(-32602), message: "SVG input cannot contain scale", data: None }
[INFO] [stderr] 2026-10-06T14:19:13.352063Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="-" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.352245Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=25 item_count=1 text_bytes=144 envelope_bytes=263 error_code="TIMEOUT"
[INFO] [stderr] 2026-10-06T14:19:13.353058Z DEBUG figma_dev_mcp_broker::ws: discarding late or foreign progress socket_id=9887afe9-1ec9-4023-a03b-1fe2b26b25b0 request_id=0f41dac2-07cd-4cd7-88f3-0da0e6fabb5b
[INFO] [stderr] 2026-10-06T14:19:13.331760Z  INFO serve_inner: rmcp::service: task cancelled
[INFO] [stderr] 2026-10-06T14:19:13.353953Z  INFO serve_inner: rmcp::service: serve finished quit_reason=Cancelled
[INFO] [stdout] test resource_control::progress_resets_inactivity_but_not_the_total_deadline ... ok
[INFO] [stderr] 2026-10-06T14:19:13.354101Z  INFO serve_inner: rmcp::service: input stream terminated
[INFO] [stdout] test screenshot::screenshot_rejects_svg_scale_before_dispatch ... ok
[INFO] [stderr] 2026-10-06T14:19:13.354142Z  INFO serve_inner: rmcp::service: serve finished quit_reason=Closed
[INFO] [stderr] 2026-10-06T14:19:13.355370Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="list_files" connection_id="-" duration_ms=1 item_count=1 text_bytes=946 envelope_bytes=1116 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.358193Z  INFO serve_inner: rmcp::service: task cancelled
[INFO] [stdout] test runtime::the_service_is_up_before_any_election_has_happened ... ok
[INFO] [stdout] test search::search_nodes_public_contract_trims_defaults_and_rejects_invalid_values ... ok
[INFO] [stderr] 2026-10-06T14:19:13.361401Z  INFO serve_inner: rmcp::service: serve finished quit_reason=Cancelled
[INFO] [stderr] 2026-10-06T14:19:13.355518Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.362017Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stdout] test resource_control::mcp_cancellation_reaches_the_plugin ... ok
[INFO] [stderr] 2026-10-06T14:19:13.362494Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.362973Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.363214Z  INFO serve_inner: rmcp::service: input stream terminated
[INFO] [stderr] 2026-10-06T14:19:13.363335Z  INFO serve_inner: rmcp::service: serve finished quit_reason=Closed
[INFO] [stderr] 2026-10-06T14:19:13.363842Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.370484Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=6a62d832-a87f-44b5-ba15-08f3f80958f4 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.364283Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: IO error: Connection reset by peer (os error 104)
[INFO] [stderr] 2026-10-06T14:19:13.364904Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.372215Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.372616Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.374467Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.375215Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.375586Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=3b01e8b1-29f0-461b-9d63-f1e05814be5e in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stdout] test conformance_adapter::adapter_catalog_is_the_production_tools_prompts_and_resources_surface ... ok
[INFO] [stdout] test runtime::leader_stdio_closure_keeps_shared_runtime_alive_for_follower_and_plugin ... ok
[INFO] [stderr] 2026-10-06T14:19:13.383810Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: WebSocket protocol error: Connection reset without closing handshake
[INFO] [stderr] 2026-10-06T14:19:13.384222Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.384719Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.385075Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.385255Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.385941Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.386270Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.386566Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.388546Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.389128Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="-" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.392471Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=5 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=66 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.392539Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="search_nodes" connection_id="-" duration_ms=0 item_count=1 text_bytes=226 envelope_bytes=345 error_code="AMBIGUOUS_CONNECTION"
[INFO] [stderr] 2026-10-06T14:19:13.390051Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.397981Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.398274Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.393773Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.394300Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="get_screenshot" connection_id="123e4567-e89b-42d3-a456-426614174002" duration_ms=44 item_count=1 text_bytes=488 envelope_bytes=781 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.394740Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.398833Z  WARN serve_inner: rmcp::service: response error id=1 error=ErrorData { code: ErrorCode(-32602), message: "missing field `scope`", data: None }
[INFO] [stderr] 2026-10-06T14:19:13.398881Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=9169e7a1-9b61-43c2-ab5a-075db3a92502 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.399772Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.399862Z  WARN serve_inner: rmcp::service: response error id=2 error=ErrorData { code: ErrorCode(-32602), message: "search scope must contain exactly one field", data: None }
[INFO] [stderr] 2026-10-06T14:19:13.399906Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174002" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.400019Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174002 request_id=91077e71-285b-4d19-a812-c6c2542cb4e4 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.400232Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=4 tool_name="get_nodes" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=2 text_bytes=796 envelope_bytes=966 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.398614Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.400526Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.400602Z  WARN serve_inner: rmcp::service: response error id=3 error=ErrorData { code: ErrorCode(-32602), message: "unknown field `pageIds`, expected `pageId` or `nodeId`", data: None }
[INFO] [stderr] 2026-10-06T14:19:13.400693Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="-" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.400796Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174001 request_id=d2cab935-7df7-43dc-bc92-58100acc4a31 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.401049Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: WebSocket protocol error: Connection reset without closing handshake
[INFO] [stderr] 2026-10-06T14:19:13.401287Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.401387Z  WARN serve_inner: rmcp::service: response error id=4 error=ErrorData { code: ErrorCode(-32602), message: "unknown field `document`, expected `pageId` or `nodeId`", data: None }
[INFO] [stderr] 2026-10-06T14:19:13.401701Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=1
[INFO] [stderr] 2026-10-06T14:19:13.402073Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=2 tool_name="get_screenshot" connection_id="123e4567-e89b-42d3-a456-426614174002" duration_ms=2 item_count=1 text_bytes=1041 envelope_bytes=2523 error_code="OK"
[INFO] [stdout] test search::search_nodes_without_connection_id_rejects_ambiguous_sessions ... ok
[INFO] [stdout] test session_registry::duplicate_connection_id_is_rejected_but_duplicate_file_name_is_allowed ... ok
[INFO] [stderr] 2026-10-06T14:19:13.404233Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=6 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=77 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.402665Z  INFO figma_dev_mcp_broker::supervisor: entered broker role role="follower" leader_address="127.0.0.1:39787"
[INFO] [stderr] 2026-10-06T14:19:13.404331Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="get_screenshot" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=33 item_count=1 text_bytes=486 envelope_bytes=778 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.407237Z  INFO figma_dev_mcp_broker::supervisor: installed a new broker backend role="follower"
[INFO] [stderr] 2026-10-06T14:19:13.402183Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.405957Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: WebSocket protocol error: Connection reset without closing handshake
[INFO] [stderr] 2026-10-06T14:19:13.408102Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="search_nodes" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=32 item_count=1 text_bytes=402 envelope_bytes=544 error_code="OK"
[INFO] [stdout] test all_tools::two_clients_and_two_files_are_explicitly_routed ... ok
[INFO] [stdout] test screenshot::jpeg_scale_and_the_three_svg_options_reach_the_plugin_intact ... ok
[INFO] [stderr] 2026-10-06T14:19:13.408335Z  WARN figma_dev_mcp_broker::supervisor: broker backend died, re-electing cause=the leader's RPC connection closed
[INFO] [stdout] test search::a_search_carrying_only_node_types_is_dispatched_with_those_types ... ok
[INFO] [stderr] 2026-10-06T14:19:13.410595Z  INFO figma_dev_mcp_broker::supervisor: detached the broker backend
[INFO] [stderr] 2026-10-06T14:19:13.410821Z  WARN figma_dev_mcp_broker::supervisor: the previous role died immediately, delaying re-election alive_ms=3 floor_ms=396 consecutive=3
[INFO] [stdout] test session_registry::production_config_is_fixed_loopback_and_test_limits_cannot_raise_ceilings ... ok
[INFO] [stdout] test session_registry::pending_completion_is_bound_to_socket_identity ... ok
[INFO] [stdout] test session_registry::outbound_queue_reports_full_without_waiting ... ok
[INFO] [stderr] 2026-10-06T14:19:13.411191Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=124 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.410409Z  WARN serve_inner: rmcp::service: response error id=5 error=ErrorData { code: ErrorCode(-32602), message: "unknown field `pageIds`, expected `pageId` or `nodeId`", data: None }
[INFO] [stderr] 2026-10-06T14:19:13.412362Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.412681Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.412768Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=2 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=125 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.412387Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.412869Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.412888Z  WARN serve_inner: rmcp::service: response error id=6 error=ErrorData { code: ErrorCode(-32602), message: "invalid type: map, expected query text with at most 1024 UTF-8 bytes", data: None }
[INFO] [stderr] 2026-10-06T14:19:13.412991Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.413630Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.413730Z  WARN serve_inner: rmcp::service: response error id=7 error=ErrorData { code: ErrorCode(-32602), message: "query must be non-empty after trimming", data: None }
[INFO] [stdout] test session_registry::stale_sessions_expire_and_socket_removal_is_idempotent ... ok
[INFO] [stderr] 2026-10-06T14:19:13.414867Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.415025Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.415125Z  WARN serve_inner: rmcp::service: response error id=8 error=ErrorData { code: ErrorCode(-32602), message: "nodeTypes must be non-empty after trimming", data: None }
[INFO] [stderr] 2026-10-06T14:19:13.415122Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.415326Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:19:13.415451Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.415862Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="-" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.415971Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=653fdfe9-7d96-4ab2-95b8-896cdaf54bbf in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.414101Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=3 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=127 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.416582Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=4 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=129 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.416940Z  INFO serve_inner: rmcp::service: Service initialized as server peer_info=Some(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-11-25"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None }, client_info: Implementation { name: "rmcp", title: None, version: "3.0.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] 2026-10-06T14:19:13.417182Z  INFO serve_inner: rmcp::service: Service initialized as client peer_info=Some(ServerPeerInfo { protocol_version: ProtocolVersion("2025-11-25"), capabilities: ServerCapabilities { experimental: None, extensions: None, logging: None, completions: None, prompts: Some(PromptsCapability { list_changed: None }), resources: Some(ResourcesCapability { subscribe: None, list_changed: None }), tools: Some(ToolsCapability { list_changed: None }) }, server_info: Some(Implementation { name: "figma-dev-mcp", title: None, version: "0.1.0", description: None, icons: None, website_url: None }), instructions: Some("Read-only Figma Dev Mode inspection through a local plugin connection."), meta: None })
[INFO] [stderr] 2026-10-06T14:19:13.417379Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stdout] test session_registry::selection_requires_exactly_one_live_session_and_never_falls_back ... ok
[INFO] [stdout] test strategy_resources::resources_list_is_the_three_strategies_and_is_publicly_cacheable ... ok
[INFO] [stdout] test session_registry::pending_timeout_socket_close_and_shutdown_remove_each_entry_once ... ok
[INFO] [stderr] 2026-10-06T14:19:13.417517Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:19:13.417868Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=plugin protocol version mismatch
[INFO] [stderr] 2026-10-06T14:19:13.418278Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.418756Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=ccf2d108-657a-4ad5-8241-c90dce14e8aa in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stdout] test resource_control::four_active_and_sixteen_queued_then_retryable_overflow ... ok
[INFO] [stderr] 2026-10-06T14:19:13.420504Z  WARN serve_inner: rmcp::service: response error id=1 error=ErrorData { code: ErrorCode(-32602), message: "resource 'figma://strategy/design_strategy' not found", data: None }
[INFO] [stderr] 2026-10-06T14:19:13.421279Z  WARN serve_inner: rmcp::service: response error id=2 error=ErrorData { code: ErrorCode(-32602), message: "resource 'figma://tool/get_metadata' not found", data: None }
[INFO] [stderr] 2026-10-06T14:19:13.421709Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174031 request_id=ping-request in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.422438Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174034 request_id=error-request in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.426237Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: WebSocket protocol error: Connection reset without closing handshake
[INFO] [stdout] test ws_origin::a_plugin_error_frame_completes_the_call_with_the_code_the_plugin_sent ... ok
[INFO] [stderr] 2026-10-06T14:19:13.426552Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=9 tool_name="search_nodes" connection_id="-" duration_ms=10 item_count=1 text_bytes=402 envelope_bytes=544 error_code="OK"
[INFO] [stdout] test search::search_nodes_without_connection_id_round_trips_and_rejects_invalid_scopes_before_dispatch ... ok
[INFO] [stderr] 2026-10-06T14:19:13.430969Z  WARN serve_inner: rmcp::service: response error id=3 error=ErrorData { code: ErrorCode(-32602), message: "resource 'file:///etc/passwd' not found", data: None }
[INFO] [stdout] test ws_origin::a_plugin_announcing_an_old_wire_version_is_refused ... ok
[INFO] [stdout] test strategy_resources::resources_read_reports_unknown_uris_as_invalid_params ... ok
[INFO] [stdout] test ws_origin::an_accepted_session_is_pinged_without_waiting_a_full_heartbeat ... ok
[INFO] [stderr] 2026-10-06T14:19:13.431062Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: WebSocket protocol error: Connection reset without closing handshake
[INFO] [stderr] 2026-10-06T14:19:13.435662Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="search_nodes" connection_id="-" duration_ms=34 item_count=0 text_bytes=256 envelope_bytes=380 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.437182Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174030 request_id=pong-request in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stdout] test search::search_nodes_without_connection_id_routes_to_the_reconnected_session ... ok
[INFO] [stdout] test strategy_resources::resources_read_serves_the_same_text_as_prompts_get ... ok
[INFO] [stdout] test ws_origin::broker_shutdown_and_deadlines_resolve_pending_requests ... ok
[INFO] [stderr] 2026-10-06T14:19:13.440466Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=timeout in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.440583Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=shutdown in_flight=2 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.443219Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=plugin socket closed
[INFO] [stdout] test ws_origin::an_unsolicited_control_pong_keeps_the_session_routable ... ok
[INFO] [stderr] 2026-10-06T14:19:13.443732Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174035 request_id=shutdown-request in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.445416Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=cancel-me in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.445470Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=plugin socket closed
[INFO] [stderr] 2026-10-06T14:19:13.445784Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: HTTP error: 403 Forbidden
[INFO] [stdout] test ws_origin::cancellation_reaches_the_owning_plugin_and_resolves_once ... ok
[INFO] [stderr] 2026-10-06T14:19:13.448399Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=plugin heartbeat expired
[INFO] [stderr] 2026-10-06T14:19:13.450510Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: HTTP error: 403 Forbidden
[INFO] [stdout] test ws_origin::close_and_heartbeat_expiry_remove_the_registered_session ... ok
[INFO] [stderr] 2026-10-06T14:19:13.455716Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=invalid JSON protocol frame: unknown variant `ping`, expected one of `hello`, `progress`, `response`, `error`, `pong` at line 1 column 24
[INFO] [stdout] test ws_origin::accepts_exact_null_origin_and_rejects_missing_or_other_origins ... ok
[INFO] [stdout] test ws_origin::broker_shutdown_resolves_pending_calls_itself_not_by_socket_teardown ... ok
[INFO] [stdout] test ws_origin::control_pings_alone_hold_a_session_across_staleness_windows ... ok
[INFO] [stderr] 2026-10-06T14:19:13.459945Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=2 tool_name="get_screenshot" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=41 item_count=1 text_bytes=1041 envelope_bytes=2523 error_code="OK"
[INFO] [stdout] test ws_origin::shutdown_interrupts_a_tcp_peer_stalled_before_websocket_upgrade ... ok
[INFO] [stdout] test resource_control::queue_admission_is_fifo_and_drop_cancels_queued_work ... ok
[INFO] [stdout] test ws_origin::a_control_ping_is_answered_and_leaves_the_session_routable ... ok
[INFO] [stderr] 2026-10-06T14:19:13.464653Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=reset-request in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.464757Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=plugin protocol version mismatch
[INFO] [stdout] test ws_origin::first_frame_must_be_hello_and_protocol_mismatch_is_rejected ... ok
[INFO] [stderr] 2026-10-06T14:19:13.466380Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: IO error: Connection reset by peer (os error 104)
[INFO] [stderr] 2026-10-06T14:19:13.466656Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.466808Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=5ff49e21-9d78-4a7a-a4f3-bd6bdf7d703b in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.467247Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=request-1 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.467728Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=3 tool_name="get_screenshot" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=0 text_bytes=254 envelope_bytes=378 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.470834Z DEBUG figma_dev_mcp_broker::ws: discarding late or foreign error socket_id=8221cff0-76c7-4407-9593-78200f8e62bc request_id=request-1
[INFO] [stdout] test ws_origin::write_failure_cleans_session_and_allows_same_connection_id_to_reconnect ... ok
[INFO] [stderr] 2026-10-06T14:19:13.471007Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=plugin socket closed
[INFO] [stdout] test ws_origin::wrong_socket_response_cannot_complete_a_real_pending_request ... ok
[INFO] [stdout] test ws_origin::control_pongs_alone_hold_a_session_across_staleness_windows ... ok
[INFO] [stderr] 2026-10-06T14:19:13.489345Z DEBUG serve_inner: figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stdout] test search::search_nodes_rejects_a_call_with_neither_query_nor_types ... ok
[INFO] [stderr] 2026-10-06T14:19:13.489653Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=1449d82e-5365-46e7-9462-412ccf275b0e in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.496609Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.496875Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=0daf8ad8-7fea-4ea2-a603-2238de939ed1 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stdout] test ws_origin::every_frame_kind_advances_the_last_seen_the_registry_reports ... ok
[INFO] [stderr] 2026-10-06T14:19:13.498068Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=5 tool_name="search_nodes" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=1 text_bytes=402 envelope_bytes=544 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.519372Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: WebSocket protocol error: Connection reset without closing handshake
[INFO] [stderr] 2026-10-06T14:19:13.520507Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=4 tool_name="get_screenshot" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=31 item_count=3 text_bytes=954 envelope_bytes=1430 error_code="OK"
[INFO] [stdout] test screenshot::screenshot_round_trips_raster_bytes_and_validated_svg_source ... ok
[INFO] [stderr] 2026-10-06T14:19:13.532352Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.532475Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=3
[INFO] [stderr] 2026-10-06T14:19:13.532582Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=7947ba9d-be17-4ca1-83bb-5f6e1343493f in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.534003Z  WARN figma_dev_mcp_broker::supervisor: broker election failed error=failed to bind the plugin WebSocket listener: Address already in use (os error 98) attempt=3
[INFO] [stderr] 2026-10-06T14:19:13.534531Z  WARN figma_dev_mcp_broker::supervisor: retrying election attempt=3 delay_ms=400
[INFO] [stderr] 2026-10-06T14:19:13.534594Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=6 tool_name="get_design_context" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=1 text_bytes=504 envelope_bytes=652 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.585216Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.585473Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=4469c8f6-cd70-492f-9589-4bfb4268791a in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.586768Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=7 tool_name="get_styles" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=1 text_bytes=644 envelope_bytes=808 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.613171Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.613482Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=14e5cc24-12bd-48d6-bcdc-de9c4b963a4c in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.614939Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=8 tool_name="get_variables" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=1 text_bytes=904 envelope_bytes=1096 error_code="OK"
[INFO] [stdout] test search::search_nodes_schema_exposes_flat_query_filters_limit_and_cursor ... ok
[INFO] [stderr] 2026-10-06T14:19:13.637872Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.638129Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=3aca9c3f-4f2e-4829-b797-8377f600e84e in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.639728Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=9 tool_name="get_components" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=1 text_bytes=1150 envelope_bytes=1354 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.661450Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.661703Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=267262a6-163b-4f40-9012-c269baa20f13 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.662912Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=10 tool_name="get_fonts" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=1 text_bytes=436 envelope_bytes=579 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.682502Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.682773Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=ada740ba-5d68-47a8-a8f8-0b36632f59e2 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.684236Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=11 tool_name="get_dev_mode_data" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=1 text_bytes=1198 envelope_bytes=1395 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.705906Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.706158Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=5c871e55-2827-4735-97f6-98eaed395ac8 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.707437Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=12 tool_name="get_reactions" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=1 text_bytes=604 envelope_bytes=759 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.735061Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.735298Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=3c64b680-0e20-4fd0-b2a2-a2b5a74f1431 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.737196Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=13 tool_name="get_motion" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=1 text_bytes=1732 envelope_bytes=1989 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.765641Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.765892Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=8cd5485c-777e-4aa3-9e33-f6248504181c in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.767179Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=14 tool_name="get_screenshot" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=1 item_count=1 text_bytes=486 envelope_bytes=779 error_code="OK"
[INFO] [stdout] test all_tools::every_tool_and_prompt_round_trips_through_mcp_service ... ok
[INFO] [stderr] 2026-10-06T14:19:13.790823Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174000" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.791114Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=3e963ae0-6a3b-453c-8039-4b3500e6049d in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.793109Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=15 tool_name="get_screenshot" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=2 item_count=1 text_bytes=1041 envelope_bytes=2524 error_code="OK"
[INFO] [stderr] 2026-10-06T14:19:13.810427Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=1
[INFO] [stderr] 2026-10-06T14:19:13.811149Z  INFO figma_dev_mcp_broker::supervisor: entered broker role role="follower" leader_address="127.0.0.1:39787"
[INFO] [stderr] 2026-10-06T14:19:13.811232Z  INFO figma_dev_mcp_broker::supervisor: installed a new broker backend role="follower"
[INFO] [stderr] 2026-10-06T14:19:13.811419Z  WARN figma_dev_mcp_broker::supervisor: broker backend died, re-electing cause=the leader's RPC connection closed
[INFO] [stderr] 2026-10-06T14:19:13.811483Z  INFO figma_dev_mcp_broker::supervisor: detached the broker backend
[INFO] [stderr] 2026-10-06T14:19:13.811545Z DEBUG figma_dev_mcp_broker::supervisor: the previous role died immediately, delaying re-election alive_ms=0 floor_ms=799 consecutive=4
[INFO] [stderr] 2026-10-06T14:19:13.819835Z DEBUG figma_dev_mcp_tools::observability: resource control connection_id="123e4567-e89b-42d3-a456-426614174099" in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:19:13.820321Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=19 tool_name="get_metadata" connection_id="123e4567-e89b-42d3-a456-426614174099" duration_ms=0 item_count=1 text_bytes=214 envelope_bytes=334 error_code="CONNECTION_NOT_FOUND"
[INFO] [stderr] 2026-10-06T14:19:13.939445Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=4
[INFO] [stderr] 2026-10-06T14:19:13.939761Z  WARN figma_dev_mcp_broker::supervisor: broker election failed error=failed to bind the plugin WebSocket listener: Address already in use (os error 98) attempt=4
[INFO] [stderr] 2026-10-06T14:19:13.939791Z  WARN figma_dev_mcp_broker::supervisor: retrying election attempt=4 delay_ms=800
[INFO] [stdout] test failover::a_client_stops_answering_once_its_supervisor_has_shut_down ... ok
[INFO] [stdout] test failover::a_detached_client_stops_answering_through_its_dead_broker ... ok
[INFO] [stderr] 2026-10-06T14:19:14.615047Z DEBUG figma_dev_mcp_broker::supervisor: entered broker role role="follower" leader_address="127.0.0.1:39787"
[INFO] [stderr] 2026-10-06T14:19:14.615126Z DEBUG figma_dev_mcp_broker::supervisor: installed a new broker backend role="follower"
[INFO] [stderr] 2026-10-06T14:19:14.615266Z DEBUG figma_dev_mcp_broker::supervisor: broker backend died, re-electing cause=the leader's RPC connection closed
[INFO] [stderr] 2026-10-06T14:19:14.615367Z DEBUG figma_dev_mcp_broker::supervisor: detached the broker backend
[INFO] [stderr] 2026-10-06T14:19:14.615423Z DEBUG figma_dev_mcp_broker::supervisor: the previous role died immediately, delaying re-election alive_ms=0 floor_ms=1599 consecutive=5
[INFO] [stderr] 2026-10-06T14:19:14.741096Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=5
[INFO] [stderr] 2026-10-06T14:19:14.741467Z  INFO figma_dev_mcp_broker::supervisor: entered broker role role="leader" plugin_address="127.0.0.1:37557" plugin_address_v6="[::1]:37557" frontend_address="127.0.0.1:36595"
[INFO] [stderr] 2026-10-06T14:19:14.741514Z  INFO figma_dev_mcp_broker::supervisor: installed a new broker backend role="leader"
[INFO] [stdout] test failover::election_retries_until_a_squatted_port_is_released ... ok
[INFO] [stderr] 2026-10-06T14:19:16.216197Z DEBUG figma_dev_mcp_broker::supervisor: entered broker role role="follower" leader_address="127.0.0.1:39787"
[INFO] [stderr] 2026-10-06T14:19:16.216278Z DEBUG figma_dev_mcp_broker::supervisor: installed a new broker backend role="follower"
[INFO] [stderr] 2026-10-06T14:19:16.216439Z DEBUG figma_dev_mcp_broker::supervisor: broker backend died, re-electing cause=the leader's RPC connection closed
[INFO] [stderr] 2026-10-06T14:19:16.216470Z DEBUG figma_dev_mcp_broker::supervisor: detached the broker backend
[INFO] [stderr] 2026-10-06T14:19:16.216491Z DEBUG figma_dev_mcp_broker::supervisor: the previous role died immediately, delaying re-election alive_ms=0 floor_ms=3199 consecutive=6
[INFO] [stdout] test failover::a_role_that_dies_on_arrival_recycles_slower_and_slower ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 111 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 5.08s
[INFO] [stdout] 
[INFO] [stderr]      Running policy/mod.rs (/opt/rustwide/target/debug/build/figma-dev-mcp-tests/486259f906deac9b/out/policy-486259f906deac9b)
[INFO] [stdout] 
[INFO] [stdout] running 42 tests
[INFO] [stdout] test read_only::documentation_ci_pins_runtimes_and_never_publishes ... ok
[INFO] [stdout] test read_only::documentation_covers_dev_mode_import_connection_selection_and_no_daemon ... ok
[INFO] [stdout] test allowlists::mvp_tool_allowlist_is_sorted_closed_and_exact ... ok
[INFO] [stdout] test prompts::the_prompt_body_scan_reads_a_distinct_body_per_name_and_its_predicates_fire ... ok
[INFO] [stdout] test read_only::documentation_gitignore_keeps_lockfiles_and_snapshots_tracked ... ok
[INFO] [stdout] test manifest::manifest_is_the_exact_read_only_loopback_surface ... ok
[INFO] [stdout] test manifest::plugin_contexts_keep_network_and_figma_apis_separate ... ok
[INFO] [stdout] test manifest::the_plugin_context_walks_reach_real_source_and_the_separation_predicate_fires ... ok
[INFO] [stdout] test manifest::bundle_policy_requires_both_artifacts ... ok
[INFO] [stdout] test allowlists::mvp_prompt_allowlist_is_sorted_closed_and_exact ... ok
[INFO] [stdout] test prompts::prompt_bodies_name_only_allowlisted_tools_and_reject_removed_or_mutating_guidance ... ok
[INFO] [stdout] test read_only::documentation_forbids_local_export_instructions_and_unadvertised_product_tools ... ok
[INFO] [stdout] test read_only::documentation_required_operator_files_exist ... ok
[INFO] [stdout] test read_only::documentation_lists_all_seven_local_verification_commands ... ok
[INFO] [stdout] test public_contracts::public_contract_wrappers_keep_protocol_types_behind_crate_private_conversions ... ok
[INFO] [stdout] test read_only::documentation_states_exact_ports_tools_and_prompts ... ok
[INFO] [stdout] test read_only::documentation_states_the_exact_strategy_resource_uris ... ok
[INFO] [stdout] test read_only::origin_socket_and_rpc_boundaries_stay_raw_tcp_and_null_origin ... ok
[INFO] [stdout] test prompts::tool_reference_extraction_is_limited_to_backticked_snake_case_verbs ... ok
[INFO] [stdout] test read_only::documentation_splits_stdio_evidence_from_official_lifecycle_smoke ... ok
[INFO] [stdout] test read_only::documentation_manual_acceptance_has_nine_spec_scenarios ... ok
[INFO] [stdout] test read_only::serving_a_strategy_resource_never_reaches_the_broker ... ok
[INFO] [stdout] test read_only::a_broker_request_carrying_an_allowlisted_operation_still_decodes_intact ... ok
[INFO] [stdout] test read_only::manifest_is_dev_mode_inspect_dynamic_page_loopback ... ok
[INFO] [stdout] test read_only::the_read_dispatcher_mutates_no_process_global_host_state ... ok
[INFO] [stdout] test read_only::the_canonical_message_scan_reaches_the_catalog_and_every_production_file ... ok
[INFO] [stdout] test read_only::the_operator_documentation_scan_reaches_all_four_files_and_instructs_still_fires ... ok
[INFO] [stdout] test read_only::the_shared_instructs_exempts_exactly_three_negation_prefixes ... ok
[INFO] [stdout] test manifest::production_dispatch_is_closed_and_removed_operations_stay_absent ... ok
[INFO] [stdout] test read_only::the_mutation_denylist_scan_reaches_real_plugin_source_and_its_list_is_not_empty ... ok
[INFO] [stdout] test read_only::the_read_dispatcher_source_still_names_every_operation_it_dispatches ... ok
[INFO] [stdout] test read_only::write_shaped_mcp_and_wire_requests_are_rejected ... ok
[INFO] [stdout] test read_only::read_tests_share_one_figma_harness ... ok
[INFO] [stdout] test plugin_source::the_plugin_source_walk_reaches_real_files_and_the_assignment_predicate_fires ... ok
[INFO] [stdout] test read_only::documentation_states_svg_source_readonly_limits_and_origin_threat_model ... ok
[INFO] [stdout] test read_only::production_plugin_source_spells_no_canonical_error_message ... ok
[INFO] [stdout] test read_only::snapshots_lock_tools_annotations_prompts_and_wire_variants ... ok
[INFO] [stdout] test read_only::input_schemas_reject_filesystem_command_and_network_targets ... ok
[INFO] [stdout] test plugin_source::plugin_source_rejects_unbounded_page_font_and_mutation_surfaces ... ok
[INFO] [stdout] test read_only::the_input_schema_scan_collects_names_from_every_level_it_claims_to_reach ... ok
[INFO] [stdout] test read_only::plugin_source_denies_mutation_private_and_motion_write_apis ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/figma-dev-mcp-tools/5a154ab6877bbd8e/out/figma_dev_mcp_tools-5a154ab6877bbd8e)
[INFO] [stdout] test read_only::every_file_line_citation_in_the_acceptance_record_resolves_to_a_real_line ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 42 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.37s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test observability::tests::converted_error_results_are_not_logged_as_ok ... ok
[INFO] [stdout] test observability::tests::a_failure_without_a_recognizable_code_is_unknown_not_a_limit_breach ... ok
[INFO] [stdout] test observability::tests::every_protocol_error_code_logs_as_itself ... ok
[INFO] [stdout] test catalog::tests::rust_widths_always_state_their_bounds ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests figma_dev_mcp
[INFO] [stderr]    Doc-tests figma_dev_mcp_broker
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests figma_dev_mcp_prompts
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests figma_dev_mcp_protocol
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests figma_dev_mcp_tests
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests figma_dev_mcp_tools
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "1e8e69b5f6f2a4a52b025497b57e46f1c3c23d418f71201f9edafc1304e0bf81", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "1e8e69b5f6f2a4a52b025497b57e46f1c3c23d418f71201f9edafc1304e0bf81", kill_on_drop: false }`
[INFO] [stdout] 1e8e69b5f6f2a4a52b025497b57e46f1c3c23d418f71201f9edafc1304e0bf81
