Closed Bug 1629617 Opened 5 years ago Closed 4 years ago

Intermittent Linux ccov devtools/client/performance/test/browser_perf-fission-switch-target.js | A promise chain failed to handle a rejection: Protocol error (noSuchActor): No such actor for ID: server0.conn28.child5/performanceActor10 from:

Categories

(DevTools :: Performance Tools (Profiler/Timeline), defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: opoprus [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=297384369&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/EMKupusXTeSrLsNQLlQSxA/runs/0/artifacts/public/logs/live_backing.log


[task 2020-04-13T16:46:28.406Z] 16:46:28 INFO - TEST-START | devtools/client/performance/test/browser_perf-fission-switch-target.js
[task 2020-04-13T16:46:28.449Z] 16:46:28 INFO - GECKO(3091) | Adding tab with url: http://example.com/browser/devtools/client/performance/test/doc_simple-test.html#4480220296356099.
[task 2020-04-13T16:46:28.824Z] 16:46:28 INFO - GECKO(3091) | Initializing a performance panel.
[task 2020-04-13T16:46:30.470Z] 16:46:30 INFO - GECKO(3091) | Waiting for event: 'Performance:BackendReadyRecordingStart' on [object PerformanceController] for 1 time(s).
[task 2020-04-13T16:46:30.471Z] 16:46:30 INFO - GECKO(3091) | Waiting for event: 'Performance:RecordingStateChange' on [object PerformanceController] for 1 time(s) with arguments: ["recording-started"].
[task 2020-04-13T16:46:30.471Z] 16:46:30 INFO - GECKO(3091) | Waiting for event: 'Performance:UI:StateChanged' on [object PerformanceView] for 1 time(s) with arguments: [{}].
[task 2020-04-13T16:46:30.471Z] 16:46:30 INFO - GECKO(3091) | Waiting for event: 'Performance:UI:OverviewRendered' on [object OverviewView] for 1 time(s) with arguments: [100].
[task 2020-04-13T16:46:30.589Z] 16:46:30 INFO - GECKO(3091) | Event: 'Performance:UI:StateChanged' on [object PerformanceView] received.
[task 2020-04-13T16:46:30.739Z] 16:46:30 INFO - GECKO(3091) | Event: 'Performance:RecordingStateChange' on [object PerformanceController] received.
[task 2020-04-13T16:46:30.816Z] 16:46:30 INFO - GECKO(3091) | Event: 'Performance:BackendReadyRecordingStart' on [object PerformanceController] received.
[task 2020-04-13T16:46:31.673Z] 16:46:31 INFO - GECKO(3091) | Event: 'Performance:UI:OverviewRendered' on [object OverviewView] received.
[task 2020-04-13T16:47:01.775Z] 16:47:01 INFO - GECKO(3091) | console.error: "Error while listing frames" (new Error("Protocol error (noSuchActor): No such actor for ID: server0.conn28.child5/frameTarget1 from: server0.conn28.child5/frameTarget1", "resource://devtools/shared/protocol/Front.js", 319))
[task 2020-04-13T16:47:02.565Z] 16:47:02 INFO - GECKO(3091) | JavaScript error: resource://devtools/shared/protocol/Front.js, line 319: Error: Protocol error (noSuchActor): No such actor for ID: server0.conn28.child5/performanceActor10 from: server0.conn28.child5/performanceActor10
[task 2020-04-13T16:47:10.099Z] 16:47:10 INFO - GECKO(3091) | Waiting for event: 'Performance:BackendReadyRecordingStop' on [object PerformanceController] for 1 time(s).
[task 2020-04-13T16:47:10.099Z] 16:47:10 INFO - GECKO(3091) | Waiting for event: 'Performance:RecordingStateChange' on [object PerformanceController] for 1 time(s) with arguments: ["recording-stopping"].
[task 2020-04-13T16:47:10.100Z] 16:47:10 INFO - GECKO(3091) | Waiting for event: 'Performance:RecordingStateChange' on [object PerformanceController] for 1 time(s) with arguments: ["recording-stopped"].
[task 2020-04-13T16:47:10.101Z] 16:47:10 INFO - GECKO(3091) | Waiting for event: 'Performance:UI:StateChanged' on [object PerformanceView] for 1 time(s) with arguments: ["recorded"].
[task 2020-04-13T16:47:10.104Z] 16:47:10 INFO - GECKO(3091) | Waiting for event: 'Performance:UI:OverviewRendered' on [object OverviewView] for 1 time(s) with arguments: [16].
[task 2020-04-13T16:47:10.108Z] 16:47:10 INFO - GECKO(3091) | Waiting for event: 'Performance:UI:WaterfallRendered' on [object WaterfallView] for 1 time(s).
[task 2020-04-13T16:47:10.472Z] 16:47:10 INFO - GECKO(3091) | Ignoring event 'Performance:UI:StateChanged' with unexpected argument at index 0: loading - expected recorded
[task 2020-04-13T16:47:10.493Z] 16:47:10 INFO - GECKO(3091) | Event: 'Performance:RecordingStateChange' on [object PerformanceController] received.
[task 2020-04-13T16:47:10.501Z] 16:47:10 INFO - GECKO(3091) | Ignoring event 'Performance:RecordingStateChange' with unexpected argument at index 0: recording-stopping - expected recording-stopped
[task 2020-04-13T16:47:10.707Z] 16:47:10 INFO - GECKO(3091) | Ignoring event 'Performance:UI:OverviewRendered' with unexpected argument at index 0: 100 - expected 16
[task 2020-04-13T16:47:11.013Z] 16:47:11 INFO - GECKO(3091) | Ignoring event 'Performance:UI:OverviewRendered' with unexpected argument at index 0: 100 - expected 16
[task 2020-04-13T16:47:14.050Z] 16:47:14 INFO - GECKO(3091) | Event: 'Performance:UI:StateChanged' on [object PerformanceView] received.
[task 2020-04-13T16:47:14.484Z] 16:47:14 INFO - GECKO(3091) | Event: 'Performance:UI:WaterfallRendered' on [object WaterfallView] received.
[task 2020-04-13T16:47:14.485Z] 16:47:14 INFO - GECKO(3091) | Event: 'Performance:RecordingStateChange' on [object PerformanceController] received.
[task 2020-04-13T16:47:14.947Z] 16:47:14 INFO - GECKO(3091) | Event: 'Performance:BackendReadyRecordingStop' on [object PerformanceController] received.
[task 2020-04-13T16:47:17.143Z] 16:47:17 INFO - GECKO(3091) | Event: 'Performance:UI:OverviewRendered' on [object OverviewView] received.
[task 2020-04-13T16:47:17.159Z] 16:47:17 INFO - GECKO(3091) | Destroying panel.
[task 2020-04-13T16:47:19.280Z] 16:47:19 INFO - GECKO(3091) | Removing tab: http://example.com/browser/devtools/client/performance/test/doc_simple-test.html.
[task 2020-04-13T16:47:19.543Z] 16:47:19 INFO - TEST-INFO | started process screentopng
[task 2020-04-13T16:47:20.045Z] 16:47:20 INFO - TEST-INFO | screentopng: exit 0
[task 2020-04-13T16:47:20.045Z] 16:47:20 INFO - Buffered messages logged at 16:46:28
[task 2020-04-13T16:47:20.045Z] 16:47:20 INFO - Entering test bound
[task 2020-04-13T16:47:20.047Z] 16:47:20 INFO - Open a page running on content process
[task 2020-04-13T16:47:20.048Z] 16:47:20 INFO - Open the performance panel
[task 2020-04-13T16:47:20.049Z] 16:47:20 INFO - Buffered messages logged at 16:46:29
[task 2020-04-13T16:47:20.050Z] 16:47:20 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2020-04-13T16:47:20.051Z] 16:47:20 INFO - Buffered messages logged at 16:46:30
[task 2020-04-13T16:47:20.051Z] 16:47:20 INFO - Start recording
[task 2020-04-13T16:47:20.052Z] 16:47:20 INFO - Buffered messages logged at 16:46:35
[task 2020-04-13T16:47:20.053Z] 16:47:20 INFO - Navigate to a page running on main process
[task 2020-04-13T16:47:20.055Z] 16:47:20 INFO - Buffered messages logged at 16:46:36
[task 2020-04-13T16:47:20.056Z] 16:47:20 INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome://global/content/elements/panel.js" line: 62}]
[task 2020-04-13T16:47:20.056Z] 16:47:20 INFO - Buffered messages logged at 16:46:58
[task 2020-04-13T16:47:20.056Z] 16:47:20 INFO - Return to a page running on content process again
[task 2020-04-13T16:47:20.056Z] 16:47:20 INFO - Buffered messages logged at 16:47:02
[task 2020-04-13T16:47:20.057Z] 16:47:20 INFO - Console message: [JavaScript Error: "Error: Protocol error (noSuchActor): No such actor for ID: server0.conn28.child5/performanceActor10 from: server0.conn28.child5/performanceActor10" {file: "resource://devtools/shared/protocol/Front.js" line: 319}]
[task 2020-04-13T16:47:20.058Z] 16:47:20 INFO - Buffered messages logged at 16:47:10
[task 2020-04-13T16:47:20.060Z] 16:47:20 INFO - Stop recording
[task 2020-04-13T16:47:20.061Z] 16:47:20 INFO - Buffered messages logged at 16:47:13
[task 2020-04-13T16:47:20.062Z] 16:47:20 INFO - Longer timeout required, waiting longer... Remaining timeouts: 2
[task 2020-04-13T16:47:20.062Z] 16:47:20 INFO - Buffered messages logged at 16:47:17
[task 2020-04-13T16:47:20.063Z] 16:47:20 INFO - TEST-PASS | devtools/client/performance/test/browser_perf-fission-switch-target.js | Have a record for every target-switching -
[task 2020-04-13T16:47:20.064Z] 16:47:20 INFO - Buffered messages finished
[task 2020-04-13T16:47:20.065Z] 16:47:20 INFO - TEST-UNEXPECTED-FAIL | devtools/client/performance/test/browser_perf-fission-switch-target.js | A promise chain failed to handle a rejection: Protocol error (noSuchActor): No such actor for ID: server0.conn28.child5/performanceActor10 from: server0.conn28.child5/performanceActor10 - stack: onPacket/<@resource://devtools/shared/protocol/Front.js:319:31
[task 2020-04-13T16:47:20.065Z] 16:47:20 INFO - DevTools RDPrequest@resource://devtools/shared/protocol/Front.js:256:14
[task 2020-04-13T16:47:20.065Z] 16:47:20 INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:47:19
[task 2020-04-13T16:47:20.066Z] 16:47:20 INFO - connect@resource://devtools/client/fronts/performance.js:49:36
[task 2020-04-13T16:47:20.066Z] 16:47:20 INFO - async
initialize@resource://devtools/client/fronts/performance.js:40:16
[task 2020-04-13T16:47:20.066Z] 16:47:20 INFO - manage@resource://devtools/shared/protocol/Front.js:116:19
[task 2020-04-13T16:47:20.067Z] 16:47:20 INFO - getFront@resource://devtools/shared/protocol/types.js:609:18
[task 2020-04-13T16:47:20.067Z] 16:47:20 INFO - getFront@resource://devtools/client/fronts/targets/target-mixin.js:193:15
[task 2020-04-13T16:47:20.067Z] 16:47:20 INFO - initPerformance@resource://devtools/client/framework/toolbox.js:3882:48
[task 2020-04-13T16:47:20.068Z] 16:47:20 INFO - switchToTarget@resource://devtools/client/framework/toolbox.js:544:16
[task 2020-04-13T16:47:20.068Z] 16:47:20 INFO - async*_onRemotenessChange@resource://devtools/client/fronts/targets/local-tab.js:157:15
[task 2020-04-13T16:47:20.068Z] 16:47:20 INFO - async*_handleTabEvent@resource://devtools/client/fronts/targets/local-tab.js:94:14
[task 2020-04-13T16:47:20.068Z] 16:47:20 INFO - updateBrowserRemoteness@chrome://browser/content/tabbrowser.js:2010:11
[task 2020-04-13T16:47:20.068Z] 16:47:20 INFO - restoreTabContent@resource:///modules/sessionstore/SessionStore.jsm:5082:39
[task 2020-04-13T16:47:20.069Z] 16:47:20 INFO - restoreTab@resource:///modules/sessionstore/SessionStore.jsm:4999:14
[task 2020-04-13T16:47:20.069Z] 16:47:20 INFO - _asyncNavigateAndRestore@resource:///modules/sessionstore/SessionStore.jsm:4138:10
[task 2020-04-13T16:47:20.069Z] 16:47:20 INFO - asyncnavigateAndRestore@resource:///modules/sessionstore/SessionStore.jsm:4030:24
[task 2020-04-13T16:47:20.070Z] 16:47:20 INFO - navigateAndRestore@resource:///modules/sessionstore/SessionStore.jsm:427:33
[task 2020-04-13T16:47:20.070Z] 16:47:20 INFO - LoadInOtherProcess@chrome://browser/content/browser.js:1594:16
[task 2020-04-13T16:47:20.070Z] 16:47:20 INFO - _loadURI@chrome://browser/content/browser.js:1567:25
[task 2020-04-13T16:47:20.070Z] 16:47:20 INFO - loadURI@resource://testing-common/BrowserTestUtils.jsm:821:13
[task 2020-04-13T16:47:20.071Z] 16:47:20 INFO - @chrome://mochitests/content/browser/devtools/client/performance/test/browser_perf-fission-switch-target.js:48:26
[task 2020-04-13T16:47:20.071Z] 16:47:20 INFO - Async
Tester_execTest/<@chrome://mochikit/content/browser-test.js:1062:34
[task 2020-04-13T16:47:20.071Z] 16:47:20 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1097:11
[task 2020-04-13T16:47:20.072Z] 16:47:20 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:925:14
[task 2020-04-13T16:47:20.072Z] 16:47:20 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:918:23
[task 2020-04-13T16:47:20.072Z] 16:47:20 INFO - Rejection date: Mon Apr 13 2020 16:47:02 GMT+0000 (Coordinated Universal Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 265
[task 2020-04-13T16:47:20.072Z] 16:47:20 INFO - Stack trace:
[task 2020-04-13T16:47:20.073Z] 16:47:20 INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:265
[task 2020-04-13T16:47:20.073Z] 16:47:20 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1093
[task 2020-04-13T16:47:20.073Z] 16:47:20 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1097
[task 2020-04-13T16:47:20.074Z] 16:47:20 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:925
[task 2020-04-13T16:47:20.074Z] 16:47:20 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-04-13T16:47:20.074Z] 16:47:20 INFO - Leaving test bound
[task 2020-04-13T16:47:20.075Z] 16:47:20 INFO - finish() was called, cleaning up...
[task 2020-04-13T16:47:21.538Z] 16:47:21 INFO - GECKO(3091) | MEMORY STAT | vsize 3542MB | residentFast 683MB | heapAllocated 298MB
[task 2020-04-13T16:47:21.538Z] 16:47:21 INFO - TEST-OK | devtools/client/performance/test/browser_perf-fission-switch-target.js | took 53131ms
[task 2020-04-13T16:47:21.639Z] 16:47:21 INFO - checking window state

Greg, please check what's going on with this Linux ccov almost permanent failure.

Flags: needinfo?(gtatum)
Summary: Intermittent [tier2] devtools/client/performance/test/browser_perf-fission-switch-target.js | A promise chain failed to handle a rejection: Protocol error (noSuchActor): No such actor for ID: server0.conn28.child5/performanceActor10 from: server0.conn28 → Intermittent Linux ccov devtools/client/performance/test/browser_perf-fission-switch-target.js | A promise chain failed to handle a rejection: Protocol error (noSuchActor): No such actor for ID: server0.conn28.child5/performanceActor10 from:

Here is the error:

TEST-UNEXPECTED-FAIL | devtools/client/performance/test/browser_perf-fission-switch-target.js

Protocol error (noSuchActor): No such actor for ID: server0.conn28.child5/performanceActor10 from: server0.conn28.child5/performanceActor10

stack:
  onPacket/<@resource://devtools/shared/protocol/Front.js:319:31
  DevTools RDP*request@resource://devtools/shared/protocol/Front.js:256:14
  generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:47:19
  connect@resource://devtools/client/fronts/performance.js:49:36
  async*initialize@resource://devtools/client/fronts/performance.js:40:16
  manage@resource://devtools/shared/protocol/Front.js:116:19
  getFront@resource://devtools/shared/protocol/types.js:609:18
  getFront@resource://devtools/client/fronts/targets/target-mixin.js:193:15
  initPerformance@resource://devtools/client/framework/toolbox.js:3882:48
  switchToTarget@resource://devtools/client/framework/toolbox.js:544:16
  async*_onRemotenessChange@resource://devtools/client/fronts/targets/local-tab.js:157:15
  async*_handleTabEvent@resource://devtools/client/fronts/targets/local-tab.js:94:14
  updateBrowserRemoteness@chrome://browser/content/tabbrowser.js:2010:11
  restoreTabContent@resource:///modules/sessionstore/SessionStore.jsm:5082:39
  restoreTab@resource:///modules/sessionstore/SessionStore.jsm:4999:14
  _asyncNavigateAndRestore@resource:///modules/sessionstore/SessionStore.jsm:4138:10
  async*navigateAndRestore@resource:///modules/sessionstore/SessionStore.jsm:4030:24
  navigateAndRestore@resource:///modules/sessionstore/SessionStore.jsm:427:33
  LoadInOtherProcess@chrome://browser/content/browser.js:1594:16
  _loadURI@chrome://browser/content/browser.js:1567:25
  loadURI@resource://testing-common/BrowserTestUtils.jsm:821:13
  @chrome://mochitests/content/browser/devtools/client/performance/test/browser_perf-fission-switch-target.js:48:26
  Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1062:34
  Tester_execTest@chrome://mochikit/content/browser-test.js:1097:11
  nextTest/<@chrome://mochikit/content/browser-test.js:925:14
  SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:918:23

jdescottes or daisuke, any ideas on why this may be happening? This looks like an issue related to the fission work, which I'm not as familiar with.

Flags: needinfo?(jdescottes)
Flags: needinfo?(gtatum)
Flags: needinfo?(daisuke)

Thanks, let me take a look at this.

Flags: needinfo?(daisuke)

hm, failed.. Will investigate more.

Removing the NI since there's been no answer and Daisuke looked into, and it's showing up in my dashboard.

Flags: needinfo?(jdescottes)
Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.