Files
msd-core/tests/observability/event.test.cjs
Tom Boucher 2d14eb8873 feat(observability): add DispatchLogger seam — ADR-0174 SDK retirement Phase 1.3 (#177) (#223)
* test(#177): add DispatchEvent factory failing tests

Red tests for makeDispatchEvent shape, traceId UUID v4, uniqueness,
parentTraceId-always-undefined (P1.3), args redaction toggle, ISO 8601
timestamp, and all result variant passthrough.

* feat(#177): introduce DispatchEvent factory

makeDispatchEvent produces an immutable event record per dispatch:
- traceId: crypto.randomUUID() (UUID v4)
- parentTraceId: always undefined (P1.4 wires composer)
- command, result, timestamp (ISO 8601)
- args only included when includeArgs === true (default: omitted)

* test(#177): add arg redaction policy failing tests

Red tests for shouldIncludeArgs (GSD_AUDIT_ARGS env gating) and
redactEvent (strips args from frozen events, preserves all other
fields, returns a new object, never mutates the source).

* feat(#177): introduce arg redaction policy

shouldIncludeArgs(): only GSD_AUDIT_ARGS==='1' opts in; all other
values (unset, '', '0', 'true') default to omitting args.

redactEvent(event): returns a shallow copy of the event, dropping the
args field unless opted in. Never mutates the (frozen) source event.

* test(#177): add DispatchLogger interface failing tests

Red tests covering:
- no-op logger: silent on all events, never throws
- default logger: silent on ok, one flattened JSON line to stderr on error
- default logger: audit file creation + append-only + redaction + config gate
- GSD_AUDIT env var and config.audit.enabled config gate
- GSD_AUDIT_ARGS opt-in for args inclusion
All tests use real fs under os.tmpdir() — no mocked appendFileSync.

* feat(#177): introduce DispatchLogger with default and no-op implementations

createNoOpLogger(): silent on all events — Hub default when no logger injected.
createDefaultLogger({ cwd, config }):
  - Silent on ok result
  - Flattened JSON line to stderr on error: { kind, traceId, ...typedPayload }
  - Append-only audit at .planning/.gsd-trace.jsonl when GSD_AUDIT=1 or config.audit.enabled
  - Args redacted by default; GSD_AUDIT_ARGS=1 opts in
  - Logger errors caught internally; never break dispatch callers

* test(#177): add Hub+logger integration failing tests

Red tests verifying:
- onEvent called exactly once per dispatch (ok, error, handler-throw, unknown)
- DispatchEvent shape: traceId uniqueness, command, result.kind, parentTraceId
- Logger errors contained (dispatch still returns Result, warn line to stderr)
- Hub defaults to no-op when no logger injected
- End-to-end with createDefaultLogger: silent on success, stderr on error, audit file

* feat(#177): wire DispatchLogger into CommandRoutingHub

Add optional logger param to createHub({ ..., logger }).
Defaults to createNoOpLogger() — silent, no behaviour change for callers
that don't inject a logger.

After every dispatch (success and error):
- Normalises HubResult { ok } to DispatchEvent { kind: 'ok'|error-kind }
- Calls makeDispatchEvent({ command, args, result }) to mint the event
- Calls logger.onEvent(event) exactly once
- Wraps in try/catch: logger errors emit { level:'warn', source:'DispatchLogger' }
  to stderr but never propagate to dispatch callers

* chore(#177): gitignore .planning/.gsd-trace.jsonl audit file

The audit trail is local-only, append-only, and must never be committed.
Slotted under the existing "Local scratch + Claude-test artifacts" block.

* docs(#177): document GSD_AUDIT, GSD_AUDIT_ARGS, config.audit.enabled

New ## Observability section at end of CONFIGURATION.md covering:
- Default silent/stderr behaviour overview
- Stderr error JSON format
- Audit file opt-in (env var and config key)
- Args redaction policy and GSD_AUDIT_ARGS opt-in

Also slots GSD_AUDIT and GSD_AUDIT_ARGS into the existing
## Environment Variables table (alphabetical order).

* chore(#177): add changeset for observability seam

type: Added — new DispatchLogger seam with default silent/stderr/audit behaviour.
2026-05-24 15:22:36 -04:00

156 lines
5.4 KiB
JavaScript

'use strict';
/**
* Tests for DispatchEvent shape factory (issue #177).
*
* Each test exercises the real module code path and asserts on
* observable behaviour (return values). No mocks, no vacuous truths.
*/
const { describe, test, beforeEach, afterEach } = require('node:test');
const assert = require('node:assert/strict');
const {
makeDispatchEvent,
} = require('../../get-shit-done/bin/lib/observability/event.cjs');
describe('makeDispatchEvent — shape', () => {
test('returns an object with required top-level fields', () => {
const event = makeDispatchEvent({
command: 'plan',
args: ['--tdd'],
result: { kind: 'ok', data: null },
});
assert.ok(typeof event.traceId === 'string', 'traceId must be a string');
assert.ok(typeof event.command === 'string', 'command must be a string');
assert.ok(typeof event.timestamp === 'string', 'timestamp must be a string');
assert.ok('result' in event, 'result must be present');
});
test('traceId is a UUID v4 (format check)', () => {
const event = makeDispatchEvent({
command: 'plan',
result: { kind: 'ok', data: null },
});
// UUID v4: xxxxxxxx-xxxx-4xxx-yxxx-xxxxxxxxxxxx
const uuidV4Re = /^[0-9a-f]{8}-[0-9a-f]{4}-4[0-9a-f]{3}-[89ab][0-9a-f]{3}-[0-9a-f]{12}$/i;
assert.match(event.traceId, uuidV4Re, `traceId '${event.traceId}' is not a valid UUID v4`);
});
test('each call produces a unique traceId', () => {
const a = makeDispatchEvent({ command: 'plan', result: { kind: 'ok', data: null } });
const b = makeDispatchEvent({ command: 'plan', result: { kind: 'ok', data: null } });
assert.notEqual(a.traceId, b.traceId, 'consecutive calls must produce different traceIds');
});
test('parentTraceId is always undefined in P1.3', () => {
const event = makeDispatchEvent({
command: 'plan',
result: { kind: 'ok', data: null },
parentTraceId: 'should-be-ignored',
});
// P1.3: parentTraceId field exists but is always undefined
assert.strictEqual(event.parentTraceId, undefined, 'parentTraceId must be undefined in P1.3');
});
test('command is set from input', () => {
const event = makeDispatchEvent({
command: 'discuss',
result: { kind: 'ok', data: null },
});
assert.equal(event.command, 'discuss');
});
test('timestamp is a valid ISO 8601 string', () => {
const before = Date.now();
const event = makeDispatchEvent({
command: 'plan',
result: { kind: 'ok', data: null },
});
const after = Date.now();
const parsed = Date.parse(event.timestamp);
assert.ok(!isNaN(parsed), `timestamp '${event.timestamp}' must parse as a date`);
assert.ok(parsed >= before, 'timestamp must not be in the past');
assert.ok(parsed <= after + 5, 'timestamp must not be in the future');
});
test('result field is passed through', () => {
const result = { kind: 'UnknownCommand', command: 'bogus' };
const event = makeDispatchEvent({ command: 'bogus', result });
assert.deepStrictEqual(event.result, result);
});
});
describe('makeDispatchEvent — args field', () => {
test('args is omitted when not provided', () => {
const event = makeDispatchEvent({
command: 'plan',
result: { kind: 'ok', data: null },
});
assert.ok(!('args' in event), 'args must not be present when not supplied');
});
test('args is omitted when provided (default redaction)', () => {
// The event factory itself does NOT decide to include args — that is the
// redaction layer's job. makeDispatchEvent simply stores args as supplied.
// By default (no includeArgs), args should NOT appear in the returned event.
const event = makeDispatchEvent({
command: 'plan',
args: ['--foo', 'bar'],
result: { kind: 'ok', data: null },
includeArgs: false,
});
assert.ok(!('args' in event), 'args must be absent when includeArgs is false');
});
test('args is included when includeArgs is true', () => {
const event = makeDispatchEvent({
command: 'plan',
args: ['--foo', 'bar'],
result: { kind: 'ok', data: null },
includeArgs: true,
});
assert.ok('args' in event, 'args must be present when includeArgs is true');
assert.deepStrictEqual(event.args, ['--foo', 'bar']);
});
});
describe('makeDispatchEvent — result variants', () => {
test('ok result shape', () => {
const event = makeDispatchEvent({
command: 'plan',
result: { kind: 'ok', data: 42 },
});
assert.equal(event.result.kind, 'ok');
assert.equal(event.result.data, 42);
});
test('UnknownCommand result shape', () => {
const event = makeDispatchEvent({
command: 'bogus',
result: { kind: 'UnknownCommand', command: 'bogus' },
});
assert.equal(event.result.kind, 'UnknownCommand');
assert.equal(event.result.command, 'bogus');
});
test('InvalidArgs result shape', () => {
const event = makeDispatchEvent({
command: 'plan',
result: { kind: 'InvalidArgs', arg: '--bad', reason: 'not recognised' },
});
assert.equal(event.result.kind, 'InvalidArgs');
assert.equal(event.result.arg, '--bad');
});
test('HandlerFailure result shape', () => {
const event = makeDispatchEvent({
command: 'plan',
result: { kind: 'HandlerFailure', message: 'boom' },
});
assert.equal(event.result.kind, 'HandlerFailure');
assert.equal(event.result.message, 'boom');
});
});