1578333 - Intermittent dom/push/test/test_serviceworker_lifetime.html | Test timed out.

Status: RESOLVED INCOMPLETE

(Core :: DOM: Push Subscriptions, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: rmaries [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=264673170&repo=mozilla-inbound
Full log: https://queue.taskcluster.net/v1/task/X0LtUw2AT969cme_EOhhbA/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-09-03T05:33:00.126Z] 05:33:00 INFO - TEST-START | dom/push/test/test_serviceworker_lifetime.html
[task 2019-09-03T05:38:24.366Z] 05:38:24 INFO - TEST-PASS | dom/push/test/test_serviceworker_lifetime.html | Service worker was terminated.
[task 2019-09-03T05:38:24.366Z] 05:38:24 INFO - Buffered messages finished
[task 2019-09-03T05:38:24.366Z] 05:38:24 INFO - TEST-UNEXPECTED-FAIL | dom/push/test/test_serviceworker_lifetime.html | Test timed out.
[task 2019-09-03T05:38:24.366Z] 05:38:24 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-09-03T05:38:24.366Z] 05:38:24 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-09-03T05:38:24.366Z] 05:38:24 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:18
[task 2019-09-03T05:38:24.366Z] 05:38:24 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:170:15
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:388:20
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:375:50
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - RunSet.runtests@SimpleTest/setup.js:201:14
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - RunSet.runall@SimpleTest/setup.js:180:12
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - hookupTests@SimpleTest/setup.js:273:12
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:48:13
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:61:28
[task 2019-09-03T05:38:24.367Z] 05:38:24 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:57:3
[task 2019-09-03T05:38:24.368Z] 05:38:24 INFO - hookup@SimpleTest/setup.js:253:20
[task 2019-09-03T05:38:24.368Z] 05:38:24 INFO - EventHandlerNonNull@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=%2Fvar%2Ffolders%2F8h%2F7d0lfw710vnfl7sffjg31wqc000017%2FT&cleanupCrashes=true:11:1
[task 2019-09-03T05:38:25.333Z] 05:38:25 INFO - GECKO(2007) | [Child 2009, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
[task 2019-09-03T05:38:25.333Z] 05:38:25 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-09-03T05:38:25.333Z] 05:38:25 INFO - TEST-UNEXPECTED-FAIL | dom/push/test/test_serviceworker_lifetime.html | This test left a service worker registered without cleaning it up
[task 2019-09-03T05:38:25.333Z] 05:38:25 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-09-03T05:38:25.333Z] 05:38:25 INFO - afterCleanup@SimpleTest/SimpleTest.js:1192:28
[task 2019-09-03T05:38:25.333Z] 05:38:25 INFO - asyncexecuteCleanupFunction@SimpleTest/SimpleTest.js:1232:13
[task 2019-09-03T05:38:25.333Z] 05:38:25 INFO - Not taking screenshot here: see the one that was previously logged
[task 2019-09-03T05:38:25.333Z] 05:38:25 INFO - TEST-UNEXPECTED-FAIL | dom/push/test/test_serviceworker_lifetime.html | Left over worker: http://mochi.test:8888/tests/dom/push/test/lifetime_worker.js (scope: http://mochi.test:8888/tests/dom/push/test/)
[task 2019-09-03T05:38:25.333Z] 05:38:25 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:277:18
[task 2019-09-03T05:38:25.334Z] 05:38:25 INFO - afterCleanup@SimpleTest/SimpleTest.js:1194:32
[task 2019-09-03T05:38:25.334Z] 05:38:25 INFO - asyncexecuteCleanupFunction@SimpleTest/SimpleTest.js:1232:13
[task 2019-09-03T05:38:25.337Z] 05:38:25 INFO - GECKO(2007) | [Parent 2007, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
[task 2019-09-03T05:38:25.341Z] 05:38:25 INFO - GECKO(2007) | [Child 2010, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
[task 2019-09-03T05:38:25.341Z] 05:38:25 INFO - GECKO(2007) | MEMORY STAT | vsize 7312MB | residentFast 149MB | heapAllocated 27MB
[task 2019-09-03T05:38:25.341Z] 05:38:25 INFO - GECKO(2007) | [Child 2008, Main Thread] WARNING: '!window', file /builds/worker/workspace/build/src/dom/cache/CacheStorage.cpp, line 575
[task 2019-09-03T05:38:25.349Z] 05:38:25 INFO - TEST-OK | dom/push/test/test_serviceworker_lifetime.html | took 325292ms
[task 2019-09-03T05:38:25.398Z] 05:38:25 ERROR - /tests/dom/push/test/test_serviceworker_lifetime.html logged result after SimpleTest.finish(): This test left a service worker registered without cleaning it up
[task 2019-09-03T05:38:25.398Z] 05:38:25 ERROR - /tests/dom/push/test/test_serviceworker_lifetime.html logged result after SimpleTest.finish(): Left over worker: http://mochi.test:8888/tests/dom/push/test/lifetime_worker.js (scope: http://mochi.test:8888/tests/dom/push/test/)
[task 2019-09-03T05:38:25.398Z] 05:38:25 INFO - GECKO(2007) | ++DOMWINDOW == 9 (0x112cb6000) [pid = 2009] [serial = 38] [outer = 0x11a454a60]

Comment 1

[Cosmin Sabou [:CosminS]]

Jean, since Bug 1556489 landed here: https://treeherder.mozilla.org/#/jobs?repo=mozilla-inbound&searchStr=os%2Cx%2C10.14%2Cdebug%2Cmochitests%2Ctest-macosx1014-64%2Fdebug-mochitest-e10s-2%2Cm%282%29&tochange=05ce641e7de439e8b8f35dabab3d46280156f3bb&fromchange=4cd56624e723867b1e508d73bd8ee82c899f5670&group_state=expanded&selectedJob=264806735 this bug has a very high failure rate. Could you please take a look over this?
At this rate it will hit the disable recommended threshold in a few days.

Comment 2

[Cosmin Sabou [:CosminS]]

Attachment: Bug 1578333 - Disable test because of frequent failures. r=#intermittent-reviewers

Comment 3

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/174361d15292
Disable test because of frequent failures. r=egao

Comment 4

[Intermittent Failures Robot]

67 failures in 869 pushes (0.077 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-inbound: 25
• autoland: 33
• mozilla-central: 7
• try: 2

Platform breakdown:

• macosx1014-64: 49
• linux64-shippable-qr: 4
• linux64: 9
• linux64-qr: 3
• macosx1014-64-shippable: 2

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

Comment 5

[Jean-Yves Avenard [:jya]]

I can't reproduce the issue locally (macos or linux64), and bug 1556489 is currently disabled and as such isn't modifying the current codepath.

Seeing that it was already permafailure on other platforms, I think it's just coincidental.

Comment 6

[Cristian Brindusan [:cbrindusan]]

https://hg.mozilla.org/mozilla-central/rev/174361d15292

Comment 7

[Intermittent Failures Robot]

70 failures in 4408 pushes (0.016 failures/push) were associated with this bug in the last 7 days.

This is the #21 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:

• mozilla-inbound: 26
• autoland: 34
• mozilla-central: 7
• try: 3

Platform breakdown:

• macosx1014-64: 51
• linux64-shippable-qr: 4
• linux64: 9
• linux64-qr: 4
• macosx1014-64-shippable: 2

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

Comment 8

[Perry Jiang [:perry] [no longer employee, use ni?]]

Attachment: Bug 1578333 - Fix test_serviceworker_lifetime.html r=asuth

• Reset idle timeout when dispatching service worker operations even if the
  worker is alive.
• Use MessageEvent instead of FetchEvent to avoid creating objects that leak
  when the test finishes.

Comment 9

[Perry Jiang [:perry] [no longer employee, use ni?]]

Moving patch D51016 to bug 1592699 because this bug isn't fission specific and it looks like there may need to be additional work to fix the non-fission intermittents.

Comment 10

[Phabricator Automation]

Comment on attachment 9105070 [details]
Bug 1578333 - Fix test_serviceworker_lifetime.html r=asuth

Revision D51016 was moved to bug 1592699. Setting attachment 9105070 [details] to obsolete.

Comment 11

[Jens Stutte [:jstutte]]

Bulk closing intermittent bugs for DOM LWS that have not seen new instances since at least 5 months.

Comment 12

[Jens Stutte [:jstutte]]

Taking back the bulk change.

Comment 13

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.