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?

This is a regression from bug 1436978: https://treeherder.mozilla.org/#/jobs?repo=autoland&filter-resultStatus=testfailed&filter-resultStatus=busted&filter-resultStatus=exception&filter-resultStatus=success&filter-resultStatus=pending&filter-resultStatus=running&filter-searchStr=linux%20asan%20devtools&tochange=46a5b62580f6184ae1423527688f40ae3932c326&fromchange=f3bfda748e339695fee5912acd12cd0c7a913b58&group_state=expanded Alexandre, please take a look if this can be fixed soon or requires a backout. Thank you in advance.

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.

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! :)

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

Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=162849194&repo=mozilla-inbound&lineNumber=1858