1814662 - Intermittent /service-workers/service-worker/getregistration.https.html | single tracking bug

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

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

---

[task 2023-02-02T14:45:57.345Z] 14:45:57     INFO - TEST-START | /service-workers/service-worker/getregistration.https.html
[task 2023-02-02T14:45:57.348Z] 14:45:57     INFO - Closing window 8f6177bf-ae42-4e79-8d73-55cae4816b28
[task 2023-02-02T14:46:07.421Z] 14:46:07     INFO - 
[task 2023-02-02T14:46:07.421Z] 14:46:07     INFO - TEST-PASS | /service-workers/service-worker/getregistration.https.html | getRegistration 
[task 2023-02-02T14:46:07.421Z] 14:46:07     INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/getregistration.https.html | Register then getRegistration - Test timed out
[task 2023-02-02T14:46:07.425Z] 14:46:07     INFO - 
[task 2023-02-02T14:46:07.425Z] 14:46:07     INFO - TEST-UNEXPECTED-NOTRUN | /service-workers/service-worker/getregistration.https.html | Register then getRegistration with a URL having a fragment - expected PASS
[task 2023-02-02T14:46:07.428Z] 14:46:07     INFO - 
[task 2023-02-02T14:46:07.428Z] 14:46:07     INFO - TEST-PASS | /service-workers/service-worker/getregistration.https.html | getRegistration with a cross origin URL 
[task 2023-02-02T14:46:07.428Z] 14:46:07     INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/getregistration.https.html | Register then Unregister then getRegistration - Test timed out
[task 2023-02-02T14:46:07.429Z] 14:46:07     INFO - 
[task 2023-02-02T14:46:07.429Z] 14:46:07     INFO - TEST-UNEXPECTED-NOTRUN | /service-workers/service-worker/getregistration.https.html | Register then Unregister then getRegistration in controlled iframe - expected PASS
[task 2023-02-02T14:46:07.430Z] 14:46:07     INFO - TEST-UNEXPECTED-TIMEOUT | /service-workers/service-worker/getregistration.https.html | expected OK
[task 2023-02-02T14:46:07.430Z] 14:46:07     INFO - TEST-INFO took 10092ms
[task 2023-02-02T14:46:07.432Z] 14:46:07     INFO - PID 6540 | 1675349167429	Marionette	INFO	Stopped listening on port 59944
[task 2023-02-02T14:46:22.485Z] 14:46:22     INFO - PID 6540 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.sys.mjs", 372))
[task 2023-02-02T14:46:35.251Z] 14:46:35     INFO - PID 6540 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-02T14:46:35.255Z] 14:46:35     INFO - PID 6540 |           Blocker:  Waiting for ping task
[task 2023-02-02T14:46:35.255Z] 14:46:35     INFO - PID 6540 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-02-02T14:46:35.256Z] 14:46:35     INFO - PID 6540 |           State: (none)
[task 2023-02-02T14:46:35.257Z] 14:46:35     INFO - PID 6540 | 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-02T14:46:35.258Z] 14:46:35     INFO - PID 6540 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-02T14:46:35.258Z] 14:46:35     INFO - PID 6540 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-02T14:46:35.259Z] 14:46:35     INFO - PID 6540 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-02T14:46:35.260Z] 14:46:35     INFO - PID 6540 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-02T14:46:35.260Z] 14:46:35     INFO - PID 6540 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-02T14:46:35.261Z] 14:46:35     INFO - PID 6540 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-02T14:46:35.261Z] 14:46:35     INFO - PID 6540 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-02T14:46:35.262Z] 14:46:35     INFO - PID 6540 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-02T14:46:35.262Z] 14:46:35     INFO - PID 6540 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-02T14:46:35.263Z] 14:46:35     INFO - PID 6540 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-02T14:46:35.264Z] 14:46:35     INFO - PID 6540 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-02T14:46:35.264Z] 14:46:35     INFO - PID 6540 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-02T14:46:35.270Z] 14:46:35     INFO - PID 6540 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-02T14:46:35.271Z] 14:46:35     INFO - PID 6540 |           Blocker:  Waiting for ping task
[task 2023-02-02T14:46:35.271Z] 14:46:35     INFO - PID 6540 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-02-02T14:46:35.272Z] 14:46:35     INFO - PID 6540 |           State: (none)
[task 2023-02-02T14:46:35.273Z] 14:46:35     INFO - PID 6540 | 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-02T14:46:35.273Z] 14:46:35     INFO - PID 6540 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-02T14:46:35.274Z] 14:46:35     INFO - PID 6540 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-02T14:46:35.275Z] 14:46:35     INFO - PID 6540 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-02T14:46:35.275Z] 14:46:35     INFO - PID 6540 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-02T14:46:35.276Z] 14:46:35     INFO - PID 6540 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-02T14:46:35.276Z] 14:46:35     INFO - PID 6540 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-02T14:46:35.277Z] 14:46:35     INFO - PID 6540 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-02T14:46:35.278Z] 14:46:35     INFO - PID 6540 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-02T14:46:35.278Z] 14:46:35     INFO - PID 6540 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-02T14:46:35.279Z] 14:46:35     INFO - PID 6540 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-02T14:46:35.279Z] 14:46:35     INFO - PID 6540 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-02T14:46:35.279Z] 14:46:35     INFO - PID 6540 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-02T14:46:35.280Z] 14:46:35     INFO - PID 6540 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-02T14:46:35.280Z] 14:46:35     INFO - PID 6540 |           Blocker:  TelemetryController: shutting down
[task 2023-02-02T14:46:35.281Z] 14:46:35     INFO - PID 6540 |           Phase: profile-before-change-telemetry
[task 2023-02-02T14:46:35.282Z] 14:46:35     INFO - PID 6540 |           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-02T14:46:35.283Z] 14:46:35     INFO - PID 6540 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-02T14:46:35.283Z] 14:46:35     INFO - PID 6540 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-02T14:46:35.283Z] 14:46:35     INFO - PID 6540 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-02T14:46:35.284Z] 14:46:35     INFO - PID 6540 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-02T14:46:35.284Z] 14:46:35     INFO - PID 6540 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-02T14:46:35.285Z] 14:46:35     INFO - PID 6540 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-02T14:46:35.285Z] 14:46:35     INFO - PID 6540 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-02T14:46:35.286Z] 14:46:35     INFO - PID 6540 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-02T14:46:35.286Z] 14:46:35     INFO - PID 6540 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-02T14:46:35.287Z] 14:46:35     INFO - PID 6540 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-02T14:46:35.287Z] 14:46:35     INFO - PID 6540 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-02T14:46:35.288Z] 14:46:35     INFO - PID 6540 | 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-02T14:46:35.288Z] 14:46:35     INFO - PID 6540 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-02T14:46:35.289Z] 14:46:35     INFO - PID 6540 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-02T14:46:35.289Z] 14:46:35     INFO - PID 6540 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-02T14:46:35.290Z] 14:46:35     INFO - PID 6540 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-02T14:46:35.290Z] 14:46:35     INFO - PID 6540 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-02T14:46:35.290Z] 14:46:35     INFO - PID 6540 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-02T14:46:35.292Z] 14:46:35     INFO - PID 6540 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-02T14:46:35.292Z] 14:46:35     INFO - PID 6540 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-02T14:46:35.292Z] 14:46:35     INFO - PID 6540 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-02T14:46:35.293Z] 14:46:35     INFO - PID 6540 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-02T14:46:35.293Z] 14:46:35     INFO - PID 6540 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-02T14:46:35.294Z] 14:46:35     INFO - PID 6540 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-02T14:46:35.594Z] 14:46:35     INFO - PID 6540 | 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-02T14:46:35.601Z] 14:46:35     INFO - PID 6540 | 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-02T14:46:35.602Z] 14:46:35     INFO - PID 6540 | 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-02T14:46:35.603Z] 14:46:35     INFO - PID 6540 | 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-02T14:46:35.604Z] 14:46:35     INFO - PID 6540 | 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-02T14:46:35.662Z] 14:46:35     INFO - Browser exited with return code 0
[task 2023-02-02T14:46:35.666Z] 14:46:35     INFO - Closing logging queue
[task 2023-02-02T14:46:35.666Z] 14:46:35     INFO - queue closed
[task 2023-02-02T14:46:36.349Z] 14:46:36     INFO - Application command: Z:\task_167534539573564\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167534539573564\AppData\Local\Temp\tmpdhr4t5zj
[task 2023-02-02T14:46:36.355Z] 14:46:36     INFO - PID 2740 | 1675349009592	Marionette	INFO	Marionette enabled
[task 2023-02-02T14:46:36.355Z] 14:46:36     INFO - PID 2740 | 1675349009595	Marionette	INFO	Listening on port 60012
[task 2023-02-02T14:46:36.356Z] 14:46:36     INFO - PID 2740 | JavaScript error: resource://gre/modules/XULStore.jsm, line 58: Error: Can't find profile directory.
[task 2023-02-02T14:46:36.357Z] 14:46:36     INFO - PID 2740 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167534539573564\\AppData\\Local\\Temp\\tmp7l_r60y7\\search.json.mozlz4", (void 0)))
[task 2023-02-02T14:46:36.358Z] 14:46:36     INFO - PID 2740 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-02T14:46:36.359Z] 14:46:36     INFO - PID 2740 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-02T14:46:36.359Z] 14:46:36     INFO - PID 2740 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-02T14:46:36.360Z] 14:46:36     INFO - PID 2740 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-02T14:46:36.360Z] 14:46:36     INFO - Starting runner
[task 2023-02-02T14:46:37.297Z] 14:46:37     INFO - TEST-START | /service-workers/service-worker/getregistrations.https.html

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1814662&startday=2023-01-30&endday=2023-02-05&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=421884487&repo=try

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1814662&startday=2023-07-03&endday=2023-07-09&tree=all

Comment 5

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

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

Comment 6

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=433933393&repo=autoland

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • opt: 1

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

Comment 8

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

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

Comment 9

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=439855936&repo=autoland

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• linux1804-64-shippable-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1814662&startday=2023-12-11&endday=2023-12-17&tree=all

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• windows11-64-2009-ccov-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1814662&startday=2023-12-25&endday=2023-12-31&tree=all

Comment 12

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

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

Comment 13

[Treeherder Bug Filer]

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

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1814662&startday=2024-11-18&endday=2024-11-24&tree=all

Comment 15

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

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