| import { describe, it, expect } from 'vitest'; |
|
|
| import { analyzeWire } from '../src/lib/analysis'; |
| import type { WireEntry } from '../src/types'; |
|
|
| let line = 0; |
| function e(data: Record<string, unknown>, time?: number): WireEntry { |
| line += 1; |
| return { lineNo: line, data: { ...data, time }, raw: data } as unknown as WireEntry; |
| } |
| function loop(event: Record<string, unknown>, time?: number): WireEntry { |
| return e({ type: 'context.append_loop_event', event }, time); |
| } |
|
|
| describe('analyzeWire', () => { |
| it('folds a session into turns/steps/tools with derived metrics', () => { |
| line = 0; |
| const entries: WireEntry[] = [ |
| e({ type: 'turn.prompt', input: [{ type: 'text', text: 'hello' }], origin: { kind: 'user' } }, 1000), |
| loop({ type: 'step.begin', uuid: 's1', turnId: 'T1', step: 0 }, 1100), |
| loop({ type: 'tool.call', uuid: 'tc1', turnId: 'T1', step: 0, stepUuid: 's1', toolCallId: 'c1', name: 'Read' }, 1200), |
| loop({ type: 'tool.result', parentUuid: 'tc1', toolCallId: 'c1', result: { output: 'x'.repeat(50), truncated: true } }, 1500), |
| loop({ type: 'step.end', uuid: 's1', turnId: 'T1', step: 0, finishReason: 'tool_use', llmFirstTokenLatencyMs: 40, usage: { inputOther: 100, output: 20, inputCacheRead: 80, inputCacheCreation: 10 } }, 1600), |
| loop({ type: 'step.begin', uuid: 's2', turnId: 'T1', step: 1 }, 1700), |
| loop({ type: 'step.end', uuid: 's2', turnId: 'T1', step: 1, finishReason: 'end_turn', usage: { inputOther: 200, output: 50, inputCacheRead: 150, inputCacheCreation: 0 } }, 2000), |
| |
| e({ type: 'turn.prompt', input: [{ type: 'text', text: 'again' }], origin: { kind: 'user' } }, 10000), |
| loop({ type: 'step.begin', uuid: 's3', turnId: 'T2', step: 0 }, 10100), |
| loop({ type: 'tool.call', uuid: 'tc2', turnId: 'T2', step: 0, stepUuid: 's3', toolCallId: 'c2', name: 'Read' }, 10200), |
| loop({ type: 'tool.result', parentUuid: 'tc2', toolCallId: 'c2', result: { output: 'y'.repeat(10), isError: true } }, 10250), |
| loop({ type: 'step.end', uuid: 's3', turnId: 'T2', step: 0, finishReason: 'filtered', usage: { inputOther: 300, output: 0, inputCacheRead: 0, inputCacheCreation: 0 } }, 10300), |
| ]; |
|
|
| const a = analyzeWire(entries); |
|
|
| |
| expect(a.turns).toHaveLength(2); |
| expect(a.turns[0]!.promptText).toBe('hello'); |
| expect(a.turns[0]!.trigger).toBe('prompt'); |
| expect(a.turns[0]!.steps).toHaveLength(2); |
| expect(a.turns[1]!.steps).toHaveLength(1); |
|
|
| |
| const tc = a.turns[0]!.steps[0]!.toolCalls[0]!; |
| expect(tc.durationMs).toBe(300); |
| expect(tc.truncated).toBe(true); |
| expect(tc.outputBytes).toBe(50); |
| expect(tc.isError).toBe(false); |
|
|
| |
| expect(a.turns[0]!.steps[0]!.contextTokens).toBe(210); |
| expect(a.turns[0]!.steps[1]!.contextTokens).toBe(400); |
| expect(a.summary.peakContextTokens).toBe(400); |
| expect(a.contextSeries.map((p) => p.contextTokens)).toEqual([210, 400, 300]); |
|
|
| |
| expect(a.turns[0]!.tokens).toEqual({ inputOther: 300, output: 70, inputCacheRead: 230, inputCacheCreation: 10 }); |
|
|
| |
| expect(a.turns[1]!.waitBeforeMs).toBe(8000); |
| expect(a.idleGaps).toHaveLength(1); |
| expect(a.idleGaps[0]).toMatchObject({ gapMs: 8000, kind: 'between_turns', afterLineNo: 7, beforeLineNo: 8 }); |
|
|
| |
| expect(a.turns[1]!.steps[0]!.isError).toBe(true); |
| expect(a.turns[1]!.toolErrorCount).toBe(1); |
|
|
| |
| expect(a.summary.turnCount).toBe(2); |
| expect(a.summary.stepCount).toBe(3); |
| expect(a.summary.toolCallCount).toBe(2); |
| expect(a.summary.toolErrorCount).toBe(1); |
| expect(a.summary.truncatedToolCount).toBe(1); |
|
|
| |
| const read = a.toolStats.find((s) => s.name === 'Read')!; |
| expect(read.count).toBe(2); |
| expect(read.errorCount).toBe(1); |
| expect(read.truncatedCount).toBe(1); |
| expect(read.timedCount).toBe(2); |
| expect(read.totalMs).toBe(350); |
| expect(read.avgMs).toBe(175); |
| expect(read.maxMs).toBe(300); |
| }); |
|
|
| it('handles an empty wire', () => { |
| const a = analyzeWire([]); |
| expect(a.turns).toEqual([]); |
| expect(a.summary.turnCount).toBe(0); |
| expect(a.cache.hitRate).toBeNull(); |
| }); |
|
|
| it('computes cache hit rate from summed input usage', () => { |
| line = 0; |
| const a = analyzeWire([ |
| e({ type: 'turn.prompt', input: [{ type: 'text', text: 'q' }], origin: { kind: 'user' } }, 0), |
| loop({ type: 'step.begin', uuid: 'x', turnId: 'A', step: 0 }, 1), |
| loop({ type: 'step.end', uuid: 'x', turnId: 'A', step: 0, finishReason: 'end_turn', usage: { inputOther: 25, output: 5, inputCacheRead: 75, inputCacheCreation: 0 } }, 2), |
| ]); |
| |
| expect(a.cache.hitRate).toBeCloseTo(0.75, 5); |
| }); |
|
|
| it('collects config.update changes', () => { |
| line = 0; |
| const a = analyzeWire([ |
| e({ type: 'config.update', modelAlias: 'opus', thinkingEffort: 'high', systemPrompt: 'x'.repeat(120) }, 0), |
| e({ type: 'config.update', modelAlias: 'sonnet' }, 10), |
| ]); |
| expect(a.configChanges).toHaveLength(2); |
| expect(a.configChanges[0]!.changed).toEqual([ |
| { field: 'model', value: 'opus' }, |
| { field: 'thinking', value: 'high' }, |
| { field: 'systemPrompt', value: '120 chars' }, |
| ]); |
| expect(a.configChanges[1]!.changed).toEqual([{ field: 'model', value: 'sonnet' }]); |
| }); |
|
|
| it('does not reset context-window fill on a zero-usage step.end', () => { |
| line = 0; |
| const a = analyzeWire([ |
| e({ type: 'turn.prompt', input: [{ type: 'text', text: 'q' }], origin: { kind: 'user' } }, 0), |
| loop({ type: 'step.begin', uuid: 's1', turnId: 'T', step: 0 }, 1), |
| loop({ type: 'step.end', uuid: 's1', turnId: 'T', step: 0, finishReason: 'tool_use', usage: { inputOther: 100, output: 20, inputCacheRead: 80, inputCacheCreation: 0 } }, 2), |
| loop({ type: 'step.begin', uuid: 's2', turnId: 'T', step: 1 }, 3), |
| |
| loop({ type: 'step.end', uuid: 's2', turnId: 'T', step: 1, finishReason: 'filtered', usage: { inputOther: 0, output: 0, inputCacheRead: 0, inputCacheCreation: 0 } }, 4), |
| ]); |
| expect(a.turns[0]!.steps[0]!.contextTokens).toBe(200); |
| expect(a.turns[0]!.steps[1]!.contextTokens).toBe(200); |
| expect(a.contextSeries.map((p) => p.contextTokens)).toEqual([200, 200]); |
| expect(a.summary.peakContextTokens).toBe(200); |
| }); |
|
|
| it('uses context.update_token_count as the absolute context-window fill', () => { |
| line = 0; |
| const a = analyzeWire([ |
| e({ type: 'context.update_token_count', tokenCount: 42 }, 1), |
| ]); |
|
|
| expect(a.summary.contextTokens).toBe(42); |
| expect(a.summary.peakContextTokens).toBe(42); |
| expect(a.contextSeries.map((point) => point.contextTokens)).toEqual([42]); |
| }); |
|
|
| it('keeps steering inside the active turn and folds the durable turn outcome', () => { |
| line = 0; |
| const a = analyzeWire([ |
| e({ type: 'turn.prompt', input: [{ type: 'text', text: 'start' }], origin: { kind: 'user' } }, 1000), |
| loop({ type: 'step.begin', uuid: 's1', turnId: '7', step: 0 }, 1100), |
| loop({ type: 'content.part', stepUuid: 's1', part: { type: 'think', think: 'reasoning' } }, 1150), |
| e({ type: 'turn.steer', input: [{ type: 'text', text: 'one more thing' }], origin: { kind: 'user' } }, 1200), |
| loop({ type: 'step.end', uuid: 's1', turnId: '7', step: 0, finishReason: 'end_turn' }, 1400), |
| e({ type: 'turn.ended', agentId: 'main', turnId: 7, reason: 'completed', durationMs: 450, stopReason: 'repeat_breaker' }, 1500), |
| e({ type: 'token_counting.turn_recorded', agentId: 'main', turnId: 7, length: 2, tokens: 50 }, 1501), |
| e({ type: 'turn.prompt', input: [{ type: 'text', text: 'next' }], origin: { kind: 'user' } }, 3000), |
| ]); |
|
|
| expect(a.turns).toHaveLength(2); |
| expect(a.turns[0]).toMatchObject({ |
| turnId: 7, |
| endTime: 1500, |
| durationMs: 450, |
| outcome: 'completed', |
| stopReason: 'repeat_breaker', |
| }); |
| expect(a.turns[0]!.steps[0]!.content.thinkChars).toBe(9); |
| expect(a.turns[1]!.waitBeforeMs).toBe(1500); |
| expect(a.contextSeries.at(-1)?.turnIndex).toBe(0); |
| expect(a.summary.activeMs).toBe(450); |
| }); |
|
|
| it('uses a steer as the trigger when the next step belongs to a new turn', () => { |
| line = 0; |
| const steerLine = 4; |
| const a = analyzeWire([ |
| e({ type: 'turn.prompt', input: [{ type: 'text', text: 'start' }], origin: { kind: 'user' } }, 1000), |
| loop({ type: 'step.begin', uuid: 's1', turnId: '7', step: 0 }, 1100), |
| loop({ type: 'step.end', uuid: 's1', turnId: '7', step: 0, finishReason: 'end_turn', usage: { inputOther: 10, output: 2, inputCacheRead: 0, inputCacheCreation: 0 } }, 1200), |
| e({ type: 'turn.steer', input: [{ type: 'text', text: 'continue' }], origin: { kind: 'system_trigger' } }, 1300), |
| loop({ type: 'step.begin', uuid: 's2', turnId: '8', step: 0 }, 1400), |
| loop({ type: 'tool.call', uuid: 'tc2', turnId: '8', step: 0, stepUuid: 's2', toolCallId: 'c2', name: 'Read' }, 1450), |
| loop({ type: 'tool.result', parentUuid: 'tc2', toolCallId: 'c2', result: { output: 'done' } }, 1500), |
| loop({ type: 'step.end', uuid: 's2', turnId: '8', step: 0, finishReason: 'end_turn', usage: { inputOther: 20, output: 4, inputCacheRead: 5, inputCacheCreation: 1 } }, 1600), |
| ]); |
|
|
| expect(a.turns).toHaveLength(2); |
| expect(a.turns[0]).toMatchObject({ |
| trigger: 'prompt', |
| turnId: 7, |
| steps: [{ uuid: 's1' }], |
| tokens: { inputOther: 10, output: 2, inputCacheRead: 0, inputCacheCreation: 0 }, |
| toolCallCount: 0, |
| }); |
| expect(a.turns[1]).toMatchObject({ |
| trigger: 'steer', |
| promptLineNo: steerLine, |
| promptTime: 1300, |
| promptText: 'continue', |
| originKind: 'system_trigger', |
| turnId: 8, |
| steps: [{ uuid: 's2' }], |
| tokens: { inputOther: 20, output: 4, inputCacheRead: 5, inputCacheCreation: 1 }, |
| toolCallCount: 1, |
| }); |
| }); |
|
|
| it('splits truncated wires when step turn ids advance without a prompt record', () => { |
| line = 0; |
| const a = analyzeWire([ |
| e({ type: 'turn.prompt', input: [{ type: 'text', text: 'start' }], origin: { kind: 'user' } }, 1000), |
| loop({ type: 'step.begin', uuid: 's1', turnId: '7', step: 0 }, 1100), |
| loop({ type: 'step.end', uuid: 's1', turnId: '7', step: 0, finishReason: 'end_turn' }, 1200), |
| loop({ type: 'step.begin', uuid: 's2', turnId: '8', step: 0 }, 1300), |
| loop({ type: 'step.end', uuid: 's2', turnId: '8', step: 0, finishReason: 'end_turn' }, 1400), |
| ]); |
|
|
| expect(a.turns).toHaveLength(2); |
| expect(a.turns[0]).toMatchObject({ turnId: 7, steps: [{ uuid: 's1' }] }); |
| expect(a.turns[1]).toMatchObject({ |
| trigger: 'prompt', |
| promptLineNo: 4, |
| promptText: '(no prompt record)', |
| turnId: 8, |
| steps: [{ uuid: 's2' }], |
| }); |
| }); |
|
|
| it('marks persisted error step endings as errors', () => { |
| line = 0; |
| const analysis = analyzeWire([ |
| e({ type: 'turn.prompt', input: [{ type: 'text', text: 'start' }], origin: { kind: 'user' } }, 1000), |
| loop({ type: 'step.begin', uuid: 's1', turnId: '3', step: 0 }, 1100), |
| loop({ type: 'step.end', uuid: 's1', turnId: '3', step: 0, finishReason: 'error' }, 1200), |
| e({ type: 'turn.ended', agentId: 'main', turnId: 3, reason: 'failed' }, 1250), |
| ]); |
|
|
| expect(analysis.turns[0]?.steps[0]?.isError).toBe(true); |
| expect(analysis.turns[0]?.outcome).toBe('failed'); |
| }); |
| }); |
|
|