Closed Bug 1808698 Opened 3 years ago Closed 3 years ago

Perma /service-workers/service-worker/installing.https.html | single tracking bug

Categories

(Core :: DOM: Service Workers, defect, P5)

defect

Tracking

()

RESOLVED FIXED
110 Branch
Tracking Status
firefox-esr102 --- unaffected
firefox108 --- unaffected
firefox109 --- unaffected
firefox110 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: jgraham)

References

(Regression)

Details

(Keywords: intermittent-failure, intermittent-testcase, regression)

Attachments

(1 file)

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


[task 2023-01-05T12:28:03.909Z] 12:28:03     INFO - TEST-START | /service-workers/service-worker/installing.https.html
[task 2023-01-05T12:28:03.914Z] 12:28:03     INFO - Closing window d5a0b83d-0f18-4560-b1b1-39dc7cf5bf82
[task 2023-01-05T12:28:04.211Z] 12:28:04     INFO - 
[task 2023-01-05T12:28:04.211Z] 12:28:04     INFO - TEST-UNEXPECTED-FAIL | /service-workers/service-worker/installing.https.html | installing is set - assert_equals: controller expected null but got object "[object ServiceWorker]"
[task 2023-01-05T12:28:04.211Z] 12:28:04     INFO - @https://web-platform.test:8443/service-workers/service-worker/installing.https.html:27:16
[task 2023-01-05T12:28:04.213Z] 12:28:04     INFO - .
[task 2023-01-05T12:28:04.214Z] 12:28:04     INFO - TEST-OK | /service-workers/service-worker/installing.https.html | took 316ms
[task 2023-01-05T12:28:04.215Z] 12:28:04     INFO - PID 6656 | 1672921684214	Marionette	INFO	Stopped listening on port 55746
[task 2023-01-05T12:28:04.587Z] 12:28:04     INFO - PID 6656 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-01-05T12:28:04.590Z] 12:28:04     INFO - PID 6656 |           Blocker:  Waiting for ping task
[task 2023-01-05T12:28:04.591Z] 12:28:04     INFO - PID 6656 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-01-05T12:28:04.591Z] 12:28:04     INFO - PID 6656 |           State: (none)
[task 2023-01-05T12:28:04.592Z] 12:28:04     INFO - PID 6656 | 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-05T12:28:04.593Z] 12:28:04     INFO - PID 6656 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:712:15
[task 2023-01-05T12:28:04.593Z] 12:28:04     INFO - PID 6656 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2023-01-05T12:28:04.594Z] 12:28:04     INFO - PID 6656 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2023-01-05T12:28:04.594Z] 12:28:04     INFO - PID 6656 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-05T12:28:04.595Z] 12:28:04     INFO - PID 6656 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-05T12:28:04.596Z] 12:28:04     INFO - PID 6656 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-05T12:28:04.596Z] 12:28:04     INFO - PID 6656 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-05T12:28:04.597Z] 12:28:04     INFO - PID 6656 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-05T12:28:04.597Z] 12:28:04     INFO - PID 6656 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-05T12:28:04.598Z] 12:28:04     INFO - PID 6656 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-05T12:28:04.598Z] 12:28:04     INFO - PID 6656 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-05T12:28:04.599Z] 12:28:04     INFO - PID 6656 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2023-01-05T12:28:04.601Z] 12:28:04     INFO - PID 6656 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-01-05T12:28:04.601Z] 12:28:04     INFO - PID 6656 |           Blocker:  Waiting for ping task
[task 2023-01-05T12:28:04.602Z] 12:28:04     INFO - PID 6656 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-01-05T12:28:04.603Z] 12:28:04     INFO - PID 6656 |           State: (none)
[task 2023-01-05T12:28:04.604Z] 12:28:04     INFO - PID 6656 | 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-05T12:28:04.604Z] 12:28:04     INFO - PID 6656 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:712:15
[task 2023-01-05T12:28:04.605Z] 12:28:04     INFO - PID 6656 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2023-01-05T12:28:04.605Z] 12:28:04     INFO - PID 6656 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2023-01-05T12:28:04.606Z] 12:28:04     INFO - PID 6656 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-05T12:28:04.606Z] 12:28:04     INFO - PID 6656 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-05T12:28:04.607Z] 12:28:04     INFO - PID 6656 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-05T12:28:04.607Z] 12:28:04     INFO - PID 6656 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-05T12:28:04.608Z] 12:28:04     INFO - PID 6656 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-05T12:28:04.608Z] 12:28:04     INFO - PID 6656 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-05T12:28:04.609Z] 12:28:04     INFO - PID 6656 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-05T12:28:04.610Z] 12:28:04     INFO - PID 6656 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-05T12:28:04.610Z] 12:28:04     INFO - PID 6656 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2023-01-05T12:28:04.611Z] 12:28:04     INFO - PID 6656 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-01-05T12:28:04.612Z] 12:28:04     INFO - PID 6656 |           Blocker:  TelemetryController: shutting down
[task 2023-01-05T12:28:04.612Z] 12:28:04     INFO - PID 6656 |           Phase: profile-before-change-telemetry
[task 2023-01-05T12:28:04.613Z] 12:28:04     INFO - PID 6656 |           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-05T12:28:04.614Z] 12:28:04     INFO - PID 6656 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2023-01-05T12:28:04.614Z] 12:28:04     INFO - PID 6656 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2023-01-05T12:28:04.615Z] 12:28:04     INFO - PID 6656 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-05T12:28:04.616Z] 12:28:04     INFO - PID 6656 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-05T12:28:04.616Z] 12:28:04     INFO - PID 6656 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-05T12:28:04.617Z] 12:28:04     INFO - PID 6656 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-05T12:28:04.617Z] 12:28:04     INFO - PID 6656 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-05T12:28:04.618Z] 12:28:04     INFO - PID 6656 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-05T12:28:04.618Z] 12:28:04     INFO - PID 6656 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-05T12:28:04.619Z] 12:28:04     INFO - PID 6656 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-05T12:28:04.620Z] 12:28:04     INFO - PID 6656 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2023-01-05T12:28:04.621Z] 12:28:04     INFO - PID 6656 | 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-05T12:28:04.621Z] 12:28:04     INFO - PID 6656 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:712:15
[task 2023-01-05T12:28:04.622Z] 12:28:04     INFO - PID 6656 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:517:26
[task 2023-01-05T12:28:04.622Z] 12:28:04     INFO - PID 6656 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:456:15
[task 2023-01-05T12:28:04.623Z] 12:28:04     INFO - PID 6656 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-01-05T12:28:04.624Z] 12:28:04     INFO - PID 6656 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-01-05T12:28:04.624Z] 12:28:04     INFO - PID 6656 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-01-05T12:28:04.625Z] 12:28:04     INFO - PID 6656 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-01-05T12:28:04.626Z] 12:28:04     INFO - PID 6656 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-01-05T12:28:04.626Z] 12:28:04     INFO - PID 6656 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-01-05T12:28:04.628Z] 12:28:04     INFO - PID 6656 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-01-05T12:28:04.628Z] 12:28:04     INFO - PID 6656 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-01-05T12:28:04.629Z] 12:28:04     INFO - PID 6656 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:566:16
[task 2023-01-05T12:28:04.629Z] 12:28:04     INFO - PID 6656 | 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-05T12:28:04.630Z] 12:28:04     INFO - PID 6656 | 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-05T12:28:04.632Z] 12:28:04     INFO - PID 6656 | 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-05T12:28:04.633Z] 12:28:04     INFO - PID 6656 | 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-05T12:28:04.634Z] 12:28:04     INFO - PID 6656 | 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-05T12:28:04.792Z] 12:28:04     INFO - Browser exited with return code 0
[task 2023-01-05T12:28:04.797Z] 12:28:04     INFO - Closing logging queue
[task 2023-01-05T12:28:04.798Z] 12:28:04     INFO - queue closed
[task 2023-01-05T12:28:05.181Z] 12:28:05     INFO - Application command: Z:\task_167291695844715\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167291695844715\AppData\Local\Temp\tmpyshj4k_h
[task 2023-01-05T12:28:05.187Z] 12:28:05     INFO - PID 8564 | 1672921571597	Marionette	INFO	Marionette enabled
[task 2023-01-05T12:28:05.187Z] 12:28:05     INFO - PID 8564 | 1672921571600	Marionette	INFO	Listening on port 55814
[task 2023-01-05T12:28:05.188Z] 12:28:05     INFO - PID 8564 | JavaScript error: resource://gre/modules/XULStore.jsm, line 58: Error: Can't find profile directory.
[task 2023-01-05T12:28:05.189Z] 12:28:05     INFO - PID 8564 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2023-01-05T12:28:05.190Z] 12:28:05     INFO - PID 8564 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167291695844715\\AppData\\Local\\Temp\\tmprwn1f9qw\\search.json.mozlz4", (void 0)))
[task 2023-01-05T12:28:05.191Z] 12:28:05     INFO - PID 8564 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2023-01-05T12:28:05.191Z] 12:28:05     INFO - PID 8564 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2023-01-05T12:28:05.192Z] 12:28:05     INFO - PID 8564 | console.error: (new TypeError("NetworkError: Network request failed", "resource://services-settings/Utils.jsm", 237))
[task 2023-01-05T12:28:05.192Z] 12:28:05     INFO - Starting runner
[task 2023-01-05T12:28:06.254Z] 12:28:06     INFO - TEST-START | /service-workers/service-worker/interface-requirements-sw.https.html
Flags: needinfo?(james)
Pushed by abutkovits@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/801c14a8d9d8 Fix metadata for serviceworker test, a=testonly. CLOSED TREE
Flags: needinfo?(james)
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → FIXED
Target Milestone: --- → 110 Branch

Set release status flags based on info from the regressing bug 1808568

Assignee: nobody → james
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: