The `full test (windows-latest, 22)` job intermittently got CANCELLED at its 20m wall-clock cap with no failed test step — a false-negative gate (recurrence of #869). Root cause: a unit test leaves an open event-loop handle, so the chunk's `node --test` child hangs ~150s on Windows after its last test prints; two such stalls push the already-~13m job past 20m. Fix (defense in depth): - run-tests.cjs: pass --test-force-exit (Node >=22; engines requires >=22.0.0) so the runner exits once all tests finish regardless of lingering handles — the durable backstop. Account for the flag in the argv-length ceiling. - run-tests.cjs: add a per-chunk execFileSync timeout (default 600000ms, env RUN_TESTS_CHUNK_TIMEOUT_MS) that fails loudly with a diagnostic naming the chunk's files, so a hung chunk can never silently eat the job budget. - perf-316 test: terminate both Worker threads on all paths (afterEach + finally) so they cannot outlive the test. - locking-bugs test: kill spawned children in a finally that wraps the whole spawn -> waitFor -> barrier-release -> Promise.all sequence, so a barrier timeout no longer leaks live child processes. - Refresh the stale synckit comment (synckit/SDK bridge was removed). Regression tests in run-tests-harness: a hung chunk hits the per-chunk timeout and fails with a clear message; force-exit lets a chunk with a leaked handle exit cleanly. Closes #1051 Co-authored-by: github-actions[bot] <41898282+github-actions[bot]@users.noreply.github.com> Co-authored-by: Claude Opus 4.8 <noreply@anthropic.com>
283 lines
12 KiB
JavaScript
283 lines
12 KiB
JavaScript
/**
|
|
* Regression test for perf #316 — acquireStateLock allocates a fresh
|
|
* SharedArrayBuffer on every retry iteration.
|
|
*
|
|
* The fix: hoist the sleep buffer allocation to once before the retry loop.
|
|
* The buffer is never mutated and never escapes — Atomics.wait(buf,0,0,delay)
|
|
* always sees 0 whether the buffer is fresh or reused, so the behavior is
|
|
* identical.
|
|
*
|
|
* Observable invariant (POST-FIX): exactly ONE SharedArrayBuffer is allocated
|
|
* per acquireStateLock call, regardless of retry count.
|
|
*
|
|
* RED (pre-fix): sabCount >= 2 when >= 1 retry occurs.
|
|
* GREEN (post-fix): sabCount === 1.
|
|
*
|
|
* Strategy: two Worker threads run in parallel.
|
|
* Worker A (lock holder): writes the lock file with the current process pid,
|
|
* sleeps 400ms via Atomics.wait, then removes the lock.
|
|
* Worker B (writer): installs a counting SharedArrayBuffer stub, then calls
|
|
* writeStateMd — which calls acquireStateLock and retries until A releases.
|
|
* Reports sabCount via postMessage.
|
|
*
|
|
* Using Worker threads (not child processes) avoids the node --test subprocess-
|
|
* detection hang that occurs with spawn() inside a test runner worker context.
|
|
*
|
|
* Total test wall-time: ~400-600ms.
|
|
*/
|
|
|
|
const { test, describe, beforeEach, afterEach } = require('node:test');
|
|
const assert = require('node:assert/strict');
|
|
const fs = require('fs');
|
|
const path = require('path');
|
|
const os = require('os');
|
|
const { Worker } = require('worker_threads');
|
|
const { cleanup } = require('./helpers.cjs');
|
|
|
|
// ─────────────────────────────────────────────────────────────────────────────
|
|
// Constants
|
|
// ─────────────────────────────────────────────────────────────────────────────
|
|
|
|
const STATE_CJS_PATH = path.join(
|
|
__dirname, '..', 'gsd-core', 'bin', 'lib', 'state.cjs'
|
|
);
|
|
|
|
const MINIMAL_STATE_MD = [
|
|
'# Project State',
|
|
'',
|
|
'**Status:** Planning',
|
|
'**Current Phase:** 01',
|
|
].join('\n') + '\n';
|
|
|
|
// Worker A: holds the lock file for holdMs, then removes it.
|
|
// workerData: { lockPath, holdMs }
|
|
const HOLDER_WORKER_CODE = `
|
|
const { parentPort, workerData } = require('worker_threads');
|
|
const fs = require('fs');
|
|
// Write pid to lock file so acquireStateLock sees a live pid and retries.
|
|
fs.writeFileSync(workerData.lockPath, String(process.pid));
|
|
parentPort.postMessage({ pid: process.pid });
|
|
// Synchronous sleep — blocks this worker thread for holdMs ms.
|
|
const buf = new Int32Array(new SharedArrayBuffer(4));
|
|
Atomics.wait(buf, 0, 0, workerData.holdMs);
|
|
// Release the lock.
|
|
try { fs.unlinkSync(workerData.lockPath); } catch { /* already gone */ }
|
|
parentPort.postMessage({ done: true });
|
|
`;
|
|
|
|
// Worker B: stubs global.SharedArrayBuffer with a counting call-through wrapper,
|
|
// then calls writeStateMd (triggering acquireStateLock), and reports sabCount.
|
|
// workerData: { stateCjsPath, statePath, content, tmpDir }
|
|
const WRITER_WORKER_CODE = `
|
|
const { parentPort, workerData } = require('worker_threads');
|
|
const fs = require('fs');
|
|
const RealSAB = global.SharedArrayBuffer;
|
|
let sabCount = 0;
|
|
// Stub: increments sabCount, calls through so Atomics.wait gets a real SAB-backed buffer.
|
|
function StubSAB(...args) {
|
|
sabCount++;
|
|
return new RealSAB(...args);
|
|
}
|
|
StubSAB.prototype = RealSAB.prototype;
|
|
global.SharedArrayBuffer = StubSAB;
|
|
|
|
// Lock-attempt counter: stubs fs.openSync to count atomic-create attempts
|
|
// (state.cjs's acquireStateLock uses fs.openSync(..., O_CREAT|O_EXCL|O_WRONLY)
|
|
// to atomically create the lock file). Each call with O_CREAT|O_EXCL flags
|
|
// is one retry-loop iteration. >=2 attempts proves the SUT entered the retry
|
|
// path — without this witness, a no-retry success would yield sabCount === 1
|
|
// from BOTH pre-fix and post-fix code (the SAB is allocated unconditionally
|
|
// post-fix, and exactly once for the single successful open pre-fix), giving
|
|
// a false-pass against the bug. The 1000ms holdMs + 200ms SUT retry delay
|
|
// guarantees >=4 attempts even on the slowest CI runners.
|
|
const realOpenSync = fs.openSync.bind(fs);
|
|
let lockAttempts = 0;
|
|
fs.openSync = function(filePath, flags, mode) {
|
|
if (typeof filePath === 'string' && filePath.endsWith('.lock') &&
|
|
typeof flags === 'number' &&
|
|
(flags & fs.constants.O_CREAT) && (flags & fs.constants.O_EXCL)) {
|
|
lockAttempts++;
|
|
}
|
|
return realOpenSync(filePath, flags, mode);
|
|
};
|
|
|
|
// Delete cache entry to ensure a fresh require picks up the stubbed constructor.
|
|
// (The inline "new SharedArrayBuffer(4)" in acquireStateLock reads the global at
|
|
// call time, so even a cached require would use our stub — but deleting avoids
|
|
// any module-level SAB allocations from a prior require contaminating sabCount.)
|
|
delete require.cache[workerData.stateCjsPath];
|
|
const { writeStateMd } = require(workerData.stateCjsPath);
|
|
|
|
let callErr = null;
|
|
try {
|
|
writeStateMd(workerData.statePath, workerData.content, workerData.tmpDir);
|
|
} catch (e) {
|
|
callErr = (e && e.message) ? e.message : String(e);
|
|
}
|
|
parentPort.postMessage({ sabCount, lockAttempts, callErr });
|
|
`;
|
|
|
|
// ─────────────────────────────────────────────────────────────────────────────
|
|
// Helpers
|
|
// ─────────────────────────────────────────────────────────────────────────────
|
|
|
|
function makeTempDir() {
|
|
const dir = fs.mkdtempSync(path.join(os.tmpdir(), 'gsd-316-'));
|
|
fs.mkdirSync(path.join(dir, '.planning'), { recursive: true });
|
|
return dir;
|
|
}
|
|
|
|
function removeTempDir(dir) {
|
|
try { cleanup(dir); } catch { /* ignore */ }
|
|
}
|
|
|
|
// ─────────────────────────────────────────────────────────────────────────────
|
|
// Test
|
|
// ─────────────────────────────────────────────────────────────────────────────
|
|
|
|
describe('perf #316: acquireStateLock hoists sleep buffer — exactly one SAB per call', () => {
|
|
let tmpDir;
|
|
let statePath;
|
|
let lockPath;
|
|
let holderWorker;
|
|
|
|
beforeEach(() => {
|
|
tmpDir = makeTempDir();
|
|
statePath = path.join(tmpDir, '.planning', 'STATE.md');
|
|
lockPath = statePath + '.lock';
|
|
fs.writeFileSync(statePath, MINIMAL_STATE_MD, 'utf-8');
|
|
});
|
|
|
|
afterEach(async () => {
|
|
await holderWorker?.terminate();
|
|
holderWorker = null;
|
|
try { fs.unlinkSync(lockPath); } catch { /* already gone */ }
|
|
removeTempDir(tmpDir);
|
|
});
|
|
|
|
test(
|
|
'sabCount === 1 after a call that undergoes >= 1 retry (post-fix assertion)',
|
|
{ timeout: 8000 },
|
|
async () => {
|
|
// ── Worker A: hold the lock for 1000ms ─────────────────────────────────
|
|
// state.cjs retry delay = 200ms + 0-50ms jitter; 1000ms hold guarantees
|
|
// >=4 retries even on the slowest CI worker (~200ms spawn + 4 retry
|
|
// intervals ~1000ms ≈ hold duration). The lockAttempts assertion below
|
|
// proves the retry path was exercised end-to-end.
|
|
const holdMs = 1000;
|
|
let resolveLockWritten;
|
|
const lockWritten = new Promise((resolve) => { resolveLockWritten = resolve; });
|
|
const holderDone = new Promise((resolve, reject) => {
|
|
holderWorker = new Worker(HOLDER_WORKER_CODE, {
|
|
eval: true,
|
|
workerData: { lockPath, holdMs },
|
|
});
|
|
holderWorker.on('message', (msg) => {
|
|
if (msg.pid !== undefined) resolveLockWritten();
|
|
if (msg.done) resolve();
|
|
});
|
|
holderWorker.on('error', (err) => {
|
|
resolveLockWritten(); // unblock so the assert below fires immediately
|
|
reject(err);
|
|
});
|
|
holderWorker.on('exit', (code) => {
|
|
resolveLockWritten(); // unblock if Worker A exits before posting
|
|
if (code !== 0) reject(new Error('Holder worker exit code: ' + code));
|
|
});
|
|
});
|
|
// Suppress unhandled-rejection warnings on holderDone — we always observe
|
|
// it later via `await holderDone`, which re-throws the original error.
|
|
holderDone.catch(() => {});
|
|
|
|
// Deterministic synchronization: await Worker A's {pid} message, which
|
|
// it posts AFTER fs.writeFileSync returns (single-thread source order
|
|
// within the worker). By the time the parent receives this message,
|
|
// the lock file exists on disk and is visible across threads (workers
|
|
// share the same OS file table). The MessagePort buffers messages
|
|
// posted before the listener attaches, so there is no listener-race.
|
|
// Ref: https://nodejs.org/api/worker_threads.html#event-message_1
|
|
// The 5000ms safety timeout catches a hung holder; nominal latency <50ms.
|
|
let lockWrittenTimer;
|
|
const lockWrittenTimeout = new Promise((_, reject) => {
|
|
lockWrittenTimer = setTimeout(
|
|
() => reject(new Error('Holder worker did not post pid within 5000ms')),
|
|
5000
|
|
);
|
|
});
|
|
try {
|
|
await Promise.race([lockWritten, lockWrittenTimeout]);
|
|
} finally {
|
|
clearTimeout(lockWrittenTimer);
|
|
}
|
|
assert.ok(fs.existsSync(lockPath), 'Worker A must have written the lock file');
|
|
|
|
// ── Worker B: call writeStateMd, measure SAB allocations ───────────────
|
|
let writerWorker;
|
|
let writeResult;
|
|
try {
|
|
writeResult = await new Promise((resolve, reject) => {
|
|
writerWorker = new Worker(WRITER_WORKER_CODE, {
|
|
eval: true,
|
|
workerData: {
|
|
stateCjsPath: STATE_CJS_PATH,
|
|
statePath,
|
|
content: MINIMAL_STATE_MD,
|
|
tmpDir,
|
|
},
|
|
});
|
|
writerWorker.on('message', resolve);
|
|
writerWorker.on('error', reject);
|
|
writerWorker.on('exit', (code) => {
|
|
if (code !== 0) reject(new Error('Writer worker exit code: ' + code));
|
|
});
|
|
});
|
|
} finally {
|
|
await writerWorker?.terminate();
|
|
}
|
|
|
|
// Wait for Worker A to finish releasing
|
|
await holderDone;
|
|
|
|
// ── Assertions ─────────────────────────────────────────────────────────
|
|
assert.ok(
|
|
writeResult.callErr === null,
|
|
'writeStateMd must succeed once the lock is released — error: ' + writeResult.callErr
|
|
);
|
|
|
|
assert.ok(
|
|
writeResult.sabCount >= 1,
|
|
'at least one SharedArrayBuffer must be allocated (the sleep buffer must exist)'
|
|
);
|
|
|
|
// PROOF OF RETRY-PATH COVERAGE (Contract 4 of test-rigor):
|
|
// The sabCount === 1 invariant below only discriminates pre-fix from
|
|
// post-fix when the SUT actually entered the retry loop. Without this
|
|
// witness, a no-retry success path yields sabCount === 1 under BOTH
|
|
// pre-fix and post-fix code (one SAB for the single successful open).
|
|
// lockAttempts counts atomic-create attempts (fs.openSync with
|
|
// O_CREAT|O_EXCL); >=2 means at least one failed-then-retried.
|
|
assert.ok(
|
|
writeResult.lockAttempts >= 2,
|
|
'SUT must have entered the retry path (>=1 failed lock attempt before success). ' +
|
|
'Got lockAttempts: ' + writeResult.lockAttempts + '. The 1000ms holdMs + 200ms ' +
|
|
'SUT retry delay guarantees >=2 attempts on any CI runner.'
|
|
);
|
|
|
|
// THE KEY INVARIANT:
|
|
// POST-FIX: sabCount === 1 (buffer allocated once, before the retry loop)
|
|
// PRE-FIX: sabCount === lockAttempts (new buffer on EVERY iteration,
|
|
// both successful and failed)
|
|
// Combined with lockAttempts >= 2 above, sabCount === 1 strictly proves
|
|
// the buffer is hoisted (post-fix). Pre-fix code would observe sabCount
|
|
// equal to the iteration count, never 1.
|
|
assert.strictEqual(
|
|
writeResult.sabCount,
|
|
1,
|
|
'post-fix: exactly one SharedArrayBuffer must be allocated per acquireStateLock call ' +
|
|
'(buffer hoisted before retry loop). Got: ' + writeResult.sabCount +
|
|
' across ' + writeResult.lockAttempts + ' lock attempts.'
|
|
);
|
|
}
|
|
);
|
|
});
|