Closed
Bug 1809484
Opened 2 years ago
Closed 2 years ago
Intermittent /service-workers/service-worker/windowclient-navigate.https.html | single tracking bug
Categories
(Core :: DOM: Service Workers, defect, P5)
Core
DOM: Service Workers
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, intermittent-testcase)
Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=401946858&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/MjFZFnlLSjOtTJkHbCtVmg/runs/0/artifacts/public/logs/live_backing.log
[task 2023-01-10T16:11:02.178Z] 16:11:02 INFO - TEST-START | /service-workers/service-worker/windowclient-navigate.https.html
[task 2023-01-10T16:11:02.191Z] 16:11:02 INFO - Closing window 21dcb727-6ccb-4b91-8e9d-b4c5607e159a
[task 2023-01-10T16:11:06.026Z] 16:11:06 INFO - PID 16110 | JavaScript warning: https://web-platform.test:8443/resources/testharness.js, line 4673: Script terminated by timeout at:
[task 2023-01-10T16:11:06.032Z] 16:11:06 INFO - PID 16110 | merge@https://web-platform.test:8443/resources/testharness.js:4673:19
[task 2023-01-10T16:11:06.033Z] 16:11:06 INFO - PID 16110 | Test.prototype.structured_clone@https://web-platform.test:8443/resources/testharness.js:2536:38
[task 2023-01-10T16:11:06.034Z] 16:11:06 INFO - PID 16110 | WindowTestEnvironment/this.message_functions.completion</cloned_tests<@https://web-platform.test:8443/resources/testharness.js:83:46
[task 2023-01-10T16:11:06.035Z] 16:11:06 INFO - PID 16110 | map@https://web-platform.test:8443/resources/testharness.js:4593:34
[task 2023-01-10T16:11:06.036Z] 16:11:06 INFO - PID 16110 | WindowTestEnvironment/this.message_functions.completion<@https://web-platform.test:8443/resources/testharness.js:82:52
[task 2023-01-10T16:11:06.037Z] 16:11:06 INFO - PID 16110 | Tests.prototype.notify_complete/<@https://web-platform.test:8443/resources/testharness.js:3761:30
[task 2023-01-10T16:11:06.038Z] 16:11:06 INFO - PID 16110 | forEach@https://web-platform.test:8443/resources/testharness.js:4608:26
[task 2023-01-10T16:11:06.039Z] 16:11:06 INFO - PID 16110 | Tests.prototype.notify_complete@https://web-platform.test:8443/resources/testharness.js:3758:17
[task 2023-01-10T16:11:06.040Z] 16:11:06 INFO - PID 16110 | all_complete@https://web-platform.test:8443/resources/testharness.js:3624:22
[task 2023-01-10T16:11:06.041Z] 16:11:06 INFO - PID 16110 | Tests.prototype.complete@https://web-platform.test:8443/resources/testharness.js:3636:13
[task 2023-01-10T16:11:06.042Z] 16:11:06 INFO - PID 16110 | Tests.prototype.notify_result@https://web-platform.test:8443/resources/testharness.js:3613:22
[task 2023-01-10T16:11:06.043Z] 16:11:06 INFO - PID 16110 | Tests.prototype.result@https://web-platform.test:8443/resources/testharness.js:3600:14
[task 2023-01-10T16:11:06.044Z] 16:11:06 INFO - PID 16110 | cleanup_done@https://web-platform.test:8443/resources/testharness.js:3042:15
[task 2023-01-10T16:11:06.045Z] 16:11:06 INFO - PID 16110 | Test.prototype.cleanup/<@https://web-platform.test:8443/resources/testharness.js:2992:39
[task 2023-01-10T16:11:06.046Z] 16:11:06 INFO - PID 16110 | elDone@https://web-platform.test:8443/resources/testharness.js:4658:29
[task 2023-01-10T16:14:07.686Z] 16:14:07 INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/windowclient-navigate.https.html | expected OK
[task 2023-01-10T16:14:07.686Z] 16:14:07 INFO - TEST-INFO took 185513ms
[task 2023-01-10T16:14:07.688Z] 16:14:07 INFO - PID 16110 | 1673367247687 Marionette INFO Stopped listening on port 53460
[task 2023-01-10T16:14:08.269Z] 16:14:08 INFO - PID 16110 | -----------------------------------------------------
[task 2023-01-10T16:14:08.269Z] 16:14:08 INFO - PID 16110 | Suppressions used:
[task 2023-01-10T16:14:08.269Z] 16:14:08 INFO - PID 16110 | count bytes template
[task 2023-01-10T16:14:08.269Z] 16:14:08 INFO - PID 16110 | 2 288 libfontconfig.so
[task 2023-01-10T16:14:08.269Z] 16:14:08 INFO - PID 16110 | -----------------------------------------------------
[task 2023-01-10T16:14:08.300Z] 16:14:08 INFO - PID 16110 | -----------------------------------------------------
[task 2023-01-10T16:14:08.300Z] 16:14:08 INFO - PID 16110 | Suppressions used:
[task 2023-01-10T16:14:08.300Z] 16:14:08 INFO - PID 16110 | count bytes template
[task 2023-01-10T16:14:08.300Z] 16:14:08 INFO - PID 16110 | 2 288 libfontconfig.so
[task 2023-01-10T16:14:08.300Z] 16:14:08 INFO - PID 16110 | -----------------------------------------------------
[task 2023-01-10T16:14:08.325Z] 16:14:08 INFO - PID 16110 | -----------------------------------------------------
[task 2023-01-10T16:14:08.326Z] 16:14:08 INFO - PID 16110 | Suppressions used:
[task 2023-01-10T16:14:08.326Z] 16:14:08 INFO - PID 16110 | count bytes template
[task 2023-01-10T16:14:08.326Z] 16:14:08 INFO - PID 16110 | 2 288 libfontconfig.so
[task 2023-01-10T16:14:08.326Z] 16:14:08 INFO - PID 16110 | -----------------------------------------------------
[task 2023-01-10T16:14:08.326Z] 16:14:08 INFO - PID 16110 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-01-10T16:14:08.326Z] 16:14:08 INFO - PID 16110 | Blocker: Waiting for ping task
[task 2023-01-10T16:14:08.327Z] 16:14:08 INFO - PID 16110 | Phase: TelemetryController: Waiting for pending ping activity
[task 2023-01-10T16:14:08.327Z] 16:14:08 INFO - PID 16110 | State: (none)
[task 2023-01-10T16:14:08.327Z] 16:14:08 INFO - PID 16110 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-10T16:14:08.327Z] 16:14:08 INFO - PID 16110 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-01-10T16:14:08.327Z] 16:14:08 INFO - PID 16110 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-01-10T16:14:08.331Z] 16:14:08 INFO - PID 16110 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-01-10T16:14:08.331Z] 16:14:08 INFO - PID 16110 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-10T16:14:08.331Z] 16:14:08 INFO - PID 16110 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-10T16:14:08.331Z] 16:14:08 INFO - PID 16110 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-10T16:14:08.332Z] 16:14:08 INFO - PID 16110 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-10T16:14:08.333Z] 16:14:08 INFO - PID 16110 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-10T16:14:08.333Z] 16:14:08 INFO - PID 16110 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-10T16:14:08.334Z] 16:14:08 INFO - PID 16110 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-10T16:14:08.334Z] 16:14:08 INFO - PID 16110 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-10T16:14:08.335Z] 16:14:08 INFO - PID 16110 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-01-10T16:14:08.348Z] 16:14:08 INFO - PID 16110 | -----------------------------------------------------
[task 2023-01-10T16:14:08.349Z] 16:14:08 INFO - PID 16110 | Suppressions used:
[task 2023-01-10T16:14:08.349Z] 16:14:08 INFO - PID 16110 | count bytes template
[task 2023-01-10T16:14:08.350Z] 16:14:08 INFO - PID 16110 | 2 288 libfontconfig.so
[task 2023-01-10T16:14:08.351Z] 16:14:08 INFO - PID 16110 | -----------------------------------------------------
[task 2023-01-10T16:14:08.405Z] 16:14:08 INFO - PID 16110 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-01-10T16:14:08.406Z] 16:14:08 INFO - PID 16110 | Blocker: Waiting for ping task
[task 2023-01-10T16:14:08.406Z] 16:14:08 INFO - PID 16110 | Phase: TelemetryController: Waiting for pending ping activity
[task 2023-01-10T16:14:08.406Z] 16:14:08 INFO - PID 16110 | State: (none)
[task 2023-01-10T16:14:08.408Z] 16:14:08 INFO - PID 16110 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-10T16:14:08.409Z] 16:14:08 INFO - PID 16110 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-01-10T16:14:08.409Z] 16:14:08 INFO - PID 16110 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-01-10T16:14:08.410Z] 16:14:08 INFO - PID 16110 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-01-10T16:14:08.411Z] 16:14:08 INFO - PID 16110 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-10T16:14:08.412Z] 16:14:08 INFO - PID 16110 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-10T16:14:08.412Z] 16:14:08 INFO - PID 16110 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-10T16:14:08.412Z] 16:14:08 INFO - PID 16110 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-10T16:14:08.413Z] 16:14:08 INFO - PID 16110 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-10T16:14:08.415Z] 16:14:08 INFO - PID 16110 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-10T16:14:08.415Z] 16:14:08 INFO - PID 16110 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | Blocker: TelemetryController: shutting down
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | Phase: profile-before-change-telemetry
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | State: Error getting state: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange" at addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | WARNING: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-10T16:14:08.423Z] 16:14:08 INFO - PID 16110 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-10T16:14:08.424Z] 16:14:08 INFO - PID 16110 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-10T16:14:08.424Z] 16:14:08 INFO - PID 16110 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-10T16:14:08.425Z] 16:14:08 INFO - PID 16110 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-10T16:14:08.425Z] 16:14:08 INFO - PID 16110 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-01-10T16:14:08.426Z] 16:14:08 INFO - PID 16110 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-10T16:14:08.428Z] 16:14:08 INFO - PID 16110 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-10T16:14:08.429Z] 16:14:08 INFO - PID 16110 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-10T16:14:08.429Z] 16:14:08 INFO - PID 16110 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-10T16:14:08.429Z] 16:14:08 INFO - PID 16110 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 727: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2023-01-10T16:14:10.181Z] 16:14:10 INFO - PID 16110 | =================================================================
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•2 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•