Closed Bug 1568361 Opened 5 years ago Closed 5 years ago

Intermittent GECKO(4476) | Assertion failure: currentThread->EventQueue() (Shutdown() may only be called from an XPCOM thread), at z:/build/build/src/xpcom/threads/nsThread.cpp:824

Categories

(Core :: IPC: MSCOM, defect, P1)

defect

Tracking

()

RESOLVED FIXED
mozilla70
Tracking Status
firefox-esr60 --- unaffected
firefox-esr68 --- unaffected
firefox68 --- unaffected
firefox69 --- unaffected
firefox70 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression)

Attachments

(1 file)

Filed by: cbrindusan [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=257960473&repo=mozilla-inbound
Full log: https://queue.taskcluster.net/v1/task/MoB8pw94SbuxQE3MWCverw/runs/0/artifacts/public/logs/live_backing.log


22:34:11 INFO - GECKO(4476) | P|IP4:10.147.27.71:52565/UDP(host(IP4
22:34:11 INFO - GECKO(4476) | :10.147.27.71:52569/UDP)|candidate:0
22:34:11 INFO - GECKO(4476) | 1 UDP 2122252543 10.147.27.71 52565 typ host)): Received response; processing
22:34:11 INFO - GECKO(4476) | (ice/INFO) ICE-PEER(PC:1563921250773000 (id=2147483
22:34:11 INFO - GECKO(4476) | 782
22:34:11 INFO - GECKO(4476) | url=https://example.com/tests/dom/media/tests/moch
22:34:11 INFO - GECKO(4476) | itest/test_pee
22:34:11 INFO - GECKO(4476) | rConnection_v
22:34:11 INFO - GECKO(4476) | ideoCodecs.html):default)/CAND
22:34:11 INFO - GECKO(4476) | -PAIR(Gw6I): setting pair to state SUC
22:34:11 INFO - GECKO(4476) | CEEDED: Gw6I|IP4:10.147.27.71:52569/UDP|IP4:10.147.27.71:52565/UDP(host(IP4:10.147.27.71:52569/UDP)|candidate:0 1 UDP 2122252543 10.147.27.71 52565 typ host)
22:34:11 INFO - GECKO(4476) | (stun/INFO) STUN-CLIENT(jWM7|IP4:10.147.27
22:34:11 INFO - GECKO(4476) | .71:52565/UDP
22:34:11 INFO - GECKO(4476) | |IP4:10.147.27.71:52569/UDP(host(IP4:10.147.27.71:52565/UDP)|prflx)): Received response; processing
22:34:11 INFO - GECKO(4476) | (ice/INFO) ICE-PEER(PC:1563921250770000 (id=2147483782 url=https://
22:34:11 INFO - GECKO(4476) | example.com/tests/dom/media/tests/mochitest/test_peerConnection_videoCodecs.html):default)/CAND-PAIR(jWM7): setting pair to state SUCCEEDED: jWM7|IP4:10.147.27.71:52565/UDP|IP4:10.147.27.71:52569/UDP(host(IP4:10.147.27.
22:34:11 INFO - GECKO(4476) | 71:52565/UDP)|prflx)
22:34:11 INFO - GECKO(4476) | [Socket 4612: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
22:34:11 INFO - GECKO(4476) | [Socket 4612: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: ****** SSL handshake completed ******
22:34:11 INFO - GECKO(4476) | [Socket 4612: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Selected ALPN string: webrtc
22:34:11 INFO - GECKO(4476) | [Socket 4612: Socket Thread]: D/mtransport Created SRTP flow!
22:34:11 INFO - GECKO(4476) | [Socket 4612: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: ****** SSL handshake completed ******
22:34:11 INFO - GECKO(4476) | [Socket 4612: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Selected ALPN string: webrtc
22:34:11 INFO - GECKO(4476) | [Socket 4612: Socket Thread]: D/mtransport Created SRTP flow!
22:34:11 INFO - GECKO(4476) | [Child 4728: Unnamed thread 0EACE2E0]: I/signaling [|WebrtcVideoSessionConduit] VideoStreamFactory.cpp:197: CreateEncoderStreams Input frame 320x240, RID scaling to 320x240
22:34:11 INFO - GECKO(4476) | [6080, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file z:/build/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
22:34:11 INFO - GECKO(4476) | [6080, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file z:/build/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
22:34:11 INFO - GECKO(4476) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to c:\users\task_1563918565\appdata\local\temp\tmpi5c_yl.mozrunner\runtests_leaks_gmplugin_pid6080.log
22:34:11 INFO - GECKO(4476) | [6080, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file z:/build/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
22:34:11 INFO - GECKO(4476) | [6080, MainThread] WARNING: XPCOM objects created/destroyed from static ctor/dtor: file z:/build/build/src/xpcom/base/nsTraceRefcnt.cpp, line 194
22:34:11 INFO - GECKO(4476) | [GMP 6080, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file z:/build/build/src/modules/libpref/Preferences.cpp, line 4445
22:34:11 INFO - GECKO(4476) | [GMP 6080, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file z:/build/build/src/modules/libpref/Preferences.cpp, line 4445
22:34:11 INFO - GECKO(4476) | [GMP 6080, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file z:/build/build/src/modules/libpref/Preferences.cpp, line 4445
22:34:11 INFO - GECKO(4476) | [GMP 6080, Main Thread] WARNING: NS_ENSURE_TRUE(Preferences::InitStaticMembers()) failed: file z:/build/build/src/modules/libpref/Preferences.cpp, line 4445
22:34:13 INFO - GECKO(4476) | [Child 4728: Main Thread]: I/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2185: CloseInt: Closing PeerConnectionImpl a1436bc61dde9f31; ending call
22:34:13 INFO - GECKO(4476) | [Child 4728: Main Thread]: I/jsep [1563921250770000 (id=2147483782 url=https://example.com/tests/dom/media/tests/mochitest/test_peerConnection_videoCodecs.html)]: stable -> closed
22:34:13 INFO - GECKO(4476) | #
22:34:13 INFO - GECKO(4476) | # Fatal error in z:/build/build/src/media/webrtc/trunk/webrtc/modules/pacing/paced_sender.cc, line 318
22:34:13 INFO - GECKO(4476) | # last system error: 0
22:34:13 INFO - GECKO(4476) | # Check failed: !paused_
22:34:13 INFO - GECKO(4476) | #
22:34:13 INFO - GECKO(4476) | #
22:34:13 INFO - GECKO(4476) | Assertion failure: currentThread->EventQueue() (Shutdown() may only be called from an XPCOM thread), at z:/build/build/src/xpcom/threads/nsThread.cpp:824
22:34:39 INFO - GECKO(4476) | #01: nsThread::Shutdown() [xpcom/threads/nsThread.cpp:894]
22:34:39 INFO - 22:34:39 INFO - GECKO(4476) | #02: void `anonymous namespace'::BackgroundMTAData::~BackgroundMTAData() [ipc/mscom/EnsureMTA.cpp:47]
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #03: static void ??FsMTAData@?1??GetMTAThread@EnsureMTA@mscom@mozilla@@CA?AV?$nsCOMPtr@VnsIThread@@@@XZ@YAXXZ() [obj-firefox/ipc/mscom/Unified_cpp_ipc_mscom0.cpp:62]
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #04: ucrtbase.DLL + 0x263c3
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #05: ucrtbase.DLL + 0x2d200
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #06: ucrtbase.DLL + 0x31580
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #07: scrt_dllmain_uninitialize_c [f:/dd/vctools/crt/vcstartup/src/utility/utility.cpp:398]
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #08: static int dllmain_crt_dispatch(struct HINSTANCE
*, const unsigned long, void *) [f:/dd/vctools/crt/vcstartup/src/startup/dll_dllmain.cpp:145]
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #09: static int dllmain_dispatch(struct HINSTANCE
*, const unsigned long, void *) [f:/dd/vctools/crt/vcstartup/src/startup/dll_dllmain.cpp:212]
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #10: _DllMainCRTStartup [f:/dd/vctools/crt/vcstartup/src/startup/dll_dllmain.cpp:253]
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #11: ntdll.dll + 0x587cc
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #12: ntdll.dll + 0x5df0a
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #13: ntdll.dll + 0x5dfa5
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #14: kernel32.dll + 0x5be6f
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #15: ucrtbase.DLL + 0x380ca
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #16: ucrtbase.DLL + 0xa43c2
22:34:39 INFO -
22:34:39 INFO - GECKO(4476) | #17: ucrtbase.DLL + 0xa2751

Component: XPCOM → IPC: MSCOM

It would be nice to know which DLL is shutting down. I want to say XUL, but why would XUL be detaching from the process without properly shutting down. Is this a GMP thing? Maybe we'll get some better dumps in the future.

Priority: P5 → P3

Bug 1566955 is what would have landed to cause this to start, but it looks to me like this process is not shutting down quite as expected, so I think we need more information there to know how to proceed.

Regressed by: 1566955
Assignee: nobody → aklotz
Status: NEW → ASSIGNED
Priority: P3 → P1
Depends on: 1570395

The new StaticLocalAutoPtr smart pointer has a trivial destructor, so we will
either properly clean up this data or leak it on process shutdown. Either way,
we will not destroy it in a way that the underlying type does not support.

Pushed by aklotz@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/3b0f04771005
Use StaticLocalAutoPtr for BackgrundMTAData; r=Jamie
Attachment #9083401 - Attachment description: Bug 1568361: Use StaticLocalAutoPtr for BackgrundMTAData; r=Jamie! → Bug 1568361: Use StaticLocalAutoPtr for BackgroundMTAData; r=Jamie!
Flags: needinfo?(aklotz)
Pushed by aklotz@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/36592e14f6ce
Use StaticLocalAutoPtr for BackgroundMTAData; r=Jamie
Status: ASSIGNED → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Target Milestone: --- → mozilla70
Has Regression Range: --- → yes
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: