1808536 - Intermittent /webstorage/defineProperty.window.html | single tracking bug

Status: RESOLVED INCOMPLETE

(Core :: Storage: localStorage & sessionStorage, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2023-01-04T12:42:21.414Z] 12:42:21     INFO - TEST-START | /webstorage/defineProperty.window.html
[task 2023-01-04T12:42:21.558Z] 12:42:21     INFO - PID 7196 | console.error: (new TypeError("this._searchProviderInfo is null", "resource:///modules/SearchSERPTelemetry.sys.mjs", 461))
[task 2023-01-04T12:42:29.415Z] 12:42:29     INFO - PID 7196 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2023-01-04T12:42:29.420Z] 12:42:29     INFO - PID 7196 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2023-01-04T12:42:29.421Z] 12:42:29     INFO - PID 7196 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2023-01-04T12:42:36.446Z] 12:42:36     INFO - TEST-UNEXPECTED-TIMEOUT | /webstorage/defineProperty.window.html | expected OK
[task 2023-01-04T12:42:36.446Z] 12:42:36     INFO - TEST-INFO took 15032ms
[task 2023-01-04T12:42:36.459Z] 12:42:36     INFO - PID 7196 | 1672836156453	Marionette	INFO	Stopped listening on port 60820
[task 2023-01-04T12:42:37.356Z] 12:42:37     INFO - PID 7196 | console.log: "RemoteSettingsWorker error: AbortError: IndexedDB:  execute() A request was aborted, for example through a call to IDBTransaction.abort."
[task 2023-01-04T12:42:37.370Z] 12:42:37     INFO - PID 7196 | console.error: (new Error("IndexedDB: main/doh-providers list() IndexedDB:  execute() The application is shutting down", "resource://services-settings/IDBHelpers.jsm", 18))
[task 2023-01-04T12:42:37.371Z] 12:42:37     INFO - PID 7196 | console.log: "RemoteSettingsWorker error: AbortError: IndexedDB:  execute() A request was aborted, for example through a call to IDBTransaction.abort."
[task 2023-01-04T12:42:37.372Z] 12:42:37     INFO - PID 7196 | console.error: (new RemoteSettingsWorkerError("AbortError: IndexedDB:  execute() A request was aborted, for example through a call to IDBTransaction.abort.", "resource://services-settings/RemoteSettingsWorker.jsm", 46))
[task 2023-01-04T12:42:37.373Z] 12:42:37     INFO - PID 7196 | console.error: (new RemoteSettingsWorkerError("AbortError: IndexedDB:  execute() A request was aborted, for example through a call to IDBTransaction.abort.", "resource://services-settings/RemoteSettingsWorker.jsm", 46))
[task 2023-01-04T12:42:37.374Z] 12:42:37     INFO - PID 7196 | console.error: (new Error("IndexedDB: main/doh-config getLastModified() IndexedDB:  execute() The application is shutting down", "resource://services-settings/IDBHelpers.jsm", 18))
[task 2023-01-04T12:42:37.375Z] 12:42:37     INFO - PID 7196 | console.log: "RemoteSettingsWorker error: AbortError: IndexedDB:  execute() A request was aborted, for example through a call to IDBTransaction.abort."
[task 2023-01-04T12:42:37.380Z] 12:42:37     INFO - PID 7196 | console.error: (new RemoteSettingsWorkerError("AbortError: IndexedDB:  execute() A request was aborted, for example through a call to IDBTransaction.abort.", "resource://services-settings/RemoteSettingsWorker.jsm", 46))
[task 2023-01-04T12:42:42.040Z] 12:42:42     INFO - PID 7196 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-01-04T12:42:42.043Z] 12:42:42     INFO - PID 7196 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.jsm", 46))
[task 2023-01-04T12:42:46.184Z] 12:42:46     INFO - PID 7196 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-01-04T12:42:46.186Z] 12:42:46     INFO - PID 7196 |           Blocker:  Waiting for ping task
[task 2023-01-04T12:42:46.186Z] 12:42:46     INFO - PID 7196 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-01-04T12:42:46.187Z] 12:42:46     INFO - PID 7196 |           State: (none)
[task 2023-01-04T12:42:46.188Z] 12:42:46     INFO - PID 7196 | 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-04T12:42:46.189Z] 12:42:46     INFO - PID 7196 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:712:15
[task 2023-01-04T12:42:46.189Z] 12:42:46     INFO - PID 7196 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2023-01-04T12:42:46.190Z] 12:42:46     INFO - PID 7196 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2023-01-04T12:42:46.191Z] 12:42:46     INFO - PID 7196 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-04T12:42:46.191Z] 12:42:46     INFO - PID 7196 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-04T12:42:46.192Z] 12:42:46     INFO - PID 7196 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-04T12:42:46.192Z] 12:42:46     INFO - PID 7196 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-04T12:42:46.192Z] 12:42:46     INFO - PID 7196 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-04T12:42:46.193Z] 12:42:46     INFO - PID 7196 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-04T12:42:46.194Z] 12:42:46     INFO - PID 7196 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-04T12:42:46.194Z] 12:42:46     INFO - PID 7196 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-04T12:42:46.195Z] 12:42:46     INFO - PID 7196 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2023-01-04T12:42:46.196Z] 12:42:46     INFO - PID 7196 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-01-04T12:42:46.196Z] 12:42:46     INFO - PID 7196 |           Blocker:  Waiting for ping task
[task 2023-01-04T12:42:46.197Z] 12:42:46     INFO - PID 7196 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-01-04T12:42:46.198Z] 12:42:46     INFO - PID 7196 |           State: (none)
[task 2023-01-04T12:42:46.198Z] 12:42:46     INFO - PID 7196 | 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-04T12:42:46.199Z] 12:42:46     INFO - PID 7196 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:712:15
[task 2023-01-04T12:42:46.200Z] 12:42:46     INFO - PID 7196 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2023-01-04T12:42:46.200Z] 12:42:46     INFO - PID 7196 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2023-01-04T12:42:46.201Z] 12:42:46     INFO - PID 7196 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-04T12:42:46.201Z] 12:42:46     INFO - PID 7196 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-04T12:42:46.202Z] 12:42:46     INFO - PID 7196 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-04T12:42:46.202Z] 12:42:46     INFO - PID 7196 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-04T12:42:46.203Z] 12:42:46     INFO - PID 7196 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-04T12:42:46.204Z] 12:42:46     INFO - PID 7196 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-04T12:42:46.204Z] 12:42:46     INFO - PID 7196 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-04T12:42:46.205Z] 12:42:46     INFO - PID 7196 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-04T12:42:46.205Z] 12:42:46     INFO - PID 7196 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2023-01-04T12:42:46.206Z] 12:42:46     INFO - PID 7196 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-01-04T12:42:46.206Z] 12:42:46     INFO - PID 7196 |           Blocker:  TelemetryController: shutting down
[task 2023-01-04T12:42:46.207Z] 12:42:46     INFO - PID 7196 |           Phase: profile-before-change-telemetry
[task 2023-01-04T12:42:46.207Z] 12:42:46     INFO - PID 7196 |           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:712:15
[task 2023-01-04T12:42:46.208Z] 12:42:46     INFO - PID 7196 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2023-01-04T12:42:46.208Z] 12:42:46     INFO - PID 7196 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2023-01-04T12:42:46.209Z] 12:42:46     INFO - PID 7196 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-04T12:42:46.210Z] 12:42:46     INFO - PID 7196 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-04T12:42:46.210Z] 12:42:46     INFO - PID 7196 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-04T12:42:46.211Z] 12:42:46     INFO - PID 7196 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-04T12:42:46.211Z] 12:42:46     INFO - PID 7196 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-04T12:42:46.212Z] 12:42:46     INFO - PID 7196 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-04T12:42:46.212Z] 12:42:46     INFO - PID 7196 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-04T12:42:46.213Z] 12:42:46     INFO - PID 7196 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-04T12:42:46.213Z] 12:42:46     INFO - PID 7196 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2023-01-04T12:42:46.214Z] 12:42:46     INFO - PID 7196 | 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-04T12:42:46.215Z] 12:42:46     INFO - PID 7196 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:712:15
[task 2023-01-04T12:42:46.215Z] 12:42:46     INFO - PID 7196 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2023-01-04T12:42:46.215Z] 12:42:46     INFO - PID 7196 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2023-01-04T12:42:46.216Z] 12:42:46     INFO - PID 7196 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-04T12:42:46.216Z] 12:42:46     INFO - PID 7196 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-04T12:42:46.216Z] 12:42:46     INFO - PID 7196 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-04T12:42:46.217Z] 12:42:46     INFO - PID 7196 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-04T12:42:46.218Z] 12:42:46     INFO - PID 7196 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-04T12:42:46.218Z] 12:42:46     INFO - PID 7196 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-04T12:42:46.219Z] 12:42:46     INFO - PID 7196 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-04T12:42:46.219Z] 12:42:46     INFO - PID 7196 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-04T12:42:46.219Z] 12:42:46     INFO - PID 7196 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2023-01-04T12:42:46.771Z] 12:42:46     INFO - PID 7196 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-04T12:42:46.774Z] 12:42:46     INFO - PID 7196 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-04T12:42:46.774Z] 12:42:46     INFO - PID 7196 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-04T12:42:46.775Z] 12:42:46     INFO - PID 7196 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-04T12:42:46.776Z] 12:42:46     INFO - PID 7196 | JavaScript error: resource://gre/modules/AsyncShutdown.sys.mjs, line 712: 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-04T12:42:46.876Z] 12:42:46     INFO - Browser exited with return code 0
[task 2023-01-04T12:42:46.880Z] 12:42:46     INFO - Closing logging queue
[task 2023-01-04T12:42:46.880Z] 12:42:46     INFO - queue closed
[task 2023-01-04T12:42:46.908Z] 12:42:46     INFO - Application command: Z:\task_167283083546467\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167283083546467\AppData\Local\Temp\tmpie0uqedn
[task 2023-01-04T12:42:46.920Z] 12:42:46     INFO - PID 5504 | 1672836140777	Marionette	INFO	Marionette enabled
[task 2023-01-04T12:42:46.920Z] 12:42:46     INFO - PID 5504 | 1672836140779	Marionette	INFO	Listening on port 60949
[task 2023-01-04T12:42:46.921Z] 12:42:46     INFO - PID 5504 | JavaScript error: resource://gre/modules/XULStore.jsm, line 58: Error: Can't find profile directory.
[task 2023-01-04T12:42:46.922Z] 12:42:46     INFO - PID 5504 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167283083546467\\AppData\\Local\\Temp\\tmprax3ni55\\search.json.mozlz4", (void 0)))
[task 2023-01-04T12:42:46.923Z] 12:42:46     INFO - PID 5504 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2023-01-04T12:42:46.923Z] 12:42:46     INFO - Starting runner
[task 2023-01-04T12:42:47.815Z] 12:42:47     INFO - PID 5504 | console.error: (new TypeError("this._searchProviderInfo is null", "resource:///modules/SearchSERPTelemetry.sys.mjs", 461))
[task 2023-01-04T12:42:47.852Z] 12:42:47     INFO - TEST-START | /webstorage/document-domain.html

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

• windows10-64-2004-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1808536&startday=2023-01-02&endday=2023-01-08&tree=all

Comment 2

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

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

Comment 3

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=412196095&repo=mozilla-beta

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

• windows11-32-2009-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1808536&startday=2023-04-10&endday=2023-04-16&tree=all

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• windows11-64-2009-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1808536&startday=2023-05-08&endday=2023-05-14&tree=all

Comment 6

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

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