note: You are running `vp test` as a Vite+ built-in command. If you meant to run the test npm script, use `vpr test` instead. RUN v5.0.5 /home/ubuntu/wt/pr-25754-evidence-after/packages/client-runtime stdout | src/state/__evidence_r4.test.ts {"environment":{"node":"v26.12.1","runner":"global vp v1.3.0 / local vite-plus v1.4.0 / Vitest v5.0.5","os":"linux 6.1.102 x64","vm":"fs-22"}} stdout | src/state/__evidence_r4.test.ts > startTurn-idle sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"startTurn-idle","sample":1,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"idle","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"command-invocation","ms":3.2058609999999135,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":21.28836799999999,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":71.62497499999995,"dispatchCount":1},{"phase":"all-complete","ms":72.24592799999982,"results":["Success"]}],"dispatchLatencyMs":[18.11520799999994],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success"],"historyCalls":0,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > startTurn-idle sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"startTurn-idle","sample":2,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"idle","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"command-invocation","ms":0.47813700000006065,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":2.626696000000038,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":53.191370000000006,"dispatchCount":1},{"phase":"all-complete","ms":53.51168099999995,"results":["Success"]}],"dispatchLatencyMs":[2.149691999999959],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success"],"historyCalls":0,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > startTurn-idle sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"startTurn-idle","sample":3,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"idle","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"command-invocation","ms":0.3449270000000979,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.51294699999994,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":51.883628000000044,"dispatchCount":1},{"phase":"all-complete","ms":52.13665199999991,"results":["Success"]}],"dispatchLatencyMs":[1.1612880000000132],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success"],"historyCalls":0,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4_history.test.ts {"environment":{"node":"v26.12.1","runner":"global vp v1.3.0 / local vite-plus v1.4.0 / Vitest v5.0.5","os":"linux 6.1.102 x64","vm":"fs-22"}} stdout | src/state/__evidence_r4_history.test.ts > ordinary-overlap sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"ordinary-overlap","sample":1,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":19.37128400000006,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":38.51584100000014,"call":1,"conversation":false},{"phase":"history-held","ms":39.15476899999999,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":39.51588600000014,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":40.33628500000009},{"phase":"rpc-dispatch","ms":42.552220000000034,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":45.69133600000009,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"release-page-gate","ms":45.69457200000011},{"phase":"history-http-release","ms":45.71322699999996,"call":1},{"phase":"commands-complete","ms":53.86928000000012,"results":["Success","Success"]}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Success","Success"],"dispatchLatencyMs":2.214162000000215,"beforeRetry":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"final":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"retry":null,"postStateHistory":null,"noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > ordinary-overlap sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"ordinary-overlap","sample":2,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":3.110392999999931,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":5.54755299999988,"call":1,"conversation":false},{"phase":"history-held","ms":5.767325000000028,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":5.986214999999902,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":6.055294999999887},{"phase":"rpc-dispatch","ms":6.74134099999992,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":8.539332000000059,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"release-page-gate","ms":8.540915999999925},{"phase":"history-http-release","ms":8.548610000000053,"call":1},{"phase":"commands-complete","ms":9.87425600000006,"results":["Success","Success"]}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Success","Success"],"dispatchLatencyMs":0.6847940000000108,"beforeRetry":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"final":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"retry":null,"postStateHistory":null,"noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > ordinary-overlap sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"ordinary-overlap","sample":3,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":2.4493930000001,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":5.062392000000045,"call":1,"conversation":false},{"phase":"history-held","ms":5.212312999999995,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":5.593087000000196,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":6.648085000000037},{"phase":"rpc-dispatch","ms":7.761062000000038,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":9.698565000000144,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"release-page-gate","ms":9.700710000000072},{"phase":"history-http-release","ms":9.714114000000109,"call":1},{"phase":"commands-complete","ms":11.372704000000113,"results":["Success","Success"]}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Success","Success"],"dispatchLatencyMs":1.1129080000000613,"beforeRetry":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"final":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"retry":null,"postStateHistory":null,"noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > progressive-overlap sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"progressive-overlap","sample":1,"inputs":{"historyCursor":"initial-cursor","throughEntryId":"message-older","pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":2.428072999999813,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":4.861896999999999,"call":1,"conversation":true},{"phase":"history-http-start","ms":7.0796859999998105,"call":2,"conversation":false},{"phase":"history-held","ms":7.099933999999848,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":7.262338,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":7.333762999999863},{"phase":"rpc-dispatch","ms":7.849088999999822,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":9.093261999999868,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}}},{"phase":"release-page-gate","ms":9.095225999999911},{"phase":"history-http-release","ms":9.102778999999828,"call":2},{"phase":"commands-complete","ms":10.123963999999887,"results":["Success","Success"]}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","throughEntryId":"message-older","view":"conversation","compactIds":"1"},"call":1},{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","throughEntryId":"message-older","compactIds":"1"},"call":2}],"results":["Success","Success"],"dispatchLatencyMs":0.5142749999999978,"beforeRetry":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"final":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"retry":null,"postStateHistory":null,"noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > progressive-overlap sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"progressive-overlap","sample":2,"inputs":{"historyCursor":"initial-cursor","throughEntryId":"message-older","pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":2.1272989999999936,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":4.115155999999843,"call":1,"conversation":true},{"phase":"history-http-start","ms":6.1638869999999315,"call":2,"conversation":false},{"phase":"history-held","ms":6.1851079999999,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":6.339375999999902,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":6.39092299999993},{"phase":"rpc-dispatch","ms":6.989474999999857,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":8.617007000000058,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}}},{"phase":"release-page-gate","ms":8.618890999999849},{"phase":"history-http-release","ms":8.62898999999993,"call":2},{"phase":"commands-complete","ms":9.590412999999899,"results":["Success","Success"]}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","throughEntryId":"message-older","view":"conversation","compactIds":"1"},"call":1},{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","throughEntryId":"message-older","compactIds":"1"},"call":2}],"results":["Success","Success"],"dispatchLatencyMs":0.5973300000000563,"beforeRetry":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"final":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"retry":null,"postStateHistory":null,"noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > progressive-overlap sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"progressive-overlap","sample":3,"inputs":{"historyCursor":"initial-cursor","throughEntryId":"message-older","pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":1.9778479999999945,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":3.9791609999999764,"call":1,"conversation":true},{"phase":"history-http-start","ms":5.963130999999976,"call":2,"conversation":false},{"phase":"history-held","ms":5.9852420000001985,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":6.131066000000146,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":6.179626999999982},{"phase":"rpc-dispatch","ms":6.722165000000132,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":7.718964000000142,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}}},{"phase":"release-page-gate","ms":7.720426000000089},{"phase":"history-http-release","ms":7.729021999999986,"call":2},{"phase":"commands-complete","ms":8.764715000000024,"results":["Success","Success"]}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","throughEntryId":"message-older","view":"conversation","compactIds":"1"},"call":1},{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","throughEntryId":"message-older","compactIds":"1"},"call":2}],"results":["Success","Success"],"dispatchLatencyMs":0.5409640000000309,"beforeRetry":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"final":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"retry":null,"postStateHistory":null,"noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > replacement-snapshot sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"replacement-snapshot","sample":1,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":2.117340000000013,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":3.9532630000001063,"call":1,"conversation":false},{"phase":"history-held","ms":4.034395000000131,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":4.271760000000086,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":4.321834000000081},{"phase":"rpc-dispatch","ms":4.830126999999948,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":5.775119000000132,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"replacement-applied","ms":6.229141000000027},{"phase":"release-page-gate","ms":6.230784000000085},{"phase":"history-http-release","ms":6.244570000000067,"call":1},{"phase":"commands-complete","ms":7.042415000000119,"results":["Success","Success"]},{"phase":"next-history-command-after-noop-or-cancel","ms":7.569834000000128,"result":"Success"}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Success","Success"],"dispatchLatencyMs":0.506349999999884,"beforeRetry":{"status":"live","history":{"historyCursor":null,"hasMoreHistory":false,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[],"visible":[]}},"final":{"status":"live","history":{"historyCursor":null,"hasMoreHistory":false,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[],"visible":[]}},"retry":null,"postStateHistory":"Success","noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > replacement-snapshot sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"replacement-snapshot","sample":2,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":1.834500000000162,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":3.634766000000127,"call":1,"conversation":false},{"phase":"history-held","ms":3.707392000000027,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":3.831344000000172,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":3.879034000000047},{"phase":"rpc-dispatch","ms":4.3278660000000855,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":5.100734000000102,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"replacement-applied","ms":5.427327000000105},{"phase":"release-page-gate","ms":5.428158000000167},{"phase":"history-http-release","ms":5.432125000000042,"call":1},{"phase":"commands-complete","ms":6.118683000000146,"results":["Success","Success"]},{"phase":"next-history-command-after-noop-or-cancel","ms":6.647305000000188,"result":"Success"}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Success","Success"],"dispatchLatencyMs":0.4468380000000707,"beforeRetry":{"status":"live","history":{"historyCursor":null,"hasMoreHistory":false,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[],"visible":[]}},"final":{"status":"live","history":{"historyCursor":null,"hasMoreHistory":false,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[],"visible":[]}},"retry":null,"postStateHistory":"Success","noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > replacement-snapshot sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"replacement-snapshot","sample":3,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":1.8151530000000093,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":4.100879000000077,"call":1,"conversation":false},{"phase":"history-held","ms":4.2577940000001036,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":17.842216000000008,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":17.922396000000163},{"phase":"rpc-dispatch","ms":18.45616800000016,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":19.471341000000166,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"replacement-applied","ms":19.74371199999996},{"phase":"release-page-gate","ms":19.744714000000158},{"phase":"history-http-release","ms":19.751015000000052,"call":1},{"phase":"commands-complete","ms":20.457170000000133,"results":["Success","Success"]},{"phase":"next-history-command-after-noop-or-cancel","ms":20.94261900000015,"result":"Success"}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Success","Success"],"dispatchLatencyMs":0.5313569999998435,"beforeRetry":{"status":"live","history":{"historyCursor":null,"hasMoreHistory":false,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[],"visible":[]}},"final":{"status":"live","history":{"historyCursor":null,"hasMoreHistory":false,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[],"visible":[]}},"retry":null,"postStateHistory":"Success","noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > replacement-bounded-snapshot sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"replacement-bounded-snapshot","sample":1,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":2.1912090000000717,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":3.8639049999999315,"call":1,"conversation":false},{"phase":"history-held","ms":4.492003000000068,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":4.667511999999988,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":4.775083999999879},{"phase":"rpc-dispatch","ms":5.289006999999856,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":6.046978999999965,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"replacement-bounded-snapshot-applied","ms":6.420949999999948},{"phase":"release-page-gate","ms":6.422011999999995},{"phase":"history-http-release","ms":6.427071000000069,"call":1},{"phase":"commands-complete","ms":7.116154000000051,"results":["Success","Success"]}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Success","Success"],"dispatchLatencyMs":0.5113490000001093,"beforeRetry":{"status":"live","history":{"historyCursor":"replacement-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":99},"projection":{"messages":[],"turnItems":[],"visible":[]}},"final":{"status":"live","history":{"historyCursor":"replacement-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":99},"projection":{"messages":[],"turnItems":[],"visible":[]}},"retry":null,"postStateHistory":null,"noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > replacement-bounded-snapshot sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"replacement-bounded-snapshot","sample":2,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":1.4899230000000898,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":3.0381569999999556,"call":1,"conversation":false},{"phase":"history-held","ms":3.107676999999967,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":3.2225619999999253,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":3.271684999999934},{"phase":"rpc-dispatch","ms":3.718994000000066,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":4.618780000000015,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"replacement-bounded-snapshot-applied","ms":4.893325000000004},{"phase":"release-page-gate","ms":4.894457000000102},{"phase":"history-http-release","ms":4.898885999999948,"call":1},{"phase":"commands-complete","ms":5.535289000000148,"results":["Success","Success"]}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Success","Success"],"dispatchLatencyMs":0.4446530000000166,"beforeRetry":{"status":"live","history":{"historyCursor":"replacement-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":99},"projection":{"messages":[],"turnItems":[],"visible":[]}},"final":{"status":"live","history":{"historyCursor":"replacement-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":99},"projection":{"messages":[],"turnItems":[],"visible":[]}},"retry":null,"postStateHistory":null,"noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > replacement-bounded-snapshot sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"replacement-bounded-snapshot","sample":3,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":1.5031990000002224,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":2.818625000000111,"call":1,"conversation":false},{"phase":"history-held","ms":2.9002080000000205,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":3.0190110000000914,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":3.0611700000001747},{"phase":"rpc-dispatch","ms":3.4759780000001683,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":4.194836000000123,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"replacement-bounded-snapshot-applied","ms":4.517771000000039},{"phase":"release-page-gate","ms":4.518823000000111},{"phase":"history-http-release","ms":4.523572000000058,"call":1},{"phase":"commands-complete","ms":10.329228000000057,"results":["Success","Success"]}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Success","Success"],"dispatchLatencyMs":0.4143169999999827,"beforeRetry":{"status":"live","history":{"historyCursor":"replacement-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":99},"projection":{"messages":[],"turnItems":[],"visible":[]}},"final":{"status":"live","history":{"historyCursor":"replacement-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":99},"projection":{"messages":[],"turnItems":[],"visible":[]}},"retry":null,"postStateHistory":null,"noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > delete-thread sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"delete-thread","sample":1,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":1.9131569999999556,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":3.711508999999978,"call":1,"conversation":false},{"phase":"history-held","ms":3.7896760000000995,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":3.9364210000001094,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":3.9902119999999286},{"phase":"rpc-dispatch","ms":4.440135999999939,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":5.401307999999972,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"deletion-applied","ms":6.180229000000054},{"phase":"release-page-gate","ms":6.181209999999965},{"phase":"history-http-release","ms":6.187762999999904,"call":1},{"phase":"commands-complete","ms":6.929092000000082,"results":["Success","Success"]},{"phase":"next-history-command-after-noop-or-cancel","ms":7.322550000000092,"result":"Success"}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Success","Success"],"dispatchLatencyMs":0.4493919999999889,"beforeRetry":{"status":"deleted","history":{"historyCursor":null,"hasMoreHistory":false,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":null},"final":{"status":"deleted","history":{"historyCursor":null,"hasMoreHistory":false,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":null},"retry":null,"postStateHistory":"Success","noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > delete-thread sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"delete-thread","sample":2,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":1.503309000000172,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":2.9638780000000224,"call":1,"conversation":false},{"phase":"history-held","ms":3.077350000000024,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":3.2057810000001155,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":3.256747000000132},{"phase":"rpc-dispatch","ms":3.730886000000055,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":4.621054000000186,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"deletion-applied","ms":5.014002000000119},{"phase":"release-page-gate","ms":5.0149330000001555},{"phase":"history-http-release","ms":5.020514000000048,"call":1},{"phase":"commands-complete","ms":5.610360000000128,"results":["Success","Success"]},{"phase":"next-history-command-after-noop-or-cancel","ms":5.9752840000001015,"result":"Success"}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Success","Success"],"dispatchLatencyMs":0.4732369999999264,"beforeRetry":{"status":"deleted","history":{"historyCursor":null,"hasMoreHistory":false,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":null},"final":{"status":"deleted","history":{"historyCursor":null,"hasMoreHistory":false,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":null},"retry":null,"postStateHistory":"Success","noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > delete-thread sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"delete-thread","sample":3,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":1.7009689999999864,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":3.018820000000005,"call":1,"conversation":false},{"phase":"history-held","ms":3.1086689999999635,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":3.276382999999896,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":3.347957999999835},{"phase":"rpc-dispatch","ms":3.8072089999998298,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":4.657181999999921,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"deletion-applied","ms":5.071688999999878},{"phase":"release-page-gate","ms":5.072560999999951},{"phase":"history-http-release","ms":5.0773699999999735,"call":1},{"phase":"commands-complete","ms":5.8113469999998415,"results":["Success","Success"]},{"phase":"next-history-command-after-noop-or-cancel","ms":6.151444999999967,"result":"Success"}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Success","Success"],"dispatchLatencyMs":0.4588699999999335,"beforeRetry":{"status":"deleted","history":{"historyCursor":null,"hasMoreHistory":false,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":null},"final":{"status":"deleted","history":{"historyCursor":null,"hasMoreHistory":false,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":null},"retry":null,"postStateHistory":"Success","noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > error-retry sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"error-retry","sample":1,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":1.2687599999999293,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":3.339382000000114,"call":1,"conversation":false},{"phase":"history-held","ms":3.4035930000000008,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":3.5059550000000854,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":3.5527420000000802},{"phase":"rpc-dispatch","ms":3.9224859999999353,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":4.654749000000038,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"release-page-gate","ms":4.656081000000086},{"phase":"history-http-release","ms":4.65992899999992,"call":1},{"phase":"commands-complete","ms":6.340439999999944,"results":["Success","Success"]},{"phase":"history-http-start","ms":7.358118000000104,"call":2,"conversation":false},{"phase":"history-http-release","ms":7.364700999999968,"call":2},{"phase":"retry-complete","ms":8.010882000000038,"retry":{"_tag":"loaded"}}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1},{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":2}],"results":["Success","Success"],"dispatchLatencyMs":0.3690329999999449,"beforeRetry":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":"Remote environment endpoint https://environment.example.test/api/orchestration/threads/thread-v2/history?cursor=initial-cursor&compactIds=1 returned undeclared status 503.","expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}},"final":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"retry":{"_tag":"loaded"},"postStateHistory":null,"noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > error-retry sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"error-retry","sample":2,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":1.500684000000092,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":3.0601480000000265,"call":1,"conversation":false},{"phase":"history-held","ms":3.1382740000001377,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":3.2398849999999584,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":3.2817139999999654},{"phase":"rpc-dispatch","ms":3.651657000000114,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":4.642145000000028,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"release-page-gate","ms":4.643477000000075},{"phase":"history-http-release","ms":4.647543999999925,"call":1},{"phase":"commands-complete","ms":5.057052999999996,"results":["Success","Success"]},{"phase":"history-http-start","ms":8.26245300000005,"call":2,"conversation":false},{"phase":"history-http-release","ms":8.269436000000042,"call":2},{"phase":"retry-complete","ms":8.792576999999937,"retry":{"_tag":"loaded"}}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1},{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":2}],"results":["Success","Success"],"dispatchLatencyMs":0.36949299999992036,"beforeRetry":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":"Remote environment endpoint https://environment.example.test/api/orchestration/threads/thread-v2/history?cursor=initial-cursor&compactIds=1 returned undeclared status 503.","expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}},"final":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"retry":{"_tag":"loaded"},"postStateHistory":null,"noopAfterClose":null} stdout | src/state/__evidence_r4_history.test.ts > error-retry sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"error-retry","sample":3,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":1.5029990000000453,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":2.817162000000053,"call":1,"conversation":false},{"phase":"history-held","ms":2.895971000000145,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":2.996800000000121,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":3.038057000000208},{"phase":"rpc-dispatch","ms":3.4340790000001107,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":4.1171310000001995,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"release-page-gate","ms":4.118303000000196},{"phase":"history-http-release","ms":4.122049000000061,"call":1},{"phase":"commands-complete","ms":4.5973310000001675,"results":["Success","Success"]},{"phase":"history-http-start","ms":5.404465000000073,"call":2,"conversation":false},{"phase":"history-http-release","ms":5.410045000000082,"call":2},{"phase":"retry-complete","ms":5.935500000000047,"retry":{"_tag":"loaded"}}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1},{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":2}],"results":["Success","Success"],"dispatchLatencyMs":0.395471999999927,"beforeRetry":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":"Remote environment endpoint https://environment.example.test/api/orchestration/threads/thread-v2/history?cursor=initial-cursor&compactIds=1 returned undeclared status 503.","expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}},"final":{"status":"live","history":{"historyCursor":"next-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":true,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"},{"id":"item-older","type":"assistant_message","text":"older synthetic","status":"completed"}],"visible":[{"id":"item-older","position":0,"text":"older synthetic","status":"completed"},{"id":"item-live","position":1,"text":"live completed","status":"completed"}]}},"retry":{"_tag":"loaded"},"postStateHistory":null,"noopAfterClose":null} stdout | src/state/__evidence_r4.test.ts > startTurn-pending-history sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"startTurn-pending-history","sample":1,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.44997399999988374},{"phase":"history-start","ms":1.56196900000009,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":1.7877020000000812,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":2.6783230000000913,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":53.01153299999987,"dispatchCount":1},{"phase":"history-release","ms":251.70793099999992,"call":1},{"phase":"all-complete","ms":251.96141599999987,"results":["Success","Success"]}],"dispatchLatencyMs":[0.8916819999999461],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4_history.test.ts > cancel-close sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"cancel-close","sample":1,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":1.5682500000000346,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":2.750397000000021,"call":1,"conversation":false},{"phase":"history-held","ms":2.8052000000000135,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":2.9002179999999953,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":2.9398619999999482},{"phase":"rpc-dispatch","ms":3.353016999999909,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":4.14118499999995,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"state-scope-closed","ms":7.039158999999927},{"phase":"release-page-gate","ms":7.039939999999888},{"phase":"commands-complete","ms":7.126021999999921,"results":["Failure","Success"]},{"phase":"next-history-command-after-noop-or-cancel","ms":7.4198129999999765,"result":"Success"}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Failure","Success"],"dispatchLatencyMs":0.41258300000004056,"beforeRetry":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}},"final":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}},"retry":null,"postStateHistory":"Success","noopAfterClose":{"_tag":"noop"}} stdout | src/state/__evidence_r4_history.test.ts > cancel-close sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"cancel-close","sample":2,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":1.2622969999999896,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":2.5665030000000115,"call":1,"conversation":false},{"phase":"history-held","ms":2.6241410000000087,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":2.721324000000095,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":2.7595550000000912},{"phase":"rpc-dispatch","ms":3.078914000000168,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":3.6751220000001013,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"state-scope-closed","ms":4.462939000000006},{"phase":"release-page-gate","ms":4.463660000000118},{"phase":"commands-complete","ms":4.5007600000001275,"results":["Failure","Success"]},{"phase":"next-history-command-after-noop-or-cancel","ms":4.982553000000053,"result":"Success"}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Failure","Success"],"dispatchLatencyMs":0.31867699999997967,"beforeRetry":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}},"final":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}},"retry":null,"postStateHistory":"Success","noopAfterClose":{"_tag":"noop"}} stdout | src/state/__evidence_r4_history.test.ts > cancel-close sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"cancel-close","sample":3,"inputs":{"historyCursor":"initial-cursor","throughEntryId":null,"pageContains":["older synthetic","STALE PAGE"],"liveText":"live completed","delay":"Deferred gate; no injected wall-clock latency in merge fixture"},"phases":[{"phase":"initial-state","ms":15.146621000000096,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"history-http-start","ms":17.380660000000034,"call":1,"conversation":false},{"phase":"history-held","ms":17.472653000000037,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"initial","streaming":true}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"initial","status":"running"}],"visible":[{"id":"item-live","position":0,"text":"initial","status":"running"}]}}},{"phase":"second-direct-controller-call","ms":17.634226000000126,"result":{"_tag":"busy"}},{"phase":"send-invocation","ms":17.703485},{"phase":"rpc-dispatch","ms":18.307718000000023,"type":"message.dispatch"},{"phase":"live-completion-while-history-held","ms":19.977930000000015,"state":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":true,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}}},{"phase":"state-scope-closed","ms":21.204770999999937},{"phase":"release-page-gate","ms":21.206043000000136},{"phase":"commands-complete","ms":21.24077800000009,"results":["Failure","Success"]},{"phase":"next-history-command-after-noop-or-cancel","ms":21.68437999999992,"result":"Success"}],"requests":[{"method":"GET","path":"/api/orchestration/threads/thread-v2/history","query":{"cursor":"initial-cursor","compactIds":"1"},"call":1}],"results":["Failure","Success"],"dispatchLatencyMs":0.603300999999874,"beforeRetry":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}},"final":{"status":"live","history":{"historyCursor":"initial-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":9},"projection":{"messages":[{"id":"message-live","text":"live completed","streaming":false}],"turnItems":[{"id":"item-live","type":"assistant_message","text":"live completed","status":"completed"}],"visible":[{"id":"item-live","position":0,"text":"live completed","status":"completed"}]}},"retry":null,"postStateHistory":"Success","noopAfterClose":{"_tag":"noop"}} stdout | src/state/__evidence_r4_history.test.ts > stop-detached-repair sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"stop-detached-repair","sample":1,"inputs":{"activeRun":"active-run","pendingItem":"pending-command","repair":"Deferred gate held until Stop and subsequent Send resolved"},"before":{"status":"synchronizing","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"running"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"running"}]}},"phases":[{"phase":"stop-invocation","ms":1.0424040000000332},{"phase":"rpc-dispatch","ms":2.3798920000001544,"type":"run.interrupt"},{"phase":"repair-lookup-start","ms":3.3626050000000305},{"phase":"stop-result-before-repair-release","ms":3.4550080000001344,"result":"Success"},{"phase":"rpc-dispatch","ms":4.0828650000000835,"type":"message.dispatch"},{"phase":"send-result-before-repair-release","ms":4.148479000000179,"result":"Success"},{"phase":"release-repair-gate","ms":4.190387000000101},{"phase":"repair-lookup-released","ms":4.201448000000028}],"stopResult":"Success","sendResult":"Success","final":{"status":"cached","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"completed"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"completed"}]}},"measurement":"RPC receipt and pending-work repair only; no provider shutdown measured"} stdout | src/state/__evidence_r4_history.test.ts > stop-detached-repair sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"stop-detached-repair","sample":2,"inputs":{"activeRun":"active-run","pendingItem":"pending-command","repair":"Deferred gate held until Stop and subsequent Send resolved"},"before":{"status":"synchronizing","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"running"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"running"}]}},"phases":[{"phase":"stop-invocation","ms":0.7894810000000234},{"phase":"rpc-dispatch","ms":1.4988799999998719,"type":"run.interrupt"},{"phase":"repair-lookup-start","ms":1.8621319999999741},{"phase":"stop-result-before-repair-release","ms":1.9258109999998396,"result":"Success"},{"phase":"rpc-dispatch","ms":2.474369999999908,"type":"message.dispatch"},{"phase":"send-result-before-repair-release","ms":2.537317999999914,"result":"Success"},{"phase":"release-repair-gate","ms":2.5923109999998815},{"phase":"repair-lookup-released","ms":2.603390999999874}],"stopResult":"Success","sendResult":"Success","final":{"status":"cached","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"completed"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"completed"}]}},"measurement":"RPC receipt and pending-work repair only; no provider shutdown measured"} stdout | src/state/__evidence_r4_history.test.ts > stop-detached-repair sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"stop-detached-repair","sample":3,"inputs":{"activeRun":"active-run","pendingItem":"pending-command","repair":"Deferred gate held until Stop and subsequent Send resolved"},"before":{"status":"synchronizing","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"running"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"running"}]}},"phases":[{"phase":"stop-invocation","ms":0.7060839999999189},{"phase":"rpc-dispatch","ms":1.3964189999999235,"type":"run.interrupt"},{"phase":"repair-lookup-start","ms":1.7165789999999106},{"phase":"stop-result-before-repair-release","ms":1.7710309999999936,"result":"Success"},{"phase":"rpc-dispatch","ms":2.4837969999998677,"type":"message.dispatch"},{"phase":"send-result-before-repair-release","ms":3.082529999999906,"result":"Success"},{"phase":"release-repair-gate","ms":3.123055999999906},{"phase":"repair-lookup-released","ms":3.133444999999938}],"stopResult":"Success","sendResult":"Success","final":{"status":"cached","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"completed"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"completed"}]}},"measurement":"RPC receipt and pending-work repair only; no provider shutdown measured"} stdout | src/state/__evidence_r4_history.test.ts > stop-close-during-repair sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"stop-close-during-repair","sample":1,"inputs":{"activeRun":"active-run","pendingItem":"pending-command","repair":"Deferred gate held until Stop and subsequent Send resolved"},"before":{"status":"synchronizing","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"running"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"running"}]}},"phases":[{"phase":"stop-invocation","ms":0.7602050000000418},{"phase":"rpc-dispatch","ms":1.3884029999999257,"type":"run.interrupt"},{"phase":"repair-lookup-start","ms":1.8101450000001478},{"phase":"stop-result-before-repair-release","ms":1.890625,"result":"Success"},{"phase":"rpc-dispatch","ms":2.397576000000072,"type":"message.dispatch"},{"phase":"send-result-before-repair-release","ms":2.4630090000000564,"result":"Success"},{"phase":"state-scope-closed","ms":3.0088120000000345}],"stopResult":"Success","sendResult":"Success","final":{"status":"synchronizing","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"running"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"running"}]}},"measurement":"RPC receipt and pending-work repair only; no provider shutdown measured"} stdout | src/state/__evidence_r4_history.test.ts > stop-close-during-repair sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"stop-close-during-repair","sample":2,"inputs":{"activeRun":"active-run","pendingItem":"pending-command","repair":"Deferred gate held until Stop and subsequent Send resolved"},"before":{"status":"synchronizing","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"running"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"running"}]}},"phases":[{"phase":"stop-invocation","ms":0.7195490000001428},{"phase":"rpc-dispatch","ms":1.339441000000079,"type":"run.interrupt"},{"phase":"repair-lookup-start","ms":1.744811000000027},{"phase":"stop-result-before-repair-release","ms":1.8069080000000213,"result":"Success"},{"phase":"rpc-dispatch","ms":2.285075000000006,"type":"message.dispatch"},{"phase":"send-result-before-repair-release","ms":2.3457680000001346,"result":"Success"},{"phase":"state-scope-closed","ms":2.749174000000039}],"stopResult":"Success","sendResult":"Success","final":{"status":"synchronizing","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"running"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"running"}]}},"measurement":"RPC receipt and pending-work repair only; no provider shutdown measured"} stdout | src/state/__evidence_r4_history.test.ts > stop-close-during-repair sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"stop-close-during-repair","sample":3,"inputs":{"activeRun":"active-run","pendingItem":"pending-command","repair":"Deferred gate held until Stop and subsequent Send resolved"},"before":{"status":"synchronizing","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"running"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"running"}]}},"phases":[{"phase":"stop-invocation","ms":0.667741999999862},{"phase":"rpc-dispatch","ms":1.3214080000000195,"type":"run.interrupt"},{"phase":"repair-lookup-start","ms":1.6464679999999134},{"phase":"stop-result-before-repair-release","ms":1.7014910000000327,"result":"Success"},{"phase":"rpc-dispatch","ms":2.289292999999816,"type":"message.dispatch"},{"phase":"send-result-before-repair-release","ms":2.3719679999999244,"result":"Success"},{"phase":"state-scope-closed","ms":2.8805519999998523}],"stopResult":"Success","sendResult":"Success","final":{"status":"synchronizing","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"running"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"running"}]}},"measurement":"RPC receipt and pending-work repair only; no provider shutdown measured"} stdout | src/state/__evidence_r4_history.test.ts > stop-rejected-repair sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"stop-rejected-repair","sample":1,"inputs":{"activeRun":"active-run","pendingItem":"pending-command","repair":"Deferred gate held until Stop and subsequent Send resolved"},"before":{"status":"synchronizing","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"running"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"running"}]}},"phases":[{"phase":"stop-invocation","ms":0.8369490000000042},{"phase":"rpc-dispatch","ms":1.4957449999999426,"type":"run.interrupt"},{"phase":"repair-lookup-start","ms":1.9116539999999986},{"phase":"stop-result-before-repair-release","ms":2.079289000000017,"result":"Failure"},{"phase":"rpc-dispatch","ms":2.9162180000000717,"type":"message.dispatch"},{"phase":"send-result-before-repair-release","ms":2.9833840000001146,"result":"Success"},{"phase":"release-repair-gate","ms":3.0237200000001394},{"phase":"repair-lookup-released","ms":3.0362330000000384}],"stopResult":"Failure","sendResult":"Success","final":{"status":"cached","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"completed"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"completed"}]}},"measurement":"RPC receipt and pending-work repair only; no provider shutdown measured"} stdout | src/state/__evidence_r4_history.test.ts > stop-rejected-repair sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"stop-rejected-repair","sample":2,"inputs":{"activeRun":"active-run","pendingItem":"pending-command","repair":"Deferred gate held until Stop and subsequent Send resolved"},"before":{"status":"synchronizing","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"running"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"running"}]}},"phases":[{"phase":"stop-invocation","ms":1.1410499999999502},{"phase":"rpc-dispatch","ms":1.843936999999869,"type":"run.interrupt"},{"phase":"repair-lookup-start","ms":2.2953939999999875},{"phase":"stop-result-before-repair-release","ms":2.3598250000000007,"result":"Failure"},{"phase":"rpc-dispatch","ms":2.847378999999819,"type":"message.dispatch"},{"phase":"send-result-before-repair-release","ms":2.923230999999987,"result":"Success"},{"phase":"release-repair-gate","ms":2.958596999999827},{"phase":"repair-lookup-released","ms":2.966993000000002}],"stopResult":"Failure","sendResult":"Success","final":{"status":"cached","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"completed"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"completed"}]}},"measurement":"RPC receipt and pending-work repair only; no provider shutdown measured"} stdout | src/state/__evidence_r4_history.test.ts > stop-rejected-repair sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"stop-rejected-repair","sample":3,"inputs":{"activeRun":"active-run","pendingItem":"pending-command","repair":"Deferred gate held until Stop and subsequent Send resolved"},"before":{"status":"synchronizing","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"running"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"running"}]}},"phases":[{"phase":"stop-invocation","ms":0.7722779999999148},{"phase":"rpc-dispatch","ms":1.4024899999999434,"type":"run.interrupt"},{"phase":"repair-lookup-start","ms":2.1070709999999053},{"phase":"stop-result-before-repair-release","ms":2.189495999999963,"result":"Failure"},{"phase":"rpc-dispatch","ms":3.0721209999999246,"type":"message.dispatch"},{"phase":"send-result-before-repair-release","ms":3.131402999999864,"result":"Success"},{"phase":"release-repair-gate","ms":3.1664180000000215},{"phase":"repair-lookup-released","ms":3.1771779999999126}],"stopResult":"Failure","sendResult":"Success","final":{"status":"cached","history":{"historyCursor":"older-cursor","hasMoreHistory":true,"loading":false,"error":null,"expanded":false,"latestLocalTurnOrdinal":null},"projection":{"messages":[],"turnItems":[{"id":"pending-command","type":"command_execution","text":null,"status":"completed"}],"visible":[{"id":"pending-command","position":0,"text":null,"status":"completed"}]}},"measurement":"RPC receipt and pending-work repair only; no provider shutdown measured"} stdout | src/state/__evidence_r4.test.ts > startTurn-pending-history sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"startTurn-pending-history","sample":2,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.3482730000000629},{"phase":"history-start","ms":1.0331969999999728,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":1.1577010000000882,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.8158750000000055,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.31814200000008,"dispatchCount":1},{"phase":"history-release","ms":250.80865400000016,"call":1},{"phase":"all-complete","ms":251.1316200000001,"results":["Success","Success"]}],"dispatchLatencyMs":[0.6578739999999925],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > startTurn-pending-history sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"startTurn-pending-history","sample":3,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.4459260000000995},{"phase":"history-start","ms":1.3101979999999003,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":1.4294310000000223,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":2.424395999999888,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.90477299999998,"dispatchCount":1},{"phase":"history-release","ms":251.6093229999999,"call":1},{"phase":"all-complete","ms":251.91188100000022,"results":["Success","Success"]}],"dispatchLatencyMs":[0.9941940000001068],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > interruptTurn-idle sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"interruptTurn-idle","sample":1,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"interruptTurn","historyState":"idle","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"command-invocation","ms":0.42130100000031234,"action":"interruptTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":2.1297960000001694,"type":"run.interrupt","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.59682000000021,"dispatchCount":1},{"phase":"pending-work-reconciled","ms":52.99661900000001,"threadId":"synthetic-thread"},{"phase":"all-complete","ms":53.048653000000286,"results":["Success"]}],"dispatchLatencyMs":[1.7062510000000657],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success"],"historyCalls":0,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > interruptTurn-idle sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"interruptTurn-idle","sample":2,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"interruptTurn","historyState":"idle","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"command-invocation","ms":0.3592630000002828,"action":"interruptTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.2888600000001134,"type":"run.interrupt","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":50.92702700000018,"dispatchCount":1},{"phase":"pending-work-reconciled","ms":51.10601200000019,"threadId":"synthetic-thread"},{"phase":"all-complete","ms":51.147161000000324,"results":["Success"]}],"dispatchLatencyMs":[0.9271799999996801],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success"],"historyCalls":0,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > interruptTurn-idle sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"interruptTurn-idle","sample":3,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"interruptTurn","historyState":"idle","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"command-invocation","ms":0.36918599999989965,"action":"interruptTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.3966049999999086,"type":"run.interrupt","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":51.94696799999974,"dispatchCount":1},{"phase":"pending-work-reconciled","ms":52.09148099999993,"threadId":"synthetic-thread"},{"phase":"all-complete","ms":52.12341699999979,"results":["Success"]}],"dispatchLatencyMs":[1.0249629999998433],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success"],"historyCalls":0,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > interruptTurn-pending-history sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"interruptTurn-pending-history","sample":1,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"interruptTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.4072070000001986},{"phase":"history-start","ms":1.0126730000001771,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":1.1034090000002834,"action":"interruptTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.7973360000000866,"type":"run.interrupt","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":51.76965700000028,"dispatchCount":1},{"phase":"pending-work-reconciled","ms":51.89388300000019,"threadId":"synthetic-thread"},{"phase":"history-release","ms":251.34003100000018,"call":1},{"phase":"all-complete","ms":251.57279400000016,"results":["Success","Success"]}],"dispatchLatencyMs":[0.6991410000000542],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > interruptTurn-pending-history sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"interruptTurn-pending-history","sample":2,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"interruptTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.3575180000002547},{"phase":"history-start","ms":1.0739180000000488,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":1.1723830000000817,"action":"interruptTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.8014360000001943,"type":"run.interrupt","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":51.278182000000015,"dispatchCount":1},{"phase":"pending-work-reconciled","ms":51.497833000000355,"threadId":"synthetic-thread"},{"phase":"history-release","ms":252.0913260000002,"call":1},{"phase":"all-complete","ms":252.30726800000002,"results":["Success","Success"]}],"dispatchLatencyMs":[0.6328819999998814],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > interruptTurn-pending-history sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"interruptTurn-pending-history","sample":3,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"interruptTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.2620529999999235},{"phase":"history-start","ms":0.9903509999999187,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":1.0956919999998718,"action":"interruptTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.7974779999999555,"type":"run.interrupt","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.182636999999886,"dispatchCount":1},{"phase":"pending-work-reconciled","ms":52.30559799999992,"threadId":"synthetic-thread"},{"phase":"history-release","ms":250.817763,"call":1},{"phase":"all-complete","ms":250.99579600000015,"results":["Success","Success"]}],"dispatchLatencyMs":[0.7021670000003724],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > reverse-command-before-history sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"reverse-command-before-history","sample":1,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"reverse","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"command-invocation","ms":0.21211300000004485,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.2397029999997358,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"history-invocation","ms":1.3404519999999138},{"phase":"history-start","ms":1.8066690000000563,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"rpc-receipt","ms":51.12281299999995,"dispatchCount":1},{"phase":"history-release","ms":252.28936699999986,"call":1},{"phase":"all-complete","ms":252.45033899999999,"results":["Success","Success"]}],"dispatchLatencyMs":[1.02824099999998],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > reverse-command-before-history sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"reverse-command-before-history","sample":2,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"reverse","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"command-invocation","ms":0.24985199999991892,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.1033090000000811,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"history-invocation","ms":1.2138010000003305},{"phase":"history-start","ms":1.5338600000000042,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"rpc-receipt","ms":50.874401000000034,"dispatchCount":1},{"phase":"history-release","ms":251.90494200000012,"call":1},{"phase":"all-complete","ms":252.0491750000001,"results":["Success","Success"]}],"dispatchLatencyMs":[0.8537559999999758],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > reverse-command-before-history sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"reverse-command-before-history","sample":3,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"reverse","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"command-invocation","ms":0.2776490000001104,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.1447180000000117,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"history-invocation","ms":1.2151840000001357},{"phase":"history-start","ms":1.698732000000291,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"rpc-receipt","ms":51.10526000000027,"dispatchCount":1},{"phase":"history-release","ms":252.40401900000006,"call":1},{"phase":"all-complete","ms":252.598571,"results":["Success","Success"]}],"dispatchLatencyMs":[0.8666590000002543],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > same-thread-command-serialization sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"same-thread-command-serialization","sample":1,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"command","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"command-invocation","ms":0.21247399999992922,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":0.9822619999999915,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"command-invocation","ms":1.0558270000001357,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-receipt","ms":51.31692100000009,"dispatchCount":1},{"phase":"rpc-dispatch","ms":51.79226999999992,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":2},{"phase":"rpc-receipt","ms":101.89253499999995,"dispatchCount":2},{"phase":"all-complete","ms":102.01857400000017,"results":["Success","Success"]}],"dispatchLatencyMs":[0.7720729999996365,50.73585299999968],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":0,"rpcCalls":2,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > same-thread-command-serialization sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"same-thread-command-serialization","sample":2,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"command","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"command-invocation","ms":0.2019599999998718,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.28879999999981,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"command-invocation","ms":1.3712759999998525,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-receipt","ms":51.846187999999984,"dispatchCount":1},{"phase":"rpc-dispatch","ms":52.901082999999744,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":2},{"phase":"rpc-receipt","ms":103.05963699999984,"dispatchCount":2},{"phase":"all-complete","ms":103.23800500000016,"results":["Success","Success"]}],"dispatchLatencyMs":[1.0861290000002555,51.52872500000012],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":0,"rpcCalls":2,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > same-thread-command-serialization sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"same-thread-command-serialization","sample":3,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"command","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"command-invocation","ms":0.2554939999999988,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.7464300000001458,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"command-invocation","ms":1.9737290000002758,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-receipt","ms":51.39601799999991,"dispatchCount":1},{"phase":"rpc-dispatch","ms":52.244005000000016,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":2},{"phase":"rpc-receipt","ms":102.56778299999996,"dispatchCount":2},{"phase":"all-complete","ms":102.70455300000003,"results":["Success","Success"]}],"dispatchLatencyMs":[1.4907149999999092,50.2700149999996],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":0,"rpcCalls":2,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > same-thread-history-serialization sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"same-thread-history-serialization","sample":1,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"history","historyOutcome":"loaded","throughEntryId":"entry-old"},"events":[{"phase":"history-invocation","ms":0.22199000000000524},{"phase":"history-start","ms":1.0579539999998815,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"history-invocation","ms":1.2109140000002299},{"phase":"command-invocation","ms":1.2735929999998916,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":2.3953190000001996,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":51.96858299999985,"dispatchCount":1},{"phase":"history-release","ms":251.56819799999994,"call":1},{"phase":"history-start","ms":252.22164599999996,"call":2,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":"entry-old"},{"phase":"history-release","ms":502.97064299999965,"call":2},{"phase":"all-complete","ms":503.41258000000016,"results":["Success","Success","Success"]}],"dispatchLatencyMs":[1.1213049999996656],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success","Success"],"historyCalls":2,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > same-thread-history-serialization sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"same-thread-history-serialization","sample":2,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"history","historyOutcome":"loaded","throughEntryId":"entry-old"},"events":[{"phase":"history-invocation","ms":0.4463560000003781},{"phase":"history-start","ms":1.6403790000003937,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"history-invocation","ms":1.771136999999726},{"phase":"command-invocation","ms":1.795882999999776,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":2.8204960000002757,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":53.293948000000455,"dispatchCount":1},{"phase":"history-release","ms":252.07162599999992,"call":1},{"phase":"history-start","ms":252.9119780000001,"call":2,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":"entry-old"},{"phase":"history-release","ms":503.62924999999996,"call":2},{"phase":"all-complete","ms":503.8846080000003,"results":["Success","Success","Success"]}],"dispatchLatencyMs":[1.0233299999999872],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success","Success"],"historyCalls":2,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > same-thread-history-serialization sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"same-thread-history-serialization","sample":3,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"history","historyOutcome":"loaded","throughEntryId":"entry-old"},"events":[{"phase":"history-invocation","ms":0.3151610000004439},{"phase":"history-start","ms":1.1716770000002725,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"history-invocation","ms":1.2982150000007096},{"phase":"command-invocation","ms":1.369698000000426,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":2.3121060000003126,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.796149000000696,"dispatchCount":1},{"phase":"history-release","ms":251.341864,"call":1},{"phase":"history-start","ms":252.22219500000028,"call":2,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":"entry-old"},{"phase":"history-release","ms":501.9680960000005,"call":2},{"phase":"all-complete","ms":502.2192070000001,"results":["Success","Success","Success"]}],"dispatchLatencyMs":[0.9418649999997797],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success","Success"],"historyCalls":2,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > other-thread-independent sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"other-thread-independent","sample":1,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.3588629999994737},{"phase":"history-start","ms":1.6598720000001776,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":1.8214360000001761,"action":"startTurn","environmentId":"synthetic-environment","threadId":"independent-thread"},{"phase":"rpc-dispatch","ms":3.00163799999973,"type":"message.dispatch","threadId":"independent-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":53.55626399999983,"dispatchCount":1},{"phase":"history-release","ms":251.4151609999999,"call":1},{"phase":"all-complete","ms":251.70073599999978,"results":["Success","Success"]}],"dispatchLatencyMs":[1.1883779999998296],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > other-thread-independent sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"other-thread-independent","sample":2,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.25915600000007544},{"phase":"history-start","ms":0.8065520000000106,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":0.8787179999999353,"action":"startTurn","environmentId":"synthetic-environment","threadId":"independent-thread"},{"phase":"rpc-dispatch","ms":1.9230360000001383,"type":"message.dispatch","threadId":"independent-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.42845899999975,"dispatchCount":1},{"phase":"history-release","ms":251.27356599999985,"call":1},{"phase":"all-complete","ms":251.456639,"results":["Success","Success"]}],"dispatchLatencyMs":[1.0423940000000584],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > other-thread-independent sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"other-thread-independent","sample":3,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.21710799999982555},{"phase":"history-start","ms":0.9378390000001673,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":1.019242000000304,"action":"startTurn","environmentId":"synthetic-environment","threadId":"independent-thread"},{"phase":"rpc-dispatch","ms":1.6469489999999496,"type":"message.dispatch","threadId":"independent-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.05832600000031,"dispatchCount":1},{"phase":"history-release","ms":251.06405300000006,"call":1},{"phase":"all-complete","ms":251.3284990000002,"results":["Success","Success"]}],"dispatchLatencyMs":[0.6270260000001144],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > other-environment-independent sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"other-environment-independent","sample":1,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.24817500000062864},{"phase":"history-start","ms":1.029540000000452,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":1.118518000000222,"action":"startTurn","environmentId":"independent-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.978700000000572,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.43640400000004,"dispatchCount":1},{"phase":"history-release","ms":252.1920280000004,"call":1},{"phase":"all-complete","ms":252.38401800000065,"results":["Success","Success"]}],"dispatchLatencyMs":[0.8681179999994129],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > other-environment-independent sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"other-environment-independent","sample":2,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.22153499999967607},{"phase":"history-start","ms":6.63524199999938,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":6.738695000000007,"action":"startTurn","environmentId":"independent-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":7.410584999999628,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":57.814156999999796,"dispatchCount":1},{"phase":"history-release","ms":256.4909889999999,"call":1},{"phase":"all-complete","ms":256.70507,"results":["Success","Success"]}],"dispatchLatencyMs":[0.6713090000002921],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > other-environment-independent sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"other-environment-independent","sample":3,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.28288100000008853},{"phase":"history-start","ms":0.9858489999996891,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":1.0931599999994432,"action":"startTurn","environmentId":"independent-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.7792869999993854,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.160696999999345,"dispatchCount":1},{"phase":"history-release","ms":250.9116669999994,"call":1},{"phase":"all-complete","ms":251.10509999999977,"results":["Success","Success"]}],"dispatchLatencyMs":[0.6858659999998054],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > history-noop-releases-queue sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"history-noop-releases-queue","sample":1,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"history","historyOutcome":"noop","throughEntryId":"entry-old"},"events":[{"phase":"history-invocation","ms":0.2724710000002233},{"phase":"history-start","ms":1.2123039999996763,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"history-invocation","ms":1.3418970000002446},{"phase":"command-invocation","ms":1.3622349999996004,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":2.4328120000000126,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.978219999999965,"dispatchCount":1},{"phase":"history-release","ms":251.64896000000044,"call":1},{"phase":"history-start","ms":252.2306410000001,"call":2,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":"entry-old"},{"phase":"history-release","ms":502.8043440000001,"call":2},{"phase":"all-complete","ms":503.05154800000037,"results":["Success","Success","Success"]}],"dispatchLatencyMs":[1.0734929999998712],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success","Success"],"historyCalls":2,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > history-noop-releases-queue sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"history-noop-releases-queue","sample":2,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"history","historyOutcome":"noop","throughEntryId":"entry-old"},"events":[{"phase":"history-invocation","ms":0.2524229999999079},{"phase":"history-start","ms":0.9878630000002886,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"history-invocation","ms":1.0899630000003526},{"phase":"command-invocation","ms":1.0998420000005353,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.6095580000001064,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":51.94976099999985,"dispatchCount":1},{"phase":"history-release","ms":250.50247000000036,"call":1},{"phase":"history-start","ms":251.01838700000008,"call":2,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":"entry-old"},{"phase":"history-release","ms":501.6125499999998,"call":2},{"phase":"all-complete","ms":501.8092080000006,"results":["Success","Success","Success"]}],"dispatchLatencyMs":[0.5089649999999892],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success","Success"],"historyCalls":2,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > history-noop-releases-queue sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"history-noop-releases-queue","sample":3,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"history","historyOutcome":"noop","throughEntryId":"entry-old"},"events":[{"phase":"history-invocation","ms":0.24148299999978917},{"phase":"history-start","ms":0.7776080000003276,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"history-invocation","ms":0.8407859999988432},{"phase":"command-invocation","ms":0.8499039999987872,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.4686739999997371,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":51.748883999998725,"dispatchCount":1},{"phase":"history-release","ms":251.22353099999964,"call":1},{"phase":"history-start","ms":251.6944939999994,"call":2,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":"entry-old"},{"phase":"history-release","ms":502.2437719999998,"call":2},{"phase":"all-complete","ms":502.4839830000001,"results":["Success","Success","Success"]}],"dispatchLatencyMs":[0.6178879999988567],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success","Success"],"historyCalls":2,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > history-busy-releases-queue sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"history-busy-releases-queue","sample":1,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"history","historyOutcome":"busy","throughEntryId":"entry-old"},"events":[{"phase":"history-invocation","ms":0.2627620000002935},{"phase":"history-start","ms":0.9979210000001331,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"history-invocation","ms":1.0993209999996907},{"phase":"command-invocation","ms":1.111823999999615,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.7500010000003385,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.124808999999004,"dispatchCount":1},{"phase":"history-release","ms":251.2682960000002,"call":1},{"phase":"history-start","ms":251.98866699999962,"call":2,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":"entry-old"},{"phase":"history-release","ms":502.61507899999924,"call":2},{"phase":"all-complete","ms":502.7936630000004,"results":["Success","Success","Success"]}],"dispatchLatencyMs":[0.6372559999999794],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success","Success"],"historyCalls":2,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > history-busy-releases-queue sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"history-busy-releases-queue","sample":2,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"history","historyOutcome":"busy","throughEntryId":"entry-old"},"events":[{"phase":"history-invocation","ms":0.21453199999996286},{"phase":"history-start","ms":0.9481680000008055,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"history-invocation","ms":1.0135810000010679},{"phase":"command-invocation","ms":1.023309000000154,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.6364190000003873,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.02799800000139,"dispatchCount":1},{"phase":"history-release","ms":250.73892400000113,"call":1},{"phase":"history-start","ms":251.32568300000094,"call":2,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":"entry-old"},{"phase":"history-release","ms":501.91893300000083,"call":2},{"phase":"all-complete","ms":502.1437450000012,"results":["Success","Success","Success"]}],"dispatchLatencyMs":[0.6119669999989128],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success","Success"],"historyCalls":2,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > history-busy-releases-queue sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"history-busy-releases-queue","sample":3,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"history","historyOutcome":"busy","throughEntryId":"entry-old"},"events":[{"phase":"history-invocation","ms":0.40135300000110874},{"phase":"history-start","ms":1.433548000000883,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"history-invocation","ms":1.5445360000012442},{"phase":"command-invocation","ms":1.5623090000008233,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":2.664215000000695,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.16948300000149,"dispatchCount":1},{"phase":"history-release","ms":251.8789310000011,"call":1},{"phase":"history-start","ms":252.67579500000102,"call":2,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":"entry-old"},{"phase":"history-release","ms":502.45394699999997,"call":2},{"phase":"all-complete","ms":502.6763740000006,"results":["Success","Success","Success"]}],"dispatchLatencyMs":[1.100202999999965],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Success","Success"],"historyCalls":2,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > history-error-releases-queue sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"history-error-releases-queue","sample":1,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"history","historyOutcome":"error","throughEntryId":"entry-old"},"events":[{"phase":"history-invocation","ms":0.3329249999987951},{"phase":"history-start","ms":1.3477169999987382,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"history-invocation","ms":1.474064999998518},{"phase":"command-invocation","ms":1.4934610000000248,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":2.6030309999987367,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.897939999998925,"dispatchCount":1},{"phase":"history-release","ms":251.96887999999853,"call":1},{"phase":"history-start","ms":254.52365999999893,"call":2,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":"entry-old"},{"phase":"history-release","ms":505.1608129999986,"call":2},{"phase":"all-complete","ms":505.4059929999985,"results":["Failure","Success","Success"]}],"dispatchLatencyMs":[1.1081489999996847],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Failure","Success","Success"],"historyCalls":2,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > history-error-releases-queue sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"history-error-releases-queue","sample":2,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"history","historyOutcome":"error","throughEntryId":"entry-old"},"events":[{"phase":"history-invocation","ms":0.4268609999999171},{"phase":"history-start","ms":1.4984299999996438,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"history-invocation","ms":1.6283739999998943},{"phase":"command-invocation","ms":1.6765340000001743,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":2.5915599999989354,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":53.094358000000284,"dispatchCount":1},{"phase":"history-release","ms":252.41101899999921,"call":1},{"phase":"history-start","ms":253.3612009999997,"call":2,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":"entry-old"},{"phase":"history-release","ms":503.15615400000024,"call":2},{"phase":"all-complete","ms":503.3707770000001,"results":["Failure","Success","Success"]}],"dispatchLatencyMs":[0.9180820000001404],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Failure","Success","Success"],"historyCalls":2,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > history-error-releases-queue sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"history-error-releases-queue","sample":3,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"startTurn","historyState":"history","historyOutcome":"error","throughEntryId":"entry-old"},"events":[{"phase":"history-invocation","ms":0.3060939999995753},{"phase":"history-start","ms":1.0799449999994977,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"history-invocation","ms":1.1811349999989034},{"phase":"command-invocation","ms":1.1973649999999907,"action":"startTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":2.043841999999131,"type":"message.dispatch","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":51.74458699999923,"dispatchCount":1},{"phase":"history-release","ms":251.4222549999995,"call":1},{"phase":"history-start","ms":252.1687839999995,"call":2,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":"entry-old"},{"phase":"history-release","ms":502.77631199999996,"call":2},{"phase":"all-complete","ms":502.96497499999896,"results":["Failure","Success","Success"]}],"dispatchLatencyMs":[0.8465570000007574],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Failure","Success","Success"],"historyCalls":2,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > stop-rpc-error-reconciles sample 1 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"stop-rpc-error-reconciles","sample":1,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"interruptTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.22216599999956088},{"phase":"history-start","ms":0.7004230000002281,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":0.7654050000001007,"action":"interruptTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.3136230000000069,"type":"run.interrupt","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":50.74651499999891,"dispatchCount":1},{"phase":"pending-work-reconciled","ms":51.22857899999872,"threadId":"synthetic-thread"},{"phase":"history-release","ms":251.50204399999893,"call":1},{"phase":"all-complete","ms":251.719681999999,"results":["Success","Failure"]}],"dispatchLatencyMs":[0.5475670000014361],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Failure"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > stop-rpc-error-reconciles sample 2 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"stop-rpc-error-reconciles","sample":2,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"interruptTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.2531440000002476},{"phase":"history-start","ms":0.7616479999996955,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":0.8253970000005211,"action":"interruptTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.3278989999998885,"type":"run.interrupt","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":51.69246899999962,"dispatchCount":1},{"phase":"pending-work-reconciled","ms":52.02383000000009,"threadId":"synthetic-thread"},{"phase":"history-release","ms":250.33518600000025,"call":1},{"phase":"all-complete","ms":250.5217560000001,"results":["Success","Failure"]}],"dispatchLatencyMs":[0.5024420000008831],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Failure"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} stdout | src/state/__evidence_r4.test.ts > stop-rpc-error-reconciles sample 3 {"row":"R4","sha":"db41c18dc69938e7196e221cc7e79982d82e5815","fixture":"stop-rpc-error-reconciles","sample":3,"inputs":{"environmentId":"synthetic-environment","threadId":"synthetic-thread","historyDelayMs":250,"rpcReplyDelayMs":50,"action":"interruptTurn","historyState":"pending","historyOutcome":"loaded","throughEntryId":null},"events":[{"phase":"history-invocation","ms":0.29669600000124774},{"phase":"history-start","ms":1.048586000000796,"call":1,"environmentId":"synthetic-environment","threadId":"synthetic-thread","throughEntryId":null},{"phase":"command-invocation","ms":1.1457880000016303,"action":"interruptTurn","environmentId":"synthetic-environment","threadId":"synthetic-thread"},{"phase":"rpc-dispatch","ms":1.973630000000412,"type":"run.interrupt","threadId":"synthetic-thread","dispatchCount":1},{"phase":"rpc-receipt","ms":52.44203500000003,"dispatchCount":1},{"phase":"pending-work-reconciled","ms":52.772735000000466,"threadId":"synthetic-thread"},{"phase":"history-release","ms":251.05889300000126,"call":1},{"phase":"all-complete","ms":251.23491300000023,"results":["Success","Failure"]}],"dispatchLatencyMs":[0.8277719999987312],"measurement":"command invocation to RPC dispatch; receipt is separate; provider termination not measured","results":["Success","Failure"],"historyCalls":1,"rpcCalls":1,"projectionCoverage":"scheduler-only controller stub; real history merge captured separately or listed unverified"} Test Files 2 passed (2) Tests 69 passed (69) Start at 22:45:46 Duration 12.53s (tests 83%, import 12%, transform 4%, setup 1%)