Open Bug 1438174 Opened 6 years ago Updated 2 years ago

Intermittent devtools/client/framework/test/browser_browser_toolbox_debugger.js | A promise chain failed to handle a rejection: eventLoop is undefined

Categories

(DevTools :: Framework, defect, P5)

defect

Tracking

(firefox60 fixed)

REOPENED
Firefox 60
Tracking Status
firefox60 --- fixed

People

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

References

Details

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

Attachments

(1 file)

Filed by: ncsoregi [at] mozilla.com

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

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

[task 2018-02-14T13:27:40.910Z] 13:27:40     INFO - TEST-INFO | started process screentopng
[task 2018-02-14T13:27:41.300Z] 13:27:41     INFO - TEST-INFO | screentopng: exit 0
[task 2018-02-14T13:27:41.300Z] 13:27:41     INFO - Buffered messages logged at 13:27:23
[task 2018-02-14T13:27:41.301Z] 13:27:41     INFO - Entering test bound runTest
[task 2018-02-14T13:27:41.301Z] 13:27:41     INFO - Opening the browser toolbox
[task 2018-02-14T13:27:41.301Z] 13:27:41     INFO - 
[task 2018-02-14T13:27:41.301Z] 13:27:41     INFO - Buffered messages logged at 13:27:24
[task 2018-02-14T13:27:41.301Z] 13:27:41     INFO - TEST-PASS | devtools/client/framework/test/browser_browser_toolbox_debugger.js | Browser toolbox started
[task 2018-02-14T13:27:41.302Z] 13:27:41     INFO -  - 
[task 2018-02-14T13:27:41.302Z] 13:27:41     INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/FileUtils.jsm" line: 170}]
[task 2018-02-14T13:27:41.302Z] 13:27:41     INFO - Buffered messages logged at 13:27:34
[task 2018-02-14T13:27:41.304Z] 13:27:41     INFO - Console message: [JavaScript Warning: "DebuggeeWouldRun: debuggee `resource://devtools/shared/base-loader.js:431' would run" {file: "resource://devtools/shared/base-loader.js -> resource://devtools/shared/builtin-modules.js" line: 158}]
[task 2018-02-14T13:27:41.304Z] 13:27:41     INFO - Buffered messages logged at 13:27:37
[task 2018-02-14T13:27:41.305Z] 13:27:41     INFO - Console message: [JavaScript Warning: "DebuggeeWouldRun: debuggee `resource://gre/modules/subprocess/subprocess_common.jsm:99' would run" {file: "resource://devtools/shared/base-loader.js -> resource://devtools/server/actors/utils/event-loop.js" line: 118}]
[task 2018-02-14T13:27:41.306Z] 13:27:41     INFO - Buffered messages logged at 13:27:38
[task 2018-02-14T13:27:41.307Z] 13:27:41     INFO - Console message: [JavaScript Warning: "DebuggeeWouldRun: debuggee `resource://gre/modules/subprocess/subprocess_common.jsm:99' would run" {file: "resource://devtools/shared/base-loader.js -> resource://devtools/server/actors/utils/event-loop.js" line: 118}]
[task 2018-02-14T13:27:41.308Z] 13:27:41     INFO - Console message: [JavaScript Error: "TypeError: eventLoop is undefined" {file: "resource://devtools/shared/base-loader.js -> resource://devtools/server/actors/thread.js" line: 185}]
[task 2018-02-14T13:27:41.309Z] 13:27:41     INFO - Buffered messages logged at 13:27:40
[task 2018-02-14T13:27:41.310Z] 13:27:41     INFO - TEST-PASS | devtools/client/framework/test/browser_browser_toolbox_debugger.js | Browser toolbox process just closed - 
[task 2018-02-14T13:27:41.310Z] 13:27:41     INFO - Buffered messages finished
[task 2018-02-14T13:27:41.312Z] 13:27:41     INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/test/browser_browser_toolbox_debugger.js | A promise chain failed to handle a rejection: eventLoop is undefined - stack: enter@resource://devtools/shared/base-loader.js -> resource://devtools/server/actors/utils/event-loop.js:118:5
Since this bug was created, a few hours ago, there have been 34 failures.

The most affected platform is Linux x64, but there are also occurrences on Linux, one failure on linux64-stylo-disabled and one failure on Windows 7 platform.

Affected build types: asan and opt

An example of a recent log file:
https://treeherder.mozilla.org/logviewer.html#?repo=autoland&job_id=162330731&lineNumber=2203

And a relevant part of if:
[task 2018-02-15T03:44:18.951Z] 03:44:18     INFO - TEST-PASS | devtools/client/framework/test/browser_browser_toolbox_debugger.js | Browser toolbox process just closed - 
[task 2018-02-15T03:44:18.952Z] 03:44:18     INFO - Buffered messages finished
[task 2018-02-15T03:44:18.953Z] 03:44:18     INFO - TEST-UNEXPECTED-FAIL | devtools/client/framework/test/browser_browser_toolbox_debugger.js | A promise chain failed to handle a rejection: eventLoop is undefined - stack: enter@resource://devtools/shared/base-loader.js -> resource://devtools/server/actors/utils/event-loop.js:118:5
[task 2018-02-15T03:44:18.954Z] 03:44:18     INFO - _pushThreadPause@resource://devtools/shared/base-loader.js -> resource://devtools/server/actors/thread.js:180:5
[task 2018-02-15T03:44:18.955Z] 03:44:18     INFO - _pauseAndRespond@resource://devtools/shared/base-loader.js -> resource://devtools/server/actors/thread.js:395:7
[task 2018-02-15T03:44:18.956Z] 03:44:18     INFO - hit@resource://devtools/shared/base-loader.js -> resource://devtools/server/actors/breakpoint.js:188:12
[task 2018-02-15T03:44:18.957Z] 03:44:18     INFO - plop@http://mozilla.org/browser-toolbox-test.js:2:7
[task 2018-02-15T03:44:18.957Z] 03:44:18     INFO - setInterval handler*runTest@chrome://mochitests/content/browser/devtools/client/framework/test/browser_browser_toolbox_debugger.js:46:18
[task 2018-02-15T03:44:18.958Z] 03:44:18     INFO - TaskImpl_run@resource://gre/modules/Task.jsm:326:42
[task 2018-02-15T03:44:18.958Z] 03:44:18     INFO - promise callback*TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:403:7
[task 2018-02-15T03:44:18.959Z] 03:44:18     INFO - TaskImpl_run@resource://gre/modules/Task.jsm:334:15
[task 2018-02-15T03:44:18.961Z] 03:44:18     INFO - TaskImpl@resource://gre/modules/Task.jsm:275:3
[task 2018-02-15T03:44:18.961Z] 03:44:18     INFO - asyncFunction@resource://gre/modules/Task.jsm:247:14
[task 2018-02-15T03:44:18.962Z] 03:44:18     INFO - Task_spawn@resource://gre/modules/Task.jsm:161:12
[task 2018-02-15T03:44:18.962Z] 03:44:18     INFO - TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:396:16
[task 2018-02-15T03:44:18.963Z] 03:44:18     INFO - TaskImpl_run@resource://gre/modules/Task.jsm:334:15
[task 2018-02-15T03:44:18.963Z] 03:44:18     INFO - TaskImpl@resource://gre/modules/Task.jsm:275:3
[task 2018-02-15T03:44:18.964Z] 03:44:18     INFO - asyncFunction@resource://gre/modules/Task.jsm:247:14
[task 2018-02-15T03:44:18.964Z] 03:44:18     INFO - Task_spawn@resource://gre/modules/Task.jsm:161:12
[task 2018-02-15T03:44:18.964Z] 03:44:18     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1058:9
[task 2018-02-15T03:44:18.965Z] 03:44:18     INFO - Tester.prototype.nextTest</<@chrome://mochikit/content/browser-test.js:958:9
[task 2018-02-15T03:44:18.966Z] 03:44:18     INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:795:59
[task 2018-02-15T03:44:18.967Z] 03:44:18     INFO - Rejection date: Thu Feb 15 2018 03:44:16 GMT+0000 (UTC) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 251
[task 2018-02-15T03:44:18.967Z] 03:44:18     INFO - Stack trace:
[task 2018-02-15T03:44:18.969Z] 03:44:18     INFO -     resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:251
[task 2018-02-15T03:44:18.969Z] 03:44:18     INFO -     chrome://mochikit/content/browser-test.js:Tester_execTest/<:1089
[task 2018-02-15T03:44:18.970Z] 03:44:18     INFO -     Tester_execTest@chrome://mochikit/content/browser-test.js:1058:9
[task 2018-02-15T03:44:18.970Z] 03:44:18     INFO -     Tester.prototype.nextTest</<@chrome://mochikit/content/browser-test.js:958:9
[task 2018-02-15T03:44:18.971Z] 03:44:18     INFO -     SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:795:59
[task 2018-02-15T03:44:18.972Z] 03:44:18     INFO - Leaving test bound runTest

:pbro could you please take a look?
Flags: needinfo?(pbrosset)
Whiteboard: [stockwell needswork]
Yes, I thought it was related to bug 1437828. bug 1437828 makes it permafail.
I already have a patch, I'll land it first here.
Let me just pull it out and push it to try.
Assignee: nobody → poirot.alex
Flags: needinfo?(poirot.alex)
Blocks: 1437828
Blocks: 1438508
Comment on attachment 8951231 [details]
Bug 1438174 - Fix race during test shutdown in browser_browser_toolbox_debugger.js.

https://reviewboard.mozilla.org/r/220500/#review226504

Thanks! :)
Attachment #8951231 - Flags: review?(jryans) → review+
Pushed by apoirot@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/69bcdd9e841f
Fix race during test shutdown in browser_browser_toolbox_debugger.js. r=jryans
https://hg.mozilla.org/mozilla-central/rev/69bcdd9e841f
Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → FIXED
Target Milestone: --- → Firefox 60
Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=162849194&repo=mozilla-inbound&lineNumber=1858
Status: RESOLVED → REOPENED
Resolution: FIXED → ---
Product: Firefox → DevTools
Severity: normal → S3
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: