Investigate flaky sequential/test-cpu-prof-dir-worker #32168
Description
- 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 ...