1521887 - Very high frequency Mochitest serviceworker devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_runtime-page.js | Service worker list is the one we registered -

Status: RESOLVED FIXED

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

Description

[Treeherder Bug Filer]

Filed by: rgurzau [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=223314689&repo=mozilla-central

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

[task 2019-01-22T17:25:28.868Z] 17:25:28 INFO - Register a service worker
[task 2019-01-22T17:25:28.869Z] 17:25:28 INFO - Adding a new tab with URL: http://example.com/browser/devtools/client/aboutdebugging-new/test/browser/resources/service-workers/push-sw.html
[task 2019-01-22T17:25:28.870Z] 17:25:28 INFO - Tab added and finished loading
[task 2019-01-22T17:25:28.871Z] 17:25:28 INFO - Wait for sw to appear in the debug pane list
[task 2019-01-22T17:25:28.872Z] 17:25:28 INFO - Buffered messages logged at 17:25:28
[task 2019-01-22T17:25:28.872Z] 17:25:28 INFO - TEST-PASS | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_runtime-page.js | Service worker list has one element -
[task 2019-01-22T17:25:28.873Z] 17:25:28 INFO - Buffered messages finished
[task 2019-01-22T17:25:28.874Z] 17:25:28 INFO - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_runtime-page.js | Service worker list is the one we registered -
[task 2019-01-22T17:25:28.875Z] 17:25:28 INFO - Stack trace:
[task 2019-01-22T17:25:28.876Z] 17:25:28 INFO - chrome://mochikit/content/browser-test.js:test_ok:1307
[task 2019-01-22T17:25:28.877Z] 17:25:28 INFO - chrome://mochitests/content/browser/devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_runtime-page.js:null:47
[task 2019-01-22T17:25:28.878Z] 17:25:28 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest/<:1108
[task 2019-01-22T17:25:28.879Z] 17:25:28 INFO - chrome://mochikit/content/browser-test.js:Tester_execTest:1099
[task 2019-01-22T17:25:28.879Z] 17:25:28 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:997
[task 2019-01-22T17:25:28.881Z] 17:25:28 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:803
[task 2019-01-22T17:25:28.882Z] 17:25:28 INFO - Unregister service worker
[task 2019-01-22T17:25:28.883Z] 17:25:28 INFO - GECKO(1080) | Push service worker registered
[task 2019-01-22T17:25:28.884Z] 17:25:28 INFO - TEST-PASS | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_runtime-page.js | getRegistration is a valid function - "function" == "function" -
[task 2019-01-22T17:25:28.885Z] 17:25:28 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-01-22T17:25:28.886Z] 17:25:28 INFO - TEST-UNEXPECTED-FAIL | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_runtime-page.js | Uncaught exception - TypeError: win.getRegistration(...) is undefined
[task 2019-01-22T17:25:28.887Z] 17:25:28 INFO - Leaving test bound
[task 2019-01-22T17:25:28.888Z] 17:25:28 INFO - Removing tab.
[task 2019-01-22T17:25:28.889Z] 17:25:28 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-01-22T17:25:28.890Z] 17:25:28 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-01-22T17:25:28.891Z] 17:25:28 INFO - Tab removed and finished closing
[task 2019-01-22T17:25:29.034Z] 17:25:29 INFO - Removing tab.
[task 2019-01-22T17:25:29.035Z] 17:25:29 INFO - Waiting for event: 'TabClose' on [object XULElement].
[task 2019-01-22T17:25:29.062Z] 17:25:29 INFO - Got event: 'TabClose' on [object XULElement].
[task 2019-01-22T17:25:29.100Z] 17:25:29 INFO - Tab removed and finished closing
[task 2019-01-22T17:25:29.161Z] 17:25:29 INFO - GECKO(1080) | 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:1415:14\ngenerateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol.js:1539:14\nlistTabs@resource://devtools/shared/fronts/root.js:180:38\nasynclistTabs@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:108:12\nasyncrequestTabs/<@resource://devtools/client/aboutdebugging-new/src/actions/debug-targets.js:143:26\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:5\nemit@resource://devtools/shared/event-emitter.js:186:15\nemit@resource://devtools/shared/event-emitter.js:267:5\nonPacket@resource://devtools/shared/protocol.js:1453:7\nonPacket@resource://devtools/shared/client/debugger-client.js:765:7\nsend/<@resource://devtools/shared/transport/local-transport.js:64:11\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14\nDevToolsUtils.executeSoonexports.executeSoon@resource://devtools/shared/DevToolsUtils.js:57:21\nsend@resource://devtools/shared/transport/local-transport.js:58:7\nsend@resource://devtools/server/main.js:1007:5\nonTabListChanged@resource://devtools/server/actors/root.js:398:5\nBrowserTabList.prototype._notifyListChanged@resource://devtools/server/actors/webbrowser.js:397:5\nBrowserTabList.prototype.handleEvent<@resource://devtools/server/actors/webbrowser.js:585:7\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14\nupdateCurrentBrowser@chrome://browser/content/tabbrowser.js:1048:7\n_setupEventListeners/<@chrome://browser/content/tabbrowser.js:4551:9\nset selectedIndex@chrome://global/content/elements/tabbox.js:201:7\nset selectedPanel@chrome://global/content/elements/tabbox.js:215:5\nset_selectedIndex@chrome://global/content/bindings/tabbox.xml:176:15\nset_selectedItem@chrome://global/content/bindings/tabbox.xml:201:34\nset selectedTab@chrome://global/content/elements/tabbox.js:80:9\nset selectedTab@chrome://browser/content/tabbrowser.js:267:5\n_blurTab@chrome://browser/content/tabbrowser.js:3233:24\n_beginRemoveTab@chrome://browser/content/tabbrowser.js:2933:5\nremoveTab@chrome://browser/content/tabbrowser.js:2841:10\nremoveTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:192:3\nasynccloseTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:456:9\nasynccleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:137:11\nasyncnextTest@chrome://mochikit/content/browser-test.js:707:30\nasynctestScope/test_finish/<@chrome://mochikit/content/browser-test.js:1410:11\nrun@chrome://mochikit/content/browser-test.js:1347:9\n"
[task 2019-01-22T17:25:29.161Z] 17:25:29 INFO - GECKO(1080) | console.error: "destroy@resource://devtools/shared/protocol.js:1328:23\ndestroy@resource://devtools/shared/protocol.js:952:9\ncleanup@resource://devtools/shared/protocol.js:966:5\nonClosed@resource://devtools/shared/client/debugger-client.js:912:7\nclose@resource://devtools/shared/transport/local-transport.js:154:9\nclose@resource://devtools/shared/transport/local-transport.js:150:7\nclose@resource://devtools/shared/transport/local-transport.js:150:7\ncleanup@resource://devtools/shared/client/debugger-client.js:300:9\ndetachClients@resource://devtools/shared/client/debugger-client.js:325:9\nclose@resource://devtools/shared/client/debugger-client.js:334:5\nclose@resource://devtools/client/aboutdebugging-new/src/modules/client-wrapper.js:136:12\nasyncdisconnectRuntime/<@resource://devtools/client/aboutdebugging-new/src/actions/runtimes.js:147:13\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:229: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:113:13\nasync@resource://devtools/client/aboutdebugging-new/aboutdebugging.js:136:3\n_endRemoveTab@chrome://browser/content/tabbrowser.js:3167:5\nremoveTab@chrome://browser/content/tabbrowser.js:2865:7\nremoveTab@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:192:3\nasynccloseTabAndToolbox@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:456:9\nasynccleanup@chrome://mochitests/content/browser/devtools/client/shared/test/shared-head.js:137:11\nasyncnextTest@chrome://mochikit/content/browser-test.js:707:30\nasynctestScope/test_finish/<@chrome://mochikit/content/browser-test.js:1410:11\nrun@chrome://mochikit/content/browser-test.js:1347:9\n"
[task 2019-01-22T17:25:29.163Z] 17:25:29 INFO - GECKO(1080) | console.error: "[ACTION FAILED] REQUEST_WORKERS_FAILURE: Can not send request because front 'serviceWorkerRegistration' is already destroyed."
[task 2019-01-22T17:25:29.164Z] 17:25:29 INFO - GECKO(1080) | console.error: "generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol.js:1522:15\nrequestWorkers/<@resource://devtools/client/aboutdebugging-new/src/actions/debug-targets.js:211:36\nasyncthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:755:18\nonWorkersUpdated@resource://devtools/client/aboutdebugging-new/src/middleware/debug-target-listener.js:23:5\nemit@resource://devtools/shared/event-emitter.js:186:15\nemit@resource://devtools/shared/event-emitter.js:267:5\nonPacket@resource://devtools/shared/protocol.js:1453:7\nonPacket@resource://devtools/shared/client/debugger-client.js:765:7\nsend/<@resource://devtools/shared/transport/local-transport.js:64:11\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14\nDevToolsUtils.executeSoonexports.executeSoon@resource://devtools/shared/DevToolsUtils.js:57:21\nsend@resource://devtools/shared/transport/local-transport.js:58:7\nsend@resource://devtools/server/main.js:1007:5\n_sendEvent@resource://devtools/shared/protocol.js:1029:5\ninitialize/<@resource://devtools/shared/protocol.js:1005:11\nemit@resource://devtools/shared/event-emitter.js:186:15\nemit@resource://devtools/shared/event-emitter.js:267:5\nonChange@resource://devtools/server/actors/worker/service-worker-registration.js:57:5\n"
[task 2019-01-22T17:25:29.165Z] 17:25:29 INFO - GECKO(1080) | console.error: "[ACTION FAILED] REQUEST_WORKERS_FAILURE: Can not send request because front 'serviceWorkerRegistration' is already destroyed."
[task 2019-01-22T17:25:29.166Z] 17:25:29 INFO - GECKO(1080) | console.error: "generateRequestMethods/</frontProto[name]@resource://devtools/shared/protocol.js:1522:15\nrequestWorkers/<@resource://devtools/client/aboutdebugging-new/src/actions/debug-targets.js:211:36\nasyncthunk/</<@resource://devtools/client/shared/redux/middleware/thunk.js:15:9\ndispatch@resource://devtools/client/shared/vendor/redux.js:755:18\nonWorkersUpdated@resource://devtools/client/aboutdebugging-new/src/middleware/debug-target-listener.js:23:5\nemit@resource://devtools/shared/event-emitter.js:186:15\nemit@resource://devtools/shared/event-emitter.js:267:5\nonPacket@resource://devtools/shared/protocol.js:1453:7\nonPacket@resource://devtools/shared/client/debugger-client.js:765:7\nsend/<@resource://devtools/shared/transport/local-transport.js:64:11\nexports.makeInfallible/<@resource://devtools/shared/ThreadSafeDevToolsUtils.js:109:14\nDevToolsUtils.executeSoonexports.executeSoon@resource://devtools/shared/DevToolsUtils.js:57:21\nsend@resource://devtools/shared/transport/local-transport.js:58:7\nsend@resource://devtools/server/main.js:1007:5\n_sendEvent@resource://devtools/shared/protocol.js:1029:5\ninitialize/<@resource://devtools/shared/protocol.js:1005:11\nemit@resource://devtools/shared/event-emitter.js:186:15\nemit@resource://devtools/shared/event-emitter.js:267:5\nonChange@resource://devtools/server/actors/worker/service-worker-registration.js:57:5\n"
[task 2019-01-22T17:25:29.284Z] 17:25:29 INFO - GECKO(1080) | MEMORY STAT | vsize 2236MB | residentFast 403MB | heapAllocated 131MB
[task 2019-01-22T17:25:29.285Z] 17:25:29 INFO - TEST-OK | devtools/client/aboutdebugging-new/test/browser/browser_aboutdebugging_serviceworker_runtime-page.js | took 5436ms

Comment 1

[Intermittent Failures Robot]

23 failures in 382 pushes (0.06 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 23

Platform breakdown:

• linux64: 23

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

Comment 2

[Dorel Luca [:dluca]]

Retriggering this failure shows me that it started on this push:

https://treeherder.mozilla.org/#/jobs?repo=autoland&resultStatus=testfailed%2Cbusted%2Cexception%2Csuccess%2Crunning%2Cpending%2Crunnable&searchStr=dt2&revision=435aedf3116a52051fc3355b45e9ae07addca589

https://hg.mozilla.org/integration/autoland/rev/435aedf3116a52051fc3355b45e9ae07addca589

Log:
https://hg.mozilla.org/integration/autoland/rev/435aedf3116a52051fc3355b45e9ae07addca589

Comment 3

[Belén [:ladybenko]]

Thanks for the heads up, I'm investigating what is causing this.

Comment 4

[Julian Descottes [:jdescottes]]

Raul, this intermittent seems to be split between this bug and https://bugzilla.mozilla.org/show_bug.cgi?id=1521999. Could we merge the two? it would be easier to keep track of the evolution of failures here.

Comment 6

[Raul Gurzau (:RaulG)]

Julian, all failures should be tracked here now. Thank you.

Comment 7

[Intermittent Failures Robot]

23 failures in 2383 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 23

Platform breakdown:

• linux64: 23

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

Comment 9

[Intermittent Failures Robot]

3 failures in 2628 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 3

Platform breakdown:

• linux64: 3

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

Comment 10

[Intermittent Failures Robot]

12 failures in 2658 pushes (0.005 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-inbound: 2
• mozilla-central: 10

Platform breakdown:

• linux64: 11
• windows7-32: 1

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

Comment 11

[Julian Descottes [:jdescottes]]

Attachment: Bug 1521887 - Fix browser_aboutdebugging_serviceworker_runtime-page.js for sw-e10s refactor;r=ladybenko

Comment 12

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/480b38a795e9
Fix browser_aboutdebugging_serviceworker_runtime-page.js for sw-e10s refactor;r=ladybenko

Comment 13

[Razvan Maries]

https://hg.mozilla.org/mozilla-central/rev/480b38a795e9

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• mozilla-central: 20

Platform breakdown:

• linux64: 21

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

Comment 15

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform breakdown:

• windows7-32: 1

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