1800025 - Intermittent /fs/FileSystemDirectoryHandle-getDirectoryHandle.https.any.html | single tracking bug

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

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

---

[task 2022-11-10T03:36:09.897Z] 03:36:09     INFO - TEST-PASS | /fs/FileSystemDirectoryHandle-getDirectoryHandle.https.any.html | getDirectoryHandle(create=false) with a path separator when the directory exists 
[task 2022-11-10T03:36:09.897Z] 03:36:09     INFO - TEST-UNEXPECTED-NOTRUN | /fs/FileSystemDirectoryHandle-getDirectoryHandle.https.any.html | getDirectoryHandle(create=true) with a path separator - expected PASS
[task 2022-11-10T03:36:09.898Z] 03:36:09     INFO - TEST-UNEXPECTED-ERROR | /fs/FileSystemDirectoryHandle-getDirectoryHandle.https.any.html | Timeout while running cleanup for test named "getDirectoryHandle(create=false) with a path separator when the directory exists".
[task 2022-11-10T03:36:09.898Z] 03:36:09     INFO - TEST-INFO took 10132ms
[task 2022-11-10T03:36:09.900Z] 03:36:09     INFO - PID 3776 | 1668051369893	Marionette	INFO	Stopped listening on port 52644
[task 2022-11-10T03:36:12.390Z] 03:36:12     INFO - PID 3776 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-10T03:36:12.395Z] 03:36:12     INFO - PID 3776 |           Blocker:  Waiting for ping task
[task 2022-11-10T03:36:12.396Z] 03:36:12     INFO - PID 3776 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2022-11-10T03:36:12.396Z] 03:36:12     INFO - PID 3776 |           State: (none)
[task 2022-11-10T03:36:12.397Z] 03:36:12     INFO - PID 3776 | 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 2022-11-10T03:36:12.398Z] 03:36:12     INFO - PID 3776 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-10T03:36:12.399Z] 03:36:12     INFO - PID 3776 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-10T03:36:12.399Z] 03:36:12     INFO - PID 3776 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-10T03:36:12.400Z] 03:36:12     INFO - PID 3776 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-10T03:36:12.400Z] 03:36:12     INFO - PID 3776 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-10T03:36:12.401Z] 03:36:12     INFO - PID 3776 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-10T03:36:12.402Z] 03:36:12     INFO - PID 3776 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-10T03:36:12.402Z] 03:36:12     INFO - PID 3776 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-11-10T03:36:12.403Z] 03:36:12     INFO - PID 3776 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-11-10T03:36:12.403Z] 03:36:12     INFO - PID 3776 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:554:48
[task 2022-11-10T03:36:12.404Z] 03:36:12     INFO - PID 3776 | send@resource://gre/modules/TelemetryControllerParent.jsm:642:24
[task 2022-11-10T03:36:12.404Z] 03:36:12     INFO - PID 3776 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-11-10T03:36:12.405Z] 03:36:12     INFO - PID 3776 | sendPing@resource://gre/modules/EventPing.jsm:51:30
[task 2022-11-10T03:36:12.405Z] 03:36:12     INFO - PID 3776 | _submitPing@resource://gre/modules/EventPing.jsm:229:12
[task 2022-11-10T03:36:12.406Z] 03:36:12     INFO - PID 3776 | shutdown@resource://gre/modules/EventPing.jsm:112:10
[task 2022-11-10T03:36:12.406Z] 03:36:12     INFO - PID 3776 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:954:31
[task 2022-11-10T03:36:12.407Z] 03:36:12     INFO - PID 3776 | shutdown/<@resource://gre/modules/TelemetryControllerParent.jsm:1024:24
[task 2022-11-10T03:36:12.407Z] 03:36:12     INFO - PID 3776 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-10T03:36:12.413Z] 03:36:12     INFO - PID 3776 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-10T03:36:12.414Z] 03:36:12     INFO - PID 3776 |           Blocker:  Waiting for ping task
[task 2022-11-10T03:36:12.415Z] 03:36:12     INFO - PID 3776 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2022-11-10T03:36:12.415Z] 03:36:12     INFO - PID 3776 |           State: (none)
[task 2022-11-10T03:36:12.416Z] 03:36:12     INFO - PID 3776 | 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 2022-11-10T03:36:12.416Z] 03:36:12     INFO - PID 3776 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-10T03:36:12.417Z] 03:36:12     INFO - PID 3776 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-10T03:36:12.417Z] 03:36:12     INFO - PID 3776 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-10T03:36:12.418Z] 03:36:12     INFO - PID 3776 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-10T03:36:12.419Z] 03:36:12     INFO - PID 3776 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-10T03:36:12.419Z] 03:36:12     INFO - PID 3776 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-10T03:36:12.420Z] 03:36:12     INFO - PID 3776 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-10T03:36:12.420Z] 03:36:12     INFO - PID 3776 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-11-10T03:36:12.421Z] 03:36:12     INFO - PID 3776 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-11-10T03:36:12.421Z] 03:36:12     INFO - PID 3776 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:554:48
[task 2022-11-10T03:36:12.422Z] 03:36:12     INFO - PID 3776 | send@resource://gre/modules/TelemetryControllerParent.jsm:642:24
[task 2022-11-10T03:36:12.422Z] 03:36:12     INFO - PID 3776 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-11-10T03:36:12.423Z] 03:36:12     INFO - PID 3776 | saveShutdownPings@resource://gre/modules/TelemetrySession.jsm:982:34
[task 2022-11-10T03:36:12.423Z] 03:36:12     INFO - PID 3776 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1232:20
[task 2022-11-10T03:36:12.423Z] 03:36:12     INFO - PID 3776 | cleanup@resource://gre/modules/TelemetrySession.jsm:1239:9
[task 2022-11-10T03:36:12.423Z] 03:36:12     INFO - PID 3776 | shutdownChromeProcess@resource://gre/modules/TelemetrySession.jsm:1256:14
[task 2022-11-10T03:36:12.425Z] 03:36:12     INFO - PID 3776 | shutdown@resource://gre/modules/TelemetrySession.jsm:247:17
[task 2022-11-10T03:36:12.425Z] 03:36:12     INFO - PID 3776 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:973:35
[task 2022-11-10T03:36:12.425Z] 03:36:12     INFO - PID 3776 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-10T03:36:12.429Z] 03:36:12     INFO - PID 3776 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-10T03:36:12.430Z] 03:36:12     INFO - PID 3776 |           Blocker:  TelemetryController: shutting down
[task 2022-11-10T03:36:12.430Z] 03:36:12     INFO - PID 3776 |           Phase: profile-before-change-telemetry
[task 2022-11-10T03:36:12.431Z] 03:36:12     INFO - PID 3776 |           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.jsm:703:15
[task 2022-11-10T03:36:12.431Z] 03:36:12     INFO - PID 3776 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-10T03:36:12.432Z] 03:36:12     INFO - PID 3776 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-10T03:36:12.432Z] 03:36:12     INFO - PID 3776 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-10T03:36:12.433Z] 03:36:12     INFO - PID 3776 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-10T03:36:12.434Z] 03:36:12     INFO - PID 3776 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-10T03:36:12.434Z] 03:36:12     INFO - PID 3776 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-10T03:36:12.434Z] 03:36:12     INFO - PID 3776 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:1687:7
[task 2022-11-10T03:36:12.435Z] 03:36:12     INFO - PID 3776 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:369:30
[task 2022-11-10T03:36:12.436Z] 03:36:12     INFO - PID 3776 | _getState@resource://gre/modules/TelemetryControllerParent.jsm:1072:38
[task 2022-11-10T03:36:12.436Z] 03:36:12     INFO - PID 3776 | setupTelemetry/<@resource://gre/modules/TelemetryControllerParent.jsm:931:18
[task 2022-11-10T03:36:12.437Z] 03:36:12     INFO - PID 3776 | safeGetState@resource://gre/modules/AsyncShutdown.jsm:242:17
[task 2022-11-10T03:36:12.437Z] 03:36:12     INFO - PID 3776 | Barrier/addBlocker/promise<@resource://gre/modules/AsyncShutdown.jsm:751:20
[task 2022-11-10T03:36:12.438Z] 03:36:12     INFO - PID 3776 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-10T03:36:12.439Z] 03:36:12     INFO - PID 3776 | 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 2022-11-10T03:36:12.439Z] 03:36:12     INFO - PID 3776 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-10T03:36:12.440Z] 03:36:12     INFO - PID 3776 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-10T03:36:12.440Z] 03:36:12     INFO - PID 3776 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-10T03:36:12.441Z] 03:36:12     INFO - PID 3776 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-10T03:36:12.441Z] 03:36:12     INFO - PID 3776 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-10T03:36:12.442Z] 03:36:12     INFO - PID 3776 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-10T03:36:12.442Z] 03:36:12     INFO - PID 3776 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-10T03:36:12.443Z] 03:36:12     INFO - PID 3776 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:735:5
[task 2022-11-10T03:36:12.443Z] 03:36:12     INFO - PID 3776 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:249:17
[task 2022-11-10T03:36:12.444Z] 03:36:12     INFO - PID 3776 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1235:42
[task 2022-11-10T03:36:12.444Z] 03:36:12     INFO - PID 3776 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-10T03:36:12.535Z] 03:36:12     INFO - PID 3776 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 703: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-11-10T03:36:12.539Z] 03:36:12     INFO - PID 3776 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 703: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-11-10T03:36:12.541Z] 03:36:12     INFO - PID 3776 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 703: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-11-10T03:36:12.542Z] 03:36:12     INFO - PID 3776 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 703: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-11-10T03:36:12.542Z] 03:36:12     INFO - PID 3776 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 703: Error: Phase "profile-before-change" is finished, it is too late to register completion condition "OS.File: flush I/O queued before profileBeforeChange"
[task 2022-11-10T03:36:12.600Z] 03:36:12     INFO - Browser exited with return code 0
[task 2022-11-10T03:36:12.604Z] 03:36:12     INFO - Closing logging queue
[task 2022-11-10T03:36:12.605Z] 03:36:12     INFO - queue closed
[task 2022-11-10T03:36:12.668Z] 03:36:12     INFO - Application command: Z:\task_166804002957399\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_166804002957399\AppData\Local\Temp\tmpwl_iu4su
[task 2022-11-10T03:36:12.673Z] 03:36:12     INFO - PID 7680 | 1668051262881	Marionette	INFO	Marionette enabled
[task 2022-11-10T03:36:12.674Z] 03:36:12     INFO - PID 7680 | 1668051262885	Marionette	INFO	Listening on port 52664
[task 2022-11-10T03:36:12.675Z] 03:36:12     INFO - PID 7680 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_166804002957399\\AppData\\Local\\Temp\\tmp0_mzk3dt\\search.json.mozlz4", (void 0)))
[task 2022-11-10T03:36:12.676Z] 03:36:12     INFO - PID 7680 | *** You are running in background task mode. ***
[task 2022-11-10T03:36:12.677Z] 03:36:12     INFO - PID 7680 | *** You are running in headless mode.
[task 2022-11-10T03:36:12.677Z] 03:36:12     INFO - PID 7680 | console.error: "C:\\Users\\task_166804002957399\\AppData\\Local\\Temp\\tmp0_mzk3dt" "" 0 ".purge.bg_rm"
[task 2022-11-10T03:36:12.678Z] 03:36:12     INFO - Starting runner
[task 2022-11-10T03:36:13.618Z] 03:36:13     INFO - TEST-START | /fs/FileSystemDirectoryHandle-getFileHandle.https.any.worker.html

Comment 1

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

:jesup, since you are the author of the regressor, bug 1777361, could you take a look?

For more information, please visit auto_nag documentation.

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1800025&startday=2022-11-07&endday=2022-11-13&tree=all

Comment 3

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

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

Comment 4

[Treeherder Bug Filer]

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

Comment 5

[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-64-2004-qr: 1
  • debug: 1

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

Comment 6

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

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

Comment 7

[Treeherder Bug Filer]

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

Comment 8

[Intermittent Failures Robot]

1 failures in 3850 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=1800025&startday=2023-09-18&endday=2023-09-24&tree=all

Comment 9

[Intermittent Failures Robot]

1 failures in 3726 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=1800025&startday=2023-09-25&endday=2023-10-01&tree=all

Comment 10

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

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

Comment 11

[Treeherder Bug Filer]

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

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 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=1800025&startday=2024-03-04&endday=2024-03-10&tree=all

Comment 13

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

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