Lab #2408: serve: the device-token poll that returns the token takes 88s, making server-login to ops.swamp-club.com take ~90s

Description

swamp auth server-login --server https://ops.swamp-club.com takes about 90 seconds to complete, even when the code is approved in the browser right away. Almost all of that time is one request: the POST /auth/device/token poll that returns the token. The serve instance held that request open for 88.3 s before responding with 200.

The client and the network are not the cause. Every other request to the server took 120–360 ms round-trip, and opening the browser took 120 ms.

Evidence

Client debug log from a build with per-step timing logs (branch login-timeouts, commit b3559aa5). ops.swamp-club.com is not running that build, so server-timing is "none":

"GET" "/auth/info" -> 200 in 356ms
"POST" "/auth/device" -> 200 in 273ms (poll interval: 5s, expires in: 1800s)
Opening the browser took 120ms
15:21:49.615Z "POST" "/auth/device/token" -> 202 in 120ms
Poll 1 pending after 121ms; waiting 5000ms
15:23:22.897Z "POST" "/auth/device/token" -> 200 in 88280ms
Poll 2 returned a token in 88280ms (93404ms since polling began)
Server login completed in 94159ms after 2 poll(s)

Poll 2 was sent at about 15:21:54.6Z (2026-09-23), 5 s after the code was issued.

Where the time goes

handleDeviceToken in src/serve/device_auth_handler.ts does the following for a successful poll:

pollForToken and getUserInfo against the OAuth provider (swamp-club.com). Both share one AbortSignal.timeout(30_000), and a timeout would produce a 500 instead of a 200. So at most 30 s of the 88 s is the provider.
mintServerToken, wrapped in withSyncGate. It waits for the sync gate, then does the vault put, definition save, token resource write, pushChanged to the datastore, and a catalog invalidate plus definition read-back.
storeAccessToken, another vault put.

That leaves at least 58 s in steps 2–3. The most likely suspect is the sync gate wait. A poller pullChanged can hold the gate for up to POLLER_PULL_TIMEOUT_MS (120 s), and the mint waits for up to GATE_WAIT_TIMEOUT_MS (150 s) before going ahead ungated. The datastore push and the read-back after a catalog invalidate are the other candidates. The pollers do not log pull durations, so the current server logs cannot tell these apart.

Steps to reproduce

swamp auth server-login --server https://ops.swamp-club.com --log-level debug
Approve the code in the browser as soon as it opens.
The poll that returns the token takes about 88 s.

Next step

Deploy the login-timeouts build to ops.swamp-club.com and log in again. That build:

returns a Server-Timing header (upstream-poll, userinfo, mint, store-access-token, total), which the client prints at debug level next to each request's round-trip time;
logs on the server how long the mint waited for the sync gate, and a mint breakdown (vault put, definition, token resource, datastore push, read-back).

Environment

Client: dev build of branch login-timeouts on Linux
Server: ops.swamp-club.com, OAuth mode

A nested structural swamp still waits on its run's lock when the lock holder is not a same-host ancestor (cross-host worker, --server into a non-ancestor serve)

A nested structural swamp that outlives the run that started it keeps skipping that run's lock while the holder writes

Show signal waits to remote clients and the dashboard

Settle expired signal waits from swamp serve

Resume signalled workflow runs automatically under swamp serve

Deliver workflow signals through a write-once outcome record instead of writing the run record

Forward held-lock identity over --server so a loopback nested swamp does not wait on its caller's lock

A nested structural swamp on a same-host remote worker waits on its own step's lock held by swamp serve

Fail fast when nested structural commands under parallel runs wait on each other's locks

Docs: remove followUpActions from the MethodResult reference in the extension model manual

signal_test: already-settled test fails by chance when a random UUID spells the sender name

Add a wait_for_signal workflow step that pauses a run for a JSON message

Deliver workflow signals through swamp serve

run doctor through serve interrupts a live run of another serve instance when no heartbeats are recorded

Flaky test: WalSink replayed segments are delivered before events written after replay leaves one WAL segment

Remove followUpActions: no model or extension can produce them

Workflow schedule watcher and workflow edit symlink lookup ignore the managed config workflows dir

main is red: repository_dirty_coverage_test fails for UnifiedData.collectGarbage(orphaned deferred write)

isProcessGone always reports alive on Linux under the test task permissions, failing 9 tests on main

Flaky test: WalSink replayed segments are delivered before events written after replay

serve: relative tls cert-file/key-file and --config resolve against the working directory, not the repository

findBySpec/findByTag lose a workflow step's output after a version is deleted, pruned or rolled back

serve HA: worker enrollment on a replica that lacks the enrollment token's definition may create a second definition (unverified)

serve HA: pull a token's definition on an auth miss so a peer accepts a new token at once

Run tracker keeps some interrupted workflow rows forever: retention waits for markSettled, which several paths never call

run doctor reads every run record on each run to rebuild the run indexes

Serve audit: two sinks with the same name share one emitter cursor, so the second never receives events

Serve audit: a durable sink write that times out is retried while the first call is still pending, so the store can hold duplicate sequences

Flaky test: ModelResolver data accessors for a renamed model return the earlier id's record from findBySpec

data query --limit returns fewer live records than exist and reports limited: false when stale catalog rows fall inside the limit

data get: help example passes --run latest, which is not a recognised run id

workflow reject leaves sibling gates waiting_approval and dependents pending in a failed, retryable run

Show expired approval gates as expired, with a Cancel action in the dashboard

digitalocean codegen leaves an orphaned security_secret.ts model file after its endpoint left the spec

workflow cancel cannot cancel a suspended run whose workflow file was deleted, and cancel --all silently skips it

A job alone in its level starts after the abort, and its unstarted step is recorded as a real failure instead of settledByAbort

workflow approve racing workflow cancel loses the cancel: the run returns to suspended after cancel reported cancelled

data query: does not follow data rename forwarding that data get follows

Unit tests share fixed /tmp catalog paths, so concurrent runs corrupt them and fail every later run

data query: renamed data items are not found by their old name

data query: model lookup by id and orphan recovery parity with data get

workflow approve on a run that is not suspended prints a fatal error with a stack trace

data query: no way to target a workflow's latest run

data query: binary content lacks contentEncoding/base64 parity with data get

data query: no fallback from spec name to data instance name

data query: no definitionHash or garbageCollection fields in query results

A nested structural swamp spawned by swamp serve skips every lock serve holds, including other runs' locks

data query: confirm catalog parity with data get for custom datastores

data query: single-result --json mode for scripts migrating from data get

Docs: document data query --single and fix the stale --limit default in reference/data.md

data get --workflow silently returns an arbitrary step's data when several steps share a data name

swamp doctor workflows misses nested and .yml extension workflows that the loader reads

auth whoami says the stored API key is no longer valid when auth.json only holds an env-key identity cache

Docs: swamp skill says cancelling a parent run cancels its nested child runs; the child is left suspended

Namespace advice in the slow-lock warning is wrong for a single repo on a local datastore

Method-run records left running by a dead owner are never reaped

Flaky: usecase_sync_characterization_data_test 'data gc (serve)' asserts markDirty order that varies under parallel load

Worker dispatch rebuilds the definition with Definition.create, so a legacy-named model cannot run on a worker

A cancelled workflow exits before its steps stop, leaving their method-run records at running

Nested swamp structural command in a shell step times out on its parent's per-model lock (SWAMP_LOCK_HOLDER_PID is stripped)

Auth gate blocks nested swamp in workflow shell steps when the credential is in SWAMP_API_KEY

swamp workflow create crashes with an [FTL] ZodError stack trace on an invalid (uppercase) workflow name

swamp model create crashes with an [FTL] ZodError stack trace on an invalid (uppercase) model name

model cancel SIGTERMs a running swamp serve process when the method run belongs to a serve-run workflow

Follow-up action retries ignore the run's abort signal and can outlast a cancel

model cancel --all on a running workflow leaves its method-run records at running, or records them failed instead of cancelled

model cancel SIGKILLs the owner 2 s after SIGTERM, before the step executor's own 3 s kill grace, so the run never records its own cancellation

workflow cancel and supersede of a suspended run leave its jobs running in the cancelled record

Tell timeouts apart from cancels in method-run records, and review the hidden 30 s fallback timer for step-called model methods

A force-exited workflow run stays running with a dead pid: resume, recover and run doctor --fix cannot clear it

A nested workflow that suspends on manual approval forwards its suspended event into the parent run's stream

workflow cancel SIGKILLs the run 2 s after SIGTERM, cutting off always/completed cleanup jobs mid-step

Warn when datastore setup keeps managedConfig but the new datastore has no config tier

Docs: run doctor --fix and workflow recover settle runs left running by a force-exited process

serve: a relative grants-dir resolves against the working directory, not the repository

workflow resume cleanup mode can run an always-gated teardown before a suspended job's approved work

Docs: serve-flags.md should state that relative grants-dir and grants-file resolve against the repository

managedConfig: extension writes update the shared lockfile from a stale cache and overwrite peers' entries

managedConfig: model/vault create and other bulk-push commands re-upload a stale extension lockfile and erase peers' entries

Cancel, reject and supersede of a parent run should settle its suspended nested child runs