Closed Bug 1616119 Opened 4 years ago Closed 4 years ago

Intermittent devtools/client/webconsole/test/browser/browser_webconsole_shows_reqs_from_netmonitor.js | A promise chain failed to handle a rejection: 'getEventTimings' active request packet to 'server0.conn0.netEvent4'

Categories

(DevTools :: Console, defect, P5)

Product:
DevTools
Component:
Console
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=289259674&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ObFU-9CxQf2RrSNQ6oROFA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ObFU-9CxQf2RrSNQ6oROFA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2020-02-17T19:32:15.146Z] 19:32:15 INFO - TEST-START | devtools/client/webconsole/test/browser/browser_webconsole_shows_reqs_from_netmonitor.js
[task 2020-02-17T19:32:16.758Z] 19:32:16 INFO - GECKO(7164) | JavaScript error: , line 0: uncaught exception: Object
[task 2020-02-17T19:32:16.758Z] 19:32:16 INFO - GECKO(7164) | JavaScript error: resource://devtools/client/netmonitor/src/connector/firefox-data-provider.js, line 627: Error: Error while calling method getEventTimings: 'getEventTimings' active request packet to 'server0.conn0.netEvent4' can't be sent as the connection just closed.
[task 2020-02-17T19:32:16.758Z] 19:32:16 INFO - GECKO(7164) | JavaScript error: resource://devtools/client/netmonitor/src/connector/firefox-data-provider.js, line 627: Error: Error while calling method getEventTimings: 'getEventTimings' active request packet to 'server0.conn0.netEvent4' can't be sent as the connection just closed.
[task 2020-02-17T19:32:16.776Z] 19:32:16 INFO - TEST-INFO | started process screenshot
[task 2020-02-17T19:32:16.822Z] 19:32:16 INFO - TEST-INFO | screenshot: exit 0
[task 2020-02-17T19:32:16.822Z] 19:32:16 INFO - Buffered messages logged at 19:32:15
[task 2020-02-17T19:32:16.823Z] 19:32:16 INFO - Entering test bound task
[task 2020-02-17T19:32:16.823Z] 19:32:16 INFO - Adding a new tab with URL: data:text/html;charset=utf8,Test that the netmonitor displays requests that have been recorded in the web console, even if the netmonitor hadn't opened yet.
[task 2020-02-17T19:32:16.823Z] 19:32:16 INFO - Tab added and finished loading
[task 2020-02-17T19:32:16.824Z] 19:32:16 INFO - Opening the toolbox
[task 2020-02-17T19:32:16.824Z] 19:32:16 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-02-17T19:32:16.824Z] 19:32:16 INFO - Buffered messages logged at 19:32:16
[task 2020-02-17T19:32:16.825Z] 19:32:16 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-02-17T19:32:16.825Z] 19:32:16 INFO - Toolbox opened and focused
[task 2020-02-17T19:32:16.826Z] 19:32:16 INFO - Web console is open
[task 2020-02-17T19:32:16.826Z] 19:32:16 INFO - Load document "http://example.com/browser/devtools/client/webconsole/test/browser/test-network-request.html"
[task 2020-02-17T19:32:16.826Z] 19:32:16 INFO - Waiting for page to be loaded…
[task 2020-02-17T19:32:16.827Z] 19:32:16 INFO - → page loaded
[task 2020-02-17T19:32:16.827Z] 19:32:16 INFO - Waiting for target 'navigate' event…
[task 2020-02-17T19:32:16.827Z] 19:32:16 INFO - → 'navigate' emitted
[task 2020-02-17T19:32:16.828Z] 19:32:16 INFO - Document loaded.
[task 2020-02-17T19:32:16.828Z] 19:32:16 INFO - Matched a message with text: "http://example.com/browser/devtools/client/webconsole/test/browser/test-network-request.html", all messages received.
[task 2020-02-17T19:32:16.828Z] 19:32:16 INFO - Network message found.
[task 2020-02-17T19:32:16.829Z] 19:32:16 INFO - Network panel is open.
[task 2020-02-17T19:32:16.829Z] 19:32:16 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_shows_reqs_from_netmonitor.js | Network request appears in the network panel -
[task 2020-02-17T19:32:16.829Z] 19:32:16 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_shows_reqs_from_netmonitor.js | The attached method is correct. -
[task 2020-02-17T19:32:16.830Z] 19:32:16 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_shows_reqs_from_netmonitor.js | The attached url is correct. -
[task 2020-02-17T19:32:16.830Z] 19:32:16 INFO - Leaving test bound task
[task 2020-02-17T19:32:16.830Z] 19:32:16 INFO - Console message: [JavaScript Warning: "Relative positioning of table rows and row groups is now supported. This site may need to be updated because it may depend on this feature having no effect." {file: "resource://devtools/client/netmonitor/src/components/request-list/RequestListHeader.js" line: 450}]
[task 2020-02-17T19:32:16.830Z] 19:32:16 INFO - Removing tab.
[task 2020-02-17T19:32:16.831Z] 19:32:16 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-02-17T19:32:16.831Z] 19:32:16 INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-02-17T19:32:16.831Z] 19:32:16 INFO - Tab removed and finished closing
[task 2020-02-17T19:32:16.832Z] 19:32:16 INFO - TEST-PASS | devtools/client/webconsole/test/browser/browser_webconsole_shows_reqs_from_netmonitor.js | The main process DevToolsServer has no pending connection when the test ends -
[task 2020-02-17T19:32:16.832Z] 19:32:16 INFO - Console message: [JavaScript Error: "uncaught exception: Object"]
[task 2020-02-17T19:32:16.832Z] 19:32:16 INFO - Console message: [JavaScript Error: "Error: Error while calling method getEventTimings: 'getEventTimings' active request packet to 'server0.conn0.netEvent4' can't be sent as the connection just closed." {file: "resource://devtools/client/netmonitor/src/connector/firefox-data-provider.js" line: 627}]
[task 2020-02-17T19:32:16.833Z] 19:32:16 INFO - Console message: [JavaScript Error: "Error: Error while calling method getEventTimings: 'getEventTimings' active request packet to 'server0.conn0.netEvent4' can't be sent as the connection just closed." {file: "resource://devtools/client/netmonitor/src/connector/firefox-data-provider.js" line: 627}]
[task 2020-02-17T19:32:16.833Z] 19:32:16 INFO - Buffered messages finished
[task 2020-02-17T19:32:16.833Z] 19:32:16 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_webconsole_shows_reqs_from_netmonitor.js | A promise chain failed to handle a rejection: 'getEventTimings' active request packet to 'server0.conn0.netEvent4' can't be sent as the connection just closed. - stack: listenerJson@resource://devtools/shared/client/debugger-client.js:253:17
[task 2020-02-17T19:32:16.833Z] 19:32:16 INFO - _emit@resource://devtools/shared/event-emitter.js:226:34
[task 2020-02-17T19:32:16.833Z] 19:32:16 INFO - emit@resource://devtools/shared/event-emitter.js:172:18
[task 2020-02-17T19:32:16.833Z] 19:32:16 INFO - emit@resource://devtools/shared/event-emitter.js:324:18
[task 2020-02-17T19:32:16.833Z] 19:32:16 INFO - reject@resource://devtools/shared/client/debugger-client.js:667:15
[task 2020-02-17T19:32:16.833Z] 19:32:16 INFO - purgeRequests/<@resource://devtools/shared/client/debugger-client.js:688:53
[task 2020-02-17T19:32:16.833Z] 19:32:16 INFO - purgeRequests@resource://devtools/shared/client/debugger-client.js:688:28
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - onClosed@resource://devtools/shared/client/debugger-client.js:614:10
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - close@resource://devtools/shared/transport/local-transport.js:169:20
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - close@resource://devtools/shared/transport/local-transport.js:165:13
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - close@resource://devtools/shared/transport/local-transport.js:165:13
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - cleanup@resource://devtools/shared/client/debugger-client.js:129:27
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - close/promise<@resource://devtools/shared/client/debugger-client.js:145:7
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - close@resource://devtools/shared/client/debugger-client.js:122:21
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - _destroyTarget@resource://devtools/shared/fronts/targets/target-mixin.js:449:30
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - destroy@resource://devtools/shared/fronts/targets/target-mixin.js:425:12
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - destroy@resource://devtools/shared/fronts/targets/browsing-context.js:154:27
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - _destroyToolbox/onceDestroyed</<@resource://devtools/client/framework/toolbox.js:3719:32
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - promise callback*_destroyToolbox/onceDestroyed<@resource://devtools/client/framework/toolbox.js:3684:12
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - _destroyToolbox@resource://devtools/client/framework/toolbox.js:3680:27
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - destroy@resource://devtools/client/framework/toolbox.js:3555:10
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - closeToolbox@resource://devtools/client/framework/devtools.js:657:19
[task 2020-02-17T19:32:16.834Z] 19:32:16 INFO - asynccloseTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:631:23
[task 2020-02-17T19:32:16.835Z] 19:32:16 INFO - asynccleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:160:11
[task 2020-02-17T19:32:16.835Z] 19:32:16 INFO - nextTest@chrome://mochikit/content/browser-test.js:570:35
[task 2020-02-17T19:32:16.835Z] 19:32:16 INFO - async*testScope/test_finish/<@chrome://mochikit/content/browser-test.js:1457:25
[task 2020-02-17T19:32:16.835Z] 19:32:16 INFO - run@chrome://mochikit/content/browser-test.js:1372:9
[task 2020-02-17T19:32:16.835Z] 19:32:16 INFO - Rejection date: Mon Feb 17 2020 19:32:16 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 265
[task 2020-02-17T19:32:16.835Z] 19:32:16 INFO - Stack trace:
[task 2020-02-17T19:32:16.835Z] 19:32:16 INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:265
[task 2020-02-17T19:32:16.835Z] 19:32:16 INFO - chrome://mochikit/content/browser-test.js:nextTest:624
[task 2020-02-17T19:32:16.835Z] 19:32:16 INFO - chrome://mochikit/content/browser-test.js:testScope/test_finish/<:1457
[task 2020-02-17T19:32:16.835Z] 19:32:16 INFO - chrome://mochikit/content/browser-test.js:run:1372
[task 2020-02-17T19:32:16.835Z] 19:32:16 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-02-17T19:32:16.835Z] 19:32:16 INFO - TEST-UNEXPECTED-FAIL | devtools/client/webconsole/test/browser/browser_webconsole_shows_reqs_from_netmonitor.js | A promise chain failed to handle a rejection: Error while calling method getEventTimings: 'getEventTimings' active request packet to 'server0.conn0.netEvent4' can't be sent as the connection just closed. - stack: _requestData/response</<@resource://devtools/client/netmonitor/src/connector/firefox-data-provider.js:627:17

Regressed by: 1615283
Has Regression Range: --- → yes
Keywords: regression

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

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.