1846185 - Frequent Win 11 debug Mn/Wd application crashed [@ DbgBreakPoint] | after WARNING: Process <PID> may be hanging at shutdown; will wait for up to <TIME>ms)

Status: RESOLVED FIXED

(Firefox :: Translations, defect)

Description

[Treeherder Bug Filer]

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

---

[task 2023-07-30T14:37:10.672Z] 14:37:10     INFO - STDOUT: PASSED
[task 2023-07-30T14:37:10.675Z] 14:37:10     INFO - PID 8656 | 1690727830674	RemoteAgent	DEBUG	WebDriverBiDiConnection 3f421b62-d685-468a-bfab-0199b79b8a56 -> {"id":96,"method":"browsingContext.close","params":{"context":"4790b8e1-b7ec-4d96-80c9-26764e50f5bf"}}
[task 2023-07-30T14:37:10.676Z] 14:37:10     INFO - PID 8656 | 1690727830674	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-07-30T14:37:10.677Z] 14:37:10     INFO - PID 8656 | 1690727830674	RemoteAgent	TRACE	Received command browsingContext.close for destination ROOT
[task 2023-07-30T14:37:10.677Z] 14:37:10     INFO - PID 8656 | 1690727830674	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-07-30T14:37:10.689Z] 14:37:10     INFO - PID 8656 | 1690727830697	RemoteAgent	DEBUG	WebDriverBiDiConnection 3f421b62-d685-468a-bfab-0199b79b8a56 <- {"id":96,"result":{}}
[task 2023-07-30T14:37:10.710Z] 14:37:10     INFO - PID 8656 | 1690727830712	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session 111df47c-c48e-4e20-8b4c-637373e05f69 is being destroyed
[task 2023-07-30T14:37:10.713Z] 14:37:10     INFO - PID 8656 | 1690727830712	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session 111df47c-c48e-4e20-8b4c-637373e05f69
[task 2023-07-30T14:37:10.721Z] 14:37:10     INFO - PID 8656 | 1690727830719	RemoteAgent	DEBUG	WebDriverBiDiConnection 3f421b62-d685-468a-bfab-0199b79b8a56 -> {"id":97,"method":"session.unsubscribe","params":{"events":["browsingContext.fragmentNavigated"]}}
[task 2023-07-30T14:37:10.721Z] 14:37:10     INFO - PID 8656 | 1690727830719	RemoteAgent	TRACE	Module root/session.sys.mjs found for ROOT
[task 2023-07-30T14:37:10.722Z] 14:37:10     INFO - PID 8656 | 1690727830719	RemoteAgent	TRACE	Received command session.unsubscribe for destination ROOT
[task 2023-07-30T14:37:10.722Z] 14:37:10     INFO - PID 8656 | 1690727830719	RemoteAgent	TRACE	Module root/session.sys.mjs found for ROOT
[task 2023-07-30T14:37:10.723Z] 14:37:10     INFO - PID 8656 | 1690727830720	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-07-30T14:37:10.724Z] 14:37:10     INFO - PID 8656 | 1690727830722	RemoteAgent	TRACE	Module windowglobal-in-root/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-07-30T14:37:10.725Z] 14:37:10     INFO - PID 8656 | 1690727830722	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-07-30T14:37:10.726Z] 14:37:10     INFO - PID 8656 | 1690727830722	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-07-30T14:37:10.727Z] 14:37:10     INFO - PID 8656 | 1690727830722	RemoteAgent	TRACE	Module windowglobal-in-root/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-07-30T14:37:10.727Z] 14:37:10     INFO - PID 8656 | 1690727830722	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-07-30T14:37:10.728Z] 14:37:10     INFO - PID 8656 | 1690727830722	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-07-30T14:37:10.729Z] 14:37:10     INFO - PID 8656 | 1690727830722	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination ROOT
[task 2023-07-30T14:37:10.730Z] 14:37:10     INFO - PID 8656 | 1690727830722	RemoteAgent	TRACE	Module root/browsingContext.sys.mjs found for ROOT
[task 2023-07-30T14:37:10.730Z] 14:37:10     INFO - PID 8656 | 1690727830723	RemoteAgent	TRACE	Received command browsingContext._applySessionData for destination WINDOW_GLOBAL
[task 2023-07-30T14:37:10.731Z] 14:37:10     INFO - PID 8656 | 1690727830723	RemoteAgent	TRACE	Module windowglobal/browsingContext.sys.mjs found for WINDOW_GLOBAL
[task 2023-07-30T14:37:10.732Z] 14:37:10     INFO - PID 8656 | 1690727830725	RemoteAgent	DEBUG	WebDriverBiDiConnection 3f421b62-d685-468a-bfab-0199b79b8a56 <- {"id":97,"result":{}}
[task 2023-07-30T14:37:10.733Z] 14:37:10     INFO - PID 8656 | 1690727830730	RemoteAgent	DEBUG	WebDriverBiDiConnection 3f421b62-d685-468a-bfab-0199b79b8a56 closed
[task 2023-07-30T14:37:10.734Z] 14:37:10     INFO - PID 8656 | 1690727830731	webdriver::server	DEBUG	-> POST /session/111df47c-c48e-4e20-8b4c-637373e05f69/timeouts {"implicit": 0}
[task 2023-07-30T14:37:10.735Z] 14:37:10     INFO - PID 8656 | 1690727830732	Marionette	DEBUG	0 -> [0,270,"WebDriver:SetTimeouts",{"implicit":0}]
[task 2023-07-30T14:37:10.736Z] 14:37:10     INFO - PID 8656 | 1690727830732	Marionette	DEBUG	0 <- [1,270,null,{"value":null}]
[task 2023-07-30T14:37:10.736Z] 14:37:10     INFO - PID 8656 | 1690727830733	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-07-30T14:37:10.739Z] 14:37:10     INFO - PID 8656 | 1690727830734	webdriver::server	DEBUG	-> POST /session/111df47c-c48e-4e20-8b4c-637373e05f69/timeouts {"pageLoad": 300000}
[task 2023-07-30T14:37:10.742Z] 14:37:10     INFO - PID 8656 | 1690727830735	Marionette	DEBUG	0 -> [0,271,"WebDriver:SetTimeouts",{"pageLoad":300000}]
<...>
[task 2023-07-30T14:37:10.902Z] 14:37:10     INFO - PID 8656 | 1690727830902	Marionette	DEBUG	Marionette stopped listening
[task 2023-07-30T14:37:10.903Z] 14:37:10     INFO - PID 8656 | 1690727830902	Marionette	DEBUG	0 <- [1,284,null,{"cause":"shutdown","forced":false,"in_app":true}]
[task 2023-07-30T14:37:10.922Z] 14:37:10     INFO - PID 8656 | [GPU 6536, Compositor] WARNING: Possibly dropping task posted to updater thread: file /builds/worker/checkouts/gecko/gfx/layers/apz/src/APZUpdater.cpp:371
[task 2023-07-30T14:37:10.938Z] 14:37:10     INFO - PID 8656 | 1690727830937	RemoteAgent	DEBUG	Resetting recommended pref browser.contentblocking.features.standard
[task 2023-07-30T14:37:10.939Z] 14:37:10     INFO - PID 8656 | 1690727830937	RemoteAgent	DEBUG	Resetting recommended pref network.cookie.cookieBehavior
[task 2023-07-30T14:37:10.940Z] 14:37:10     INFO - PID 8656 | 1690727830938	webdriver::server	DEBUG	Teardown session
[task 2023-07-30T14:37:10.946Z] 14:37:10     INFO - PID 8656 | 1690727830945	Marionette	DEBUG	Closed connection 0
[task 2023-07-30T14:37:10.956Z] 14:37:10     INFO - PID 8656 | 1690727830955	RemoteAgent	TRACE	MessageHandler WINDOW_GLOBAL for session 111df47c-c48e-4e20-8b4c-637373e05f69 is being destroyed
[task 2023-07-30T14:37:10.957Z] 14:37:10     INFO - PID 8656 | 1690727830956	RemoteAgent	TRACE	Unregistered MessageHandler WINDOW_GLOBAL for session 111df47c-c48e-4e20-8b4c-637373e05f69
[task 2023-07-30T14:37:10.961Z] 14:37:10     INFO - PID 8656 | [Parent 3640, Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/checkouts/gecko/netwerk/protocol/http/nsHttpConnectionMgr.cpp:232
[task 2023-07-30T14:37:10.962Z] 14:37:10     INFO - PID 8656 | [Child 8388, IPC I/O Child] WARNING: [7B77838C432E580E.EA5C4E411F9E215E]: GetUserData call for port '36A611C4709631B5.275B9631E7584266' failed: file /builds/worker/checkouts/gecko/ipc/glue/NodeController.cpp:406
[task 2023-07-30T14:37:10.967Z] 14:37:10     INFO - PID 8656 | [Child 8388, Main Thread] WARNING: A runnable was posted to a worker that is already shutting down!: file /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp:1595
[task 2023-07-30T14:37:10.968Z] 14:37:10     INFO - PID 8656 | [Child 8388, Main Thread] WARNING: Failed to dispatch offline status change event!: file /builds/worker/checkouts/gecko/dom/workers/WorkerPrivate.cpp:2091
[task 2023-07-30T14:37:10.983Z] 14:37:10     INFO - PID 8656 | [Parent 3640, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, nullptr) failed with result 0x8046001E (NS_ERROR_ILLEGAL_DURING_SHUTDOWN): file /builds/worker/checkouts/gecko/toolkit/components/credentialmanagement/IdentityCredentialStorageService.cpp:55
[task 2023-07-30T14:37:10.986Z] 14:37:10     INFO - PID 8656 | [Parent 3640, Main Thread] WARNING: NS_ENSURE_TRUE(inst) failed: file StaticComponents.cpp:10829
[task 2023-07-30T14:37:10.991Z] 14:37:10     INFO - PID 8656 | console.error: ({})
[task 2023-07-30T14:37:11.236Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.251Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: initiateShutdownWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.252Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: idb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.253Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: cache: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.254Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: sdb: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.254Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: fs: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.256Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: ls: '0.000000s: starting', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.256Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: quota manager shutdown step: '0.000000s: startKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.256Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.259Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.260Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.260Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.260Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: idb: '0.000000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.262Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: idb: '0.000000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.263Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: idb: '0.016000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.263Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: idb: '0.016000s: Live database entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.263Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: idb: '0.016000s: gLiveDatabaseHashtable entry removed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.267Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: quota manager shutdown step: '0.031000s: stopKillActorsTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.277Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: quota manager shutdown step: '0.031000s: shutdownAndJoinWorkThreads', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.278Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: idb: '0.031000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.279Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: cache: '0.031000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.279Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: sdb: '0.031000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.280Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: fs: '0.031000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.281Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: ls: '0.031000s: completed', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.282Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: quota manager shutdown step: '0.031000s: shutdownAndJoinIOThread', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.283Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: quota manager shutdown step: '0.031000s: invalidatePendingDirectoryLocks', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.284Z] 14:37:11     INFO - PID 8656 | [Parent 3640, IPDL Background] WARNING: quota manager shutdown step: '0.031000s: stopCrashBrowserTimer', file /builds/worker/checkouts/gecko/dom/quota/ActorsParent.cpp:2285
[task 2023-07-30T14:37:11.407Z] 14:37:11     INFO - PID 8656 | [Utility 8332, Main Thread] WARNING: IPC message 'PUtilityProcess::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:508
[task 2023-07-30T14:37:11.414Z] 14:37:11     INFO - PID 8656 | [GPU 6536, Main Thread] WARNING: IPC message 'PGPU::Msg_FOGData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:508
[task 2023-07-30T14:37:11.425Z] 14:37:11     INFO - PID 8656 | Destroying context 07171000 surface 05380820 on display 0713CA00
[task 2023-07-30T14:37:11.517Z] 14:37:11     INFO - PID 8656 | [Child 5944, Main Thread] WARNING: IPC message 'PContent::Msg_AccumulateChildHistograms' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:508
[task 2023-07-30T14:37:11.519Z] 14:37:11     INFO - PID 8656 | [Child 5944, Main Thread] WARNING: '!ipcActor->SendAccumulateChildHistograms(histogramsToSend)', file /builds/worker/checkouts/gecko/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp:279
[task 2023-07-30T14:37:11.519Z] 14:37:11     INFO - PID 8656 | [Child 5944, Main Thread] WARNING: IPC message 'PContent::Msg_RecordDiscardedData' discarded: actor cannot send: file /builds/worker/checkouts/gecko/ipc/glue/ProtocolUtils.cpp:508
[task 2023-07-30T14:37:11.520Z] 14:37:11     INFO - PID 8656 | [Child 5944, Main Thread] WARNING: '!ipcActor->SendRecordDiscardedData(discardedData)', file /builds/worker/checkouts/gecko/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp:298
[task 2023-07-30T14:37:17.354Z] 14:37:17     INFO - PID 8656 | [Parent 3640, Main Thread] WARNING: You don't want to get wake lock information during xpcom-shutdown!: file /builds/worker/checkouts/gecko/hal/HalWakeLock.cpp:242
[task 2023-07-30T14:37:17.356Z] 14:37:17     INFO - PID 8656 | [Parent 3640, Main Thread] WARNING: You don't want to get wake lock information during xpcom-shutdown!: file /builds/worker/checkouts/gecko/hal/HalWakeLock.cpp:242
[task 2023-07-30T14:37:21.872Z] 14:37:21     INFO - PID 8656 | [Parent 3640, Main Thread] WARNING: '!top', file /builds/worker/checkouts/gecko/dom/xul/MenuBarListener.cpp:99
[task 2023-07-30T14:37:21.879Z] 14:37:21     INFO - PID 8656 | [Parent 3640, Main Thread] WARNING: '!top', file /builds/worker/checkouts/gecko/dom/xul/MenuBarListener.cpp:99
[task 2023-07-30T14:37:22.014Z] 14:37:22     INFO - PID 8656 | [Parent 3640, IPC I/O Parent] WARNING: Process 5944 may be hanging at shutdown; will wait for up to 8000ms: file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/process_watcher_win.cc:129
[task 2023-07-30T14:37:30.016Z] 14:37:30     INFO - PID 8656 | [Parent 3640, IPC I/O Parent] WARNING: Process 5944 hanging at shutdown; attempting crash report (fatal error): file /builds/worker/checkouts/gecko/ipc/chromium/src/chrome/common/process_watcher_win.cc:164
[task 2023-07-30T14:37:30.345Z] 14:37:30     INFO - PID 8656 | 1690727850345	geckodriver::browser	DEBUG	Browser process stopped: exit code: 0
[task 2023-07-30T14:37:30.347Z] 14:37:30     INFO - PID 8656 | 1690727850346	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2023-07-30T14:37:30.349Z] 14:37:30     INFO - STDOUT: ================================== FAILURES ===================================
[task 2023-07-30T14:37:30.350Z] 14:37:30     INFO - STDOUT: _____________________________ test_document_write _____________________________
[task 2023-07-30T14:37:30.351Z] 14:37:30     INFO - STDOUT: bidi_session = <webdriver.bidi.client.BidiSession object at 0x000002442CF40E80>
[task 2023-07-30T14:37:30.351Z] 14:37:30     INFO - STDOUT: subscribe_events = <function subscribe_events.<locals>.subscribe_events at 0x000002442CFDB820>
[task 2023-07-30T14:37:30.363Z] 14:37:30     INFO - mozcrash .................
[task 2023-07-30T14:37:30.363Z] 14:37:30     INFO - mozcrash Copy/paste: Z:\task_169071922299099\fetches\minidump-stackwalk\minidump-stackwalk.exe --symbols-url=https://symbols.mozilla.org/ --cyborg=C:\Users\task_169071922299099\AppData\Local\Temp\tmp6gawfo0n\2d1b8cbf-136f-4b81-9475-f54fdf029fcb.trace C:\Users\task_169071922299099\AppData\Local\Temp\tmp9y01hemy.mozrunner\minidumps\2d1b8cbf-136f-4b81-9475-f54fdf029fcb.dmp Z:\task_169071922299099\build\symbols
[task 2023-07-30T14:37:40.896Z] 14:37:40     INFO - mozcrash Saved minidump as Z:\task_169071922299099\build\blobber_upload_dir\2d1b8cbf-136f-4b81-9475-f54fdf029fcb.dmp
[task 2023-07-30T14:37:41.052Z] 14:37:41     INFO - PROCESS-CRASH | application crashed [@ DbgBreakPoint] | /webdriver/tests/bidi/browsing_context/fragment_navigated/fragment_navigated.py 
[task 2023-07-30T14:37:41.052Z] 14:37:41     INFO - Process type: unknown
[task 2023-07-30T14:37:41.052Z] 14:37:41     INFO - Process pid: 5944
[task 2023-07-30T14:37:41.052Z] 14:37:41     INFO - Crash dump filename: C:\Users\task_169071922299099\AppData\Local\Temp\tmp9y01hemy.mozrunner\minidumps\2d1b8cbf-136f-4b81-9475-f54fdf029fcb.dmp
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO - Operating system: Windows NT
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -                   10.0.22621
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO - CPU: x86
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -      GenuineIntel family 6 model 106 stepping 6
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -      8 CPUs
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO - 
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO - Crash reason:  EXCEPTION_BREAKPOINT
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO - Crash address: 0x779285a0
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO - Process uptime: 27 seconds
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO - 
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO - Thread 24  (crashed)
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -  0  ntdll.dll!DbgBreakPoint + 0x0
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -      eip = 0x779285a0    esp = 0x0f8ffe44    ebp = 0x0f8ffe50    ebx = 0x00435049
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -      esi = 0x779285a0    edi = 0x779285a0    eax = 0x0ef250b4    ecx = 0x779285a0
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -      edx = 0x00540000 eflags = 0x00000247
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -     Found by: given as instruction pointer in context
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -  1  kernel32.dll!BaseThreadInitThunk + 0x18
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -      eip = 0x76677d49    esp = 0x0f8ffe48    ebp = 0x0f8ffe50    ebx = 0x00435049
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -      esi = 0x779285a0    edi = 0x779285a0
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -     Found by: call frame info
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -  2  mozglue.dll!mozilla::interceptor::FuncHook<mozilla::interceptor::WindowsDllInterceptor<mozilla::interceptor::VMSharingPolicyShared>,void (*)(int, void *, void *) __attribute__((fastcall))>::operator()(int&, void*&, void*&) const [nsWindowsDllInterceptor.h:ce5b2b0d4bc01294b78eeb50a7738e0b5755b3a4 : 150]
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -     Found by: inlining
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -  3  mozglue.dll!patched_BaseThreadInitThunk(int, void*, void*) [WindowsDllBlocklist.cpp:ce5b2b0d4bc01294b78eeb50a7738e0b5755b3a4 : 566 + 0xa]
[task 2023-07-30T14:37:41.066Z] 14:37:41     INFO -      eip = 0x72080373    esp = 0x0f8ffe58    ebp = 0x0f8ffe84    ebx = 0x00435049
[task 2023-07-30T14:37:41.067Z] 14:37:41     INFO -      esi = 0x779285a0    edi = 0x779285a0
[task 2023-07-30T14:37:41.067Z] 14:37:41     INFO -     Found by: call frame info
[task 2023-07-30T14:37:41.067Z] 14:37:41     INFO -  4  ntdll.dll!__RtlUserThreadStart + 0x2a
[task 2023-07-30T14:37:41.067Z] 14:37:41     INFO -      eip = 0x7791b74b    esp = 0x0f8ffe8c    ebp = 0x0f8ffedc    ebx = 0x00435049
[task 2023-07-30T14:37:41.067Z] 14:37:41     INFO -      esi = 0x76677d30    edi = 0x779285a0
[task 2023-07-30T14:37:41.067Z] 14:37:41     INFO -     Found by: call frame info
[task 2023-07-30T14:37:41.067Z] 14:37:41     INFO -  5  ntdll.dll!_RtlUserThreadStart + 0x1a
[task 2023-07-30T14:37:41.067Z] 14:37:41     INFO -      eip = 0x7791b6cf    esp = 0x0f8ffee4    ebp = 0x0f8ffeec    ebx = 0x00435049
[task 2023-07-30T14:37:41.067Z] 14:37:41     INFO -      esi = 0x76677d30    edi = 0x779285a0
[task 2023-07-30T14:37:41.067Z] 14:37:41     INFO -     Found by: call frame info
[task 2023-07-30T14:37:41.067Z] 14:37:41     INFO - 

Comment 1

[Iulian Moraru]

So far, this only happened on Windows 11 x86 22H2 WebRender debug.

Comment 2

[Iulian Moraru]

Hi Greg! Can you please take a look at this too? It has that same line with shutdown hang that is also present in Bug 1845424. Not sure if it is the same issue or something totally different.
Thank you!

Comment 3

[Intermittent Failures Robot]

7 failures in 3403 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 7

Platform and build breakdown:

• windows11-32-2009-qr: 7
  • debug: 7

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1846185&startday=2023-07-24&endday=2023-07-30&tree=all

Comment 4

[Henrik Skupin [:whimboo][⌚️UTC+2] (away 02/17 - 02/21)]

I actually wonder if that is more a dupe of bug 1831236 given that it also includes the DLL blocklist frame in the crash stack:
https://treeherder.mozilla.org/logviewer?job_id=424295218&repo=autoland&lineNumber=46446-46469

[task 2023-07-30T15:18:10.916Z] 15:18:10     INFO -  Thread 25  (crashed)
[task 2023-07-30T15:18:10.916Z] 15:18:10     INFO -   0  ntdll.dll!DbgBreakPoint + 0x0
[task 2023-07-30T15:18:10.916Z] 15:18:10     INFO -       eip = 0x77cd85a0    esp = 0x1083fbd8    ebp = 0x1083fbe4    ebx = 0x00435049
[task 2023-07-30T15:18:10.916Z] 15:18:10     INFO -       esi = 0x77cd85a0    edi = 0x77cd85a0    eax = 0x0ef9b0b4    ecx = 0x77cd85a0
[task 2023-07-30T15:18:10.916Z] 15:18:10     INFO -       edx = 0x00540000 eflags = 0x00000247
[task 2023-07-30T15:18:10.916Z] 15:18:10     INFO -      Found by: given as instruction pointer in context
[task 2023-07-30T15:18:10.916Z] 15:18:10     INFO -   1  kernel32.dll!BaseThreadInitThunk + 0x18
[task 2023-07-30T15:18:10.916Z] 15:18:10     INFO -       eip = 0x76327d49    esp = 0x1083fbdc    ebp = 0x1083fbe4    ebx = 0x00435049
[task 2023-07-30T15:18:10.916Z] 15:18:10     INFO -       esi = 0x77cd85a0    edi = 0x77cd85a0
[task 2023-07-30T15:18:10.917Z] 15:18:10     INFO -      Found by: call frame info
[task 2023-07-30T15:18:10.917Z] 15:18:10     INFO -   2  mozglue.dll!mozilla::interceptor::FuncHook<mozilla::interceptor::WindowsDllInterceptor<mozilla::interceptor::VMSharingPolicyShared>,void (*)(int, void *, void *) __attribute__((fastcall))>::operator()(int&, void*&, void*&) const [nsWindowsDllInterceptor.h:ce5b2b0d4bc01294b78eeb50a7738e0b5755b3a4 : 150]
[task 2023-07-30T15:18:10.917Z] 15:18:10     INFO -      Found by: inlining
[task 2023-07-30T15:18:10.918Z] 15:18:10     INFO -   3  mozglue.dll!patched_BaseThreadInitThunk(int, void*, void*) [WindowsDllBlocklist.cpp:ce5b2b0d4bc01294b78eeb50a7738e0b5755b3a4 : 566 + 0xa]
[task 2023-07-30T15:18:10.918Z] 15:18:10     INFO -       eip = 0x72460373    esp = 0x1083fbec    ebp = 0x1083fc18    ebx = 0x00435049
[task 2023-07-30T15:18:10.918Z] 15:18:10     INFO -       esi = 0x77cd85a0    edi = 0x77cd85a0
[task 2023-07-30T15:18:10.918Z] 15:18:10     INFO -      Found by: call frame info
[task 2023-07-30T15:18:10.918Z] 15:18:10     INFO -   4  ntdll.dll!__RtlUserThreadStart + 0x2a
[task 2023-07-30T15:18:10.918Z] 15:18:10     INFO -       eip = 0x77ccb74b    esp = 0x1083fc20    ebp = 0x1083fc70    ebx = 0x00435049
[task 2023-07-30T15:18:10.918Z] 15:18:10     INFO -       esi = 0x76327d30    edi = 0x77cd85a0
[task 2023-07-30T15:18:10.918Z] 15:18:10     INFO -      Found by: call frame info
[task 2023-07-30T15:18:10.918Z] 15:18:10     INFO -   5  ntdll.dll!_RtlUserThreadStart + 0x1a
[task 2023-07-30T15:18:10.918Z] 15:18:10     INFO -       eip = 0x77ccb6cf    esp = 0x1083fc78    ebp = 0x1083fc80    ebx = 0x00435049
[task 2023-07-30T15:18:10.919Z] 15:18:10     INFO -       esi = 0x76327d30    edi = 0x77cd85a0
[task 2023-07-30T15:18:10.919Z] 15:18:10     INFO -      Found by: call frame info

Note that this failure also started pretty recently and not some days ago as bug 1845424. So maybe sheriffs could run some backfills?

Comment 5

[Iulian Moraru]

I think this is indeed a dupe of Bug 1831236 and not something Mn/Wd specific because I've started to see more and more mochitests, like this, that have the same crash. Probably something triggered a spike in the failure rate for this crash.
If you think the same way, please close this bug.
Thank you!

At the moment I have some backfills running from where I filed this bug.

Comment 6

[Iulian Moraru]

Backfill and retriggers look like this.

Comment 7

[Henrik Skupin [:whimboo][⌚️UTC+2] (away 02/17 - 02/21)]

I would leave this decision up to some developers knowing more about that. Mike, do you think that the above assumption is right and we should dupe the bug?

Comment 8

[Intermittent Failures Robot]

24 failures in 679 pushes (0.035 failures/push) were associated with this bug yesterday.

Repository breakdown:

• mozilla-central: 15
• autoland: 8
• mozilla-beta: 1

Platform and build breakdown:

• windows11-64-2009-qr: 3
  • debug: 3
• android-em-7-0-x86_64-qr: 1
  • debug: 1
• linux1804-64-tsan-qr: 3
  • opt: 3
• linux1804-64-ccov-qr: 3
  • opt: 3
• windows11-64-2009-ccov-qr: 5
  • opt: 5
• windows11-32-2009-qr: 8
  • debug: 7
  • opt: 1
• windows11-64-2009-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1846185&startday=2023-08-02&endday=2023-08-02&tree=all

Comment 9

[Greg Tatum [:gregtatum]]

If it was the translations automation issue, the fix has landed and the failures should go away.

Comment 10

[Henrik Skupin [:whimboo][⌚️UTC+2] (away 02/17 - 02/21)]

Yes, no more failures since the patch on bug 1845518 landed on central. Thanks!

Comment 11

[Intermittent Failures Robot]

39 failures in 3276 pushes (0.012 failures/push) were associated with this bug in the last 7 days.

This is the #45 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• try: 1
• mozilla-central: 19
• mozilla-beta: 5
• autoland: 14

Platform and build breakdown:

• windows11-64-2009-qr: 9
  • debug: 9
• windows11-32-2009-qr: 19
  • debug: 18
  • opt: 1
• android-em-7-0-x86_64-qr: 1
  • debug: 1
• linux1804-64-ccov-qr: 3
  • opt: 3
• windows11-64-2009-ccov-qr: 6
  • opt: 6
• windows11-64-2009-asan-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1846185&startday=2023-07-31&endday=2023-08-06&tree=all