Closed Bug 1588709 Opened 5 years ago Closed 5 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/activation-after-registration.https.html | activation occurs after registration - Test timed out

Categories

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

Product:
Core
Component:
DOM: Service Workers
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

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

[task 2019-10-15T05:04:58.905Z] 05:04:58     INFO - TEST-START | /service-workers/service-worker/activation-after-registration.https.html
[task 2019-10-15T05:04:58.909Z] 05:04:58     INFO - Closing window 27
[task 2019-10-15T05:04:58.990Z] 05:04:58     INFO - PID 26758 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-10-15T05:04:59.605Z] 05:04:59     INFO - PID 26758 | -----------------------------------------------------
[task 2019-10-15T05:04:59.605Z] 05:04:59     INFO - PID 26758 | Suppressions used:
[task 2019-10-15T05:04:59.605Z] 05:04:59     INFO - PID 26758 |   count      bytes template
[task 2019-10-15T05:04:59.605Z] 05:04:59     INFO - PID 26758 |      27        832 nsComponentManagerImpl
[task 2019-10-15T05:04:59.605Z] 05:04:59     INFO - PID 26758 |     611      17713 libfontconfig.so
[task 2019-10-15T05:04:59.606Z] 05:04:59     INFO - PID 26758 |       1         29 libglib-2.0.so
[task 2019-10-15T05:04:59.606Z] 05:04:59     INFO - PID 26758 | -----------------------------------------------------
[task 2019-10-15T05:05:29.673Z] 05:05:29     INFO - 
[task 2019-10-15T05:05:29.673Z] 05:05:29     INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/activation-after-registration.https.html | activation occurs after registration - Test timed out
[task 2019-10-15T05:05:29.673Z] 05:05:29     INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/activation-after-registration.https.html | expected OK
[task 2019-10-15T05:05:29.673Z] 05:05:29     INFO - TEST-INFO took 30769ms
[task 2019-10-15T05:05:29.711Z] 05:05:29     INFO - PID 26758 | 1571115929701	Marionette	INFO	Stopped listening on port 36550
[task 2019-10-15T05:05:29.974Z] 05:05:29     INFO - PID 26758 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-10-15T05:05:29.981Z] 05:05:29     INFO - PID 26758 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-10-15T05:05:30.547Z] 05:05:30     INFO - PID 26758 | -----------------------------------------------------
[task 2019-10-15T05:05:30.547Z] 05:05:30     INFO - PID 26758 | Suppressions used:
[task 2019-10-15T05:05:30.548Z] 05:05:30     INFO - PID 26758 |   count      bytes template
[task 2019-10-15T05:05:30.548Z] 05:05:30     INFO - PID 26758 |      27        832 nsComponentManagerImpl
[task 2019-10-15T05:05:30.548Z] 05:05:30     INFO - PID 26758 |     611      17713 libfontconfig.so
[task 2019-10-15T05:05:30.548Z] 05:05:30     INFO - PID 26758 |       1         29 libglib-2.0.so
[task 2019-10-15T05:05:30.549Z] 05:05:30     INFO - PID 26758 | -----------------------------------------------------
[task 2019-10-15T05:05:30.556Z] 05:05:30     INFO - PID 26758 | -----------------------------------------------------
[task 2019-10-15T05:05:30.557Z] 05:05:30     INFO - PID 26758 | Suppressions used:
[task 2019-10-15T05:05:30.557Z] 05:05:30     INFO - PID 26758 |   count      bytes template
[task 2019-10-15T05:05:30.557Z] 05:05:30     INFO - PID 26758 |      27        832 nsComponentManagerImpl
[task 2019-10-15T05:05:30.558Z] 05:05:30     INFO - PID 26758 |     611      17713 libfontconfig.so
[task 2019-10-15T05:05:30.558Z] 05:05:30     INFO - PID 26758 |       1         29 libglib-2.0.so
[task 2019-10-15T05:05:30.558Z] 05:05:30     INFO - PID 26758 | -----------------------------------------------------
[task 2019-10-15T05:05:40.007Z] 05:05:40     INFO - PID 26758 | 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-15T05:05:44.955Z] 05:05:44     INFO - PID 26758 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.jsm", 433))
[task 2019-10-15T05:06:40.751Z] 05:06:40     INFO - Browser exited with return code -15
[task 2019-10-15T05:06:40.752Z] 05:06:40     INFO - PROCESS LEAKS None
[task 2019-10-15T05:06:40.754Z] 05:06:40     INFO - Closing logging queue
[task 2019-10-15T05:06:40.754Z] 05:06:40     INFO - queue closed
[task 2019-10-15T05:06:40.756Z] 05:06:40     INFO - INFO | runtests.py | ASan using symbolizer at /builds/worker/workspace/build/application/firefox/llvm-symbolizer
[task 2019-10-15T05:06:40.772Z] 05:06:40     INFO - LSan enabled.
[task 2019-10-15T05:06:40.772Z] 05:06:40     INFO - LSan using suppression file /builds/worker/workspace/build/tests/web-platform/prefs/lsan_suppressions.txt
[task 2019-10-15T05:06:40.772Z] 05:06:40     INFO - INFO | runtests.py | ASan running in default memory configuration
[task 2019-10-15T05:06:40.788Z] 05:06:40     INFO - Setting up ssl
[task 2019-10-15T05:06:40.849Z] 05:06:40     INFO - certutil | 
[task 2019-10-15T05:06:40.905Z] 05:06:40     INFO - certutil | 
[task 2019-10-15T05:06:40.941Z] 05:06:40     INFO - certutil | 
[task 2019-10-15T05:06:40.941Z] 05:06:40     INFO - Certificate Nickname                                         Trust Attributes
[task 2019-10-15T05:06:40.941Z] 05:06:40     INFO -                                                              SSL,S/MIME,JAR/XPI
[task 2019-10-15T05:06:40.941Z] 05:06:40     INFO - 
[task 2019-10-15T05:06:40.941Z] 05:06:40     INFO - web-platform-tests                                           CT,, 
[task 2019-10-15T05:06:40.942Z] 05:06:40     INFO - 
[task 2019-10-15T05:06:40.957Z] 05:06:40     INFO - Application command: /builds/worker/workspace/build/application/firefox/firefox --marionette about:blank -profile /tmp/tmpyLf8aJ.mozrunner
[task 2019-10-15T05:06:40.973Z] 05:06:40     INFO - Starting runner
[task 2019-10-15T05:06:42.713Z] 05:06:42     INFO - PID 27139 | 1571116002703	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: mozillaAddons
[task 2019-10-15T05:06:42.713Z] 05:06:42     INFO - PID 27139 | 1571116002704	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: telemetry
[task 2019-10-15T05:06:42.713Z] 05:06:42     INFO - PID 27139 | 1571116002704	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: resource://pdf.js/
[task 2019-10-15T05:06:42.714Z] 05:06:42     INFO - PID 27139 | 1571116002705	addons.webextension.screenshots@mozilla.org	WARN	Loading extension 'screenshots@mozilla.org': Reading manifest: Invalid extension permission: about:reader*
[task 2019-10-15T05:06:49.957Z] 05:06:49     INFO - PID 27139 | 1571116009948	Marionette	INFO	Listening on port 36550

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

Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.