[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.99.0-beta.8 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-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-7-tc1/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-7-tc1/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-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/emizuki/figma-dev-mcp on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate 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.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded wasm-bindgen-macro v0.2.126
[INFO] [stderr]   Downloaded uuid v1.24.1
[INFO] [stderr]   Downloaded wasm-bindgen-shared v0.2.126
[INFO] [stderr]   Downloaded strum v0.28.0
[INFO] [stderr]   Downloaded crypto-common v0.2.2
[INFO] [stderr]   Downloaded android_system_properties v0.1.6
[INFO] [stderr]   Downloaded num v0.4.3
[INFO] [stderr]   Downloaded windows-targets v0.52.6
[INFO] [stderr]   Downloaded wasm-bindgen-macro-support v0.2.126
[INFO] [stderr]   Downloaded serde_derive_internals v0.30.0
[INFO] [stderr]   Downloaded thiserror v2.0.20
[INFO] [stderr]   Downloaded strum_macros v0.28.0
[INFO] [stderr]   Downloaded uuid-simd v0.8.0
[INFO] [stderr]   Downloaded borrow-or-share v0.2.4
[INFO] [stderr]   Downloaded zerofrom-derive v0.1.7
[INFO] [stderr]   Downloaded serde_path_to_error v0.1.20
[INFO] [stderr]   Downloaded http-body v1.0.1
[INFO] [stderr]   Downloaded zerovec-derive v0.11.4
[INFO] [stderr]   Downloaded yoke-derive v0.8.2
[INFO] [stderr]   Downloaded wasm-bindgen v0.2.126
[INFO] [stderr]   Downloaded cpufeatures v0.3.0
[INFO] [stderr]   Downloaded futures-macro v0.3.34
[INFO] [stderr]   Downloaded sha1 v0.11.0
[INFO] [stderr]   Downloaded email_address v0.2.9
[INFO] [stderr]   Downloaded block-buffer v0.12.1
[INFO] [stderr]   Downloaded dyn-clone v1.0.20
[INFO] [stderr]   Downloaded matchit v0.8.4
[INFO] [stderr]   Downloaded thiserror-impl v2.0.20
[INFO] [stderr]   Downloaded digest v0.11.3
[INFO] [stderr]   Downloaded schemars_derive v1.2.2
[INFO] [stderr]   Downloaded ref-cast-impl v1.0.26
[INFO] [stderr]   Downloaded find-msvc-tools v0.1.9
[INFO] [stderr]   Downloaded sse-stream v0.2.5
[INFO] [stderr]   Downloaded futures-executor v0.3.34
[INFO] [stderr]   Downloaded async-trait v0.1.92
[INFO] [stderr]   Downloaded windows-result v0.2.0
[INFO] [stderr]   Downloaded windows-interface v0.58.0
[INFO] [stderr]   Downloaded allocator-api2 v0.2.21
[INFO] [stderr]   Downloaded num-cmp v0.1.0
[INFO] [stderr]   Downloaded num-iter v0.1.46
[INFO] [stderr]   Downloaded jsonschema-regex v0.49.9
[INFO] [stderr]   Downloaded bit-set v0.8.0
[INFO] [stderr]   Downloaded windows-strings v0.1.0
[INFO] [stderr]   Downloaded windows-implement v0.58.0
[INFO] [stderr]   Downloaded darling_macro v0.24.0
[INFO] [stderr]   Downloaded outref v0.5.2
[INFO] [stderr]   Downloaded tokio-stream v0.1.19
[INFO] [stderr]   Downloaded vsimd v0.8.0
[INFO] [stderr]   Downloaded foldhash v0.2.0
[INFO] [stderr]   Downloaded num-rational v0.4.2
[INFO] [stderr]   Downloaded num-complex v0.4.6
[INFO] [stderr]   Downloaded bytecount v0.6.9
[INFO] [stderr]   Downloaded synstructure v0.13.2
[INFO] [stderr]   Downloaded tracing-attributes v0.1.31
[INFO] [stderr]   Downloaded tokio-tungstenite v0.30.0
[INFO] [stderr]   Downloaded hybrid-array v0.4.14
[INFO] [stderr]   Downloaded data-encoding v2.11.1
[INFO] [stderr]   Downloaded ref-cast v1.0.26
[INFO] [stderr]   Downloaded autocfg v1.5.1
[INFO] [stderr]   Downloaded iana-time-zone v0.1.65
[INFO] [stderr]   Downloaded windows-core v0.58.0
[INFO] [stderr]   Downloaded core-foundation-sys v0.8.7
[INFO] [stderr]   Downloaded http-body-util v0.1.3
[INFO] [stderr]   Downloaded rmcp-macros v3.1.2
[INFO] [stderr]   Downloaded darling v0.24.0
[INFO] [stderr]   Downloaded pastey v0.2.3
[INFO] [stderr]   Downloaded unicode-general-category v1.1.0
[INFO] [stderr]   Downloaded num-integer v0.1.47
[INFO] [stderr]   Downloaded chacha20 v0.10.1
[INFO] [stderr]   Downloaded referencing v0.49.9
[INFO] [stderr]   Downloaded ahash v0.8.12
[INFO] [stderr]   Downloaded jsonschema-value v0.49.9
[INFO] [stderr]   Downloaded icu_provider v2.3.0
[INFO] [stderr]   Downloaded futures v0.3.34
[INFO] [stderr]   Downloaded micromap v0.3.0
[INFO] [stderr]   Downloaded getrandom v0.3.4
[INFO] [stderr]   Downloaded wit-bindgen v0.57.1
[INFO] [stderr]   Downloaded r-efi v5.3.0
[INFO] [stderr]   Downloaded fluent-uri v0.4.1
[INFO] [stderr]   Downloaded bit-vec v0.8.0
[INFO] [stderr]   Downloaded bumpalo v3.20.3
[INFO] [stderr]   Downloaded const-oid v0.10.2
[INFO] [stderr]   Downloaded darling_core v0.24.0
[INFO] [stderr]   Downloaded axum-core v0.5.6
[INFO] [stderr]   Downloaded r-efi v6.0.0
[INFO] [stderr]   Downloaded tungstenite v0.30.0
[INFO] [stderr]   Downloaded schemars v1.2.2
[INFO] [stderr]   Downloaded cc v1.4.0
[INFO] [stderr]   Downloaded zerocopy-derive v0.8.56
[INFO] [stderr]   Downloaded fraction v0.15.4
[INFO] [stderr]   Downloaded num-bigint v0.4.8
[INFO] [stderr]   Downloaded js-sys v0.3.103
[INFO] [stderr]   Downloaded rand v0.10.2
[INFO] [stderr]   Downloaded zerovec v0.11.7
[INFO] [stderr]   Downloaded regex v1.13.1
[INFO] [stderr]   Downloaded wasip2 v1.0.4+wasi-0.2.12
[INFO] [stderr]   Downloaded axum v0.8.8
[INFO] [stderr]   Downloaded hyper-util v0.1.20
[INFO] [stderr]   Downloaded hyper v1.8.1
[INFO] [stderr]   Downloaded chrono v0.4.45
[INFO] [stderr]   Downloaded fancy-regex v0.19.0
[INFO] [stderr]   Downloaded zerocopy v0.8.56
[INFO] [stderr]   Downloaded windows_x86_64_gnullvm v0.52.6
[INFO] [stderr]   Downloaded windows_aarch64_gnullvm v0.52.6
[INFO] [stderr]   Downloaded rmcp v3.0.0
[INFO] [stderr]   Downloaded jsonschema v0.49.9
[INFO] [stderr]   Downloaded windows_i686_gnullvm v0.52.6
[INFO] [stderr]   Downloaded windows_aarch64_msvc v0.52.6
[INFO] [stderr]   Downloaded windows_x86_64_gnu v0.52.6
[INFO] [stderr]   Downloaded windows_x86_64_msvc v0.52.6
[INFO] [stderr]   Downloaded windows_i686_msvc v0.52.6
[INFO] [stderr]   Downloaded windows_i686_gnu v0.52.6
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] d560a27ddc0fc3efb48b48de6d74cbab3ca819fd2e430d265bdfc1b898eeba16
[INFO] running `Command { std: "docker" "start" "d560a27ddc0fc3efb48b48de6d74cbab3ca819fd2e430d265bdfc1b898eeba16", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d560a27ddc0fc3efb48b48de6d74cbab3ca819fd2e430d265bdfc1b898eeba16", 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" "d560a27ddc0fc3efb48b48de6d74cbab3ca819fd2e430d265bdfc1b898eeba16" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "d560a27ddc0fc3efb48b48de6d74cbab3ca819fd2e430d265bdfc1b898eeba16", 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" "d560a27ddc0fc3efb48b48de6d74cbab3ca819fd2e430d265bdfc1b898eeba16" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling unicode-ident v1.0.24
[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 bytes v1.12.1
[INFO] [stderr]    Compiling futures-core v0.3.34
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling futures-sink v0.3.34
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling slab v0.4.12
[INFO] [stderr]    Compiling log v0.4.33
[INFO] [stderr]    Compiling futures-io v0.3.34
[INFO] [stderr]    Compiling zmij v1.0.23
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling futures-task v0.3.34
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling futures-channel v0.3.34
[INFO] [stderr]    Compiling rand_core v0.10.1
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling autocfg v1.5.1
[INFO] [stderr]    Compiling typenum v1.20.1
[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 tracing-core v0.1.36
[INFO] [stderr]    Compiling thiserror v2.0.20
[INFO] [stderr]    Compiling httparse v1.10.1
[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 num-traits v0.2.19
[INFO] [stderr]    Compiling dyn-clone v1.0.20
[INFO] [stderr]    Compiling const-oid v0.10.2
[INFO] [stderr]    Compiling rmcp v3.0.0
[INFO] [stderr]    Compiling data-encoding v2.11.1
[INFO] [stderr]    Compiling regex-syntax v0.8.11
[INFO] [stderr]    Compiling pastey v0.2.3
[INFO] [stderr]    Compiling http v1.5.0
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling base64 v0.23.1
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling anyhow v1.0.104
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling hybrid-array v0.4.14
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling thread_local v1.1.10
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling httpdate v1.0.3
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling atomic-waker v1.1.2
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling tower-layer v0.3.3
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling mime v0.3.17
[INFO] [stderr]    Compiling ryu v1.0.23
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling matchit v0.8.4
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling block-buffer v0.12.1
[INFO] [stderr]    Compiling crypto-common v0.2.2
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling clap_builder v4.6.6
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling socket2 v0.6.5
[INFO] [stderr]    Compiling mio v1.2.2
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling rand v0.10.2
[INFO] [stderr]    Compiling regex-automata v0.4.18
[INFO] [stderr]    Compiling uuid v1.24.1
[INFO] [stderr]    Compiling serde_path_to_error v0.1.20
[INFO] [stderr]    Compiling digest v0.11.3
[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 tokio-macros v2.7.2
[INFO] [stderr]    Compiling futures-macro v0.3.34
[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 tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling futures-util v0.3.34
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling axum-core v0.5.6
[INFO] [stderr]    Compiling tungstenite v0.30.0
[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 36s
[INFO] running `Command { std: "docker" "inspect" "d560a27ddc0fc3efb48b48de6d74cbab3ca819fd2e430d265bdfc1b898eeba16", 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" "d560a27ddc0fc3efb48b48de6d74cbab3ca819fd2e430d265bdfc1b898eeba16" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling num-traits v0.2.19
[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 writeable v0.6.4
[INFO] [stderr]    Compiling litemap v0.8.3
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling zerocopy v0.8.56
[INFO] [stderr]    Compiling version_check v0.9.5
[INFO] [stderr]    Compiling icu_properties_data v2.3.0
[INFO] [stderr]    Compiling icu_normalizer_data v2.3.0
[INFO] [stderr]    Compiling utf8_iter v1.0.4
[INFO] [stderr]    Compiling foldhash v0.2.0
[INFO] [stderr]    Compiling borrow-or-share v0.2.4
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling unicode-general-category v1.1.0
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling allocator-api2 v0.2.21
[INFO] [stderr]    Compiling fluent-uri v0.4.1
[INFO] [stderr]    Compiling micromap v0.3.0
[INFO] [stderr]    Compiling ahash v0.8.12
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling vsimd v0.8.0
[INFO] [stderr]    Compiling outref v0.5.2
[INFO] [stderr]    Compiling num-cmp v0.1.0
[INFO] [stderr]    Compiling bytecount v0.6.9
[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 figma-dev-mcp-protocol v0.1.0 (/opt/rustwide/workdir/crates/protocol)
[INFO] [stderr]    Compiling num v0.4.3
[INFO] [stderr]    Compiling fraction v0.15.4
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling referencing v0.49.9
[INFO] [stderr]    Compiling jsonschema-value v0.49.9
[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 tracing v0.1.44
[INFO] [stderr]    Compiling zerofrom v0.1.8
[INFO] [stderr]    Compiling yoke v0.8.3
[INFO] [stderr]    Compiling rmcp v3.0.0
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling axum-core v0.5.6
[INFO] [stderr]    Compiling zerovec v0.11.7
[INFO] [stderr]    Compiling zerotrie v0.2.5
[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 matchers v0.2.0
[INFO] [stderr]    Compiling fancy-regex v0.19.0
[INFO] [stderr]    Compiling regex v1.13.1
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling icu_provider v2.3.0
[INFO] [stderr]    Compiling icu_normalizer v2.3.0
[INFO] [stderr]    Compiling icu_properties v2.3.0
[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 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-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 00s
[INFO] running `Command { std: "docker" "inspect" "d560a27ddc0fc3efb48b48de6d74cbab3ca819fd2e430d265bdfc1b898eeba16", 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" "d560a27ddc0fc3efb48b48de6d74cbab3ca819fd2e430d265bdfc1b898eeba16" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.06s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/figma_dev_mcp-4425e0f738869808)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/figma_dev_mcp-596f85bb2f99161b)
[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] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stderr]      Running tests/stdio_eras.rs (/opt/rustwide/target/debug/deps/stdio_eras-a2f36be6f3ab62d6)
[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 an_election_that_never_succeeds_still_answers_the_client ... ok
[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_uninitialized_follower_still_reopens_the_plugin_port ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/figma_dev_mcp_broker-d2dac347d8932c02)
[INFO] [stdout] test killing_the_leader_lets_a_follower_reopen_the_plugin_port ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 2.10s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 15 tests
[INFO] [stdout] test supervisor::tests::a_healthy_role_outlives_the_longest_delay_the_supervisor_imposes ... ok
[INFO] [stdout] test supervisor::tests::a_stuck_election_still_reports_periodically ... ok
[INFO] [stdout] test supervisor::tests::only_ipv4_loopback_has_a_companion ... ok
[INFO] [stdout] test supervisor::tests::consecutive_immediate_deaths_double_the_delay ... ok
[INFO] [stdout] test supervisor::tests::the_first_recycles_are_reported_in_full ... ok
[INFO] [stdout] test supervisor::tests::the_recycle_delay_never_decreases ... ok
[INFO] [stdout] test supervisor::tests::a_sustained_spin_still_reports_periodically ... ok
[INFO] [stdout] test supervisor::tests::the_recycle_delay_saturates_at_the_backoff_cap ... ok
[INFO] [stdout] test supervisor::tests::a_permanently_failing_election_goes_quiet ... ok
[INFO] [stdout] test supervisor::tests::a_role_that_lived_a_healthy_life_re_elects_immediately ... ok
[INFO] [stdout] test supervisor::tests::the_companion_follows_an_ephemeral_port ... 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::the_backoff_growth_phase_is_logged_in_full ... ok
[INFO] [stdout] test supervisor::tests::a_sustained_spin_goes_quiet ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 15 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.23s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/figma_dev_mcp_prompts-d4cf44761a5ed4c9)
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/figma_dev_mcp_protocol-ac8dd7d7eb4a0c1a)
[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 8 tests
[INFO] [stdout] test rpc::tests::streaming_encoder_aborts_at_cap_without_serializing_the_remaining_items ... ok
[INFO] [stdout] test error::error_code_all_tests::all_lists_each_member_once ... ok
[INFO] [stdout] test deferred::tests::the_running_estimate_equals_the_bytes_decode_produces ... ok
[INFO] [stdout] test domain::common::tests::text_properties_round_trip_and_stay_closed ... ok
[INFO] [stdout] test domain::common::tests::blend_mode_round_trips_and_stays_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/deps/figma_dev_mcp_tests-80ee52c402f7c461)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stderr]      Running unittests src/bin/conformance-server.rs (/opt/rustwide/target/debug/deps/conformance_server-82824f2177d2459e)
[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]      Running contracts/mod.rs (/opt/rustwide/target/debug/deps/contracts-305915d4c3b65f2f)
[INFO] [stdout] 
[INFO] [stdout] running 101 tests
[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 a_frame_declaring_exactly_the_envelope_ceiling_is_not_refused_for_its_size ... ok
[INFO] [stdout] test an_unsupported_effect_names_its_figma_type_and_needs_no_radius ... ok
[INFO] [stdout] test an_svg_verdict_is_stated_and_matches_its_rule ... ok
[INFO] [stdout] test an_input_asking_for_exactly_the_maximum_depth_is_accepted ... 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 actual_wire_discriminators_accept_tag_last_and_reject_duplicate_or_unknown_fields ... ok
[INFO] [stdout] test an_unsafe_screenshot_asset_carries_its_rule_through_the_result ... ok
[INFO] [stdout] test angular_and_diamond_gradients_are_first_class_paints ... ok
[INFO] [stdout] test component_property_values_round_trip_and_reject_unknown_kinds ... ok
[INFO] [stdout] test checked_in_wire_fixtures_round_trip_without_shape_drift ... ok
[INFO] [stdout] test corner_radius_round_trips_and_stays_closed ... ok
[INFO] [stdout] test both_ends_declare_the_same_wire_version ... ok
[INFO] [stdout] test both_detail_result_macro_arms_agree_on_every_shared_field ... ok
[INFO] [stdout] test error_catalog::the_plugin_error_catalog_is_generated_from_the_protocol ... ok
[INFO] [stdout] test boundary_decoders_reject_oversized_inputs_before_dispatch ... ok
[INFO] [stdout] test every_svg_rejection_rule_round_trips_named_and_unnamed ... ok
[INFO] [stdout] test broker_and_rpc_enums_are_exhaustive_and_shape_locked ... ok
[INFO] [stdout] test allocation::outbound_validation_rejects_width_before_allocating_a_wide_auxiliary_stack ... ok
[INFO] [stdout] test error_catalog::the_generated_catalog_pairs_each_code_with_its_canonical_message ... ok
[INFO] [stdout] test error_code_all_is_the_set_the_enum_declares ... ok
[INFO] [stdout] test get_motion_result_requires_visited_nodes ... ok
[INFO] [stdout] test get_motion_result_still_rejects_unknown_fields ... ok
[INFO] [stdout] test get_motion_result_round_trips_visited_nodes ... ok
[INFO] [stdout] test get_reactions_result_requires_visited_nodes ... ok
[INFO] [stdout] test get_dev_mode_data_result_round_trips_visited_nodes ... ok
[INFO] [stdout] test get_dev_mode_data_result_requires_visited_nodes ... ok
[INFO] [stdout] test gradient_and_image_paints_carry_opacity_and_direction ... ok
[INFO] [stdout] test get_reactions_result_still_rejects_unknown_fields ... ok
[INFO] [stdout] test hello_carries_explicit_session_identity_and_fresh_display_metadata ... ok
[INFO] [stdout] test modelled_effects_still_require_their_own_fields ... ok
[INFO] [stdout] test layout_alignment_round_trips_and_rejects_unknown_values ... ok
[INFO] [stdout] test amended_motion_contract_uses_seconds_keyed_maps_and_distinct_style_types ... ok
[INFO] [stdout] test fixed_limits_match_the_reviewed_ceiling ... ok
[INFO] [stdout] test get_dev_mode_data_result_still_rejects_unknown_fields ... ok
[INFO] [stdout] test get_reactions_result_round_trips_visited_nodes ... ok
[INFO] [stdout] test nested_enum_fields_are_camel_case_and_input_bounds_are_schema_backed ... ok
[INFO] [stdout] test prompts_catalog::prompts_catalog_is_sorted_argumentless_and_publicly_cacheable ... ok
[INFO] [stdout] test frontend_invocations_cannot_supply_plugin_request_ids ... ok
[INFO] [stdout] test public_scalar_boundaries_reject_invalid_inbound_and_outbound_values ... ok
[INFO] [stdout] test reaction_overlay_settings_are_closed_optional_and_camel_case ... ok
[INFO] [stdout] test plugin_failures_cannot_supply_public_or_diagnostic_messages ... ok
[INFO] [stdout] test full_node_results_carry_populated_instance_properties_past_the_serializer ... ok
[INFO] [stdout] test detail_results_are_discriminated_and_rich_nodes_are_schema_explicit ... ok
[INFO] [stdout] test minimal_results_preserve_recursive_depth_and_reject_flat_summaries ... ok
[INFO] [stdout] test resources_catalog::uris_outside_the_strategy_prefix_do_not_resolve ... ok
[INFO] [stdout] test resources_catalog::resource_catalog_is_one_prefixed_uri_per_prompt_and_publicly_cacheable ... ok
[INFO] [stdout] test prompts_catalog::prompt_snapshot_is_stable ... ok
[INFO] [stdout] test resources_catalog::reading_a_strategy_resource_returns_the_prompt_body_verbatim ... ok
[INFO] [stdout] test outbound_node_collections_reject_wide_roots_and_children_without_auxiliary_growth ... 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 response_accounting::svg_source_is_counted_in_structured_text_and_image ... ok
[INFO] [stdout] test a_hidden_node_is_refused_by_name_rather_than_reported_missing ... ok
[INFO] [stdout] test bounded_collection_decoders_stop_at_max_plus_one_and_frame_encoding_is_stream_capped ... ok
[INFO] [stdout] test response_accounting::utf8_and_json_escaping_count_serialized_bytes ... ok
[INFO] [stdout] test allocation::actual_frame_decoder_stops_large_node_id_input_without_content_expansion ... ok
[INFO] [stdout] test schemas_and_decoders_agree_on_utf8_byte_limits ... ok
[INFO] [stdout] test screenshot_schema_and_decoder_exclude_raster_scale_from_svg ... ok
[INFO] [stdout] test rpc_frames_are_length_prefixed_and_reject_oversize_before_body_read ... ok
[INFO] [stdout] test selector_schema_is_one_of_and_selection_is_literal_true ... ok
[INFO] [stdout] test search_node_types_are_count_and_utf8_byte_bounded ... ok
[INFO] [stdout] test stable_error_codes_are_exact_and_screaming_snake_case ... ok
[INFO] [stdout] test nested_returned_value_collections_reject_more_than_the_result_limit ... ok
[INFO] [stdout] test stroke_value_round_trips_and_rejects_unknown_fields ... 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 svg_rejection_rules_stay_closed ... ok
[INFO] [stdout] test text_style_units_round_trip_and_reject_unknown_units ... ok
[INFO] [stdout] test the_empty_bounds_code_round_trips_and_owns_its_message ... ok
[INFO] [stdout] test the_forest_results_decode_an_unresolved_entry_and_keep_it ... ok
[INFO] [stdout] test read_operation_and_result_tags_are_closed_and_exact ... ok
[INFO] [stdout] test the_outbound_node_builder_accepts_a_tree_at_exactly_the_depth_ceiling ... ok
[INFO] [stdout] test the_plugin_mirrors_every_svg_rejection_rule ... ok
[INFO] [stdout] test the_tool_error_no_longer_carries_an_svg_rule ... ok
[INFO] [stdout] test response_accounting::per_item_ceilings_reject_complete_assets_without_slicing ... ok
[INFO] [stdout] test screenshot_assets_enforce_wire_byte_and_raster_dimension_limits ... ok
[INFO] [stdout] test a_wire_snapshot_change_must_be_a_deliberate_version_decision ... ok
[INFO] [stdout] test structured_outputs::every_stable_tool_error_matches_the_tool_error_schema ... ok
[INFO] [stdout] test tools_catalog::structured_image_content_preserves_the_compatibility_text_block ... ok
[INFO] [stdout] test allocation::actual_plugin_result_decoder_bounds_wide_and_deep_tag_last_payloads ... ok
[INFO] [stdout] test tools_catalog::schemas_carry_no_format_outside_a_validator_vocabulary ... ok
[INFO] [stdout] test unknown_fields_are_rejected_at_nested_boundaries ... ok
[INFO] [stdout] test tools_catalog::dropping_rust_widths_spares_formats_and_fields_that_carry_meaning ... ok
[INFO] [stdout] test wire_message_tags_are_closed_camel_case_and_exact ... ok
[INFO] [stdout] test visited_nodes_reaches_every_per_node_output_schema ... ok
[INFO] [stdout] test tools_catalog::screenshot_schema_accepts_each_format_without_top_level_property_blocking ... ok
[INFO] [stdout] test tools_catalog::tools_catalog_is_complete_sorted_read_only_and_cacheable ... 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 8.90s
[INFO] [stdout] 
[INFO] [stderr]      Running integration/mod.rs (/opt/rustwide/target/debug/deps/integration-1addf38cae5a0281)
[INFO] [stdout] 
[INFO] [stdout] running 111 tests
[INFO] [stdout] test election::concurrent_elections_produce_exactly_one_leader ... ok
[INFO] [stdout] test election::one_process_owns_both_listeners_and_later_processes_follow ... ok
[INFO] [stdout] test conformance_adapter::production_crate_does_not_enable_http_transport_features ... ok
[INFO] [stdout] test conformance_adapter::tests_crate_isolates_http_features_and_the_adapter_binary ... ok
[INFO] [stdout] test conformance_adapter::conformance_scripts_pin_the_two_lifecycle_scenarios_not_the_full_suite ... ok
[INFO] [stdout] test design_system::public_contracts_default_style_source_and_alias_resolution ... ok
[INFO] [stdout] test failover::a_detached_client_fails_calls_after_the_deadline ... ok
[INFO] [stdout] test election::plugin_bind_failure_releases_the_frontend_listener ... ok
[INFO] [stdout] test failover::a_call_made_before_the_first_election_succeeds_once_a_backend_arrives ... ok
[INFO] [stdout] test failover::a_leader_shuts_down_while_its_ipv6_retry_is_still_waiting ... 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 failover::a_remote_client_has_no_local_broker ... ok
[INFO] [stdout] test design_system::styles_and_variables_round_trip_through_server_broker_and_plugin ... ok
[INFO] [stdout] test all_tools::cancellation_and_disconnect_use_stable_tool_errors ... ok
[INFO] [stdout] test components_fonts::components_and_fonts_round_trip_through_server_broker_and_plugin ... ok
[INFO] [stdout] test all_tools::inactivity_timeout_uses_the_stable_timeout_error ... ok
[INFO] [stdout] test all_tools::queue_saturation_returns_retryable_limit_exceeded ... ok
[INFO] [stdout] test failover::an_unattached_client_still_fails_calls_retryably_after_the_deadline ... ok
[INFO] [stdout] test failover::a_supervisor_built_unattached_elects_inside_supervise ... ok
[INFO] [stdout] test failover::cancelling_a_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_an_open_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_swapped_backend_does_not_steal_a_calls_cancellation ... ok
[INFO] [stdout] test failover::an_open_call_records_the_broker_that_opened_it ... ok
[INFO] [stdout] test failover::local_broker_resolves_through_the_swappable_cell ... ok
[INFO] [stdout] test failover::a_follower_promotes_itself_when_the_leader_dies ... ok
[INFO] [stdout] test failover::waiting_for_a_backend_does_not_outlast_the_deadline ... ok
[INFO] [stdout] test failover::the_first_supervisor_leads_and_the_second_follows ... 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::a_leader_keeps_trying_the_ipv6_plugin_port_until_it_is_free ... ok
[INFO] [stdout] test failover::a_leader_whose_broker_dies_re_elects_and_rebinds ... 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 idle_lifetime::idle_shutdown_atomically_rejects_new_activity_after_grace ... ok
[INFO] [stdout] test metadata::get_metadata_round_trips_through_server_broker_and_plugin ... 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 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 failover::frontend_client_open_drop_governs_the_remote_watcher_on_both_branches ... ok
[INFO] [stdout] test failover::exactly_one_of_two_orphans_takes_the_ports ... ok
[INFO] [stdout] test multi_client::duplicate_active_rpc_id_closes_frontend_and_cancels_owned_call ... ok
[INFO] [stdout] test multi_client::two_frontends_share_one_leader_registry ... ok
[INFO] [stderr] 2026-10-06T14:14:58.406364Z  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::remote_broker_client_can_back_an_mcp_frontend ... ok
[INFO] [stderr] 2026-10-06T14:14:58.406708Z  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:14:58.406909Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.407070Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.407416Z  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:14:58.407711Z  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:14:58.408015Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.408192Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.408927Z 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:14:58.409044Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=b1fe449f-7ec1-40b6-9dec-de5d7982c18b in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.410399Z  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:14:58.410766Z 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:14:58.410796Z  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:14:58.411080Z  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=1 item_count=1 text_bytes=854 envelope_bytes=1016 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.411102Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.411284Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.413783Z 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:14:58.413942Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=a9955f0b-648f-4bb9-86fe-1a100804dd6d in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.414726Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174006 request_id=6353dd5a-3611-4e09-a3e5-76bcbfce8aa5 in_flight=2 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.420227Z  WARN serve_inner: rmcp::service: response error id=4 error=ErrorData { code: ErrorCode(-32602), message: "prompt 'design_strategy' not found", data: None }
[INFO] [stdout] test all_tools::screenshot_partial_batch_keeps_successes_and_item_errors ... ok
[INFO] [stderr] 2026-10-06T14:14:58.427090Z  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 observability::tool_logs_are_schema_safe_and_stdout_stays_protocol_only ... ok
[INFO] [stderr] 2026-10-06T14:14:58.428794Z  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 prompts::prompts_get_returns_one_user_text_message_and_rejects_unknown_names ... ok
[INFO] [stderr] 2026-10-06T14:14:58.428830Z  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:14:58.428926Z  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:14:58.429549Z  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:14:58.429203Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.429798Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.430064Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.431479Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.432022Z 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:14:58.432281Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=be80b69f-e1eb-4a34-b302-381ce886cd79 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.434122Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=plugin socket closed
[INFO] [stdout] test prompts::prompts_list_is_sorted_argumentless_and_publicly_cacheable ... ok
[INFO] [stderr] 2026-10-06T14:14:58.429587Z  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:14:58.437700Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.437904Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.435266Z  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=172 envelope_bytes=291 error_code="CONNECTION_LOST"
[INFO] [stderr] 2026-10-06T14:14:58.438470Z 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:14:58.438592Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=0950fc9b-e600-41bd-b502-6d33c34f2d62 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.438729Z 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] [stderr] 2026-10-06T14:14:58.439820Z  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] [stdout] test multi_client::concurrent_frontend_calls_are_correlated_when_responses_arrive_out_of_order ... ok
[INFO] [stderr] 2026-10-06T14:14:58.440480Z 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:14:58.441478Z  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:14:58.441912Z  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:14:58.442433Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=d5cbe4f8-c824-44a3-8989-2ca93bb4123b in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.443182Z DEBUG figma_dev_mcp_broker::ws: discarding late or foreign response socket_id=29f58fa6-e336-4a4d-93f7-27c9712f9aec request_id=be80b69f-e1eb-4a34-b302-381ce886cd79
[INFO] [stderr] 2026-10-06T14:14:58.442639Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.443698Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.445142Z  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:14:58.445470Z  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 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] [stdout] test multi_client::malformed_frame_cancels_an_active_frontend_call_before_closing ... ok
[INFO] [stderr] 2026-10-06T14:14:58.450631Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.455586Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.458834Z 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:14:58.459056Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=cdca34de-3f22-48fd-87e2-fa905a5c754e in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.460009Z  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:14:58.451198Z  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::cancellation_is_scoped_to_one_concurrent_frontend_call ... ok
[INFO] [stderr] 2026-10-06T14:14:58.455118Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="get_selection" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=41 item_count=0 text_bytes=290 envelope_bytes=418 error_code="OK"
[INFO] [stdout] test resource_control::disconnect_fails_inflight_and_late_responses_are_discarded ... ok
[INFO] [stderr] 2026-10-06T14:14:58.447237Z  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=4 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.467857Z 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:14:58.468013Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=3dcc8641-3c30-4fa4-9b0b-18307cf1ab6e in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.456259Z  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:14:58.468576Z  INFO figma_dev_mcp_broker::supervisor: running the first broker election
[INFO] [stderr] 2026-10-06T14:14:58.468609Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=1
[INFO] [stderr] 2026-10-06T14:14:58.468710Z  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:14:58.468710Z  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:14:58.468869Z  INFO figma_dev_mcp_broker::supervisor: entered broker role role="leader" plugin_address="127.0.0.1:42001" plugin_address_v6="[::1]:42001" frontend_address="127.0.0.1:37173"
[INFO] [stderr] 2026-10-06T14:14:58.468904Z  INFO figma_dev_mcp_broker::supervisor: running the first broker election
[INFO] [stderr] 2026-10-06T14:14:58.468916Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=1
[INFO] [stderr] 2026-10-06T14:14:58.469004Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.469004Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.469155Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.469161Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.469459Z  INFO figma_dev_mcp_broker::supervisor: entered broker role role="follower" leader_address="127.0.0.1:37173"
[INFO] [stderr] 2026-10-06T14:14:58.469542Z 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:14:58.469542Z 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:14:58.469643Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=51f9f3d6-d73c-4345-93c6-1724a41f08d1 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.469651Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=556037c2-990c-4853-8c33-efc5d9843302 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.469676Z  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:14:58.469955Z  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:14:58.470109Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.470289Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.470514Z  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:14:58.470854Z  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:14:58.471143Z  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:14:58.471507Z  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:14:58.448987Z  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:14:58.471963Z  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:14:58.472281Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.472428Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.457531Z 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:14:58.472655Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=635ecdd5-729d-4fe9-bdd3-3a10b432d78b in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.472782Z 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:14:58.473275Z 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:14:58.473394Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=fcc78841-f0ae-45bd-858e-b9c07c433eba in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.473899Z  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:14:58.474113Z  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=4 item_count=1 text_bytes=144 envelope_bytes=263 error_code="TIMEOUT"
[INFO] [stderr] 2026-10-06T14:14:58.475056Z  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:14:58.475120Z  INFO serve_inner: rmcp::service: cancelled id=1 reason="user cancelled"
[INFO] [stderr] 2026-10-06T14:14:58.475233Z  INFO serve_inner: rmcp::service: cancelled id=1 reason="user cancelled"
[INFO] [stderr] 2026-10-06T14:14:58.471291Z 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:14:58.475472Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=a8d8cace-d9bb-49d8-b4fa-19624d83e951 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.475618Z 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:14:58.475716Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=8062e7c6-e78a-4924-89c1-3e8ac1bc4e2c in_flight=2 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.475834Z 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:14:58.475974Z DEBUG figma_dev_mcp_broker::ws: discarding late or foreign response socket_id=a32373e1-c68f-41db-bcca-a75e8ee14e2f request_id=556037c2-990c-4853-8c33-efc5d9843302
[INFO] [stderr] 2026-10-06T14:14:58.472888Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=9dce1b56-c1d0-4532-8688-bdea26e82b15 in_flight=2 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.476251Z 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:14:58.476365Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=9f6ce7b5-e9d1-4d87-a861-1b5b7ccd9094 in_flight=3 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.476510Z 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:14:58.476644Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=212449ba-a78d-4222-8c48-706d0046a92a in_flight=4 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.476792Z 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:14:58.476899Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=a3a20d5f-5196-4b74-8da4-22cd39f8311b in_flight=4 queue_depth=1 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.477026Z 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:14:58.477136Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=22719557-8c3d-4d56-82ab-cb4fd3f68e0f in_flight=4 queue_depth=2 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.475978Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=5c584fd4-34d3-4dbf-93f9-3fcf9c81ae97 in_flight=3 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.477730Z 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:14:58.477765Z 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:14:58.477844Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=0612af37-da4b-4fe5-a701-0956c45f8a96 in_flight=4 queue_depth=3 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stdout] test resource_control::progress_resets_inactivity_but_not_the_total_deadline ... ok
[INFO] [stderr] 2026-10-06T14:14:58.478610Z DEBUG figma_dev_mcp_broker::ws: discarding late or foreign progress socket_id=8200a470-e7fc-436f-8709-005db7e99e97 request_id=51f9f3d6-d73c-4345-93c6-1724a41f08d1
[INFO] [stderr] 2026-10-06T14:14:58.478766Z 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:14:58.478879Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=5e4b0628-f2b1-4b28-a7f6-4d4c6c6d74c7 in_flight=4 queue_depth=4 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.479033Z 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:14:58.479154Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=499a8ae6-973f-4a38-9c42-9f051dc1bd3d in_flight=4 queue_depth=5 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.479292Z 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:14:58.479427Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=6498341c-8591-4705-9b84-3b0ec286176e in_flight=4 queue_depth=6 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.479562Z 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:14:58.479701Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=7266c3d9-22b8-443c-adee-c99779d5f788 in_flight=4 queue_depth=7 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.479880Z 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:14:58.480037Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=8696d7e5-cfbd-4547-b52c-41250ed04a01 in_flight=4 queue_depth=8 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.480252Z 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:14:58.480394Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=8b1157d1-47fc-4a0f-a60b-beebb0d839f5 in_flight=4 queue_depth=9 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.480513Z  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:14:58.480562Z 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:14:58.480659Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=53e208b9-97bd-4a55-ac04-73d03b21d238 in_flight=4 queue_depth=10 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.480829Z 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:14:58.480912Z  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:14:58.480935Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=82460a2e-3171-4f5f-9536-5477d582de12 in_flight=4 queue_depth=11 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.481057Z 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:14:58.481138Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=2650554c-92ef-48be-a8d5-6f5ae102b4ca in_flight=4 queue_depth=12 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.481246Z 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:14:58.477868Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=440f81c9-4e34-4508-9bcc-e972eb7eba3e in_flight=4 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.481348Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=a88a2889-49ae-4f18-9ef1-45212de7968e in_flight=4 queue_depth=13 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.480829Z  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:14:58.477592Z  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=2 item_count=1 text_bytes=158 envelope_bytes=277 error_code="CANCELLED"
[INFO] [stderr] 2026-10-06T14:14:58.478524Z 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::bounded_progress_is_forwarded_without_design_content ... ok
[INFO] [stderr] 2026-10-06T14:14:58.481874Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=3031b71b-6ed8-4a81-bb78-8e96852fd323 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.482772Z 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:14:58.482893Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=cb618602-c4c1-466b-9caf-3b3e51e0d3ec in_flight=4 queue_depth=14 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.482993Z 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:14:58.483068Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=bbe7702e-eb4d-47be-b3cc-1ab1b198d8e9 in_flight=4 queue_depth=15 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.483171Z 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:14:58.483252Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=81292af1-4bfd-4573-9216-d25b0f52b678 in_flight=4 queue_depth=16 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.483386Z 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:14:58.483591Z  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:14:58.473459Z 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:14:58.484418Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174001 request_id=0598afa0-7bfb-4f64-9900-86b5c9ccbade in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.485480Z  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:14:58.485477Z  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=3 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.485518Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.485925Z  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:14:58.486017Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.486025Z 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:14:58.486140Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=a6950ddd-6795-4bf4-b898-7b987ab7022b in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.486154Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.486408Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=1
[INFO] [stderr] 2026-10-06T14:14:58.486506Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.486733Z  INFO figma_dev_mcp_broker::supervisor: entered broker role role="leader" plugin_address="127.0.0.1:41029" plugin_address_v6="[::1]:41029" frontend_address="127.0.0.1:38807"
[INFO] [stderr] 2026-10-06T14:14:58.486774Z  INFO figma_dev_mcp_broker::supervisor: installed a new broker backend role="leader"
[INFO] [stderr] 2026-10-06T14:14:58.486987Z  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:14:58.487098Z  INFO figma_dev_mcp_tools::observability: tool completed request_id=3 tool_name="get_selection" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=0 item_count=1 text_bytes=504 envelope_bytes=652 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.487245Z  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:14:58.496700Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.496954Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.497479Z 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:14:58.497653Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174002 request_id=14797263-52de-4de6-885f-4b5e9231fe31 in_flight=1 queue_depth=0 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:14:58.498763Z 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:14:58.499002Z  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=1 item_count=1 text_bytes=488 envelope_bytes=781 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.486256Z  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:14:58.500735Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.500953Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.500296Z 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:14:58.487297Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=2
[INFO] [stderr] 2026-10-06T14:14:58.501365Z 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:14:58.501563Z  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:14:58.501602Z  WARN figma_dev_mcp_broker::supervisor: retrying election attempt=2 delay_ms=200
[INFO] [stderr] 2026-10-06T14:14:58.501791Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174002 request_id=ca78f4b8-d70f-4b90-89b9-1af272d8363f in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.501858Z  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] [stdout] test resource_control::inactivity_deadline_is_fifteen_seconds_without_progress ... ok
[INFO] [stderr] 2026-10-06T14:14:58.502761Z  INFO serve_inner: rmcp::service: task cancelled
[INFO] [stderr] 2026-10-06T14:14:58.501946Z  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:14:58.503641Z  INFO serve_inner: rmcp::service: task cancelled
[INFO] [stderr] 2026-10-06T14:14:58.503740Z  INFO serve_inner: rmcp::service: serve finished quit_reason=Cancelled
[INFO] [stderr] 2026-10-06T14:14:58.503882Z  INFO serve_inner: rmcp::service: input stream terminated
[INFO] [stderr] 2026-10-06T14:14:58.503954Z  INFO serve_inner: rmcp::service: serve finished quit_reason=Cancelled
[INFO] [stderr] 2026-10-06T14:14:58.504068Z  INFO serve_inner: rmcp::service: input stream terminated
[INFO] [stderr] 2026-10-06T14:14:58.504136Z  INFO serve_inner: rmcp::service: serve finished quit_reason=Closed
[INFO] [stderr] 2026-10-06T14:14:58.504853Z 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:14:58.504289Z  INFO serve_inner: rmcp::service: serve finished quit_reason=Closed
[INFO] [stderr] 2026-10-06T14:14:58.506550Z  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:14:58.507444Z  INFO serve_inner: rmcp::service: task cancelled
[INFO] [stderr] 2026-10-06T14:14:58.507546Z  INFO serve_inner: rmcp::service: serve finished quit_reason=Cancelled
[INFO] [stderr] 2026-10-06T14:14:58.507692Z  INFO serve_inner: rmcp::service: input stream terminated
[INFO] [stderr] 2026-10-06T14:14:58.507770Z  INFO serve_inner: rmcp::service: serve finished quit_reason=Closed
[INFO] [stdout] test runtime::the_service_is_up_before_any_election_has_happened ... ok
[INFO] [stderr] 2026-10-06T14:14:58.508296Z 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:14:58.510474Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=2 tool_name="get_nodes" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=42 item_count=2 text_bytes=796 envelope_bytes=966 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.511853Z 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 runtime::leader_stdio_closure_keeps_shared_runtime_alive_for_follower_and_plugin ... ok
[INFO] [stderr] 2026-10-06T14:14:58.511851Z  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 search::search_nodes_public_contract_trims_defaults_and_rejects_invalid_values ... ok
[INFO] [stderr] 2026-10-06T14:14:58.512005Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=ba8a8a3e-4639-4be3-bb69-d201c563d7cd in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.512167Z  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:14:58.512383Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.512534Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.512871Z  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:14:58.512896Z 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 resource_control::mcp_cancellation_reaches_the_plugin ... ok
[INFO] [stderr] 2026-10-06T14:14:58.512878Z 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:14:58.514448Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=8c2c4508-8a8a-499d-959f-d5346e3866ca in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.514630Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=3 tool_name="get_design_context" connection_id="123e4567-e89b-42d3-a456-426614174000" duration_ms=2 item_count=1 text_bytes=504 envelope_bytes=652 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.516045Z  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 navigation::navigation_tools_round_trip_through_server_broker_and_plugin ... ok
[INFO] [stderr] 2026-10-06T14:14:58.517470Z  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:14:58.517726Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.520861Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.520930Z  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=6 item_count=1 text_bytes=486 envelope_bytes=778 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.521632Z  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:14:58.521650Z 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:14:58.521804Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=4c1efcd6-4b43-46ec-96e7-c03c61e36957 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.521986Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.523113Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.523574Z 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:14:58.523667Z 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:14:58.523703Z  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:14:58.523766Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=6a7f91f4-68fb-46cf-a4d3-7db2bf6f8d7b in_flight=4 queue_depth=1 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.523882Z 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:14:58.523965Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=51747592-3d50-4f53-ace8-e287ac2b9b5a in_flight=4 queue_depth=2 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.524559Z 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:14:58.524651Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=d54a5f8a-17ca-4475-8955-618f54193a2e in_flight=4 queue_depth=3 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.525079Z  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:14:58.525133Z  INFO serve_inner: rmcp::service: cancelled id=7 reason="drop queued work"
[INFO] [stderr] 2026-10-06T14:14:58.525255Z  INFO serve_inner: rmcp::service: cancelled id=7 reason="drop queued work"
[INFO] [stderr] 2026-10-06T14:14:58.525508Z  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=0 item_count=1 text_bytes=158 envelope_bytes=277 error_code="CANCELLED"
[INFO] [stderr] 2026-10-06T14:14:58.527236Z 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 screenshot::screenshot_rejects_svg_scale_before_dispatch ... ok
[INFO] [stderr] 2026-10-06T14:14:58.532947Z  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:14:58.533290Z  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:14:58.533513Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.533679Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.534050Z 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:14:58.534256Z  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:14:58.535108Z  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=59 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.540061Z 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:14:58.540905Z  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=39 item_count=1 text_bytes=1041 envelope_bytes=2523 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.533294Z  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:14:58.539234Z  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=15 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.541839Z  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:14:58.542432Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=fc584f45-958e-4279-ab5d-ce381a838435 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.542561Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.543646Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.543059Z  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=18 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.544877Z 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 screenshot::jpeg_scale_and_the_three_svg_options_reach_the_plugin_intact ... ok
[INFO] [stderr] 2026-10-06T14:14:58.549509Z  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:14:58.551552Z 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:14:58.551850Z  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:14:58.552718Z 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:14:58.552840Z  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:14:58.553758Z 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_without_connection_id_rejects_ambiguous_sessions ... ok
[INFO] [stderr] 2026-10-06T14:14:58.553921Z  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:14:58.553964Z 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:14:58.554809Z 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:14:58.557952Z 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:14:58.558292Z  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=36 item_count=1 text_bytes=402 envelope_bytes=544 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.559486Z  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:14:58.561193Z  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 search::a_search_carrying_only_node_types_is_dispatched_with_those_types ... ok
[INFO] [stdout] test session_registry::outbound_queue_reports_full_without_waiting ... ok
[INFO] [stdout] test session_registry::duplicate_connection_id_is_rejected_but_duplicate_file_name_is_allowed ... ok
[INFO] [stdout] test session_registry::pending_completion_is_bound_to_socket_identity ... ok
[INFO] [stdout] test session_registry::pending_timeout_socket_close_and_shutdown_remove_each_entry_once ... ok
[INFO] [stdout] test session_registry::production_config_is_fixed_loopback_and_test_limits_cannot_raise_ceilings ... ok
[INFO] [stdout] test session_registry::selection_requires_exactly_one_live_session_and_never_falls_back ... ok
[INFO] [stderr] 2026-10-06T14:14:58.570898Z  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:14:58.571249Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.562633Z 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:14:58.557958Z  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=30 item_count=1 text_bytes=1041 envelope_bytes=2523 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.571559Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.569038Z  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:14:58.571952Z  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:14:58.572005Z 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:14:58.572149Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.572229Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174001 request_id=700057d7-d8fe-4bb4-8710-0ba4b5dde174 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.572283Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.573121Z 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:14:58.573323Z  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:14:58.574369Z 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 session_registry::stale_sessions_expire_and_socket_removal_is_idempotent ... ok
[INFO] [stderr] 2026-10-06T14:14:58.574486Z  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] [stderr] 2026-10-06T14:14:58.575074Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=aba70035-cd98-40b9-817d-05925b4ce1c8 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.578413Z 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:14:58.578608Z  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:14:58.575406Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=1
[INFO] [stderr] 2026-10-06T14:14:58.579379Z 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:14:58.577218Z  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=104 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.579889Z  INFO figma_dev_mcp_broker::supervisor: entered broker role role="follower" leader_address="127.0.0.1:46029"
[INFO] [stderr] 2026-10-06T14:14:58.579932Z  INFO figma_dev_mcp_broker::supervisor: installed a new broker backend role="follower"
[INFO] [stderr] 2026-10-06T14:14:58.580062Z  WARN figma_dev_mcp_broker::supervisor: broker backend died, re-electing cause=the leader's RPC connection closed
[INFO] [stderr] 2026-10-06T14:14:58.580089Z  INFO figma_dev_mcp_broker::supervisor: detached the broker backend
[INFO] [stderr] 2026-10-06T14:14:58.580110Z  WARN figma_dev_mcp_broker::supervisor: the previous role died immediately, delaying re-election alive_ms=0 floor_ms=399 consecutive=3
[INFO] [stderr] 2026-10-06T14:14:58.580225Z  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=107 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.580591Z  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=104 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.581154Z  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=104 item_count=1 text_bytes=654 envelope_bytes=816 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.584460Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=cc08e794-6ac9-488b-80c6-2c581e2324ea in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.585607Z 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:14:58.585899Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=9 tool_name="search_nodes" connection_id="-" duration_ms=6 item_count=1 text_bytes=402 envelope_bytes=544 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.602184Z 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:14:58.602556Z  INFO serve_inner: figma_dev_mcp_tools::observability: tool completed request_id=1 tool_name="search_nodes" connection_id="-" duration_ms=30 item_count=0 text_bytes=256 envelope_bytes=380 error_code="OK"
[INFO] [stderr] 2026-10-06T14:14:58.614119Z  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:14:58.614511Z  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:14:58.614766Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.614948Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.615160Z  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=40 item_count=0 text_bytes=254 envelope_bytes=378 error_code="OK"
[INFO] [stdout] test all_tools::two_clients_and_two_files_are_explicitly_routed ... ok
[INFO] [stdout] test strategy_resources::resources_list_is_the_three_strategies_and_is_publicly_cacheable ... ok
[INFO] [stdout] test search::search_nodes_without_connection_id_round_trips_and_rejects_invalid_scopes_before_dispatch ... ok
[INFO] [stdout] test resource_control::four_active_and_sixteen_queued_then_retryable_overflow ... ok
[INFO] [stdout] test resource_control::queue_admission_is_fifo_and_drop_cancels_queued_work ... ok
[INFO] [stdout] test search::search_nodes_without_connection_id_routes_to_the_reconnected_session ... ok
[INFO] [stderr] 2026-10-06T14:14:58.618978Z  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:14:58.615352Z  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:14:58.619324Z  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:14:58.619539Z  INFO serve_inner: rmcp::service: received notification notification=InitializedNotification(NotificationNoParam { method: InitializedNotificationMethod, extensions: Extensions })
[INFO] [stderr] 2026-10-06T14:14:58.619717Z  INFO serve_inner: rmcp::handler::server: client initialized
[INFO] [stderr] 2026-10-06T14:14:58.619919Z 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:14:58.620113Z  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:14:58.620803Z  WARN serve_inner: rmcp::service: response error id=3 error=ErrorData { code: ErrorCode(-32602), message: "resource 'file:///etc/passwd' not found", data: None }
[INFO] [stderr] 2026-10-06T14:14:58.624898Z 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] [stdout] test ws_origin::a_plugin_error_frame_completes_the_call_with_the_code_the_plugin_sent ... ok
[INFO] [stderr] 2026-10-06T14:14:58.622234Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=plugin protocol version mismatch
[INFO] [stdout] test strategy_resources::resources_read_reports_unknown_uris_as_invalid_params ... ok
[INFO] [stderr] 2026-10-06T14:14:58.623967Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=plugin socket closed
[INFO] [stderr] 2026-10-06T14:14:58.624071Z 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:14:58.627163Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=WebSocket error: HTTP error: 403 Forbidden
[INFO] [stderr] 2026-10-06T14:14:58.627241Z DEBUG serve_inner: figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=550372f8-e64f-407f-803f-999c7065e6c2 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.628807Z 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::accepts_exact_null_origin_and_rejects_missing_or_other_origins ... ok
[INFO] [stderr] 2026-10-06T14:14:58.631913Z 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 ws_origin::a_plugin_announcing_an_old_wire_version_is_refused ... ok
[INFO] [stdout] test ws_origin::an_unsolicited_control_pong_keeps_the_session_routable ... ok
[INFO] [stdout] test ws_origin::an_accepted_session_is_pinged_without_waiting_a_full_heartbeat ... ok
[INFO] [stderr] 2026-10-06T14:14:58.634971Z 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:14:58.635426Z 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] [stdout] test ws_origin::broker_shutdown_and_deadlines_resolve_pending_requests ... ok
[INFO] [stderr] 2026-10-06T14:14:58.635535Z 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] [stdout] test ws_origin::cancellation_reaches_the_owning_plugin_and_resolves_once ... ok
[INFO] [stderr] 2026-10-06T14:14:58.636620Z 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:14:58.641963Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=plugin socket closed
[INFO] [stderr] 2026-10-06T14:14:58.650699Z DEBUG figma_dev_mcp_broker::ws: plugin socket closed during setup or transport error=plugin heartbeat expired
[INFO] [stdout] test ws_origin::broker_shutdown_resolves_pending_calls_itself_not_by_socket_teardown ... ok
[INFO] [stdout] test ws_origin::close_and_heartbeat_expiry_remove_the_registered_session ... ok
[INFO] [stderr] 2026-10-06T14:14:58.665126Z 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 strategy_resources::resources_read_serves_the_same_text_as_prompts_get ... ok
[INFO] [stderr] 2026-10-06T14:14:58.666254Z  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=39 item_count=3 text_bytes=954 envelope_bytes=1430 error_code="OK"
[INFO] [stdout] test ws_origin::a_control_ping_is_answered_and_leaves_the_session_routable ... ok
[INFO] [stdout] test ws_origin::shutdown_interrupts_a_tcp_peer_stalled_before_websocket_upgrade ... ok
[INFO] [stdout] test screenshot::screenshot_round_trips_raster_bytes_and_validated_svg_source ... ok
[INFO] [stdout] test ws_origin::control_pongs_alone_hold_a_session_across_staleness_windows ... ok
[INFO] [stderr] 2026-10-06T14:14:58.682568Z 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:14:58.682927Z 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:14:58.685466Z 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:14:58.686064Z DEBUG figma_dev_mcp_broker::ws: discarding late or foreign error socket_id=b1a09fb4-2b9c-4d85-bde8-fea0d168413e request_id=request-1
[INFO] [stderr] 2026-10-06T14:14:58.690500Z 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::write_failure_cleans_session_and_allows_same_connection_id_to_reconnect ... ok
[INFO] [stdout] test ws_origin::every_frame_kind_advances_the_last_seen_the_registry_reports ... ok
[INFO] [stdout] test ws_origin::control_pings_alone_hold_a_session_across_staleness_windows ... ok
[INFO] [stderr] 2026-10-06T14:14:58.702522Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=3
[INFO] [stderr] 2026-10-06T14:14:58.702819Z  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:14:58.702855Z  WARN figma_dev_mcp_broker::supervisor: retrying election attempt=3 delay_ms=400
[INFO] [stdout] test conformance_adapter::adapter_catalog_is_the_production_tools_prompts_and_resources_surface ... ok
[INFO] [stderr] 2026-10-06T14:14:58.785095Z 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] [stderr] 2026-10-06T14:14:58.786192Z 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] [stdout] test search::search_nodes_rejects_a_call_with_neither_query_nor_types ... ok
[INFO] [stdout] test search::search_nodes_schema_exposes_flat_query_filters_limit_and_cursor ... ok
[INFO] [stderr] 2026-10-06T14:14:58.816429Z 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:14:58.816607Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=e0b28f04-8b2f-4488-bc28-b1ae11f9d702 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.817898Z  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:14:58.878882Z 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:14:58.879151Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=54ea3d3c-7cf1-47c8-977f-46d9bdc97a6a in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.880365Z  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:14:58.900277Z 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:14:58.900546Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=05c8f377-e7b2-4cdb-9f19-f43c1780c05e in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.901761Z  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:14:58.940518Z 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:14:58.940776Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=81616955-f8a2-42d8-a37e-c1c8cb7cabbf in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.942053Z  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:14:58.971336Z 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:14:58.971506Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=b71cd7b7-44eb-4e1d-8d33-805acc86ff8c in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.972950Z  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] [stderr] 2026-10-06T14:14:58.980554Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=1
[INFO] [stderr] 2026-10-06T14:14:58.981175Z  INFO figma_dev_mcp_broker::supervisor: entered broker role role="follower" leader_address="127.0.0.1:46029"
[INFO] [stderr] 2026-10-06T14:14:58.981213Z  INFO figma_dev_mcp_broker::supervisor: installed a new broker backend role="follower"
[INFO] [stderr] 2026-10-06T14:14:58.981362Z  WARN figma_dev_mcp_broker::supervisor: broker backend died, re-electing cause=the leader's RPC connection closed
[INFO] [stderr] 2026-10-06T14:14:58.981387Z  INFO figma_dev_mcp_broker::supervisor: detached the broker backend
[INFO] [stderr] 2026-10-06T14:14:58.981431Z 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:14:58.995490Z 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:14:58.995769Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=ba586d5f-8523-46cb-ad89-94f6d49201a2 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:58.997208Z  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:14:59.019061Z 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:14:59.019333Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=40c852ab-9a94-4649-b25c-9f35f70989a9 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:59.020514Z  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:14:59.040537Z 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:14:59.040790Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=a5a40e9f-d863-4d98-a366-d69dcdd936d8 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:59.042256Z  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:14:59.064793Z 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:14:59.065045Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=7808fcbb-1fef-4c7d-aed8-5fb58f412fcb in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:59.066413Z  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:14:59.090425Z 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:14:59.090674Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=d6f60f7a-e4ca-4aea-8f30-074a4fc463d3 in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:59.092597Z  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:14:59.103568Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=4
[INFO] [stderr] 2026-10-06T14:14:59.103944Z  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:14:59.104022Z  WARN figma_dev_mcp_broker::supervisor: retrying election attempt=4 delay_ms=800
[INFO] [stderr] 2026-10-06T14:14:59.121793Z 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:14:59.122067Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=45bb25c9-ecdc-4474-bd06-f670796c869e in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:59.123605Z  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] [stderr] 2026-10-06T14:14:59.148693Z 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:14:59.148950Z DEBUG figma_dev_mcp_broker: admitted broker request connection_id=123e4567-e89b-42d3-a456-426614174000 request_id=83604c0f-6658-4a3d-82d1-79632c6ee97a in_flight=1 queue_depth=0 inactivity_timeout_secs=15 total_timeout_secs=120
[INFO] [stderr] 2026-10-06T14:14:59.150924Z  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:14:59.176156Z 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:14:59.176470Z  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] [stdout] test all_tools::every_tool_and_prompt_round_trips_through_mcp_service ... ok
[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:14:59.786055Z DEBUG figma_dev_mcp_broker::supervisor: entered broker role role="follower" leader_address="127.0.0.1:46029"
[INFO] [stderr] 2026-10-06T14:14:59.786135Z DEBUG figma_dev_mcp_broker::supervisor: installed a new broker backend role="follower"
[INFO] [stderr] 2026-10-06T14:14:59.786279Z DEBUG figma_dev_mcp_broker::supervisor: broker backend died, re-electing cause=the leader's RPC connection closed
[INFO] [stderr] 2026-10-06T14:14:59.786326Z DEBUG figma_dev_mcp_broker::supervisor: detached the broker backend
[INFO] [stderr] 2026-10-06T14:14:59.786348Z 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:14:59.907432Z  INFO figma_dev_mcp_broker::supervisor: starting broker election attempt=5
[INFO] [stderr] 2026-10-06T14:14:59.907858Z  INFO figma_dev_mcp_broker::supervisor: entered broker role role="leader" plugin_address="127.0.0.1:38387" plugin_address_v6="[::1]:38387" frontend_address="127.0.0.1:45199"
[INFO] [stderr] 2026-10-06T14:14:59.907894Z  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:15:01.391087Z DEBUG figma_dev_mcp_broker::supervisor: entered broker role role="follower" leader_address="127.0.0.1:46029"
[INFO] [stderr] 2026-10-06T14:15:01.391166Z DEBUG figma_dev_mcp_broker::supervisor: installed a new broker backend role="follower"
[INFO] [stderr] 2026-10-06T14:15:01.391336Z DEBUG figma_dev_mcp_broker::supervisor: broker backend died, re-electing cause=the leader's RPC connection closed
[INFO] [stderr] 2026-10-06T14:15:01.391363Z DEBUG figma_dev_mcp_broker::supervisor: detached the broker backend
[INFO] [stderr] 2026-10-06T14:15:01.391386Z 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.11s
[INFO] [stdout] 
[INFO] [stderr]      Running policy/mod.rs (/opt/rustwide/target/debug/deps/policy-486259f906deac9b)
[INFO] [stdout] 
[INFO] [stdout] running 42 tests
[INFO] [stdout] test prompts::the_prompt_body_scan_reads_a_distinct_body_per_name_and_its_predicates_fire ... ok
[INFO] [stdout] test prompts::prompt_bodies_name_only_allowlisted_tools_and_reject_removed_or_mutating_guidance ... ok
[INFO] [stdout] test read_only::documentation_ci_pins_runtimes_and_never_publishes ... ok
[INFO] [stdout] test allowlists::mvp_prompt_allowlist_is_sorted_closed_and_exact ... ok
[INFO] [stdout] test manifest::plugin_contexts_keep_network_and_figma_apis_separate ... ok
[INFO] [stdout] test prompts::tool_reference_extraction_is_limited_to_backticked_snake_case_verbs ... ok
[INFO] [stdout] test read_only::documentation_covers_dev_mode_import_connection_selection_and_no_daemon ... ok
[INFO] [stdout] test manifest::manifest_is_the_exact_read_only_loopback_surface ... ok
[INFO] [stdout] test allowlists::mvp_tool_allowlist_is_sorted_closed_and_exact ... ok
[INFO] [stdout] test manifest::bundle_policy_requires_both_artifacts ... ok
[INFO] [stdout] test read_only::documentation_manual_acceptance_has_nine_spec_scenarios ... ok
[INFO] [stdout] test public_contracts::public_contract_wrappers_keep_protocol_types_behind_crate_private_conversions ... ok
[INFO] [stdout] test read_only::documentation_gitignore_keeps_lockfiles_and_snapshots_tracked ... ok
[INFO] [stdout] test read_only::documentation_forbids_local_export_instructions_and_unadvertised_product_tools ... ok
[INFO] [stdout] test manifest::the_plugin_context_walks_reach_real_source_and_the_separation_predicate_fires ... ok
[INFO] [stdout] test read_only::documentation_states_exact_ports_tools_and_prompts ... 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_lists_all_seven_local_verification_commands ... ok
[INFO] [stdout] test read_only::documentation_splits_stdio_evidence_from_official_lifecycle_smoke ... ok
[INFO] [stdout] test read_only::documentation_states_the_exact_strategy_resource_uris ... ok
[INFO] [stdout] test read_only::documentation_required_operator_files_exist ... ok
[INFO] [stdout] test read_only::documentation_states_svg_source_readonly_limits_and_origin_threat_model ... 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::serving_a_strategy_resource_never_reaches_the_broker ... ok
[INFO] [stdout] test read_only::origin_socket_and_rpc_boundaries_stay_raw_tcp_and_null_origin ... ok
[INFO] [stdout] test read_only::the_read_dispatcher_source_still_names_every_operation_it_dispatches ... ok
[INFO] [stdout] test read_only::the_canonical_message_scan_reaches_the_catalog_and_every_production_file ... ok
[INFO] [stdout] test manifest::production_dispatch_is_closed_and_removed_operations_stay_absent ... 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_read_dispatcher_mutates_no_process_global_host_state ... ok
[INFO] [stdout] test read_only::write_shaped_mcp_and_wire_requests_are_rejected ... ok
[INFO] [stdout] test read_only::the_shared_instructs_exempts_exactly_three_negation_prefixes ... 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::read_tests_share_one_figma_harness ... ok
[INFO] [stdout] test read_only::production_plugin_source_spells_no_canonical_error_message ... ok
[INFO] [stdout] test plugin_source::plugin_source_rejects_unbounded_page_font_and_mutation_surfaces ... ok
[INFO] [stdout] test read_only::plugin_source_denies_mutation_private_and_motion_write_apis ... 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 read_only::the_input_schema_scan_collects_names_from_every_level_it_claims_to_reach ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/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.38s
[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.08s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests figma_dev_mcp
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests figma_dev_mcp_broker
[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] [stderr]    Doc-tests figma_dev_mcp_protocol
[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_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" "d560a27ddc0fc3efb48b48de6d74cbab3ca819fd2e430d265bdfc1b898eeba16", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d560a27ddc0fc3efb48b48de6d74cbab3ca819fd2e430d265bdfc1b898eeba16", kill_on_drop: false }`
[INFO] [stdout] d560a27ddc0fc3efb48b48de6d74cbab3ca819fd2e430d265bdfc1b898eeba16