Closed Bug 1814730 Opened 2 years ago Closed 2 years ago

Intermittent /forced-colors-mode/forced-colors-mode-05.html | single tracking bug

Categories

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

Product:
Testing
Component:
web-platform-tests
Type:
defect

Tracking

(Not tracked)

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

Filed by: imoraru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=404379359&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/I4hGDBqeQ6eABCuJ9vR9zA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/I4hGDBqeQ6eABCuJ9vR9zA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2023-02-02T19:18:22.877Z] 19:18:22     INFO - TEST-START | /forced-colors-mode/forced-colors-mode-05.html
[task 2023-02-02T19:18:22.879Z] 19:18:22     INFO - PID 6096 | 1675365502878	Marionette	INFO	Testing http://web-platform.test:8000/forced-colors-mode/forced-colors-mode-05.html == http://web-platform.test:8000/forced-colors-mode/forced-colors-mode-05-ref.html
[task 2023-02-02T19:18:43.370Z] 19:18:43     INFO - PID 6096 | JavaScript error: resource://devtools/client/jsonview/Sniffer.sys.mjs, line 50: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIChannel.contentType]
[task 2023-02-02T19:18:43.391Z] 19:18:43     INFO - TEST-UNEXPECTED-TIMEOUT | /forced-colors-mode/forced-colors-mode-05.html | expected FAIL
[task 2023-02-02T19:18:43.391Z] 19:18:43     INFO - TEST-INFO expected FAIL | took 20515ms
[task 2023-02-02T19:18:43.417Z] 19:18:43     INFO - PID 6096 | 1675365523416	Marionette	INFO	Stopped listening on port 62461
[task 2023-02-02T19:18:43.439Z] 19:18:43     INFO - PID 6096 | JavaScript error: resource://activity-stream/lib/ActivityStreamPrefs.jsm, line 27: NS_ERROR_ILLEGAL_VALUE: Component returned failure code: 0x80070057 (NS_ERROR_ILLEGAL_VALUE) [nsIPrefBranch.removeObserver]
[task 2023-02-02T19:18:45.916Z] 19:18:45     INFO - PID 6096 | console.error: (new AbortError("IndexedDB: main/anti-tracking-url-decoration list() IndexedDB:  execute() A request was aborted, for example through a call to IDBTransaction.abort.", "resource://services-settings/IDBHelpers.jsm", 18))
[task 2023-02-02T19:18:46.379Z] 19:18:46     INFO - PID 6096 | JavaScript error: resource://gre/modules/JSONFile.sys.mjs, line 184: Error: Data is not ready.
[task 2023-02-02T19:18:46.567Z] 19:18:46     INFO - PID 6096 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-02-02T19:18:46.568Z] 19:18:46     INFO - PID 6096 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.jsm", 46))
[task 2023-02-02T19:18:46.830Z] 19:18:46     INFO - PID 6096 | console.error: "Failed to import bookmarks from chrome://browser/content/default-bookmarks.html: Error: places.sqlite#0: cannot execute operation Bookmarks.jsm: insertBookmarkTree, the connection is already closing"
[task 2023-02-02T19:18:46.831Z] 19:18:46     INFO - PID 6096 | console.error: "Bookmarks.html file could be corrupt. " (new Error("places.sqlite#0: cannot execute operation Bookmarks.jsm: insertBookmarkTree, the connection is already closing", "resource://gre/modules/Sqlite.sys.mjs", 409))
[task 2023-02-02T19:18:49.537Z] 19:18:49     INFO - PID 6096 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-02-02T19:18:49.538Z] 19:18:49     INFO - PID 6096 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.jsm", 46))
[task 2023-02-02T19:18:49.539Z] 19:18:49     INFO - PID 6096 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-02-02T19:18:49.544Z] 19:18:49     INFO - PID 6096 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-02-02T19:18:49.546Z] 19:18:49     INFO - PID 6096 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-02-02T19:18:49.549Z] 19:18:49     INFO - PID 6096 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-02-02T19:18:49.551Z] 19:18:49     INFO - PID 6096 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-02-02T19:18:49.555Z] 19:18:49     INFO - PID 6096 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.jsm", 46))
[task 2023-02-02T19:18:49.555Z] 19:18:49     INFO - PID 6096 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.jsm", 46))
[task 2023-02-02T19:18:49.556Z] 19:18:49     INFO - PID 6096 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.jsm", 46))
[task 2023-02-02T19:18:49.557Z] 19:18:49     INFO - PID 6096 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.jsm", 46))
[task 2023-02-02T19:18:49.558Z] 19:18:49     INFO - PID 6096 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.jsm", 46))
[task 2023-02-02T19:18:49.560Z] 19:18:49     INFO - PID 6096 | console.error: (new Error("IndexedDB: main/doh-config getLastModified() IndexedDB:  execute() The application is shutting down", "resource://services-settings/IDBHelpers.jsm", 18))
[task 2023-02-02T19:18:49.571Z] 19:18:49     INFO - PID 6096 | 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 "DoHController: clear state and remove observers"
[task 2023-02-02T19:18:51.281Z] 19:18:51     INFO - PID 6096 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-02T19:18:51.282Z] 19:18:51     INFO - PID 6096 |           Blocker:  Waiting for ping task
[task 2023-02-02T19:18:51.282Z] 19:18:51     INFO - PID 6096 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-02-02T19:18:51.283Z] 19:18:51     INFO - PID 6096 |           State: (none)
[task 2023-02-02T19:18:51.284Z] 19:18:51     INFO - PID 6096 | 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-02T19:18:51.284Z] 19:18:51     INFO - PID 6096 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-02-02T19:18:51.285Z] 19:18:51     INFO - PID 6096 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-02-02T19:18:51.285Z] 19:18:51     INFO - PID 6096 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-02-02T19:18:51.286Z] 19:18:51     INFO - PID 6096 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-02-02T19:18:51.286Z] 19:18:51     INFO - PID 6096 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-02-02T19:18:51.287Z] 19:18:51     INFO - PID 6096 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-02-02T19:18:51.288Z] 19:18:51     INFO - PID 6096 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-02-02T19:18:51.288Z] 19:18:51     INFO - PID 6096 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-02-02T19:18:51.289Z] 19:18:51     INFO - PID 6096 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-02-02T19:18:51.289Z] 19:18:51     INFO - PID 6096 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-02-02T19:18:51.290Z] 19:18:51     INFO - PID 6096 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:829:36
[task 2023-02-02T19:18:51.290Z] 19:18:51     INFO - PID 6096 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-02-02T19:18:51.291Z] 19:18:51     INFO - PID 6096 | console.error: "TelemetryScheduler.shutdown - Already shut down"
[task 2023-02-02T19:18:51.292Z] 19:18:51     INFO - PID 6096 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-02-02T19:18:51.292Z] 19:18:51     INFO - PID 6096 |           Blocker:  Waiting for ping task
[task 2023-02-02T19:18:51.293Z] 19:18:51     INFO - PID 6096 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-02-02T19:18:51.293Z] 19:18:51     INFO - PID 6096 |           State: (none)
[task 2023-02-02T19:18:51.294Z] 19:18:51     INFO - PID 6096 | 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-02T19:18:51.294Z] 19:18:51     INFO - PID 6096 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15\
<...>
[task 2023-02-02T19:18:52.257Z] 19:18:52     INFO - PID 6096 | 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-02T19:18:52.348Z] 19:18:52     INFO - Browser exited with return code 0
[task 2023-02-02T19:18:52.351Z] 19:18:52     INFO - Closing logging queue
[task 2023-02-02T19:18:52.351Z] 19:18:52     INFO - queue closed
[task 2023-02-02T19:18:52.400Z] 19:18:52     INFO - Application command: Z:\task_167536260427532\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_167536260427532\AppData\Local\Temp\tmpkacaayg_
[task 2023-02-02T19:18:52.405Z] 19:18:52     INFO - PID 9760 | 1675365501816	Marionette	INFO	Marionette enabled
[task 2023-02-02T19:18:52.406Z] 19:18:52     INFO - PID 9760 | 1675365501819	Marionette	INFO	Listening on port 62473
[task 2023-02-02T19:18:52.406Z] 19:18:52     INFO - PID 9760 | JavaScript error: resource://gre/modules/XULStore.jsm, line 58: Error: Can't find profile directory.
[task 2023-02-02T19:18:52.407Z] 19:18:52     INFO - PID 9760 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_167536260427532\\AppData\\Local\\Temp\\tmpxz9vhu0y\\search.json.mozlz4", (void 0)))
[task 2023-02-02T19:18:52.408Z] 19:18:52     INFO - PID 9760 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-02-02T19:18:52.408Z] 19:18:52     INFO - Starting runner
[task 2023-02-02T19:18:53.593Z] 19:18:53     INFO - TEST-START | /forced-colors-mode/forced-colors-mode-06.html

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

Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE

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

Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

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

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