Closed Bug 1586620 Opened 6 years ago Closed 5 years ago

Intermittent devtools/client/netmonitor/test/browser_net_resend_csp.js | A promise chain failed to handle a rejection: Connection closed, pending request to server0.conn98.child1/consoleActor2, type sendHTTPRequest failed

Categories

(DevTools :: Netmonitor, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, regression)

Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=269987157&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/fU9nAbApT_GmVMq4lpD0VQ/runs/0/artifacts/public/logs/live_backing.log


[task 2019-10-06T20:48:57.992Z] 20:48:57 INFO - TEST-START | devtools/client/netmonitor/test/browser_net_resend_csp.js
[task 2019-10-06T20:48:58.012Z] 20:48:58 INFO - GECKO(4228) | ++DOCSHELL 00A6D400 == 2 [pid = 5872] [id = {585547fa-cec4-48f3-a7b0-fd17e24c29e2}]
[task 2019-10-06T20:48:58.012Z] 20:48:58 INFO - GECKO(4228) | ++DOMWINDOW == 7 (00A9CEE0) [pid = 5872] [serial = 393] [outer = 00000000]
[task 2019-10-06T20:48:58.012Z] 20:48:58 INFO - GECKO(4228) | ++DOMWINDOW == 8 (08ABF800) [pid = 5872] [serial = 394] [outer = 00A9CEE0]
[task 2019-10-06T20:48:58.129Z] 20:48:58 INFO - GECKO(4228) | ++DOMWINDOW == 9 (0D10A000) [pid = 5872] [serial = 395] [outer = 00A9CEE0]
[task 2019-10-06T20:48:58.224Z] 20:48:58 INFO - GECKO(4228) | ++DOCSHELL 00988C00 == 8 [pid = 5880] [id = {6faae153-0907-4584-9c76-e918e3f5d914}]
[task 2019-10-06T20:48:58.224Z] 20:48:58 INFO - GECKO(4228) | ++DOMWINDOW == 21 (0C3FB5E0) [pid = 5880] [serial = 638] [outer = 00000000]
[task 2019-10-06T20:48:58.231Z] 20:48:58 INFO - GECKO(4228) | ++DOMWINDOW == 22 (0EE39C00) [pid = 5880] [serial = 639] [outer = 0C3FB5E0]
[task 2019-10-06T20:48:58.252Z] 20:48:58 INFO - GECKO(4228) | ++DOMWINDOW == 23 (1083B800) [pid = 5880] [serial = 640] [outer = 0C3FB5E0]
[task 2019-10-06T20:48:58.279Z] 20:48:58 INFO - GECKO(4228) | ++DOMWINDOW == 24 (10842000) [pid = 5880] [serial = 641] [outer = 0C3FB5E0]
[task 2019-10-06T20:48:58.366Z] 20:48:58 INFO - GECKO(4228) | [Parent 5880, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005: file z:/build/build/src/dom/base/nsContentUtils.cpp, line 3690
[task 2019-10-06T20:48:58.486Z] 20:48:58 INFO - GECKO(4228) | ++DOCSHELL 0EE3C400 == 9 [pid = 5880] [id = {549e1a1d-2061-400b-9a9a-cb0724f031e3}]
[task 2019-10-06T20:48:58.486Z] 20:48:58 INFO - GECKO(4228) | ++DOMWINDOW == 25 (0C3FB940) [pid = 5880] [serial = 642] [outer = 00000000]
[task 2019-10-06T20:48:58.489Z] 20:48:58 INFO - GECKO(4228) | ++DOMWINDOW == 26 (1082E800) [pid = 5880] [serial = 643] [outer = 0C3FB940]
[task 2019-10-06T20:48:58.844Z] 20:48:58 INFO - GECKO(4228) | [Parent 5880, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x804B000A: file z:/build/build/src/extensions/permissions/nsPermission.cpp, line 42
[task 2019-10-06T20:48:58.844Z] 20:48:58 INFO - GECKO(4228) | [Parent 5880, Main Thread] WARNING: '!principal', file z:/build/build/src/netwerk/cookie/CookieSettings.cpp, line 132
[task 2019-10-06T20:48:58.844Z] 20:48:58 INFO - GECKO(4228) | [Parent 5880, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/toolkit/components/antitracking/StorageAccess.cpp, line 33
[task 2019-10-06T20:48:59.106Z] 20:48:59 INFO - GECKO(4228) | ++DOMWINDOW == 10 (0D10A800) [pid = 5872] [serial = 396] [outer = 00A9CEE0]
[task 2019-10-06T20:48:59.990Z] 20:48:59 INFO - GECKO(4228) | --DOMWINDOW == 9 (00A6DC00) [pid = 5872] [serial = 390] [outer = 00000000] [url = about:blank]
[task 2019-10-06T20:48:59.990Z] 20:48:59 INFO - GECKO(4228) | --DOMWINDOW == 8 (0C489000) [pid = 5872] [serial = 391] [outer = 00000000] [url = http://example.com/browser/devtools/client/netmonitor/test/html_cors-test-page.html]
[task 2019-10-06T20:49:00.009Z] 20:49:00 INFO - GECKO(4228) | --DOCSHELL 00A65400 == 1 [pid = 5872] [id = {2b69e982-192e-4fc2-b2af-7e93c96627b2}] [url = http://example.com/browser/devtools/client/netmonitor/test/html_cors-test-page.html]
[task 2019-10-06T20:49:00.108Z] 20:49:00 INFO - GECKO(4228) | --DOMWINDOW == 7 (00A9C160) [pid = 5872] [serial = 385] [outer = 00000000] [url = http://example.com/browser/devtools/client/netmonitor/test/html_post-data-test-page.html]
[task 2019-10-06T20:49:00.655Z] 20:49:00 INFO - GECKO(4228) | --DOCSHELL 00964C00 == 1 [pid = 5160] [id = {19122715-da29-452b-ba3b-a913510189b7}] [url = about:blank]
[task 2019-10-06T20:49:00.694Z] 20:49:00 INFO - GECKO(4228) | --DOMWINDOW == 3 (0898C700) [pid = 5160] [serial = 134] [outer = 00000000] [url = about:blank]
[task 2019-10-06T20:49:00.989Z] 20:49:00 INFO - GECKO(4228) | --DOMWINDOW == 25 (276D2C00) [pid = 5880] [serial = 634] [outer = 00000000] [url = about:blank]
[task 2019-10-06T20:49:00.989Z] 20:49:00 INFO - GECKO(4228) | --DOMWINDOW == 24 (10846C00) [pid = 5880] [serial = 633] [outer = 00000000] [url = about:blank]
[task 2019-10-06T20:49:00.989Z] 20:49:00 INFO - GECKO(4228) | --DOMWINDOW == 23 (29A5CC00) [pid = 5880] [serial = 631] [outer = 00000000] [url = about:blank]
[task 2019-10-06T20:49:00.989Z] 20:49:00 INFO - GECKO(4228) | --DOCSHELL 0EE31000 == 8 [pid = 5880] [id = {6a24d829-6be2-482c-a706-b388712a65ed}] [url = about:devtools-toolbox]
[task 2019-10-06T20:49:00.989Z] 20:49:00 INFO - GECKO(4228) | --DOCSHELL 00982800 == 7 [pid = 5880] [id = {8cf172c9-d03f-4ff5-84ca-8a0d168546db}] [url = chrome://devtools/content/netmonitor/index.html]
[task 2019-10-06T20:49:00.989Z] 20:49:00 INFO - GECKO(4228) | --DOMWINDOW == 22 (2B301C00) [pid = 5880] [serial = 629] [outer = 00000000] [url = about:devtools-toolbox]
[task 2019-10-06T20:49:01.148Z] 20:49:01 INFO - GECKO(4228) | [Parent 5880, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file z:/build/build/src/dom/events/DOMEventTargetHelper.cpp, line 318
[task 2019-10-06T20:49:01.149Z] 20:49:01 INFO - GECKO(4228) | [Parent 5880, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file z:/build/build/src/dom/events/DOMEventTargetHelper.cpp, line 318
[task 2019-10-06T20:49:01.150Z] 20:49:01 INFO - GECKO(4228) | [Parent 5880, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file z:/build/build/src/dom/events/DOMEventTargetHelper.cpp, line 318
[task 2019-10-06T20:49:01.150Z] 20:49:01 INFO - GECKO(4228) | [Parent 5880, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file z:/build/build/src/dom/events/DOMEventTargetHelper.cpp, line 318
[task 2019-10-06T20:49:01.190Z] 20:49:01 INFO - GECKO(4228) | [Parent 5880, Main Thread] WARNING: NS_ENSURE_TRUE(weakFrame.IsAlive()) failed: file z:/build/build/src/layout/xul/nsXULPopupManager.cpp, line 1059
[task 2019-10-06T20:49:01.211Z] 20:49:01 INFO - GECKO(4228) | --DOMWINDOW == 21 (2FBB7DC0) [pid = 5880] [serial = 632] [outer = 00000000] [url = about:devtools-toolbox][task 2019-10-06T20:49:01.211Z] 20:49:01 INFO - GECKO(4228) | --DOMWINDOW == 20 (355B3160) [pid = 5880] [serial = 636] [outer = 00000000] [url = chrome://devtools/content/netmonitor/index.html]
[task 2019-10-06T20:49:01.231Z] 20:49:01 INFO - GECKO(4228) | JavaScript error: resource://devtools/shared/protocol/Front.js, line 68: Error: Connection closed, pending request to server0.conn98.child1/consoleActor2, type sendHTTPRequest failed
[task 2019-10-06T20:49:01.232Z] 20:49:01 INFO - GECKO(4228) | Request stack:
[task 2019-10-06T20:49:01.232Z] 20:49:01 INFO - GECKO(4228) | request@resource://devtools/shared/protocol/Front.js:167:14
[task 2019-10-06T20:49:01.233Z] 20:49:01 INFO - GECKO(4228) | generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:49:19
[task 2019-10-06T20:49:01.234Z] 20:49:01 INFO - GECKO(4228) | sendHTTPRequest@resource://devtools/client/netmonitor/src/connector/firefox-connector.js:288:27
[task 2019-10-06T20:49:01.234Z] 20:49:01 INFO - GECKO(4228) | sendHTTPRequest@resource://devtools/client/netmonitor/src/connector/index.js:96:27
[task 2019-10-06T20:49:01.234Z] 20:49:01 INFO - GECKO(4228) | sendCustomRequest/<@resource://devtools/client/netmonitor/src/actions/requests.js:99:15
[task 2019-10-06T20:49:01.235Z] 20:49:01 INFO - GECKO(4228) | thunk/</<@resource://devtools/client/netmonitor/src/middleware/thunk.js:15:9
[task 2019-10-06T20:49:01.235Z] 20:49:01 INFO - GECKO(4228) | requestBlockingMiddleware/</</<@resource://devtools/client/netmonitor/src/middleware/request-blocking.js:31:21
[task 2019-10-06T20:49:01.236Z] 20:49:01 INFO - GECKO(4228) | sendCustomRequest@resource://devtools/client/netmonitor/src/components/RequestListContent.js:431:15
[task 2019-10-06T20:49:01.236Z] 20:49:01 INFO - GECKO(4228) | click@resource://devtools/client/netmonitor/src/widgets/RequestListContextMenu.js:295:9
[task 2019-10-06T20:49:01.236Z] 20:49:01 INFO - GECKO(4228) | Menu.prototype._createMenuItems/</<@resource://devtools/client/framework/menu.js:164:14
[task 2019-10-06T20:49:01.236Z] 20:49:01 INFO - GECKO(4228) | @chrome://mochitests/content/browser/devtools/client/netmonitor/test/browser_net_resend_csp.js:32:67
[task 2019-10-06T20:49:01.236Z] 20:49:01 INFO - GECKO(4228) | AsyncTester_execTest/<@chrome://mochikit/content/browser-test.js:1350:34
[task 2019-10-06T20:49:01.236Z] 20:49:01 INFO - GECKO(4228) | Tester_execTest@chrome://mochikit/content/browser-test.js:1385:11
[task 2019-10-06T20:49:01.236Z] 20:49:01 INFO - GECKO(4228) | nextTest/<@chrome://mochikit/content/browser-test.js:1213:14
[task 2019-10-06T20:49:01.236Z] 20:49:01 INFO - GECKO(4228) | SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:805:67
[task 2019-10-06T20:49:01.251Z] 20:49:01 INFO - TEST-INFO | started process screenshot
[task 2019-10-06T20:49:01.332Z] 20:49:01 INFO - TEST-INFO | screenshot: exit 0
[task 2019-10-06T20:49:01.333Z] 20:49:01 INFO - Buffered messages logged at 20:48:57
[task 2019-10-06T20:49:01.333Z] 20:49:01 INFO - Entering test bound
[task 2019-10-06T20:49:01.333Z] 20:49:01 INFO - Initializing a network monitor pane.
[task 2019-10-06T20:49:01.334Z] 20:49:01 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/netmonitor/test/html_csp-resend-test-page.html
[task 2019-10-06T20:49:01.334Z] 20:49:01 INFO - Buffered messages logged at 20:48:58
[task 2019-10-06T20:49:01.334Z] 20:49:01 INFO - Tab added and finished loading
[task 2019-10-06T20:49:01.335Z] 20:49:01 INFO - Net tab added successfully: http://example.com/browser/devtools/client/netmonitor/test/html_csp-resend-test-page.html
[task 2019-10-06T20:49:01.335Z] 20:49:01 INFO - Network monitor pane shown successfully.
[task 2019-10-06T20:49:01.335Z] 20:49:01 INFO - Disabling cache and reloading page.
[task 2019-10-06T20:49:01.336Z] 20:49:01 INFO - Buffered messages logged at 20:48:59
[task 2019-10-06T20:49:01.336Z] 20:49:01 INFO - Request server0.conn98.netEvent4 for http://example.com/browser/devtools/client/netmonitor/test/html_csp-resend-test-page.html done
[task 2019-10-06T20:49:01.336Z] 20:49:01 INFO - All requests finished
[task 2019-10-06T20:49:01.337Z] 20:49:01 INFO - Got marker: dom-interactive
[task 2019-10-06T20:49:01.337Z] 20:49:01 INFO - Got marker: dom-complete
[task 2019-10-06T20:49:01.337Z] 20:49:01 INFO - Got two timeline markers, done waiting
[task 2019-10-06T20:49:01.338Z] 20:49:01 INFO - Cache disabled when the current and all future toolboxes are open.
[task 2019-10-06T20:49:01.342Z] 20:49:01 INFO - TEST-PASS | devtools/client/netmonitor/test/browser_net_resend_csp.js | Request to reconfigure the tab was recorded. -
[task 2019-10-06T20:49:01.342Z] 20:49:01 INFO - Clearing requests in the console client.[task 2019-10-06T20:49:01.342Z] 20:49:01 INFO - Clearing requests in the UI.
[task 2019-10-06T20:49:01.343Z] 20:49:01 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/RequestListHeader.js" line: 440}]
[task 2019-10-06T20:49:01.343Z] 20:49:01 INFO - > Network event progress: NetworkEvent: 1/1, PayloadReady: 0/1, got NetMonitor:NetworkEvent for server0.conn98.netEvent23
[task 2019-10-06T20:49:01.343Z] 20:49:01 INFO - > Network event progress: NetworkEvent: 1/1, PayloadReady: 1/1, got NetMonitor:PayloadReady for server0.conn98.netEvent23
[task 2019-10-06T20:49:01.344Z] 20:49:01 INFO - TEST-PASS | devtools/client/netmonitor/test/browser_net_resend_csp.js | Correct request selected -
[task 2019-10-06T20:49:01.344Z] 20:49:01 INFO - TEST-PASS | devtools/client/netmonitor/test/browser_net_resend_csp.js | Orig and Sel url match -
[task 2019-10-06T20:49:01.344Z] 20:49:01 INFO - TEST-PASS | devtools/client/netmonitor/test/browser_net_resend_csp.js | Correct type of selected -
[task 2019-10-06T20:49:01.345Z] 20:49:01 INFO - TEST-PASS | devtools/client/netmonitor/test/browser_net_resend_csp.js | Orig and Sel type match -
[task 2019-10-06T20:49:01.346Z] 20:49:01 INFO - TEST-PASS | devtools/client/netmonitor/test/browser_net_resend_csp.js | CSP: should be one policy -
[task 2019-10-06T20:49:01.346Z] 20:49:01 INFO - TEST-PASS | devtools/client/netmonitor/test/browser_net_resend_csp.js | CSP: img-src should be * -
[task 2019-10-06T20:49:01.346Z] 20:49:01 INFO - Destroying the specified network monitor.
[task 2019-10-06T20:49:01.347Z] 20:49:01 INFO - Wait for completion of all NetworkUpdateEvents packets...
[task 2019-10-06T20:49:01.348Z] 20:49:01 INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/NetUtil.jsm" line: 253}]
[task 2019-10-06T20:49:01.348Z] 20:49:01 INFO - All pending requests finished.
[task 2019-10-06T20:49:01.349Z] 20:49:01 INFO - Buffered messages logged at 20:49:01
[task 2019-10-06T20:49:01.349Z] 20:49:01 INFO - Removing tab.
[task 2019-10-06T20:49:01.349Z] 20:49:01 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-10-06T20:49:01.350Z] 20:49:01 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-10-06T20:49:01.350Z] 20:49:01 INFO - Tab removed and finished closing
[task 2019-10-06T20:49:01.350Z] 20:49:01 INFO - Leaving test bound
[task 2019-10-06T20:49:01.350Z] 20:49:01 INFO - TEST-PASS | devtools/client/netmonitor/test/browser_net_resend_csp.js | The main process DebuggerServer has no pending connection when the test ends -
[task 2019-10-06T20:49:01.350Z] 20:49:01 INFO - finish() was called, cleaning up...
[task 2019-10-06T20:49:01.350Z] 20:49:01 INFO - Console message: [JavaScript Error: "Error: Connection closed, pending request to server0.conn98.child1/consoleActor2, type sendHTTPRequest failed
[task 2019-10-06T20:49:01.350Z] 20:49:01 INFO -
[task 2019-10-06T20:49:01.350Z] 20:49:01 INFO - Request stack:
[task 2019-10-06T20:49:01.350Z] 20:49:01 INFO - request@resource://devtools/shared/protocol/Front.js:167:14
[task 2019-10-06T20:49:01.350Z] 20:49:01 INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:49:19
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - sendHTTPRequest@resource://devtools/client/netmonitor/src/connector/firefox-connector.js:288:27
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - sendHTTPRequest@resource://devtools/client/netmonitor/src/connector/index.js:96:27
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - sendCustomRequest/<@resource://devtools/client/netmonitor/src/actions/requests.js:99:15
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - thunk/</<@resource://devtools/client/netmonitor/src/middleware/thunk.js:15:9
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - requestBlockingMiddleware/</</<@resource://devtools/client/netmonitor/src/middleware/request-blocking.js:31:21
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - sendCustomRequest@resource://devtools/client/netmonitor/src/components/RequestListContent.js:431:15
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - click@resource://devtools/client/netmonitor/src/widgets/RequestListContextMenu.js:295:9
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - Menu.prototype._createMenuItems/</<@resource://devtools/client/framework/menu.js:164:14
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - @chrome://mochitests/content/browser/devtools/client/netmonitor/test/browser_net_resend_csp.js:32:67
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - Async
Tester_execTest/<@chrome://mochikit/content/browser-test.js:1350:34
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1385:11
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1213:14
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:805:67
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - " {file: "resource://devtools/shared/protocol/Front.js" line: 68}]
[task 2019-10-06T20:49:01.351Z] 20:49:01 INFO - Buffered messages finished
[task 2019-10-06T20:49:01.363Z] 20:49:01 INFO - TEST-UNEXPECTED-FAIL | devtools/client/netmonitor/test/browser_net_resend_csp.js | A promise chain failed to handle a rejection: Connection closed, pending request to server0.conn98.child1/consoleActor2, type sendHTTPRequest failed

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.