[confirm] FAILED: audio renderer afte resume (1 Viewer)

[pur_berger]

Note sure if this is MP or skin related - but since 1.4 I'm experiancing this issue. I get the last TV picture before I send the unit in standby and that's it - need to kill the task to make it orking again. I think with the old version "restart MP on resume" was the workaround for this:

[2013-05-29 13:09:57,900] [Error  ] [MPMain  ] [ERROR] - Exception while loading GUIWindows instances: MyAnimePlugin3.Windows.RandomWindow
[2013-05-29 13:09:57,937] [Error  ] [MPMain  ] [ERROR] - Ein Aufrufziel hat einen Ausnahmefehler verursacht.
[2013-05-29 13:09:57,938] [Error  ] [MPMain  ] [ERROR] -	bei System.RuntimeTypeHandle.CreateInstance(RuntimeType type, Boolean publicOnly, Boolean noCheck, Boolean& canBeCached, RuntimeMethodHandle& ctor, Boolean& bNeedSecurityCheck)
  bei System.RuntimeType.CreateInstanceSlow(Boolean publicOnly, Boolean fillCache)
  bei System.RuntimeType.CreateInstanceImpl(Boolean publicOnly, Boolean skipVisibilityChecks, Boolean fillCache)
  bei System.Activator.CreateInstance(Type type, Boolean nonPublic)
  bei MediaPortal.GUI.Library.PluginManager.LoadWindowPlugin(String strFile)
[2013-05-29 13:09:58,249] [Error  ] [MPMain  ] [ERROR] - PluginManager: 'ClickfinderProgramGuide, Version=1.0.0.0, Culture=neutral, PublicKeyToken=null' is tagged as incompatible with the current MediaPortal version and won't be loaded!
[2013-05-29 13:10:02,860] [Error  ] [MPMain  ] [ERROR] - PluginManager: 'MyLyrics, Version=1.7.0.67, Culture=neutral, PublicKeyToken=null' is tagged as incompatible with the current MediaPortal version and won't be loaded!
[2013-05-29 13:10:03,112] [Error  ] [MPMain  ] [ERROR] - PluginManager: 'ShowTimesGUI, Version=3.3.0.0, Culture=neutral, PublicKeyToken=null' is tagged as incompatible with the current MediaPortal version and won't be loaded!
[2013-05-29 13:10:04,858] [Error  ] [MPMain  ] [ERROR] - PluginManager: 'SubCentral, Version=1.2.0.289, Culture=neutral, PublicKeyToken=null' is tagged as incompatible with the current MediaPortal version and won't be loaded!
[2013-05-29 13:10:08,850] [Error  ] [MPMain  ] [ERROR] - PluginManager: 'TvWishListMP, Version=1.3.0.5, Culture=neutral, PublicKeyToken=null' is tagged as incompatible with the current MediaPortal version and won't be loaded!
[2013-05-29 13:10:12,406] [Error  ] [MPMain  ] [ERROR] - PluginManager: 'DVDArt_Plugin, Version=1.0.1.1, Culture=neutral, PublicKeyToken=null' is tagged as incompatible with the current MediaPortal version and won't be loaded!
[2013-05-29 13:10:12,516] [Error  ] [MPMain  ] [ERROR] - PluginManager: 'ShowTimesGrabber, Version=3.3.0.0, Culture=neutral, PublicKeyToken=null' is tagged as incompatible with the current MediaPortal version and won't be loaded!
[2013-05-29 13:10:24,042] [Error  ] [MPMain  ] [ERROR] - MiniDisplay:Error while loading driver C:\Program Files (x86)\Team MediaPortal\MediaPortal\Plugins\process\LCDDrivers\iMONDisplay.dll: Der Einstiegspunkt "DLL_GetInfo" wurde nicht in der DLL "C:\Program Files (x86)\Team MediaPortal\MediaPortal\Plugins\process\LCDDrivers\iMONDisplay.dll" gefunden.
[2013-05-29 13:10:24,050] [Error  ] [MPMain  ] [ERROR] - MiniDisplay:Error while loading driver C:\Program Files (x86)\Team MediaPortal\MediaPortal\Plugins\process\LCDDrivers\iMONDisplayWrapper.dll: Der Einstiegspunkt "DLL_GetInfo" wurde nicht in der DLL "C:\Program Files (x86)\Team MediaPortal\MediaPortal\Plugins\process\LCDDrivers\iMONDisplayWrapper.dll" gefunden.
[2013-05-29 13:10:24,067] [Error  ] [MPMain  ] [ERROR] - MiniDisplay:Error while loading driver C:\Program Files (x86)\Team MediaPortal\MediaPortal\Plugins\process\LCDDrivers\LCD2USB.dll: Die DLL "C:\Program Files (x86)\Team MediaPortal\MediaPortal\Plugins\process\LCDDrivers\LCD2USB.dll": Das angegebene Modul wurde nicht gefunden. (Ausnahme von HRESULT: 0x8007007E) kann nicht geladen werden.
[2013-05-29 13:11:09,424] [Error  ] [MPMain  ] [ERROR] - DirectShowUtils: failed filter Realtek HDMI Output (ATI HDMI A not found
[2013-05-29 13:11:09,427] [Error  ] [MPMain  ] [ERROR] - FAILED: audio renderer:Realtek HDMI Output (ATI HDMI A doesnt exists

 

[pur_berger]

Watchdog Logs:

 

[pur_berger]

Any info required from my side?

 

[mm1352000]

Hello pur_berger

From the logs I'm guessing that the driver takes some time to load. I can see that MP is still detecting device removal and arrival when you try to start TV:

[collapse][2013-05-29 13:34:55,333] [Log ] [MPMain ] [INFO ] - add filter:Realtek HDMI Output (ATI HDMI A to graph clock:True
[2013-05-29 13:34:55,333] [Error ] [MPMain ] [ERROR] - FAILED: audio renderer:Realtek HDMI Output (ATI HDMI A doesnt exists
[2013-05-29 13:34:55,404] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: Add TsReader to graph
[2013-05-29 13:34:55,404] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: Open file: E:\\live2-0.ts.tsbuffer
[2013-05-29 13:34:55,406] [Log ] [MPMain ] [INFO ] - TSReaderPlayer:OnRequestAudioChange()
[2013-05-29 13:34:55,406] [Log ] [MPMain ] [DEBUG] - TVHome.OnAudioTracksReady()
[2013-05-29 13:34:55,420] [Log ] [MPMain ] [DEBUG] - TVHome.GetPreferedAudioStreamIndex(): preferred LANG(s):n/a preferAC3:False _preferAudioTypeOverLang:True
[2013-05-29 13:34:55,420] [Log ] [MPMain ] [DEBUG] - Audio streams avail: 1
[2013-05-29 13:34:55,421] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded
[2013-05-29 13:34:55,421] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded
[2013-05-29 13:34:55,421] [Log ] [MPMain ] [INFO ] - Audio stream: switching to preferred AC3/MPEG audio stream 0 (only 1 track avail.)
[2013-05-29 13:34:55,422] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: AudioDualMonoMode switching not available. Audioswitcher filter not loaded
[2013-05-29 13:34:55,427] [Log ] [MPMain ] [INFO ] - TsReaderPlayer: OnVideoFormatChanged - streamtype=MPEG2 resolution=720x576 aspect ratio=16:9 bitrate=15000000 isInterlaced=True
[2013-05-29 13:34:55,433] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: Add codecs
[2013-05-29 13:34:55,707] [Log ] [MPMain ] [INFO ] - VMR9: added EVR Renderer to graph
[2013-05-29 13:34:55,708] [Log ] [MPMain ] [DEBUG] - VMR9: Now active
[2013-05-29 13:34:55,708] [Log ] [MPMain ] [DEBUG] - VMR9: Renderer successfully added
[2013-05-29 13:34:55,925] [Log ] [MPMain ] [INFO ] - Added filter: CyberLink Video Decoder (PDVD13) to graph
[2013-05-29 13:34:55,925] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: UpdateFilters Video done
[2013-05-29 13:34:55,975] [Log ] [MPMain ] [INFO ] - Added filter: LAV Audio Decoder to graph
[2013-05-29 13:34:55,975] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: UpdateFilters Audio done
[2013-05-29 13:34:55,980] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: PostProcessingEngine to DummyEngine
[2013-05-29 13:34:55,980] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: Render TsReader outputs
[2013-05-29 13:34:55,984] [Log ] [MPMain ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEARRIVAL)
[2013-05-29 13:34:55,984] [Log ] [MPMain ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: ATI HDMI Audio
[2013-05-29 13:34:55,984] [Log ] [MPMain ] [DEBUG] - Main: WM_ACTIVATE (WA_INACTIVE)
[2013-05-29 13:34:55,984] [Log ] [MPMain ] [INFO ] - Main: Deactivation request received
[2013-05-29 13:34:55,984] [Log ] [MPMain ] [INFO ] - Main: Refresh rate changer running. Ignoring deactivation request
[2013-05-29 13:34:55,985] [Log ] [MPMain ] [DEBUG] - KeyboardInputPlugin: Stopping worker thread
[2013-05-29 13:34:55,985] [Log ] [MPMain ] [DEBUG] - KeyboardInputPlugin: Waiting for worker thread to finish
[2013-05-29 13:34:55,986] [Log ] [31 ] [DEBUG] - KeyboardInputPlugin: Disabling keyboard handler
[2013-05-29 13:34:55,986] [Log ] [31 ] [DEBUG] - KeyboardInputPlugin: IsEnabled: False
[2013-05-29 13:34:55,987] [Log ] [MPMain ] [INFO ] - KeyboardInputPlugin: Worker thread finished
[2013-05-29 13:34:55,987] [Log ] [MPMain ] [DEBUG] - D3D: OnLostFocus()
[2013-05-29 13:34:55,989] [Log ] [MPMain ] [DEBUG] - D3D: OnPaint()
[2013-05-29 13:34:55,990] [Log ] [MPMain ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEARRIVAL)
[2013-05-29 13:34:55,990] [Log ] [MPMain ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: ATI HDMI Audio
[2013-05-29 13:34:55,997] [Log ] [MPMain ] [DEBUG] - Filter: TsReader - try to connect: Audio
[2013-05-29 13:34:56,105] [Log ] [MPMain ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEREMOVECOMPLETE)
[2013-05-29 13:34:56,105] [Log ] [MPMain ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: ATI HDMI Audio
[2013-05-29 13:34:56,205] [Log ] [MPMain ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEREMOVECOMPLETE)
[2013-05-29 13:34:56,206] [Log ] [MPMain ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: ATI HDMI Audio
[2013-05-29 13:34:56,207] [Log ] [MPMain ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEREMOVECOMPLETE)
[2013-05-29 13:34:56,207] [Log ] [MPMain ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: ATI HDMI Audio
[2013-05-29 13:34:56,215] [Log ] [MPMain ] [DEBUG] - Filter: TsReader - try to connect: Video
[2013-05-29 13:34:56,222] [Log ] [MPMain ] [DEBUG] - PlaneScene: PresentImage() dispose surfaces
[2013-05-29 13:34:56,244] [Log ] [MPMain ] [DEBUG] - Filter: TsReader - try to connect: Subtitle
[2013-05-29 13:34:56,266] [Log ] [MPMain ] [DEBUG] - - failed
[2013-05-29 13:34:56,267] [Log ] [MPMain ] [DEBUG] - Filter: CyberLink Video Decoder (PDVD13) - try to connect: ~Closed Caption Out
[2013-05-29 13:34:56,272] [Log ] [MPMain ] [DEBUG] - Filter: CyberLink Video Decoder (PDVD13) - try to connect: ~Encode Out
[2013-05-29 13:34:56,324] [Log ] [MPMain ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEARRIVAL)
[2013-05-29 13:34:56,325] [Log ] [MPMain ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: ATI HDMI Audio
[2013-05-29 13:34:56,334] [Log ] [MPMain ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEARRIVAL)
[2013-05-29 13:34:56,335] [Log ] [MPMain ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: ATI HDMI Audio
[2013-05-29 13:34:56,337] [Log ] [MPMain ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEARRIVAL)
[2013-05-29 13:34:56,337] [Log ] [MPMain ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: ATI HDMI Audio
[2013-05-29 13:34:56,428] [Log ] [MPMain ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEREMOVECOMPLETE)
[2013-05-29 13:34:56,429] [Log ] [MPMain ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: ATI HDMI Audio
[2013-05-29 13:34:56,432] [Log ] [MPMain ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEREMOVECOMPLETE)
[2013-05-29 13:34:56,432] [Log ] [MPMain ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: ATI HDMI Audio
[2013-05-29 13:34:56,434] [Log ] [MPMain ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEREMOVECOMPLETE)
[2013-05-29 13:34:56,434] [Log ] [MPMain ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: ATI HDMI Audio
[2013-05-29 13:34:56,627] [Log ] [MPMain ] [DEBUG] - - failed
[2013-05-29 13:34:56,628] [Log ] [MPMain ] [INFO ] - TSReaderPlayer: Cleanup Captions
[2013-05-29 13:34:56,628] [Log ] [MPMain ] [DEBUG] - TSReaderPlayer: CleanupCC filter (Tv/Recorded Stream Detected)[/collapse]

So you can see that MP wants to insert the audio renderer at 2013-05-29 13:34:55,333 but the audio device detection is not complete until 2013-05-29 13:34:56,434.

[edit: does playback work correctly if you restart TV after 10 or 20 seconds (without MP restart)]

mm

 

[pur_berger]

That was my guessing too - so - delay startup (when working) will do the fix? Or - on teh other hand - can it be solved via code so MP "waits" for teh driver to be loaded?

About your question - the problem is that MP is not responsive at all when this happens - so the only way is to kill it and restart it.

Edit: I have tested it when switching from Live TV directly to standby and back on. Probaly when stopping Live TV before it goes to standby it would not happen - I think I can remember that at one point I did it without Live TV and when back on sound was gone and wouldn't come up at all - restart of MP did the trick there.

 

[mm1352000]

That was my guessing too - so - delay startup (when working) will do the fix?

I guess we will see.

Or - on teh other hand - can it be solved via code so MP "waits" for teh driver to be loaded?

I don't think this would be easy/possible.

About your question - the problem is that MP is not responsive at all when this happens - so the only way is to kill it and restart it.

Okay.

Edit: I have tested it when switching from Live TV directly to standby and back on. Probaly when stopping Live TV before it goes to standby it would not happen - I think I can remember that at one point I did it without Live TV and when back on sound was gone and wouldn't come up at all - restart of MP did the trick there.

Yeah, I noticed that sometimes it looks like the PC is going into standby without TV properly stopping. I don't know if that is contributing to the problem, but I would recommended to avoid that if possible.

 

[pur_berger]

OK - so there are two different issues here:

- TV does not stop properly when sending HTPC in standby (no idea how to collect logs here ...)
- MP tries to load audio render before it is loaded by Windows after resume