[mythtv-users] Failed to get A/V sync when using VDPAU

[Richard]

Hi,

Is anyone else having getting the error message 'Failed to get A/V sync' 
when trying to play back a recording?  I don't get it all the time but 
it's often enough that it's irritating.  It doesn't seem to matter 
whether I select HD or SD material.

I'm running Mythbuntu 12.04 as a combined BE/FE with Myth 
v0.25.2-16-gd519276.  The graphics card is an NVidia GeForce 8400 GS 
using the 295.40 drivers.

Here's an excerpt from the frontend log:

Aug 30 21:19:57 dvr mythfrontend[2139]: I CoreContext tv_play.cpp:987 
(TV) TV: Creating TV object
Aug 30 21:19:57 dvr mythfrontend[2139]: N CoreContext 
mythmainwindow.cpp:2591 (PauseIdleTimer) Resuming idle timer
Aug 30 21:19:57 dvr mythfrontend[2139]: N CoreContext 
mythmainwindow.cpp:2586 (PauseIdleTimer) Suspending idle timer
Aug 30 21:19:57 dvr mythfrontend[2139]: I CoreContext tv_play.cpp:1206 
(Init) TV: Created TvPlayWindow.
Aug 30 21:19:57 dvr mythfrontend[2139]: I CoreContext tv_play.cpp:2121 
(HandleStateChange) TV: Attempting to change from None to 
WatchingPreRecorded
Aug 30 21:19:57 dvr mythfrontend[2139]: N CoreContext 
audioplayer.cpp:167 (ReinitAudio) AudioPlayer: Enabling Audio
Aug 30 21:19:58 dvr mythfrontend[2139]: I CoreContext 
avformatdecoder.cpp:2102 (ScanStreams) AFD: Opened codec 0x19d7b70, 
id(H264) type(Video)
Aug 30 21:19:58 dvr mythfrontend[2139]: I CoreContext 
avformatdecoder.cpp:1960 (ScanStreams) AFD: codec MP2 has 2 channels
Aug 30 21:19:58 dvr mythfrontend[2139]: I CoreContext 
avformatdecoder.cpp:2102 (ScanStreams) AFD: Opened codec 0x34bf430, 
id(MP2) type(Audio)
Aug 30 21:19:58 dvr mythfrontend[2139]: I CoreContext 
avformatdecoder.cpp:2102 (ScanStreams) AFD: Opened codec 0x7fb7840, 
id(DVB_SUBTITLE) type(Subtitle)
Aug 30 21:19:58 dvr mythfrontend[2139]: I CoreContext 
avformatdecoder.cpp:1960 (ScanStreams) AFD: codec AC3 has 6 channels
Aug 30 21:19:58 dvr mythfrontend[2139]: I CoreContext 
avformatdecoder.cpp:2102 (ScanStreams) AFD: Opened codec 0x50ca800, 
id(AC3) type(Audio)
Aug 30 21:19:58 dvr mythfrontend[2139]: I CoreContext 
audio/audiooutputbase.cpp:791 (Reconfigure) AO: Opening audio device 
'iec958:CARD=Intel,DEV=0' ch 2(6) sr 48000 sf signed 16 bit reenc 1
Aug 30 21:19:59 dvr mythfrontend[2139]: I CoreContext 
mythpainter_ogl.cpp:62 (ClearCache) Clearing OpenGL painter cache.
Aug 30 21:19:59 dvr mythfrontend[2139]: I CoreContext 
mythrender_vdpau.cpp:1675 (CreatePresentationSurfaces) VDPAU: Created 2 
output surfaces.
Aug 30 21:19:59 dvr mythfrontend[2139]: I CoreContext 
mythrender_vdpau.cpp:401 (Create) VDPAU: Created VDPAU render device 
1920x1080
Aug 30 21:19:59 dvr mythfrontend[2139]: N CoreContext mythplayer.cpp:506 
(CheckExtraAudioDecode) Player(4): Forcing decode extra audio option on 
(Video method requires it).
Aug 30 21:19:59 dvr mythfrontend[2139]: I CoreContext 
mythplayer.cpp:1737 (InitAVSync) Player(4): Video timing method: USleep 
with busy wait
Aug 30 21:19:59 dvr mythfrontend[2139]: I CoreContext tv_play.cpp:5169 
(StartPlayer) TV: Created player.
Aug 30 21:19:59 dvr mythfrontend[2139]: I CoreContext tv_play.cpp:2360 
(HandleStateChange) TV: Changing from None to WatchingPreRecorded
Aug 30 21:19:59 dvr mythfrontend[2139]: I CoreContext tv_play.cpp:2451 
(HandleStateChange) TV: Main UI disabled.
Aug 30 21:19:59 dvr mythfrontend[2139]: I CoreContext tv_play.cpp:378 
(StartTV) TV: Entering main playback loop.
Aug 30 21:19:59 dvr mythfrontend[2139]: I Decoder videoout_vdpau.cpp:627 
(DrawSlice) VidOutVDPAU: Added 2 new buffers. New buffer size 16 (4 
decode and 12 process)
Aug 30 21:19:59 dvr mythfrontend[2139]: E Decoder 
mythrender_vdpau.cpp:734 (CreateDecoder) VDPAU: Error at 
mythrender_vdpau.cpp:734 (#23, The system does not have enough resources 
to complete the requested operation at this time.)
Aug 30 21:19:59 dvr mythfrontend[2139]: E Decoder videoout_vdpau.cpp:670 
(DrawSlice) VidOutVDPAU: Failed to create decoder.
Aug 30 21:19:59 dvr mythfrontend[2139]: E Decoder videoout_vdpau.cpp:560 
(DrawSlice) VidOutVDPAU: IsErrored() in DrawSlice
Aug 30 21:19:59 dvr mythfrontend[2139]: E CoreContext 
videoout_vdpau.cpp:384 (ProcessFrame) VidOutVDPAU: IsErrored() in 
ProcessFrame
Aug 30 21:19:59 dvr mythfrontend[2139]: E CoreContext 
mythplayer.cpp:1782 (AVSync) Player(4): AVSync: Unknown error in 
videoOutput, aborting playback.
Aug 30 21:19:59 dvr mythfrontend[2139]: I CoreContext tv_play.cpp:2121 
(HandleStateChange) TV: Attempting to change from WatchingPreRecorded to 
None
Aug 30 21:19:59 dvr mythfrontend[2139]: W CoreContext 
mythplayer.cpp:2958 (PauseDecoder) Player(4): Waited 100ms for decoder 
to pause
Aug 30 21:20:09  mythfrontend[2139]: last message repeated 99 times
Aug 30 21:20:09 dvr mythfrontend[2139]: I CoreContext 
mythpainter_vdpau.cpp:111 (ClearCache) VDPAU Painter: Clearing VDPAU 
painter cache.
Aug 30 21:20:09 dvr mythfrontend[2139]: I CoreContext tv_play.cpp:2360 
(HandleStateChange) TV: Changing from WatchingPreRecorded to None


The line that strikes me is:

The system does not have enough resources to complete the requested 
operation at this time.)

from the driver.  I would say 75-80% of the time everything works fine, 
so the card is perfectly capable of handling playback.

It usually gets cleared up if I exit MythFrontend and start it again. 
Sometimes that's not enough and I have to kill MythWelcome as well.

Is it possible that some VDPAU resources are sometimes not being cleaned 
up properly?

In this particular case, we had been watching a recording and wanted to 
watch another.  The first recording had not been a problem.  After it 
finished, I moved around the menus a bit and then got this error when I 
tried to start the next recording.

Occasionally, the popup 'Failed to get A/V sync' appears and 
MythFrontend locks up completely.  I have to kill the process to 
continue.  The only thing that seems to be different in this case is 
that a tiny square appears in the top left corner of the popup, in case 
that gives anyone a clue as to what may be going wrong.

If other traces are required I'll do my best to get them.  I haven't 
worked out yet exactly what causes this but it shouldn't take too long 
before it re-occurs.

Any help/tips would be gratefully received!

Richard.