Open Bug 1818256 Opened 1 year ago Updated 1 month ago

Intermittent /fetch/api/headers/header-setcookie.any.serviceworker.html | single tracking bug

Categories

(Core :: DOM: Networking, defect, P5)

defect

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure, intermittent-testcase, Whiteboard: [necko-triaged])

Attachments

(1 obsolete file)

Filed by: ctuns [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=406539606&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ULQ67IDbRl69A2LVV-pj5Q/runs/0/artifacts/public/logs/live_backing.log


[task 2023-02-22T15:55:27.802Z] 15:55:27     INFO - TEST-START | /fetch/api/headers/header-setcookie.any.serviceworker.html
[task 2023-02-22T15:55:27.864Z] 15:55:27     INFO - PID 7188 | console.error: (new TypeError("this._searchProviderInfo is null", "resource:///modules/SearchSERPTelemetry.sys.mjs", 465))
[task 2023-02-22T15:55:27.960Z] 15:55:27     INFO - PID 7188 | console.error: (new TypeError("this._searchProviderInfo is null", "resource:///modules/SearchSERPTelemetry.sys.mjs", 465))
[task 2023-02-22T15:55:28.766Z] 15:55:28     INFO - PID 7188 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-22T15:55:38.001Z] 15:55:38     INFO - TEST-UNEXPECTED-TIMEOUT | /fetch/api/headers/header-setcookie.any.serviceworker.html | expected OK
[task 2023-02-22T15:55:38.001Z] 15:55:38     INFO - TEST-INFO took 10199ms
[task 2023-02-22T15:55:38.006Z] 15:55:38     INFO - PID 7188 | 1677081338005	Marionette	INFO	Stopped listening on port 55144
[task 2023-02-22T15:55:40.437Z] 15:55:40     INFO - PID 7188 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-02-22T15:55:40.442Z] 15:55:40     INFO - PID 7188 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.sys.mjs", 39))
[task 2023-02-22T15:55:40.805Z] 15:55:40     INFO - PID 7188 | JavaScript error: resource://gre/modules/JSONFile.sys.mjs, line 184: Error: Data is not ready.
[task 2023-02-22T15:55:41.520Z] 15:55:41     INFO - PID 7188 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-02-22T15:55:41.529Z] 15:55:41     INFO - PID 7188 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.sys.mjs", 39))
[task 2023-02-22T15:55:41.530Z] 15:55:41     INFO - PID 7188 | console.error: (new Error("IndexedDB: main/doh-config getLastModified() IndexedDB:  execute() The application is shutting down", "resource://services-settings/IDBHelpers.jsm", 18))
[task 2023-02-22T15:55:41.531Z] 15:55:41     INFO - PID 7188 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-02-22T15:55:41.532Z] 15:55:41     INFO - PID 7188 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.sys.mjs", 39))
[task 2023-02-22T15:55:41.532Z] 15:55:41     INFO - PID 7188 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-02-22T15:55:41.533Z] 15:55:41     INFO - PID 7188 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.sys.mjs", 39))
[task 2023-02-22T15:55:41.535Z] 15:55:41     INFO - PID 7188 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-02-22T15:55:41.538Z] 15:55:41     INFO - PID 7188 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.sys.mjs", 39))
[task 2023-02-22T15:55:41.538Z] 15:55:41     INFO - PID 7188 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-02-22T15:55:41.539Z] 15:55:41     INFO - PID 7188 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.sys.mjs", 39))
[task 2023-02-22T15:55:43.149Z] 15:55:43     INFO - PID 7188 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-22T15:55:43.157Z] 15:55:43     INFO - PID 7188 |           Blocker:  Waiting for ping task
[task 2023-02-22T15:55:43.158Z] 15:55:43     INFO - PID 7188 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-02-22T15:55:43.158Z] 15:55:43     INFO - PID 7188 |           State: (none)
[task 2023-02-22T15:55:43.159Z] 15:55:43     INFO - PID 7188 | 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-22T15:55:43.159Z] 15:55:43     INFO - PID 7188 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-22T15:55:43.161Z] 15:55:43     INFO - PID 7188 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-22T15:55:43.161Z] 15:55:43     INFO - PID 7188 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-22T15:55:43.162Z] 15:55:43     INFO - PID 7188 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-22T15:55:43.162Z] 15:55:43     INFO - PID 7188 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-22T15:55:43.163Z] 15:55:43     INFO - PID 7188 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-22T15:55:43.163Z] 15:55:43     INFO - PID 7188 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-22T15:55:43.164Z] 15:55:43     INFO - PID 7188 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-22T15:55:43.164Z] 15:55:43     INFO - PID 7188 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-22T15:55:43.165Z] 15:55:43     INFO - PID 7188 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-22T15:55:43.166Z] 15:55:43     INFO - PID 7188 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:828:36
[task 2023-02-22T15:55:43.167Z] 15:55:43     INFO - PID 7188 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-22T15:55:43.167Z] 15:55:43     INFO - PID 7188 | console.error: "TelemetryScheduler.shutdown - Already shut down"
[task 2023-02-22T15:55:43.169Z] 15:55:43     INFO - PID 7188 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-22T15:55:43.169Z] 15:55:43     INFO - PID 7188 |           Blocker:  Waiting for ping task
[task 2023-02-22T15:55:43.170Z] 15:55:43     INFO - PID 7188 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-02-22T15:55:43.170Z] 15:55:43     INFO - PID 7188 |           State: (none)
[task 2023-02-22T15:55:43.171Z] 15:55:43     INFO - PID 7188 | 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-22T15:55:43.171Z] 15:55:43     INFO - PID 7188 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-22T15:55:43.172Z] 15:55:43     INFO - PID 7188 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-22T15:55:43.173Z] 15:55:43     INFO - PID 7188 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-22T15:55:43.174Z] 15:55:43     INFO - PID 7188 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-22T15:55:43.174Z] 15:55:43     INFO - PID 7188 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-22T15:55:43.175Z] 15:55:43     INFO - PID 7188 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-22T15:55:43.176Z] 15:55:43     INFO - PID 7188 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-22T15:55:43.176Z] 15:55:43     INFO - PID 7188 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-22T15:55:43.177Z] 15:55:43     INFO - PID 7188 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-22T15:55:43.177Z] 15:55:43     INFO - PID 7188 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-22T15:55:43.178Z] 15:55:43     INFO - PID 7188 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:828:36
[task 2023-02-22T15:55:43.178Z] 15:55:43     INFO - PID 7188 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-22T15:55:43.179Z] 15:55:43     INFO - PID 7188 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-22T15:55:43.179Z] 15:55:43     INFO - PID 7188 |           Blocker:  TelemetryController: shutting down
[task 2023-02-22T15:55:43.180Z] 15:55:43     INFO - PID 7188 |           Phase: profile-before-change-telemetry
[task 2023-02-22T15:55:43.181Z] 15:55:43     INFO - PID 7188 |           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-22T15:55:43.182Z] 15:55:43     INFO - PID 7188 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-22T15:55:43.182Z] 15:55:43     INFO - PID 7188 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-22T15:55:43.183Z] 15:55:43     INFO - PID 7188 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-22T15:55:43.183Z] 15:55:43     INFO - PID 7188 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-22T15:55:43.183Z] 15:55:43     INFO - PID 7188 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-22T15:55:43.184Z] 15:55:43     INFO - PID 7188 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-22T15:55:43.184Z] 15:55:43     INFO - PID 7188 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-22T15:55:43.185Z] 15:55:43     INFO - PID 7188 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-22T15:55:43.185Z] 15:55:43     INFO - PID 7188 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-22T15:55:43.185Z] 15:55:43     INFO - PID 7188 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:828:36
[task 2023-02-22T15:55:43.186Z] 15:55:43     INFO - PID 7188 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-22T15:55:43.188Z] 15:55:43     INFO - PID 7188 | 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-22T15:55:43.188Z] 15:55:43     INFO - PID 7188 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-22T15:55:43.189Z] 15:55:43     INFO - PID 7188 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-22T15:55:43.189Z] 15:55:43     INFO - PID 7188 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-22T15:55:43.190Z] 15:55:43     INFO - PID 7188 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-22T15:55:43.191Z] 15:55:43     INFO - PID 7188 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-22T15:55:43.191Z] 15:55:43     INFO - PID 7188 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-22T15:55:43.192Z] 15:55:43     INFO - PID 7188 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-22T15:55:43.193Z] 15:55:43     INFO - PID 7188 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-22T15:55:43.193Z] 15:55:43     INFO - PID 7188 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-22T15:55:43.193Z] 15:55:43     INFO - PID 7188 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-22T15:55:43.194Z] 15:55:43     INFO - PID 7188 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:828:36
[task 2023-02-22T15:55:43.194Z] 15:55:43     INFO - PID 7188 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-22T15:55:43.435Z] 15:55:43     INFO - PID 7188 | 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-22T15:55:43.436Z] 15:55:43     INFO - PID 7188 | 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-22T15:55:43.437Z] 15:55:43     INFO - PID 7188 | 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-22T15:55:43.438Z] 15:55:43     INFO - PID 7188 | 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-22T15:55:43.439Z] 15:55:43     INFO - PID 7188 | 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-22T15:55:43.499Z] 15:55:43     INFO - Browser exited with return code 0
[task 2023-02-22T15:55:43.504Z] 15:55:43     INFO - Closing logging queue
[task 2023-02-22T15:55:43.504Z] 15:55:43     INFO - queue closed
[task 2023-02-22T15:55:43.531Z] 15:55:43     INFO - Application command: Z:\task_167707918040350\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167707918040350\AppData\Local\Temp\tmpiqugr6i4
[task 2023-02-22T15:55:43.546Z] 15:55:43     INFO - PID 1468 | 1677081324743	Marionette	INFO	Marionette enabled
[task 2023-02-22T15:55:43.547Z] 15:55:43     INFO - PID 1468 | 1677081324745	Marionette	INFO	Listening on port 55173
[task 2023-02-22T15:55:43.548Z] 15:55:43     INFO - PID 1468 | JavaScript error: resource://gre/modules/XULStore.jsm, line 58: Error: Can't find profile directory.
[task 2023-02-22T15:55:43.548Z] 15:55:43     INFO - PID 1468 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167707918040350\\AppData\\Local\\Temp\\tmpfaqlku2x\\search.json.mozlz4", (void 0)))
[task 2023-02-22T15:55:43.550Z] 15:55:43     INFO - PID 1468 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-22T15:55:43.550Z] 15:55:43     INFO - Starting runner
[task 2023-02-22T15:55:44.098Z] 15:55:44     INFO - PID 1468 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-22T15:55:44.101Z] 15:55:44     INFO - PID 1468 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-22T15:55:44.102Z] 15:55:44     INFO - PID 1468 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-22T15:55:44.516Z] 15:55:44     INFO - TEST-START | /fetch/api/headers/header-setcookie.any.sharedworker.html
Status: NEW → RESOLVED
Closed: 11 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 11 months ago10 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 10 months ago9 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 9 months ago8 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Whiteboard: [necko-triaged]
Blocks: fetch
Status: REOPENED → NEW
Attachment #9383543 - Attachment is obsolete: true
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: