Closed Bug 1571863 Opened 6 years ago Closed 5 years ago

Intermittent devtools/client/debugger/test/mochitest/browser_dbg-worker-scopes.js | Test timed out -

Categories

(DevTools :: Debugger, defect, P5)

defect

Tracking

(firefox-esr68 unaffected, firefox71 wontfix, firefox72 fixed)

RESOLVED FIXED
Firefox 72
Tracking Status
firefox-esr68 --- unaffected
firefox71 --- wontfix
firefox72 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [test isolation][stockwell disabled])

Attachments

(3 files)

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


[task 2019-08-06T19:17:36.805Z] 19:17:36 INFO - TEST-PASS | devtools/client/debugger/test/mochitest/browser_dbg-worker-scopes.js | a new breakpoint was created -
[task 2019-08-06T19:17:36.806Z] 19:17:36 INFO - Invoking in tab: startWorker()
[task 2019-08-06T19:17:36.806Z] 19:17:36 INFO - Waiting for state change: paused
[task 2019-08-06T19:17:36.806Z] 19:17:36 INFO - Finished waiting for state change: paused
[task 2019-08-06T19:17:36.806Z] 19:17:36 INFO - Finished waiting for state change: paused
[task 2019-08-06T19:17:36.806Z] 19:17:36 INFO - Waiting for state change: selected source
[task 2019-08-06T19:17:36.806Z] 19:17:36 INFO - Console message: [JavaScript Error: "uncaught exception: Error: Parser: source sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/scopes-worker.js was not provided."]
[task 2019-08-06T19:17:36.806Z] 19:17:36 INFO - Buffered messages finished
[task 2019-08-06T19:17:36.806Z] 19:17:36 INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-worker-scopes.js | Test timed out -
[task 2019-08-06T19:17:36.861Z] 19:17:36 INFO - Removing tab.
[task 2019-08-06T19:17:36.861Z] 19:17:36 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-08-06T19:17:36.861Z] 19:17:36 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-08-06T19:17:36.868Z] 19:17:36 INFO - Tab removed and finished closing
[task 2019-08-06T19:17:36.893Z] 19:17:36 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-worker-scopes.js | A promise chain failed to handle a rejection: Error: Parser: source sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/scopes-worker.js was not provided. - stack: setInScopeLines/<@resource://devtools/client/debugger/src/actions/ast/setInScopeLines.js:59:13
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - asyncthunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:21:45
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - selectLocation/<@resource://devtools/client/debugger/src/actions/sources/select.js:147:13
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - async
thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:21:45
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - paused/<@resource://devtools/client/debugger/src/actions/pause/paused.js:53:13
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - asyncthunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:21:45
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - bindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:644:12
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - paused@resource://devtools/client/debugger/src/client/firefox/events.js:64:13
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - Async
emit@resource://devtools/shared/event-emitter.js:190:24
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - emit@resource://devtools/shared/event-emitter.js:271:18
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - onPacket@resource://devtools/shared/protocol/Front.js:204:13
[task 2019-08-06T19:17:36.896Z] 19:17:36 INFO - onPacket@resource://devtools/shared/client/debugger-client.js:593:13
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - send/<@resource://devtools/shared/transport/local-transport.js:70:25
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - DevToolsUtils.executeSoonexports.executeSoon@resource://devtools/shared/DevToolsUtils.js:62:29
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - send@resource://devtools/shared/transport/local-transport.js:58:21
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - send@resource://devtools/server/main.js:1108:20
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - receiveMessage@resource://devtools/shared/transport/child-transport.js:66:16
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - MessageListener.receiveMessage
_addListener@resource://devtools/shared/transport/child-transport.js:40:14
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - ready@resource://devtools/shared/transport/child-transport.js:57:10
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - connectToFrame/</onActorCreated<@resource://devtools/server/main.js:800:24
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - Rejection date: Tue Aug 06 2019 19:16:52 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 263
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - Stack trace:
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:263
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - chrome://mochikit/content/browser-test.js:nextTest:908
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1467
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1414
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:1209
[task 2019-08-06T19:17:36.897Z] 19:17:36 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:803
[task 2019-08-06T19:17:36.924Z] 19:17:36 INFO - GECKO(3212) | MEMORY STAT | vsize 1115MB | vsizeMaxContiguous 250MB | residentFast 411MB | heapAllocated 94MB
[task 2019-08-06T19:17:36.924Z] 19:17:36 INFO - TEST-OK | devtools/client/debugger/test/mochitest/browser_dbg-worker-scopes.js | took 45287ms
[task 2019-08-06T19:17:36.939Z] 19:17:36 INFO - checking window state
[task 2019-08-06T19:17:36.946Z] 19:17:36 INFO - GECKO(3212) | JavaScript error: resource://testing-common/PromiseTestUtils.jsm, line 112: uncaught exception: Object

Whiteboard: [test isolation]

Yep, we should definitely fix this. It looks like there was a source that was unavailble

source sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/scopes-worker.js was not provided

[task 2019-09-18T14:01:27.535Z] 14:01:27     INFO - TEST-UNEXPECTED-FAIL | devtools/client/debugger/test/mochitest/browser_dbg-worker-scopes.js | A promise chain failed to handle a rejection: Parser: source sourceURL-http://example.com/browser/devtools/client/debugger/test/mochitest/examples/scopes-worker.js was not provided. - stack: 14/task/flush/listener/<@resource://devtools/client/debugger/dist/vendors.js:1492:25
[task 2019-09-18T14:01:27.535Z] 14:01:27     INFO - listener@resource://devtools/client/debugger/dist/vendors.js:1488:24
[task 2019-09-18T14:01:27.535Z] 14:01:27     INFO - EventListener.handleEvent*flush@resource://devtools/client/debugger/dist/vendors.js:1501:19
[task 2019-09-18T14:01:27.535Z] 14:01:27     INFO - 14/task/push/<@resource://devtools/client/debugger/dist/vendors.js:1456:11
[task 2019-09-18T14:01:27.535Z] 14:01:27     INFO - push@resource://devtools/client/debugger/dist/vendors.js:1448:14
[task 2019-09-18T14:01:27.535Z] 14:01:27     INFO - 14/task/<@resource://devtools/client/debugger/dist/vendors.js:1504:29
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - invoke@resource://devtools/client/debugger/dist/vendors.js:1508:29
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - findOutOfScopeLocations@resource://devtools/client/debugger/src/workers/parser/index.js:19:17
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - getInScopeLines@resource://devtools/client/debugger/src/actions/ast/setInScopeLines.js:34:30
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - setInScopeLines/<@resource://devtools/client/debugger/src/actions/ast/setInScopeLines.js:66:25
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - selectLocation/<@resource://devtools/client/debugger/src/actions/sources/select.js:184:13
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - async*thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - dispatch@resource://devtools/client/shared/vendor/redux.js:755:18
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - paused/<@resource://devtools/client/debugger/src/actions/pause/paused.js:63:13
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - async*thunk/</</<@resource://devtools/client/debugger/src/actions/utils/middleware/thunk.js:29:45
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - bindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:644:12
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - paused@resource://devtools/client/debugger/src/client/firefox/events.js:73:13
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - Async*emit@resource://devtools/shared/event-emitter.js:190:24
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - emit@resource://devtools/shared/event-emitter.js:271:18
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - onPacket@resource://devtools/shared/protocol/Front.js:218:13
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - onPacket@resource://devtools/shared/client/debugger-client.js:597:13
[task 2019-09-18T14:01:27.536Z] 14:01:27     INFO - send/<@resource://devtools/shared/transport/local-transport.js:70:25
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:62:21
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - send@resource://devtools/shared/transport/local-transport.js:58:21
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - send@resource://devtools/server/debugger-server-connection.js:89:20
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - receiveMessage@resource://devtools/shared/transport/child-transport.js:66:16
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - MessageListener.receiveMessage*_addListener@resource://devtools/shared/transport/child-transport.js:40:14
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - ready@resource://devtools/shared/transport/child-transport.js:57:10
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - connectToFrame/</onActorCreated<@resource://devtools/server/connectors/frame-connector.js:183:22
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - MessageListener.receiveMessage*trackMessageManager@resource://devtools/server/connectors/frame-connector.js:55:12
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - connectToFrame/<@resource://devtools/server/connectors/frame-connector.js:283:5
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - connectToFrame@resource://devtools/server/connectors/frame-connector.js:41:10
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - connect@resource://devtools/server/actors/targets/frame-proxy.js:65:35
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - async*BrowserTabList.prototype._getActorForBrowser@resource://devtools/server/actors/webbrowser.js:368:16
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - BrowserTabList.prototype.getTab@resource://devtools/server/actors/webbrowser.js:410:21
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - onGetTab@resource://devtools/server/actors/root.js:364:35
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - onPacket@resource://devtools/server/debugger-server-connection.js:378:58
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - send/<@resource://devtools/shared/transport/local-transport.js:70:25
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - exports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:111:22
[task 2019-09-18T14:01:27.537Z] 14:01:27     INFO - DevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:62:21
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - send@resource://devtools/shared/transport/local-transport.js:58:21
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - send@resource://devtools/shared/protocol/Front.js:164:30
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - request@resource://devtools/shared/protocol/Front.js:182:10
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:49:19
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - getTab@resource://devtools/shared/fronts/root.js:329:30
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - createTargetForTab@resource://devtools/client/framework/target.js:94:28
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - async*forTab@resource://devtools/client/framework/target.js:40:26
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - openToolboxForTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:455:38
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - openNewTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:487:10
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - async*initDebugger@chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/helpers.js:558:25
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - async*@chrome://mochitests/content/browser/devtools/client/debugger/test/mochitest/browser_dbg-worker-scopes.js:37:21
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - Tester_execTest/<@chrome://mochikit/content/browser-test.js:1350:34
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1385:11
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1213:14
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:805:67
[task 2019-09-18T14:01:27.538Z] 14:01:27     INFO - Rejection date: Wed Sep 18 2019 14:00:43 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 265

Brian, would you be able to look into this?

Assignee: nobody → bhackett1024

Pushed by csabou@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/d184d0b3aeaa
Disable browser_dbg-worker-scopes.js on linux, windows and mac. r=gbrown

Keywords: checkin-needed

This test was recently disabled, but still warrants investigation and re-enabling.

Keywords: leave-open
Whiteboard: [test isolation][stockwell disable-recommended] → [test isolation][stockwell disabled]

When I run this test locally, I get test failures pretty frequently. These have several causes but are all related to problems communicating with a terminating worker. With the fixes in the attached patches, I was able to repeat the test 750 times in a row without a failure.

Flags: needinfo?(bhackett1024)
Pushed by bhackett@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/10affb63a6a2 Part 1 - Manage SourceFront from its associated ThreadFront, r=jdescottes,ochameau. https://hg.mozilla.org/integration/autoland/rev/2ca5dd5040e4 Part 2 - Handle failures when communicating with source fronts, and reenable browser_dbg-worker-scopes.js, r=loganfsmyth.
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Target Milestone: --- → Firefox 72
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: