Skip to content

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

Description

@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
Details 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 ...

Activity

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment

Metadata

Metadata

Assignees

No one assigned

    Labels

    duplicateIssues and PRs that are duplicates of other issues or PRs.flaky-testIssues and PRs involving tests that fail intermittently in CI.

    Type

    No type

    Projects

    No projects

      Milestone

      No milestone

      Relationships

      None yet

      Development

      No branches or pull requests

      Issue actions