Closed Bug 1825836 Opened 3 years ago Closed 2 years ago

Intermittent /IndexedDB/list_ordering.htm | single tracking bug

Categories

(Core :: Storage: IndexedDB, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, intermittent-testcase)

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


[task 2023-03-31T16:10:03.345Z] 16:10:03     INFO - TEST-PASS | /IndexedDB/list_ordering.htm | objectStoreNames and indexNames order - numbers 'overflow' 
[task 2023-03-31T16:10:03.345Z] 16:10:03     INFO - TEST-UNEXPECTED-TIMEOUT | /IndexedDB/list_ordering.htm | objectStoreNames and indexNames order - lexigraphical string sort - Test timed out
[task 2023-03-31T16:10:03.346Z] 16:10:03     INFO - TEST-UNEXPECTED-TIMEOUT | /IndexedDB/list_ordering.htm | expected OK
[task 2023-03-31T16:10:03.346Z] 16:10:03     INFO - TEST-INFO took 10077ms
[task 2023-03-31T16:10:03.348Z] 16:10:03     INFO - PID 6712 | 1680279003345	Marionette	INFO	Stopped listening on port 51858
[task 2023-03-31T16:10:06.728Z] 16:10:06     INFO - PID 6712 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-03-31T16:10:06.731Z] 16:10:06     INFO - PID 6712 |           Blocker:  Waiting for ping task
[task 2023-03-31T16:10:06.732Z] 16:10:06     INFO - PID 6712 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-03-31T16:10:06.732Z] 16:10:06     INFO - PID 6712 |           State: (none)
[task 2023-03-31T16:10:06.733Z] 16:10:06     INFO - PID 6712 | 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-03-31T16:10:06.733Z] 16:10:06     INFO - PID 6712 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-31T16:10:06.734Z] 16:10:06     INFO - PID 6712 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-31T16:10:06.735Z] 16:10:06     INFO - PID 6712 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-31T16:10:06.735Z] 16:10:06     INFO - PID 6712 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-31T16:10:06.736Z] 16:10:06     INFO - PID 6712 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-31T16:10:06.736Z] 16:10:06     INFO - PID 6712 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-31T16:10:06.736Z] 16:10:06     INFO - PID 6712 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-31T16:10:06.737Z] 16:10:06     INFO - PID 6712 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-31T16:10:06.738Z] 16:10:06     INFO - PID 6712 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-31T16:10:06.738Z] 16:10:06     INFO - PID 6712 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-31T16:10:06.739Z] 16:10:06     INFO - PID 6712 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-31T16:10:06.740Z] 16:10:06     INFO - PID 6712 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-31T16:10:06.740Z] 16:10:06     INFO - PID 6712 | console.error: "TelemetryScheduler.shutdown - Already shut down"
[task 2023-03-31T16:10:06.760Z] 16:10:06     INFO - PID 6712 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-03-31T16:10:06.769Z] 16:10:06     INFO - PID 6712 |           Blocker:  Waiting for ping task
[task 2023-03-31T16:10:06.769Z] 16:10:06     INFO - PID 6712 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-03-31T16:10:06.770Z] 16:10:06     INFO - PID 6712 |           State: (none)
[task 2023-03-31T16:10:06.771Z] 16:10:06     INFO - PID 6712 | 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-03-31T16:10:06.771Z] 16:10:06     INFO - PID 6712 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-31T16:10:06.772Z] 16:10:06     INFO - PID 6712 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-31T16:10:06.772Z] 16:10:06     INFO - PID 6712 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-31T16:10:06.773Z] 16:10:06     INFO - PID 6712 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-31T16:10:06.773Z] 16:10:06     INFO - PID 6712 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-31T16:10:06.774Z] 16:10:06     INFO - PID 6712 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-31T16:10:06.774Z] 16:10:06     INFO - PID 6712 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-31T16:10:06.775Z] 16:10:06     INFO - PID 6712 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-31T16:10:06.775Z] 16:10:06     INFO - PID 6712 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-31T16:10:06.776Z] 16:10:06     INFO - PID 6712 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-31T16:10:06.777Z] 16:10:06     INFO - PID 6712 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-31T16:10:06.777Z] 16:10:06     INFO - PID 6712 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-31T16:10:06.778Z] 16:10:06     INFO - PID 6712 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-03-31T16:10:06.778Z] 16:10:06     INFO - PID 6712 |           Blocker:  TelemetryController: shutting down
[task 2023-03-31T16:10:06.779Z] 16:10:06     INFO - PID 6712 |           Phase: profile-before-change-telemetry
[task 2023-03-31T16:10:06.780Z] 16:10:06     INFO - PID 6712 |           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-03-31T16:10:06.780Z] 16:10:06     INFO - PID 6712 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-31T16:10:06.781Z] 16:10:06     INFO - PID 6712 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-31T16:10:06.781Z] 16:10:06     INFO - PID 6712 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-31T16:10:06.782Z] 16:10:06     INFO - PID 6712 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-31T16:10:06.782Z] 16:10:06     INFO - PID 6712 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-31T16:10:06.783Z] 16:10:06     INFO - PID 6712 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-31T16:10:06.783Z] 16:10:06     INFO - PID 6712 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-31T16:10:06.784Z] 16:10:06     INFO - PID 6712 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-31T16:10:06.784Z] 16:10:06     INFO - PID 6712 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-31T16:10:06.785Z] 16:10:06     INFO - PID 6712 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-31T16:10:06.786Z] 16:10:06     INFO - PID 6712 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-31T16:10:06.787Z] 16:10:06     INFO - PID 6712 | 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-03-31T16:10:06.787Z] 16:10:06     INFO - PID 6712 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-31T16:10:06.788Z] 16:10:06     INFO - PID 6712 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-31T16:10:06.788Z] 16:10:06     INFO - PID 6712 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-31T16:10:06.789Z] 16:10:06     INFO - PID 6712 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-31T16:10:06.789Z] 16:10:06     INFO - PID 6712 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-31T16:10:06.790Z] 16:10:06     INFO - PID 6712 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-31T16:10:06.790Z] 16:10:06     INFO - PID 6712 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-31T16:10:06.791Z] 16:10:06     INFO - PID 6712 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-31T16:10:06.791Z] 16:10:06     INFO - PID 6712 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-31T16:10:06.791Z] 16:10:06     INFO - PID 6712 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-31T16:10:06.792Z] 16:10:06     INFO - PID 6712 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-31T16:10:06.793Z] 16:10:06     INFO - PID 6712 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-31T16:10:06.839Z] 16:10:06     INFO - PID 6712 | 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-03-31T16:10:06.851Z] 16:10:06     INFO - PID 6712 | 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-03-31T16:10:06.852Z] 16:10:06     INFO - PID 6712 | 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-03-31T16:10:06.853Z] 16:10:06     INFO - PID 6712 | 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-03-31T16:10:06.854Z] 16:10:06     INFO - PID 6712 | 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-03-31T16:10:06.917Z] 16:10:06     INFO - Browser exited with return code 0
[task 2023-03-31T16:10:06.921Z] 16:10:06     INFO - Closing logging queue
[task 2023-03-31T16:10:06.921Z] 16:10:06     INFO - queue closed
[task 2023-03-31T16:10:07.215Z] 16:10:07     INFO - Application command: Z:\task_168027640912493\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_168027640912493\AppData\Local\Temp\tmpwx0jjpk4
[task 2023-03-31T16:10:07.227Z] 16:10:07     INFO - PID 4372 | 1680278368697	Marionette	INFO	Marionette enabled
[task 2023-03-31T16:10:07.228Z] 16:10:07     INFO - PID 4372 | 1680278368700	Marionette	INFO	Listening on port 51859
[task 2023-03-31T16:10:07.229Z] 16:10:07     INFO - PID 4372 | JavaScript error: resource://gre/modules/XULStore.jsm, line 58: Error: Can't find profile directory.
[task 2023-03-31T16:10:07.230Z] 16:10:07     INFO - PID 4372 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_168027640912493\\AppData\\Local\\Temp\\tmp1ux2wl35\\search.json.mozlz4", (void 0)))
[task 2023-03-31T16:10:07.231Z] 16:10:07     INFO - PID 4372 | [ERROR glean_core] Error setting metrics feature config: Json(Error("EOF while parsing a value", line: 1, column: 0))
[task 2023-03-31T16:10:07.231Z] 16:10:07     INFO - PID 4372 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-31T16:10:07.232Z] 16:10:07     INFO - PID 4372 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-31T16:10:07.233Z] 16:10:07     INFO - PID 4372 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.jsm", 407))
[task 2023-03-31T16:10:07.233Z] 16:10:07     INFO - PID 4372 | console.error: (new Error("Polling for changes failed: Unexpected content-type \"text/plain;charset=US-ASCII\".", "resource://services-settings/remote-settings.sys.mjs", 325))
[task 2023-03-31T16:10:07.234Z] 16:10:07     INFO - PID 4372 | 1680278758747	addons.xpi	ERROR	System addon update list error Error: Failed downloading XML, status: 0, reason: error
[task 2023-03-31T16:10:07.235Z] 16:10:07     INFO - Starting runner
[task 2023-03-31T16:10:08.204Z] 16:10:08     INFO - TEST-START | /IndexedDB/name-scopes.html
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.