Closed Bug 1780612 Opened 3 years ago Closed 3 years ago

Intermittent [tier 2] devtools/client/netmonitor/test/browser_net_stacktraces-visibility.js | Test timed out -

Categories

(DevTools :: Netmonitor, defect, P5)

Product:
DevTools
Component:
Netmonitor
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED DUPLICATE of bug 1781468

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [retriggered])

Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=385016590&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/PFE7kUOLTgGfmfTCzrrPeA/runs/0/artifacts/public/logs/live_backing.log

[task 2022-07-21T16:12:18.624Z] 16:12:18     INFO - Console message: [JavaScript Error: "Error: Error while calling method getEventTimings: No such actor for ID: server0.conn0.netEvent26" {file: "resource://devtools/client/netmonitor/src/connector/firefox-data-provider.js" line: 615}]
[task 2022-07-21T16:12:18.625Z] 16:12:18     INFO - → page loaded
[task 2022-07-21T16:12:18.626Z] 16:12:18     INFO - Wait for the toolbox to reload
[task 2022-07-21T16:12:18.626Z] 16:12:18     INFO - Waiting for toolbox commands to be reloaded…
[task 2022-07-21T16:12:18.627Z] 16:12:18     INFO - Waiting for target switch…
[task 2022-07-21T16:12:18.627Z] 16:12:18     INFO - Buffered messages logged at 16:10:50
[task 2022-07-21T16:12:18.628Z] 16:12:18     INFO - → switched-target emitted
[task 2022-07-21T16:12:18.629Z] 16:12:18     INFO - Waiting for 'dom-complete' resource…
[task 2022-07-21T16:12:18.629Z] 16:12:18     INFO - → 'dom-complete' resource emitted
[task 2022-07-21T16:12:18.630Z] 16:12:18     INFO - Waiting for netmonitor to be reloaded…
[task 2022-07-21T16:12:18.631Z] 16:12:18     INFO - Waiting for netmonitor updates after page reload
[task 2022-07-21T16:12:18.631Z] 16:12:18     INFO - → panel reloaded
[task 2022-07-21T16:12:18.632Z] 16:12:18     INFO - Wait for Responsive UI to reload
[task 2022-07-21T16:12:18.633Z] 16:12:18     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 508}]
[task 2022-07-21T16:12:18.633Z] 16:12:18     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 508}]
[task 2022-07-21T16:12:18.634Z] 16:12:18     INFO - Console message: [JavaScript Error: "Content Security Policy: The page’s settings blocked the loading of a resource at inline (“default-src”)." {file: "chrome" line: 62}]
[task 2022-07-21T16:12:18.635Z] 16:12:18     INFO - Destroying the specified network monitor.
[task 2022-07-21T16:12:18.636Z] 16:12:18     INFO - Wait for completion of all NetworkUpdateEvents packets...
[task 2022-07-21T16:12:18.637Z] 16:12:18     INFO - Buffered messages logged at 16:11:14
[task 2022-07-21T16:12:18.639Z] 16:12:18     INFO - Console message: [JavaScript Error: "1658419874433	addons.xpi	ERROR	System addon update list error Error: got node name: html, expected: updates" {file: "resource://gre/modules/Log.jsm" line: 724}]
[task 2022-07-21T16:12:18.639Z] 16:12:18     INFO - append@resource://gre/modules/Log.jsm:724:12
[task 2022-07-21T16:12:18.639Z] 16:12:18     INFO - log@resource://gre/modules/Log.jsm:380:16
[task 2022-07-21T16:12:18.639Z] 16:12:18     INFO - error@resource://gre/modules/Log.jsm:388:10
[task 2022-07-21T16:12:18.639Z] 16:12:18     INFO - updateSystemAddons/res<@resource://gre/modules/addons/XPIInstall.jsm:4086:25
[task 2022-07-21T16:12:18.639Z] 16:12:18     INFO - promise callback*updateSystemAddons@resource://gre/modules/addons/XPIInstall.jsm:4086:7
[task 2022-07-21T16:12:18.640Z] 16:12:18     INFO - async*XPIProvider[meth]@resource://gre/modules/addons/XPIProvider.jsm:3284:33
[task 2022-07-21T16:12:18.640Z] 16:12:18     INFO - backgroundUpdateCheck/buPromise<@resource://gre/modules/AddonManager.jsm:1302:13
[task 2022-07-21T16:12:18.640Z] 16:12:18     INFO - backgroundUpdateCheck@resource://gre/modules/AddonManager.jsm:1310:7
[task 2022-07-21T16:12:18.640Z] 16:12:18     INFO - backgroundUpdateTimerHandler@resource://gre/modules/AddonManager.jsm:3541:26
[task 2022-07-21T16:12:18.640Z] 16:12:18     INFO - notify@resource://gre/modules/addonManager.js:171:25
[task 2022-07-21T16:12:18.640Z] 16:12:18     INFO - TM_notify/<@resource://gre/modules/UpdateTimerManager.jsm:224:50
[task 2022-07-21T16:12:18.640Z] 16:12:18     INFO - TM_notify@resource://gre/modules/UpdateTimerManager.jsm:295:7
[task 2022-07-21T16:12:18.641Z] 16:12:18     INFO - 
[task 2022-07-21T16:12:18.641Z] 16:12:18     INFO - Buffered messages finished
[task 2022-07-21T16:12:18.642Z] 16:12:18     INFO - TEST-UNEXPECTED-FAIL | devtools/client/netmonitor/test/browser_net_stacktraces-visibility.js | Test timed out - 
[task 2022-07-21T16:12:18.643Z] 16:12:18     INFO - Removing tab.
[task 2022-07-21T16:12:18.645Z] 16:12:18     INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2022-07-21T16:12:18.646Z] 16:12:18     INFO - Got event: 'TabClose' on [object XULElement].
[task 2022-07-21T16:12:18.646Z] 16:12:18     INFO - GECKO(4695) | console.warn: "IGNORED REDUX ACTION:" "AUTOCOMPLETE_CLEAR"
[task 2022-07-21T16:12:18.647Z] 16:12:18     INFO - Tab removed and finished closing
[task 2022-07-21T16:12:18.648Z] 16:12:18     INFO - GECKO(4695) | JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'DevToolsFrame'
[task 2022-07-21T16:12:18.649Z] 16:12:18     INFO - TEST-PASS | devtools/client/netmonitor/test/browser_net_stacktraces-visibility.js | The main process DevToolsServer has no pending connection when the test ends - 
[task 2022-07-21T16:12:18.650Z] 16:12:18     INFO - finish() was called, cleaning up...
[task 2022-07-21T16:12:18.651Z] 16:12:18     INFO - Console message: [JavaScript Error: "NotFoundError: No such JSWindowActor 'DevToolsFrame'"]
[task 2022-07-21T16:12:18.652Z] 16:12:18     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-07-21T16:12:18.660Z] 16:12:18     INFO - TEST-UNEXPECTED-FAIL | devtools/client/netmonitor/test/browser_net_stacktraces-visibility.js | A promise chain failed to handle a rejection: Error while calling method getRequestCookies: No such actor for ID: server0.conn0.netEvent26 - stack: _requestData@resource://devtools/client/netmonitor/src/connector/firefox-data-provider.js:615:15
[task 2022-07-21T16:12:18.661Z] 16:12:18     INFO - async*requestData@resource://devtools/client/netmonitor/src/connector/firefox-data-provider.js:532:20
[task 2022-07-21T16:12:18.661Z] 16:12:18     INFO - requestData@resource://devtools/client/netmonitor/src/connector/index.js:592:30
[task 2022-07-21T16:12:18.661Z] 16:12:18     INFO - fetchNetworkUpdatePacket/<@resource://devtools/client/netmonitor/src/utils/request-utils.js:102:23
[task 2022-07-21T16:12:18.662Z] 16:12:18     INFO - fetchNetworkUpdatePacket@resource://devtools/client/netmonitor/src/utils/request-utils.js:92:17
[task 2022-07-21T16:12:18.663Z] 16:12:18     INFO - componentDidMount@resource://devtools/client/netmonitor/src/components/request-list/RequestListColumnCookies.js:24:29
[task 2022-07-21T16:12:18.663Z] 16:12:18     INFO - commitLifeCycles@resource://devtools/client/shared/vendor/react-dom.js:12949:22
[task 2022-07-21T16:12:18.664Z] 16:12:18     INFO - commitAllLifeCycles@resource://devtools/client/shared/vendor/react-dom.js:14174:23
[task 2022-07-21T16:12:18.664Z] 16:12:18     INFO - commitRoot@resource://devtools/client/shared/vendor/react-dom.js:14380:28
[task 2022-07-21T16:12:18.665Z] 16:12:18     INFO - completeRoot/<@resource://devtools/client/shared/vendor/react-dom.js:15731:15
[task 2022-07-21T16:12:18.665Z] 16:12:18     INFO - unstable_runWithPriority@resource://devtools/client/shared/vendor/react.js:617:12
[task 2022-07-21T16:12:18.666Z] 16:12:18     INFO - completeRoot@resource://devtools/client/shared/vendor/react-dom.js:15730:27
[task 2022-07-21T16:12:18.666Z] 16:12:18     INFO - performWorkOnRoot@resource://devtools/client/shared/vendor/react-dom.js:15659:21
[task 2022-07-21T16:12:18.667Z] 16:12:18     INFO - performWork@resource://devtools/client/shared/vendor/react-dom.js:15567:24
[task 2022-07-21T16:12:18.667Z] 16:12:18     INFO - performSyncWork@resource://devtools/client/shared/vendor/react-dom.js:15541:14
[task 2022-07-21T16:12:18.668Z] 16:12:18     INFO - requestWork@resource://devtools/client/shared/vendor/react-dom.js:15410:5
[task 2022-07-21T16:12:18.669Z] 16:12:18     INFO - scheduleWork@resource://devtools/client/shared/vendor/react-dom.js:15224:16
[task 2022-07-21T16:12:18.669Z] 16:12:18     INFO - enqueueSetState@resource://devtools/client/shared/vendor/react-dom.js:8192:17
[task 2022-07-21T16:12:18.670Z] 16:12:18     INFO - Component.prototype.setState@resource://devtools/client/shared/vendor/react.js:328:16
[task 2022-07-21T16:12:18.670Z] 16:12:18     INFO - onStateChange@resource://devtools/client/shared/vendor/react-redux.js:1412:16
[task 2022-07-21T16:12:18.671Z] 16:12:18     INFO - notify@resource://devtools/client/shared/vendor/react-redux.js:1159:21
[task 2022-07-21T16:12:18.672Z] 16:12:18     INFO - notifyNestedSubs@resource://devtools/client/shared/vendor/react-redux.js:1198:20
[task 2022-07-21T16:12:18.672Z] 16:12:18     INFO - onStateChange@resource://devtools/client/shared/vendor/react-redux.js:1409:16
[task 2022-07-21T16:12:18.673Z] 16:12:18     INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:265:7
[task 2022-07-21T16:12:18.674Z] 16:12:18     INFO - waitUntilService/</<@resource://devtools/client/shared/redux/middleware/wait-service.js:59:24
[task 2022-07-21T16:12:18.675Z] 16:12:18     INFO - eventTelemetryMiddleware/</</<@resource://devtools/client/netmonitor/src/middleware/event-telemetry.js:29:21
[task 2022-07-21T16:12:18.676Z] 16:12:18     INFO - throttlingMiddleware/</</<@resource://devtools/client/netmonitor/src/middleware/throttling.js:18:21
[task 2022-07-21T16:12:18.676Z] 16:12:18     INFO - batchingMiddleware/</<@resource://devtools/client/netmonitor/src/middleware/batching.js:44:15
[task 2022-07-21T16:12:18.677Z] 16:12:18     INFO - prefsMiddleware/</<@resource://devtools/client/netmonitor/src/middleware/prefs.js:27:21
[task 2022-07-21T16:12:18.677Z] 16:12:18     INFO - thunk/</</<@resource://devtools/client/shared/redux/middleware/thunk.js:18:11
[task 2022-07-21T16:12:18.678Z] 16:12:18     INFO - requestBlockingMiddleware/</</<@resource://devtools/client/netmonitor/src/middleware/request-blocking.js:39:21
[task 2022-07-21T16:12:18.678Z] 16:12:18     INFO - bindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:520:12
[task 2022-07-21T16:12:18.678Z] 16:12:18     INFO - addRequest@resource://devtools/client/netmonitor/src/connector/firefox-data-provider.js:118:26
[task 2022-07-21T16:12:18.679Z] 16:12:18     INFO - onNetworkResourceAvailable@resource://devtools/client/netmonitor/src/connector/firefox-data-provider.js:419:16
[task 2022-07-21T16:12:18.679Z] 16:12:18     INFO - onResourceAvailable@resource://devtools/client/netmonitor/src/connector/index.js:213:27
[task 2022-07-21T16:12:18.680Z] 16:12:18     INFO - _notifyWatchers@resource://devtools/shared/commands/resource/resource-command.js:862:24
[task 2022-07-21T16:12:18.680Z] 16:12:18     INFO - watchResources@resource://devtools/shared/commands/resource/resource-command.js:232:10
[task 2022-07-21T16:12:18.681Z] 16:12:18     INFO - async*waitForNextResource@resource://devtools/shared/commands/resource/resource-command.js:358:16
[task 2022-07-21T16:12:18.681Z] 16:12:18     INFO - watchForCommandsReload@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:841:38
[task 2022-07-21T16:12:18.682Z] 16:12:18     INFO - _watchForToolboxReload@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:653:46
[task 2022-07-21T16:12:18.683Z] 16:12:18     INFO - async*watchForDevToolsReload@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:616:38
[task 2022-07-21T16:12:18.683Z] 16:12:18     INFO - navigateTo@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:544:39
[task 2022-07-21T16:12:18.684Z] 16:12:18     INFO - reloadBrowser@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:509:10
[task 2022-07-21T16:12:18.684Z] 16:12:18     INFO - @chrome://mochitests/content/browser/devtools/client/netmonitor/test/browser_net_stacktraces-visibility.js:32:9
[task 2022-07-21T16:12:18.685Z] 16:12:18     INFO - Async*handleTask@chrome://mochikit/content/browser-test.js:988:26
[task 2022-07-21T16:12:18.686Z] 16:12:18     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1060:18
[task 2022-07-21T16:12:18.686Z] 16:12:18     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1195:14
[task 2022-07-21T16:12:18.687Z] 16:12:18     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:977:14
[task 2022-07-21T16:12:18.688Z] 16:12:18     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1041:13
[task 2022-07-21T16:12:18.689Z] 16:12:18     INFO - Rejection date: Thu Jul 21 2022 16:10:49 GMT+0000 (Coordinated Universal Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 270
[task 2022-07-21T16:12:18.690Z] 16:12:18     INFO - Stack trace:
[task 2022-07-21T16:12:18.690Z] 16:12:18     INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:270
[task 2022-07-21T16:12:18.691Z] 16:12:18     INFO - chrome://mochikit/content/browser-test.js:nextTest:624
[task 2022-07-21T16:12:18.691Z] 16:12:18     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1288
[task 2022-07-21T16:12:18.692Z] 16:12:18     INFO - setTimeout handler*chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest_setTimeoutShim:907
[task 2022-07-21T16:12:18.693Z] 16:12:18     INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1269
[task 2022-07-21T16:12:18.693Z] 16:12:18     INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1228
[task 2022-07-21T16:12:18.694Z] 16:12:18     INFO - chrome://mochikit/content/browser-test.js:nextTest/<:977
[task 2022-07-21T16:12:18.695Z] 16:12:18     INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/<:1041
[task 2022-07-21T16:12:18.696Z] 16:12:18     INFO - Not taking screenshot here: see the one that was previously logged
[task 2022-07-21T16:12:18.705Z] 16:12:18     INFO - TEST-UNEXPECTED-FAIL | devtools/client/netmonitor/test/browser_net_stacktraces-visibility.js | A promise chain failed to handle a rejection: Error while calling method getRequestCookies: No such actor for ID: server0.conn0.netEvent26 - stack: _requestData@resource://devtools/client/netmonitor/src/connector/firefox-data-provider.js:615:15
[task 2022-07-21T16:12:18.707Z] 16:12:18     INFO - async*requestData@resource://devtools/client/netmonitor/src/connector/firefox-data-provider.js:532:20
[task 2022-07-21T16:12:18.707Z] 16:12:18     INFO - requestData@resource://devtools/client/netmonitor/src/connector/index.js:592:30
[task 2022-07-21T16:12:18.707Z] 16:12:18     INFO - fetchNetworkUpdatePacket/<@resource://devtools/client/netmonitor/src/utils/request-utils.js:102:23
[task 2022-07-21T16:12:18.708Z] 16:12:18     INFO - fetchNetworkUpdatePacket@resource://devtools/client/netmonitor/src/utils/request-utils.js:92:17
[task 2022-07-21T16:12:18.708Z] 16:12:18     INFO - componentDidMount@resource://devtools/client/netmonitor/src/components/request-list/RequestListColumnCookies.js:24:29
[task 2022-07-21T16:12:18.708Z] 16:12:18     INFO - commitLifeCycles@resource://devtools/client/shared/vendor/react-dom.js:12949:22
[task 2022-07-21T16:12:18.708Z] 16:12:18     INFO - commitAllLifeCycles@resource://devtools/client/shared/vendor/react-dom.js:14174:23
[task 2022-07-21T16:12:18.708Z] 16:12:18     INFO - commitRoot@resource://devtools/client/shared/vendor/react-dom.js:14380:28
[task 2022-07-21T16:12:18.708Z] 16:12:18     INFO - completeRoot/<@resource://devtools/client/shared/vendor/react-dom.js:15731:15
[task 2022-07-21T16:12:18.709Z] 16:12:18     INFO - unstable_runWithPriority@resource://devtools/client/shared/vendor/react.js:617:12

Thanks for the ping. I doubt this is related to my patch. My changes only impact components which should not even be loaded in this test.
Given that the error is Error while calling method getRequestCookies: No such actor for ID: server0.conn0.netEvent26 I imagine this is the same issue as Bug 1767792, and therefore most likely regressed by Bug 1764349.

The retriggers so far are not very clear. There's one failure on my push for this intermittent. All the other failures are for other intermittents.

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