1589195 - Intermittent TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/activation.https.html | finishing a request triggers unregister - Test timed out

Status: RESOLVED INCOMPLETE

(Core :: DOM: Service Workers, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=271529895&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/Dlr0kSZcQOSxVD3NZyhXqw/runs/0/artifacts/public/logs/live_backing.log

---

[task 2019-10-16T17:18:55.228Z] 17:18:55 INFO - TEST-OK | /service-workers/service-worker/activation-after-registration.https.html | took 844ms
[task 2019-10-16T17:18:55.229Z] 17:18:55 INFO - TEST-START | /service-workers/service-worker/activation.https.html
[task 2019-10-16T17:18:55.233Z] 17:18:55 INFO - Closing window 29
[task 2019-10-16T17:18:55.329Z] 17:18:55 INFO - PID 26866 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-10-16T17:18:56.072Z] 17:18:56 INFO - PID 26866 | -----------------------------------------------------
[task 2019-10-16T17:18:56.073Z] 17:18:56 INFO - PID 26866 | Suppressions used:
[task 2019-10-16T17:18:56.073Z] 17:18:56 INFO - PID 26866 | count bytes template
[task 2019-10-16T17:18:56.073Z] 17:18:56 INFO - PID 26866 | 27 832 nsComponentManagerImpl
[task 2019-10-16T17:18:56.073Z] 17:18:56 INFO - PID 26866 | 611 17713 libfontconfig.so
[task 2019-10-16T17:18:56.073Z] 17:18:56 INFO - PID 26866 | 1 29 libglib-2.0.so
[task 2019-10-16T17:18:56.073Z] 17:18:56 INFO - PID 26866 | -----------------------------------------------------
[task 2019-10-16T17:19:25.890Z] 17:19:25 INFO -
[task 2019-10-16T17:19:25.890Z] 17:19:25 INFO - TEST-PASS | /service-workers/service-worker/activation.https.html | loss of controllees triggers activation
[task 2019-10-16T17:19:25.890Z] 17:19:25 INFO - TEST-PASS | /service-workers/service-worker/activation.https.html | finishing a request triggers activation
[task 2019-10-16T17:19:25.890Z] 17:19:25 INFO - TEST-PASS | /service-workers/service-worker/activation.https.html | skipWaiting bypasses no controllee requirement
[task 2019-10-16T17:19:25.890Z] 17:19:25 INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/activation.https.html | finishing a request triggers unregister - Test timed out
[task 2019-10-16T17:19:25.891Z] 17:19:25 INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/activation.https.html | expected OK
[task 2019-10-16T17:19:25.891Z] 17:19:25 INFO - TEST-INFO took 30661ms
[task 2019-10-16T17:19:25.927Z] 17:19:25 INFO - PID 26866 | 1571246365916 Marionette INFO Stopped listening on port 52387
[task 2019-10-16T17:19:26.173Z] 17:19:26 INFO - PID 26866 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-10-16T17:19:26.181Z] 17:19:26 INFO - PID 26866 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-10-16T17:19:26.769Z] 17:19:26 INFO - PID 26866 | -----------------------------------------------------
[task 2019-10-16T17:19:26.769Z] 17:19:26 INFO - PID 26866 | Suppressions used:
[task 2019-10-16T17:19:26.769Z] 17:19:26 INFO - PID 26866 | count bytes template
[task 2019-10-16T17:19:26.770Z] 17:19:26 INFO - PID 26866 | 27 832 nsComponentManagerImpl
[task 2019-10-16T17:19:26.770Z] 17:19:26 INFO - PID 26866 | 611 17713 libfontconfig.so
[task 2019-10-16T17:19:26.770Z] 17:19:26 INFO - PID 26866 | 1 29 libglib-2.0.so
[task 2019-10-16T17:19:26.770Z] 17:19:26 INFO - PID 26866 | -----------------------------------------------------
[task 2019-10-16T17:19:26.806Z] 17:19:26 INFO - PID 26866 | -----------------------------------------------------
[task 2019-10-16T17:19:26.807Z] 17:19:26 INFO - PID 26866 | Suppressions used:
[task 2019-10-16T17:19:26.807Z] 17:19:26 INFO - PID 26866 | count bytes template
[task 2019-10-16T17:19:26.807Z] 17:19:26 INFO - PID 26866 | 27 832 nsComponentManagerImpl
[task 2019-10-16T17:19:26.807Z] 17:19:26 INFO - PID 26866 | 611 17713 libfontconfig.so
[task 2019-10-16T17:19:26.807Z] 17:19:26 INFO - PID 26866 | 1 29 libglib-2.0.so
[task 2019-10-16T17:19:26.807Z] 17:19:26 INFO - PID 26866 | -----------------------------------------------------
[task 2019-10-16T17:19:36.232Z] 17:19:36 INFO - PID 26866 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"ServiceWorkerShutdownBlocker: shutting down Service Workers","state":{"pendingPromises":1,"acceptingPromises":false},"filename":"/builds/worker/workspace/build/src/dom/serviceworkers/ServiceWorkerShutdownBlocker.cpp","lineNumber":82,"stack":"Service Workers shutdown"}] Barrier: profile-change-teardown
[task 2019-10-16T17:19:41.143Z] 17:19:41 INFO - PID 26866 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 433))
[task 2019-10-16T17:20:36.968Z] 17:20:36 INFO - Browser exited with return code -15
[task 2019-10-16T17:20:36.968Z] 17:20:36 INFO - PROCESS LEAKS None
[task 2019-10-16T17:20:36.971Z] 17:20:36 INFO - Closing logging queue

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux64: 1

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

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux32-shippable: 1

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

Comment 3

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

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