Closed Bug 1815087 Opened 2 years ago Closed 2 years ago

Intermittent [tier 2] /compression/compression-constructor-error.tentative.any.serviceworker.html | single tracking bug

Categories

(Testing :: web-platform-tests, defect, P5)

defect

Tracking

(Not tracked)

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

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

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


[task 2023-02-04T23:13:51.959Z] 23:13:51     INFO - TEST-START | /compression/compression-constructor-error.tentative.any.serviceworker.html
[task 2023-02-04T23:13:51.960Z] 23:13:51     INFO - Closing window 1d926fc7-61cd-47cd-9103-99341992bf8b
[task 2023-02-04T23:13:51.987Z] 23:13:51     INFO - PID 3440 | console.error: (new TypeError("can't access property \"find\", this._searchProviderInfo is null", "resource:///modules/SearchSERPTelemetry.sys.mjs", 461))
[task 2023-02-04T23:13:52.060Z] 23:13:52     INFO - PID 3440 | console.error: (new TypeError("can't access property \"find\", this._searchProviderInfo is null", "resource:///modules/SearchSERPTelemetry.sys.mjs", 461))
[task 2023-02-04T23:13:54.592Z] 23:13:54     INFO - TEST-UNEXPECTED-TIMEOUT | /compression/compression-constructor-error.tentative.any.serviceworker.html | expected OK
[task 2023-02-04T23:13:54.592Z] 23:13:54     INFO - TEST-INFO took 2647ms
[task 2023-02-04T23:13:54.596Z] 23:13:54     INFO - PID 3440 | 1675552434595	Marionette	INFO	Stopped listening on port 51231
[task 2023-02-04T23:13:56.786Z] 23:13:56     INFO - PID 3440 | console.error: (new AbortError("IndexedDB: main/cookie-banner-rules-list list() IndexedDB:  execute() A request was aborted, for example through a call to IDBTransaction.abort.", "resource://services-settings/IDBHelpers.jsm", 18))
[task 2023-02-04T23:13:56.796Z] 23:13:56     INFO - PID 3440 | console.error: (new AbortError("IndexedDB: blocklists/gfx list() IndexedDB:  execute() A request was aborted, for example through a call to IDBTransaction.abort.", "resource://services-settings/IDBHelpers.jsm", 18))
[task 2023-02-04T23:13:56.797Z] 23:13:56     INFO - PID 3440 | console.error: (new AbortError("IndexedDB: main/url-classifier-skip-urls list() IndexedDB:  execute() A request was aborted, for example through a call to IDBTransaction.abort.", "resource://services-settings/IDBHelpers.jsm", 18))
[task 2023-02-04T23:13:56.797Z] 23:13:56     INFO - PID 3440 | console.error: (new AbortError("IndexedDB: main/doh-config list() IndexedDB:  execute() A request was aborted, for example through a call to IDBTransaction.abort.", "resource://services-settings/IDBHelpers.jsm", 18))
[task 2023-02-04T23:14:00.746Z] 23:14:00     INFO - PID 3440 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-04T23:14:00.746Z] 23:14:00     INFO - PID 3440 |           Blocker:  Waiting for ping task
[task 2023-02-04T23:14:00.754Z] 23:14:00     INFO - PID 3440 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-02-04T23:14:00.754Z] 23:14:00     INFO - PID 3440 |           State: (none)
[task 2023-02-04T23:14:00.755Z] 23:14:00     INFO - PID 3440 | 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-04T23:14:00.755Z] 23:14:00     INFO - PID 3440 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-04T23:14:00.756Z] 23:14:00     INFO - PID 3440 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-04T23:14:00.756Z] 23:14:00     INFO - PID 3440 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-04T23:14:00.757Z] 23:14:00     INFO - PID 3440 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-04T23:14:00.758Z] 23:14:00     INFO - PID 3440 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-04T23:14:00.758Z] 23:14:00     INFO - PID 3440 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-04T23:14:00.759Z] 23:14:00     INFO - PID 3440 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-04T23:14:00.759Z] 23:14:00     INFO - PID 3440 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-04T23:14:00.760Z] 23:14:00     INFO - PID 3440 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-04T23:14:00.760Z] 23:14:00     INFO - PID 3440 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-04T23:14:00.761Z] 23:14:00     INFO - PID 3440 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-04T23:14:00.762Z] 23:14:00     INFO - PID 3440 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-04T23:14:00.762Z] 23:14:00     INFO - PID 3440 | console.error: "TelemetryScheduler.shutdown - Already shut down"
[task 2023-02-04T23:14:00.763Z] 23:14:00     INFO - PID 3440 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-04T23:14:00.763Z] 23:14:00     INFO - PID 3440 |           Blocker:  Waiting for ping task
[task 2023-02-04T23:14:00.764Z] 23:14:00     INFO - PID 3440 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-02-04T23:14:00.764Z] 23:14:00     INFO - PID 3440 |           State: (none)
[task 2023-02-04T23:14:00.765Z] 23:14:00     INFO - PID 3440 | 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-04T23:14:00.765Z] 23:14:00     INFO - PID 3440 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-04T23:14:00.766Z] 23:14:00     INFO - PID 3440 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-04T23:14:00.766Z] 23:14:00     INFO - PID 3440 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-04T23:14:00.767Z] 23:14:00     INFO - PID 3440 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-04T23:14:00.767Z] 23:14:00     INFO - PID 3440 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-04T23:14:00.768Z] 23:14:00     INFO - PID 3440 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-04T23:14:00.769Z] 23:14:00     INFO - PID 3440 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-04T23:14:00.769Z] 23:14:00     INFO - PID 3440 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-04T23:14:00.770Z] 23:14:00     INFO - PID 3440 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-04T23:14:00.770Z] 23:14:00     INFO - PID 3440 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-04T23:14:00.771Z] 23:14:00     INFO - PID 3440 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-04T23:14:00.771Z] 23:14:00     INFO - PID 3440 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-04T23:14:00.772Z] 23:14:00     INFO - PID 3440 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-04T23:14:00.772Z] 23:14:00     INFO - PID 3440 |           Blocker:  TelemetryController: shutting down
[task 2023-02-04T23:14:00.772Z] 23:14:00     INFO - PID 3440 |           Phase: profile-before-change-telemetry
[task 2023-02-04T23:14:00.773Z] 23:14:00     INFO - PID 3440 |           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-04T23:14:00.774Z] 23:14:00     INFO - PID 3440 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-04T23:14:00.774Z] 23:14:00     INFO - PID 3440 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-04T23:14:00.775Z] 23:14:00     INFO - PID 3440 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-04T23:14:00.776Z] 23:14:00     INFO - PID 3440 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-04T23:14:00.776Z] 23:14:00     INFO - PID 3440 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-04T23:14:00.777Z] 23:14:00     INFO - PID 3440 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-04T23:14:00.777Z] 23:14:00     INFO - PID 3440 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-04T23:14:00.778Z] 23:14:00     INFO - PID 3440 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-04T23:14:00.778Z] 23:14:00     INFO - PID 3440 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-04T23:14:00.779Z] 23:14:00     INFO - PID 3440 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-04T23:14:00.779Z] 23:14:00     INFO - PID 3440 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-04T23:14:00.780Z] 23:14:00     INFO - PID 3440 | 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-04T23:14:00.781Z] 23:14:00     INFO - PID 3440 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-04T23:14:00.781Z] 23:14:00     INFO - PID 3440 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-04T23:14:00.781Z] 23:14:00     INFO - PID 3440 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-04T23:14:00.782Z] 23:14:00     INFO - PID 3440 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-04T23:14:00.783Z] 23:14:00     INFO - PID 3440 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-04T23:14:00.783Z] 23:14:00     INFO - PID 3440 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-04T23:14:00.784Z] 23:14:00     INFO - PID 3440 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-04T23:14:00.784Z] 23:14:00     INFO - PID 3440 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-04T23:14:00.785Z] 23:14:00     INFO - PID 3440 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-04T23:14:00.785Z] 23:14:00     INFO - PID 3440 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-04T23:14:00.786Z] 23:14:00     INFO - PID 3440 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-04T23:14:00.786Z] 23:14:00     INFO - PID 3440 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-04T23:14:00.926Z] 23:14:00     INFO - PID 3440 | 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-04T23:14:00.929Z] 23:14:00     INFO - PID 3440 | 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-04T23:14:00.929Z] 23:14:00     INFO - PID 3440 | 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-04T23:14:00.930Z] 23:14:00     INFO - PID 3440 | 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-04T23:14:00.931Z] 23:14:00     INFO - PID 3440 | 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-04T23:14:00.993Z] 23:14:00     INFO - Browser exited with return code 0
[task 2023-02-04T23:14:00.997Z] 23:14:00     INFO - Closing logging queue
[task 2023-02-04T23:14:00.997Z] 23:14:00     INFO - queue closed
[task 2023-02-04T23:14:01.024Z] 23:14:01     INFO - Application command: Z:\task_167554646045537\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167554646045537\AppData\Local\Temp\tmp3y7rw_hv
[task 2023-02-04T23:14:01.037Z] 23:14:01     INFO - PID 8452 | 1675552428358	Marionette	INFO	Marionette enabled
[task 2023-02-04T23:14:01.040Z] 23:14:01     INFO - PID 8452 | 1675552428362	Marionette	INFO	Listening on port 51297
[task 2023-02-04T23:14:01.041Z] 23:14:01     INFO - PID 8452 | JavaScript error: resource://gre/modules/XULStore.jsm, line 58: Error: Can't find profile directory.
[task 2023-02-04T23:14:01.042Z] 23:14:01     INFO - PID 8452 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167554646045537\\AppData\\Local\\Temp\\tmpkwfqlqiy\\search.json.mozlz4", (void 0)))
[task 2023-02-04T23:14:01.043Z] 23:14:01     INFO - Starting runner
[task 2023-02-04T23:14:01.085Z] 23:14:01     INFO - PID 8452 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-04T23:14:01.954Z] 23:14:01     INFO - PID 8452 | console.error: (new TypeError("can't access property \"find\", this._searchProviderInfo is null", "resource:///modules/SearchSERPTelemetry.sys.mjs", 461))
[task 2023-02-04T23:14:01.970Z] 23:14:01     INFO - TEST-START | /compression/compression-constructor-error.tentative.any.html

First occurrence, so far: backfill range and retriggers.

Whiteboard: [retriggered]
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 2 years ago2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.