Closed
Bug 1814570
Opened 3 years ago
Closed 3 years ago
Intermittent /cookie-store/cookieStore_subscriptions_empty.https.window.html | single tracking bug
Categories
(Testing :: web-platform-tests, defect, P5)
Testing
web-platform-tests
Tracking
(Not tracked)
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, intermittent-testcase)
Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=404303480&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/DSJTimD5QtSnNybD5fg5CA/runs/0/artifacts/public/logs/live_backing.log
[task 2023-02-02T06:17:30.091Z] 06:17:30 INFO - TEST-START | /cookie-store/cookieStore_subscriptions_empty.https.window.html
[task 2023-02-02T06:17:30.093Z] 06:17:30 INFO - Closing window 880d145b-ba2e-4100-b063-0cc44e81649c
[task 2023-02-02T06:17:32.698Z] 06:17:32 INFO -
[task 2023-02-02T06:17:32.698Z] 06:17:32 INFO - TEST-UNEXPECTED-TIMEOUT | /cookie-store/cookieStore_subscriptions_empty.https.window.html | Newly registered and activated service worker has no subscriptions - Test timed out
[task 2023-02-02T06:17:32.702Z] 06:17:32 INFO - TEST-INFO | expected FAIL
[task 2023-02-02T06:17:32.703Z] 06:17:32 INFO - TEST-UNEXPECTED-TIMEOUT | /cookie-store/cookieStore_subscriptions_empty.https.window.html | expected OK
[task 2023-02-02T06:17:32.703Z] 06:17:32 INFO - TEST-INFO took 2618ms
[task 2023-02-02T06:17:32.705Z] 06:17:32 INFO - PID 8752 | 1675318652703 Marionette INFO Stopped listening on port 51498
[task 2023-02-02T06:17:35.772Z] 06:17:35 INFO - PID 8752 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-02T06:17:35.772Z] 06:17:35 INFO - PID 8752 | Blocker: Waiting for ping task
[task 2023-02-02T06:17:35.774Z] 06:17:35 INFO - PID 8752 | Phase: TelemetryController: Waiting for pending ping activity
[task 2023-02-02T06:17:35.775Z] 06:17:35 INFO - PID 8752 | State: (none)
[task 2023-02-02T06:17:35.776Z] 06:17:35 INFO - PID 8752 | 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-02-02T06:17:35.777Z] 06:17:35 INFO - PID 8752 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-02T06:17:35.777Z] 06:17:35 INFO - PID 8752 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-02T06:17:35.778Z] 06:17:35 INFO - PID 8752 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-02T06:17:35.778Z] 06:17:35 INFO - PID 8752 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-02T06:17:35.779Z] 06:17:35 INFO - PID 8752 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-02T06:17:35.779Z] 06:17:35 INFO - PID 8752 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-02T06:17:35.780Z] 06:17:35 INFO - PID 8752 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-02T06:17:35.780Z] 06:17:35 INFO - PID 8752 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-02T06:17:35.781Z] 06:17:35 INFO - PID 8752 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-02T06:17:35.782Z] 06:17:35 INFO - PID 8752 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-02T06:17:35.783Z] 06:17:35 INFO - PID 8752 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-02T06:17:35.784Z] 06:17:35 INFO - PID 8752 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-02T06:17:35.784Z] 06:17:35 INFO - PID 8752 | console.error: "TelemetryScheduler.shutdown - Already shut down"
[task 2023-02-02T06:17:35.785Z] 06:17:35 INFO - PID 8752 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-02T06:17:35.785Z] 06:17:35 INFO - PID 8752 | Blocker: Waiting for ping task
[task 2023-02-02T06:17:35.786Z] 06:17:35 INFO - PID 8752 | Phase: TelemetryController: Waiting for pending ping activity
[task 2023-02-02T06:17:35.787Z] 06:17:35 INFO - PID 8752 | State: (none)
[task 2023-02-02T06:17:35.787Z] 06:17:35 INFO - PID 8752 | 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-02-02T06:17:35.788Z] 06:17:35 INFO - PID 8752 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-02T06:17:35.788Z] 06:17:35 INFO - PID 8752 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-02T06:17:35.789Z] 06:17:35 INFO - PID 8752 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-02T06:17:35.790Z] 06:17:35 INFO - PID 8752 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-02T06:17:35.791Z] 06:17:35 INFO - PID 8752 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-02T06:17:35.791Z] 06:17:35 INFO - PID 8752 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-02T06:17:35.792Z] 06:17:35 INFO - PID 8752 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-02T06:17:35.793Z] 06:17:35 INFO - PID 8752 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-02T06:17:35.793Z] 06:17:35 INFO - PID 8752 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-02T06:17:35.794Z] 06:17:35 INFO - PID 8752 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-02T06:17:35.794Z] 06:17:35 INFO - PID 8752 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-02T06:17:35.795Z] 06:17:35 INFO - PID 8752 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-02T06:17:35.795Z] 06:17:35 INFO - PID 8752 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-02T06:17:35.796Z] 06:17:35 INFO - PID 8752 | Blocker: TelemetryController: shutting down
[task 2023-02-02T06:17:35.796Z] 06:17:35 INFO - PID 8752 | Phase: profile-before-change-telemetry
[task 2023-02-02T06:17:35.797Z] 06:17:35 INFO - PID 8752 | 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-02-02T06:17:35.798Z] 06:17:35 INFO - PID 8752 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-02T06:17:35.798Z] 06:17:35 INFO - PID 8752 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-02T06:17:35.799Z] 06:17:35 INFO - PID 8752 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-02T06:17:35.799Z] 06:17:35 INFO - PID 8752 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-02T06:17:35.800Z] 06:17:35 INFO - PID 8752 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-02T06:17:35.800Z] 06:17:35 INFO - PID 8752 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-02T06:17:35.801Z] 06:17:35 INFO - PID 8752 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-02T06:17:35.801Z] 06:17:35 INFO - PID 8752 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-02T06:17:35.802Z] 06:17:35 INFO - PID 8752 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-02T06:17:35.802Z] 06:17:35 INFO - PID 8752 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-02T06:17:35.803Z] 06:17:35 INFO - PID 8752 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-02T06:17:35.803Z] 06:17:35 INFO - PID 8752 | 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-02-02T06:17:35.804Z] 06:17:35 INFO - PID 8752 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-02T06:17:35.804Z] 06:17:35 INFO - PID 8752 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-02T06:17:35.804Z] 06:17:35 INFO - PID 8752 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-02T06:17:35.805Z] 06:17:35 INFO - PID 8752 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-02T06:17:35.806Z] 06:17:35 INFO - PID 8752 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-02T06:17:35.806Z] 06:17:35 INFO - PID 8752 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-02T06:17:35.807Z] 06:17:35 INFO - PID 8752 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-02T06:17:35.807Z] 06:17:35 INFO - PID 8752 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-02T06:17:35.808Z] 06:17:35 INFO - PID 8752 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-02T06:17:35.808Z] 06:17:35 INFO - PID 8752 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-02T06:17:35.809Z] 06:17:35 INFO - PID 8752 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-02T06:17:35.809Z] 06:17:35 INFO - PID 8752 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-02T06:17:35.816Z] 06:17:35 INFO - PID 8752 | 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-02-02T06:17:35.817Z] 06:17:35 INFO - PID 8752 | 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-02-02T06:17:35.818Z] 06:17:35 INFO - PID 8752 | 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-02-02T06:17:35.819Z] 06:17:35 INFO - PID 8752 | 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-02-02T06:17:35.820Z] 06:17:35 INFO - PID 8752 | 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-02-02T06:17:35.881Z] 06:17:35 INFO - Browser exited with return code 0
[task 2023-02-02T06:17:35.885Z] 06:17:35 INFO - Closing logging queue
[task 2023-02-02T06:17:35.885Z] 06:17:35 INFO - queue closed
[task 2023-02-02T06:17:35.965Z] 06:17:35 INFO - Application command: Z:\task_167531457297912\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167531457297912\AppData\Local\Temp\tmp6rc84uwy
[task 2023-02-02T06:17:35.981Z] 06:17:35 INFO - PID 7860 | 1675318636528 Marionette INFO Marionette enabled
[task 2023-02-02T06:17:35.981Z] 06:17:35 INFO - PID 7860 | 1675318636532 Marionette INFO Listening on port 51508
[task 2023-02-02T06:17:35.982Z] 06:17:35 INFO - PID 7860 | JavaScript error: resource://gre/modules/XULStore.jsm, line 58: Error: Can't find profile directory.
[task 2023-02-02T06:17:35.983Z] 06:17:35 INFO - PID 7860 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-02T06:17:35.983Z] 06:17:35 INFO - PID 7860 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167531457297912\\AppData\\Local\\Temp\\tmp24j_2ri4\\search.json.mozlz4", (void 0)))
[task 2023-02-02T06:17:35.984Z] 06:17:35 INFO - PID 7860 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-02T06:17:35.985Z] 06:17:35 INFO - PID 7860 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-02T06:17:35.985Z] 06:17:35 INFO - PID 7860 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-02T06:17:35.986Z] 06:17:35 INFO - Starting runner
[task 2023-02-02T06:17:37.007Z] 06:17:37 INFO - TEST-START | /cookie-store/encoding.https.any.serviceworker.html
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•