Repository navigation
test-cpu-prof-worker-argv multiple assertion errors #34031
Description
Activity
- addedflaky-testIssues and PRs involving tests that fail intermittently in CI.Issues and PRs involving tests that fail intermittently in CI.
on Jun 23, 2020 Seeing it on GitHub Actions CI for macOS too.
https://github.com/nodejs/node/pull/34096/checks?check_run_id=815269380
Path: sequential/test-cpu-prof-worker-argv --- stderr --- assert.js:103 throw new AssertionError(obj); ^ AssertionError [ERR_ASSERTION]: Expected "actual" not to be strictly deep-equal to: [] at verifyFrames (/Users/runner/runners/2.263.0/work/node/node/test/common/cpu-prof.js:34:10) at Object.<anonymous> (/Users/runner/runners/2.263.0/work/node/node/test/sequential/test-cpu-prof-worker-argv.js:37:3) at Module._compile (internal/modules/cjs/loader.js:1217:30) at Object.Module._extensions..js (internal/modules/cjs/loader.js:1238:10) at Module.load (internal/modules/cjs/loader.js:1066:32) at Function.Module._load (internal/modules/cjs/loader.js:954:14) at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:71:12) at internal/main/run_main_module.js:17:47 { generatedMessage: true, code: 'ERR_ASSERTION', actual: [], expected: [], operator: 'notDeepStrictEqual' }There is a lot of stuff in stdout but GitHub Actions does not make copy/paste for large things easy. (It's entirely possible there is a way to make it easy, but I"m unfamiliar with the interface for the most part.) Here's how it starts:
[ { id: 1, callFrame: { functionName: '(root)', scriptId: '0', url: '', lineNumber: -1, columnNumber: -1 }, hitCount: 0, children: [ 2, 3, 10, 19, 20, 38 ] }, { id: 2, callFrame: { functionName: '(program)', scriptId: '0', url: '', lineNumber: -1,Again on macOS CI today:
https://ci.nodejs.org/job/node-test-commit-osx/37895/nodes=osx1015/console
Details
00:23:31 not ok 3106 sequential/test-cpu-prof-worker-argv 00:23:31 --- 00:23:31 duration_ms: 0.821 00:23:31 severity: fail 00:23:31 exitcode: 1 00:23:31 stack: |- 00:23:31 00:23:31 [ 00:23:31 { 00:23:31 id: 1, 00:23:31 callFrame: { 00:23:31 functionName: '(root)', 00:23:31 scriptId: '0', 00:23:31 url: '', 00:23:31 lineNumber: -1, 00:23:31 columnNumber: -1 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 2, 3, 6, 24, 25, 28 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 2, 00:23:31 callFrame: { 00:23:31 functionName: '(program)', 00:23:31 scriptId: '0', 00:23:31 url: '', 00:23:31 lineNumber: -1, 00:23:31 columnNumber: -1 00:23:31 }, 00:23:31 hitCount: 0 00:23:31 }, 00:23:31 { 00:23:31 id: 3, 00:23:31 callFrame: { 00:23:31 functionName: '', 00:23:31 scriptId: '10', 00:23:31 url: 'node:internal/bootstrap/node', 00:23:31 lineNumber: 0, 00:23:31 columnNumber: 0 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 4, 7, 10, 15 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 4, 00:23:31 callFrame: { 00:23:31 functionName: 'nativeModuleRequire', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 302, 00:23:31 columnNumber: 28 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 children: [ 5 ], 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 5, 00:23:31 callFrame: { 00:23:31 functionName: 'compileForInternalLoader', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 269, 00:23:31 columnNumber: 26 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 children: [ 14, 20 ], 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 14, 00:23:31 callFrame: { 00:23:31 functionName: '', 00:23:31 scriptId: '22', 00:23:31 url: 'node:internal/async_hooks', 00:23:31 lineNumber: 0, 00:23:31 columnNumber: 0 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 20, 00:23:31 callFrame: { 00:23:31 functionName: '', 00:23:31 scriptId: '30', 00:23:31 url: 'node:internal/url', 00:23:31 lineNumber: 0, 00:23:31 columnNumber: 0 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 21 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 21, 00:23:31 callFrame: { 00:23:31 functionName: 'nativeModuleRequire', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 302, 00:23:31 columnNumber: 28 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 22 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 22, 00:23:31 callFrame: { 00:23:31 functionName: 'compileForInternalLoader', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 269, 00:23:31 columnNumber: 26 00:23:31 }, 00:23:31 hitCount: 3, 00:23:31 children: [ 23 ], 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 23, 00:23:31 callFrame: { 00:23:31 functionName: '', 00:23:31 scriptId: '31', 00:23:31 url: 'node:internal/querystring', 00:23:31 lineNumber: 0, 00:23:31 columnNumber: 0 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 7, 00:23:31 callFrame: { 00:23:31 functionName: 'setupProcessObject', 00:23:31 scriptId: '10', 00:23:31 url: 'node:internal/bootstrap/node', 00:23:31 lineNumber: 298, 00:23:31 columnNumber: 27 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 8 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 8, 00:23:31 callFrame: { 00:23:31 functionName: 'EventEmitter', 00:23:31 scriptId: '13', 00:23:31 url: 'node:events', 00:23:31 lineNumber: 82, 00:23:31 columnNumber: 21 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 9 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 9, 00:23:31 callFrame: { 00:23:31 functionName: 'EventEmitter.init', 00:23:31 scriptId: '13', 00:23:31 url: 'node:events', 00:23:31 lineNumber: 191, 00:23:31 columnNumber: 28 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 10, 00:23:31 callFrame: { 00:23:31 functionName: 'setupBuffer', 00:23:31 scriptId: '10', 00:23:31 url: 'node:internal/bootstrap/node', 00:23:31 lineNumber: 327, 00:23:31 columnNumber: 20 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 11 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 11, 00:23:31 callFrame: { 00:23:31 functionName: 'nativeModuleRequire', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 302, 00:23:31 columnNumber: 28 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 12 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 12, 00:23:31 callFrame: { 00:23:31 functionName: 'compileForInternalLoader', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 269, 00:23:31 columnNumber: 26 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 children: [ 13 ], 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 13, 00:23:31 callFrame: { 00:23:31 functionName: '', 00:23:31 scriptId: '18', 00:23:31 url: 'node:buffer', 00:23:31 lineNumber: 0, 00:23:31 columnNumber: 0 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 15, 00:23:31 callFrame: { 00:23:31 functionName: 'createGlobalConsole', 00:23:31 scriptId: '10', 00:23:31 url: 'node:internal/bootstrap/node', 00:23:31 lineNumber: 344, 00:23:31 columnNumber: 28 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 16, 19 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 16, 00:23:31 callFrame: { 00:23:31 functionName: 'nativeModuleRequire', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 302, 00:23:31 columnNumber: 28 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 17 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 17, 00:23:31 callFrame: { 00:23:31 functionName: 'compileForInternalLoader', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 269, 00:23:31 columnNumber: 26 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 18 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 18, 00:23:31 callFrame: { 00:23:31 functionName: '', 00:23:31 scriptId: '26', 00:23:31 url: 'node:internal/console/global', 00:23:31 lineNumber: 0, 00:23:31 columnNumber: 0 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 19, 00:23:31 callFrame: { 00:23:31 functionName: 'wrapConsole', 00:23:31 scriptId: '29', 00:23:31 url: 'node:internal/util/inspector', 00:23:31 lineNumber: 35, 00:23:31 columnNumber: 20 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 6, 00:23:31 callFrame: { 00:23:31 functionName: '(garbage collector)', 00:23:31 scriptId: '0', 00:23:31 url: '', 00:23:31 lineNumber: -1, 00:23:31 columnNumber: -1 00:23:31 }, 00:23:31 hitCount: 2 00:23:31 }, 00:23:31 { 00:23:31 id: 24, 00:23:31 callFrame: { 00:23:31 functionName: '', 00:23:31 scriptId: '58', 00:23:31 url: 'node:internal/bootstrap/switches/is_not_main_thread', 00:23:31 lineNumber: 0, 00:23:31 columnNumber: 0 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 25, 00:23:31 callFrame: { 00:23:31 functionName: 'oninit', 00:23:31 scriptId: '38', 00:23:31 url: 'node:internal/worker/io', 00:23:31 lineNumber: 159, 00:23:31 columnNumber: 15 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 26 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 26, 00:23:31 callFrame: { 00:23:31 functionName: 'initNodeEventTarget', 00:23:31 scriptId: '35', 00:23:31 url: 'node:internal/event_target', 00:23:31 lineNumber: 463, 00:23:31 columnNumber: 28 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 27 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 27, 00:23:31 callFrame: { 00:23:31 functionName: 'initEventTarget', 00:23:31 scriptId: '35', 00:23:31 url: 'node:internal/event_target', 00:23:31 lineNumber: 230, 00:23:31 columnNumber: 24 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 28, 00:23:31 callFrame: { 00:23:31 functionName: 'exports.emitMessage', 00:23:31 scriptId: '8', 00:23:31 url: 'node:internal/per_context/messageport', 00:23:31 lineNumber: 15, 00:23:31 columnNumber: 30 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 29 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 29, 00:23:31 callFrame: { 00:23:31 functionName: '', 00:23:31 scriptId: '35', 00:23:31 url: 'node:internal/event_target', 00:23:31 lineNumber: 378, 00:23:31 columnNumber: 19 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 30 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 30, 00:23:31 callFrame: { 00:23:31 functionName: '', 00:23:31 scriptId: '62', 00:23:31 url: 'node:internal/main/worker_thread', 00:23:31 lineNumber: 97, 00:23:31 columnNumber: 19 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 31, 36, 45 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 31, 00:23:31 callFrame: { 00:23:31 functionName: 'initializeReport', 00:23:31 scriptId: '63', 00:23:31 url: 'node:internal/bootstrap/pre_execution', 00:23:31 lineNumber: 178, 00:23:31 columnNumber: 25 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 32 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 32, 00:23:31 callFrame: { 00:23:31 functionName: 'nativeModuleRequire', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 302, 00:23:31 columnNumber: 28 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 33 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 33, 00:23:31 callFrame: { 00:23:31 functionName: 'compileForInternalLoader', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 269, 00:23:31 columnNumber: 26 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 34 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 34, 00:23:31 callFrame: { 00:23:31 functionName: '', 00:23:31 scriptId: '69', 00:23:31 url: 'node:internal/process/report', 00:23:31 lineNumber: 0, 00:23:31 columnNumber: 0 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 35 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 35, 00:23:31 callFrame: { 00:23:31 functionName: 'internalBinding', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 136, 00:23:31 columnNumber: 44 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 36, 00:23:31 callFrame: { 00:23:31 functionName: 'initializeCJSLoader', 00:23:31 scriptId: '63', 00:23:31 url: 'node:internal/bootstrap/pre_execution', 00:23:31 lineNumber: 412, 00:23:31 columnNumber: 28 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 37 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 37, 00:23:31 callFrame: { 00:23:31 functionName: 'nativeModuleRequire', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 302, 00:23:31 columnNumber: 28 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 38 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 38, 00:23:31 callFrame: { 00:23:31 functionName: 'compileForInternalLoader', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 269, 00:23:31 columnNumber: 26 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 39 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 39, 00:23:31 callFrame: { 00:23:31 functionName: '', 00:23:31 scriptId: '70', 00:23:31 url: 'node:internal/modules/cjs/loader', 00:23:31 lineNumber: 0, 00:23:31 columnNumber: 0 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 40 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 40, 00:23:31 callFrame: { 00:23:31 functionName: 'nativeModuleRequire', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 302, 00:23:31 columnNumber: 28 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 41 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 41, 00:23:31 callFrame: { 00:23:31 functionName: 'compileForInternalLoader', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 269, 00:23:31 columnNumber: 26 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 children: [ 42 ], 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 42, 00:23:31 callFrame: { 00:23:31 functionName: '', 00:23:31 scriptId: '71', 00:23:31 url: 'node:internal/source_map/source_map_cache', 00:23:31 lineNumber: 0, 00:23:31 columnNumber: 0 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 43 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 43, 00:23:31 callFrame: { 00:23:31 functionName: 'nativeModuleRequire', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 302, 00:23:31 columnNumber: 28 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 44 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 44, 00:23:31 callFrame: { 00:23:31 functionName: 'compileForInternalLoader', 00:23:31 scriptId: '9', 00:23:31 url: 'node:internal/bootstrap/loaders', 00:23:31 lineNumber: 269, 00:23:31 columnNumber: 26 00:23:31 }, 00:23:31 hitCount: 1, 00:23:31 positionTicks: [ [Object] ] 00:23:31 }, 00:23:31 { 00:23:31 id: 45, 00:23:31 callFrame: { 00:23:31 functionName: 'executeUserEntryPoint', 00:23:31 scriptId: '94', 00:23:31 url: 'node:internal/modules/run_main', 00:23:31 lineNumber: 68, 00:23:31 columnNumber: 30 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 46 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 46, 00:23:31 callFrame: { 00:23:31 functionName: 'Module._load', 00:23:31 scriptId: '70', 00:23:31 url: 'node:internal/modules/cjs/loader', 00:23:31 lineNumber: 747, 00:23:31 columnNumber: 23 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 47 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 47, 00:23:31 callFrame: { 00:23:31 functionName: 'Module.load', 00:23:31 scriptId: '70', 00:23:31 url: 'node:internal/modules/cjs/loader', 00:23:31 lineNumber: 960, 00:23:31 columnNumber: 32 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 48 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 48, 00:23:31 callFrame: { 00:23:31 functionName: 'Module._extensions..js', 00:23:31 scriptId: '70', 00:23:31 url: 'node:internal/modules/cjs/loader', 00:23:31 lineNumber: 1116, 00:23:31 columnNumber: 36 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 49 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 49, 00:23:31 callFrame: { 00:23:31 functionName: 'readFileSync', 00:23:31 scriptId: '72', 00:23:31 url: 'node:fs', 00:23:31 lineNumber: 392, 00:23:31 columnNumber: 21 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 50 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 50, 00:23:31 callFrame: { 00:23:31 functionName: 'tryReadSync', 00:23:31 scriptId: '72', 00:23:31 url: 'node:fs', 00:23:31 lineNumber: 380, 00:23:31 columnNumber: 20 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 51 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 51, 00:23:31 callFrame: { 00:23:31 functionName: 'readSync', 00:23:31 scriptId: '72', 00:23:31 url: 'node:fs', 00:23:31 lineNumber: 573, 00:23:31 columnNumber: 17 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 52 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 52, 00:23:31 callFrame: { 00:23:31 functionName: 'hidden', 00:23:31 scriptId: '11', 00:23:31 url: 'node:internal/errors', 00:23:31 lineNumber: 308, 00:23:31 columnNumber: 24 00:23:31 }, 00:23:31 hitCount: 0, 00:23:31 children: [ 53 ] 00:23:31 }, 00:23:31 { 00:23:31 id: 53, 00:23:31 callFrame: { 00:23:31 functionName: '', 00:23:31 scriptId: '17', 00:23:31 url: 'node:internal/validators', 00:23:31 lineNumber: 86, 00:23:31 columnNumber: 2 00:23:31 }, 00:23:31 hitCount: 2, 00:23:31 positionTicks: [ [Object] ] 00:23:31 } 00:23:31 ] 00:23:31 node:assert:119 00:23:31 throw new AssertionError(obj); 00:23:31 ^ 00:23:31 00:23:31 AssertionError [ERR_ASSERTION]: Expected "actual" not to be strictly deep-equal to: [] 00:23:31 at verifyFrames (/Users/iojs/build/workspace/node-test-commit-osx/nodes/osx1015/test/common/cpu-prof.js:34:10) 00:23:31 at Object.<anonymous> (/Users/iojs/build/workspace/node-test-commit-osx/nodes/osx1015/test/sequential/test-cpu-prof-worker-argv.js:37:3) 00:23:31 at Module._compile (node:internal/modules/cjs/loader:1108:14) 00:23:31 at Object.Module._extensions..js (node:internal/modules/cjs/loader:1137:10) 00:23:31 at Module.load (node:internal/modules/cjs/loader:973:32) 00:23:31 at Function.Module._load (node:internal/modules/cjs/loader:813:14) 00:23:31 at Function.executeUserEntryPoint [as runMain] (node:internal/modules/run_main:76:12) 00:23:31 at node:internal/main/run_main_module:17:47 { 00:23:31 generatedMessage: true, 00:23:31 code: 'ERR_ASSERTION', 00:23:31 actual: [], 00:23:31 expected: [], 00:23:31 operator: 'notDeepStrictEqual' 00:23:31 } 00:23:31 ...Stress test: https://ci.nodejs.org/job/node-stress-single-test/208/
Failed 5 times in 1000 runs on osx15 and 1 time in 1000 runs on osx14.
Saw it again today at https://ci.nodejs.org/job/node-test-commit-osx/38260/nodes=osx1014/console.
Details
00:16:59 not ok 3121 sequential/test-cpu-prof-worker-argv 00:17:00 --- 00:17:00 duration_ms: 0.426 00:17:00 severity: fail 00:17:00 exitcode: 1 00:17:00 stack: |- 00:17:00 00:17:00 [ 00:17:00 { 00:17:00 id: 1, 00:17:00 callFrame: { 00:17:00 functionName: '(root)', 00:17:00 scriptId: '0', 00:17:00 url: '', 00:17:00 lineNumber: -1, 00:17:00 columnNumber: -1 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 2, 3, 20, 21 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 2, 00:17:00 callFrame: { 00:17:00 functionName: '(program)', 00:17:00 scriptId: '0', 00:17:00 url: '', 00:17:00 lineNumber: -1, 00:17:00 columnNumber: -1 00:17:00 }, 00:17:00 hitCount: 3 00:17:00 }, 00:17:00 { 00:17:00 id: 3, 00:17:00 callFrame: { 00:17:00 functionName: '', 00:17:00 scriptId: '10', 00:17:00 url: 'node:internal/bootstrap/node', 00:17:00 lineNumber: 0, 00:17:00 columnNumber: 0 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 4, 7 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 4, 00:17:00 callFrame: { 00:17:00 functionName: 'setupPrepareStackTrace', 00:17:00 scriptId: '10', 00:17:00 url: 'node:internal/bootstrap/node', 00:17:00 lineNumber: 279, 00:17:00 columnNumber: 31 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 5 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 5, 00:17:00 callFrame: { 00:17:00 functionName: 'nativeModuleRequire', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 302, 00:17:00 columnNumber: 28 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 6 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 6, 00:17:00 callFrame: { 00:17:00 functionName: 'compileForInternalLoader', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 269, 00:17:00 columnNumber: 26 00:17:00 }, 00:17:00 hitCount: 1, 00:17:00 positionTicks: [ [Object] ] 00:17:00 }, 00:17:00 { 00:17:00 id: 7, 00:17:00 callFrame: { 00:17:00 functionName: 'nativeModuleRequire', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 302, 00:17:00 columnNumber: 28 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 8 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 8, 00:17:00 callFrame: { 00:17:00 functionName: 'compileForInternalLoader', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 269, 00:17:00 columnNumber: 26 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 9, 11, 14, 17 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 9, 00:17:00 callFrame: { 00:17:00 functionName: '', 00:17:00 scriptId: '12', 00:17:00 url: 'node:internal/util', 00:17:00 lineNumber: 0, 00:17:00 columnNumber: 0 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 10 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 10, 00:17:00 callFrame: { 00:17:00 functionName: 'internalBinding', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 136, 00:17:00 columnNumber: 44 00:17:00 }, 00:17:00 hitCount: 1, 00:17:00 positionTicks: [ [Object] ] 00:17:00 }, 00:17:00 { 00:17:00 id: 11, 00:17:00 callFrame: { 00:17:00 functionName: '', 00:17:00 scriptId: '23', 00:17:00 url: 'node:internal/process/task_queues', 00:17:00 lineNumber: 0, 00:17:00 columnNumber: 0 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 12 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 12, 00:17:00 callFrame: { 00:17:00 functionName: 'nativeModuleRequire', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 302, 00:17:00 columnNumber: 28 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 13 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 13, 00:17:00 callFrame: { 00:17:00 functionName: 'compileForInternalLoader', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 269, 00:17:00 columnNumber: 26 00:17:00 }, 00:17:00 hitCount: 1, 00:17:00 positionTicks: [ [Object] ] 00:17:00 }, 00:17:00 { 00:17:00 id: 14, 00:17:00 callFrame: { 00:17:00 functionName: '', 00:17:00 scriptId: '34', 00:17:00 url: 'node:internal/abort_controller', 00:17:00 lineNumber: 0, 00:17:00 columnNumber: 0 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 15 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 15, 00:17:00 callFrame: { 00:17:00 functionName: 'nativeModuleRequire', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 302, 00:17:00 columnNumber: 28 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 16 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 16, 00:17:00 callFrame: { 00:17:00 functionName: 'compileForInternalLoader', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 269, 00:17:00 columnNumber: 26 00:17:00 }, 00:17:00 hitCount: 1, 00:17:00 positionTicks: [ [Object] ] 00:17:00 }, 00:17:00 { 00:17:00 id: 17, 00:17:00 callFrame: { 00:17:00 functionName: '', 00:17:00 scriptId: '38', 00:17:00 url: 'node:internal/worker/io', 00:17:00 lineNumber: 0, 00:17:00 columnNumber: 0 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 18 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 18, 00:17:00 callFrame: { 00:17:00 functionName: 'nativeModuleRequire', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 302, 00:17:00 columnNumber: 28 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 19 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 19, 00:17:00 callFrame: { 00:17:00 functionName: 'compileForInternalLoader', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 269, 00:17:00 columnNumber: 26 00:17:00 }, 00:17:00 hitCount: 1, 00:17:00 positionTicks: [ [Object] ] 00:17:00 }, 00:17:00 { 00:17:00 id: 20, 00:17:00 callFrame: { 00:17:00 functionName: '(garbage collector)', 00:17:00 scriptId: '0', 00:17:00 url: '', 00:17:00 lineNumber: -1, 00:17:00 columnNumber: -1 00:17:00 }, 00:17:00 hitCount: 1 00:17:00 }, 00:17:00 { 00:17:00 id: 21, 00:17:00 callFrame: { 00:17:00 functionName: 'exports.emitMessage', 00:17:00 scriptId: '8', 00:17:00 url: 'node:internal/per_context/messageport', 00:17:00 lineNumber: 15, 00:17:00 columnNumber: 30 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 22 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 22, 00:17:00 callFrame: { 00:17:00 functionName: '', 00:17:00 scriptId: '35', 00:17:00 url: 'node:internal/event_target', 00:17:00 lineNumber: 380, 00:17:00 columnNumber: 19 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 23 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 23, 00:17:00 callFrame: { 00:17:00 functionName: '', 00:17:00 scriptId: '62', 00:17:00 url: 'node:internal/main/worker_thread', 00:17:00 lineNumber: 97, 00:17:00 columnNumber: 19 00:17:00 }, 00:17:00 hitCount: 2, 00:17:00 children: [ 24, 27, 44 ], 00:17:00 positionTicks: [ [Object], [Object] ] 00:17:00 }, 00:17:00 { 00:17:00 id: 24, 00:17:00 callFrame: { 00:17:00 functionName: 'initializeReport', 00:17:00 scriptId: '63', 00:17:00 url: 'node:internal/bootstrap/pre_execution', 00:17:00 lineNumber: 178, 00:17:00 columnNumber: 25 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 25 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 25, 00:17:00 callFrame: { 00:17:00 functionName: 'nativeModuleRequire', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 302, 00:17:00 columnNumber: 28 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 26 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 26, 00:17:00 callFrame: { 00:17:00 functionName: 'compileForInternalLoader', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 269, 00:17:00 columnNumber: 26 00:17:00 }, 00:17:00 hitCount: 1, 00:17:00 positionTicks: [ [Object] ] 00:17:00 }, 00:17:00 { 00:17:00 id: 27, 00:17:00 callFrame: { 00:17:00 functionName: 'initializeCJSLoader', 00:17:00 scriptId: '63', 00:17:00 url: 'node:internal/bootstrap/pre_execution', 00:17:00 lineNumber: 412, 00:17:00 columnNumber: 28 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 28 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 28, 00:17:00 callFrame: { 00:17:00 functionName: 'nativeModuleRequire', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 302, 00:17:00 columnNumber: 28 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 29 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 29, 00:17:00 callFrame: { 00:17:00 functionName: 'compileForInternalLoader', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 269, 00:17:00 columnNumber: 26 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 30 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 30, 00:17:00 callFrame: { 00:17:00 functionName: '', 00:17:00 scriptId: '70', 00:17:00 url: 'node:internal/modules/cjs/loader', 00:17:00 lineNumber: 0, 00:17:00 columnNumber: 0 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 31 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 31, 00:17:00 callFrame: { 00:17:00 functionName: 'nativeModuleRequire', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 302, 00:17:00 columnNumber: 28 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 32 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 32, 00:17:00 callFrame: { 00:17:00 functionName: 'compileForInternalLoader', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 269, 00:17:00 columnNumber: 26 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 33 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 33, 00:17:00 callFrame: { 00:17:00 functionName: '', 00:17:00 scriptId: '81', 00:17:00 url: 'node:internal/process/esm_loader', 00:17:00 lineNumber: 0, 00:17:00 columnNumber: 0 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 34 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 34, 00:17:00 callFrame: { 00:17:00 functionName: 'nativeModuleRequire', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 302, 00:17:00 columnNumber: 28 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 35 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 35, 00:17:00 callFrame: { 00:17:00 functionName: 'compileForInternalLoader', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 269, 00:17:00 columnNumber: 26 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 36 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 36, 00:17:00 callFrame: { 00:17:00 functionName: '', 00:17:00 scriptId: '82', 00:17:00 url: 'node:internal/modules/esm/loader', 00:17:00 lineNumber: 0, 00:17:00 columnNumber: 0 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 37 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 37, 00:17:00 callFrame: { 00:17:00 functionName: 'nativeModuleRequire', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 302, 00:17:00 columnNumber: 28 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 38 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 38, 00:17:00 callFrame: { 00:17:00 functionName: 'compileForInternalLoader', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 269, 00:17:00 columnNumber: 26 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 39 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 39, 00:17:00 callFrame: { 00:17:00 functionName: '', 00:17:00 scriptId: '87', 00:17:00 url: 'node:internal/modules/esm/get_source', 00:17:00 lineNumber: 0, 00:17:00 columnNumber: 0 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 40 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 40, 00:17:00 callFrame: { 00:17:00 functionName: 'nativeModuleRequire', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 302, 00:17:00 columnNumber: 28 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 41 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 41, 00:17:00 callFrame: { 00:17:00 functionName: 'compileForInternalLoader', 00:17:00 scriptId: '9', 00:17:00 url: 'node:internal/bootstrap/loaders', 00:17:00 lineNumber: 269, 00:17:00 columnNumber: 26 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 42 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 42, 00:17:00 callFrame: { 00:17:00 functionName: '', 00:17:00 scriptId: '88', 00:17:00 url: 'node:internal/fs/promises', 00:17:00 lineNumber: 0, 00:17:00 columnNumber: 0 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 43 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 43, 00:17:00 callFrame: { 00:17:00 functionName: 'EventEmitterMixin', 00:17:00 scriptId: '35', 00:17:00 url: 'node:internal/event_target', 00:17:00 lineNumber: 653, 00:17:00 columnNumber: 26 00:17:00 }, 00:17:00 hitCount: 1, 00:17:00 positionTicks: [ [Object] ] 00:17:00 }, 00:17:00 { 00:17:00 id: 44, 00:17:00 callFrame: { 00:17:00 functionName: 'executeUserEntryPoint', 00:17:00 scriptId: '94', 00:17:00 url: 'node:internal/modules/run_main', 00:17:00 lineNumber: 68, 00:17:00 columnNumber: 30 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 45 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 45, 00:17:00 callFrame: { 00:17:00 functionName: 'Module._load', 00:17:00 scriptId: '70', 00:17:00 url: 'node:internal/modules/cjs/loader', 00:17:00 lineNumber: 747, 00:17:00 columnNumber: 23 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 46 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 46, 00:17:00 callFrame: { 00:17:00 functionName: 'Module.load', 00:17:00 scriptId: '70', 00:17:00 url: 'node:internal/modules/cjs/loader', 00:17:00 lineNumber: 960, 00:17:00 columnNumber: 32 00:17:00 }, 00:17:00 hitCount: 0, 00:17:00 children: [ 47 ] 00:17:00 }, 00:17:00 { 00:17:00 id: 47, 00:17:00 callFrame: { 00:17:00 functionName: 'Module._extensions..js', 00:17:00 scriptId: '70', 00:17:00 url: 'node:internal/modules/cjs/loader', 00:17:00 lineNumber: 1116, 00:17:00 columnNumber: 36 00:17:00 }, 00:17:00 hitCount: 1, 00:17:00 positionTicks: [ [Object] ] 00:17:00 } 00:17:00 ] 00:17:00 node:assert:119 00:17:00 throw new AssertionError(obj); 00:17:00 ^ 00:17:00 00:17:00 AssertionError [ERR_ASSERTION]: Expected "actual" not to be strictly deep-equal to: [] 00:17:00 at verifyFrames (/Users/iojs/build/workspace/node-test-commit-osx/nodes/osx1014/test/common/cpu-prof.js:34:10) 00:17:00 at Object.<anonymous> (/Users/iojs/build/workspace/node-test-commit-osx/nodes/osx1014/test/sequential/test-cpu-prof-worker-argv.js:37:3) 00:17:00 at Module._compile (node:internal/modules/cjs/loader:1108:14) 00:17:00 at Object.Module._extensions..js (node:internal/modules/cjs/loader:1137:10) 00:17:00 at Module.load (node:internal/modules/cjs/loader:973:32) 00:17:00 at Function.Module._load (node:internal/modules/cjs/loader:813:14) 00:17:00 at Function.executeUserEntryPoint [as runMain] (node:internal/modules/run_main:76:12) 00:17:00 at node:internal/main/run_main_module:17:47 { 00:17:00 generatedMessage: true, 00:17:00 code: 'ERR_ASSERTION', 00:17:00 actual: [], 00:17:00 expected: [], 00:17:00 operator: 'notDeepStrictEqual' 00:17:00 } 00:17:00 ...- addedmacosIssues and PRs related to the macOS platform.Issues and PRs related to the macOS platform.
on Jan 6, 2021 @joyeecheung Based on the output, would you say that the test is valid but should be made more lenient? Or is there a bug somewhere (in Node.js or in the test or in a dependency)?
@Trott Making the test more lenient SGTM, though it's difficult to say what parameters would be good for the CI. Maybe tuning the CPU_PROF_INTERVAL in the test down would help. It might also help to tune the FIB environment variable up so that the workload lasts longer, though that would lead to longer test running time.
This seems to not be failing on macOS in CI anymore (or at least not with noticeable frequency) but has been failing a lot in CI on FreeBSD 12.
- Confirm that a stress test on FreeBSD fails
- Try decreasing CPU_PROF_INTERVAL to see if that fixes it
- Try increasing FIB
- If the fixed test takes a long time to run, consider moving it to pummel
Of course, if that approach doesn't fix the test, then we'll have to add more things to try.
- Confirm that a stress test on FreeBSD fails
https://ci.nodejs.org/job/node-stress-single-test/324/
EDIT: 76 failures in 1000 runs.
- Try decreasing CPU_PROF_INTERVAL to see if that fixes it
Stress test with CPU_PROF_INTERVAL reduced from 50 to 32: https://ci.nodejs.org/job/node-stress-single-test/325/
EDIT: 98 failures so that seems to have made it worse, if anything.
- Try increasing FIB
Stress test with FIB increased from 30 to 40: https://ci.nodejs.org/job/node-stress-single-test/328/
EDIT: This seems to have fixed it. If it doesn't cause timeouts on the Pi devices, this is probably the way to go.
- Try decreasing CPU_PROF_INTERVAL to see if that fixes it
- Try increasing FIB
Stress test that does both: https://ci.nodejs.org/job/node-stress-single-test/327/
- added a commit that references this issue
on Feb 19, 2022 - added a commit that references this issue
on Feb 21, 2022 - added a commit that references this issue
on Mar 7, 2022 - added 4 commits that reference this issue
on Apr 21, 2022
I have encountered this error twice.