Issue22033 real application runtime; commit ea3642a487 Linux fs-27, web1280x800, ClaudeSonnet5.5 CLI2.1.303.
Isolated .t3 state. Real SDK/provider; no injected frames or stubs. Long60echo prompt; queued followup; Stop; CLI-only backgroundNode30s+foregroundNode26s+300word reply; notification launches backgroundAgent60echoes; later CLI ping.
Projection observed 2026-10-24T10:53:46.235080+00:00
RUN {"id": "run:thread:7a62ce4c-b5f9-42e3-845f-bad1e0186d08:ordinal:1", "threadId": "7a62ce4c-b5f9-42e3-845f-bad1e0186d08", "ordinal": 1, "providerInstanceId": "claudeAgent", "modelSelection": {"instanceId": "claudeAgent", "model": "claude-sonnet-5-5"}, "providerThreadId": "provider-thread:provider:claudeAgent:native-thread:pending%3Arun%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A1", "userMessageId": "28389b9e-7ade-4e21-8236-05ff1af236c7", "rootNodeId": "node:run:run%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A1:root", "activeAttemptId": "run-attempt:run:run%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A1:attempt:1", "status": "interrupted", "queuePosition": null, "requestedAt": "2026-10-24T10:50:33.588Z", "startedAt": "2026-10-24T10:50:34.227Z", "completedAt": "2026-10-24T10:50:36.108Z", "checkpointId": null, "contextHandoffId": null, "workspacePreparation": {"type": "root"}}
RUN {"id": "run:thread:7a62ce4c-b5f9-42e3-845f-bad1e0186d08:ordinal:2", "threadId": "7a62ce4c-b5f9-42e3-845f-bad1e0186d08", "ordinal": 2, "providerInstanceId": "claudeAgent", "modelSelection": {"instanceId": "claudeAgent", "model": "claude-sonnet-5-5"}, "providerThreadId": "provider-thread:provider:claudeAgent:native-thread:pending%3Arun%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A1", "userMessageId": "57f462f6-4b91-4303-9613-f0414c2495c7", "rootNodeId": "node:run:run%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A2:root", "activeAttemptId": "run-attempt:run:run%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A2:attempt:1", "status": "queued", "queuePosition": 1, "queueHeld": true, "requestedAt": "2026-10-24T10:50:35.795Z", "startedAt": null, "completedAt": null, "checkpointId": null, "contextHandoffId": null}
RUN {"id": "run:thread:7a62ce4c-b5f9-42e3-845f-bad1e0186d08:ordinal:3", "threadId": "7a62ce4c-b5f9-42e3-845f-bad1e0186d08", "ordinal": 3, "providerInstanceId": "claudeAgent", "modelSelection": {"instanceId": "claudeAgent", "model": "claude-sonnet-5-5"}, "providerThreadId": "provider-thread:provider:claudeAgent:native-thread:pending%3Arun%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A1", "userMessageId": "message:mcp:client%3Aacd3ebc1-4299-4e5b-be6a-99cb154fbb65:thread-send:03cf0ef3-101e-4a01-ae93-d927beb454c8", "rootNodeId": "node:run:run%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A3:root", "activeAttemptId": "run-attempt:run:run%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A3:attempt:1", "status": "completed", "queuePosition": null, "requestedAt": "2026-10-24T10:50:52.315Z", "startedAt": "2026-10-24T10:50:52.394Z", "completedAt": "2026-10-24T10:51:30.746Z", "checkpointId": "checkpoint:scope:checkpoint-scope%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aname%3Aroot:name:3", "contextHandoffId": null}
RUN {"id": "run:thread:7a62ce4c-b5f9-42e3-845f-bad1e0186d08:ordinal:4", "threadId": "7a62ce4c-b5f9-42e3-845f-bad1e0186d08", "ordinal": 4, "providerInstanceId": "claudeAgent", "modelSelection": {"instanceId": "claudeAgent", "model": "claude-sonnet-5-5"}, "providerThreadId": "provider-thread:provider:claudeAgent:native-thread:pending%3Arun%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A1", "userMessageId": "message:thread:7a62ce4c-b5f9-42e3-845f-bad1e0186d08:ordinal:1:4a25b39c-48a2-4586-a1b4-a14e1c463636", "rootNodeId": "node:run:run%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A4:root", "activeAttemptId": "run-attempt:run:run%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A4:attempt:1", "status": "completed", "queuePosition": null, "requestedAt": "2026-10-24T10:51:30.716Z", "startedAt": "2026-10-24T10:51:32.295Z", "completedAt": "2026-10-24T10:51:36.132Z", "checkpointId": "checkpoint:scope:checkpoint-scope%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aname%3Aroot:name:4", "contextHandoffId": null, "workStartedAt": "2026-10-24T10:50:52.394Z"}
RUN {"id": "run:thread:7a62ce4c-b5f9-42e3-845f-bad1e0186d08:ordinal:5", "threadId": "7a62ce4c-b5f9-42e3-845f-bad1e0186d08", "ordinal": 5, "providerInstanceId": "claudeAgent", "modelSelection": {"instanceId": "claudeAgent", "model": "claude-sonnet-5-5"}, "providerThreadId": "provider-thread:provider:claudeAgent:native-thread:pending%3Arun%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A1", "userMessageId": "message:mcp:client%3A7988c282-b2d8-40ed-a16d-4c4a5dd9cb6b:thread-send:e9ec6603-d58e-4d28-a393-ada531402098", "rootNodeId": "node:run:run%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A5:root", "activeAttemptId": "run-attempt:run:run%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A5:attempt:1", "status": "completed", "queuePosition": null, "requestedAt": "2026-10-24T10:52:37.216Z", "startedAt": "2026-10-24T10:52:37.326Z", "completedAt": "2026-10-24T10:52:38.896Z", "checkpointId": "checkpoint:scope:checkpoint-scope%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aname%3Aroot:name:5", "contextHandoffId": null}
RUN {"id": "run:thread:7a62ce4c-b5f9-42e3-845f-bad1e0186d08:ordinal:6", "threadId": "7a62ce4c-b5f9-42e3-845f-bad1e0186d08", "ordinal": 6, "providerInstanceId": "claudeAgent", "modelSelection": {"instanceId": "claudeAgent", "model": "claude-sonnet-5-5"}, "providerThreadId": "provider-thread:provider:claudeAgent:native-thread:pending%3Arun%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A1", "userMessageId": "message:thread:7a62ce4c-b5f9-42e3-845f-bad1e0186d08:ordinal:1:288de8b6-61f2-4655-a2db-6f86b0cf60af", "rootNodeId": "node:run:run%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A6:root", "activeAttemptId": "run-attempt:run:run%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A6:attempt:1", "status": "completed", "queuePosition": null, "requestedAt": "2026-10-24T10:53:24.087Z", "startedAt": "2026-10-24T10:53:24.171Z", "completedAt": "2026-10-24T10:53:25.678Z", "checkpointId": "checkpoint:scope:checkpoint-scope%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aname%3Aroot:name:6", "contextHandoffId": null, "workStartedAt": "2026-10-24T10:52:37.326Z"}
SUBAGENT {"id":"node:provider:claudeAgent:native-item:task%3Aa6e4d76ef03e6d065","threadId":"7a62ce4c-b5f9-42e3-845f-bad1e0186d08","runId":"run:thread:7a62ce4c-b5f9-42e3-845f-bad1e0186d08:ordinal:4","parentNodeId":"node:run:run%3Athread%3A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%3Aordinal%3A4:root","origin":"provider_native","createdBy":"agent","driver":"claudeAgent","providerInstanceId":"claudeAgent","providerThreadId":null,"childThreadId":"thread:provider:claudeAgent:native-thread:provider-thread%3Aprovider%3AclaudeAgent%3Anative-thread%3Apending%253Arun%253Athread%253A7a62ce4c-b5f9-42e3-845f-bad1e0186d08%253Aordinal%253A1%3Aa6e4d76ef03e6d065","nativeTaskRef":{"driver":"claudeAgent","nativeId":"a6e4d76ef03e6d065","strength":"strong"},"prompt":"Synthetic task, no file changes. Make 60 separate foreground Bash calls strictly sequentially, exactly one echo per call: echo probe3-step-1 through echo probe3-step-60. No loops, no background tools. After all sixty calls reply exactly PROBE_22033_OK.","title":"issue22033 lineage probe","model":"claude-sonnet-5-5","modelSelection":{"instanceId":"claudeAgent","model":"claude-sonnet-5-5","options":[{"id":"effort","value":"high"}]},"status":"completed","progress":"Running Echo probe step 60","result":"PROBE_22033_OK","startedAt":"2026-10-24T10:51:34.042Z","completedAt":"2026-10-24T10:53:24.070Z","updatedAt":"2026-10-24T10:53:24.070Z"}
NATIVE [2026-10-24T10:50:57.017Z] {"type": "system", "subtype": "task_started", "task_id": "bvj1g3g0k", "task_type": "local_bash", "tool_use_id": "toolu_01MoLNBcdtcgsNGmwD7xXVKF", "description": "Run 30s timer in background", "session_id": "20c799ce-2073-4b25-babc-d341b2ed0701", "uuid": "cf09e274-9904-438e-b664-b94a309ded16"}
NATIVE [2026-10-24T10:51:01.354Z] {"type": "system", "subtype": "task_started", "task_id": "bjplwpdw8", "task_type": "local_bash", "tool_use_id": "toolu_01F3MQJeeGsaSKTs7ojNmexj", "description": "Run 26s timer in foreground", "session_id": "20c799ce-2073-4b25-babc-d341b2ed0701", "uuid": "6c8a5091-fa74-4fb5-84dd-66b81dfee226"}
NATIVE [2026-10-24T10:51:24.444Z] {"type": "system", "subtype": "task_notification", "task_id": "bjplwpdw8", "tool_use_id": "toolu_01F3MQJeeGsaSKTs7ojNmexj", "status": "completed", "summary": "Run 26s timer in foreground", "session_id": "20c799ce-2073-4b25-babc-d341b2ed0701", "uuid": "a23125b3-0a3a-4bee-b7df-bc6ed2adb1c9"}
NATIVE [2026-10-24T10:51:27.134Z] {"type": "system", "subtype": "task_notification", "task_id": "bvj1g3g0k", "tool_use_id": "toolu_01MoLNBcdtcgsNGmwD7xXVKF", "status": "completed", "summary": "Background command \"Run 30s timer in background\" completed (exit code 0)", "session_id": "20c799ce-2073-4b25-babc-d341b2ed0701", "uuid": "5507c423-9862-4023-bd74-98331b7f7fd1"}
NATIVE [2026-10-24T10:51:30.629Z] {"type": "result", "subtype": "success", "result": "Both timers are now set. The 30-second one is running in the background, and the 26-second one has finished in the foreground. I'm using this turn to write the reply you asked for, so the turn ends while the background timer is still pending.\n\nThis is a synthetic probe for issue 22033, which concerns wake latching. The background command should finish a few seconds after this reply ends. Its completion notification should then wake the session and start a new turn.\n\nWhat matters is the order of events. I started the background timer first, so it began counting down before anything else happened. The foreground timer ran for 26 seconds while the background one kept running. That leaves roughly four seconds between the foreground call returning and the background process exiting. This reply is written inside that gap.\n\nThe wake turn is where the behavior could break. When the notification arrives, I'm supposed to spawn a background general-purpose agent. That agent makes sixty sequential foreground echo calls and replies with a fixed token. Then I end the wake turn without waiting for it.\n\nSeveral things can go wrong here:\n\n- The notification could arrive while this turn is still running and be dropped instead of latched.\n- The wake could fire, but the spawned agent's lineage could be attributed to the wrong run.\n- The held queue could keep the wake from being delivered after the turn ends.\n- The sixty tool calls in the child could stall if the parent's turn state interferes with it.\n\nI haven't made any file changes, and I won't make any. The only things this session has done are the two Bash timers and this message. Whether the wake path works depends on what happens after I stop writing.\n\nIf the notification arrives, I'll call the Agent tool with the prompt you specified and end that turn straight away. If it never arrives, that would itself be a useful result for the investigation.", "session_id": "20c799ce-2073-4b25-babc-d341b2ed0701", "uuid": "08511dcf-38de-4f98-b20e-d64305b8e8b7"}
NATIVE [2026-10-24T10:51:34.040Z] {"type": "system", "subtype": "task_started", "task_id": "a6e4d76ef03e6d065", "task_type": "local_agent", "tool_use_id": "toolu_01TemGJE6kFHcbhcot4UZUWr", "description": "issue22033 lineage probe", "session_id": "20c799ce-2073-4b25-babc-d341b2ed0701", "uuid": "a26d9d00-1101-4607-8e09-b6fb53187b22"}
NATIVE [2026-10-24T10:51:36.082Z] {"type": "result", "subtype": "success", "result": "The background timer finished, and I launched the \"issue22033 lineage probe\" agent in the background. It's making the sixty sequential echo calls. I'm ending this wake turn now without waiting for it, so I don't know its result yet.", "session_id": "20c799ce-2073-4b25-babc-d341b2ed0701", "uuid": "e142d5a3-089a-4201-b91a-99261a4daba7"}
NATIVE [2026-10-24T10:52:38.835Z] {"type": "result", "subtype": "success", "result": "PONG", "session_id": "20c799ce-2073-4b25-babc-d341b2ed0701", "uuid": "0caa1880-0a28-4f70-be4d-2646c02aadd9"}
NATIVE [2026-10-24T10:53:24.068Z] {"type": "system", "subtype": "task_notification", "task_id": "a6e4d76ef03e6d065", "tool_use_id": "toolu_01TemGJE6kFHcbhcot4UZUWr", "status": "completed", "summary": "PROBE_22033_OK", "session_id": "20c799ce-2073-4b25-babc-d341b2ed0701", "uuid": "2268851b-db17-46ec-b364-dc3e401e2850"}
NATIVE [2026-10-24T10:53:25.639Z] {"type": "result", "subtype": "success", "result": "The \"issue22033 lineage probe\" agent finished. It made 60 tool calls over about 110 seconds and replied `PROBE_22033_OK`, as the prompt asked.", "session_id": "20c799ce-2073-4b25-babc-d341b2ed0701", "uuid": "118dea21-b1d1-4915-b70f-591ecd892e1b"}