Closed Bug 1582575 Opened 5 years ago Closed 3 years ago

Intermittent Assertion failure: mRawPtr, at /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/StaticPtr.h:149

Categories

(Core :: Audio/Video: Playback, defect, P5)

defect

Tracking

()

RESOLVED FIXED
Tracking Status
firefox-esr78 --- wontfix
firefox-esr91 --- fixed
firefox90 --- wontfix
firefox91 --- wontfix
firefox92 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: bryce)

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure, regression, Whiteboard: [stockwell unknown])

Attachments

(1 file)

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=267484143&repo=mozilla-central
Full log: https://queue.taskcluster.net/v1/task/G72rdJZ7RhOqn8a9r1ndFA/runs/1/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://queue.taskcluster.net/v1/task/G72rdJZ7RhOqn8a9r1ndFA/runs/1/artifacts/public/logs/live_backing.log&only_show_unexpected=1


[task 2019-09-19T19:44:46.544Z] 19:44:46 INFO - REFTEST TEST-START | file:///Z:/task_1568920570/build/tests/reftest/tests/image/test/reftest/ico/ico-bmp-corrupted/wrapper.html?invalid-compression.ico == about:blank
[task 2019-09-19T19:44:46.545Z] 19:44:46 INFO - REFTEST TEST-LOAD | file:///Z:/task_1568920570/build/tests/reftest/tests/image/test/reftest/ico/ico-bmp-corrupted/wrapper.html?invalid-compression.ico | 3 / 4 (75%)
[task 2019-09-19T19:44:46.599Z] 19:44:46 INFO - ++DOMWINDOW == 14 (00000252D3808400) [pid = 5824] [serial = 14] [outer = 00000252C4C9F7A0]
[task 2019-09-19T19:44:46.769Z] 19:44:46 INFO - --DOMWINDOW == 13 (00000252C4C9F200) [pid = 5824] [serial = 1] [outer = 0000000000000000] [url = chrome://gfxsanity/content/sanitytest.html]
[task 2019-09-19T19:44:46.813Z] 19:44:46 INFO - REFTEST TEST-PASS | file:///Z:/task_1568920570/build/tests/reftest/tests/image/test/reftest/ico/ico-bmp-corrupted/wrapper.html?invalid-compression.ico == about:blank | image comparison, max difference: 0, number of differing pixels: 0
[task 2019-09-19T19:44:46.814Z] 19:44:46 INFO - REFTEST TEST-END | file:///Z:/task_1568920570/build/tests/reftest/tests/image/test/reftest/ico/ico-bmp-corrupted/wrapper.html?invalid-compression.ico == about:blank
[task 2019-09-19T19:44:46.824Z] 19:44:46 INFO - ++DOMWINDOW == 14 (00000252D338AC00) [pid = 5824] [serial = 15] [outer = 00000252C4C9F7A0]
[task 2019-09-19T19:44:46.854Z] 19:44:46 INFO - REFTEST INFO | Slowest test took 208ms (file:///Z:/task_1568920570/build/tests/reftest/tests/image/test/reftest/ico/ico-bmp-corrupted/wrapper.html?invalid-compression.ico)
[task 2019-09-19T19:44:46.855Z] 19:44:46 INFO - REFTEST INFO | Total canvas count = 2
[task 2019-09-19T19:44:47.172Z] 19:44:47 INFO - [Parent 7228, Gecko_IOThread] WARNING: pipe error: 109: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-19T19:44:47.176Z] 19:44:47 INFO - [Child 6996, Chrome_ChildThread] WARNING: pipe error: 109: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-19T19:44:47.177Z] 19:44:47 INFO - [Child 6996, Chrome_ChildThread] WARNING: pipe error: 109: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-19T19:44:47.194Z] 19:44:47 INFO - [GPU 7324, Chrome_ChildThread] WARNING: pipe error: 109: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-19T19:44:47.194Z] 19:44:47 INFO - [GPU 7324, Chrome_ChildThread] WARNING: pipe error: 109: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-19T19:44:47.195Z] 19:44:47 INFO - ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2019-09-19T19:44:47.195Z] 19:44:47 INFO - [GPU 7324, Chrome_ChildThread] WARNING: pipe error: 109: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-19T19:44:47.196Z] 19:44:47 INFO - [GPU 7324, Chrome_ChildThread] WARNING: pipe error: 109: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line[Par en341t 7228
[task 2019-09-19T19:44:47.196Z] 19:44:47 INFO - , Socket Thread] WARNING: cannot post event if not initialized: file /builds/worker/workspace/build/src/netwerk/protocol/http/nsHttpConnectionMgr.cpp, line 278
[task 2019-09-19T19:44:47.284Z] 19:44:47 INFO - [Child 5824, Chrome_ChildThread] WARNING: pipe error: 109: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-19T19:44:47.309Z] 19:44:47 INFO - [Child 5824, Chrome_ChildThread] WARNING: pipe error: 109: file /builds/worker/workspace/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
[task 2019-09-19T19:44:47.349Z] 19:44:47 INFO - --DOCSHELL 00000238A0F08000 == 3 [pid = 6996] [id = {6fbf2f39-d9d3-4eca-b8ca-416c1e927e42}] [url = moz-extension://6b4e7fbd-f306-424b-869b-98d854c62669/_generated_background_page.html]
[task 2019-09-19T19:44:47.349Z] 19:44:47 INFO - --DOCSHELL 00000238A0F0B000 == 2 [pid = 6996] [id = {9969b70e-39a6-4325-8411-74a511c27f48}] [url = moz-extension://e4ec7fa4-c092-4674-8f39-b0c392c479b8/_generated_background_page.html]
[task 2019-09-19T19:44:47.349Z] 19:44:47 INFO - --DOCSHELL 00000238A0F0E000 == 1 [pid = 6996] [id = {ac74a0f7-7797-4253-97d4-1b1a0f016bb3}] [url = moz-extension://6d992576-8bac-4d00-a71e-af7c87efee2d/_generated_background_page.html]
[task 2019-09-19T19:44:47.350Z] 19:44:47 INFO - --DOCSHELL 00000238A0F10800 == 0 [pid = 6996] [id = {96bb896b-7338-4c7d-972c-1b4f8f96920f}] [url = moz-extension://c2b6330f-28ae-4705-aa45-793f16cb4410/_generated_background_page.html]
[task 2019-09-19T19:44:47.392Z] 19:44:47 INFO - [Child 5824, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 481
[task 2019-09-19T19:44:47.397Z] 19:44:47 INFO - [Child 5824, Main Thread] WARNING: '!ipcActor->SendAccumulateChildHistograms(histogramsToSend)', file /builds/worker/workspace/build/src/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp, line 282
[task 2019-09-19T19:44:47.397Z] 19:44:47 INFO - [Child 5824, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 481
[task 2019-09-19T19:44:47.402Z] 19:44:47 INFO - [Child 5824, Main Thread] WARNING: '!ipcActor->SendAccumulateChildKeyedHistograms(keyedHistogramsToSend)', file /builds/worker/workspace/build/src/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp, line 286
[task 2019-09-19T19:44:47.403Z] 19:44:47 INFO - [Child 5824, Main Thread] WARNING: IPC message discarded: actor cannot send: file /builds/worker/workspace/build/src/ipc/glue/ProtocolUtils.cpp, line 481
[task 2019-09-19T19:44:47.403Z] 19:44:47 INFO - [Child 5824, Main Thread] WARNING: '!ipcActor->SendRecordDiscardedData(discardedData)', file /builds/worker/workspace/build/src/toolkit/components/telemetry/core/ipc/TelemetryIPCAccumulator.cpp, line 301
[task 2019-09-19T19:44:47.422Z] 19:44:47 INFO - Assertion failure: mRawPtr, at /builds/worker/workspace/build/src/obj-firefox/dist/include/mozilla/StaticPtr.h:149
[task 2019-09-19T19:44:47.467Z] 19:44:47 INFO - --DOMWINDOW == 11 (000002389409C7A0) [pid = 6996] [serial = 1] [outer = 0000000000000000] [url = moz-extension://6b4e7fbd-f306-424b-869b-98d854c62669/_generated_background_page.html]
[task 2019-09-19T19:44:47.468Z] 19:44:47 INFO - --DOMWINDOW == 10 (00000238940CEC00) [pid = 6996] [serial = 2] [outer = 0000000000000000] [url = about:blank]
[task 2019-09-19T19:44:47.468Z] 19:44:47 INFO - --DOMWINDOW == 9 (000002389409C980) [pid = 6996] [serial = 3] [outer = 0000000000000000] [url = moz-extension://e4ec7fa4-c092-4674-8f39-b0c392c479b8/_generated_background_page.html]
[task 2019-09-19T19:44:47.469Z] 19:44:47 INFO - --DOMWINDOW == 8 (000002389A766000) [pid = 6996] [serial = 4] [outer = 0000000000000000] [url = about:blank]
[task 2019-09-19T19:44:47.469Z] 19:44:47 INFO - --DOMWINDOW == 7 (000002389409C200) [pid = 6996] [serial = 5] [outer = 0000000000000000] [url = moz-extension://6d992576-8bac-4d00-a71e-af7c87efee2d/_generated_background_page.html]
[task 2019-09-19T19:44:47.469Z] 19:44:47 INFO - --DOMWINDOW == 6 (000002389A768800) [pid = 6996] [serial = 6] [outer = 0000000000000000] [url = about:blank]
[task 2019-09-19T19:44:47.470Z] 19:44:47 INFO - --DOMWINDOW == 5 (000002389409C3E0) [pid = 6996] [serial = 7] [outer = 0000000000000000] [url = moz-extension://c2b6330f-28ae-4705-aa45-793f16cb4410/_generated_background_page.html]
[task 2019-09-19T19:44:47.470Z] 19:44:47 INFO - --DOMWINDOW == 4 (00000238A0F8D800) [pid = 6996] [serial = 8] [outer = 0000000000000000] [url = about:blank]
[task 2019-09-19T19:44:47.471Z] 19:44:47 INFO - --DOMWINDOW == 3 (00000238A0F97400) [pid = 6996] [serial = 11] [outer = 0000000000000000] [url = moz-extension://6d992576-8bac-4d00-a71e-af7c87efee2d/_generated_background_page.html]
[task 2019-09-19T19:44:47.471Z] 19:44:47 INFO - --DOMWINDOW == 2 (00000238A0F95C00) [pid = 6996] [serial = 10] [outer = 0000000000000000] [url = moz-extension://e4ec7fa4-c092-4674-8f39-b0c392c479b8/_generated_background_page.html]
[task 2019-09-19T19:44:47.471Z] 19:44:47 INFO - --DOMWINDOW == 1 (00000238A0F8CC00) [pid = 6996] [serial = 9] [outer = 0000000000000000] [url = moz-extension://6b4e7fbd-f306-424b-869b-98d854c62669/_generated_background_page.html]
[task 2019-09-19T19:44:47.471Z] 19:44:47 INFO - --DOMWINDOW == 0 (00000238A0F98800) [pid = 6996] [serial = 12] [outer = 0000000000000000] [url = moz-extension://c2b6330f-28ae-4705-aa45-793f16cb4410/_generated_background_page.html]
[task 2019-09-19T19:44:47.516Z] 19:44:47 INFO - nsStringStats
[task 2019-09-19T19:44:47.517Z] 19:44:47 INFO - => mAllocCount: 13456
[task 2019-09-19T19:44:47.517Z] 19:44:47 INFO - => mReallocCount: 0
[task 2019-09-19T19:44:47.517Z] 19:44:47 INFO - => mFreeCount: 13456
[task 2019-09-19T19:44:47.518Z] 19:44:47 INFO - => mShareCount: 8239
[task 2019-09-19T19:44:47.518Z] 19:44:47 INFO - => mAdoptCount: 386
[task 2019-09-19T19:44:47.518Z] 19:44:47 INFO - => mAdoptFreeCount: 410
[task 2019-09-19T19:44:47.519Z] 19:44:47 INFO - => Process ID: 6996, Thread ID: 10992
[task 2019-09-19T19:44:47.601Z] 19:44:47 INFO - --DOCSHELL 000002968A506800 == 1 [pid = 4428] [id = {1d23f9ad-67e7-4b6d-b2de-058c9bcb0f71}] [url = about:blank]
[task 2019-09-19T19:44:47.641Z] 19:44:47 INFO - #01: XRE_GetBootstrap[Z:\task_1568920570\build\application\firefox\xul.dll +0x655ebc3]
[task 2019-09-19T19:44:47.642Z] 19:44:47 INFO - #02: XRE_GetBootstrap[Z:\task_1568920570\build\application\firefox\xul.dll +0x1200010]
[task 2019-09-19T19:44:47.643Z] 19:44:47 INFO - #03: XRE_GetBootstrap[Z:\task_1568920570\build\application\firefox\xul.dll +0x577303a]
[task 2019-09-19T19:44:47.643Z] 19:44:47 INFO - #04: XRE_GetBootstrap[Z:\task_1568920570\build\application\firefox\xul.dll +0x16491c9]
[task 2019-09-19T19:44:47.643Z] 19:44:47 INFO - #05: XRE_GetBootstrap[Z:\task_1568920570\build\application\firefox\xul.dll +0x655c655]
[task 2019-09-19T19:44:47.643Z] 19:44:47 INFO - #06: PR_NativeCreateThread[Z:\task_1568920570\build\application\firefox\nss3.dll +0x95789]
[task 2019-09-19T19:44:47.644Z] 19:44:47 INFO - #07: pr_test_ipv6_socket[Z:\task_1568920570\build\application\firefox\nss3.dll +0x11ca10]
[task 2019-09-19T19:44:47.644Z] 19:44:47 INFO - #08: o_ceil[C:\Windows\System32\ucrtbase.dll +0x1c4be]
[task 2019-09-19T19:44:47.644Z] 19:44:47 INFO - #09: BaseThreadInitThunk[C:\Windows\System32\KERNEL32.DLL +0x13034]
[task 2019-09-19T19:44:47.644Z] 19:44:47 INFO - #10: Z32DllBlocklist_SetBasicDllServicesPN7mozilla4glue6detail15DllServicesBaseE[Z:\task_1568920570\build\application\firefox\mozglue.dll +0x73a1a]
[task 2019-09-19T19:44:47.644Z] 19:44:47 INFO - #11: RtlUserThreadStart[C:\Windows\SYSTEM32\ntdll.dll +0x71461]
[task 2019-09-19T19:44:47.743Z] 19:44:47 INFO - --DOMWINDOW == 5 (00000296FC39D200) [pid = 4428] [serial = 1] [outer = 0000000000000000] [url = about:blank]
[task 2019-09-19T19:44:47.743Z] 19:44:47 INFO - --DOMWINDOW == 4 (00000296FC3C9000) [pid = 4428] [serial = 2] [outer = 0000000000000000] [url = about:blank]
[task 2019-09-19T19:44:47.744Z] 19:44:47 INFO - --DOMWINDOW == 3 (00000296FFD7AC00) [pid = 4428] [serial = 3] [outer = 0000000000000000] [url = about:blank]
[task 2019-09-19T19:44:47.744Z] 19:44:47 INFO - --DOMWINDOW == 2 (000002968A58BC00) [pid = 4428] [serial = 5] [outer = 0000000000000000] [url = about:blank]
[task 2019-09-19T19:44:47.745Z] 19:44:47 INFO - --DOMWINDOW == 1 (00000296FC39D3E0) [pid = 4428] [serial = 4] [outer = 0000000000000000] [url = about:newtab]
[task 2019-09-19T19:44:47.745Z] 19:44:47 INFO - --DOCSHELL 000002968A5DD000 == 0 [pid = 4428] [id = {896a2e56-c638-4107-8a4b-9140da48c7d6}] [url = about:newtab]
[task 2019-09-19T19:44:47.745Z] 19:44:47 INFO - --DOMWINDOW == 0 (000002968A58F800) [pid = 4428] [serial = 6] [outer = 0000000000000000] [url = about:newtab]

Status: NEW → RESOLVED
Closed: 3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

Update:
There have been 39 failures within the last 7 days:
• 5 failures on Windows 10 x86 debug
• 34 failures on Windows 10 x64 WebRender debug
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=343351478&repo=mozilla-central&lineNumber=29648

[task 2021-06-21T10:38:29.303Z] 10:38:29     INFO - REFTEST TEST-START | layout/reftests/webcomponents/cross-tree-selection-1.html == layout/reftests/webcomponents/cross-tree-selection-1-ref.html
[task 2021-06-21T10:38:29.320Z] 10:38:29     INFO - REFTEST TEST-LOAD | file:///Z:/task_1624270704/build/tests/reftest/tests/layout/reftests/webcomponents/cross-tree-selection-1.html | 0 / 26 (0%)
[task 2021-06-21T10:38:29.352Z] 10:38:29     INFO - [Parent 6844, Main Thread] WARNING: WebProgress Ignored: no longer current window global: file /builds/worker/checkouts/gecko/dom/ipc/BrowserParent.cpp:2931
[task 2021-06-21T10:38:29.364Z] 10:38:29     INFO - [Child 7104, Main Thread] WARNING: Extra shutdown CC: 'i < NORMAL_SHUTDOWN_COLLECTIONS', file /builds/worker/checkouts/gecko/xpcom/base/nsCycleCollector.cpp:3357
[task 2021-06-21T10:38:29.397Z] 10:38:29     INFO - ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_1624270704\AppData\Local\Temp\tmpyte3dm7c.mozrunner\runreftest_leaks_tab_pid2560.log
[task 2021-06-21T10:38:29.398Z] 10:38:29     INFO - [2560, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:247
[task 2021-06-21T10:38:29.459Z] 10:38:29     INFO - Assertion failure: mRawPtr, at /builds/worker/workspace/obj-build/dist/include/mozilla/StaticPtr.h:155
[task 2021-06-21T10:38:29.487Z] 10:38:29     INFO - [Child 7104, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file /builds/worker/checkouts/gecko/modules/libpref/Preferences.cpp:4442
[task 2021-06-21T10:38:29.628Z] 10:38:29     INFO - 1624271909619	Marionette	TRACE	[42] MarionetteEvents actor created for window id 8589934593
[task 2021-06-21T10:38:29.630Z] 10:38:29     INFO - JavaScript error: , line 0: NotFoundError: No such JSWindowActor 'MarionetteEvents'
[task 2021-06-21T10:38:29.680Z] 10:38:29     INFO - [Child 10568, Main Thread] WARNING: NS_ENSURE_TRUE(!mHasOrHasHadOwnerWindow || mOwnerWindow) failed: file /builds/worker/checkouts/gecko/dom/events/DOMEventTargetHelper.cpp:327
[task 2021-06-21T10:38:29.708Z] 10:38:29     INFO - [Child 2560, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, rv) failed with result 0x80520012 (NS_ERROR_FILE_NOT_FOUND): file /builds/worker/checkouts/gecko/layout/style/Loader.cpp:2014
[task 2021-06-21T10:38:29.770Z] 10:38:29     INFO -  Initializing stack-fixing for the first stack frame, this may take a while...
[task 2021-06-21T10:38:52.618Z] 10:38:52     INFO - #01: mozilla::MediaCache::Update() [dom/media/MediaCache.cpp:1221]
[task 2021-06-21T10:38:52.619Z] 10:38:52     INFO - #02: mozilla::MediaCache::QueueUpdate::<unnamed-tag>::operator()() [dom/media/MediaCache.cpp:1608]
[task 2021-06-21T10:38:52.619Z] 10:38:52     INFO - #03: mozilla::detail::RunnableFunction<`lambda at /builds/worker/checkouts/gecko/dom/media/MediaCache.cpp:1607:34'>::Run() [xpcom/threads/nsThreadUtils.h:535]
[task 2021-06-21T10:38:52.620Z] 10:38:52     INFO - #04: nsThread::ProcessNextEvent(bool, bool*) [xpcom/threads/nsThread.cpp:1146]
[task 2021-06-21T10:38:52.621Z] 10:38:52     INFO - #05: NS_ProcessNextEvent(nsIThread*, bool) [xpcom/threads/nsThreadUtils.cpp:548]
[task 2021-06-21T10:38:52.623Z] 10:38:52     INFO - #06: mozilla::ipc::MessagePumpForNonMainThreads::Run(base::MessagePump::Delegate*) [ipc/glue/MessagePump.cpp:300]
[task 2021-06-21T10:38:52.624Z] 10:38:52     INFO - [Child 2560, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/Idl#07: MessageLoop::RunInternal() [ipc/chromium/src/base/message_loop.cc:331]
[task 2021-06-21T10:38:52.625Z] 10:38:52     INFO - eTaskRunner.cpp:179
[task 2021-06-21T10:38:52.626Z] 10:38:52     INFO - #08: MessageLoop::RunHandler() [ipc/chromium/src/base/message_loop.cc:325]
[task 2021-06-21T10:38:52.627Z] 10:38:52     INFO - #09: MessageLoop::Run() [ipc/chromium/src/base/message_loop.cc:307]
[task 2021-06-21T10:38:52.627Z] 10:38:52     INFO - #10: static nsThread::ThreadFunc(void*) [xpcom/threads/nsThread.cpp:394]
[task 2021-06-21T10:38:53.086Z] 10:38:53     INFO - #11: _PR_NativeRunThread(void*) [nsprpub/pr/src/threads/combined/pruthr.c:399]
[task 2021-06-21T10:38:53.087Z] 10:38:53     INFO - #12: pr_root(void*) [nsprpub/pr/src/md/windows/w95thred.c:139]
[task 2021-06-21T10:38:53.097Z] 10:38:53     INFO -  fix-stacks: error: failed to read debug info file `ucrtbase.pdb` for `C:\Windows\System32\ucrtbase.dll`
[task 2021-06-21T10:38:53.097Z] 10:38:53     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2021-06-21T10:38:53.098Z] 10:38:53     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2021-06-21T10:38:53.099Z] 10:38:53     INFO - #13: o____lc_collate_cp_func [C:\Windows\System32\ucrtbase.dll + 0x3e8af]
[task 2021-06-21T10:38:53.104Z] 10:38:53     INFO -  fix-stacks: error: failed to read debug info file `wkernel32.pdb` for `C:\Windows\System32\KERNEL32.DLL`
[task 2021-06-21T10:38:53.105Z] 10:38:53     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2021-06-21T10:38:53.105Z] 10:38:53     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2021-06-21T10:38:53.106Z] 10:38:53     INFO - #14: BaseThreadInitThunk [C:\Windows\System32\KERNEL32.DLL + 0x18484]
[task 2021-06-21T10:38:53.114Z] 10:38:53     INFO -  fix-stacks: error: failed to read debug info file `wntdll.pdb` for `C:\Windows\SYSTEM32\ntdll.dll`
[task 2021-06-21T10:38:53.115Z] 10:38:53     INFO -  fix-stacks: note: this is expected and harmless for all PDB files on opt automation runs
[task 2021-06-21T10:38:53.115Z] 10:38:53     INFO -  fix-stacks: The system cannot find the file specified. (os error 2)
[task 2021-06-21T10:38:53.116Z] 10:38:53     INFO - #15: RtlValidSecurityDescriptor [C:\Windows\SYSTEM32\ntdll.dll + 0x6305a]
[task 2021-06-21T10:38:53.117Z] 10:38:53     INFO - #16: RtlValidSecurityDescriptor [C:\Windows\SYSTEM32\ntdll.dll + 0x6302a]
[task 2021-06-21T10:38:53.119Z] 10:38:53     INFO - REFTEST INFO | drawWindow flags = DRAWWINDOW_DRAW_CARET | DRAWWINDOW_DRAW_VIEW | DRAWWINDOW_USE_WIDGET_LAYERS; window size = 816,1039; test browser size = 800,1000
[task 2021-06-21T10:38:53.121Z] 10:38:53     INFO - [Child 2560, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:179
[task 2021-06-21T10:38:53.123Z] 10:38:53     INFO - [Child 2560, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:179
[task 2021-06-21T10:38:53.127Z] 10:38:53     INFO - [Child 2560, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:179
[task 2021-06-21T10:38:53.130Z] 10:38:53     INFO - [Child 2560, Main Thread] WARNING: early callback, or time went backwards: '!aAllowIdleDispatch', file /builds/worker/checkouts/gecko/xpcom/threads/IdleTaskRunner.cpp:179
[task 2021-06-21T10:38:53.153Z] 10:38:53     INFO - REFTEST TEST-UNEXPECTED-FAIL | layout/reftests/webcomponents/cross-tree-selection-1.html == layout/reftests/webcomponents/cross-tree-selection-1-ref.html | crash-check | This test left crash dumps behind, but we weren't expecting it to!
[task 2021-06-21T10:38:53.154Z] 10:38:53     INFO - REFTEST INFO | Found unexpected crash dump file C:\Users\task_1624270704\AppData\Local\Temp\tmpyte3dm7c.mozrunner\minidumps\d7b9ae34-4242-4d3d-8c3f-dc7168b16c17.dmp
[task 2021-06-21T10:38:53.154Z] 10:38:53     INFO - REFTEST TEST-LOAD | file:///Z:/task_1624270704/build/tests/reftest/tests/layout/reftests/webcomponents/cross-tree-selection-1-ref.html | 0 / 26 (0%)
[task 2021-06-21T10:38:53.159Z] 10:38:53     INFO - REFTEST TEST-PASS | layout/reftests/webcomponents/cross-tree-selection-1.html == layout/reftests/webcomponents/cross-tree-selection-1-ref.html | image comparison, max difference: 0, number of differing pixels: 0
[task 2021-06-21T10:38:53.160Z] 10:38:53     INFO - REFTEST TEST-END | layout/reftests/webcomponents/cross-tree-selection-1.html == layout/reftests/webcomponents/cross-tree-selection-1-ref.html
[task 2021-06-21T10:38:53.162Z] 10:38:53     INFO - REFTEST TEST-START | layout/reftests/webcomponents/basic-shadow-1.html == layout/reftests/webcomponents/basic-shadow-1-ref.html
Whiteboard: [stockwell needswork:owner]

Nika, as the owner of this component, could you help us assign it to someone?
Thank you.

Flags: needinfo?(nika)

The recent failures are mostly or all in mozilla::MediaCache::Update(), so it is a media issue most likely.

Looks like maybe sThread is null on this line: MOZ_ASSERT(sThread->IsOnCurrentThread());

Component: XPCOM → Audio/Video: Playback

Forwarding the NI to the new components triage owner.

Flags: needinfo?(nika) → needinfo?(bvandyk)

Acking, investigating.

Failures are Windows specific. Not clear why.

Brainstorming possible contributing factors (take with grain of salt, these are just possible leads):

  • Enabling of Fission and Fission tests recently.
  • There's been some changes in test infra recently.
  • Bug 1705194 + bug 1705267 - changed some of the MediaCache code recently (but we have failures prior to that, and it was only months after landing we see an uptick).

Analysis:

So I think we're shutting down then running Update during shutdown. The sThread member has been cleared at this stage, but object that it referred to is still processing dispatches.

I'm not entirely confident, but this is my best bet ATM. Will get a patch ready assuming the above is the issue. Holding NI. Marking leave-open so we can keep an eye on if the patch works.

Keywords: leave-open
Assignee: nobody → bvandyk

sThread is nulled as xpcom shuts down. So if Update is run after we are shutting
down, the assert may deref a null pointer. Avoid this by using a conditional
assert.

Attachment #9230167 - Attachment description: Bug 1582575 - Only assert Update is running on sThread if sThread not null. r?alwu → Bug 1582575 - Shutdown MediaCache::sThread before nulling it. r?alwu
Pushed by bvandyk@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/4e7dad15dac9
Shutdown MediaCache::sThread before nulling it. r=alwu

We've gone from multiple reports a day to 0 following the patch. Looks like the patch may have fixed this. Resolving. Please NI me if this reoccurs.

Status: REOPENED → RESOLVED
Closed: 3 years ago3 years ago
Flags: needinfo?(bvandyk)
Keywords: leave-open
Resolution: --- → FIXED

Looks like we may get sporadic reports of this from esr-91. It's annoying, but I don't know if it's worth uplifting.

Please nominate for uplift, ESR91 has a lot of life ahead of it :)

Flags: needinfo?(bvandyk)

Comment on attachment 9230167 [details]
Bug 1582575 - Shutdown MediaCache::sThread before nulling it. r?alwu

ESR Uplift Approval Request

  • If this is not a sec:{high,crit} bug, please state case for ESR consideration: Fixes an intermittent we'd prefer to avoid being reported for ESR91's lifetime.
  • User impact if declined: Bug 1582575 will keep getting intermittent reports while ESR91 is running tests.
  • Fix Landed on Version: 92
  • Risk to taking this patch: Low
  • Why is the change risky/not risky? (and alternatives if risky): The change is small and has had a good amount of analysis from a couple of Media engineers that led to it.
  • String or UUID changes made by this patch: None
Flags: needinfo?(bvandyk)
Attachment #9230167 - Flags: approval-mozilla-esr91?

Comment on attachment 9230167 [details]
Bug 1582575 - Shutdown MediaCache::sThread before nulling it. r?alwu

Approved for 91.1esr.

Attachment #9230167 - Flags: approval-mozilla-esr91? → approval-mozilla-esr91+

This is still failing, the fail appeared on mozilla-esr91.
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=348557830&repo=mozilla-esr91&lineNumber=37358

Flags: needinfo?(ryanvm)

(In reply to Iulian Moraru from comment #101)

This is still failing, the fail appeared on mozilla-esr91.
Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=348557830&repo=mozilla-esr91&lineNumber=37358

That's on a relbranch from a point prior this patch being uplifted.

Flags: needinfo?(ryanvm)
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: