Opencode took too long to start

[Blue101black]

Hey, had a sandbox fail because opencode took too long to download models.dev catalog?

Some logs below

{"level": "info", "service": "modal-infra", "component": "httpx", "event": "HTTP Request: GET http://localhost:4096/global/health \"HTTP/1.1 200 OK\"", "ts": 1781769725293}
{"level": "info", "service": "modal-infra", "component": "supervisor", "event": "opencode.ready", "ts": 1781769725295, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e"}
{"level": "info", "service": "modal-infra", "component": "supervisor", "event": "bridge.start", "ts": 1781769725295, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e"}
{"level": "info", "service": "modal-infra", "component": "supervisor", "event": "bridge.started", "ts": 1781769725298, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e"}
{"level": "info", "service": "modal-infra", "component": "bridge", "event": "bridge.timeout_config", "ts": 1781769725784, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e", "timeout_name": "BRIDGE_SSE_INACTIVITY_TIMEOUT", "timeout_ms": 120000, "min_ms": 5000, "max_ms": 3600000}
{"level": "info", "service": "modal-infra", "component": "bridge", "event": "bridge.run_start", "ts": 1781769725784, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e"}
{"level": "info", "service": "modal-infra", "component": "supervisor", "event": "sandbox.startup", "ts": 1781769725799, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e", "repo_owner": "<redacted>", "repo_name": "<redacted>", "boot_mode": "repo_image", "restored_from_snapshot": false, "from_repo_image": true, "git_sync_success": true, "setup_success": null, "start_success": true, "opencode_ready": true, "duration_ms": 73271, "outcome": "success"}
[opencode] ERROR 2026-06-18T08:02:14 +9670ms service=default Failed to fetch models.dev [object Object]
{"level": "warning", "service": "modal-infra", "component": "bridge", "event": "bridge.disconnect", "ts": 1781769735852, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e", "reason": "connection_error", "detail": "timed out during opening handshake"}
{"level": "info", "service": "modal-infra", "component": "bridge", "event": "bridge.reconnect", "ts": 1781769735853, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e", "attempt": 1, "delay_s": 2.0}
{"level": "info", "service": "modal-infra", "component": "bridge", "event": "bridge.connect", "ts": 1781769740030, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e", "outcome": "success"}
{"level": "info", "service": "modal-infra", "component": "bridge", "event": "prompt.start", "ts": 1781769740031, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e", "message_id": "45c2f2f4ec11d7d0089b121bfde4d154", "model": "openai/gpt-5.5", "reasoning_effort": null}
[opencode] INFO  2026-06-18T08:02:20 +5109ms service=server method=POST path=/session opencode.server.backend=hono opencode.server.backend.reason=stable opencode.installation.channel=latest opencode.installation.version=1.14.41 request
[opencode] INFO  2026-06-18T08:02:20 +0ms service=server status=started method=POST path=/session opencode.server.backend=hono opencode.server.backend.reason=stable opencode.installation.channel=latest opencode.installation.version=1.14.41 request
[opencode] INFO  2026-06-18T08:02:20 +6ms service=default creating instance [object Object]
[opencode] INFO  2026-06-18T08:02:20 +1ms service=project directory=/workspace/<redacted> fromDirectory
[opencode] INFO  2026-06-18T08:02:20 +178ms service=default bootstrapping [object Object]
[opencode] INFO  2026-06-18T08:02:20 +195ms service=config path=/workspace/<redacted>/.opencode/opencode.json loading
[opencode] INFO  2026-06-18T08:02:20 +1ms service=config path=/workspace/<redacted>/.opencode/opencode.jsonc loading
[opencode] INFO  2026-06-18T08:02:20 +43ms service=plugin name=J8 loading internal plugin
[opencode] INFO  2026-06-18T08:02:20 +1ms service=plugin name=Y8 loading internal plugin
[opencode] INFO  2026-06-18T08:02:20 +0ms service=plugin name=RF loading internal plugin
[opencode] INFO  2026-06-18T08:02:20 +0ms service=plugin name=$$ loading internal plugin
[opencode] INFO  2026-06-18T08:02:20 +0ms service=plugin name=hF loading internal plugin
[opencode] INFO  2026-06-18T08:02:20 +1ms service=plugin name=uF loading internal plugin
[opencode] INFO  2026-06-18T08:02:20 +0ms service=plugin name=yF loading internal plugin
{"level": "error", "service": "modal-infra", "component": "bridge", "event": "prompt.error", "ts": 1781769770068, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e", "message_id": "45c2f2f4ec11d7d0089b121bfde4d154", "error_type": "ReadTimeout", "error_message": "", "error_stack": " exception:\n\nTraceback (most recent call last):\n  File \"/app/sandbox_runtime/bridge.py\", line 626, in _handle_prompt\n    await self._create_opencode_session()\n  File \"/app/sandbox_runtime/bridge.py\", line 690, in _create_opencode_session\n    resp = await self.http_client.post(\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/local/lib/python3.12/site-packages/httpx/_client.py\", line 1859, in post\n    return await self.request(\n           ^^^^^^^^^^^^^^^^^^^\n  File \"/usr/local/lib/python3.12/site-packages/httpx/_client.py\", line 1540, in request\n    return await self.send(request, auth=auth, follow_redirects=follow_redirects)\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/local/lib/python3.12/site-packages/httpx/_client.py\", line 1629, in send\n    response = await self._send_handling_auth(\n               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/local/lib/python3.12/site-packages/httpx/_client.py\", line 1657, in _send_handling_auth\n    response = await self._send_handling_redirects(\n               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/local/lib/python3.12/site-packages/httpx/_client.py\", line 1694, in _send_handling_redirects\n    response = await self._send_single_request(request)\n               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/local/lib/python3.12/site-packages/httpx/_client.py\", line 1730, in _send_single_request\n    response = await transport.handle_async_request(request)\n               ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/local/lib/python3.12/site-packages/httpx/_transports/default.py\", line 393, in handle_async_request\n    with map_httpcore_exceptions():\n         ^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/local/lib/python3.12/contextlib.py\", line 158, in __exit__\n    self.gen.throw(value)\n  File \"/usr/local/lib/python3.12/site-packages/httpx/_transports/default.py\", line 118, in map_httpcore_exceptions\n    raise mapped_exc(message) from exc\nhttpx.ReadTimeout"}
{"level": "info", "service": "modal-infra", "component": "bridge", "event": "prompt.run", "ts": 1781769770074, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e", "message_id": "45c2f2f4ec11d7d0089b121bfde4d154", "model": "openai/gpt-5.5", "reasoning_effort": null, "outcome": "error", "duration_ms": 30042}
Filesystem snapshot taken (image id: im-Nh5ZhEh9zNeVetkX24xp1n): 3882 files scanned, 64.19 MiB uploaded, 13.0s
[opencode] INFO  2026-06-18T08:06:40 +259879ms service=plugin path=file:///workspace/<redacted>/.opencode/plugins/codex-auth-plugin.js loading plugin
[opencode] INFO  2026-06-18T08:06:40 +14ms service=bus type=* subscribing
[opencode] INFO  2026-06-18T08:06:40 +3ms service=lsp all LSPs are disabled
[opencode] INFO  2026-06-18T08:06:40 +1ms service=bus type=session.updated subscribing
[opencode] INFO  2026-06-18T08:06:40 +0ms service=bus type=message.updated subscribing
[opencode] INFO  2026-06-18T08:06:40 +0ms service=bus type=message.part.updated subscribing
[opencode] INFO  2026-06-18T08:06:40 +0ms service=bus type=session.diff subscribing
[opencode] INFO  2026-06-18T08:06:40 +0ms service=bus type=session.deleted subscribing
[opencode] INFO  2026-06-18T08:06:40 +1ms service=format all formatters are disabled
[opencode] INFO  2026-06-18T08:06:40 +0ms service=format init
[opencode] INFO  2026-06-18T08:06:40 +5ms service=file.watcher directory=/workspace/<redacted> init
[opencode] INFO  2026-06-18T08:06:40 +5ms service=file.watcher directory=/workspace/<redacted> platform=linux backend=inotify watcher backend
[opencode] INFO  2026-06-18T08:06:40 +3ms service=bus type=command.executed subscribing
[opencode] INFO  2026-06-18T08:06:40 +51ms service=session id=ses_1263b67baffeoz9e59MFzPBp4w slug=cosmic-tiger version=1.14.41 projectID=015fc46da112aa043172341c6f59be68ab4b2934 directory=/workspace/<redacted> path= title=New session - 2026-06-18T08:06:40.453Z time={"created":1781770000453,"updated":1781770000453} created
[opencode] INFO  2026-06-18T08:06:40 +5ms service=bus type=session.created publishing
[opencode] INFO  2026-06-18T08:06:40 +1ms service=bus type=session.updated publishing
[opencode] INFO  2026-06-18T08:06:40 +5ms service=server status=completed duration=260399 method=POST path=/session opencode.server.backend=hono opencode.server.backend.reason=stable opencode.installation.channel=latest opencode.installation.version=1.14.41 request
[opencode] INFO  2026-06-18T08:06:40 +7ms service=ripgrep url=https://github.com/BurntSushi/ripgrep/releases/download/15.1.0/ripgrep-15.1.0-x86_64-unknown-linux-musl.tar.gz downloading ripgrep
[opencode] INFO  2026-06-18T08:06:40 +17ms service=vcs branch=develop default_branch=develop initialized
[opencode] INFO  2026-06-18T08:06:40 +1ms service=bus type=file.watcher.updated subscribing
Filesystem snapshot taken (image id: im-ugKXgsAkeEWjfwsBBBJnTK): 7598 files scanned, 69.46 MiB uploaded, 6.1s
{"level": "info", "service": "modal-infra", "component": "bridge", "event": "bridge.reconnect", "ts": 1781770380179, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e", "attempt": 1, "delay_s": 2.0}
{"level": "info", "service": "modal-infra", "component": "bridge", "event": "bridge.disconnect", "ts": 1781770382430, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e", "reason": "session_terminated", "detail": "Session rejected by control plane (HTTP 410)."}
{"level": "info", "service": "modal-infra", "component": "supervisor", "event": "bridge.graceful_exit", "ts": 1781770383303, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e", "exit_code": 0}
{"level": "info", "service": "modal-infra", "component": "supervisor", "event": "supervisor.shutdown_start", "ts": 1781770383304, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e"}
{"level": "info", "service": "modal-infra", "component": "supervisor", "event": "ttyd_proxy.terminating", "ts": 1781770383304, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e"}
{"level": "info", "service": "modal-infra", "component": "supervisor", "event": "ttyd.terminating", "ts": 1781770383308, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e"}
{"level": "info", "service": "modal-infra", "component": "supervisor", "event": "ttyd.stdout", "ts": 1781770383309, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e", "line": "[2026/06/18 08:13:03:3092] N: received signal: SIGTERM (15), exiting..."}
{"level": "info", "service": "modal-infra", "component": "supervisor", "event": "ttyd.stdout", "ts": 1781770383309, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e", "line": "[2026/06/18 08:13:03:3093] N: send ^C to force exit."}
{"level": "info", "service": "modal-infra", "component": "supervisor", "event": "supervisor.shutdown_complete", "ts": 1781770393312, "sandbox_id": "sandbox-<redacted>-<redacted>-1781769643143", "session_id": "9c67a707dcedd50f73514cabc29be11e"}

[ColeMurray]

Thanks for the report — this is a cold-start timeout on OpenCode's POST /session, and we've just merged a fix: #790.

What the logs actually show

The Failed to fetch models.dev line is a red herring — that fetch fails fast (~9.6s in) and is non-blocking. The real signal is POST /session:

• the bridge issues POST /session and hits ReadTimeout at duration_ms: 30042 (its 30s OPENCODE_REQUEST_TIMEOUT);
• but OpenCode itself logs the same request eventually finishing: status=completed duration=260399 method=POST path=/session — i.e. ~260s, right after loading plugin .../codex-auth-plugin.js.

So POST /session blocked for ~4 minutes; the bridge gave up at 30s and surfaced "Execution failed". The cost is an npm install that runs during session bootstrap.

Root cause: a plugin turns OpenCode's per-directory npm install into a blocking prerequisite of POST /session — and one of those directories is the global config dir we never seed

POST /session runs OpenCode's per-directory bootstrap — config.get() then plugin.init() (project/bootstrap.ts#L36-L42). The chain (OpenCode anomalyco/opencode @ v1.14.41):

1. A plugin is present. This session is openai/gpt-5.5, so we deploy .opencode/plugins/codex-auth-plugin.js (the OAuth proxy — it's the loading plugin codex-auth-plugin.js line in your log). OpenCode globs {plugin,plugins}/*.{ts,js} and records it: config/plugin.ts#L30-L40.

2. A present plugin flips dependency installation from fire-and-forget to blocking. plugin.init() runs if (plugins.length) yield* config.waitForDependencies(): plugin/index.ts#L164. With no plugin this is skipped and the install stays in the background — which is why pure-Anthropic sessions usually dodge this.

3. The install runs for every directory in the config search path. config.get() loops over ConfigPaths.directories(...) and forks npm install @opencode-ai/plugin for each one: config/config.ts#L586-L621. Note the .opencode-suffix check at L587 only gates loading opencode.json; the install (L600-L608, forked at L619) runs unconditionally for every directory.

4. waitForDependencies then joins all of those installs. config/config.ts#L772-L776 (Fiber.join over every forked install). So POST /session blocks until they all complete.

5. That directory list includes the global config dir — it's always first. ConfigPaths.directories returns [Global.Path.config, …repo .opencode dirs]: config/paths.ts#L23-L41.

6. The global config dir is created empty on every startup, and we never seeded it. Global.Path.config = $XDG_CONFIG_HOME/opencode (≈ ~/.config/opencode, defined at core/global.ts#L12), and OpenCode mkdirs it empty at module load: core/global.ts#L33-L40. We pre-stage an in-sync @opencode-ai/plugin tree (/app/opencode-deps) and copy it into the repo's .opencode/ (_install_tools), but nothing seeded ~/.config/opencode.

7. Empty dir → a real arborist.reify() (npm install, no internal timeout). With no node_modules, Npm.install → checkNodeModules reifies: core/npm.ts#L137-L155 (reify itself at #L78-L111). On a cold/slow install that blows past the bridge's 30s budget → httpx.ReadTimeout → "Execution failed".

We confirmed this on a live sandbox: ~/.config/opencode/ ends up containing a package.json of exactly {"dependencies":{"@opencode-ai/plugin":"1.14.41"}} — arborist's synthesized manifest, not our staged file — proving OpenCode reified that directory itself.

Aside: _wait_for_health() polls GET /global/health, which is a static {healthy:true} mounted outside the instance middleware (server/routes/global.ts#L77-L94), so it returns 200 while /session is still blocked — readiness false-greens and the prompt dispatches straight into the wait.

The fix — #790

Seed OpenCode's global config dir with the same pre-staged plugin tree we already stage for the repo dir, so the cold-start install is a no-op for every session at zero per-session cost. This removes the specific directory that actually reifies. Merged and rolling out to sandboxes.

Defense-in-depth follow-ups we're tracking (not required for this fix): a dedicated longer POST /session timeout + retry in the bridge, and warming the bootstrap before announcing ready (which also covers repos that ship their own .opencode/ npm dependencies).