1667084 - Intermittent devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js | Test timed out -

Status: RESOLVED FIXED

(DevTools :: about:debugging, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=316577617&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/fWMCK8yzSiOiPuylIyIvLA/runs/0/artifacts/public/logs/live_backing.log

---

[task 2020-09-24T12:16:43.083Z] 12:16:43 INFO - TEST-START | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js
[task 2020-09-24T12:16:45.881Z] 12:16:45 INFO - GECKO(5232) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2020-09-24T12:16:45.906Z] 12:16:45 INFO - GECKO(5232) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2020-09-24T12:16:45.965Z] 12:16:45 INFO - GECKO(5232) | console.log: "[DISPATCH] action type:" "UPDATE_GRIDS"
[task 2020-09-24T12:16:45.965Z] 12:16:45 INFO - GECKO(5232) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2020-09-24T12:16:45.965Z] 12:16:45 INFO - GECKO(5232) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2020-09-24T12:16:45.975Z] 12:16:45 INFO - GECKO(5232) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2020-09-24T12:16:46.067Z] 12:16:46 INFO - GECKO(5232) | console.log: "[DISPATCH] action type:" "CLEAR_FLEXBOX"
[task 2020-09-24T12:16:46.067Z] 12:16:46 INFO - GECKO(5232) | console.log: "[DISPATCH] action type:" "UPDATE_OFFSET_PARENT"
[task 2020-09-24T12:16:46.067Z] 12:16:46 INFO - GECKO(5232) | console.log: "[DISPATCH] action type:" "UPDATE_LAYOUT"
[task 2020-09-24T12:16:47.164Z] 12:16:47 INFO - GECKO(5232) | JavaScript error: resource://devtools/client/webconsole/webconsole-ui.js, line 722: TypeError: can't access property "getInspectorSelection", this.hud is null
[task 2020-09-24T12:18:13.119Z] 12:18:13 INFO - TEST-INFO | started process screenshot
[task 2020-09-24T12:18:13.179Z] 12:18:13 INFO - TEST-INFO | screenshot: exit 0
[task 2020-09-24T12:18:13.181Z] 12:18:13 INFO - Buffered messages logged at 12:16:43
[task 2020-09-24T12:18:13.181Z] 12:18:13 INFO - Entering test bound testWebExtensionsToolboxWebConsole
[task 2020-09-24T12:18:13.182Z] 12:18:13 INFO - TEST-PASS | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js | disable_autohide should be initially false -
[task 2020-09-24T12:18:13.182Z] 12:18:13 INFO - Create promises waiting for the messages emitted by the test addon
[task 2020-09-24T12:18:13.183Z] 12:18:13 INFO - opening about:debugging
[task 2020-09-24T12:18:13.183Z] 12:18:13 INFO - Adding a new tab with URL: about:debugging
[task 2020-09-24T12:18:13.184Z] 12:18:13 INFO - Tab added and finished loading
[task 2020-09-24T12:18:13.184Z] 12:18:13 INFO - Wait until Connect page is displayed
[task 2020-09-24T12:18:13.184Z] 12:18:13 INFO - Buffered messages logged at 12:16:44
[task 2020-09-24T12:18:13.185Z] 12:18:13 INFO - Select This Firefox page
[task 2020-09-24T12:18:13.185Z] 12:18:13 INFO - Wait for requests to be complete
[task 2020-09-24T12:18:13.185Z] 12:18:13 INFO - Wait for runtime page to be rendered
[task 2020-09-24T12:18:13.186Z] 12:18:13 INFO - Buffered messages logged at 12:16:45
[task 2020-09-24T12:18:13.186Z] 12:18:13 INFO - Generate XPI file for [e-mail]
[task 2020-09-24T12:18:13.187Z] 12:18:13 INFO - Install temporary extension named test-devtools-webextension
[task 2020-09-24T12:18:13.187Z] 12:18:13 INFO - Wait for addon to be installed
[task 2020-09-24T12:18:13.187Z] 12:18:13 INFO - Wait until the addon debug target appears
[task 2020-09-24T12:18:13.187Z] 12:18:13 INFO - Open a toolbox to debug the addon
[task 2020-09-24T12:18:13.188Z] 12:18:13 INFO - Open about:devtools-toolbox page
[task 2020-09-24T12:18:13.188Z] 12:18:13 INFO - TEST-PASS | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js | test-devtools-webextension tab target appeared -
[task 2020-09-24T12:18:13.188Z] 12:18:13 INFO - TEST-PASS | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js | Inspect button for test-devtools-webextension appeared -
[task 2020-09-24T12:18:13.188Z] 12:18:13 INFO - Buffered messages logged at 12:16:46
[task 2020-09-24T12:18:13.189Z] 12:18:13 INFO - Wait for about:devtools-toolbox tab will be selected
[task 2020-09-24T12:18:13.189Z] 12:18:13 INFO - Wait until the addon popup is opened from the test script
[task 2020-09-24T12:18:13.189Z] 12:18:13 INFO - Clicking the menu button
[task 2020-09-24T12:18:13.189Z] 12:18:13 INFO - Clicked the menu button
[task 2020-09-24T12:18:13.189Z] 12:18:13 INFO - TEST-PASS | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js | Got the browserAction button from the browser UI -
[task 2020-09-24T12:18:13.190Z] 12:18:13 INFO - Clicked on the browserAction button
[task 2020-09-24T12:18:13.190Z] 12:18:13 INFO - Clicking the frame list button
[task 2020-09-24T12:18:13.190Z] 12:18:13 INFO - TEST-PASS | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js | Has the expected number of frames -
[task 2020-09-24T12:18:13.190Z] 12:18:13 INFO - TEST-PASS | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js | Extension Popup frame found in the listed frames -
[task 2020-09-24T12:18:13.191Z] 12:18:13 INFO - Click on the extension popup frame and wait for navigate
[task 2020-09-24T12:18:13.191Z] 12:18:13 INFO - Execute myWebExtensionPopupAddonFunction()
[task 2020-09-24T12:18:13.191Z] 12:18:13 INFO - Wait for all pending requests to settle on the DevToolsClient
[task 2020-09-24T12:18:13.191Z] 12:18:13 INFO - Removing tab.
[task 2020-09-24T12:18:13.192Z] 12:18:13 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-09-24T12:18:13.192Z] 12:18:13 INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-09-24T12:18:13.192Z] 12:18:13 INFO - Tab removed and finished closing
[task 2020-09-24T12:18:13.192Z] 12:18:13 INFO - Console message: Webconsole context has changed
[task 2020-09-24T12:18:13.193Z] 12:18:13 INFO - Buffered messages logged at 12:16:47
[task 2020-09-24T12:18:13.193Z] 12:18:13 INFO - Console message: [JavaScript Error: "TypeError: can't access property "getInspectorSelection", this.hud is null" {file: "resource://devtools/client/webconsole/webconsole-ui.js" line: 722}]
[task 2020-09-24T12:18:13.193Z] 12:18:13 INFO - Buffered messages logged at 12:17:28
[task 2020-09-24T12:18:13.193Z] 12:18:13 INFO - Longer timeout required, waiting longer... Remaining timeouts: 1
[task 2020-09-24T12:18:13.194Z] 12:18:13 INFO - Buffered messages finished
[task 2020-09-24T12:18:13.194Z] 12:18:13 INFO - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js | Test timed out -
[task 2020-09-24T12:18:13.194Z] 12:18:13 INFO - Removing tab.
[task 2020-09-24T12:18:13.194Z] 12:18:13 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2020-09-24T12:18:13.194Z] 12:18:13 INFO - Got event: 'TabClose' on [object XULElement].
[task 2020-09-24T12:18:13.195Z] 12:18:13 INFO - Tab removed and finished closing
[task 2020-09-24T12:18:13.195Z] 12:18:13 INFO - GECKO(5232) | console.warn: "Error while detaching the worker target:" (new Error("Connection closed, pending request to server0.conn8.workerTarget29, type detach failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:274:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19\ndetach@resource://devtools/client/fronts/targets/worker.js:107:30\n_destroyTarget@resource://devtools/client/fronts/targets/target-mixin.js:684:22\ndestroy@resource://devtools/client/fronts/targets/target-mixin.js:629:12\n_emit@resource://devtools/shared/event-emitter.js:226:34\nemit@resource://devtools/shared/event-emitter.js:172:18\nemit@resource://devtools/shared/event-emitter.js:324:18\nonClosed@resource://devtools/client/devtools-client.js:606:10\nclose@resource://devtools/shared/transport/local-transport.js:169:20\nclose@resource://devtools/shared/transport/local-transport.js:165:13\nclose@resource://devtools/shared/transport/local-transport.js:165:13\ncleanup@resource://devtools/client/devtools-client.js:123:27\nclose/promise<@resource://devtools/client/devtools-client.js:139:7\nclose@resource://devtools/client/devtools-client.js:116:21\nclose@resource://devtools/client/aboutdebugging/src/modules/client-wrapper.js:165:24\ndisconnectRuntime/<@resource://devtools/client/aboutdebugging/src/actions/runtimes.js:232:27\nthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\nunwatchRuntime/<@resource://devtools/client/aboutdebugging/src/actions/runtimes.js:323:15\nthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\nbindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:520:12\ndestroy@resource://devtools/client/aboutdebugging/aboutdebugging.js:162:26\n@resource://devtools/client/aboutdebugging/aboutdebugging.js:199:20\n_endRemoveTab@chrome://browser/content/tabbrowser.js:3709:15\nremoveTab@chrome://browser/content/tabbrowser.js:3354:14\nremoveTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:427:12\ncloseTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:879:9\ncleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:306:11\nnextTest@chrome://mochikit/content/browser-test.js:555:35\nasynctimeoutFn@chrome://mochikit/content/browser-test.js:1195:18\nsetTimeout handlerSimpleTest_setTimeoutShim@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:907:41\ntimeoutFn@chrome://mochikit/content/browser-test.js:1163:52\nsetTimeout handlerTester_execTest@chrome://mochikit/content/browser-test.js:1142:80\nnextTest/<@chrome://mochikit/content/browser-test.js:932:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23\n", "resource://devtools/shared/protocol/Front.js", 98))
[task 2020-09-24T12:18:13.195Z] 12:18:13 INFO - GECKO(5232) | console.warn: "Error while detaching the worker target:" (new Error("Connection closed, pending request to server0.conn8.workerTarget30, type detach failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:274:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19\ndetach@resource://devtools/client/fronts/targets/worker.js:107:30\n_destroyTarget@resource://devtools/client/fronts/targets/target-mixin.js:684:22\ndestroy@resource://devtools/client/fronts/targets/target-mixin.js:629:12\n_emit@resource://devtools/shared/event-emitter.js:226:34\nemit@resource://devtools/shared/event-emitter.js:172:18\nemit@resource://devtools/shared/event-emitter.js:324:18\nonClosed@resource://devtools/client/devtools-client.js:606:10\nclose@resource://devtools/shared/transport/local-transport.js:169:20\nclose@resource://devtools/shared/transport/local-transport.js:165:13\nclose@resource://devtools/shared/transport/local-transport.js:165:13\ncleanup@resource://devtools/client/devtools-client.js:123:27\nclose/promise<@resource://devtools/client/devtools-client.js:139:7\nclose@resource://devtools/client/devtools-client.js:116:21\nclose@resource://devtools/client/aboutdebugging/src/modules/client-wrapper.js:165:24\ndisconnectRuntime/<@resource://devtools/client/aboutdebugging/src/actions/runtimes.js:232:27\nthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\nunwatchRuntime/<@resource://devtools/client/aboutdebugging/src/actions/runtimes.js:323:15\nthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\nbindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:520:12\ndestroy@resource://devtools/client/aboutdebugging/aboutdebugging.js:162:26\n@resource://devtools/client/aboutdebugging/aboutdebugging.js:199:20\n_endRemoveTab@chrome://browser/content/tabbrowser.js:3709:15\nremoveTab@chrome://browser/content/tabbrowser.js:3354:14\nremoveTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:427:12\ncloseTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:879:9\ncleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:306:11\nnextTest@chrome://mochikit/content/browser-test.js:555:35\nasynctimeoutFn@chrome://mochikit/content/browser-test.js:1195:18\nsetTimeout handlerSimpleTest_setTimeoutShim@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:907:41\ntimeoutFn@chrome://mochikit/content/browser-test.js:1163:52\nsetTimeout handlerTester_execTest@chrome://mochikit/content/browser-test.js:1142:80\nnextTest/<@chrome://mochikit/content/browser-test.js:932:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23\n", "resource://devtools/shared/protocol/Front.js", 98))
[task 2020-09-24T12:18:13.195Z] 12:18:13 INFO - GECKO(5232) | console.warn: "Error while detaching the worker target:" (new Error("Connection closed, pending request to server0.conn8.workerTarget31, type detach failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:274:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19\ndetach@resource://devtools/client/fronts/targets/worker.js:107:30\n_destroyTarget@resource://devtools/client/fronts/targets/target-mixin.js:684:22\ndestroy@resource://devtools/client/fronts/targets/target-mixin.js:629:12\n_emit@resource://devtools/shared/event-emitter.js:226:34\nemit@resource://devtools/shared/event-emitter.js:172:18\nemit@resource://devtools/shared/event-emitter.js:324:18\nonClosed@resource://devtools/client/devtools-client.js:606:10\nclose@resource://devtools/shared/transport/local-transport.js:169:20\nclose@resource://devtools/shared/transport/local-transport.js:165:13\nclose@resource://devtools/shared/transport/local-transport.js:165:13\ncleanup@resource://devtools/client/devtools-client.js:123:27\nclose/promise<@resource://devtools/client/devtools-client.js:139:7\nclose@resource://devtools/client/devtools-client.js:116:21\nclose@resource://devtools/client/aboutdebugging/src/modules/client-wrapper.js:165:24\ndisconnectRuntime/<@resource://devtools/client/aboutdebugging/src/actions/runtimes.js:232:27\nthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\nunwatchRuntime/<@resource://devtools/client/aboutdebugging/src/actions/runtimes.js:323:15\nthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\nbindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:520:12\ndestroy@resource://devtools/client/aboutdebugging/aboutdebugging.js:162:26\n@resource://devtools/client/aboutdebugging/aboutdebugging.js:199:20\n_endRemoveTab@chrome://browser/content/tabbrowser.js:3709:15\nremoveTab@chrome://browser/content/tabbrowser.js:3354:14\nremoveTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:427:12\ncloseTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:879:9\ncleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:306:11\nnextTest@chrome://mochikit/content/browser-test.js:555:35\nasynctimeoutFn@chrome://mochikit/content/browser-test.js:1195:18\nsetTimeout handlerSimpleTest_setTimeoutShim@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:907:41\ntimeoutFn@chrome://mochikit/content/browser-test.js:1163:52\nsetTimeout handlerTester_execTest@chrome://mochikit/content/browser-test.js:1142:80\nnextTest/<@chrome://mochikit/content/browser-test.js:932:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23\n", "resource://devtools/shared/protocol/Front.js", 98))
[task 2020-09-24T12:18:13.195Z] 12:18:13 INFO - GECKO(5232) | console.warn: "Error while detaching the worker target:" (new Error("Connection closed, pending request to server0.conn8.workerTarget32, type detach failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol/Front.js:274:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol/Front/FrontClassWithSpec.js:46:19\ndetach@resource://devtools/client/fronts/targets/worker.js:107:30\n_destroyTarget@resource://devtools/client/fronts/targets/target-mixin.js:684:22\ndestroy@resource://devtools/client/fronts/targets/target-mixin.js:629:12\n_emit@resource://devtools/shared/event-emitter.js:226:34\nemit@resource://devtools/shared/event-emitter.js:172:18\nemit@resource://devtools/shared/event-emitter.js:324:18\nonClosed@resource://devtools/client/devtools-client.js:606:10\nclose@resource://devtools/shared/transport/local-transport.js:169:20\nclose@resource://devtools/shared/transport/local-transport.js:165:13\nclose@resource://devtools/shared/transport/local-transport.js:165:13\ncleanup@resource://devtools/client/devtools-client.js:123:27\nclose/promise<@resource://devtools/client/devtools-client.js:139:7\nclose@resource://devtools/client/devtools-client.js:116:21\nclose@resource://devtools/client/aboutdebugging/src/modules/client-wrapper.js:165:24\ndisconnectRuntime/<@resource://devtools/client/aboutdebugging/src/actions/runtimes.js:232:27\nthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:681:28\nunwatchRuntime/<@resource://devtools/client/aboutdebugging/src/actions/runtimes.js:323:15\nthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\nbindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:520:12\ndestroy@resource://devtools/client/aboutdebugging/aboutdebugging.js:162:26\n@resource://devtools/client/aboutdebugging/aboutdebugging.js:199:20\n_endRemoveTab@chrome://browser/content/tabbrowser.js:3709:15\nremoveTab@chrome://browser/content/tabbrowser.js:3354:14\nremoveTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:427:12\ncloseTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:879:9\ncleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:306:11\nnextTest@chrome://mochikit/content/browser-test.js:555:35\nasynctimeoutFn@chrome://mochikit/content/browser-test.js:1195:18\nsetTimeout handlerSimpleTest_setTimeoutShim@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:907:41\ntimeoutFn@chrome://mochikit/content/browser-test.js:1163:52\nsetTimeout handlerTester_execTest@chrome://mochikit/content/browser-test.js:1142:80\nnextTest/<@chrome://mochikit/content/browser-test.js:932:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23\n", "resource://devtools/shared/protocol/Front.js", 98))
[task 2020-09-24T12:18:13.195Z] 12:18:13 INFO - TEST-PASS | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js | The main process DevToolsServer has no pending connection when the test ends -
[task 2020-09-24T12:18:13.195Z] 12:18:13 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-09-24T12:18:13.195Z] 12:18:13 INFO - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js | Cleanup function threw an exception - [Exception... "Component returned failure code: 0x80520015 (NS_ERROR_FILE_ACCESS_DENIED) [nsIFile.remove]" nsresult: "0x80520015 (NS_ERROR_FILE_ACCESS_DENIED)" location: "JS frame :: chrome://mochitests/content/browser/devtools/client/aboutdebugging/test/browser/helper-addons.js :: createTemporaryXPI/< :: line 102" data: no]
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js | A promise chain failed to handle a rejection: can't access property "getInspectorSelection", this.hud is null - stack: getSelectedNodeActorID@resource://devtools/client/webconsole/webconsole-ui.js:722:32
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - evaluateExpression/<@resource://devtools/client/webconsole/actions/input.js:107:41
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - asyncthunkWithOptions/</<@resource://devtools/client/shared/redux/middleware/thunk-with-options.js:16:9
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - ignore/</<@resource://devtools/client/shared/redux/middleware/ignore.js:31:12
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - createPerformanceMarkerMiddleware/</</<@resource://devtools/client/shared/redux/middleware/performance-marker.js:50:28
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - dispatchEvaluateExpression@resource://devtools/client/webconsole/webconsole-wrapper.js:284:11
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - toolboxTestScript@chrome://mochitests/content/browser/devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js:250:18
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - asynctestWebExtensionsToolboxWebConsole@chrome://mochitests/content/browser/devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js:146:20
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - AsyncTester_execTest/<@chrome://mochikit/content/browser-test.js:1069:34
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1109:11
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - nextTest/<@chrome://mochikit/content/browser-test.js:932:14
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1037:23
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - Rejection date: Thu Sep 24 2020 12:16:46 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 265
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - Stack trace:
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:265
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - chrome://mochikit/content/browser-test.js:nextTest:615
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1195
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - setTimeout handlerchrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest_setTimeoutShim:907
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1163
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1142
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:932
[task 2020-09-24T12:18:13.196Z] 12:18:13 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:1037
[task 2020-09-24T12:18:13.215Z] 12:18:13 INFO - GECKO(5232) | MEMORY STAT | vsize 2104398MB | vsizeMaxContiguous 65840513MB | residentFast 357MB | heapAllocated 106MB
[task 2020-09-24T12:18:13.215Z] 12:18:13 INFO - TEST-OK | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_debug_popup.js | took 90126ms
[task 2020-09-24T12:18:13.225Z] 12:18:13 INFO - checking window state
[task 2020-09-24T12:18:13.243Z] 12:18:13 INFO - TEST-START | devtools/client/aboutdebugging/test/browser/browser_aboutdebugging_addons_manifest_url.js

Comment 1

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

Any chance this is from bug 1663614? https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=Windows%2C10%2Cx64%2Copt%2CMochitests%2Ctest-windows10-64%2Fopt-mochitest-devtools-chrome-e10s%2Cdt5&tochange=cece3a83fe6dde98265ba12d5b1faa0f58a8e43b&fromchange=f2e684839464354718ff84e5e7abd5410f6b2c03&group_state=expanded&selectedTaskRun=UQrFUQjNQEqW7hbOqD3yxw.0.

Comment 2

[Intermittent Failures Robot]

19 failures in 656 pushes (0.029 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 10
• autoland: 6
• try: 3

Platform breakdown:

• linux1804-64-shippable: 2
• windows10-64-shippable: 3
• macosx1014-64: 5
• windows10-64: 4
• macosx1014-64-shippable: 4
• macosx1014-64-devedition: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1667084&startday=2020-09-25&endday=2020-09-25&tree=all

Comment 3

[Intermittent Failures Robot]

19 failures in 149 pushes (0.128 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 5
• try: 13
• autoland: 1

Platform breakdown:

• macosx1014-64: 1
• macosx1014-64-shippable: 8
• windows10-64-shippable: 3
• macosx1014-64-devedition: 3
• windows10-64-devedition: 3
• linux1804-64-asan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1667084&startday=2020-09-26&endday=2020-09-26&tree=all

Comment 4

[Natalia Csoregi [:nataliaCs]]

Update:

There have been 37 failures since the bug was created:

• 5 failures on Windows 10 x64 Shippable opt
• 12 failures on Windows 10 x64 debug
• 5 failures on OS X 10.14 Shippable opt
• 11 failures on OS X 10.14 debug
• 2 failures on Linux 18.04 x64 shippable opt
• 1 failure on Linux 18.04 x64 opt
• 1 failure on Linux 18.04 x64 asan opt

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=316852010&repo=autoland&lineNumber=2058

Comment 5

[Intermittent Failures Robot]

54 failures in 4019 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #13 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 20
• mozilla-central: 17
• try: 17

Platform breakdown:

• linux1804-64: 1
• windows10-64: 12
• macosx1014-64: 11
• linux1804-64-shippable: 2
• windows10-64-shippable: 8
• macosx1014-64-shippable: 12
• macosx1014-64-devedition: 4
• windows10-64-devedition: 3
• linux1804-64-asan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1667084&startday=2020-09-21&endday=2020-09-27&tree=all

Comment 6

[Intermittent Failures Robot]

18 failures in 856 pushes (0.021 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 7
• try: 8
• mozilla-central: 3

Platform breakdown:

• macosx1014-64: 6
• windows10-64: 3
• linux1804-64: 3
• windows10-64-shippable: 3
• macosx1014-64-shippable: 2
• linux1804-64-asan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1667084&startday=2020-09-30&endday=2020-09-30&tree=all

Comment 7

[Julian Descottes [:jdescottes]]

Looking at this.

Comment 8

[Julian Descottes [:jdescottes]]

Attachment: Bug 1667084 - [devtools] Mark WebConsoleUI::getFrameActor as synchronous

Side cleanup, the method getFrameActor is no longer async.
It makes the intermittent from this bug more frequent, even though this is not the definitive fix.

Comment 9

[Julian Descottes [:jdescottes]]

Attachment: Bug 1667084 - [devtools] Rewrite browser_aboutdebugging_addons_debug_popup.js to close toolbox after message evaluation

Depends on D92090

Comment 10

[Julian Descottes [:jdescottes]]

Attachment: Bug 1667084 - [devtools] Rewrite other browser_aboutdebugging_addons_debug tests

Depends on D92092

This is optional, but all those tests are still shaped based on their old implementation.
There is no need to separate part of the test in a testToolboxScript method.
The about:devtools-toolbox tab should also be clsoed with existing helpers to avoid intermittents

Comment 11

[Julian Descottes [:jdescottes]]

try at https://treeherder.mozilla.org/#/jobs?repo=try&revision=c06fe8b1cfe1fad8b56b36311cf14895c3759095

Comment 12

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/9603a52d90c3
[devtools] Mark WebConsoleUI::getFrameActor as synchronous r=nchevobbe
https://hg.mozilla.org/integration/autoland/rev/d4f892fc52cf
[devtools] Rewrite browser_aboutdebugging_addons_debug_popup.js to close toolbox after message evaluation r=daisuke
https://hg.mozilla.org/integration/autoland/rev/55b34e8080e5
[devtools] Rewrite other browser_aboutdebugging_addons_debug tests r=daisuke

Comment 13

[Andreea Pavel [:apavel]]

https://hg.mozilla.org/mozilla-central/rev/9603a52d90c3
https://hg.mozilla.org/mozilla-central/rev/d4f892fc52cf
https://hg.mozilla.org/mozilla-central/rev/55b34e8080e5

Comment 14

[Intermittent Failures Robot]

56 failures in 4195 pushes (0.013 failures/push) were associated with this bug in the last 7 days.

This is the #14 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 21
• mozilla-central: 14
• try: 21

Platform breakdown:

• windows10-64: 12
• macosx1014-64: 12
• linux1804-64-shippable: 3
• macosx1014-64-shippable: 7
• linux1804-64: 7
• windows10-64-devedition: 1
• macosx1014-64-devedition: 3
• windows10-64-shippable: 9
• linux1804-64-asan: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1667084&startday=2020-09-28&endday=2020-10-04&tree=all

Comment 15

[Julian Descottes [:jdescottes]]

Attachment: Bug 1667084 - [devtools] Add timeout in browser_aboutdebugging_addons_debug_popup.js

Comment 16

[Phabricator Automation]

Comment on attachment 9197135 [details]
Bug 1667084 - [devtools] Add timeout in browser_aboutdebugging_addons_debug_popup.js

Revision D101789 was moved to bug 1685470. Setting attachment 9197135 [details] to obsolete.