[mythtv-users] Video file with poor playback on ION board

[Phil Wild]

>
> Go into  Setup->tv settings->playback->playback profiles and edit your
> profile. on the second page there is a 'custom filters' text entry box
> - add vdpaubuffersize=XX where XX is something greater than 17 (the
> default) and less than 50 (the max allowed). You'll probably want to
> test a little to get a value that works well without going too high
> (each extra buffer costs more video memory).
>
> It's a little quirk/bug with the mythtv VDPAU code that should
> hopefully be resolved for 0.25.
>

Thanks, I have now tried vdpaubuffersize=32, 40 and 50 and for each of
them, playback is still very jittery and audio continually cuts in and
out... See new log below with the setting on 50.

Now this is interesting... I just tried using the command that JYA
provided "mythavtest -v audio,playback test.m4v > logfile" and the
video actually plays fine... I will put the logfile in another email
so as not to mix things up. What gives with this - why are they
different?

2011-01-24 21:57:48.740 MythCoreContext: Connecting to backend server:
10.3.0.3:6543 (try 1 of 1)
2011-01-24 21:57:48.743 Using protocol version 63
2011-01-24 21:57:51.872 ScreenSaverX11Private: Calling
xscreensaver-command -deactivate
2011-01-24 21:58:05.157 TV: StartTV() -- begin
2011-01-24 21:58:05.157 TV: ctor -- begin
2011-01-24 21:58:05.165 TV: ctor -- end
2011-01-24 21:58:05.165 TV: Init -- begin
2011-01-24 21:58:05.170 TV: DrawUnusedRects() -- begin
2011-01-24 21:58:05.170 TV: DrawUnusedRects() -- end
2011-01-24 21:58:05.170 TV: Init -- end
2011-01-24 21:58:05.172 TV: tv->Playback() -- begin
2011-01-24 21:58:05.181 TV: tv->Playback() -- end
2011-01-24 21:58:05.181 TV: StartTV -- process events begin
2011-01-24 21:58:05.333 TV: HandleStateChange(0) -- begin
2011-01-24 21:58:05.333 TV: Attempting to change from None to WatchingVideo
2011-01-24 21:58:05.333
RingBuf(myth://Videos@10.3.0.3:6543/Downloading/test/test.m4v):
OpenFile(myth://Videos@10.3.0.3:6543/Downloading/test/test.m4v, 2000
ms)
2011-01-24 21:58:05.447 TV: DrawUnusedRects() -- begin
2011-01-24 21:58:05.447 TV: DrawUnusedRects() -- end
2011-01-24 21:58:05.448 TV: StartPlayer(0, WatchingVideo, main) -- begin
2011-01-24 21:58:05.448 TV: Elapsed time since TV constructor was called: 291 ms
2011-01-24 21:58:05.612 Pulse: PulseAudio not running
2011-01-24 21:58:05.623 ALSA: OpenDevice hdmi
2011-01-24 21:58:05.636 AO: Sample rate 22050 is supported
2011-01-24 21:58:05.636 AO: Sample rate 44100 is supported
2011-01-24 21:58:05.636 AO: Sample rate 48000 is supported
2011-01-24 21:58:05.637 AO: Sample rate 88200 is supported
2011-01-24 21:58:05.637 AO: Sample rate 96000 is supported
2011-01-24 21:58:05.637 AO: Sample rate 176400 is supported
2011-01-24 21:58:05.637 AO: Sample rate 192000 is supported
2011-01-24 21:58:05.637 AO: 2 channel(s) are supported
2011-01-24 21:58:05.637 AO: 3 channel(s) are supported
2011-01-24 21:58:05.637 AO: 4 channel(s) are supported
2011-01-24 21:58:05.637 AO: 5 channel(s) are supported
2011-01-24 21:58:05.638 AO: 6 channel(s) are supported
2011-01-24 21:58:05.638 AO: 7 channel(s) are supported
2011-01-24 21:58:05.638 AO: 8 channel(s) are supported
2011-01-24 21:58:05.676 AudioPlayer: Enabling Audio
2011-01-24 21:58:06.335 AFD: Stream #0, has id 0x1 codec id H264, type
Video, bitrate 2990033 at 0x8ed2cb0
2011-01-24 21:58:06.345 VDP: Accepting: cmp(> 0 720) dec(vdpau)
cpus(2) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpauadvanced) filt(
vdpaubuffersize=50)
2011-01-24 21:58:06.345 VDP: Accepting: cmp(== 0 720) dec(vdpau)
cpus(2) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(none,none) filt(vdpaubuffersize=50)
2011-01-24 21:58:06.345 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(2)
skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpauadvanceddoublerate,vdpauadvanced) filt
(vdpaubuffersize=50)
2011-01-24 21:58:06.346 VDP: LoadBestPreferences(2048x2048, 0)
2011-01-24 21:58:06.346 VDP: LoadBestPreferences(2048x2048, 60)
2011-01-24 21:58:06.356 VDP: LoadBestPreferences(1280x720, 60)
2011-01-24 21:58:06.361 VDP: Accepting: cmp(> 0 720) dec(vdpau)
cpus(2) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpauadvanced) filt(
vdpaubuffersize=50)

2011-01-24 21:58:06.362 VDP: Accepting: cmp(== 0 720) dec(vdpau)
cpus(2) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(none,none) filt(vdpaubuffersize=50)
2011-01-24 21:58:06.362 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(2)
skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaubuffersize=50)
2011-01-24 21:58:06.362 VDP: LoadBestPreferences(2048x2048, 0)
2011-01-24 21:58:06.362 VDP: LoadBestPreferences(2048x2048, 60)
2011-01-24 21:58:06.363 VDP: LoadBestPreferences(1280x720, 60)
2011-01-24 21:58:06.375 AFD: Using 1 CPUs for decoding
2011-01-24 21:58:06.375 AFD: InitVideoCodec() 0x8ed2e80 id(H264) type (Video).
2011-01-24 21:58:06.376 AFD: Selected FPS is 23.976 (avg 23.976 stream
23.976 container 24000 estimated 23.976)
2011-01-24 21:58:06.376 Player(0): detectInterlace(Detect Scan,
Interlaced Scan, 23.976, 720) ->Progressive Scan
2011-01-24 21:58:06.377 AFD Warning: ScanATSCCaptionStreams() called with no PMT
2011-01-24 21:58:06.377 AFD: Using vdpau for video decoding
2011-01-24 21:58:06.377 AFD: Looking for decoder for H264
2011-01-24 21:58:06.378 AFD: Opened codec 0x8ed2e80, id(H264) type(Video)
2011-01-24 21:58:06.378 AFD: Stream #1, has id 0x2 codec id AAC, type
Audio, bitrate 159981 at 0x8ed3390
2011-01-24 21:58:06.378 AFD: codec AAC has 2 channels
2011-01-24 21:58:06.378 AFD: Looking for decoder for AAC
2011-01-24 21:58:06.396 AFD: Opened codec 0x8ed3560, id(AAC) type(Audio)
2011-01-24 21:58:06.396 AFD: Audio Track #1 is A/V stream #1 and has 2
channels in the English language(6647399).
2011-01-24 21:58:06.396 AFD: Stream #2, has id 0x3 codec id AC3, type
Audio, bitrate 448000 at 0x8ed77d0
2011-01-24 21:58:06.397 AFD: codec AC3 has 2 channels
2011-01-24 21:58:06.397 AFD: Looking for decoder for AC3
2011-01-24 21:58:06.398 AFD: Opened codec 0x8ed79a0, id(AC3) type(Audio)
2011-01-24 21:58:06.398 AFD: Audio Track #2 is A/V stream #2 and has 2
channels in the English language(6647399).
2011-01-24 21:58:06.399 AFD: Trying to select audio track (w/lang)
2011-01-24 21:58:06.400 AFD: Selected track 2: English AC3 2ch (A/V Stream #2)
2011-01-24 21:58:06.401 AFD: Initializing audio parms from audio track #2
2011-01-24 21:58:06.401 AFD: Audio format changed
                        from id(NONE)     -1Hz -1ch -1bps     (br
1536000bps) to id( AC3)  48000Hz  2ch 16bps     (br 1536000bps)
2011-01-24 21:58:06.401 AO: Killing AudioOutputDSP
2011-01-24 21:58:06.401 AO: Original codec was AC3, signed 16 bit, 48
kHz, 2 channels
2011-01-24 21:58:06.401 AO: enc(0), passthru(0), features
(AC3,DTS,LPCM) configured_channels(2), 2 channels supported(1)
2011-01-24 21:58:06.402 AO: Opening audio device 'hdmi' ch 2(2) sr
48000 sf signed 16 bit reenc 1
2011-01-24 21:58:06.402 ALSA: OpenDevice hdmi
2011-01-24 21:58:06.403 ALSA: SetParameters(format=2, channels=2,
rate=48000, buffer_time=200000, period_time=50000)
2011-01-24 21:58:06.404 ALSA: Buffer time = 200000 us
2011-01-24 21:58:06.404 ALSA: Period time = 50000 us
2011-01-24 21:58:06.404 ALSA: Buffer size = 9600 | Period size = 2400
2011-01-24 21:58:06.405 AO: Audio fragment size: 4800
2011-01-24 21:58:06.405 AO: Audio Stretch Factor: 1
2011-01-24 21:58:06.405 AO: Ending Reconfigure()
2011-01-24 21:58:06.405 AO: kickoffOutputAudioLoop: pid = 5062
2011-01-24 21:58:06.405 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:06.406 Dec: Selected track #1 in the Unknown language(0)
2011-01-24 21:58:06.406 Dec: Resyncing position map. posmapStarted = 0
livetv(0) watchingRec(0)
2011-01-24 21:58:06.412 Dec: SyncPositionMap prerecorded, from DB: 0 entries
2011-01-24 21:58:06.412 AFD: Recording has no position -- using
libavformat seeking.
2011-01-24 21:58:06.412 AFD: Successfully opened decoder for file:
"myth://Videos@10.3.0.3:6543/Downloading/test/test.m4v". novideo(0)
2011-01-24 21:58:06.419 VideoOutput: Allowed renderers: vdpau

2011-01-24 21:58:06.419 VideoOutput: Allowed renderers (filt: vdpau): vdpau
2011-01-24 21:58:06.424 VDP: Accepting: cmp(> 0 720) dec(vdpau)
cpus(2) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpauadvanced) filt(vdpaubuffersize=50)
2011-01-24 21:58:06.424 VDP: Accepting: cmp(== 0 720) dec(vdpau)
cpus(2) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(none,none) filt(vdpaubuffersize=50)
2011-01-24 21:58:06.425 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(2)
skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaubuffersize=50)
2011-01-24 21:58:06.425 VDP: LoadBestPreferences(2048x2048, 0)
2011-01-24 21:58:06.425 VDP: LoadBestPreferences(2048x2048, 60)
2011-01-24 21:58:06.425 VDP: LoadBestPreferences(1280x720, 60)
2011-01-24 21:58:06.425 VideoOutput: Preferred renderer: vdpau
2011-01-24 21:58:06.426 VideoOutput: Trying video renderer: 'vdpau'
2011-01-24 21:58:06.454 VDP: Accepting: cmp(> 0 720) dec(vdpau)
cpus(2) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpauadvanced) filt(vdpaubuffersize=50)
2011-01-24 21:58:06.454 VDP: Accepting: cmp(== 0 720) dec(vdpau)
cpus(2) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(none,none) filt(vdpaubuffersize=50)
2011-01-24 21:58:06.455 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(2)
skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaubuffersize=50)
2011-01-24 21:58:06.455 VDP: LoadBestPreferences(2048x2048, 0)
2011-01-24 21:58:06.455 VDP: LoadBestPreferences(2048x2048, 60)
2011-01-24 21:58:06.455 VideoOutWindow::SetPIPState. pip_state: 0]
2011-01-24 21:58:06.455 VDP: LoadBestPreferences(2048x2048, 23.976)
2011-01-24 21:58:06.455 Clearing OpenGL painter cache.
2011-01-24 21:58:06.472 Display Rect  left: 0, top: 135, width: 1920,
height: 810, aspect: 1.33333
2011-01-24 21:58:06.472 Video Rect    left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2011-01-24 21:58:06.473 VDP: LoadBestPreferences(1280x720, 23.976)
2011-01-24 21:58:06.473 Display Rect  left: 0, top: 135, width: 1920,
height: 810, aspect: 1.33333
2011-01-24 21:58:06.473 Video Rect    left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2011-01-24 21:58:06.473 VDP: SetVideoRenderer(vdpau)
2011-01-24 21:58:06.473 VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2011-01-24 21:58:06.476 Trying to match best refresh rate 23.976Hz
2011-01-24 21:58:06.476 Trying 1920x1080 23.971 Hz
2011-01-24 21:58:06.479 Dynamic TwinView rate found, set 23.9709Hz as XRandR 54
2011-01-24 21:58:06.744 SwitchToVideo: Video size 1280 x 720:
    Switched to displaying resolution 1920 x 1080, 243mm x 137mm
2011-01-24 21:58:06.745 Display Rect  left: 0, top: 0, width: 1920,
height: 1080, aspect: 1.77778
2011-01-24 21:58:06.745 Video Rect    left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2011-01-24 21:58:06.745 VideoOutput: Pixel dimensions: Screen
1920x1080, window 1920x1080
2011-01-24 21:58:06.745 VideoOutput: Actual display dimensions:
243x137 mm  Aspect: 1.77372
2011-01-24 21:58:06.745 VideoOutput: Estimated window dimensions:
243x137 mm  Aspect: 1.77372
2011-01-24 21:58:06.745 VidOutVDPAU: VDPAU video buffer size: 50 (default 17)
2011-01-24 21:58:06.809 VDPAU: Created 2 output surfaces.
2011-01-24 21:58:06.809 VDPAU: Set colorkey to 0x20202
2011-01-24 21:58:06.809 VDPAU: Version 1
2011-01-24 21:58:06.809 VDPAU: Information NVIDIA VDPAU Driver Shared
Library  256.53  Fri Aug 27 21:32:59 PDT 2010
2011-01-24 21:58:06.809 VDPAU: HQ Scaling not supported.
2011-01-24 21:58:06.810 VDPAU: MPEG4 hardware acceleration not supported.
2011-01-24 21:58:06.810 VDPAU: Created VDPAU render device 1920x1080
2011-01-24 21:58:06.810 VidOutVDPAU: Created VDPAU osd (1920x1080)
2011-01-24 21:58:07.616 Display Rect  left: 0, top: 0, width: 1920,
height: 1080, aspect: 1.77778
2011-01-24 21:58:07.617 Video Rect    left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2011-01-24 21:58:07.617 VidOutVDPAU: Created VDPAU context (GPU decode)

2011-01-24 21:58:07.621 Over/underscan. V: 0, H: 0
2011-01-24 21:58:07.622 Display Rect  left: 0, top: 0, width: 1920,
height: 1080, aspect: 1.77778
2011-01-24 21:58:07.622 Video Rect    left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2011-01-24 21:58:07.622 Player(0): Forcing decode extra audio option
on (Video method requires it).
2011-01-24 21:58:07.622 FilterManager: GetFilterInfo(convert) returning: 0x0
2011-01-24 21:58:07.622 Player(0): LoadFilters('vdpaubuffersize=50'..) -> 0x0
2011-01-24 21:58:07.626 Player(0): Decoder thread starting.
2011-01-24 21:58:07.638 OSD: Loaded window osd_message
2011-01-24 21:58:07.650 OSD: Loaded window osd_input
2011-01-24 21:58:07.664 OSD: Loaded window program_info
2011-01-24 21:58:07.678 OSD: Loaded window browse_info
2011-01-24 21:58:07.693 OSD: Loaded window osd_status
2011-01-24 21:58:07.720 OSD: Loaded window osd_program_editor
2011-01-24 21:58:07.720 OSD: Loaded OSD: size 1920x1080 offset 0+0
2011-01-24 21:58:07.725 Player(0): ClearAfterSeek(0)
2011-01-24 21:58:07.729 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:07.733 VDP: GetFilteredDeint() : vdpau ->
'vdpaubasicdoublerate'
2011-01-24 21:58:07.800 VidOutVDPAU: Created VDPAU decoder (5 ref frames)
2011-01-24 21:58:07.830 Detect Letterbox: The source is not a
supported frame format (was 11)
2011-01-24 21:58:07.831 VidOutVDPAU: Enabled deinterlacing.
2011-01-24 21:58:07.831 VSYNC: DRMVideoSync: Could not open device
/dev/dri/card0, No such file or directory
2011-01-24 21:58:07.831 VSYNC: RTCVideoSync: Could not open /dev/rtc,
Permission denied.
2011-01-24 21:58:07.831 Player(0): Video sync method can't support
double framerate (refresh rate too low for 2x deint)
2011-01-24 21:58:07.863 VDP: GetFilteredDeint(vdpauadvanced) : vdpau
-> 'vdpauadvanced'
2011-01-24 21:58:07.898 VidOutVDPAU: Enabled deinterlacing.
2011-01-24 21:58:07.901 Player(0): Video timing method: USleep with busy wait
2011-01-24 21:58:07.901 Player(0): Display Refresh Rate: 23.971 Video
Frame Rate: 23.976
2011-01-24 21:58:07.901 playCtx: StartPlaying(): took 1 ms to start player.
2011-01-24 21:58:07.902 TV: StartPlayer(0, WatchingVideo, main) -- end ok
2011-01-24 21:58:07.902 TV: Changing from None to WatchingVideo
2011-01-24 21:58:07.942 AFD: Audio stream changed
2011-01-24 21:58:07.942 AFD: Trying to reselect audio track
2011-01-24 21:58:07.942 AFD: Selected track 2: English AC3 2ch (A/V Stream #2)
2011-01-24 21:58:07.942 AFD: Initializing audio parms from audio track #2
2011-01-24 21:58:07.943 AFD: Audio format changed
                        from id( AC3)  48000Hz  2ch 16bps     (br
1536000bps) to id( AC3)  48000Hz  6ch 16bps  pt (br 1536000bps)
2011-01-24 21:58:07.943 AO: Setting AC3 Passthrough
2011-01-24 21:58:07.943 AO: Killing AudioOutputDSP
2011-01-24 21:58:07.945 AO: OutputAudioLoop: Stop Event
2011-01-24 21:58:07.945 AO: kickoffOutputAudioLoop exiting
2011-01-24 21:58:07.946 AO: Original codec was AC3, signed 16 bit, 48
kHz, 6 channels
2011-01-24 21:58:07.946 AO: enc(0), passthru(1), features
(AC3,DTS,LPCM) configured_channels(2), 6 channels supported(1)
2011-01-24 21:58:07.946 AO: Opening audio device 'hdmi' ch 2(6) sr
48000 sf signed 16 bit reenc 0
2011-01-24 21:58:07.946 ALSA: OpenDevice
hdmi:AES0=6,AES1=0x82,AES2=0x00,AES3=0x01 for passthrough
2011-01-24 21:58:07.957 ALSA: SetParameters(format=2, channels=2,
rate=48000, buffer_time=200000, period_time=50000)
2011-01-24 21:58:07.961 ALSA: Buffer time = 200000 us
2011-01-24 21:58:07.961 ALSA: Period time = 50000 us
2011-01-24 21:58:07.964 ALSA: Buffer size = 9600 | Period size = 2400

2011-01-24 21:58:07.965 AO: Audio fragment size: 4800
2011-01-24 21:58:07.965 AO: Audio Stretch Factor: 1
2011-01-24 21:58:07.967 AO: Ending Reconfigure()
2011-01-24 21:58:07.967 AO: kickoffOutputAudioLoop: pid = 5062
2011-01-24 21:58:07.968 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:07.983 TV: HandleStateChange(0) -- end
2011-01-24 21:58:07.986 ScreenSaverX11Private: ResetTimer -- begin
2011-01-24 21:58:07.986 ScreenSaverX11Private: StopTimer
2011-01-24 21:58:07.989 ScreenSaverX11Private: StartTimer
2011-01-24 21:58:07.989 ScreenSaverX11Private: ResetTimer -- end
2011-01-24 21:58:07.990 Player(0): progressive frame seen after 2
interlaced  frames
2011-01-24 21:58:07.993 VDPAU: Added 2 output surfaces (total 4, max 4)
2011-01-24 21:58:07.998 Player(0): Waiting for video buffers...
2011-01-24 21:58:07.998 AO: Pause 1
2011-01-24 21:58:08.008 AO: OutputAudioLoop: audio paused
2011-01-24 21:58:08.016 SPDIFEncoder: Creating spdif encoder
(MPEG1VIDEO, 48000Hz)
2011-01-24 21:58:08.101 Player(0): Waited 100ms for video buffers
AAAAAAAA(ad)(AL)AAAAAAAAAAAAAAAAAAAAAAAAAAAAAA(ad)(AL)AAAAAAAA
2011-01-24 21:58:08.107 Player(0): Waited 100ms for video buffers
AAAAAAAA(ad)(AL)AAAAAAAAAAAAAAAAAAAAAAAAAAAAAA(ad)(AL)AAAAAAAA
2011-01-24 21:58:08.113 AO: Pause 0
2011-01-24 21:58:08.113 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:08.155 Player(0): Waiting for video buffers...
2011-01-24 21:58:08.155 AO: Pause 1
2011-01-24 21:58:08.161 AO: Pause 0
2011-01-24 21:58:08.194 Player(0): Disabled deinterlacing
2011-01-24 21:58:08.194 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:08.203 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.203 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.203 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.244 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.245 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.328 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.328 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.369 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.370 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.411 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.411 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.495 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.495 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.536 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.537 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.537 Player(0): Waiting for video buffers...
2011-01-24 21:58:08.537 AO: Pause 1
2011-01-24 21:58:08.538 AO: OutputAudioLoop: audio paused
2011-01-24 21:58:08.553 AO: Pause 0
2011-01-24 21:58:08.609 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:08.637 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.637 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.679 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.679 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.762 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.

2011-01-24 21:58:08.762 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.804 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.804 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.887 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.888 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.929 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.929 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:08.930 Player(0): Waiting for video buffers...
2011-01-24 21:58:08.930 AO: Pause 1
2011-01-24 21:58:08.933 AO: OutputAudioLoop: audio paused
2011-01-24 21:58:08.935 AO: Pause 0
2011-01-24 21:58:09.009 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:09.019 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:09.019 VidOutVDPAU: Frame a is in use by avlib and so
is being held for later discarding.
2011-01-24 21:58:09.516 ALSA: WriteAudio: buffer underrun
2011-01-24 21:58:09.603 Player(0): Waiting for video buffers...
2011-01-24 21:58:09.604 AO: Pause 1
2011-01-24 21:58:09.607 AO: OutputAudioLoop: audio paused
2011-01-24 21:58:09.609 AO: Pause 0
2011-01-24 21:58:09.651 Player(0): Waiting for video buffers...
2011-01-24 21:58:09.651 AO: Pause 1
2011-01-24 21:58:09.657 AO: Pause 0
2011-01-24 21:58:09.658 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:09.699 Player(0): Waiting for video buffers...
2011-01-24 21:58:09.699 AO: Pause 1
2011-01-24 21:58:09.705 AO: Pause 0
2011-01-24 21:58:09.758 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:09.789 Player(0): Waiting for video buffers...
2011-01-24 21:58:09.789 AO: Pause 1
2011-01-24 21:58:09.798 AO: OutputAudioLoop: audio paused
2011-01-24 21:58:09.800 AO: Pause 0
2011-01-24 21:58:09.842 Player(0): Waiting for video buffers...
2011-01-24 21:58:09.842 AO: Pause 1
2011-01-24 21:58:09.848 AO: Pause 0
2011-01-24 21:58:09.858 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:09.973 Player(0): Waiting for video buffers...
2011-01-24 21:58:09.974 AO: Pause 1
2011-01-24 21:58:09.978 AO: OutputAudioLoop: audio paused
2011-01-24 21:58:09.995 AO: Pause 0
2011-01-24 21:58:10.058 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:10.371 Player(0): Waiting for video buffers...
2011-01-24 21:58:10.573 ALSA: WriteAudio: buffer underrun
2011-01-24 21:58:11.124 AO: Pause 1
2011-01-24 21:58:11.124 AO: OutputAudioLoop: audio paused
2011-01-24 21:58:11.130 AO: Pause 0
2011-01-24 21:58:11.175 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:12.869 ALSA: WriteAudio: buffer underrun
2011-01-24 21:58:13.007 Player(0): Waiting for video buffers...
2011-01-24 21:58:13.007 AO: Pause 1
2011-01-24 21:58:13.010 AO: OutputAudioLoop: audio paused

2011-01-24 21:58:13.056 AO: Pause 0
2011-01-24 21:58:13.061 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:13.098 Player(0): Waiting for video buffers...
2011-01-24 21:58:13.098 AO: Pause 1
2011-01-24 21:58:13.104 AO: Pause 0
2011-01-24 21:58:13.146 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:13.146 Player(0): Waiting for video buffers...
2011-01-24 21:58:13.146 AO: Pause 1
2011-01-24 21:58:13.152 AO: Pause 0
2011-01-24 21:58:13.156 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:13.278 Player(0): Waiting for video buffers...
2011-01-24 21:58:13.278 AO: Pause 1
2011-01-24 21:58:13.287 AO: OutputAudioLoop: audio paused
2011-01-24 21:58:13.294 AO: Pause 0
2011-01-24 21:58:13.361 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:13.671 Player(0): Waiting for video buffers...
2011-01-24 21:58:13.672 AO: Pause 1
2011-01-24 21:58:13.682 AO: OutputAudioLoop: audio paused
2011-01-24 21:58:13.683 AO: Pause 0
2011-01-24 21:58:13.761 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:14.305 ALSA: WriteAudio: buffer underrun
'video_output' mean = '63116.33', std. dev. = '81923.65', fps = '15.84'
2011-01-24 21:58:14.392 Player(0): Waiting for video buffers...
2011-01-24 21:58:14.392 AO: Pause 1
2011-01-24 21:58:14.396 AO: OutputAudioLoop: audio paused
2011-01-24 21:58:14.398 AO: Pause 0
2011-01-24 21:58:14.440 Player(0): Waiting for video buffers...
2011-01-24 21:58:14.440 AO: Pause 1
2011-01-24 21:58:14.447 AO: OutputAudioLoop: audio paused
2011-01-24 21:58:14.494 AO: Pause 0
2011-01-24 21:58:14.547 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:15.164 ALSA: WriteAudio: buffer underrun
2011-01-24 21:58:15.329 Player(0): Waiting for video buffers...
2011-01-24 21:58:15.329 AO: Pause 1
2011-01-24 21:58:15.336 AO: OutputAudioLoop: audio paused
2011-01-24 21:58:15.351 AO: Pause 0
2011-01-24 21:58:15.386 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:15.393 Player(0): Waiting for video buffers...
2011-01-24 21:58:15.393 AO: Pause 1
2011-01-24 21:58:15.399 AO: Pause 0
2011-01-24 21:58:15.441 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:15.441 Player(0): Waiting for video buffers...
2011-01-24 21:58:15.441 AO: Pause 1
2011-01-24 21:58:15.447 AO: Pause 0
2011-01-24 21:58:15.451 AO: OutputAudioLoop: Play Event
2011-01-24 21:58:15.531 Player(0): Waiting for video buffers...
2011-01-24 21:58:15.531 AO: Pause 1
2011-01-24 21:58:15.532 AO: OutputAudioLoop: audio paused
2011-01-24 21:58:15.542 AO: Pause 0
2011-01-24 21:58:15.584 AO: OutputAudioLoop: Play Event