Closed Bug 1477140 Opened 7 years ago Closed 7 years ago

Intermittent asan GECKO(5036) | FATAL ERROR: AsyncShutdown timeout in xpcom-will-shutdown Conditions: [{"name":"GeckoMediaPluginServiceParent: shutdown","state":"(none)","filename":"GMPServiceParent.cpp","lineNumber":1657,"stack":"GeckoMediaPluginServiceP

Categories

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

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

Tracking

()

Status:
RESOLVED DUPLICATE of bug 1476870

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: btara [at] mozilla.com https://treeherder.mozilla.org/logviewer.html#?job_id=189060842&repo=autoland https://queue.taskcluster.net/v1/task/U8wvvEzETGy-nC3ym_Acbg/runs/0/artifacts/public/logs/live_backing.log 23:32:05 INFO - GECKO(5036) | [GMP 8104, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - 952 INFO TEST-START | Shutdown 23:32:05 INFO - 953 INFO Passed: 1985 23:32:05 INFO - 954 INFO Failed: 0 23:32:05 INFO - 955 INFO Todo: 2 23:32:05 INFO - 956 INFO Mode: e10s 23:32:05 INFO - 957 INFO Slowest: 31015ms - /tests/dom/media/test/test_eme_unsetMediaKeys_then_capture.html 23:32:05 INFO - 958 INFO SimpleTest FINISHED 23:32:05 INFO - 959 INFO TEST-INFO | Ran 1 Loops 23:32:05 INFO - 960 INFO SimpleTest FINISHED 23:32:05 INFO - GECKO(5036) | [Parent 5036, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Child 6828, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Child 6828, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Parent 5036, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 23:32:05 INFO - GECKO(5036) | [Parent 5036, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Parent 5036, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Child 4776, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Parent 5036, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Parent 5036, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Parent 5036, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Parent 5036, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Child 6028, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Child 6028, Chrome_ChildThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Parent 5036, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Parent 5036, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost 23:32:05 INFO - GECKO(5036) | [Parent 5036, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:05 INFO - GECKO(5036) | [Parent 5036, Gecko_IOThread] WARNING: pipe error: 109: file z:/build/build/src/ipc/chromium/src/chrome/common/ipc_channel_win.cc, line 346 23:32:06 INFO - GECKO(5036) | 1532043126197 Marionette DEBUG Received observer notification xpcom-will-shutdown 23:32:06 INFO - GECKO(5036) | 1532043126198 Marionette INFO Stopped listening on port 2828 23:32:06 INFO - GECKO(5036) | 1532043126198 Marionette DEBUG Remote service is inactive 23:32:16 INFO - GECKO(5036) | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"GeckoMediaPluginServiceParent: shutdown","state":"(none)","filename":"z:/build/build/src/dom/media/gmp/GMPServiceParent.cpp","lineNumber":1657,"stack":"GeckoMediaPluginServiceParent shutdown"}] Barrier: xpcom-will-shutdown 23:35:07 INFO - GECKO(5036) | FATAL ERROR: AsyncShutdown timeout in xpcom-will-shutdown Conditions: [{"name":"GeckoMediaPluginServiceParent: shutdown","state":"(none)","filename":"z:/build/build/src/dom/media/gmp/GMPServiceParent.cpp","lineNumber":1657,"stack":"GeckoMediaPluginServiceParent shutdown"}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources. 23:35:07 INFO - GECKO(5036) | WARNING: No crash reporter available 23:35:07 INFO - GECKO(5036) | [Parent 5036, Main Thread] ###!!! ABORT: file z:/build/build/src/dom/media/gmp/GMPServiceParent.cpp, line 1657 23:35:07 INFO - TEST-INFO | Main app process: exit 80000003 23:35:07 INFO - Buffered messages finished 23:35:07 ERROR - 961 ERROR TEST-UNEXPECTED-FAIL | Last test finished | application terminated with exit code 2147483651 23:35:07 INFO - runtests.py | Application ran for: 0:05:36.307000 23:35:07 INFO - zombiecheck | Reading PID log: c:\users\task_1532041486\appdata\local\temp\tmp58zpkvpidlog 23:35:07 INFO - ==> process 5036 launched child process 4776 ("Z:\task_1532041486\build\application\firefox\firefox.exe" -contentproc --channel="5036.0.2108754579\1705391938" -childID 1 -isForBrowser -prefsHandle 1924 -prefMapHandle 1944 -prefsLen 1 -prefMapSize 185804 -schedulerPrefs 0001,2 -parentBuildID 20180719220202 -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 tab) 23:35:07 INFO - ==> process 5036 launched child process 6028 ("Z:\task_1532041486\build\application\firefox\firefox.exe" -contentproc --channel="5036.6.1061236054\365443156" -childID 2 -isForBrowser -prefsHandle 2480 -prefMapHandle 2484 -prefsLen 82 -prefMapSize 185804 -schedulerPrefs 0001,2 -parentBuildID 20180719220202 -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 tab) 23:35:07 INFO - ==> process 5036 launched child process 6828 ("Z:\task_1532041486\build\application\firefox\firefox.exe" -contentproc --channel="5036.12.513335231\1814505836" -childID 3 -isForBrowser -prefsHandle 3056 -prefMapHandle 3060 -prefsLen 3783 -prefMapSize 185804 -schedulerPrefs 0001,2 -parentBuildID 20180719220202 -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 tab) 23:35:07 INFO - ==> process 5036 launched child process 6316 ("Z:\task_1532041486\build\application\firefox\plugin-container.exe" --channel="5036.19.2061601199\2056049951" "Z:\task_1532041486\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 geckomediaplugin) 23:35:07 INFO - ==> process 5036 launched child process 6332 ("Z:\task_1532041486\build\application\firefox\plugin-container.exe" --channel="5036.21.2122984108\2490748" "Z:\task_1532041486\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 geckomediaplugin) 23:35:07 INFO - ==> process 5036 launched child process 7104 ("Z:\task_1532041486\build\application\firefox\plugin-container.exe" --channel="5036.23.982898465\1702468804" "Z:\task_1532041486\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 geckomediaplugin) 23:35:07 INFO - ==> process 5036 launched child process 3508 ("Z:\task_1532041486\build\application\firefox\plugin-container.exe" --channel="5036.25.1312416012\1187409941" "Z:\task_1532041486\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 geckomediaplugin) 23:35:07 INFO - ==> process 5036 launched child process 7188 ("Z:\task_1532041486\build\application\firefox\plugin-container.exe" --channel="5036.27.2074737520\1361478171" "Z:\task_1532041486\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 geckomediaplugin) 23:35:07 INFO - ==> process 5036 launched child process 8024 ("Z:\task_1532041486\build\application\firefox\plugin-container.exe" --channel="5036.29.571658881\1509169790" "Z:\task_1532041486\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 geckomediaplugin) 23:35:07 INFO - ==> process 5036 launched child process 7928 ("Z:\task_1532041486\build\application\firefox\plugin-container.exe" --channel="5036.31.1709335676\1746074978" "Z:\task_1532041486\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 geckomediaplugin) 23:35:07 INFO - ==> process 5036 launched child process 7204 ("Z:\task_1532041486\build\application\firefox\plugin-container.exe" --channel="5036.33.2056030879\1663962554" "Z:\task_1532041486\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 geckomediaplugin) 23:35:07 INFO - ==> process 5036 launched child process 6432 ("Z:\task_1532041486\build\application\firefox\plugin-container.exe" --channel="5036.35.1549795602\304122570" "Z:\task_1532041486\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 geckomediaplugin) 23:35:07 INFO - ==> process 5036 launched child process 1224 ("Z:\task_1532041486\build\application\firefox\plugin-container.exe" --channel="5036.37.2050718155\1243446658" "Z:\task_1532041486\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 geckomediaplugin) 23:35:07 INFO - ==> process 5036 launched child process 5776 ("Z:\task_1532041486\build\application\firefox\plugin-container.exe" --channel="5036.39.1209385531\67706650" "Z:\task_1532041486\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 geckomediaplugin) 23:35:07 INFO - ==> process 5036 launched child process 6064 ("Z:\task_1532041486\build\application\firefox\plugin-container.exe" --channel="5036.41.422897813\529197533" "Z:\task_1532041486\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 geckomediaplugin) 23:35:07 INFO - ==> process 5036 launched child process 8104 ("Z:\task_1532041486\build\application\firefox\plugin-container.exe" --channel="5036.43.915700274\825921260" "Z:\task_1532041486\build\application\firefox\gmp-clearkey\0.1" -greomni "Z:\task_1532041486\build\application\firefox\omni.ja" -appomni "Z:\task_1532041486\build\application\firefox\browser\omni.ja" -appdir "Z:\task_1532041486\build\application\firefox\browser" - 5036 geckomediaplugin) 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 7104 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 6432 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 6064 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 7204 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 4776 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 6316 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 6028 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 6828 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 1224 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 5776 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 7928 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 8104 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 3508 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 8024 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 7188 23:35:07 INFO - zombiecheck | Checking for orphan process with PID: 6332 23:35:07 INFO - Stopping web server 23:35:07 INFO - Stopping web socket server 23:35:07 INFO - Stopping ssltunnel 23:35:07 INFO - Stopping websocket/process bridge 23:35:07 INFO - leakcheck | refcount logging is off, so leaks can't be detected! 23:35:07 INFO - runtests.py | Running tests: end.
Not sure about the product and component. Sorry.
(In reply to Treeherder Bug Filer from comment #0) > 23:32:16 INFO - GECKO(5036) | WARNING: At least one completion condition is taking too long to complete. Conditions: [{"name":"GeckoMediaPluginServiceParent: shutdown","state":"(none)","filename":"z:/build/build/src/dom/media/gmp/GMPServiceParent.cpp","lineNumber":1657,"stack":"GeckoMediaPluginServiceParent shutdown"}] Barrier: xpcom-will-shutdown > 23:35:07 INFO - GECKO(5036) | FATAL ERROR: AsyncShutdown timeout in xpcom-will-shutdown Conditions: [{"name":"GeckoMediaPluginServiceParent: shutdown","state":"(none)","filename":"z:/build/build/src/dom/media/gmp/GMPServiceParent.cpp","lineNumber":1657,"stack":"GeckoMediaPluginServiceParent shutdown"}] At least one completion condition failed to complete within a reasonable amount of time. Causing a crash to ensure that we do not leave the user with an unresponsive process draining resources. I'm also not sure about the component, but those lines point to GMP.
Component: IPC → Audio/Video: GMP
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.