Closed Bug 1582057 Opened 5 years ago Closed 5 years ago

Perma tier2 ccov devtools/client/debugger/test/mochitest/browser_dbg-content-script-sources.js | A promise chain failed to handle a rejection: Page has navigated - stack: ContextError@resource://devtools/client/debugger/src/utils/context.js:15:49

Categories

(DevTools :: Debugger, defect, P5)

defect

Tracking

(firefox-esr60 unaffected, firefox-esr68 unaffected, firefox69 unaffected, firefox70 unaffected, firefox71 fixed)

RESOLVED FIXED
Firefox 71
Tracking Status
firefox-esr60 --- unaffected
firefox-esr68 --- unaffected
firefox69 --- unaffected
firefox70 --- unaffected
firefox71 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: bhackett1024)

References

Details

(Keywords: intermittent-failure, regression, Whiteboard: [debugger-mvp])

Attachments

(1 file)

Filed by: apavel [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=266532565&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/I7uDURpaSransYhM3S0D_A/runs/0/artifacts/public/logs/live_backing.log


[task 2019-09-13T13:31:33.585Z] 13:31:33 INFO - TEST-START | devtools/client/debugger/test/mochitest/browser_dbg-content-script-sources.js
[task 2019-09-13T13:31:33.804Z] 13:31:33 INFO - GECKO(1652) | --DOMWINDOW == 25 (0x7fcebb8953e0) [pid = 1652] [serial = 169] [outer = (nil)] [url = about:devtools-toolbox]
[task 2019-09-13T13:31:33.804Z] 13:31:33 INFO - GECKO(1652) | --DOMWINDOW == 24 (0x7fcebb8955c0) [pid = 1652] [serial = 173] [outer = (nil)] [url = chrome://devtools/content/debugger/index.html]
[task 2019-09-13T13:31:33.805Z] 13:31:33 INFO - GECKO(1652) | --DOCSHELL 0x7fcec95a9800 == 8 [pid = 1652] [id = {0dff88e9-6295-43f4-ac16-b7c2e2a587cc}] [url = chrome://devtools/content/webconsole/index.html]
[task 2019-09-13T13:31:33.807Z] 13:31:33 INFO - GECKO(1652) | --DOMWINDOW == 23 (0x7fcecad63c40) [pid = 1652] [serial = 175] [outer = (nil)] [url = chrome://devtools/content/webconsole/index.html]
[task 2019-09-13T13:31:33.923Z] 13:31:33 INFO - GECKO(1652) | ++DOCSHELL 0x7f51b1b07800 == 2 [pid = 1783] [id = {4a254c8b-a487-44c9-88ce-e8c06a6f9f91}]
[task 2019-09-13T13:31:33.923Z] 13:31:33 INFO - GECKO(1652) | ++DOMWINDOW == 3 (0x7f51b4cd1880) [pid = 1783] [serial = 62] [outer = (nil)]
[task 2019-09-13T13:31:33.925Z] 13:31:33 INFO - GECKO(1652) | ++DOMWINDOW == 4 (0x7f51b4d4fc00) [pid = 1783] [serial = 63] [outer = 0x7f51b4cd1880]
[task 2019-09-13T13:31:34.425Z] 13:31:34 INFO - GECKO(1652) | ++DOMWINDOW == 5 (0x7f51b4d92000) [pid = 1783] [serial = 64] [outer = 0x7f51b4cd1880]
[task 2019-09-13T13:31:34.744Z] 13:31:34 INFO - GECKO(1652) | ++DOCSHELL 0x7fcebb81e000 == 9 [pid = 1652] [id = {9d690f44-fab2-473a-9c8f-6a6456e90385}]
[task 2019-09-13T13:31:34.744Z] 13:31:34 INFO - GECKO(1652) | ++DOMWINDOW == 24 (0x7fcebb8953e0) [pid = 1652] [serial = 185] [outer = (nil)]

[task 2019-09-13T13:32:17.095Z] 13:32:17 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-content-script-sources.js | There is only one line -
[task 2019-09-13T13:32:17.099Z] 13:32:17 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-content-script-sources.js | debug line is visible -
[task 2019-09-13T13:32:17.099Z] 13:32:17 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-content-script-sources.js | expression is highlighted as paused -
[task 2019-09-13T13:32:17.099Z] 13:32:17 INFO - Resuming from 2
[task 2019-09-13T13:32:17.100Z] 13:32:17 INFO - Buffered messages finished
[task 2019-09-13T13:32:17.100Z] 13:32:17 INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-content-script-sources.js | A promise chain failed to handle a rejection: Page has navigated - stack: ContextError@resource://devtools/client/debugger/src/utils/context.js:15:49
[task 2019-09-13T13:32:17.101Z] 13:32:17 INFO - validateNavigateContext@resource://devtools/client/debugger/src/utils/context.js:23:11
[task 2019-09-13T13:32:17.101Z] 13:32:17 INFO - validateContext@resource://devtools/client/debugger/src/utils/context.js:40:26
[task 2019-09-13T13:32:17.101Z] 13:32:17 INFO - validateActionContext@resource://devtools/client/debugger/src/actions/utils/middleware/context.js:21:32
[task 2019-09-13T13:32:17.101Z] 13:32:17 INFO - context/</<@resource://devtools/client/debugger/src/actions/utils/middleware/context.js:32:28
[task 2019-09-13T13:32:17.101Z] 13:32:17 INFO - thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:100
[task 2019-09-13T13:32:17.102Z] 13:32:17 INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
[task 2019-09-13T13:32:17.109Z] 13:32:17 INFO - promiseMiddleware/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/promise.js:62:15
[task 2019-09-13T13:32:17.109Z] 13:32:17 INFO - promise callbackpromiseMiddleware/</<@resource://devtools/client/debugger/src/actions/utils/middleware/promise.js:61:121
[task 2019-09-13T13:32:17.110Z] 13:32:17 INFO - context/</<@resource://devtools/client/debugger/src/actions/utils/middleware/context.js:35:12
[task 2019-09-13T13:32:17.110Z] 13:32:17 INFO - thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:100
[task 2019-09-13T13:32:17.110Z] 13:32:17 INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
[task 2019-09-13T13:32:17.110Z] 13:32:17 INFO - addBreakpoint/<@resource://devtools/client/debugger/src/actions/breakpoints/modify.js:137:12
[task 2019-09-13T13:32:17.110Z] 13:32:17 INFO - async
thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45
[task 2019-09-13T13:32:17.110Z] 13:32:17 INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
[task 2019-09-13T13:32:17.110Z] 13:32:17 INFO - syncBreakpoint/<@resource://devtools/client/debugger/src/actions/breakpoints/syncBreakpoint.js:139:12
[task 2019-09-13T13:32:17.110Z] 13:32:17 INFO - asyncthunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45
[task 2019-09-13T13:32:17.111Z] 13:32:17 INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
[task 2019-09-13T13:32:17.115Z] 13:32:17 INFO - checkPendingBreakpoints/</<@resource://devtools/client/debugger/src/actions/sources/newSources.js:186:14
[task 2019-09-13T13:32:17.115Z] 13:32:17 INFO - checkPendingBreakpoints/<@resource://devtools/client/debugger/src/actions/sources/newSources.js:185:42
[task 2019-09-13T13:32:17.115Z] 13:32:17 INFO - async
thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45
[task 2019-09-13T13:32:17.115Z] 13:32:17 INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
[task 2019-09-13T13:32:17.115Z] 13:32:17 INFO - newGeneratedSources/</<@resource://devtools/client/debugger/src/actions/sources/newSources.js:368:17
[task 2019-09-13T13:32:17.116Z] 13:32:17 INFO - asyncnewGeneratedSources/<@resource://devtools/client/debugger/src/actions/sources/newSources.js:370:7
[task 2019-09-13T13:32:17.116Z] 13:32:17 INFO - async
thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45
[task 2019-09-13T13:32:17.116Z] 13:32:17 INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
[task 2019-09-13T13:32:17.116Z] 13:32:17 INFO - newQueuedSources/<@resource://devtools/client/debugger/src/actions/sources/newSources.js:225:13
[task 2019-09-13T13:32:17.116Z] 13:32:17 INFO - thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45
[task 2019-09-13T13:32:17.116Z] 13:32:17 INFO - bindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:644:12
[task 2019-09-13T13:32:17.116Z] 13:32:17 INFO - dispatchNewSources@resource://devtools/client/debugger/src/utils/source-queue.js:20:23
[task 2019-09-13T13:32:17.116Z] 13:32:17 INFO - invokeFunc@resource://devtools/client/shared/vendor/lodash.js:10333:23
[task 2019-09-13T13:32:17.116Z] 13:32:17 INFO - leadingEdge@resource://devtools/client/shared/vendor/lodash.js:10343:26
[task 2019-09-13T13:32:17.116Z] 13:32:17 INFO - debounced@resource://devtools/client/shared/vendor/lodash.js:10410:20
[task 2019-09-13T13:32:17.117Z] 13:32:17 INFO - queue@resource://devtools/client/debugger/src/utils/source-queue.js:31:5
[task 2019-09-13T13:32:17.117Z] 13:32:17 INFO - newSource@resource://devtools/client/debugger/src/client/firefox/events.js:96:24
[task 2019-09-13T13:32:17.117Z] 13:32:17 INFO - emit@resource://devtools/shared/event-emitter.js:190:24
[task 2019-09-13T13:32:17.117Z] 13:32:17 INFO - emit@resource://devtools/shared/event-emitter.js:271:18
[task 2019-09-13T13:32:17.117Z] 13:32:17 INFO - onPacket@resource://devtools/shared/protocol/Front.js:214:13
[task 2019-09-13T13:32:17.117Z] 13:32:17 INFO - onPacket@resource://devtools/shared/client/debugger-client.js:592:13
[task 2019-09-13T13:32:17.117Z] 13:32:17 INFO - send/<@resource://devtools/shared/transport/local-transport.js:70:25
[task 2019-09-13T13:32:17.117Z] 13:32:17 INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-09-13T13:32:17.117Z] 13:32:17 INFO - DevToolsUtils.executeSoonexports.executeSoon@resource://devtools/shared/DevToolsUtils.js:62:21
[task 2019-09-13T13:32:17.117Z] 13:32:17 INFO - send@resource://devtools/shared/transport/local-transport.js:58:21
[task 2019-09-13T13:32:17.117Z] 13:32:17 INFO - send@resource://devtools/server/debugger-server-connection.js:89:20
[task 2019-09-13T13:32:17.118Z] 13:32:17 INFO - receiveMessage@resource://devtools/shared/transport/child-transport.js:66:16
[task 2019-09-13T13:32:17.118Z] 13:32:17 INFO - MessageListener.receiveMessage
_addListener@resource://devtools/shared/transport/child-transport.js:40:14
[task 2019-09-13T13:32:17.118Z] 13:32:17 INFO - Rejection date: Fri Sep 13 2019 13:32:09 GMT+0000 (Coordinated Universal Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 263
[task 2019-09-13T13:32:17.118Z] 13:32:17 INFO - Stack trace:
[task 2019-09-13T13:32:17.118Z] 13:32:17 INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:263
[task 2019-09-13T13:32:17.118Z] 13:32:17 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1381
[task 2019-09-13T13:32:17.118Z] 13:32:17 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1385
[task 2019-09-13T13:32:17.118Z] 13:32:17 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1213
[task 2019-09-13T13:32:17.118Z] 13:32:17 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:805
[task 2019-09-13T13:32:17.118Z] 13:32:17 INFO - Leaving test bound
[task 2019-09-13T13:32:21.242Z] 13:32:21 INFO - GECKO(1652) | --DOMWINDOW == 25 (0x7fceb5e0a000) [pid = 1652] [serial = 191] [outer = (nil)] [url = about:blank]
[task 2019-09-13T13:32:21.242Z] 13:32:21 INFO - GECKO(1652) | --DOMWINDOW == 24 (0x7fceb5e0b400) [pid = 1652] [serial = 193] [outer = (nil)] [url = about:blank]
[task 2019-09-13T13:32:21.242Z] 13:32:21 INFO - GECKO(1652) | --DOMWINDOW == 23 (0x7fceb5e0a800) [pid = 1652] [serial = 194] [outer = (nil)] [url = about:blank]
[task 2019-09-13T13:32:21.243Z] 13:32:21 INFO - GECKO(1652) | --DOMWINDOW == 22 (0x7fcecaeb9c00) [pid = 1652] [serial = 182] [outer = (nil)] [url = about:blank]
[task 2019-09-13T13:32:21.243Z] 13:32:21 INFO - GECKO(1652) | --DOMWINDOW == 21 (0x7fcecaebfc00) [pid = 1652] [serial = 180] [outer = (nil)] [url = about:devtools-toolbox]
[task 2019-09-13T13:32:21.244Z] 13:32:21 INFO - GECKO(1652) | --DOMWINDOW == 20 (0x7fcee4cdd000) [pid = 1652] [serial = 184] [outer = (nil)] [url = about:blank]
[task 2019-09-13T13:32:21.905Z] 13:32:21 INFO - GECKO(1652) | [Parent 1652, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 318
[task 2019-09-13T13:32:21.905Z] 13:32:21 INFO - GECKO(1652) | [Parent 1652, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 318
[task 2019-09-13T13:32:21.909Z] 13:32:21 INFO - GECKO(1652) | [Parent 1652, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 318
[task 2019-09-13T13:32:21.909Z] 13:32:21 INFO - GECKO(1652) | [Parent 1652, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/workspace/build/src/dom/events/DOMEventTargetHelper.cpp, line 318
[task 2019-09-13T13:32:21.925Z] 13:32:21 INFO - Removing tab.
[task 2019-09-13T13:32:21.925Z] 13:32:21 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-09-13T13:32:22.006Z] 13:32:22 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-09-13T13:32:22.067Z] 13:32:22 INFO - Tab removed and finished closing
[task 2019-09-13T13:32:22.209Z] 13:32:22 INFO - GECKO(1652) | JavaScript error: resource://testing-common/PromiseTestUtils.jsm, line 112: uncaught exception: Object
[task 2019-09-13T13:32:22.230Z] 13:32:22 INFO - Console message: [JavaScript Error: "uncaught exception: Object" {file: "resource://testing-common/PromiseTestUtils.jsm" line: 112}]
[task 2019-09-13T13:32:22.231Z] 13:32:22 INFO - TEST-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-content-script-sources.js | Assertion count 4 is greater than expected range 0-0 assertions. -
[task 2019-09-13T13:32:22.235Z] 13:32:22 INFO - GECKO(1652) | MEMORY STAT | vsize 3854MB | residentFast 753MB | heapAllocated 191MB
[task 2019-09-13T13:32:22.235Z] 13:32:22 INFO - TEST-OK | devtools/client/debugger/test/mochitest/browser_dbg-content-script-sources.js | took 48651ms

(In reply to Bogdan Tara[:bogdan_tara] from comment #18) [bug 1559600]

This seems to perma fail on central, linux ccov tier 2:
https://treeherder.mozilla.org/#/jobs?repo=mozilla-central&fromchange=1367114a0633863badba4331e7f1c9ddbd84d1ee&searchStr=linux%2Cx64%2Cccov%2Cdebug%2Cmochitests%2Ctest-linux64-ccov%2Fdebug-mochitest-devtools-chrome-e10s-11%2Cm%28dt11%29&tochange=cbec1b8d83edf24146e48d6819dda24d9ab7a57b&selectedJob=266532565

Jason can you assign someone to take a look?

Flags: needinfo?(jlaster)
See Also: → 1559600

Andrea, when did this start?

Flags: needinfo?(jlaster)

This looks like a timing issue

[task 2019-09-18T11:08:50.700Z] 11:08:50     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-content-script-sources.js | A promise chain failed to handle a rejection: Page has navigated - stack: ContextError@resource://devtools/client/debugger/src/utils/context.js:15:49
[task 2019-09-18T11:08:50.701Z] 11:08:50     INFO - validateNavigateContext@resource://devtools/client/debugger/src/utils/context.js:23:11
[task 2019-09-18T11:08:50.701Z] 11:08:50     INFO - validateContext@resource://devtools/client/debugger/src/utils/context.js:40:26
[task 2019-09-18T11:08:50.702Z] 11:08:50     INFO - validateActionContext@resource://devtools/client/debugger/src/actions/utils/middleware/context.js:21:32
[task 2019-09-18T11:08:50.702Z] 11:08:50     INFO - context/</<@resource://devtools/client/debugger/src/actions/utils/middleware/context.js:32:28
[task 2019-09-18T11:08:50.702Z] 11:08:50     INFO - thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:100
[task 2019-09-18T11:08:50.703Z] 11:08:50     INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
[task 2019-09-18T11:08:50.703Z] 11:08:50     INFO - promiseMiddleware/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/promise.js:62:15
[task 2019-09-18T11:08:50.703Z] 11:08:50     INFO - promise callback*promiseMiddleware/</<@resource://devtools/client/debugger/src/actions/utils/middleware/promise.js:61:121
[task 2019-09-18T11:08:50.704Z] 11:08:50     INFO - context/</<@resource://devtools/client/debugger/src/actions/utils/middleware/context.js:35:12
[task 2019-09-18T11:08:50.704Z] 11:08:50     INFO - thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:100
[task 2019-09-18T11:08:50.705Z] 11:08:50     INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
[task 2019-09-18T11:08:50.705Z] 11:08:50     INFO - addBreakpoint/<@resource://devtools/client/debugger/src/actions/breakpoints/modify.js:137:12

That seems unlikely because those apis should not be used. Brian, what do you think?

Flags: needinfo?(bhackett1024)

(In reply to Jason Laster [:jlast] from comment #5)

That seems unlikely because those apis should not be used. Brian, what do you think?

Bug 1576781 isn't to blame but this failure is due to a debugger operation being canceled by context.js, which doesn't indicate an actual problem. There are several other tests that whitelist these exceptions but I think we should do it across all debugger mochitests because many of them could generate these exceptions.

Flags: needinfo?(bhackett1024)
Assignee: nobody → bhackett1024
Blocks: dbg-71
Status: NEW → ASSIGNED
Whiteboard: [debugger-mvp]
Pushed by bhackett@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/4064739e043f
Whitelist exceptions from canceled operations in debugger tests, r=jlast.
Status: ASSIGNED → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Target Milestone: --- → Firefox 71

Thanks for fixing Brian!

You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: