1527224 - Intermittent devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_multie10s.js | Test timed out -

Status: RESOLVED FIXED

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

Description

[Treeherder Bug Filer]

#[markdown(off)]
Filed by: ncsoregi [at] mozilla.com

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

https://queue.taskcluster.net/v1/task/F_3fEZGCRCmpsQGObA-5gg/runs/0/artifacts/public/logs/live_backing.log

[task 2019-02-12T04:07:33.403Z] 04:07:33 INFO - Buffered messages logged at 04:06:08
[task 2019-02-12T04:07:33.404Z] 04:07:33 INFO - Tab added and finished loading
[task 2019-02-12T04:07:33.405Z] 04:07:33 INFO - Forward service worker messages to the test
[task 2019-02-12T04:07:33.407Z] 04:07:33 INFO - Make the test page notify us when the service worker sends a message.
[task 2019-02-12T04:07:33.409Z] 04:07:33 INFO - Wait for the service worker to claim the test window before proceeding.
[task 2019-02-12T04:07:33.410Z] 04:07:33 INFO - Buffered messages logged at 04:06:09
[task 2019-02-12T04:07:33.411Z] 04:07:33 INFO - Wait until the service worker appears and is running
[task 2019-02-12T04:07:33.413Z] 04:07:33 INFO - Buffered messages finished
[task 2019-02-12T04:07:33.414Z] 04:07:33 INFO - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_multie10s.js | Test timed out -
[task 2019-02-12T04:07:33.415Z] 04:07:33 INFO - Removing tab.
[task 2019-02-12T04:07:33.417Z] 04:07:33 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-02-12T04:07:33.418Z] 04:07:33 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-02-12T04:07:33.419Z] 04:07:33 INFO - Tab removed and finished closing
[task 2019-02-12T04:07:33.420Z] 04:07:33 INFO - GECKO(1087) | [Parent 1087, Main Thread] WARNING: Content used context-fill-opacity when not in a context element: file /builds/worker/workspace/build/src/layout/svg/nsSVGUtils.cpp, line 1497
[task 2019-02-12T04:07:33.598Z] 04:07:33 INFO - Removing tab.
[task 2019-02-12T04:07:33.600Z] 04:07:33 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-02-12T04:07:33.658Z] 04:07:33 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-02-12T04:07:33.714Z] 04:07:33 INFO - Tab removed and finished closing
[task 2019-02-12T04:07:33.816Z] 04:07:33 INFO - GECKO(1087) | console.error: "[ACTION FAILED] REQUEST_TABS_FAILURE: Connection closed, pending request to root, type listTabs failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol.js:1386:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol.js:1510:19\nlistTabs@resource://devtools/shared/fronts/root.js:189:44\nasync*listTabs@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:123:33\nasync*requestTabs/<@resource://devtools/client/aboutdebugging-new/src/actions/debug-targets.js:149:40\nasync*thunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:755:18\nonTabsUpdated@resource://devtools/client/aboutdebugging-new/src/middleware/debug-target-listener.js:19:11\nemit@resource://devtools/shared/event-emitter.js:186:24\nemit@resource://devtools/shared/event-emitter.js:267:18\nonPacket@resource://devtools/shared/protocol.js:1424:13\nonPacket@resource://devtools/shared/client/debugger-client.js:770:13\nsend/<@resource://devtools/shared/transport/local-transport.js:64:23\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\nDevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:57:21\nsend@resource://devtools/shared/transport/local-transport.js:58:21\nsend@resource://devtools/server/main.js:1011:20\nonTabListChanged@resource://devtools/server/actors/root.js:396:15\nBrowserTabList.prototype._notifyListChanged@resource://devtools/server/actors/webbrowser.js:397:10\nBrowserTabList.prototype.handleEvent<@resource://devtools/server/actors/webbrowser.js:585:12\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\naddTab@chrome://browser/content/tabbrowser.js:2583:7\naddTab@resource://testing-common/BrowserTestUtils.jsm:1940:23\naddTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:162:32\nasync*testDebuggingSW@chrome://mochitests/content/browser/devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_multie10s.js:45:23\nasync*@chrome://mochitests/content/browser/devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_multie10s.js:22:9\nAsync*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1106:34\nasync*Tester_execTest@chrome://mochikit/content/browser-test.js:1134:12\nnextTest/<@chrome://mochikit/content/browser-test.js:995:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59\n"
[task 2019-02-12T04:07:33.818Z] 04:07:33 INFO - GECKO(1087) | console.error: "destroy@resource://devtools/shared/protocol.js:1299:23\ndestroy@resource://devtools/shared/protocol.js:946:17\ncleanup@resource://devtools/shared/protocol.js:960:10\nonClosed@resource://devtools/shared/client/debugger-client.js:917:12\nclose@resource://devtools/shared/transport/local-transport.js:154:20\nclose@resource://devtools/shared/transport/local-transport.js:150:13\nclose@resource://devtools/shared/transport/local-transport.js:150:13\ncleanup@resource://devtools/shared/client/debugger-client.js:300:25\ndetachClients@resource://devtools/shared/client/debugger-client.js:325:9\nclose@resource://devtools/shared/client/debugger-client.js:339:5\nclose@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:151:24\nasync*disconnectRuntime/<@resource://devtools/client/aboutdebugging-new/src/actions/runtimes.js:161:27\nasync*thunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:755:18\nunwatchRuntime/<@resource://devtools/client/aboutdebugging-new/src/actions/runtimes.js:244:15\nasync*thunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\nbindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:644:12\ndestroy@resource://devtools/client/aboutdebugging-new/aboutdebugging.js:117:26\nasync*@resource://devtools/client/aboutdebugging-new/aboutdebugging.js:147:18\n_endRemoveTab@chrome://browser/content/tabbrowser.js:3178:13\nremoveTab@chrome://browser/content/tabbrowser.js:2876:12\nremoveTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:191:12\nasync*closeTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:455:9\nasync*cleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:136:11\nasync*nextTest@chrome://mochikit/content/browser-test.js:705:35\nasync*timeoutFn@chrome://mochikit/content/browser-test.js:1202:14\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1164:45\nnextTest/<@chrome://mochikit/content/browser-test.js:995:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59\n"
[task 2019-02-12T04:07:33.820Z] 04:07:33 INFO - GECKO(1087) | console.error: "[ACTION FAILED] REQUEST_TABS_FAILURE: Connection closed, pending request to root, type listTabs failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol.js:1386:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol.js:1510:19\nlistTabs@resource://devtools/shared/fronts/root.js:189:44\nasync*listTabs@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:123:33\nasync*requestTabs/<@resource://devtools/client/aboutdebugging-new/src/actions/debug-targets.js:149:40\nasync*thunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:755:18\nonTabsUpdated@resource://devtools/client/aboutdebugging-new/src/middleware/debug-target-listener.js:19:11\nemit@resource://devtools/shared/event-emitter.js:186:24\nemit@resource://devtools/shared/event-emitter.js:267:18\nonPacket@resource://devtools/shared/protocol.js:1424:13\nonPacket@resource://devtools/shared/client/debugger-client.js:770:13\nsend/<@resource://devtools/shared/transport/local-transport.js:64:23\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\nDevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:57:21\nsend@resource://devtools/shared/transport/local-transport.js:58:21\nsend@resource://devtools/server/main.js:1011:20\nonTabListChanged@resource://devtools/server/actors/root.js:396:15\nBrowserTabList.prototype._notifyListChanged@resource://devtools/server/actors/webbrowser.js:397:10\nBrowserTabList.prototype._onDOMTitleChanged<@resource://devtools/server/actors/webbrowser.js:567:12\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\nBrowserTabList.prototype.receiveMessage<@resource://devtools/server/actors/webbrowser.js:554:14\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\nMessageListener.receiveMessage*BrowserTabList.prototype._listenForMessagesIf@resource://devtools/server/actors/webbrowser.js:525:33\nBrowserTabList.prototype._checkListening@resource://devtools/server/actors/webbrowser.js:464:8\nBrowserTabList.prototype.getList@resource://devtools/server/actors/webbrowser.js:291:8\nonListTabs@resource://devtools/server/actors/root.js:301:40\nAsync*onPacket@resource://devtools/server/main.js:1279:58\nsend/<@resource://devtools/shared/transport/local-transport.js:64:23\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\nDevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:57:21\nsend@resource://devtools/shared/transport/local-transport.js:58:21\nsend@resource://devtools/shared/protocol.js:1370:30\nrequest@resource://devtools/shared/protocol.js:1388:10\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol.js:1510:19\nlistTabs@resource://devtools/shared/fronts/root.js:189:44\nasync*listTabs@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:123:33\nasync*requestTabs/<@resource://devtools/client/aboutdebugging-new/src/actions/debug-targets.js:149:40\n"
[task 2019-02-12T04:07:33.825Z] 04:07:33 INFO - GECKO(1087) | console.error: "destroy@resource://devtools/shared/protocol.js:1299:23\ndestroy@resource://devtools/shared/protocol.js:946:17\ncleanup@resource://devtools/shared/protocol.js:960:10\nonClosed@resource://devtools/shared/client/debugger-client.js:917:12\nclose@resource://devtools/shared/transport/local-transport.js:154:20\nclose@resource://devtools/shared/transport/local-transport.js:150:13\nclose@resource://devtools/shared/transport/local-transport.js:150:13\ncleanup@resource://devtools/shared/client/debugger-client.js:300:25\ndetachClients@resource://devtools/shared/client/debugger-client.js:325:9\nclose@resource://devtools/shared/client/debugger-client.js:339:5\nclose@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:151:24\nasync*disconnectRuntime/<@resource://devtools/client/aboutdebugging-new/src/actions/runtimes.js:161:27\nasync*thunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:755:18\nunwatchRuntime/<@resource://devtools/client/aboutdebugging-new/src/actions/runtimes.js:244:15\nasync*thunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\nbindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:644:12\ndestroy@resource://devtools/client/aboutdebugging-new/aboutdebugging.js:117:26\nasync*@resource://devtools/client/aboutdebugging-new/aboutdebugging.js:147:18\n_endRemoveTab@chrome://browser/content/tabbrowser.js:3178:13\nremoveTab@chrome://browser/content/tabbrowser.js:2876:12\nremoveTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:191:12\nasync*closeTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:455:9\nasync*cleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:136:11\nasync*nextTest@chrome://mochikit/content/browser-test.js:705:35\nasync*timeoutFn@chrome://mochikit/content/browser-test.js:1202:14\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1164:45\nnextTest/<@chrome://mochikit/content/browser-test.js:995:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59\n"
[task 2019-02-12T04:07:33.830Z] 04:07:33 INFO - GECKO(1087) | console.error: "[ACTION FAILED] REQUEST_TABS_FAILURE: Connection closed, pending request to root, type listTabs failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol.js:1386:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol.js:1510:19\nlistTabs@resource://devtools/shared/fronts/root.js:189:44\nasync*listTabs@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:123:33\nasync*requestTabs/<@resource://devtools/client/aboutdebugging-new/src/actions/debug-targets.js:149:40\nasync*thunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:755:18\nonTabsUpdated@resource://devtools/client/aboutdebugging-new/src/middleware/debug-target-listener.js:19:11\nemit@resource://devtools/shared/event-emitter.js:186:24\nemit@resource://devtools/shared/event-emitter.js:267:18\nonPacket@resource://devtools/shared/protocol.js:1424:13\nonPacket@resource://devtools/shared/client/debugger-client.js:770:13\nsend/<@resource://devtools/shared/transport/local-transport.js:64:23\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\nDevToolsUtils.executeSoon*exports.executeSoon@resource://devtools/shared/DevToolsUtils.js:57:21\nsend@resource://devtools/shared/transport/local-transport.js:58:21\nsend@resource://devtools/server/main.js:1011:20\nonTabListChanged@resource://devtools/server/actors/root.js:396:15\nBrowserTabList.prototype._notifyListChanged@resource://devtools/server/actors/webbrowser.js:397:10\nBrowserTabList.prototype.handleEvent<@resource://devtools/server/actors/webbrowser.js:585:12\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\nupdateCurrentBrowser@chrome://browser/content/tabbrowser.js:1047:14\n_setupEventListeners/<@chrome://browser/content/tabbrowser.js:4569:14\nset selectedIndex@chrome://global/content/elements/tabbox.js:202:12\nset selectedPanel@chrome://global/content/elements/tabbox.js:216:5\nset_selectedIndex@chrome://global/content/bindings/tabbox.xml:176:15\nset_selectedItem@chrome://global/content/bindings/tabbox.xml:201:39\nset selectedTab@chrome://global/content/elements/tabbox.js:81:9\nset selectedTab@chrome://browser/content/tabbrowser.js:266:5\n_blurTab@chrome://browser/content/tabbrowser.js:3244:29\n_beginRemoveTab@chrome://browser/content/tabbrowser.js:2944:10\nremoveTab@chrome://browser/content/tabbrowser.js:2852:15\nremoveTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:191:12\nasync*closeTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:455:9\nasync*cleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:136:11\nasync*nextTest@chrome://mochikit/content/browser-test.js:705:35\nasync*timeoutFn@chrome://mochikit/content/browser-test.js:1202:14\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1164:45\nnextTest/<@chrome://mochikit/content/browser-test.js:995:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59\n"
[task 2019-02-12T04:07:33.834Z] 04:07:33 INFO - GECKO(1087) | console.error: "destroy@resource://devtools/shared/protocol.js:1299:23\ndestroy@resource://devtools/shared/protocol.js:946:17\ncleanup@resource://devtools/shared/protocol.js:960:10\nonClosed@resource://devtools/shared/client/debugger-client.js:917:12\nclose@resource://devtools/shared/transport/local-transport.js:154:20\nclose@resource://devtools/shared/transport/local-transport.js:150:13\nclose@resource://devtools/shared/transport/local-transport.js:150:13\ncleanup@resource://devtools/shared/client/debugger-client.js:300:25\ndetachClients@resource://devtools/shared/client/debugger-client.js:325:9\nclose@resource://devtools/shared/client/debugger-client.js:339:5\nclose@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:151:24\nasync*disconnectRuntime/<@resource://devtools/client/aboutdebugging-new/src/actions/runtimes.js:161:27\nasync*thunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:755:18\nunwatchRuntime/<@resource://devtools/client/aboutdebugging-new/src/actions/runtimes.js:244:15\nasync*thunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\nbindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:644:12\ndestroy@resource://devtools/client/aboutdebugging-new/aboutdebugging.js:117:26\nasync*@resource://devtools/client/aboutdebugging-new/aboutdebugging.js:147:18\n_endRemoveTab@chrome://browser/content/tabbrowser.js:3178:13\nremoveTab@chrome://browser/content/tabbrowser.js:2876:12\nremoveTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:191:12\nasync*closeTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:455:9\nasync*cleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:136:11\nasync*nextTest@chrome://mochikit/content/browser-test.js:705:35\nasync*timeoutFn@chrome://mochikit/content/browser-test.js:1202:14\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1164:45\nnextTest/<@chrome://mochikit/content/browser-test.js:995:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59\n"
[task 2019-02-12T04:07:33.972Z] 04:07:33 INFO - GECKO(1087) | MEMORY STAT | vsize 2408MB | residentFast 477MB | heapAllocated 140MB
[task 2019-02-12T04:07:33.972Z] 04:07:33 INFO - TEST-OK | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_multie10s.js | took 91487ms

Comment 1

[Cristina Coroiu [:ccoroiu]]

Started retriggers on this range:
https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=linux%2Cx64%2Cdebug%2Cmochitests%2Cwith%2Ce10s%2Ctest-linux64%2Fdebug-mochitest-devtools-chrome-e10s-4%2Cm-e10s%28dt4%29&tochange=519b52f60bb95a1568641ccd4fcf9b409dcd45e8&fromchange=944a5d9d7f54085c053ef96d3ba15338545ceae9&group_state=expanded&selectedJob=228574019

Comment 2

[Intermittent Failures Robot]

23 failures in 588 pushes (0.039 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 9
• mozilla-central: 9
• mozilla-inbound: 5

Platform breakdown:

• osx-10-10: 5
• linux64-ccov: 2
• windows10-64: 1
• windows7-32: 1
• windows10-64-ccov: 4
• linux64: 10

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1527224&startday=2019-02-15&endday=2019-02-15&tree=all

Comment 3

[Intermittent Failures Robot]

64 failures in 2832 pushes (0.023 failures/push) were associated with this bug in the last 7 days.

This is the #20 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: 24
• mozilla-inbound: 11
• try: 5
• mozilla-central: 24

Platform breakdown:

• linux64: 33
• windows7-32: 1
• linux64-ccov: 9
• windows10-64: 2
• osx-10-10: 11
• windows10-64-ccov: 8

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1527224&startday=2019-02-11&endday=2019-02-17&tree=all

Comment 4

[Noemi Erli[:noemi_erli]]

There are 57 failures in the past week: windows10-64-ccov debug, osx-10-10 debug, linux64-qr opt, linux64-ccov and linux64 debug

Recent log failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=230047077&repo=mozilla-central&lineNumber=3671

Relevant part of the log:
06:00:45 INFO - Select This Firefox page
06:00:45 INFO - Buffered messages logged at 05:59:17
06:00:45 INFO - Wait for requests to be complete
06:00:45 INFO - Buffered messages logged at 05:59:21
06:00:45 INFO - Wait for runtime page to be rendered
06:00:45 INFO - Disabling multi e10s
06:00:45 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/aboutdebugging-new/test/browser/resources/service-workers/push-sw.html
06:00:45 INFO - Buffered messages logged at 05:59:26
06:00:45 INFO - Tab added and finished loading
06:00:45 INFO - Forward service worker messages to the test
06:00:45 INFO - Make the test page notify us when the service worker sends a message.
06:00:45 INFO - Wait for the service worker to claim the test window before proceeding.
06:00:45 INFO - Buffered messages logged at 05:59:27
06:00:45 INFO - Wait until the service worker appears and is running
06:00:45 INFO - Buffered messages finished
06:00:45 INFO - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_multie10s.js | Test timed out -
06:00:45 INFO - Removing tab.
06:00:45 INFO - Waiting for event: 'TabClose' on [object XULElement].
06:00:45 INFO - Got event: 'TabClose' on [object XULElement].
06:00:45 INFO - Tab removed and finished closing
06:00:45 INFO - Removing tab.
06:00:45 INFO - Waiting for event: 'TabClose' on [object XULElement].
06:00:45 INFO - Got event: 'TabClose' on [object XULElement].
06:00:45 INFO - Tab removed and finished closing
06:00:45 INFO - GECKO(1496) | console.error: "[ACTION FAILED] REQUEST_TABS_FAILURE: Connection closed, pending request to root, type listTabs failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol.js:1386:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol.js:1510:19\nlistTabs@resource://devtools/shared/fronts/root.js:189:44\nasynclistTabs@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:131:33\nasyncrequestTabs/<@resource://devtools/client/aboutdebugging-new/src/actions/debug-targets.js:149:40\nasyncthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:755:18\nonTabsUpdated@resource://devtools/client/aboutdebugging-new/src/middleware/debug-target-listener.js:19:11\nemit@resource://devtools/shared/event-emitter.js:186:24\nemit@resource://devtools/shared/event-emitter.js:267:18\nonPacket@resource://devtools/shared/protocol.js:1424:13\nonPacket@resource://devtools/shared/client/debugger-client.js:633:13\nsend/<@resource://devtools/shared/transport/local-transport.js:64:23\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\nDevToolsUtils.executeSoonexports.executeSoon@resource://devtools/shared/DevToolsUtils.js:57:21\nsend@resource://devtools/shared/transport/local-transport.js:58:21\nsend@resource://devtools/server/main.js:1011:20\nonTabListChanged@resource://devtools/server/actors/root.js:396:15\nBrowserTabList.prototype._notifyListChanged@resource://devtools/server/actors/webbrowser.js:397:10\nBrowserTabList.prototype.handleEvent<@resource://devtools/server/actors/webbrowser.js:585:12\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\naddTab@chrome://browser/content/tabbrowser.js:2583:7\naddTab@resource://testing-common/BrowserTestUtils.jsm:1940:23\naddTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:162:32\nasynctestDebuggingSW@chrome://mochitests/content/browser/devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_multie10s.js:47:23\nasync@chrome://mochitests/content/browser/devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_multie10s.js:22:9\nAsyncTester_execTest/<@chrome://mochikit/content/browser-test.js:1106:34\nasyncTester_execTest@chrome://mochikit/content/browser-test.js:1134:12\nnextTest/<@chrome://mochikit/content/browser-test.js:995:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59\n"
06:00:45 INFO - GECKO(1496) | console.error: "destroy@resource://devtools/shared/protocol.js:1299:23\ndestroy@resource://devtools/shared/protocol.js:946:17\ncleanup@resource://devtools/shared/protocol.js:960:10\nonClosed@resource://devtools/shared/client/debugger-client.js:780:12\nclose@resource://devtools/shared/transport/local-transport.js:154:20\nclose@resource://devtools/shared/transport/local-transport.js:150:13\nclose@resource://devtools/shared/transport/local-transport.js:150:13\ncleanup@resource://devtools/shared/client/debugger-client.js:212:25\ndetachClients@resource://devtools/shared/client/debugger-client.js:237:9\nclose@resource://devtools/shared/client/debugger-client.js:246:5\nclose@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:159:24\nasyncdisconnectRuntime/<@resource://devtools/client/aboutdebugging-new/src/actions/runtimes.js:166:27\nasyncthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:755:18\nunwatchRuntime/<@resource://devtools/client/aboutdebugging-new/src/actions/runtimes.js:275:15\nasyncthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\nbindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:644:12\ndestroy@resource://devtools/client/aboutdebugging-new/aboutdebugging.js:117:26\nasync@resource://devtools/client/aboutdebugging-new/aboutdebugging.js:147:18\n_endRemoveTab@chrome://browser/content/tabbrowser.js:3182:13\nremoveTab@chrome://browser/content/tabbrowser.js:2880:12\nremoveTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:191:12\nasynccloseTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:457:9\nasynccleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:136:11\nasyncnextTest@chrome://mochikit/content/browser-test.js:705:35\nasynctimeoutFn@chrome://mochikit/content/browser-test.js:1202:14\nsetTimeout handlerTester_execTest@chrome://mochikit/content/browser-test.js:1164:45\nnextTest/<@chrome://mochikit/content/browser-test.js:995:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59\n"
06:00:45 INFO - GECKO(1496) | console.error: "[ACTION FAILED] REQUEST_TABS_FAILURE: Connection closed, pending request to root, type listTabs failed\n\nRequest stack:\nrequest@resource://devtools/shared/protocol.js:1386:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol.js:1510:19\nlistTabs@resource://devtools/shared/fronts/root.js:189:44\nasynclistTabs@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:131:33\nasyncrequestTabs/<@resource://devtools/client/aboutdebugging-new/src/actions/debug-targets.js:149:40\nasyncthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:755:18\nonTabsUpdated@resource://devtools/client/aboutdebugging-new/src/middleware/debug-target-listener.js:19:11\nemit@resource://devtools/shared/event-emitter.js:186:24\nemit@resource://devtools/shared/event-emitter.js:267:18\nonPacket@resource://devtools/shared/protocol.js:1424:13\nonPacket@resource://devtools/shared/client/debugger-client.js:633:13\nsend/<@resource://devtools/shared/transport/local-transport.js:64:23\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\nDevToolsUtils.executeSoonexports.executeSoon@resource://devtools/shared/DevToolsUtils.js:57:21\nsend@resource://devtools/shared/transport/local-transport.js:58:21\nsend@resource://devtools/server/main.js:1011:20\nonTabListChanged@resource://devtools/server/actors/root.js:396:15\nBrowserTabList.prototype._notifyListChanged@resource://devtools/server/actors/webbrowser.js:397:10\nBrowserTabList.prototype._onDOMTitleChanged<@resource://devtools/server/actors/webbrowser.js:567:12\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\nBrowserTabList.prototype.receiveMessage<@resource://devtools/server/actors/webbrowser.js:554:14\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\nMessageListener.receiveMessageBrowserTabList.prototype._listenForMessagesIf@resource://devtools/server/actors/webbrowser.js:525:33\nBrowserTabList.prototype._checkListening@resource://devtools/server/actors/webbrowser.js:464:8\nBrowserTabList.prototype.getList@resource://devtools/server/actors/webbrowser.js:291:8\nonListTabs@resource://devtools/server/actors/root.js:301:40\nAsynconPacket@resource://devtools/server/main.js:1279:58\nsend/<@resource://devtools/shared/transport/local-transport.js:64:23\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:22\nDevToolsUtils.executeSoonexports.executeSoon@resource://devtools/shared/DevToolsUtils.js:57:21\nsend@resource://devtools/shared/transport/local-transport.js:58:21\nsend@resource://devtools/shared/protocol.js:1370:30\nrequest@resource://devtools/shared/protocol.js:1388:10\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol.js:1510:19\nlistTabs@resource://devtools/shared/fronts/root.js:189:44\nasynclistTabs@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:131:33\nasyncrequestTabs/<@resource://devtools/client/aboutdebugging-new/src/actions/debug-targets.js:149:40\n"
06:00:45 INFO - GECKO(1496) | console.error: "destroy@resource://devtools/shared/protocol.js:1299:23\ndestroy@resource://devtools/shared/protocol.js:946:17\ncleanup@resource://devtools/shared/protocol.js:960:10\nonClosed@resource://devtools/shared/client/debugger-client.js:780:12\nclose@resource://devtools/shared/transport/local-transport.js:154:20\nclose@resource://devtools/shared/transport/local-transport.js:150:13\nclose@resource://devtools/shared/transport/local-transport.js:150:13\ncleanup@resource://devtools/shared/client/debugger-client.js:212:25\ndetachClients@resource://devtools/shared/client/debugger-client.js:237:9\nclose@resource://devtools/shared/client/debugger-client.js:246:5\nclose@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:159:24\nasyncdisconnectRuntime/<@resource://devtools/client/aboutdebugging-new/src/actions/runtimes.js:166:27\nasyncthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:755:18\nunwatchRuntime/<@resource://devtools/client/aboutdebugging-new/src/actions/runtimes.js:275:15\nasyncthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\nbindActionCreator/<@resource://devtools/client/shared/vendor/redux.js:644:12\ndestroy@resource://devtools/client/aboutdebugging-new/aboutdebugging.js:117:26\nasync@resource://devtools/client/aboutdebugging-new/aboutdebugging.js:147:18\n_endRemoveTab@chrome://browser/content/tabbrowser.js:3182:13\nremoveTab@chrome://browser/content/tabbrowser.js:2880:12\nremoveTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:191:12\nasynccloseTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:457:9\nasynccleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:136:11\nasyncnextTest@chrome://mochikit/content/browser-test.js:705:35\nasynctimeoutFn@chrome://mochikit/content/browser-test.js:1202:14\nsetTimeout handler*Tester_execTest@chrome://mochikit/content/browser-test.js:1164:45\nnextTest/<@chrome://mochikit/content/browser-test.js:995:14\nSimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:803:59\n"
06:00:45 INFO - GECKO(1496) | MEMORY STAT | vsize 2103924MB | vsizeMaxContiguous 65045102MB | residentFast 559MB | heapAllocated 140MB
06:00:45 INFO - TEST-OK | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_multie10s.js | took 90834ms
06:00:45 INFO - GECKO(1496) | DLL blocklist was unable to intercept AppInit DLLs.
06:00:45 INFO - GECKO(1496) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to c:\users\task_1550900258\appdata\local\temp\tmpaojz8p.mozrunner\runtests_leaks_tab_pid10368.log
06:00:45 INFO - checking window state

:jdescottes can you please take a look at this?

Comment 5

[Intermittent Failures Robot]

46 failures in 2602 pushes (0.018 failures/push) were associated with this bug in the last 7 days.

This is the #27 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: 17
• mozilla-central: 15
• mozilla-inbound: 4
• try: 10

Platform breakdown:

• linux64: 18
• windows10-64-ccov: 10
• linux64-ccov: 5
• windows10-64: 6
• osx-10-10: 5
• linux64-qr: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1527224&startday=2019-02-18&endday=2019-02-24&tree=all

Comment 6

[Julian Descottes [:jdescottes]]

This seems to have spiked just after we landed the test browser_aboutdebugging_serviceworker_fetch_flag.js which runs right before this one. I did a few try pushes to try and understand what was causing this, but I didn't manage to get a single failure yet. Will try again with linux debug.

Since the failures are mostly on debug builds, if we can't find anything, we may skip it on debug for now to reduce the noise.

Comment 7

[Intermittent Failures Robot]

21 failures in 504 pushes (0.042 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 10
• autoland: 11

Platform breakdown:

• linux64-ccov: 4
• windows10-64-ccov: 5
• linux64: 9
• osx-10-10: 2
• windows10-64: 1

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

Comment 8

[Alexandre Poirot [:ochameau]]

bug 1517210 may make this slightly more intermittent.
It may be related to unexpected GC behavior where the GC isn't being triggered frequently enough and leads to OOM crashes.

Comment 9

[Intermittent Failures Robot]

18 failures in 504 pushes (0.036 failures/push) were associated with this bug yesterday.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 6
• mozilla-central: 6
• try: 1
• mozilla-inbound: 5

Platform breakdown:

• osx-10-10: 4
• linux64: 8
• windows10-64-ccov: 4
• windows10-64: 1
• linux64-ccov: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1527224&startday=2019-02-28&endday=2019-02-28&tree=all

Comment 10

[Julian Descottes [:jdescottes]]

Attachment: Bug 1527224 - Skip browser_aboutdebugging_serviceworker_multie10s.js on debug || asan;r=ladybenko

Fails primarily on asan and debug. Have sent try pushes to try fo force GC to see if it helps.
Main worry is that if this is GC related, skipping the test might just move the intermittent to the next test.
Try pushes
base: https://treeherder.mozilla.org/#/jobs?repo=try&revision=153e1a9cae6afe923d706126755db9871b195cdf
skip(this patch): https://treeherder.mozilla.org/#/jobs?repo=try&revision=be3cfc2d597195ccc60cec4b35796a812b7f91f7
forceGC: https://treeherder.mozilla.org/#/jobs?repo=try&revision=d9ba430399b00bc2134b3af89b35034fe6e9fa9d
force + shrinkGC: https://treeherder.mozilla.org/#/jobs?repo=try&revision=9f184f1063749dcaf8f3fe6a8654404f3f649eec

Comment 11

[Julian Descottes [:jdescottes]]

skip(this patch): https://treeherder.mozilla.org/#/jobs?repo=try&revision=be3cfc2d597195ccc60cec4b35796a812b7f91f7

Retriggered the wrong job here, because the skip-if modified the chunks. The dt3 failures in this push are not relevant. The test is running in dt7 here.

Edit: Retriggers on dt7 are finished, no failure in the aboutdebugging-new tests. I think it's safe to move forward with the patch attached here. The GC patches didn't fix the intermittent (one of them might have reduced the frequency, but needs more retriggers)

Comment 12

[Intermittent Failures Robot]

43 failures in 608 pushes (0.071 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 30
• mozilla-inbound: 4
• try: 4
• mozilla-central: 5

Platform breakdown:

• osx-10-10: 12
• windows10-64: 3
• linux64: 24
• linux64-ccov: 2
• windows10-64-ccov: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1527224&startday=2019-03-01&endday=2019-03-01&tree=all

Comment 13

[Intermittent Failures Robot]

122 failures in 2981 pushes (0.041 failures/push) were associated with this bug in the last 7 days.

This is the #8 most frequent failure this week.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• mozilla-central: 33
• autoland: 65
• mozilla-inbound: 18
• try: 6

Platform breakdown:

• linux64-ccov: 9
• windows10-64-ccov: 14
• linux64: 63
• osx-10-10: 23
• windows10-64: 12
• windows7-32: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1527224&startday=2019-02-25&endday=2019-03-03&tree=all

Comment 14

[Intermittent Failures Robot]

23 failures in 394 pushes (0.058 failures/push) were associated with this bug yesterday.

** This test has failed more than 150 times in the last 21 days. It should be disabled until it can be fixed. **

Repository breakdown:

• autoland: 18
• mozilla-central: 3
• mozilla-inbound: 2

Platform breakdown:

• osx-10-10: 2
• linux64: 11
• windows7-32: 1
• linux64-ccov: 1
• windows10-64-ccov: 2
• windows10-64: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1527224&startday=2019-03-04&endday=2019-03-04&tree=all

Comment 15

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/bf56d6419bc4
Skip browser_aboutdebugging_serviceworker_multie10s.js on debug || asan;r=ladybenko

Comment 16

[Stefan Hindli [:stefan_hindli]]

https://hg.mozilla.org/mozilla-central/rev/bf56d6419bc4

Comment 17

[Intermittent Failures Robot]

28 failures in 522 pushes (0.054 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-inbound: 5
• mozilla-central: 9
• autoland: 12
• try: 2

Platform breakdown:

• osx-10-10: 7
• linux64: 13
• windows10-64-ccov: 4
• windows10-64: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1527224&startday=2019-03-05&endday=2019-03-05&tree=all

Comment 18

[Intermittent Failures Robot]

52 failures in 2818 pushes (0.018 failures/push) were associated with this bug in the last 7 days.

This is the #34 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: 30
• mozilla-central: 12
• mozilla-inbound: 7
• try: 3

Platform breakdown:

• osx-10-10: 9
• linux64: 25
• windows7-32: 1
• windows10-64-ccov: 6
• linux64-ccov: 1
• windows10-64: 10

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