[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