Closed Bug 1850258 Opened 2 years ago Closed 2 years ago

Intermittent [tier 2] /IndexedDB/idbobjectstore_put11.htm | single tracking bug

Categories

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

Product:
Core
Component:
Storage: IndexedDB
Type:
defect

Tracking

()

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=427158138&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/VATM9EkoT9q1t-FKKd9zcg/runs/0/artifacts/public/logs/live_backing.log

[task 2023-08-26T23:44:11.102Z] 23:44:11     INFO - TEST-START | /IndexedDB/idbobjectstore_put11.htm
[task 2023-08-26T23:44:11.103Z] 23:44:11     INFO - Closing window fe58a6b4-65c0-4796-98a9-982a07d99f87
[task 2023-08-26T23:44:46.103Z] 23:44:46     INFO - Got timeout in harness
[task 2023-08-26T23:44:46.105Z] 23:44:46     INFO - TEST-UNEXPECTED-TIMEOUT | /IndexedDB/idbobjectstore_put11.htm | TestRunner hit external timeout (this may indicate a hang)
[task 2023-08-26T23:44:46.105Z] 23:44:46     INFO - TEST-INFO took 35014ms
[task 2023-08-26T23:46:00.905Z] 23:46:00     INFO - Browser exited with return code 572
[task 2023-08-26T23:46:00.932Z] 23:46:00     INFO - IOError on command, setting status to CRASH
[task 2023-08-26T23:46:00.933Z] 23:46:00     INFO - Browser not responding, setting status to CRASH
[task 2023-08-26T23:46:00.933Z] 23:46:00  WARNING - Command left in command_queue during cleanup: 'test_ended', (<wptrunner.wpttest.TestharnessTest /IndexedDB/idbobjectstore_put11.htm>, (<wptrunner.wpttest.TestharnessResult CRASH>, []))
[task 2023-08-26T23:46:00.934Z] 23:46:00     INFO - Closing logging queue
[task 2023-08-26T23:46:00.934Z] 23:46:00     INFO - queue closed
[task 2023-08-26T23:46:08.456Z] 23:46:08     INFO - Application command: Z:\task_169309230638824\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_169309230638824\AppData\Local\Temp\tmpzaj0nctw
[task 2023-08-26T23:46:08.464Z] 23:46:08     INFO - PID 3588 | 1693093062080	Marionette	INFO	Marionette enabled
[task 2023-08-26T23:46:08.465Z] 23:46:08     INFO - PID 3588 | 1693093062237	Marionette	INFO	Listening on port 55900
[task 2023-08-26T23:46:08.466Z] 23:46:08     INFO - PID 3588 | JavaScript error: resource://gre/modules/XULStore.sys.mjs, line 60: Error: Can't find profile directory.
[task 2023-08-26T23:46:08.467Z] 23:46:08     INFO - PID 3588 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-26T23:46:08.468Z] 23:46:08     INFO - PID 3588 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-26T23:46:08.469Z] 23:46:08     INFO - PID 3588 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-26T23:46:08.469Z] 23:46:08     INFO - PID 3588 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-26T23:46:08.470Z] 23:46:08     INFO - PID 3588 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-26T23:46:08.471Z] 23:46:08     INFO - PID 3588 | console.error: (new Error("Polling for changes failed: Unexpected content-type \"text/plain;charset=US-ASCII\".", "resource://services-settings/remote-settings.sys.mjs", 321))
[task 2023-08-26T23:46:08.471Z] 23:46:08     INFO - Starting runner
[task 2023-08-26T23:46:09.791Z] 23:46:09     INFO - TEST-START | /IndexedDB/idbobjectstore_put12.htm
[task 2023-08-26T23:46:09.801Z] 23:46:09     INFO - Setting pref extensions.blocklist.enabled to false
[task 2023-08-26T23:46:30.034Z] 23:46:30     INFO - 
[task 2023-08-26T23:46:30.034Z] 23:46:30     INFO - TEST-UNEXPECTED-NOTRUN | /IndexedDB/idbobjectstore_put12.htm | IDBObjectStore.put() - Attempt to put a record where the record's in-line key is not defined  - expected PASS
[task 2023-08-26T23:46:30.042Z] 23:46:30     INFO - TEST-UNEXPECTED-TIMEOUT | /IndexedDB/idbobjectstore_put12.htm | expected OK
[task 2023-08-26T23:46:30.042Z] 23:46:30     INFO - TEST-INFO took 20243ms
[task 2023-08-26T23:46:30.044Z] 23:46:30     INFO - PID 3588 | 1693093590046	Marionette	INFO	Stopped listening on port 55900
[task 2023-08-26T23:46:31.679Z] 23:46:31     INFO - PID 3588 | console.error: ({})
[task 2023-08-26T23:46:38.064Z] 23:46:38     INFO - PID 3588 | console.log: "RemoteSettingsWorker error: Error: Can't import when we've started shutting down."
[task 2023-08-26T23:46:38.064Z] 23:46:38     INFO - PID 3588 | console.error: (new RemoteSettingsWorkerError("Error: Can't import when we've started shutting down.", "resource://services-settings/RemoteSettingsWorker.sys.mjs", 34))
[task 2023-08-26T23:46:41.942Z] 23:46:41     INFO - PID 3588 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"Sqlite.sys.mjs shutdown blocker","state":{"description":"Waiting for connections to close","state":[{"name":"protections.sqlite#0: waiting for shutdown","state":{"identifier":"protections.sqlite#0","isCloseRequested":false,"hasDbConn":true,"initiatedTransaction":false,"pendingStatements":1,"statementCounter":2},"filename":"resource://gre/modules/Sqlite.sys.mjs","lineNumber":391,"stack":["resource://gre/modules/Sqlite.sys.mjs:ConnectionData:391","resource://gre/modules/Sqlite.sys.mjs:OpenedConnection:1602","resource://gre/modules/Sqlite.sys.mjs:openConnection/</<:1377","resource://gre/modules/AsyncShutdown.sys.mjs:observe:573"]}]},"filename":"resource://gre/modules/Sqlite.sys.mjs","lineNumber":234,"stack":["resource://gre/modules/Sqlite.sys.mjs:null:234","resource://gre/modules/Sqlite.sys.mjs:ConnectionData:391","resource://gre/modules/Sqlite.sys.mjs:OpenedConnection:1602","resource://gre/modules/Sqlite.sys.mjs:openConnection/</<:1377"]},{"name":"Places Connection shutdown","state":{"PlacesShutdownProgress":5},"filename":"D:/task_169308794631013/build/src/toolkit/components/places/Database.cpp","lineNumber":463,"stack":""}] Barrier: profile-before-change
[task 2023-08-26T23:46:41.945Z] 23:46:41     INFO - PID 3588 | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"protections.sqlite#0: waiting for shutdown","state":{"identifier":"protections.sqlite#0","isCloseRequested":false,"hasDbConn":true,"initiatedTransaction":false,"pendingStatements":1,"statementCounter":2},"filename":"resource://gre/modules/Sqlite.sys.mjs","lineNumber":391,"stack":["resource://gre/modules/Sqlite.sys.mjs:ConnectionData:391","resource://gre/modules/Sqlite.sys.mjs:OpenedConnection:1602","resource://gre/modules/Sqlite.sys.mjs:openConnection/</<:1377","resource://gre/modules/AsyncShutdown.sys.mjs:observe:573"]}] Barrier: Sqlite.sys.mjs: wait until all connections are closed
[task 2023-08-26T23:46:45.928Z] 23:46:45     INFO - PID 3588 | console.error: (new Error("SessionFile is closed", "resource:///modules/sessionstore/SessionFile.sys.mjs", 370))
[task 2023-08-26T23:46:54.380Z] 23:46:54     INFO - PID 3588 | [Parent 2888, IPC I/O Parent] WARNING: Process 6732 may be hanging at shutdown; will wait for up to 80000ms: file D:/task_169308794631013/build/src/ipc/chromium/src/chrome/common/process_watcher_win.cc:129
[task 2023-08-26T23:47:05.579Z] 23:47:05     INFO - PID 3588 | [Parent 2888, IPC I/O Parent] WARNING: Process 4464 may be hanging at shutdown; will wait for up to 68813ms: file D:/task_169308794631013/build/src/ipc/chromium/src/chrome/common/process_watcher_win.cc:129
[task 2023-08-26T23:47:05.590Z] 23:47:05     INFO - PID 3588 | [Parent 2888, IPC I/O Parent] WARNING: Process 8124 may be hanging at shutdown; will wait for up to 68797ms: file D:/task_169308794631013/build/src/ipc/chromium/src/chrome/common/process_watcher_win.cc:129
[task 2023-08-26T23:47:10.358Z] 23:47:10     INFO - Browser exited with return code 0
[task 2023-08-26T23:47:10.360Z] 23:47:10     INFO - Closing logging queue
[task 2023-08-26T23:47:10.362Z] 23:47:10     INFO - queue closed
[task 2023-08-26T23:47:10.413Z] 23:47:10     INFO - Application command: Z:\task_169309230638824\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_169309230638824\AppData\Local\Temp\tmp5sc9ap7f
[task 2023-08-26T23:47:10.418Z] 23:47:10     INFO - PID 5524 | 1693093569342	Marionette	INFO	Marionette enabled
[task 2023-08-26T23:47:10.421Z] 23:47:10     INFO - PID 5524 | 1693093575850	Marionette	INFO	Listening on port 56639
[task 2023-08-26T23:47:10.422Z] 23:47:10     INFO - PID 5524 | JavaScript error: resource://gre/modules/XULStore.sys.mjs, line 60: Error: Can't find profile directory.
[task 2023-08-26T23:47:10.423Z] 23:47:10     INFO - PID 5524 | console.error: (new Error("Polling for changes failed: Unexpected content-type \"text/plain;charset=US-ASCII\".", "resource://services-settings/remote-settings.sys.mjs", 321))
[task 2023-08-26T23:47:10.423Z] 23:47:10     INFO - PID 5524 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-26T23:47:10.424Z] 23:47:10     INFO - PID 5524 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-26T23:47:10.424Z] 23:47:10     INFO - PID 5524 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-26T23:47:10.425Z] 23:47:10     INFO - PID 5524 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-26T23:47:10.426Z] 23:47:10     INFO - PID 5524 | console.error: (new Error("Unexpected content-type \"text/plain;charset=US-ASCII\"", "resource://services-settings/Utils.sys.mjs", 399))
[task 2023-08-26T23:47:10.426Z] 23:47:10     INFO - Starting runner
[task 2023-08-26T23:47:11.757Z] 23:47:11     INFO - TEST-START | /IndexedDB/idbobjectstore_put13.htm

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

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.