Closed
Bug 1802200
Opened 3 years ago
Closed 3 years ago
Intermittent /IndexedDB/idbindex_batchGetAll.tentative.any.html | single tracking bug
Categories
(Core :: Storage: IndexedDB, defect, P5)
Core
Storage: IndexedDB
Tracking
()
RESOLVED
INCOMPLETE
People
(Reporter: intermittent-bug-filer, Unassigned)
Details
(Keywords: intermittent-failure, intermittent-testcase)
Filed by: nfay [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=397470484&repo=mozilla-beta
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/EgO3TT10SWm0PA0PKRNS3w/runs/0/artifacts/public/logs/live_backing.log
[task 2022-11-23T15:35:08.312Z] 15:35:08 INFO - TEST-START | /IndexedDB/idbindex_batchGetAll.tentative.any.html
[task 2022-11-23T15:35:08.313Z] 15:35:08 INFO - Closing window db6b64ce-cf27-4071-ab09-808baf037a3b
[task 2022-11-23T15:35:08.324Z] 15:35:08 INFO - PID 5372 | [Child 7592, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:500
[task 2022-11-23T15:35:08.360Z] 15:35:08 INFO - PID 5372 | [Child 7592, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:500
[task 2022-11-23T15:35:38.535Z] 15:35:38 INFO -
[task 2022-11-23T15:35:38.535Z] 15:35:38 INFO - TEST-FAIL | /IndexedDB/idbindex_batchGetAll.tentative.any.html | Single getAll - index.batchGetAll is not a function
[task 2022-11-23T15:35:38.535Z] 15:35:38 INFO - createBatchGetAllRequest@http://web-platform.test:8000/IndexedDB/idbindex_batchGetAll.tentative.any.js:71:21
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - @http://web-platform.test:8000/IndexedDB/idbindex_batchGetAll.tentative.any.js:81:39
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - indexeddb_test/</open.onsuccess<@http://web-platform.test:8000/IndexedDB/resources/support.js:141:20
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - Test.prototype.step_func/<@http://web-platform.test:8000/resources/testharness.js:2637:35
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - TEST-FAIL | /IndexedDB/idbindex_batchGetAll.tentative.any.html | Multiple getAll - index.batchGetAll is not a function
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - createBatchGetAllRequest@http://web-platform.test:8000/IndexedDB/idbindex_batchGetAll.tentative.any.js:71:21
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - @http://web-platform.test:8000/IndexedDB/idbindex_batchGetAll.tentative.any.js:93:31
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - indexeddb_test/</open.onsuccess<@http://web-platform.test:8000/IndexedDB/resources/support.js:141:20
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - Test.prototype.step_func/<@http://web-platform.test:8000/resources/testharness.js:2637:35
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - TEST-FAIL | /IndexedDB/idbindex_batchGetAll.tentative.any.html | Get bound range - index.batchGetAll is not a function
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - createBatchGetAllRequest@http://web-platform.test:8000/IndexedDB/idbindex_batchGetAll.tentative.any.js:71:21
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - @http://web-platform.test:8000/IndexedDB/idbindex_batchGetAll.tentative.any.js:107:37
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - indexeddb_test/</open.onsuccess<@http://web-platform.test:8000/IndexedDB/resources/support.js:141:20
[task 2022-11-23T15:35:38.537Z] 15:35:38 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - Test.prototype.step_func/<@http://web-platform.test:8000/resources/testharness.js:2637:35
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - TEST-FAIL | /IndexedDB/idbindex_batchGetAll.tentative.any.html | Get bound range with maxCount - index.batchGetAll is not a function
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - createBatchGetAllRequest@http://web-platform.test:8000/IndexedDB/idbindex_batchGetAll.tentative.any.js:71:21
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - @http://web-platform.test:8000/IndexedDB/idbindex_batchGetAll.tentative.any.js:121:37
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - indexeddb_test/</open.onsuccess<@http://web-platform.test:8000/IndexedDB/resources/support.js:141:20
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - Test.prototype.step_func/<@http://web-platform.test:8000/resources/testharness.js:2637:35
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - TEST-FAIL | /IndexedDB/idbindex_batchGetAll.tentative.any.html | Non existent key - index.batchGetAll is not a function
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - createBatchGetAllRequest@http://web-platform.test:8000/IndexedDB/idbindex_batchGetAll.tentative.any.js:71:21
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - @http://web-platform.test:8000/IndexedDB/idbindex_batchGetAll.tentative.any.js:142:37
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - indexeddb_test/</open.onsuccess<@http://web-platform.test:8000/IndexedDB/resources/support.js:141:20
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - Test.prototype.step_func/<@http://web-platform.test:8000/resources/testharness.js:2637:35
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - TEST-FAIL | /IndexedDB/idbindex_batchGetAll.tentative.any.html | Retrieve multiEntry key - index.batchGetAll is not a function
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - createBatchGetAllRequest@http://web-platform.test:8000/IndexedDB/idbindex_batchGetAll.tentative.any.js:71:21
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - @http://web-platform.test:8000/IndexedDB/idbindex_batchGetAll.tentative.any.js:153:37
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - indexeddb_test/</open.onsuccess<@http://web-platform.test:8000/IndexedDB/resources/support.js:141:20
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - Test.prototype.step_func/<@http://web-platform.test:8000/resources/testharness.js:2637:35
[task 2022-11-23T15:35:38.538Z] 15:35:38 INFO - TEST-UNEXPECTED-TIMEOUT | /IndexedDB/idbindex_batchGetAll.tentative.any.html | Retrieve one key multiple values - Test timed out
[task 2022-11-23T15:35:38.539Z] 15:35:38 INFO - TEST-INFO | expected FAIL
[task 2022-11-23T15:35:38.539Z] 15:35:38 INFO - TEST-UNEXPECTED-TIMEOUT | /IndexedDB/idbindex_batchGetAll.tentative.any.html | expected OK
[task 2022-11-23T15:35:38.539Z] 15:35:38 INFO - TEST-INFO took 30228ms
[task 2022-11-23T15:35:38.542Z] 15:35:38 INFO - PID 5372 | 1669217738533 Marionette INFO Stopped listening on port 49929
[task 2022-11-23T15:35:38.569Z] 15:35:38 INFO - PID 5372 | [Child 6804, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-11-23T15:35:38.575Z] 15:35:38 INFO - PID 5372 | [Child 6804, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-11-23T15:35:38.578Z] 15:35:38 INFO - PID 5372 | [Child 6804, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-11-23T15:35:38.582Z] 15:35:38 INFO - PID 5372 | [Child 6804, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-11-23T15:35:38.584Z] 15:35:38 INFO - PID 5372 | [Child 6804, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-11-23T15:35:38.585Z] 15:35:38 INFO - PID 5372 | [Child 6804, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-11-23T15:35:38.590Z] 15:35:38 INFO - PID 5372 | [Child 6804, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2022-11-23T15:35:38.646Z] 15:35:38 INFO - PID 5372 | [Child 7592, Main Thread] WARNING: DispatchEvent called on non-current inner window, dropping. Please check the window in the caller instead.: file /builds/worker/checkouts/gecko/dom/base/nsGlobalWindowInner.cpp:4353
[task 2022-11-23T15:35:38.649Z] 15:35:38 INFO - PID 5372 | [Parent 3576, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:500
[task 2022-11-23T15:35:38.660Z] 15:35:38 INFO - PID 5372 | [Child 7592, Main Thread] WARNING: '!CanHandleWith(aPresContext)', file /builds/worker/checkouts/gecko/dom/events/IMEStateManager.cpp:500
[task 2022-11-23T15:35:38.665Z] 15:35:38 INFO - PID 5372 | [GPU 6668, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2022-11-23T15:35:38.680Z] 15:35:38 INFO - PID 5372 | [Parent 3576, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:200
[task 2022-11-23T15:35:38.695Z] 15:35:38 INFO - PID 5372 | [Parent 3576, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:200
[task 2022-11-23T15:35:38.697Z] 15:35:38 INFO - PID 5372 | [Parent 3576, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:200
[task 2022-11-23T15:35:38.698Z] 15:35:38 INFO - PID 5372 | [Parent 3576, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:200
[task 2022-11-23T15:35:38.699Z] 15:35:38 INFO - PID 5372 | [Parent 3576, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:200
[task 2022-11-23T15:35:38.706Z] 15:35:38 INFO - PID 5372 | [GPU 6668, Compositor] WARNING: IPC Connection Error: [Parent][PImageBridgeParent] RunMessage(msgname=PImageBridge::Msg_WillClose) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1883
[task 2022-11-23T15:35:38.746Z] 15:35:38 INFO - PID 5372 | [Child 7592, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3387
[task 2022-11-23T15:35:38.759Z] 15:35:38 INFO - PID 5372 | [Child 7592, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-11-23T15:35:38.768Z] 15:35:38 INFO - PID 5372 | [Child 7592, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4628
[task 2022-11-23T15:35:38.779Z] 15:35:38 INFO - PID 5372 | [Child 6804, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3387
[task 2022-11-23T15:35:38.782Z] 15:35:38 INFO - PID 5372 | [Child 6804, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-11-23T15:35:38.789Z] 15:35:38 INFO - PID 5372 | [Child 6804, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4628
[task 2022-11-23T15:35:38.861Z] 15:35:38 INFO - PID 5372 | [Child 8380, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3387
[task 2022-11-23T15:35:38.871Z] 15:35:38 INFO - PID 5372 | [Child 8800, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3387
[task 2022-11-23T15:35:38.872Z] 15:35:38 INFO - PID 5372 | [Child 8380, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-11-23T15:35:38.873Z] 15:35:38 INFO - PID 5372 | [Child 8800, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-11-23T15:35:38.874Z] 15:35:38 INFO - PID 5372 | [Child 7164, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3387
[task 2022-11-23T15:35:38.875Z] 15:35:38 INFO - PID 5372 | [Child 7164, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-11-23T15:35:38.877Z] 15:35:38 INFO - PID 5372 | [Child 8380, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4628
[task 2022-11-23T15:35:38.878Z] 15:35:38 INFO - PID 5372 | [Child 8800, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4628
[task 2022-11-23T15:35:38.880Z] 15:35:38 INFO - PID 5372 | [Child 7164, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4628
[task 2022-11-23T15:35:38.907Z] 15:35:38 INFO - PID 5372 | [Parent 3576, QuotaManager IO] WARNING: QM_TRY failure (ERROR): 'ToResult(DoDatabaseWork()) failed with resultCode 0x80660001, resultName NS_ERROR_DOM_INDEXEDDB_UNKNOWN_ERR', file dom/indexedDB/ActorsParent.cpp:15686
[task 2022-11-23T15:35:38.910Z] 15:35:38 INFO - PID 5372 | [Parent 3576, QuotaManager IO] WARNING: QM_TRY failure (ERROR): 'ToResult(DoDatabaseWork()) failed with resultCode 0x80660001, resultName NS_ERROR_DOM_INDEXEDDB_UNKNOWN_ERR', file dom/indexedDB/ActorsParent.cpp:15686
[task 2022-11-23T15:35:38.910Z] 15:35:38 INFO - PID 5372 | [Parent 3576, QuotaManager IO] WARNING: QM_TRY failure (ERROR): 'ToResult(DoDatabaseWork()) failed with resultCode 0x80660001, resultName NS_ERROR_DOM_INDEXEDDB_UNKNOWN_ERR', file dom/indexedDB/ActorsParent.cpp:15686
[task 2022-11-23T15:35:38.912Z] 15:35:38 INFO - PID 5372 | [Parent 3576, QuotaManager IO] WARNING: QM_TRY failure (ERROR): 'ToResult(DoDatabaseWork()) failed with resultCode 0x80660001, resultName NS_ERROR_DOM_INDEXEDDB_UNKNOWN_ERR', file dom/indexedDB/ActorsParent.cpp:15686
[task 2022-11-23T15:35:38.912Z] 15:35:38 INFO - PID 5372 | [Parent 3576, QuotaManager IO] WARNING: QM_TRY failure (ERROR): 'ToResult(DoDatabaseWork()) failed with resultCode 0x80660001, resultName NS_ERROR_DOM_INDEXEDDB_UNKNOWN_ERR', file dom/indexedDB/ActorsParent.cpp:15686
[task 2022-11-23T15:35:38.914Z] 15:35:38 INFO - PID 5372 | [Parent 3576, QuotaManager IO] WARNING: QM_TRY failure (ERROR): 'ToResult(DoDatabaseWork()) failed with resultCode 0x80660001, resultName NS_ERROR_DOM_INDEXEDDB_UNKNOWN_ERR', file dom/indexedDB/ActorsParent.cpp:15686
[task 2022-11-23T15:35:38.915Z] 15:35:38 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: QM_TRY failure (ERROR): 'ToResult(BeginVersionChange()) failed with resultCode 0x80660001, resultName NS_ERROR_DOM_INDEXEDDB_UNKNOWN_ERR', file dom/indexedDB/ActorsParent.cpp:15690
[task 2022-11-23T15:35:41.247Z] 15:35:41 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:41.248Z] 15:35:41 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: initiateShutdownWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:41.248Z] 15:35:41 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:41.249Z] 15:35:41 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:41.249Z] 15:35:41 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:41.250Z] 15:35:41 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: fs: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:41.250Z] 15:35:41 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:41.251Z] 15:35:41 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:41.252Z] 15:35:41 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:41.253Z] 15:35:41 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:41.671Z] 15:35:41 INFO - PID 5372 | [Parent 3576, QuotaManager IO] WARNING: quota manager shutdown step: '0.421000s: ClearRequestBase: Starting deleting files', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:41.676Z] 15:35:41 INFO - PID 5372 | [Parent 3576, QuotaManager IO] WARNING: quota manager shutdown step: '0.421000s: ClearRequestBase: Starting repeated directory removal #0', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:41.676Z] 15:35:41 INFO - PID 5372 | [Parent 3576, QuotaManager IO] WARNING: quota manager shutdown step: '0.421000s: ClearRequestBase: Completed repeated directory removal #0', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:41.677Z] 15:35:41 INFO - PID 5372 | [Parent 3576, QuotaManager IO] WARNING: quota manager shutdown step: '0.421000s: ClearRequestBase: Completed deleting files', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.196Z] 15:35:42 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: idb: '0.953000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.201Z] 15:35:42 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: idb: '0.953000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.202Z] 15:35:42 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: idb: '0.953000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.780Z] 15:35:42 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: quota manager shutdown step: '1.531000s: stopKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.784Z] 15:35:42 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: quota manager shutdown step: '1.531000s: shutdownAndJoinWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.785Z] 15:35:42 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: idb: '1.531000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.786Z] 15:35:42 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: cache: '1.531000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.786Z] 15:35:42 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: sdb: '1.531000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.787Z] 15:35:42 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: fs: '1.531000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.787Z] 15:35:42 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: ls: '1.531000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.788Z] 15:35:42 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: quota manager shutdown step: '1.531000s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.789Z] 15:35:42 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: quota manager shutdown step: '1.531000s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.790Z] 15:35:42 INFO - PID 5372 | [Parent 3576, IPDL Background] WARNING: quota manager shutdown step: '1.531000s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3297
[task 2022-11-23T15:35:42.850Z] 15:35:42 INFO - PID 5372 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-23T15:35:42.850Z] 15:35:42 INFO - PID 5372 | Blocker: Waiting for ping task
[task 2022-11-23T15:35:42.858Z] 15:35:42 INFO - PID 5372 | Phase: TelemetryController: Waiting for pending ping activity
[task 2022-11-23T15:35:42.859Z] 15:35:42 INFO - PID 5372 | State: (none)
[task 2022-11-23T15:35:42.860Z] 15:35:42 INFO - PID 5372 | 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-23T15:35:42.860Z] 15:35:42 INFO - PID 5372 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-23T15:35:42.861Z] 15:35:42 INFO - PID 5372 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-23T15:35:42.861Z] 15:35:42 INFO - PID 5372 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-23T15:35:42.862Z] 15:35:42 INFO - PID 5372 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-23T15:35:42.863Z] 15:35:42 INFO - PID 5372 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-23T15:35:42.863Z] 15:35:42 INFO - PID 5372 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-23T15:35:42.864Z] 15:35:42 INFO - PID 5372 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-23T15:35:42.864Z] 15:35:42 INFO - PID 5372 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-11-23T15:35:42.865Z] 15:35:42 INFO - PID 5372 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-11-23T15:35:42.865Z] 15:35:42 INFO - PID 5372 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:540:48
[task 2022-11-23T15:35:42.866Z] 15:35:42 INFO - PID 5372 | send@resource://gre/modules/TelemetryControllerParent.jsm:628:24
[task 2022-11-23T15:35:42.866Z] 15:35:42 INFO - PID 5372 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-11-23T15:35:42.867Z] 15:35:42 INFO - PID 5372 | sendPing@resource://gre/modules/EventPing.jsm:51:30
[task 2022-11-23T15:35:42.867Z] 15:35:42 INFO - PID 5372 | _submitPing@resource://gre/modules/EventPing.jsm:229:12
[task 2022-11-23T15:35:42.868Z] 15:35:42 INFO - PID 5372 | shutdown@resource://gre/modules/EventPing.jsm:112:10
[task 2022-11-23T15:35:42.868Z] 15:35:42 INFO - PID 5372 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:940:31
[task 2022-11-23T15:35:42.869Z] 15:35:42 INFO - PID 5372 | shutdown/<@resource://gre/modules/TelemetryControllerParent.jsm:1010:24
[task 2022-11-23T15:35:42.869Z] 15:35:42 INFO - PID 5372 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-23T15:35:42.961Z] 15:35:42 INFO - PID 5372 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-23T15:35:42.964Z] 15:35:42 INFO - PID 5372 | Blocker: Waiting for ping task
[task 2022-11-23T15:35:42.964Z] 15:35:42 INFO - PID 5372 | Phase: TelemetryController: Waiting for pending ping activity
[task 2022-11-23T15:35:42.965Z] 15:35:42 INFO - PID 5372 | State: (none)
[task 2022-11-23T15:35:42.966Z] 15:35:42 INFO - PID 5372 | 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-23T15:35:42.967Z] 15:35:42 INFO - PID 5372 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-23T15:35:42.967Z] 15:35:42 INFO - PID 5372 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-23T15:35:42.968Z] 15:35:42 INFO - PID 5372 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-23T15:35:42.969Z] 15:35:42 INFO - PID 5372 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-23T15:35:42.969Z] 15:35:42 INFO - PID 5372 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-23T15:35:42.970Z] 15:35:42 INFO - PID 5372 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-23T15:35:42.970Z] 15:35:42 INFO - PID 5372 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-23T15:35:42.971Z] 15:35:42 INFO - PID 5372 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:103:5
[task 2022-11-23T15:35:42.971Z] 15:35:42 INFO - PID 5372 | promiseArchivePing@resource://gre/modules/TelemetryArchive.jsm:63:33
[task 2022-11-23T15:35:42.972Z] 15:35:42 INFO - PID 5372 | _submitPingLogic@resource://gre/modules/TelemetryControllerParent.jsm:540:48
[task 2022-11-23T15:35:42.973Z] 15:35:42 INFO - PID 5372 | send@resource://gre/modules/TelemetryControllerParent.jsm:628:24
[task 2022-11-23T15:35:42.973Z] 15:35:42 INFO - PID 5372 | submitExternalPing@resource://gre/modules/TelemetryControllerParent.jsm:182:17
[task 2022-11-23T15:35:42.973Z] 15:35:42 INFO - PID 5372 | saveShutdownPings@resource://gre/modules/TelemetrySession.jsm:982:34
[task 2022-11-23T15:35:42.974Z] 15:35:42 INFO - PID 5372 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1232:20
[task 2022-11-23T15:35:42.974Z] 15:35:42 INFO - PID 5372 | cleanup@resource://gre/modules/TelemetrySession.jsm:1239:9
[task 2022-11-23T15:35:42.975Z] 15:35:42 INFO - PID 5372 | shutdownChromeProcess@resource://gre/modules/TelemetrySession.jsm:1256:14
[task 2022-11-23T15:35:42.975Z] 15:35:42 INFO - PID 5372 | shutdown@resource://gre/modules/TelemetrySession.jsm:247:17
[task 2022-11-23T15:35:42.976Z] 15:35:42 INFO - PID 5372 | _cleanupOnShutdown@resource://gre/modules/TelemetryControllerParent.jsm:959:35
[task 2022-11-23T15:35:42.976Z] 15:35:42 INFO - PID 5372 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-23T15:35:42.988Z] 15:35:42 INFO - PID 5372 | WARNING: A blocker encountered an error while we were waiting.
[task 2022-11-23T15:35:42.988Z] 15:35:42 INFO - PID 5372 | Blocker: TelemetryController: shutting down
[task 2022-11-23T15:35:42.988Z] 15:35:42 INFO - PID 5372 | Phase: profile-before-change-telemetry
[task 2022-11-23T15:35:42.991Z] 15:35:42 INFO - PID 5372 | 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-23T15:35:42.991Z] 15:35:42 INFO - PID 5372 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-23T15:35:42.992Z] 15:35:42 INFO - PID 5372 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-23T15:35:42.992Z] 15:35:42 INFO - PID 5372 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-23T15:35:42.993Z] 15:35:42 INFO - PID 5372 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-23T15:35:42.993Z] 15:35:42 INFO - PID 5372 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-23T15:35:42.994Z] 15:35:42 INFO - PID 5372 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-23T15:35:42.995Z] 15:35:42 INFO - PID 5372 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:1687:7
[task 2022-11-23T15:35:42.995Z] 15:35:42 INFO - PID 5372 | getShutdownState@resource://gre/modules/TelemetrySend.jsm:369:30
[task 2022-11-23T15:35:42.996Z] 15:35:42 INFO - PID 5372 | _getState@resource://gre/modules/TelemetryControllerParent.jsm:1058:38
[task 2022-11-23T15:35:42.996Z] 15:35:42 INFO - PID 5372 | setupTelemetry/<@resource://gre/modules/TelemetryControllerParent.jsm:917:18
[task 2022-11-23T15:35:42.997Z] 15:35:42 INFO - PID 5372 | safeGetState@resource://gre/modules/AsyncShutdown.jsm:242:17
[task 2022-11-23T15:35:42.997Z] 15:35:42 INFO - PID 5372 | Barrier/addBlocker/promise<@resource://gre/modules/AsyncShutdown.jsm:751:20
[task 2022-11-23T15:35:42.998Z] 15:35:42 INFO - PID 5372 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-23T15:35:42.998Z] 15:35:42 INFO - PID 5372 | 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-23T15:35:42.999Z] 15:35:42 INFO - PID 5372 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.jsm:703:15
[task 2022-11-23T15:35:42.999Z] 15:35:42 INFO - PID 5372 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:511:26
[task 2022-11-23T15:35:42.999Z] 15:35:42 INFO - PID 5372 | addBlocker@resource://gre/modules/AsyncShutdown.jsm:450:15
[task 2022-11-23T15:35:43.000Z] 15:35:43 INFO - PID 5372 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1563:28
[task 2022-11-23T15:35:43.000Z] 15:35:43 INFO - PID 5372 | @resource://gre/modules/osfile/osfile_async_front.jsm:1583:16
[task 2022-11-23T15:35:43.001Z] 15:35:43 INFO - PID 5372 | @resource://gre/modules/osfile.jsm:12:30
[task 2022-11-23T15:35:43.002Z] 15:35:43 INFO - PID 5372 | @resource://gre/modules/TelemetryStorage.jsm:19:28
[task 2022-11-23T15:35:43.002Z] 15:35:43 INFO - PID 5372 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:721:5
[task 2022-11-23T15:35:43.003Z] 15:35:43 INFO - PID 5372 | removeAbortedSessionPing@resource://gre/modules/TelemetryControllerParent.jsm:249:17
[task 2022-11-23T15:35:43.003Z] 15:35:43 INFO - PID 5372 | shutdownChromeProcess/cleanup/<@resource://gre/modules/TelemetrySession.jsm:1235:42
[task 2022-11-23T15:35:43.004Z] 15:35:43 INFO - PID 5372 | observe@resource://gre/modules/AsyncShutdown.jsm:560:16
[task 2022-11-23T15:35:43.386Z] 15:35:43 INFO - PID 5372 | [GPU 6668, Main Thread] WARNING: IPC Connection Error: [Parent][PGPUParent] RunMessage(msgname=PGPU::Msg_ShutdownVR) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1883
[task 2022-11-23T15:35:43.389Z] 15:35:43 INFO - PID 5372 | 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-23T15:35:43.390Z] 15:35:43 INFO - PID 5372 | 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-23T15:35:43.391Z] 15:35:43 INFO - PID 5372 | 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-23T15:35:43.392Z] 15:35:43 INFO - PID 5372 | 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-23T15:35:43.392Z] 15:35:43 INFO - PID 5372 | 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-23T15:35:43.393Z] 15:35:43 INFO - PID 5372 | [GPU 6668, Main Thread] WARNING: IPC message 'PGPU::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2022-11-23T15:35:43.402Z] 15:35:43 INFO - PID 5372 | [GPU 6668, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4628
[task 2022-11-23T15:35:43.846Z] 15:35:43 INFO - PID 5372 | [Parent 3576, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3387
[task 2022-11-23T15:35:43.888Z] 15:35:43 INFO - PID 5372 | [Parent 3576, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2022-11-23T15:35:43.937Z] 15:35:43 INFO - PID 5372 | [Parent 3576, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4628
[task 2022-11-23T15:35:43.998Z] 15:35:43 INFO - Browser exited with return code 0
| Comment hidden (Intermittent Failures Robot) |
|
||
Comment 2•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
|
Reporter | |
Comment 3•3 years ago
|
||
| treeherder | ||
New failure instance: https://treeherder.mozilla.org/logviewer?job_id=417318467&repo=mozilla-beta
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
| Comment hidden (Intermittent Failures Robot) |
|
|
||
Comment 5•3 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.
Status: REOPENED → RESOLVED
Closed: 3 years ago → 3 years ago
Resolution: --- → INCOMPLETE
You need to log in
before you can comment on or make changes to this bug.
Description
•