Closed Bug 1824356 Opened 2 years ago Closed 2 years ago

Intermittent /fs/FileSystemBaseHandle-postMessage-BroadcastChannel.https.window.html | single tracking bug

Categories

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

Product:
Core
Component:
DOM: File
Type:
defect

Tracking

()

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

[task 2023-03-24T12:14:06.596Z] 12:14:06     INFO - TEST-START | /fs/FileSystemBaseHandle-postMessage-BroadcastChannel.https.window.html
[task 2023-03-24T12:14:06.600Z] 12:14:06     INFO - Closing window ce81e5cf-177e-4ab8-9003-0225c86fc84c
[task 2023-03-24T12:14:06.617Z] 12:14:06     INFO - PID 1340 | [Child 3328, Main Thread] WARNING: QM_TRY failure (ERROR): 'OkIf(false)', file dom/fs/shared/IPCRejectReporter.cpp:22
[task 2023-03-24T12:14:07.260Z] 12:14:07     INFO - PID 1340 | [Parent 5404, StreamTrans #4] WARNING: IPC Connection Error: [Parent][PFileSystemManagerParent] Send(msgname=PFileSystemManager::Reply_GetDirectoryHandle) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-03-24T12:14:16.832Z] 12:14:16     INFO - PID 1340 | [Parent 5404, DOMCacheThread] WARNING: QM_TRY failure (WARNING): '"ToResult(file->Remove( false))" failed with resultCode 0x80520012, resultName NS_ERROR_FILE_NOT_FOUND', file dom/cache/FileUtils.cpp:774
[task 2023-03-24T12:14:16.836Z] 12:14:16     INFO - PID 1340 | [Parent 5404, DOMCacheThread] WARNING: QM_TRY failure (WARNING): '"ToResult(file->Remove( false))" failed with resultCode 0x80520012, resultName NS_ERROR_FILE_NOT_FOUND', file dom/cache/FileUtils.cpp:774
[task 2023-03-24T12:14:19.186Z] 12:14:19     INFO - PID 1340 | [Child 4844, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-24T12:14:20.211Z] 12:14:20     INFO - PID 1340 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_167965443836495\AppData\Local\Temp\tmph5u1q2og\runtests_leaks_9052_tab_pid5248.log
[task 2023-03-24T12:14:20.218Z] 12:14:20     INFO - PID 1340 | [5248, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:345
[task 2023-03-24T12:14:20.229Z] 12:14:20     INFO - PID 1340 | [Child 5248, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-24T12:14:20.252Z] 12:14:20     INFO - PID 1340 | [Child 5248, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-24T12:14:20.253Z] 12:14:20     INFO - PID 1340 | [Child 5248, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-24T12:14:20.264Z] 12:14:20     INFO - PID 1340 | [Child 5248, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-24T12:14:50.334Z] 12:14:50     INFO - PID 1340 | [Parent 5404, Main Thread] WARNING: 'mProgress != Progress::ShutdownCompleted', file /builds/worker/checkouts/gecko/dom/serviceworkers/ServiceWorkerShutdownState.cpp:56
[task 2023-03-24T12:14:50.345Z] 12:14:50     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerController.cpp:475
[task 2023-03-24T12:14:50.349Z] 12:14:50     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: 'aResult.IsReject()', file /builds/worker/checkouts/gecko/dom/workers/remoteworkers/RemoteWorkerControllerParent.cpp:125
[task 2023-03-24T12:14:50.360Z] 12:14:50     INFO - PID 1340 | [Child 4844, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-24T12:14:50.364Z] 12:14:50     INFO - PID 1340 | [Child 4844, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-24T12:14:50.369Z] 12:14:50     INFO - PID 1340 | [Child 4844, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-24T12:17:06.895Z] 12:17:06     INFO - 
[task 2023-03-24T12:17:06.896Z] 12:17:06     INFO - TEST-UNEXPECTED-TIMEOUT | /fs/FileSystemBaseHandle-postMessage-BroadcastChannel.https.window.html | Send and receive messages using a broadcast channel in an iframe, dedicated worker and service worker. - Test timed out
[task 2023-03-24T12:17:06.896Z] 12:17:06     INFO - TEST-UNEXPECTED-TIMEOUT | /fs/FileSystemBaseHandle-postMessage-BroadcastChannel.https.window.html | expected OK
[task 2023-03-24T12:17:06.896Z] 12:17:06     INFO - TEST-INFO took 180294ms
[task 2023-03-24T12:17:06.898Z] 12:17:06     INFO - PID 1340 | 1679660226898	Marionette	INFO	Stopped listening on port 62608
[task 2023-03-24T12:17:06.976Z] 12:17:06     INFO - PID 1340 | [Child 9128, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-03-24T12:17:06.980Z] 12:17:06     INFO - PID 1340 | [Child 9128, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-03-24T12:17:06.981Z] 12:17:06     INFO - PID 1340 | [Child 9128, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-03-24T12:17:06.982Z] 12:17:06     INFO - PID 1340 | [Child 9128, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-03-24T12:17:06.983Z] 12:17:06     INFO - PID 1340 | [Child 9128, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-03-24T12:17:06.983Z] 12:17:06     INFO - PID 1340 | [Child 9128, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-03-24T12:17:06.984Z] 12:17:06     INFO - PID 1340 | [Child 9128, Main Thread] WARNING: '!CanSend() || !mManager || !mManager->CanSend()', file /builds/worker/checkouts/gecko/dom/ipc/jsactor/JSWindowActorChild.cpp:44
[task 2023-03-24T12:17:07.021Z] 12:17:07     INFO - PID 1340 | [Child 8160, 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:4398
[task 2023-03-24T12:17:07.049Z] 12:17:07     INFO - PID 1340 | [GPU 4944, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2023-03-24T12:17:07.068Z] 12:17:07     INFO - PID 1340 | [Child 3328, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:302
[task 2023-03-24T12:17:07.092Z] 12:17:07     INFO - PID 1340 | [Parent 5404, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-03-24T12:17:07.094Z] 12:17:07     INFO - PID 1340 | [Parent 5404, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-03-24T12:17:07.104Z] 12:17:07     INFO - PID 1340 | [Child 8160, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-24T12:17:07.111Z] 12:17:07     INFO - PID 1340 | [Child 8160, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-24T12:17:07.119Z] 12:17:07     INFO - PID 1340 | [Child 3328, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:302
[task 2023-03-24T12:17:07.120Z] 12:17:07     INFO - PID 1340 | [Child 8160, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-24T12:17:07.132Z] 12:17:07     INFO - PID 1340 | [Child 3328, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:302
[task 2023-03-24T12:17:07.141Z] 12:17:07     INFO - PID 1340 | [Child 3328, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:302
[task 2023-03-24T12:17:07.142Z] 12:17:07     INFO - PID 1340 | [Child 3328, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-24T12:17:07.144Z] 12:17:07     INFO - PID 1340 | [Child 3328, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-24T12:17:07.153Z] 12:17:07     INFO - PID 1340 | [Child 3328, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-24T12:17:07.176Z] 12:17:07     INFO - PID 1340 | [Child 9128, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-24T12:17:07.192Z] 12:17:07     INFO - PID 1340 | [Child 9128, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-24T12:17:07.200Z] 12:17:07     INFO - PID 1340 | [Child 9128, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-24T12:17:07.411Z] 12:17:07     INFO - PID 1340 | [GPU 4944, 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:1927
[task 2023-03-24T12:17:07.427Z] 12:17:07     INFO - PID 1340 | [GPU 4944, Compositor] WARNING: IPC Connection Error: [Parent][PCompositorManagerParent] RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-03-24T12:17:07.428Z] 12:17:07     INFO - PID 1340 | [GPU 4944, 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:1927
[task 2023-03-24T12:17:07.430Z] 12:17:07     INFO - PID 1340 | [GPU 4944, Compositor] WARNING: IPC Connection Error: [Parent][PCompositorManagerParent] RunMessage(msgname=PCompositorBridge::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-03-24T12:17:07.430Z] 12:17:07     INFO - PID 1340 | [Child 8220, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-24T12:17:07.439Z] 12:17:07     INFO - PID 1340 | [Child 524, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-24T12:17:07.440Z] 12:17:07     INFO - PID 1340 | [Child 8220, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-24T12:17:07.441Z] 12:17:07     INFO - PID 1340 | [Child 524, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-24T12:17:07.442Z] 12:17:07     INFO - PID 1340 | [Child 5248, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-24T12:17:07.442Z] 12:17:07     INFO - PID 1340 | [Child 5248, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-24T12:17:07.445Z] 12:17:07     INFO - PID 1340 | [Child 8220, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-24T12:17:07.446Z] 12:17:07     INFO - PID 1340 | [Child 524, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-24T12:17:07.447Z] 12:17:07     INFO - PID 1340 | [Child 5248, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-24T12:17:07.520Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.525Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: initiateShutdownWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.526Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.526Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.527Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.528Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: fs: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.528Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.529Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.529Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.530Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.541Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: idb: '0.032000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.545Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: idb: '0.032000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.545Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: idb: '0.032000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.567Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: quota manager shutdown step: '0.047000s: stopKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.570Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: quota manager shutdown step: '0.047000s: shutdownAndJoinWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.571Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: idb: '0.047000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.572Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: cache: '0.047000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.572Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: sdb: '0.047000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.573Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: fs: '0.047000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.574Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: ls: '0.047000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.575Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: quota manager shutdown step: '0.047000s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.575Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: quota manager shutdown step: '0.047000s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.576Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: quota manager shutdown step: '0.047000s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:3330
[task 2023-03-24T12:17:07.577Z] 12:17:07     INFO - PID 1340 | [Parent 5404, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80004005 (NS_ERROR_FAILURE): file /builds/worker/checkouts/gecko/toolkit/xre/nsXREDirProvider.cpp:475
[task 2023-03-24T12:17:07.614Z] 12:17:07     INFO - PID 1340 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-03-24T12:17:07.615Z] 12:17:07     INFO - PID 1340 |           Blocker:  Waiting for ping task
[task 2023-03-24T12:17:07.616Z] 12:17:07     INFO - PID 1340 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-03-24T12:17:07.616Z] 12:17:07     INFO - PID 1340 |           State: (none)
[task 2023-03-24T12:17:07.617Z] 12:17:07     INFO - PID 1340 | 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-24T12:17:07.618Z] 12:17:07     INFO - PID 1340 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-24T12:17:07.618Z] 12:17:07     INFO - PID 1340 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-24T12:17:07.619Z] 12:17:07     INFO - PID 1340 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-24T12:17:07.619Z] 12:17:07     INFO - PID 1340 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-24T12:17:07.620Z] 12:17:07     INFO - PID 1340 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-24T12:17:07.620Z] 12:17:07     INFO - PID 1340 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-24T12:17:07.621Z] 12:17:07     INFO - PID 1340 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-24T12:17:07.622Z] 12:17:07     INFO - PID 1340 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-24T12:17:07.622Z] 12:17:07     INFO - PID 1340 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-24T12:17:07.623Z] 12:17:07     INFO - PID 1340 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-24T12:17:07.623Z] 12:17:07     INFO - PID 1340 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-24T12:17:07.624Z] 12:17:07     INFO - PID 1340 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-24T12:17:07.625Z] 12:17:07     INFO - PID 1340 | console.error: "TelemetryScheduler.shutdown - Already shut down"
[task 2023-03-24T12:17:07.636Z] 12:17:07     INFO - PID 1340 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-03-24T12:17:07.646Z] 12:17:07     INFO - PID 1340 |           Blocker:  Waiting for ping task
[task 2023-03-24T12:17:07.646Z] 12:17:07     INFO - PID 1340 |           Phase: TelemetryController: Waiting for pending ping activity
[task 2023-03-24T12:17:07.647Z] 12:17:07     INFO - PID 1340 |           State: (none)
[task 2023-03-24T12:17:07.648Z] 12:17:07     INFO - PID 1340 | 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-24T12:17:07.649Z] 12:17:07     INFO - PID 1340 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-24T12:17:07.650Z] 12:17:07     INFO - PID 1340 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-24T12:17:07.650Z] 12:17:07     INFO - PID 1340 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-24T12:17:07.651Z] 12:17:07     INFO - PID 1340 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-24T12:17:07.651Z] 12:17:07     INFO - PID 1340 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-24T12:17:07.652Z] 12:17:07     INFO - PID 1340 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-24T12:17:07.652Z] 12:17:07     INFO - PID 1340 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-24T12:17:07.653Z] 12:17:07     INFO - PID 1340 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-24T12:17:07.653Z] 12:17:07     INFO - PID 1340 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-24T12:17:07.654Z] 12:17:07     INFO - PID 1340 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-24T12:17:07.654Z] 12:17:07     INFO - PID 1340 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-24T12:17:07.655Z] 12:17:07     INFO - PID 1340 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-24T12:17:07.655Z] 12:17:07     INFO - PID 1340 | WARNING: A blocker encountered an error while we were waiting.
[task 2023-03-24T12:17:07.656Z] 12:17:07     INFO - PID 1340 |           Blocker:  TelemetryController: shutting down
[task 2023-03-24T12:17:07.656Z] 12:17:07     INFO - PID 1340 |           Phase: profile-before-change-telemetry
[task 2023-03-24T12:17:07.657Z] 12:17:07     INFO - PID 1340 |           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-24T12:17:07.658Z] 12:17:07     INFO - PID 1340 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-24T12:17:07.658Z] 12:17:07     INFO - PID 1340 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-24T12:17:07.659Z] 12:17:07     INFO - PID 1340 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-24T12:17:07.660Z] 12:17:07     INFO - PID 1340 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-24T12:17:07.660Z] 12:17:07     INFO - PID 1340 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-24T12:17:07.661Z] 12:17:07     INFO - PID 1340 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-24T12:17:07.662Z] 12:17:07     INFO - PID 1340 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-24T12:17:07.662Z] 12:17:07     INFO - PID 1340 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-24T12:17:07.663Z] 12:17:07     INFO - PID 1340 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-24T12:17:07.664Z] 12:17:07     INFO - PID 1340 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-24T12:17:07.664Z] 12:17:07     INFO - PID 1340 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-24T12:17:07.665Z] 12:17:07     INFO - PID 1340 | 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-24T12:17:07.666Z] 12:17:07     INFO - PID 1340 | WARNING: addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:727:15
[task 2023-03-24T12:17:07.666Z] 12:17:07     INFO - PID 1340 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:523:26
[task 2023-03-24T12:17:07.667Z] 12:17:07     INFO - PID 1340 | addBlocker@resource://gre/modules/AsyncShutdown.sys.mjs:458:15
[task 2023-03-24T12:17:07.668Z] 12:17:07     INFO - PID 1340 | setupShutdown@resource://gre/modules/osfile/osfile_async_front.jsm:1548:28
[task 2023-03-24T12:17:07.668Z] 12:17:07     INFO - PID 1340 | @resource://gre/modules/osfile/osfile_async_front.jsm:1568:16
[task 2023-03-24T12:17:07.669Z] 12:17:07     INFO - PID 1340 | @resource://gre/modules/osfile.jsm:12:30
[task 2023-03-24T12:17:07.670Z] 12:17:07     INFO - PID 1340 | @resource://gre/modules/TelemetryStorage.sys.mjs:10:28
[task 2023-03-24T12:17:07.671Z] 12:17:07     INFO - PID 1340 | _checkPendingPings@resource://gre/modules/TelemetrySend.sys.mjs:863:17
[task 2023-03-24T12:17:07.672Z] 12:17:07     INFO - PID 1340 | setup@resource://gre/modules/TelemetrySend.sys.mjs:803:18
[task 2023-03-24T12:17:07.672Z] 12:17:07     INFO - PID 1340 | setup@resource://gre/modules/TelemetrySend.sys.mjs:241:30
[task 2023-03-24T12:17:07.673Z] 12:17:07     INFO - PID 1340 | setupTelemetry/this._delayedInitTask<@resource://gre/modules/TelemetryControllerParent.sys.mjs:824:36
[task 2023-03-24T12:17:07.674Z] 12:17:07     INFO - PID 1340 | observe@resource://gre/modules/AsyncShutdown.sys.mjs:576:16
[task 2023-03-24T12:17:07.674Z] 12:17:07     INFO - PID 1340 | 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-24T12:17:07.675Z] 12:17:07     INFO - PID 1340 | 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-24T12:17:07.676Z] 12:17:07     INFO - PID 1340 | 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-24T12:17:07.677Z] 12:17:07     INFO - PID 1340 | 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-24T12:17:07.678Z] 12:17:07     INFO - PID 1340 | 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-24T12:17:07.679Z] 12:17:07     INFO - PID 1340 | [Utility 9208, Main Thread] WARNING: IPC message 'PUtilityProcess::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-03-24T12:17:07.680Z] 12:17:07     INFO - PID 1340 | [GPU 4944, 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:1927
[task 2023-03-24T12:17:07.681Z] 12:17:07     INFO - PID 1340 | [GPU 4944, Main Thread] WARNING: IPC message 'PGPU::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:504
[task 2023-03-24T12:17:07.682Z] 12:17:07     INFO - PID 1340 | [Parent 5404, IPDL Background] WARNING: IPC Connection Error: [Parent][PBackgroundParent] RunMessage(msgname=PRemoteWorkerService::Msg___delete__) Channel closing: too late to send/recv, messages will be lost: file /builds/worker/checkouts/gecko/ipc/glue/MessageChannel.cpp:1927
[task 2023-03-24T12:17:07.683Z] 12:17:07     INFO - PID 1340 | [Utility 9208, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-24T12:17:07.685Z] 12:17:07     INFO - PID 1340 | [GPU 4944, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-24T12:17:07.960Z] 12:17:07     INFO - PID 1340 | [Parent 5404, Main Thread] WARNING: '!top', file /builds/worker/checkouts/gecko/dom/xul/MenuBarListener.cpp:99
[task 2023-03-24T12:17:08.069Z] 12:17:08     INFO - PID 1340 | [Parent 5404, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3396
[task 2023-03-24T12:17:08.093Z] 12:17:08     INFO - PID 1340 | [Parent 5404, Main Thread] WARNING: NS_ENSURE_TRUE(InitStaticMembers()) failed: file /builds/worker/workspace/obj-build/dist/include/mozilla/Preferences.h:129
[task 2023-03-24T12:17:08.132Z] 12:17:08     INFO - PID 1340 | [Parent 5404, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4689
[task 2023-03-24T12:17:08.164Z] 12:17:08     INFO - Browser exited with return code 0
[task 2023-03-24T12:17:08.166Z] 12:17:08     INFO - PROCESS LEAKS C:\Users\task_167965443836495\AppData\Local\Temp\tmph5u1q2og\runtests_leaks_9052.log
[task 2023-03-24T12:17:08.167Z] 12:17:08     INFO - leakcheck | Processing log file C:\Users\task_167965443836495\AppData\Local\Temp\tmph5u1q2og\runtests_leaks_9052.log for scope /fs
[task 2023-03-24T12:17:08.167Z] 12:17:08     INFO - TEST-INFO | leakcheck | default process: leak threshold set at 16000 bytes
[task 2023-03-24T12:17:08.168Z] 12:17:08     INFO - TEST-INFO | leakcheck | tab process: leak threshold set at 10000 bytes
[task 2023-03-24T12:17:08.169Z] 12:17:08     INFO - TEST-INFO | leakcheck | gmplugin process: leak threshold set at 20000 bytes
[task 2023-03-24T12:17:08.169Z] 12:17:08     INFO - TEST-INFO | leakcheck | gpu process: leak threshold set at 10000 bytes
[task 2023-03-24T12:17:08.169Z] 12:17:08     INFO - TEST-INFO | leakcheck | vr process: leak threshold set at 0 bytes
[task 2023-03-24T12:17:08.170Z] 12:17:08     INFO - TEST-INFO | leakcheck | rdd process: leak threshold set at 400 bytes
[task 2023-03-24T12:17:08.170Z] 12:17:08     INFO - TEST-INFO | leakcheck | socket process: leak threshold set at 0 bytes
[task 2023-03-24T12:17:08.170Z] 12:17:08     INFO - TEST-INFO | leakcheck | sandboxbroker process: leak threshold set at 0 bytes
[task 2023-03-24T12:17:08.171Z] 12:17:08     INFO - TEST-INFO | leakcheck | forkserver process: leak threshold set at 0 bytes
[task 2023-03-24T12:17:08.171Z] 12:17:08     INFO - TEST-INFO | leakcheck | utility process: leak threshold set at 0 bytes
[task 2023-03-24T12:17:08.172Z] 12:17:08     INFO - leakcheck | Processing leak log file C:\Users\task_167965443836495\AppData\Local\Temp\tmph5u1q2og\runtests_leaks_9052.log
[task 2023-03-24T12:17:08.172Z] 12:17:08     INFO - TEST-PASS | leakcheck | default no leaks detected!
<...>
[task 2023-03-24T12:17:08.333Z] 12:17:08     INFO - PID 2588 | [WARN  rkv::backend::impl_safe::environment] `load_ratio()` is irrelevant for this storage backend.
[task 2023-03-24T12:17:08.334Z] 12:17:08     INFO - PID 2588 | console.error: (new Error("Polling for changes failed: Unexpected content-type \"text/plain;charset=US-ASCII\".", "resource://services-settings/remote-settings.sys.mjs", 324))
[task 2023-03-24T12:17:08.334Z] 12:17:08     INFO - Starting runner
[task 2023-03-24T12:17:09.685Z] 12:17:09     INFO - TEST-START | /fs/FileSystemBaseHandle-postMessage-Error.https.window.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=441304979&repo=mozilla-beta

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.