Investigate flaky sequential/test-cpu-prof-dir-worker

[gireeshpunathil]

• Version: master
• Platform: OSX
• Subsystem: worker_threads

https://ci.nodejs.org/job/node-test-commit-osx/nodes=osx1015/32504/consoleFull

11:10:12 not ok 2736 sequential/test-cpu-prof-dir-worker
11:10:12   ---
11:10:12   duration_ms: 0.313
11:10:12   severity: fail
11:10:12   exitcode: 1
11:10:12   stack: |-
11:10:12     Dispatching message { "id": 1, "method": "Profiler.enable" }
11:10:12     Receive CPU profile message, ending = false
11:10:12     Dispatching message { "id": 2, "method": "Profiler.start" }
11:10:12     Receive CPU profile message, ending = false
11:10:12     Dispatching message { "id": 3, "method": "Profiler.setSamplingInterval", "params": { "interval": 50 } }
11:10:12     Receive CPU profile message, ending = false
11:10:12     Dispatching message { "id": 1, "method": "Profiler.enable" }
11:10:12     Receive CPU profile message, ending = false
11:10:12     Dispatching message { "id": 2, "method": "Profiler.start" }
11:10:12     Receive CPU profile message, ending = false
11:10:12     Dispatching message { "id": 3, "method": "Profiler.setSamplingInterval", "params": { "interval": 50 } }
11:10:12     Receive CPU profile message, ending = false
11:10:12     EndStartedProfilers
11:10:12     Ending cpu profiling
11:10:12     Dispatching message { "id": 4, "method": "Profiler.stop" }
11:10:12     Receive CPU profile message, ending = true
11:10:12     Written result to prof/CPU.20200309.224012.66378.1.002.cpuprofile
11:10:12     EndStartedProfilers
11:10:12     Ending cpu profiling
11:10:12     Dispatching message { "id": 4, "method": "Profiler.stop" }
11:10:12     Receive CPU profile message, ending = true
11:10:12     Written result to prof/CPU.20200309.224012.66378.0.001.cpuprofile

11:10:12 11:10:12 CPU path: /Users/iojs/node-tmp/.tmp.2735/prof/CPU.20200309.224012.66378.0.001.cpuprofile 11:10:12 [ 11:10:12 { 11:10:12 id: 1, 11:10:12 callFrame: { 11:10:12 functionName: '(root)', 11:10:12 scriptId: '0', 11:10:12 url: '', 11:10:12 lineNumber: -1, 11:10:12 columnNumber: -1 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 2, 3, 10, 11 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 2, 11:10:12 callFrame: { 11:10:12 functionName: '(program)', 11:10:12 scriptId: '0', 11:10:12 url: '', 11:10:12 lineNumber: -1, 11:10:12 columnNumber: -1 11:10:12 }, 11:10:12 hitCount: 12 11:10:12 }, 11:10:12 { 11:10:12 id: 3, 11:10:12 callFrame: { 11:10:12 functionName: '', 11:10:12 scriptId: '11', 11:10:12 url: 'internal/bootstrap/node.js', 11:10:12 lineNumber: 0, 11:10:12 columnNumber: 0 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 4 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 4, 11:10:12 callFrame: { 11:10:12 functionName: 'nativeModuleRequire', 11:10:12 scriptId: '10', 11:10:12 url: 'internal/bootstrap/loaders.js', 11:10:12 lineNumber: 295, 11:10:12 columnNumber: 28 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 5 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 5, 11:10:12 callFrame: { 11:10:12 functionName: 'compileForInternalLoader', 11:10:12 scriptId: '10', 11:10:12 url: 'internal/bootstrap/loaders.js', 11:10:12 lineNumber: 262, 11:10:12 columnNumber: 26 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 6 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 6, 11:10:12 callFrame: { 11:10:12 functionName: '', 11:10:12 scriptId: '29', 11:10:12 url: 'internal/url.js', 11:10:12 lineNumber: 0, 11:10:12 columnNumber: 0 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 7 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 7, 11:10:12 callFrame: { 11:10:12 functionName: 'nativeModuleRequire', 11:10:12 scriptId: '10', 11:10:12 url: 'internal/bootstrap/loaders.js', 11:10:12 lineNumber: 295, 11:10:12 columnNumber: 28 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 8 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 8, 11:10:12 callFrame: { 11:10:12 functionName: 'compileForInternalLoader', 11:10:12 scriptId: '10', 11:10:12 url: 'internal/bootstrap/loaders.js', 11:10:12 lineNumber: 262, 11:10:12 columnNumber: 26 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 9 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 9, 11:10:12 callFrame: { 11:10:12 functionName: '', 11:10:12 scriptId: '30', 11:10:12 url: 'internal/querystring.js', 11:10:12 lineNumber: 0, 11:10:12 columnNumber: 0 11:10:12 }, 11:10:12 hitCount: 1, 11:10:12 positionTicks: [ [Object] ] 11:10:12 }, 11:10:12 { 11:10:12 id: 10, 11:10:12 callFrame: { 11:10:12 functionName: '(garbage collector)', 11:10:12 scriptId: '0', 11:10:12 url: '', 11:10:12 lineNumber: -1, 11:10:12 columnNumber: -1 11:10:12 }, 11:10:12 hitCount: 2 11:10:12 }, 11:10:12 { 11:10:12 id: 11, 11:10:12 callFrame: { 11:10:12 functionName: '', 11:10:12 scriptId: '44', 11:10:12 url: 'internal/main/run_main_module.js', 11:10:12 lineNumber: 0, 11:10:12 columnNumber: 0 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 12 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 12, 11:10:12 callFrame: { 11:10:12 functionName: 'executeUserEntryPoint', 11:10:12 scriptId: '68', 11:10:12 url: 'internal/modules/run_main.js', 11:10:12 lineNumber: 63, 11:10:12 columnNumber: 30 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 13 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 13, 11:10:12 callFrame: { 11:10:12 functionName: 'Module._load', 11:10:12 scriptId: '48', 11:10:12 url: 'internal/modules/cjs/loader.js', 11:10:12 lineNumber: 886, 11:10:12 columnNumber: 23 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 14 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 14, 11:10:12 callFrame: { 11:10:12 functionName: 'Module.load', 11:10:12 scriptId: '48', 11:10:12 url: 'internal/modules/cjs/loader.js', 11:10:12 lineNumber: 1038, 11:10:12 columnNumber: 32 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 15 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 15, 11:10:12 callFrame: { 11:10:12 functionName: 'Module._extensions..js', 11:10:12 scriptId: '48', 11:10:12 url: 'internal/modules/cjs/loader.js', 11:10:12 lineNumber: 1210, 11:10:12 columnNumber: 36 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 16 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 16, 11:10:12 callFrame: { 11:10:12 functionName: 'Module._compile', 11:10:12 scriptId: '48', 11:10:12 url: 'internal/modules/cjs/loader.js', 11:10:12 lineNumber: 1155, 11:10:12 columnNumber: 36 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 17 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 17, 11:10:12 callFrame: { 11:10:12 functionName: '', 11:10:12 scriptId: '69', 11:10:12 url: 'file:///Users/iojs/build/workspace/node-test-commit-osx/nodes/osx1015/test/fixtures/workload/fibonacci-worker.js', 11:10:12 lineNumber: 0, 11:10:12 columnNumber: 0 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 18 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 18, 11:10:12 callFrame: { 11:10:12 functionName: 'Worker', 11:10:12 scriptId: '71', 11:10:12 url: 'internal/worker.js', 11:10:12 lineNumber: 85, 11:10:12 columnNumber: 13 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 19 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 19, 11:10:12 callFrame: { 11:10:12 functionName: 'getStdout', 11:10:12 scriptId: '41', 11:10:12 url: 'internal/bootstrap/switches/is_main_thread.js', 11:10:12 lineNumber: 115, 11:10:12 columnNumber: 18 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 20 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 20, 11:10:12 callFrame: { 11:10:12 functionName: 'createWritableStdioStream', 11:10:12 scriptId: '41', 11:10:12 url: 'internal/bootstrap/switches/is_main_thread.js', 11:10:12 lineNumber: 36, 11:10:12 columnNumber: 34 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 21 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 21, 11:10:12 callFrame: { 11:10:12 functionName: 'nativeModuleRequire', 11:10:12 scriptId: '10', 11:10:12 url: 'internal/bootstrap/loaders.js', 11:10:12 lineNumber: 295, 11:10:12 columnNumber: 28 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 22 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 22, 11:10:12 callFrame: { 11:10:12 functionName: 'compileForInternalLoader', 11:10:12 scriptId: '10', 11:10:12 url: 'internal/bootstrap/loaders.js', 11:10:12 lineNumber: 262, 11:10:12 columnNumber: 26 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 23 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 23, 11:10:12 callFrame: { 11:10:12 functionName: '', 11:10:12 scriptId: '86', 11:10:12 url: 'net.js', 11:10:12 lineNumber: 0, 11:10:12 columnNumber: 0 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 24 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 24, 11:10:12 callFrame: { 11:10:12 functionName: 'internalBinding', 11:10:12 scriptId: '10', 11:10:12 url: 'internal/bootstrap/loaders.js', 11:10:12 lineNumber: 131, 11:10:12 columnNumber: 44 11:10:12 }, 11:10:12 hitCount: 1, 11:10:12 positionTicks: [ [Object] ] 11:10:12 } 11:10:12 ] 11:10:12 CPU path: /Users/iojs/node-tmp/.tmp.2735/prof/CPU.20200309.224012.66378.1.002.cpuprofile 11:10:12 [ 11:10:12 { 11:10:12 id: 1, 11:10:12 callFrame: { 11:10:12 functionName: '(root)', 11:10:12 scriptId: '0', 11:10:12 url: '', 11:10:12 lineNumber: -1, 11:10:12 columnNumber: -1 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 2, 3, 8, 9 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 2, 11:10:12 callFrame: { 11:10:12 functionName: '(program)', 11:10:12 scriptId: '0', 11:10:12 url: '', 11:10:12 lineNumber: -1, 11:10:12 columnNumber: -1 11:10:12 }, 11:10:12 hitCount: 1 11:10:12 }, 11:10:12 { 11:10:12 id: 3, 11:10:12 callFrame: { 11:10:12 functionName: '', 11:10:12 scriptId: '11', 11:10:12 url: 'internal/bootstrap/node.js', 11:10:12 lineNumber: 0, 11:10:12 columnNumber: 0 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 4 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 4, 11:10:12 callFrame: { 11:10:12 functionName: 'nativeModuleRequire', 11:10:12 scriptId: '10', 11:10:12 url: 'internal/bootstrap/loaders.js', 11:10:12 lineNumber: 295, 11:10:12 columnNumber: 28 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 5 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 5, 11:10:12 callFrame: { 11:10:12 functionName: 'compileForInternalLoader', 11:10:12 scriptId: '10', 11:10:12 url: 'internal/bootstrap/loaders.js', 11:10:12 lineNumber: 262, 11:10:12 columnNumber: 26 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 6 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 6, 11:10:12 callFrame: { 11:10:12 functionName: '', 11:10:12 scriptId: '13', 11:10:12 url: 'internal/util.js', 11:10:12 lineNumber: 0, 11:10:12 columnNumber: 0 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 7 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 7, 11:10:12 callFrame: { 11:10:12 functionName: 'internalBinding', 11:10:12 scriptId: '10', 11:10:12 url: 'internal/bootstrap/loaders.js', 11:10:12 lineNumber: 131, 11:10:12 columnNumber: 44 11:10:12 }, 11:10:12 hitCount: 1, 11:10:12 positionTicks: [ [Object] ] 11:10:12 }, 11:10:12 { 11:10:12 id: 8, 11:10:12 callFrame: { 11:10:12 functionName: '(garbage collector)', 11:10:12 scriptId: '0', 11:10:12 url: '', 11:10:12 lineNumber: -1, 11:10:12 columnNumber: -1 11:10:12 }, 11:10:12 hitCount: 2 11:10:12 }, 11:10:12 { 11:10:12 id: 9, 11:10:12 callFrame: { 11:10:12 functionName: 'exports.emitMessage', 11:10:12 scriptId: '9', 11:10:12 url: 'internal/per_context/messageport.js', 11:10:12 lineNumber: 8, 11:10:12 columnNumber: 30 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 10 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 10, 11:10:12 callFrame: { 11:10:12 functionName: 'onmessage', 11:10:12 scriptId: '43', 11:10:12 url: 'internal/worker/io.js', 11:10:12 lineNumber: 73, 11:10:12 columnNumber: 62 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 11 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 11, 11:10:12 callFrame: { 11:10:12 functionName: 'emit', 11:10:12 scriptId: '14', 11:10:12 url: 'events.js', 11:10:12 lineNumber: 262, 11:10:12 columnNumber: 43 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 12 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 12, 11:10:12 callFrame: { 11:10:12 functionName: '', 11:10:12 scriptId: '58', 11:10:12 url: 'internal/main/worker_thread.js', 11:10:12 lineNumber: 92, 11:10:12 columnNumber: 19 11:10:12 }, 11:10:12 hitCount: 0, 11:10:12 children: [ 13 ] 11:10:12 }, 11:10:12 { 11:10:12 id: 13, 11:10:12 callFrame: { 11:10:12 functionName: 'Readable.push', 11:10:12 scriptId: '49', 11:10:12 url: '_stream_readable.js', 11:10:12 lineNumber: 212, 11:10:12 columnNumber: 34 11:10:12 }, 11:10:12 hitCount: 1, 11:10:12 positionTicks: [ [Object] ] 11:10:12 } 11:10:12 ] 11:10:12 assert.js:387 11:10:12 throw err; 11:10:12 ^ 11:10:12 11:10:12 AssertionError [ERR_ASSERTION]: The expression evaluated to a falsy value: 11:10:12 11:10:12 assert(profile1.frames.length > 0 || profile2.frames.length > 0) 11:10:12 11:10:12 at Object. (/Users/iojs/build/workspace/node-test-commit-osx/nodes/osx1015/test/sequential/test-cpu-prof-dir-worker.js:54:3) 11:10:12 at Module._compile (internal/modules/cjs/loader.js:1202:30) 11:10:12 at Object.Module._extensions..js (internal/modules/cjs/loader.js:1222:10) 11:10:12 at Module.load (internal/modules/cjs/loader.js:1051:32) 11:10:12 at Function.Module._load (internal/modules/cjs/loader.js:947:14) 11:10:12 at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:71:12) 11:10:12 at internal/main/run_main_module.js:17:47 { 11:10:12 generatedMessage: true, 11:10:12 code: 'ERR_ASSERTION', 11:10:12 actual: false, 11:10:12 expected: true, 11:10:12 operator: '==' 11:10:12 } 11:10:12 ...

[bnoordhuis]

Duplicate of #27611 but you're forgiven for having missed that one because it predates the break-up into smaller tests done in #28170.

[sam-github]

closing as duplicate of #27611