Closed Bug 1572134 Opened 6 years ago Closed 6 years ago

Intermittent TV GECKO(1572) | Assertion failure: ((bool)(__builtin_expect(!!(!NS_FAILED_impl(rv)), 1))), at z:/build/build/src/ipc/mscom/EnsureMTA.cpp:36

Categories

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

Product:
Core
Component:
Audio/Video: cubeb
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure, regression)

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=260371778&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/RHSGRaH2QXS4iDFfdRcYSQ/runs/0/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/RHSGRaH2QXS4iDFfdRcYSQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2019-08-07T15:18:41.979Z] 15:18:41 INFO - TEST-OK | dom/media/tests/mochitest/test_getUserMedia_cubebDisabled.html | took 236ms
[task 2019-08-07T15:18:41.986Z] 15:18:41 INFO - GECKO(1572) | ++DOMWINDOW == 5 (110A2400) [pid = 792] [serial = 29] [outer = 00987A60]
[task 2019-08-07T15:18:42.013Z] 15:18:42 INFO - TEST-START | Shutdown
[task 2019-08-07T15:18:42.013Z] 15:18:42 INFO - Passed: 33
[task 2019-08-07T15:18:42.014Z] 15:18:42 INFO - Failed: 0
[task 2019-08-07T15:18:42.014Z] 15:18:42 INFO - Todo: 0
[task 2019-08-07T15:18:42.014Z] 15:18:42 INFO - Mode: e10s
[task 2019-08-07T15:18:42.014Z] 15:18:42 INFO - Slowest: 1471ms - /tests/dom/media/tests/mochitest/test_getUserMedia_cubebDisabled.html
[task 2019-08-07T15:18:42.014Z] 15:18:42 INFO - TEST-INFO | Ran 11 Loops
[task 2019-08-07T15:18:42.014Z] 15:18:42 INFO - SimpleTest FINISHED
[task 2019-08-07T15:18:42.213Z] 15:18:42 INFO - GECKO(1572) | [Parent 2816, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 341
<...>
[task 2019-08-07T15:18:42.353Z] 15:18:42 INFO - GECKO(1572) | --DOMWINDOW == 1 (110A2400) [pid = 792] [serial = 29] [outer = 00000000] [url = https://example.com/tests/SimpleTest/iframe-between-tests.html]
[task 2019-08-07T15:18:42.354Z] 15:18:42 INFO - GECKO(1572) | --DOMWINDOW == 0 (110A1400) [pid = 792] [serial = 28] [outer = 00000000] [url = https://example.com/tests/dom/media/tests/mochitest/test_getUserMedia_cubebDisabled.html]
[task 2019-08-07T15:18:42.369Z] 15:18:42 INFO - GECKO(1572) | [Child 792, Main Thread] WARNING: '!mInitialized', file z:/build/build/src/xpcom/threads/nsThreadManager.cpp, line 406
[task 2019-08-07T15:18:42.369Z] 15:18:42 INFO - GECKO(1572) | [Child 792, Main Thread] WARNING: 'NS_FAILED(rv)', file z:/build/build/src/xpcom/threads/nsThreadUtils.cpp, line 151
[task 2019-08-07T15:18:42.369Z] 15:18:42 INFO - GECKO(1572) | [Child 792, Main Thread] WARNING: NS_NewNamedThread failed: 'NS_SUCCEEDED(rv)', file z:/build/build/src/ipc/mscom/EnsureMTA.cpp, line 35
[task 2019-08-07T15:18:42.369Z] 15:18:42 INFO - GECKO(1572) | Assertion failure: ((bool)(__builtin_expect(!!(!NS_FAILED_impl(rv)), 1))), at z:/build/build/src/ipc/mscom/EnsureMTA.cpp:36
[task 2019-08-07T15:18:42.384Z] 15:18:42 INFO - GECKO(1572) | WARN 2019-08-07T15:18:42Z: xulstore::persist: tried to remove key that isn't in the store
[task 2019-08-07T15:18:42.384Z] 15:18:42 INFO - GECKO(1572) | WARN 2019-08-07T15:18:42Z: xulstore::persist: tried to remove key that isn't in the store
[task 2019-08-07T15:18:42.385Z] 15:18:42 INFO - GECKO(1572) | --DOMWINDOW == 14 (08252160) [pid = 3816] [serial = 1] [outer = 00000000] [url = moz-extension://d5cc87f6-eac2-4759-a47f-1052b4a80c37/_generated_background_page.html]
[task 2019-08-07T15:18:42.385Z] 15:18:42 INFO - GECKO(1572) | --DOMWINDOW == 13 (082524C0) [pid = 3816] [serial = 2] [outer = 00000000] [url = moz-extension://8b6ed6e0-ac83-41f4-bbbf-5be726a75d17/_generated_background_page.html]

I'm not convinced this is an MSCOM bug. It looks to me like something is invoking EnsureMTA before the thread manager is up.

Yeah, this is cubeb trying to do some EnsureMTA stuff during shutdown.

[task 2019-08-07T15:19:10.121Z] 15:19:10     INFO - GECKO(1572) | #01: static void mozilla::mscom::EnsureMTA::EnsureMTA<`lambda at z:/build/build/src/dom/media/CubebUtils.cpp:524:31'>(const class mozilla::CubebUtils::GetCubebContextUnlocked::<unnamed-tag> & const, mozilla::mscom::EnsureMTA::Option) [ipc/mscom/EnsureMTA.h:68]
[task 2019-08-07T15:19:10.121Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.121Z] 15:19:10     INFO - GECKO(1572) | #02: mozilla::CubebUtils::GetCubebContextUnlocked() [dom/media/CubebUtils.cpp:532]
[task 2019-08-07T15:19:10.122Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.122Z] 15:19:10     INFO - GECKO(1572) | #03: mozilla::CubebUtils::GetCubebContext() [dom/media/CubebUtils.cpp:310]
[task 2019-08-07T15:19:10.122Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.122Z] 15:19:10     INFO - GECKO(1572) | #04: mozilla::CubebDeviceEnumerator::~CubebDeviceEnumerator() [dom/media/webrtc/CubebDeviceEnumerator.cpp:59]
[task 2019-08-07T15:19:10.122Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.122Z] 15:19:10     INFO - GECKO(1572) | #05: mozilla::CubebDeviceEnumerator::Release() [dom/media/webrtc/CubebDeviceEnumerator.h:19]
[task 2019-08-07T15:19:10.122Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.122Z] 15:19:10     INFO - GECKO(1572) | #06: mozilla::CubebDeviceEnumerator::Shutdown() [dom/media/webrtc/CubebDeviceEnumerator.cpp:56]
[task 2019-08-07T15:19:10.122Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.123Z] 15:19:10     INFO - GECKO(1572) | #07: mozilla::CubebUtils::ShutdownLibrary() [dom/media/CubebUtils.cpp:658]
[task 2019-08-07T15:19:10.123Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.123Z] 15:19:10     INFO - GECKO(1572) | #08: nsLayoutStatics::Shutdown() [layout/build/nsLayoutStatics.cpp:395]
[task 2019-08-07T15:19:10.123Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.123Z] 15:19:10     INFO - GECKO(1572) | #09: nsLayoutModuleDtor() [layout/build/nsLayoutModule.cpp:256]
[task 2019-08-07T15:19:10.123Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.123Z] 15:19:10     INFO - GECKO(1572) | #10: nsComponentManagerImpl::Shutdown() [xpcom/components/nsComponentManager.cpp:947]
[task 2019-08-07T15:19:10.123Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.123Z] 15:19:10     INFO - GECKO(1572) | #11: mozilla::ShutdownXPCOM(nsIServiceManager *) [xpcom/build/XPCOMInit.cpp:725]
[task 2019-08-07T15:19:10.123Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.123Z] 15:19:10     INFO - GECKO(1572) | #12: XRE_TermEmbedding() [toolkit/xre/nsEmbedFunctions.cpp:223]
[task 2019-08-07T15:19:10.123Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.124Z] 15:19:10     INFO - GECKO(1572) | #13: mozilla::ipc::ScopedXREEmbed::Stop() [ipc/glue/ScopedXREEmbed.cpp:91]
[task 2019-08-07T15:19:10.124Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.124Z] 15:19:10     INFO - GECKO(1572) | #14: XRE_InitChildProcess(int,char * * const,XREChildData const *) [toolkit/xre/nsEmbedFunctions.cpp:759]
[task 2019-08-07T15:19:10.124Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.124Z] 15:19:10     INFO - GECKO(1572) | #15: mozilla::BootstrapImpl::XRE_InitChildProcess(int,char * * const,XREChildData const *) [toolkit/xre/Bootstrap.cpp:67]
[task 2019-08-07T15:19:10.124Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.319Z] 15:19:10     INFO - GECKO(1572) | #16: NS_internal_main(int,char * *,char * *) [browser/app/nsBrowserApp.cpp:267]
[task 2019-08-07T15:19:10.319Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.320Z] 15:19:10     INFO - GECKO(1572) | #17: wmain [toolkit/xre/nsWindowsWMain.cpp:131]
[task 2019-08-07T15:19:10.320Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.320Z] 15:19:10     INFO - GECKO(1572) | #18: static int __scrt_common_main_seh() [f:/dd/vctools/crt/vcstartup/src/startup/exe_common.inl:288]
[task 2019-08-07T15:19:10.320Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.321Z] 15:19:10     INFO - GECKO(1572) | #19: kernel32.dll + 0x4ef3c
[task 2019-08-07T15:19:10.321Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.321Z] 15:19:10     INFO - GECKO(1572) | #20: ntdll.dll + 0x63618
[task 2019-08-07T15:19:10.321Z] 15:19:10     INFO - 
[task 2019-08-07T15:19:10.322Z] 15:19:10     INFO - GECKO(1572) | #21: ntdll.dll + 0x635eb
Component: IPC: MSCOM → Audio/Video: cubeb

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.