Sound stops working after first playback (2 Viewers)

Glith · January 30, 2019

After playing a file the sounds stops working on all following playbacks. A restart of MediaPortal before every playback is working but not a viable workaround.
For example as: Started playback of one episode of a series, stopped and then started the same episode from start again. Second time did not have sound.

Windows 10 Enterprise
Mediaportal 1.21 Final
LAV Filter 0.73.1
madVR 0.92.17 (Using madVR refresh changer)

It worked fine in the 1.21 pre-build.

Known issue?

Ps. Also had issue with LAVFilter Audio Delay was reset to 0 every time in the final build(worked in pre-build).
After I changed the delay in MediaPortal as well and it went away. But maybe this Feature/bug is related to this change?

Owlsroost · January 31, 2019

Glith said:
Known issue?

No.

Logs please?

Glith said:
Ps. Also had issue with LAVFilter Audio Delay was reset to 0 every time in the final build(worked in pre-build).

That issue was reported by a user in the pre-release, so changes were made in Final release to try and fix that (see discussion in - Audio sync problems on LAV filters ).

It might be we need to look at it again, if there is a problem in some situations.

Glith · January 31, 2019

Logs are now attached.

Owlsroost · February 1, 2019

It looks like the audio renderer has a problem (note the DBT_DEVICEREMOVECOMPLETE events etc.) before this happens):

Code:

[2019-01-31 19:04:11,721] [Log    ] [MPMain   ] [DEBUG] - D3D: ForceMPAlive start.
[2019-01-31 19:04:11,768] [Log    ] [MPMain   ] [DEBUG] - Main: WM_ACTIVATE (WA_INACTIVE)
[2019-01-31 19:04:11,776] [Log    ] [MPMain   ] [INFO ] - Main: Deactivation request received
[2019-01-31 19:04:11,807] [Log    ] [MPMain   ] [DEBUG] - D3D: OnLostFocus()
[2019-01-31 19:04:11,817] [Log    ] [MPMain   ] [DEBUG] - Main: WM_WINDOWPOSCHANGED
[2019-01-31 19:04:11,819] [Log    ] [MPMain   ] [DEBUG] - Main: WM_ACTIVATE (WA_ACTIVE)
[2019-01-31 19:04:11,820] [Log    ] [MPMain   ] [INFO ] - Main: Activation request received
[2019-01-31 19:04:11,823] [Log    ] [MPMain   ] [DEBUG] - D3D: OnGotFocus()
[2019-01-31 19:04:11,832] [Log    ] [MPMain   ] [DEBUG] - D3D: ForceMpAlive MP Successfully switched focus.
[2019-01-31 19:04:11,835] [Log    ] [MPMain   ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEREMOVECOMPLETE)
[2019-01-31 19:04:11,843] [Log    ] [MPMain   ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: Intel(R) Display Audio
[2019-01-31 19:04:11,849] [Log    ] [MPMain   ] [DEBUG] - Main: DBT_DEVICEREMOVECOMPLETE AUDIO Intel(R) Display Audio
[2019-01-31 19:04:11,851] [Log    ] [MPMain   ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEREMOVECOMPLETE)
[2019-01-31 19:04:11,853] [Log    ] [MPMain   ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: Intel(R) Display Audio
[2019-01-31 19:04:11,865] [Log    ] [MPMain   ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEREMOVECOMPLETE)
[2019-01-31 19:04:11,866] [Log    ] [MPMain   ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: Intel(R) Display Audio
[2019-01-31 19:04:11,866] [Log    ] [MPMain   ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEREMOVECOMPLETE)
[2019-01-31 19:04:11,867] [Log    ] [MPMain   ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: Intel(R) Display Audio
[2019-01-31 19:04:11,868] [Log    ] [MPMain   ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEREMOVECOMPLETE)
[2019-01-31 19:04:11,869] [Log    ] [MPMain   ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: Intel(R) Display Audio
[2019-01-31 19:04:11,870] [Log    ] [MPMain   ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEREMOVECOMPLETE)
[2019-01-31 19:04:11,870] [Log    ] [MPMain   ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: Intel(R) HD Graphics 630
[2019-01-31 19:04:11,871] [Log    ] [MPMain   ] [INFO ] - Main: Video Device or Screen Intel(R) HD Graphics 630 removed
[2019-01-31 19:04:11,872] [Log    ] [MPMain   ] [DEBUG] - D3D: ForceMPAlive start.
[2019-01-31 19:04:11,874] [Log    ] [MPMain   ] [DEBUG] - Main: WM_ACTIVATE (WA_INACTIVE)
[2019-01-31 19:04:11,874] [Log    ] [MPMain   ] [INFO ] - Main: Deactivation request received
[2019-01-31 19:04:11,875] [Log    ] [MPMain   ] [DEBUG] - D3D: OnLostFocus()
[2019-01-31 19:04:11,878] [Log    ] [MPMain   ] [DEBUG] - Main: WM_WINDOWPOSCHANGED
[2019-01-31 19:04:11,879] [Log    ] [MPMain   ] [DEBUG] - Main: WM_ACTIVATE (WA_ACTIVE)
[2019-01-31 19:04:11,880] [Log    ] [MPMain   ] [INFO ] - Main: Activation request received
[2019-01-31 19:04:11,881] [Log    ] [MPMain   ] [DEBUG] - D3D: OnGotFocus()
[2019-01-31 19:04:11,882] [Log    ] [MPMain   ] [DEBUG] - D3D: ForceMpAlive MP Successfully switched focus.
[2019-01-31 19:04:11,883] [Log    ] [MPMain   ] [DEBUG] - GraphicContext: device video removed - Count 0
[2019-01-31 19:04:11,884] [Log    ] [MPMain   ] [DEBUG] - Main: WM_DEVICECHANGE (Event: DBT_DEVICEREMOVECOMPLETE)
[2019-01-31 19:04:11,884] [Log    ] [MPMain   ] [DEBUG] - Main: Device type is DBT_DEVTYP_DEVICEINTERFACE - Name: TX-SR608 (Intel(R) Display Audio)
[2019-01-31 19:04:11,885] [Log    ] [MPMain   ] [DEBUG] - Main: DBT_DEVICEREMOVECOMPLETE AUDIO TX-SR608 (Intel(R) Display Audio)
[2019-01-31 19:04:11,885] [Log    ] [MPMain   ] [DEBUG] - Main: WM_GETMINMAXINFO Start (MaxSize: 1920x1080 - MaxPostion: 0,0 - MinTrackSize: 2x2 - MaxTrackSize: 1940x1100)
[2019-01-31 19:04:11,886] [Log    ] [MPMain   ] [DEBUG] - Main: WM_GETMINMAXINFO End (MaxSize: 1920x1080 - MaxPostion: 0,0 - MinTrackSize: 1920x1080 - MaxTrackSize: 1920x1080)
[2019-01-31 19:04:11,888] [Log    ] [MPMain   ] [INFO ] - Main: Audio Renderer TX-SR608 (Intel(R) Display Audio) removed
[2019-01-31 19:04:11,889] [Log    ] [MPMain   ] [DEBUG] - Main: send message to stop mediacontrol for audio playback
[2019-01-31 19:04:11,890] [Log    ] [MPMain   ] [INFO ] - Main: Audio Renderer TX-SR608 (Intel(R) Display Audio) connected
[2019-01-31 19:04:11,890] [Log    ] [MPMain   ] [DEBUG] - Main: AUDIODEVICEARRIVAL CurrentAudioRendererDevice NULL - send message to add audio playback
[2019-01-31 19:04:11,891] [Log    ] [MPMain   ] [DEBUG] - VMR9 : madVR reposition window
[2019-01-31 19:04:11,913] [Log    ] [MPMain   ] [WARN ] - GUIWindow:OnWindowLoaded: 'C:\ProgramData\Team MediaPortal\MediaPortal\Skin\StreamedMP\TVSeries.xml' is missing control id 526 (window property: loadingImage)
[2019-01-31 19:04:11,933] [Log    ] [MPMain   ] [DEBUG] - TextureManagerEx: load from memory: [TVSeries:C:\ProgramData\Team MediaPortal\MediaPortal\Thumbs\TVSeriesDynLogo720PH264AC3 5.1HDTV.png]
[2019-01-31 19:04:11,964] [Log    ] [MPMain   ] [DEBUG] - VMR9: AudioRendererMediaControlStop
[2019-01-31 19:04:11,965] [Log    ] [MPMain   ] [DEBUG] - VMR9: AudioRendererMediaControlStop
[2019-01-31 19:04:12,037] [Log    ] [70       ] [DEBUG] - SubEngine : init engine : MediaPortal.Player.Subtitles.MpcEngine
[2019-01-31 19:04:12,038] [Log    ] [70       ] [DEBUG] - MpcEngine: using texture size of 1280x720
[2019-01-31 19:04:12,038] [Log    ] [70       ] [DEBUG] - Planescene: Set subtitle device - 568854816
[2019-01-31 19:04:12,066] [Log    ] [MPMain   ] [DEBUG] - DirectShowUtil: AddAudioRendererToGraph : (Default DirectSound Device) init
[2019-01-31 19:04:12,066] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: AddAudioRenderer - Attach volume handler device to audio renderer: Default DirectSound Device
[2019-01-31 19:04:12,067] [Log    ] [MPMain   ] [DEBUG] - Mixer: changed audio device init : TX-SR608 (Intel(R) Display Audio)
[2019-01-31 19:04:12,067] [Log    ] [MPMain   ] [DEBUG] - Mixer: changed audio device done : TX-SR608 (Intel(R) Display Audio)
[2019-01-31 19:04:12,068] [Log    ] [MPMain   ] [DEBUG] - Mixer: changed audio device EnumAudioEndpoints init : TX-SR608 (Intel(R) Display Audio)
[2019-01-31 19:04:12,070] [Log    ] [MPMain   ] [DEBUG] - Mixer: changed audio device EnumAudioEndpoints done : TX-SR608 (Intel(R) Display Audio)
[2019-01-31 19:04:12,070] [Log    ] [MPMain   ] [DEBUG] - GraphicContext: device audio removed - Count 0
[2019-01-31 19:04:12,072] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: First try to insert new audio renderer Default DirectSound Device
[2019-01-31 19:04:12,073] [Log    ] [MPMain   ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: TX-SR608 (Intel(R) Display Audio) to graph for Default DirectSound Device
[2019-01-31 19:04:12,073] [Log    ] [MPMain   ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: MediaPortal - Audio Renderer to graph for Default DirectSound Device
[2019-01-31 19:04:12,074] [Log    ] [MPMain   ] [DEBUG] - DirectShowUtil: List AddAudioRenderer filter: Default DirectSound Device to graph for Default DirectSound Device
[2019-01-31 19:04:12,075] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Found audio renderer
[2019-01-31 19:04:12,093] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: inserting new audio renderer Default DirectSound Device failed - try a new method
[2019-01-31 19:04:12,097] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: add filter:Default DirectSound Device to graph clock:False
[2019-01-31 19:04:12,107] [Log    ] [MPMain   ] [DEBUG] - DirectShowUtil: List 'check if already exist' AddAudioRendererToGraph filter: TX-SR608 (Intel(R) Display Audio) to graph for Default DirectSound Device
[2019-01-31 19:04:12,108] [Log    ] [MPMain   ] [DEBUG] - DirectShowUtil: List 'check if already exist' AddAudioRendererToGraph filter: MediaPortal - Audio Renderer to graph for Default DirectSound Device
[2019-01-31 19:04:12,110] [Log    ] [MPMain   ] [DEBUG] - DirectShowUtil: List 'check if already exist' AddAudioRendererToGraph filter: Default DirectSound Device to graph for Default DirectSound Device
[2019-01-31 19:04:12,111] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: found audio renderer - Default DirectSound Device
[2019-01-31 19:04:12,112] [Log    ] [MPMain   ] [DEBUG] - DirectShowUtil: List 'check if already exist' AddAudioRendererToGraph filter: Default WaveOut Device to graph for Default DirectSound Device
[2019-01-31 19:04:12,113] [Log    ] [MPMain   ] [DEBUG] - DirectShowUtil: List 'check if already exist' AddAudioRendererToGraph filter: DirectSound: TX-SR608 (Intel(R) Display Audio) to graph for Default DirectSound Device
[2019-01-31 19:04:12,120] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\wave:{CE7B65A8-4271-4910-AEC9-2F7958494F8D}): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,141] [Log    ] [madVR - RenderOverlay] [DEBUG] - PlaneScene: crop T, B  : 0, 0
[2019-01-31 19:04:12,143] [Log    ] [madVR - RenderOverlay] [DEBUG] - PlaneScene: crop L, R  : 0, 0
[2019-01-31 19:04:12,144] [Log    ] [madVR - RenderOverlay] [INFO ] - PlaneScene: video WxH  : 1920x1080
[2019-01-31 19:04:12,145] [Log    ] [madVR - RenderOverlay] [DEBUG] - PlaneScene: video AR   : 1280:720
[2019-01-31 19:04:12,145] [Log    ] [madVR - RenderOverlay] [INFO ] - PlaneScene: screen WxH : 1920x1080
[2019-01-31 19:04:12,146] [Log    ] [madVR - RenderOverlay] [DEBUG] - PlaneScene: AR type    : Normal
[2019-01-31 19:04:12,147] [Log    ] [madVR - RenderOverlay] [DEBUG] - PlaneScene: PixelRatio : 1
[2019-01-31 19:04:12,147] [Log    ] [madVR - RenderOverlay] [DEBUG] - PlaneScene: src        : (0,0)-(1920,1080)
[2019-01-31 19:04:12,147] [Log    ] [madVR - RenderOverlay] [DEBUG] - PlaneScene: dst        : (0,0)-(1920,1080)
[2019-01-31 19:04:12,148] [Log    ] [madVR - RenderOverlay] [DEBUG] - Main: RenderFrame - DBT_DEVICEREMOVECOMPLETE
[2019-01-31 19:04:12,155] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\Default DirectSound Device): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,156] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\Default WaveOut Device): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,157] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\DirectSound:{CE7B65A8-4271-4910-AEC9-2F7958494F8D}): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,158] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\wave:{CE7B65A8-4271-4910-AEC9-2F7958494F8D}): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,172] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\Default DirectSound Device): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,173] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\Default WaveOut Device): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,174] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\DirectSound:{CE7B65A8-4271-4910-AEC9-2F7958494F8D}): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,175] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\wave:{CE7B65A8-4271-4910-AEC9-2F7958494F8D}): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,189] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\Default DirectSound Device): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,191] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\Default WaveOut Device): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,192] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\DirectSound:{CE7B65A8-4271-4910-AEC9-2F7958494F8D}): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,193] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\wave:{CE7B65A8-4271-4910-AEC9-2F7958494F8D}): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,207] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\Default DirectSound Device): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,209] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\Default WaveOut Device): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,210] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\DirectSound:{CE7B65A8-4271-4910-AEC9-2F7958494F8D}): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,211] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\wave:{CE7B65A8-4271-4910-AEC9-2F7958494F8D}): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,224] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\Default DirectSound Device): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,225] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\Default WaveOut Device): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,226] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Exception in BindToMoniker(@device:cm:{E0F158E1-CB04-11D0-BD4E-00A0C911CE86}\DirectSound:{CE7B65A8-4271-4910-AEC9-2F7958494F8D}): Exception from HRESULT: 0x80040256
[2019-01-31 19:04:12,226] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Passed removing audio renderer
[2019-01-31 19:04:12,227] [Log    ] [MPMain   ] [INFO ] - DirectShowUtil: Passed finding Audio Renderer
[2019-01-31 19:04:12,228] [Error  ] [MPMain   ] [ERROR] - DirectshowUtil: Failed to add filter:Default DirectSound Device to graph :Exception from HRESULT: 0x80040256 mscorlib    at System.Runtime.InteropServices.Marshal.BindMoniker(IMoniker pmk, UInt32 grfOpt, Guid& iidResult, Object& ppvResult)
   at System.Runtime.InteropServices.Marshal.BindToMoniker(String monikerName)
   at DShowNET.Helper.DirectShowUtil.AddAudioRenderer(IGraphBuilder graphBuilder, String strFilterName, Boolean setAsReferenceClock)
[2019-01-31 19:04:12,229] [Log    ] [MPMain   ] [DEBUG] - DirectShowUtil: volume handler value 65535
[2019-01-31 19:04:12,231] [Log    ] [MPMain   ] [DEBUG] - GraphicContext: init volume handler

The error exception code (HRESULT: 0x80040256) means:

0x80040256 VFW_E_NO_AUDIO_HARDWARE
Cannot play back the audio stream: no audio hardware is available, or the hardware is not supported.

@Sebastiii - any thoughts?

Sebastiii · February 2, 2019

It's weird but maybe there is a bug somewhere, I wasn't able to reproduce this on my side @Glith can you post your settings from there : (and try like mine

)

Glith · February 2, 2019

I have changed to the settings you showed and tried again, with the same result. New logs are attached.

Here _was_ my original settings.

Sebastiii · February 2, 2019

Can you try to test with DirectSound device (instead of MPAR)?

Glith · February 2, 2019

I'm already using DirectSound device. These are my current unmodified settings:

Sebastiii · February 2, 2019

Also does this happen if you disable refresh rate changer from MP and/or madVR?

Sebastiii · February 2, 2019

Glith said:
I'm already using DirectSound device. These are my current unmodified settings:
View attachment 200045

Ok, so the audio renderer log comes because of this weird issue and surely loaded to DirectShow graph instead of DirectSound.

Can you try attached?
Are you able to always reproduce this?

Sound stops working after first playback (2 Viewers)

Glith

Portal Pro

Attachments

Owlsroost

Retired Team Member

Glith

Portal Pro

Owlsroost

Retired Team Member

Sebastiii

Development Group

Glith

Portal Pro

Attachments

Sebastiii

Development Group

Glith

Portal Pro

Sebastiii

Development Group

Sebastiii

Development Group

Users who are viewing this thread