nodejs / nodejs/node

test_runner: --test-force-exit with concurrency silently loses test verdicts (parent reports fewer tests than ran, exit 0)

未关闭
#64,833 10 条评论 1 个 reaction 已指派 0 人 在 GitHub 查看

还没有人认领这个 Issue。

test_runner
主要语言
JavaScript
星标
122k
派生
37.4k
平均合并
4 天 3 小时
30 天内合并 PR
272

描述

Version

v26.5.0 (minimal repro below, macOS arm64, Darwin 25.5.0); also observed on v24.18.0 (our CI)

Platform

macOS arm64 (Darwin 25.5.0), also observed on v24.18.0 elsewhere

Subsystem

test_runner

What steps will reproduce the bug?

Run many fast test files under --test-force-exit with --test-concurrency > 1. The parent process silently loses a fraction of the test verdicts: reporters (both built-in tap and a custom one) receive fewer test:pass/test:fail events than tests that actually ran, the parent's own # tests summary counter drops by the same amount, and the run still exits 0. No warning or error of any kind.

Generate 40 files × 50 trivially-passing tests (2000 total):

// gen.mjs
import fs from 'node:fs';
const FILES = 40, TESTS = 50;
fs.rmSync('tests', { recursive: true, force: true });
fs.mkdirSync('tests');
for (let f = 0; f < FILES; f++) {
  const lines = ["import { test } from 'node:test';"];
  for (let t = 0; t < TESTS; t++) {
    lines.push(`test('file ${f} test ${t} — some reasonably long test name padding', () => {});`);
  }
  fs.writeFileSync(`tests/f${f}.test.mjs`, lines.join('\n'));
}

Counting reporter (counts leaf verdicts the parent actually received):

// count-reporter.mjs
import fs from 'node:fs';
export default async function* countReporter(source) {
  let pass = 0, fail = 0;
  for await (const event of source) {
    if (event.type === 'test:pass' || event.type === 'test:fail') {
      if (event.data.details?.type === 'test') {
        if (event.type === 'test:pass') pass++; else fail++;
      }
    }
  }
  fs.writeSync(2, `\nREPORTER-COUNT pass=${pass} fail=${fail} total=${pass + fail}\n`);
}

Run:

node gen.mjs
node --test --test-force-exit --test-concurrency=4 \
  --test-reporter=tap --test-reporter-destination=stdout \
  --test-reporter=./count-reporter.mjs --test-reporter-destination=stderr \
  'tests/*.test.mjs'
How often does it reproduce? Is there a required condition?

Every run in our testing (21/21 runs lost verdicts), with a different loss each time. Three consecutive runs of the exact command above on v26.5.0:

run exit code parent # tests summary custom reporter count actual tests
1 0 # tests 1835 1835 2000
2 0 # tests 1794 1794 2000
3 0 # tests 1936 1936 2000

Both reporters always agree with each other and with the summary counters — the events never reach the parent at all. Removing --test-force-exit (same command otherwise) reports exactly 2000/2000 every time. Loss magnitude varies (we've seen 25–234 of 2000, ~1–12%) and occurs at every concurrency level we tried on a larger real suite; higher concurrency loses more.

Required conditions in our testing: --test-force-exit + --test-concurrency > 1 (default process isolation).

What is the expected behavior? Why is that the expected behavior?

Either every verdict from every child is reported before the forced exit, or the runner fails loudly when it knows it dropped report data. Silent partial results defeat the purpose of a test run: on our production suite (Node v24.18.0, 1351 tests) the parent reported as few as 1168 — and since exit code stays 0 when the lost fragments contain only passes, CI stays green while a meaningful fraction of the suite is unaccounted for. A failing test whose verdict lands in a lost fragment is never printed, so the failure detail can vanish from logs even when the child's non-zero exit still fails the run. (To be precise about what we measured: in the minimal repro the injected failure's verdict happened to survive all 15 sample runs and exit stayed 1; the silent loss of pass verdicts with exit 0 reproduces on every all-pass run.)

What do you see instead?

Fewer tests reported than ran — # tests 1835 for a 2000-test suite — with exit code 0 and no diagnostic (see table above).

Additional information

Suspected mechanism: --test-force-exit calls process.exit() while child→parent report streams are still flushing, so V8-serialized test-event frames from process-isolated children are truncated/dropped before the parent's FileTest parser consumes them. This is the same failure class that #54327 reported for reporter destination files and #55099 fixed — but that fix only awaits the reporter destinations' close() on the parent side before exiting. The flush guarantee was never extended one layer up, to the child→parent report stream itself, which is where these events are being lost (a reporter writing synchronously on every event still never sees them).

Related, reviewed, and believed distinct:

  • #54327 / #55099 — same class, parent-side reporter destinations only (fixed).
  • #63432 — event ordering in process isolation mode; present in v24.18.0 which still exhibits this loss.
  • #62693 / #62704 — FileTest frame parser hang on malformed frames (hang, not loss).
  • #49925 — the original --test-force-exit feature request.

We currently work around this in CI with a committed manifest of expected tests plus a completeness gate that re-runs files whose verdicts went missing — it works, but it amounts to reimplementing "report everything you ran" outside the runner.

贡献指南

打开贡献指南

从这里开始

  1. 先读完整个 Issue,再读项目的贡献指南。
  2. 在 Issue 下留言说明你要接手 —— 这能避免两个人做同样的事。
  3. Fork 仓库,在一个分支上完成修改。
  4. 提交 Pull Request,并在描述里引用这个 Issue 编号。

调研方向

先使用 --test-force-exit 和 --test-concurrency 复现完全相同的命令,然后沿着 test_runner 经过 FileTest 以及子进程到父进程的报告流的路径进行追踪。issue 没有指定源文件或测试,因此先从这些入口点开始跟踪。完成的标准是全部 2,000 个叶 verdict 都一致地到达两个 reporter,或者丢失的数据会产生诊断信息和非成功结果。

由索引模型根据 Issue 内容生成。

评估

技术栈
javascript, node.js
领域
testing-qa
Issue 类型
缺陷
难度
4/5
预计耗时
3-5 天
活跃度
活跃
描述清晰度
基本清楚
新手友好度
56/100

把新 issue 发到你的邮箱

精选适合新手参与的 GitHub issue 摘要。