Closed Bug 1453815 Opened 6 years ago Closed 5 years ago

Intermittent devtools/client/debugger/new/test/mochitest/browser_dbg-navigation.js | A promise chain failed to handle a rejection: source is undefined - stack: findScopeByName@resource://devtools/shared/base-loader.js

Categories

(DevTools :: Debugger, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

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

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell fixed:other])

Attachments

(1 file)

Filed by: ncsoregi [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=173351889&repo=autoland

https://queue.taskcluster.net/v1/task/VjNjhp4oR1KDBjRplqwmcg/runs/0/artifacts/public/logs/live_backing.log

0:55:54     INFO -  225 INFO TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-navigation.js | Selected source is long.js -
20:55:54     INFO -  Buffered messages finished
20:55:54    ERROR -  226 INFO TEST-UNEXPECTED-FAIL | devtools/client/debugger/new/test/mochitest/browser_dbg-navigation.js | A promise chain failed to handle a rejection: source is undefined - stack: findScopeByName@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/debugger.js:7894:29
20:55:54     INFO -  async*makeScopedLocation@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/debugger.js:13722:24
20:55:54     INFO -  async*syncClientBreakpoint@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/debugger.js:13762:32
20:55:54     INFO -  async*syncBreakpoint/<@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/debugger.js:5947:53
20:55:54     INFO -  async*thunk/</</<@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/debugger.js:28585:45
20:55:54     INFO -  dispatch@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/vendor/redux.js:755:18
20:55:54     INFO -  checkPendingBreakpoints/<@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/debugger.js:31743:13
20:55:54     INFO -  async*thunk/</</<@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/debugger.js:28585:45
20:55:54     INFO -  dispatch@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/vendor/redux.js:755:18
20:55:54     INFO -  newSources/<@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/debugger.js:31788:7
20:55:54     INFO -  async*thunk/</</<@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/debugger.js:28585:45
20:55:54     INFO -  bindActionCreator/<@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/vendor/redux.js:644:12
20:55:54     INFO -  dispatchNewSources@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/debugger.js:31090:23
20:55:54     INFO -  async*invokeFunc@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/vendor/lodash.js:10350:18
20:55:54     INFO -  leadingEdge@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/vendor/lodash.js:10360:26
20:55:54     INFO -  debounced@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/vendor/lodash.js:10425:20
20:55:54     INFO -  queue@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/debugger.js:31104:5
20:55:54     INFO -  newSource@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/debugger.js:13096:3
20:55:54     INFO -  eventSource/proto.emit@resource://devtools/shared/base-loader.js -> resource://devtools/shared/client/event-source.js:124:9
20:55:54     INFO -  onPacket@resource://devtools/shared/base-loader.js -> resource://devtools/shared/client/debugger-client.js:883:9
20:55:54     INFO -  send/<@resource://devtools/shared/base-loader.js -> resource://devtools/shared/transport/transport.js:569:13
20:55:54     INFO -  exports.makeInfallible/<@resource://devtools/shared/base-loader.js -> resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14
20:55:54     INFO -  DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/base-loader.js -> resource://devtools/shared/DevToolsUtils.js:57:19
20:55:54     INFO -  send@resource://devtools/shared/base-loader.js -> resource://devtools/shared/transport/transport.js:562:9
20:55:54     INFO -  send@resource://devtools/shared/base-loader.js -> resource://devtools/server/main.js:1487:5
20:55:54     INFO -  receiveMessage@resource://devtools/shared/base-loader.js -> resource://devtools/shared/transport/transport.js:760:7
20:55:54     INFO -  MessageListener.receiveMessage*_addListener@resource://devtools/shared/base-loader.js -> resource://devtools/shared/transport/transport.js:732:7
20:55:54     INFO -  ready@resource://devtools/shared/base-loader.js -> resource://devtools/shared/transport/transport.js:749:7
20:55:54     INFO -  connectToFrame/</onActorCreated<@resource://devtools/shared/base-loader.js -> resource://devtools/server/main.js:1070:9
20:55:54     INFO -  Rejection date: Thu Apr 12 2018 20:55:49 GMT+0000 (Coordinated Universal Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 257
20:55:54     INFO -  Stack trace:
20:55:54     INFO -      resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:257
20:55:54     INFO -      chrome://mochikit/content/browser-test.js:Tester_execTest/<:1098
20:55:54     INFO -      Tester_execTest@chrome://mochikit/content/browser-test.js:1067:9
20:55:54     INFO -      Tester.prototype.nextTest</<@chrome://mochikit/content/browser-test.js:967:9
20:55:54     INFO -      SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:795:59
20:55:54     INFO -  227 INFO Leaving test bound
20:55:54     INFO -  GECKO(6180) | ++DOMWINDOW == 58 (0000028441350400) [pid = 6180] [serial = 229] [outer = 000002843047B400]
20:55:54     INFO -  228 INFO Removing tab.
20:55:54     INFO -  229 INFO Waiting for event: 'TabClose' on [object XULElement].
20:55:54     INFO -  230 INFO Got event: 'TabClose' on [object XULElement].
20:55:54     INFO -  231 INFO Tab removed and finished closing
20:55:54     INFO -  GECKO(6180) | MEMORY STAT | vsize 2099339MB | vsizeMaxContiguous 129440216MB | residentFast 632MB | heapAllocated 232MB
20:55:54     INFO -  232 INFO TEST-OK | devtools/client/debugger/new/test/mochitest/browser_dbg-navigation.js | took 17515ms
There have been 30 failures in the last 7 days, mostly on debug, as follows:

- 1 failure on linux 32 opt
- 5 failures on linux64-ccov debug
- 15 failure on osx 10.10 debug
- 1 failure on windows 10x64 debug
- 7 failures on windows 10x64 ccov debug
- 1 failure on win7 32 debug

Example failure log:
https://treeherder.mozilla.org/logviewer.html#?job_id=179730853&repo=mozilla-central&lineNumber=5980

23:56:16     INFO -  289 INFO TEST-PASS | devtools/client/debugger/new/test/mochitest/browser_dbg-navigation.js | Selected source is long.js -
23:56:16     INFO -  Buffered messages finished
23:56:16    ERROR -  290 INFO TEST-UNEXPECTED-FAIL | devtools/client/debugger/new/test/mochitest/browser_dbg-navigation.js | A promise chain failed to handle a rejection: source is undefined - stack: findScopeByName@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/src/utils/breakpoint/astBreakpointLocation.js:46:29
23:56:16     INFO -  async*makeScopedLocation@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/src/actions/breakpoints/syncBreakpoint.js:26:24
23:56:16     INFO -  async*syncClientBreakpoint@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/src/actions/breakpoints/syncBreakpoint.js:71:32
23:56:16     INFO -  async*syncBreakpoint/<@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/src/actions/breakpoints.js:65:16
23:56:16     INFO -  async*thunk/</</<@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/src/actions/utils/middleware/thunk.js:31:45
23:56:16     INFO -  dispatch@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/vendor/redux.js:755:18
23:56:16     INFO -  checkPendingBreakpoints/<@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/src/actions/sources/newSources.js:143:13
23:56:16     INFO -  async*thunk/</</<@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/src/actions/utils/middleware/thunk.js:31:45
23:56:16     INFO -  dispatch@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/vendor/redux.js:755:18
23:56:16     INFO -  newSources/<@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/src/actions/sources/newSources.js:198:7
23:56:16     INFO -  async*thunk/</</<@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/src/actions/utils/middleware/thunk.js:31:45
23:56:16     INFO -  bindActionCreator/<@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/vendor/redux.js:644:12
23:56:16     INFO -  dispatchNewSources@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/src/utils/source-queue.js:21:23
23:56:16     INFO -  async*invokeFunc@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/vendor/lodash.js:10350:18
23:56:16     INFO -  leadingEdge@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/vendor/lodash.js:10360:26
23:56:16     INFO -  debounced@resource://devtools/shared/base-loader.js -> resource://devtools/client/shared/vendor/lodash.js:10425:20
23:56:16     INFO -  queue@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/src/utils/source-queue.js:36:5
23:56:16     INFO -  newSource@resource://devtools/shared/base-loader.js -> resource://devtools/client/debugger/new/src/client/firefox/events.js:87:3
23:56:16     INFO -  eventSource/proto.emit@resource://devtools/shared/base-loader.js -> resource://devtools/shared/client/event-source.js:124:9
23:56:16     INFO -  onPacket@resource://devtools/shared/base-loader.js -> resource://devtools/shared/client/debugger-client.js:878:9
23:56:16     INFO -  send/<@resource://devtools/shared/base-loader.js -> resource://devtools/shared/transport/transport.js:553:13
23:56:16     INFO -  exports.makeInfallible/<@resource://devtools/shared/base-loader.js -> resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14
23:56:16     INFO -  DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/base-loader.js -> resource://devtools/shared/DevToolsUtils.js:57:19
23:56:16     INFO -  send@resource://devtools/shared/base-loader.js -> resource://devtools/shared/transport/transport.js:547:9
23:56:16     INFO -  send@resource://devtools/shared/base-loader.js -> resource://devtools/server/main.js:1476:5
23:56:16     INFO -  receiveMessage@resource://devtools/shared/base-loader.js -> resource://devtools/shared/transport/transport.js:735:7
23:56:16     INFO -  MessageListener.receiveMessage*_addListener@resource://devtools/shared/base-loader.js -> resource://devtools/shared/transport/transport.js:709:7
23:56:16     INFO -  ready@resource://devtools/shared/base-loader.js -> resource://devtools/shared/transport/transport.js:726:7
23:56:16     INFO -  connectToFrame/</onActorCreated<@resource://devtools/shared/base-loader.js -> resource://devtools/server/main.js:1059:9
23:56:16     INFO -  exports.makeInfallible/<@resource://devtools/shared/base-loader.js -> resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14
23:56:16     INFO -  MessageListener.receiveMessage*trackMessageManager@resource://devtools/shared/base-loader.js -> resource://devtools/server/main.js:994:11
23:56:16     INFO -  connectToFrame/<@resource://devtools/shared/base-loader.js -> resource://devtools/server/main.js:1147:7
23:56:16     INFO -  connectToFrame@resource://devtools/shared/base-loader.js -> resource://devtools/server/main.js:984:12
23:56:16     INFO -  connect@resource://devtools/shared/base-loader.js -> resource://devtools/server/actors/webbrowser.js:723:19
23:56:16     INFO -  async*BrowserTabList.prototype._getActorForBrowser@resource://devtools/shared/base-loader.js -> resource://devtools/server/actors/webbrowser.js:325:10
23:56:16     INFO -  BrowserTabList.prototype.getTab@resource://devtools/shared/base-loader.js -> resource://devtools/server/actors/webbrowser.js:364:16
23:56:16     INFO -  onGetTab@resource://devtools/shared/base-loader.js -> resource://devtools/server/actors/root.js:332:24
23:56:16     INFO -  Async*onPacket@resource://devtools/shared/base-loader.js -> resource://devtools/server/main.js:1761:15
23:56:16     INFO -  send/<@resource://devtools/shared/base-loader.js -> resource://devtools/shared/transport/transport.js:553:13
23:56:16     INFO -  exports.makeInfallible/<@resource://devtools/shared/base-loader.js -> resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14
23:56:16     INFO -  DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/base-loader.js -> resource://devtools/shared/DevToolsUtils.js:57:19
23:56:16     INFO -  send@resource://devtools/shared/base-loader.js -> resource://devtools/shared/transport/transport.js:547:9
23:56:16     INFO -  _sendRequest@resource://devtools/shared/base-loader.js -> resource://devtools/shared/client/debugger-client.js:776:7
23:56:16     INFO -  _sendOrQueueRequest@resource://devtools/shared/base-loader.js -> resource://devtools/shared/client/debugger-client.js:760:7
23:56:16     INFO -  request@resource://devtools/shared/base-loader.js -> resource://devtools/shared/client/debugger-client.js:650:5
23:56:16     INFO -  Rejection date: Tue May 22 2018 23:55:51 GMT+0000 (Coordinated Universal Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 257
23:56:16     INFO -  Stack trace:
23:56:16     INFO -  resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:257
23:56:16     INFO -  chrome://mochikit/content/browser-test.js:Tester_execTest/<:1108
23:56:16     INFO -  chrome://mochikit/content/browser-test.js:Tester_execTest:1074
23:56:16     INFO -  chrome://mochikit/content/browser-test.js:nextTest/<:976
23:56:16     INFO -  chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:795
23:56:16     INFO -  291 INFO Leaving test bound
23:56:16     INFO -  GECKO(5628) | ++DOMWINDOW == 30 (000001D9B3557C00) [pid = 5628] [serial = 195] [outer = 000001D9B420DC00]
23:56:24     INFO -  GECKO(5628) | --DOCSHELL 000001D9B7A98000 == 6 [pid = 5628] [id = {e6206dbb-c6ea-450b-ae0c-7056a3dfd7bb}]
23:56:24     INFO -  GECKO(5628) | --DOCSHELL 000001D9A7944800 == 5 [pid = 5628] [id = {fbd8861f-690f-4ecb-83f2-1189821f38fe}]
23:56:27     INFO -  292 INFO Removing tab.
23:56:27     INFO -  293 INFO Waiting for event: 'TabClose' on [object XULElement].
23:56:27     INFO -  294 INFO Got event: 'TabClose' on [object XULElement].
23:56:27     INFO -  295 INFO Tab removed and finished closing
23:56:27     INFO -  GECKO(5628) | --DOMWINDOW == 29 (000001D9B4211A00) [pid = 5628] [serial = 187] [outer = 0000000000000000] [url = chrome://devtools/content/debugger/new/index.html]
23:56:27     INFO -  GECKO(5628) | --DOMWINDOW == 28 (000001D9B4210200) [pid = 5628] [serial = 184] [outer = 0000000000000000] [url = about:blank]
23:56:27     INFO -  GECKO(5628) | MEMORY STAT | vsize 2553MB | vsizeMaxContiguous 132237826MB | residentFast 583MB | heapAllocated 240MB
23:56:27     INFO -  296 INFO TEST-OK | devtools/client/debugger/new/test/mochitest/browser_dbg-navigation.js | took 75056ms
Flags: needinfo?(jlaster)
Whiteboard: [stockwell needswork]
Product: Firefox → DevTools
We have 30 failures in the last 7 days
For the failure pattern, please see Comment 7.
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=183260904&repo=mozilla-inbound&lineNumber=5181

:jlast could you please take a look?
We have 49 failures in the last 7 days. 
Most are on Linux 64 with some Win 10 and OSX, all of them on debug builds.
:jlast, could you please take a look?
I'm looking into this now, this looks easier to reproduce than dbg-stepping
Flags: needinfo?(jlaster)
Depends on: 1470999
No longer depends on: 1470999
this will be fixed here - https://github.com/devtools-html/debugger.html/pull/6553.

we should have a fix in nightly in a day or two
:jlast, did you manage to fix it or do you think is best if we disable this?
Flags: needinfo?(jlaster)
Created a patch for disabling this test if needed. Could you please take a look?
Assignee: nobody → aiakab
Attachment #8990185 - Flags: review?(jmaher)
Attachment #8990185 - Flags: review?(jmaher) → review+
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]
Pushed by shindli@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/d08e63a3f658
Disabled devtools/client/debugger/new/test/mochitest/browser_dbg-navigation.js for frequent failures. r=jmaher
Keywords: checkin-needed
Sorry for missing the comment 3 days ago. We fixed the test July 3rd.

https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2018-06-29&endday=2018-07-06&tree=trunk&bug=1453815


Could we revert this commit so that the test runs on more platforms?
Flags: needinfo?(jlaster)
Backed out changeset d08e63a3f658 (bug 1453815) for dev requirement - test was fixed before this patch landed.

Backout: https://hg.mozilla.org/integration/mozilla-inbound/rev/042060c4482e39b9edc07266321fbda6b05b3d57
Keywords: leave-open
Whiteboard: [stockwell disabled] → [stockwell fixed:other]
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
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.

Attachment

General

Created:
Updated:
Size: