[mythtv-users] Audio dropouts after upgrade to 0.24

Enigma enigma at thedonnerparty.com
Tue Dec 7 06:09:20 UTC 2010


> Hi
>
> Post the log with -v audio,playback
>
> those logs do not show anything special, just that you either paused,
> or the player asked to pause the audio

Hmm, I tried to post the log for a minute of playback but it got
bounced because it was too big.  Here is the first half:

$ mythfrontend -v audio,playback
2010-12-06 21:29:14.357 mythfrontend version: trunk [es27305] www.mythtv.org
2010-12-06 21:29:14.358 Using runtime prefix = /usr
2010-12-06 21:29:14.358 Using configuration directory = /home/mythtv/.mythtv
2010-12-06 21:29:14.360 ThreadPool:HTTP: Initial 1, Max 25, Timeout 60000
2010-12-06 21:29:15.372 Empty LocalHostName.
2010-12-06 21:29:15.372 Using localhost value of hedonism-bot
2010-12-06 21:29:15.373 Testing network connectivity to 'burns'
2010-12-06 21:29:15.494 New DB connection, total: 1
2010-12-06 21:29:15.502 Connected to database 'mythconverg' at host: burns
2010-12-06 21:29:15.511 Closing DB connection named 'DBManager0'
2010-12-06 21:29:15.516 Connected to database 'mythconverg' at host: burns
2010-12-06 21:29:15.520 Current locale EN_US
2010-12-06 21:29:15.520 Reading locale defaults from
/usr/share/mythtv//locales/en_us.xml
2010-12-06 21:29:15.680 ScreenSaverX11Private: Gnome screen saver
support enabled
2010-12-06 21:29:15.682 DPMS is disabled.
2010-12-06 21:29:15.726 Desktop video mode: 1680x1050 59.954 Hz
2010-12-06 21:29:15.769 max_width: 1680 max_height: 1050
2010-12-06 21:29:15.772 user: 116 effective user: 116 before privileged thread
2010-12-06 21:29:15.772 user: 116 effective user: 116 after privileged thread
2010-12-06 21:29:15.773 Enabled verbose msgs:  important general audio playback
2010-12-06 21:29:15.775 user: 116 effective user: 116 run_priv_thread
2010-12-06 21:29:15.783 Loading en_us translation for module mythfrontend
2010-12-06 21:29:15.812 LIRC: Successfully initialized
'/var/run/lirc/lircd' using '/home/mythtv/.mythtv/lircrc' config
2010-12-06 21:29:15.812 JoystickMenuThread: Joystick disabled - Failed
to read /home/mythtv/.mythtv/joystickmenurc
2010-12-06 21:29:15.906 Using Frameless Window
2010-12-06 21:29:15.906 Using Full Screen Window
2010-12-06 21:29:16.322 Using the OpenGL painter
2010-12-06 21:29:16.381 OpenGL: OpenGL vendor  : NVIDIA Corporation
2010-12-06 21:29:16.382 OpenGL: OpenGL renderer: GeForce 8400 GS/PCI/SSE2
2010-12-06 21:29:16.382 OpenGL: OpenGL version : 3.2.0 NVIDIA 195.36.15
2010-12-06 21:29:16.382 OpenGL: Max texture size: 8192 x 8192
2010-12-06 21:29:16.382 OpenGL: Max texture units: 4
2010-12-06 21:29:16.382 OpenGL: Direct rendering: Yes
2010-12-06 21:29:16.382 OpenGL: Initialised MythRenderOpenGL
2010-12-06 21:29:17.033 Current MythTV Schema Version (DBSchemaVer): 1264
2010-12-06 21:29:17.035 VDP: decoder<->render support: ffmpeg
null directfb xlib xshm xv-blit opengl vdpau
2010-12-06 21:29:17.035 VDP: decoder<->render support: vdpau       vdpau
2010-12-06 21:29:17.035 VDP: decoder<->render support: libmpeg2
null directfb xlib xshm xv-blit opengl vdpau
2010-12-06 21:29:17.048 VDP: Ignoring profile item 332 (decoder xvmc
is not supported (supported: ffmpeg,vdpau,libmpeg2))
2010-12-06 21:29:17.048 VDP: Ignoring profile item 334 (decoder xvmc
is not supported (supported: ffmpeg,vdpau,libmpeg2))
2010-12-06 21:29:17.055 VDP: Ignoring profile item 330 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:17.059 VDP: Ignoring profile item 336 (decoder ivtv
is not supported (supported: ffmpeg,vdpau,libmpeg2))
2010-12-06 21:29:17.059 VDP: Ignoring profile item 337 (decoder xvmc
is not supported (supported: ffmpeg,vdpau,libmpeg2))
2010-12-06 21:29:17.059 VDP: Ignoring profile item 338 (decoder xvmc
is not supported (supported: ffmpeg,vdpau,libmpeg2))
2010-12-06 21:29:17.060 VDP: Ignoring profile item 339 (decoder xvmc
is not supported (supported: ffmpeg,vdpau,libmpeg2))
2010-12-06 21:29:17.067 VDP: Ignoring profile item 343 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:17.067 VDP: Ignoring profile item 344 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:17.069 New DB connection, total: 2
2010-12-06 21:29:17.074 Connected to database 'mythconverg' at host: burns
2010-12-06 21:29:17.077 VDP: Ignoring profile item 347 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:17.077 VDP: Ignoring profile item 348 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:17.084 VDP: Ignoring profile item 351 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:17.084 VDP: Ignoring profile item 352 (renderer
quartz-blit is not supported w/decoder ffmpeg (supported:
null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-12-06 21:29:19.819 ThemeInfo, Warning: Unable to open
themeinfo.xml for
/usr/share/mythtv/themes/BlackCurves-OSD/themeinfo.xml
2010-12-06 21:29:19.820 ThemeInfo, Error: The theme
(/usr/share/mythtv/themes/BlackCurves-OSD) is missing a themeinfo.xml
file.
2010-12-06 21:29:19.825 ThemeInfo, Warning: Unable to open
themeinfo.xml for /usr/share/mythtv/themes/Gray-OSD/themeinfo.xml
2010-12-06 21:29:19.825 ThemeInfo, Error: The theme
(/usr/share/mythtv/themes/Gray-OSD) is missing a themeinfo.xml file.
2010-12-06 21:29:19.984 Pulse: PulseAudio not running
2010-12-06 21:29:20.002 AO: Sample rate 8000 is supported
2010-12-06 21:29:20.002 AO: Sample rate 11025 is supported
2010-12-06 21:29:20.003 AO: Sample rate 16000 is supported
2010-12-06 21:29:20.003 AO: Sample rate 22050 is supported
2010-12-06 21:29:20.003 AO: Sample rate 32000 is supported
2010-12-06 21:29:20.003 AO: Sample rate 44100 is supported
2010-12-06 21:29:20.003 AO: Sample rate 48000 is supported
2010-12-06 21:29:20.003 AO: Sample rate 96000 is supported
2010-12-06 21:29:20.003 AO: 2 channel(s) are supported
2010-12-06 21:29:20.003 AO: 4 channel(s) are supported
2010-12-06 21:29:20.003 AO: 6 channel(s) are supported
2010-12-06 21:29:20.028 AO: Killing AudioOutputDSP
2010-12-06 21:29:20.029 Found ALSA:front:CARD=ICH5,DEV=0
(ALSA:front:CARD=ICH5,DEV=0
Device supports up to 5.1 (AC3,DTS,multi-channels LPCM))
2010-12-06 21:29:20.405 Registering Internal as a media playback plugin.
2010-12-06 21:29:20.448 Current MythMusic Schema Version
(MusicDBSchemaVer): 1017
2010-12-06 21:29:20.573 MediaMonitorUnix::AddDevice() - empty device path.
2010-12-06 21:29:20.574 MediaMonitorUnix::AddDevice() - empty device path.
2010-12-06 21:29:20.575 MediaMonitorUnix::AddDevice() - empty device path.
2010-12-06 21:29:20.577 MonitorRegisterExtensions(0x40,
mp3,mp2,ogg,oga,flac,wma,wav,ac3,oma,omg,atp,ra,dts,aac,m4a,aa3,tta,mka,aiff,swa,wv)
2010-12-06 21:29:20.593 Loading en_us translation for module mythmusic
2010-12-06 21:29:20.614 Current MythVideo Schema Version
(mythvideo.DBSchemaVer): 1038
2010-12-06 21:29:20.668 Loading en_us translation for module mythvideo
2010-12-06 21:29:20.671 MythPlugin::init() dlerror:
libmythdb-0.23.1.so.0: cannot open shared object file: No such file or
directory
2010-12-06 21:29:20.671 Unable to initialize plugin 'mythzoneminder'.
2010-12-06 21:29:21.413 Found mainmenu.xml for theme 'MythCenter-wide'
2010-12-06 21:29:21.868 MythCoreContext: Connecting to backend server:
192.168.2.6:6543 (try 1 of 1)
2010-12-06 21:29:21.869 Using protocol version 63
2010-12-06 21:29:54.435 PreviewQueue: Requesting preview for
'4041_20101206203000.mpg_0x0_-1s'
2010-12-06 21:29:54.436 PreviewQueue: Requested preview for
'4041_20101206203000.mpg_0x0_-1s'
2010-12-06 21:30:07.674 TV: StartTV() -- begin
2010-12-06 21:30:07.687 TV: ctor -- begin
2010-12-06 21:30:07.688 TV: ctor -- end
2010-12-06 21:30:07.689 TV: Init -- begin
2010-12-06 21:30:07.716 TV: DrawUnusedRects() -- begin
2010-12-06 21:30:07.716 TV: DrawUnusedRects() -- end
2010-12-06 21:30:07.718 TV: Init -- end
2010-12-06 21:30:07.719 TV: tv->Playback() -- begin
2010-12-06 21:30:07.733 TV: tv->Playback() -- end
2010-12-06 21:30:07.733 TV: StartTV -- process events begin
2010-12-06 21:30:07.837 TV: HandleStateChange(0) -- begin
2010-12-06 21:30:07.837 TV: Attempting to change from None to WatchingRecording
2010-12-06 21:30:07.842
RingBuf(/home/share/video/9070_20101206183000.mpg):
OpenFile(/home/share/video/9070_20101206183000.mpg, 2000 ms)
2010-12-06 21:30:07.911 TV: DrawUnusedRects() -- begin
2010-12-06 21:30:07.911 TV: DrawUnusedRects() -- end
2010-12-06 21:30:07.915 MythCoreContext: Connecting to backend server:
192.168.2.92:6543 (try 1 of 1)
2010-12-06 21:30:07.955 Using protocol version 63
2010-12-06 21:30:08.001 TV: StartPlayer(0, WatchingRecording, main) -- begin
2010-12-06 21:30:08.001 TV: Elapsed time since TV constructor was called: 314 ms
2010-12-06 21:30:08.171 Pulse: PulseAudio not running
2010-12-06 21:30:08.180 AO: Sample rate 8000 is supported
2010-12-06 21:30:08.180 AO: Sample rate 11025 is supported
2010-12-06 21:30:08.180 AO: Sample rate 16000 is supported
2010-12-06 21:30:08.180 AO: Sample rate 22050 is supported
2010-12-06 21:30:08.181 AO: Sample rate 32000 is supported
2010-12-06 21:30:08.181 AO: Sample rate 44100 is supported
2010-12-06 21:30:08.181 AO: Sample rate 48000 is supported
2010-12-06 21:30:08.181 AO: Sample rate 96000 is supported
2010-12-06 21:30:08.181 AO: 2 channel(s) are supported
2010-12-06 21:30:08.181 AO: 4 channel(s) are supported
2010-12-06 21:30:08.181 AO: 6 channel(s) are supported
2010-12-06 21:30:08.207 AO: Killing AudioOutputDSP
2010-12-06 21:30:09.197 AFD: Stream #0, has id 0x1011 codec id H264,
type Video, bitrate 0 at 0xb21a4f0
2010-12-06 21:30:09.230 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0)
skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpaubasic) filt()
2010-12-06 21:30:09.230 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-06 21:30:09.231 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-06 21:30:09.231 VDP: LoadBestPreferences(1280x720, 60)
2010-12-06 21:30:09.235 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0)
skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpaubasic) filt()
2010-12-06 21:30:09.235 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-06 21:30:09.236 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-06 21:30:09.236 VDP: LoadBestPreferences(1280x720, 60)
2010-12-06 21:30:09.236 AFD: Using 1 CPUs for decoding
2010-12-06 21:30:09.236 AFD: InitVideoCodec() 0xb2bab50 id(H264) type (Video).
2010-12-06 21:30:09.237 AFD: Selected FPS is 59.9401 (avg 59.96 stream
59.9401 container 90000 estimated 59.9401)
2010-12-06 21:30:09.237 Player(0): detectInterlace(Detect Scan,
Interlaced Scan, 59.9401, 720) ->Progressive Scan
2010-12-06 21:30:09.237 AFD: Using vdpau for video decoding
2010-12-06 21:30:09.238 AFD: Looking for decoder for H264
2010-12-06 21:30:09.238 AFD: Opened codec 0xb2bab50, id(H264) type(Video)
2010-12-06 21:30:09.238 AFD: Stream #1, has id 0x1100 codec id AAC,
type Audio, bitrate 121500 at 0xb2baf30
2010-12-06 21:30:09.238 AFD: codec AAC has 2 channels
2010-12-06 21:30:09.238 AFD: Looking for decoder for AAC
2010-12-06 21:30:09.241 AFD: Opened codec 0xb2bb100, id(AAC) type(Audio)
2010-12-06 21:30:09.241 AFD: Audio Track #1 is A/V stream #1 and has 2
channels in the Undetermined language(7695972).
2010-12-06 21:30:09.241 AFD: Selected track 1: Undetermined AAC 2ch
(A/V Stream #1)
2010-12-06 21:30:09.242 AFD: Initializing audio parms from audio track #1
2010-12-06 21:30:09.242 AFD: Audio format changed
            from id(NONE)     -1Hz -1ch -1bps     to id( AAC)  48000Hz
 2ch 16bps
2010-12-06 21:30:09.242 AO: Killing AudioOutputDSP
2010-12-06 21:30:09.373 Pulse: PulseAudio not running
2010-12-06 21:30:09.382 AO: Sample rate 8000 is supported
2010-12-06 21:30:09.383 AO: Sample rate 11025 is supported
2010-12-06 21:30:09.383 AO: Sample rate 16000 is supported
2010-12-06 21:30:09.383 AO: Sample rate 22050 is supported
2010-12-06 21:30:09.383 AO: Sample rate 32000 is supported
2010-12-06 21:30:09.383 AO: Sample rate 44100 is supported
2010-12-06 21:30:09.383 AO: Sample rate 48000 is supported
2010-12-06 21:30:09.384 AO: Sample rate 96000 is supported
2010-12-06 21:30:09.385 AO: 2 channel(s) are supported
2010-12-06 21:30:09.385 AO: 4 channel(s) are supported
2010-12-06 21:30:09.385 AO: 6 channel(s) are supported
2010-12-06 21:30:09.415 AO: Killing AudioOutputDSP
2010-12-06 21:30:09.416 AO: Original codec was AAC, signed 16 bit, 48
kHz, 2 channels
2010-12-06 21:30:09.416 AO: enc(0), passthru(0), canAC3(0), canDTS(0),
canLPCM(0), configured_channels(2), 2 channels supported(1)
2010-12-06 21:30:09.416 AO: Opening audio device
'front:CARD=ICH5,DEV=0' ch 2(2) sr 48000 sf signed 16 bit reenc 0
2010-12-06 21:30:09.417 ALSA: SetParameters(format=2, channels=2,
rate=48000, buffer_time=200000, period_time=50000)
2010-12-06 21:30:09.418 ALSA: Buffer time = 200000 us
2010-12-06 21:30:09.418 ALSA: Period time = 50000 us
2010-12-06 21:30:09.418 ALSA: Buffer size = 9600 | Period size = 2400
2010-12-06 21:30:09.430 ALSA: found playback control Master on mixer
device default
2010-12-06 21:30:09.430 ALSA: mixer volume range on mixer device
default/Master - min 0, max 31, range 31
2010-12-06 21:30:09.430 ALSA: mixer device default/Master set up successfully
2010-12-06 21:30:09.432 ALSA: channel 0 volume set 85 => 26
2010-12-06 21:30:09.432 ALSA: channel 1 volume set 85 => 26
2010-12-06 21:30:09.432 ALSA: get volume channel 0: 84
2010-12-06 21:30:09.432 ALSA: channel 0 volume set 84 => 26
2010-12-06 21:30:09.432 ALSA: channel 1 volume set 84 => 26
2010-12-06 21:30:09.432 AO: Audio fragment size: 4800
2010-12-06 21:30:09.433 AO: Audio Stretch Factor: 1
2010-12-06 21:30:09.433 AO: Ending Reconfigure()
2010-12-06 21:30:09.433 AudioPlayer: Enabling Audio
2010-12-06 21:30:09.433 AO: Reconfigure(): No change -> exiting
2010-12-06 21:30:09.433 Dec: Selected track #1 in the Unknown language(0)
2010-12-06 21:30:09.433 Dec: Resyncing position map. posmapStarted = 0
livetv(0) watchingRec(1)
2010-12-06 21:30:09.434 AO: kickoffOutputAudioLoop: pid = 1271
2010-12-06 21:30:09.435 AO: OutputAudioLoop: Play Event
2010-12-06 21:30:10.680 Dec: Position map filled from DB to: 647165
2010-12-06 21:30:10.681 Dec: SyncPositionMap watchingrecording, from
DB: 5057 entries
2010-12-06 21:30:10.682 Player(0): Filling position map from 647166 to end
2010-12-06 21:30:10.699 Dec: Position map filled from Encoder to: 647421
2010-12-06 21:30:10.699 Dec: SyncPositionMap watchingrecording total:
5059 entries
2010-12-06 21:30:10.699 Dec: SyncPositionMap, new totframes: 647421,
new length: 10801, posMap size: 5059
2010-12-06 21:30:10.700 AFD: Partial position map found
2010-12-06 21:30:10.700 AFD: Successfully opened decoder for file:
"/home/share/video/9070_20101206183000.mpg". novideo(0)
2010-12-06 21:30:10.746 VideoOutput: Allowed renderers: vdpau
2010-12-06 21:30:10.747 VideoOutput: Allowed renderers (filt: vdpau): vdpau
2010-12-06 21:30:10.751 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0)
skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpaubasic) filt()
2010-12-06 21:30:10.751 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-06 21:30:10.751 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-06 21:30:10.752 VDP: LoadBestPreferences(1280x720, 60)
2010-12-06 21:30:10.752 VideoOutput: Preferred renderer: vdpau
2010-12-06 21:30:10.752 VideoOutput: Trying video renderer: 'vdpau'
2010-12-06 21:30:10.805 VDP: Accepting: cmp(> 0 0) dec(vdpau) cpus(0)
skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
deint(vdpaubasicdoublerate,vdpaubasic) filt()
2010-12-06 21:30:10.805 VDP: LoadBestPreferences(2048x2048, 0)
2010-12-06 21:30:10.806 VDP: LoadBestPreferences(2048x2048, 60)
2010-12-06 21:30:10.806 VideoOutWindow::SetPIPState. pip_state: 0]
2010-12-06 21:30:10.806 Clearing OpenGL painter cache.
2010-12-06 21:30:10.816 Display Rect  left: 0, top: 131, width: 1680,
height: 787, aspect: 1.33333
2010-12-06 21:30:10.816 Video Rect    left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2010-12-06 21:30:10.816 VDP: LoadBestPreferences(1280x720, 60)
2010-12-06 21:30:10.816 Display Rect  left: 0, top: 131, width: 1680,
height: 787, aspect: 1.33333
2010-12-06 21:30:10.816 Video Rect    left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2010-12-06 21:30:10.817 VDP: SetVideoRenderer(vdpau)
2010-12-06 21:30:10.817 VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2010-12-06 21:30:10.819 VideoOutput: Pixel dimensions: Screen
1680x1050, window 1680x1050
2010-12-06 21:30:10.819 VideoOutput: Actual display dimensions:
431x272 mm  Aspect: 1.58456
2010-12-06 21:30:10.819 VideoOutput: Estimated window dimensions:
431x272 mm  Aspect: 1.58456
2010-12-06 21:30:11.462 VDPAU: Created 2 output surfaces.
2010-12-06 21:30:11.462 VDPAU: Set colorkey to 0x20202
2010-12-06 21:30:11.462 VDPAU: Version 1
2010-12-06 21:30:11.462 VDPAU: Information NVIDIA VDPAU Driver Shared
Library  195.36.15  Thu Mar 11 23:42:13 PST 2010
2010-12-06 21:30:11.462 VDPAU: HQ Scaling not supported.
2010-12-06 21:30:11.462 VDPAU: MPEG4 hardware acceleration not supported.
2010-12-06 21:30:11.462 VDPAU: Created VDPAU render device 1680x1050
2010-12-06 21:30:11.463 VidOutVDPAU: Created VDPAU osd (1680x1050)
2010-12-06 21:30:11.916 Display Rect  left: 0, top: 57, width: 1680,
height: 936, aspect: 1.58456
2010-12-06 21:30:11.916 Video Rect    left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2010-12-06 21:30:11.916 VidOutVDPAU: Created VDPAU context (GPU decode)
2010-12-06 21:30:11.922 Over/underscan. V: 0, H: 0
2010-12-06 21:30:11.922 Display Rect  left: 0, top: 57, width: 1680,
height: 936, aspect: 1.58456
2010-12-06 21:30:11.922 Video Rect    left: 0, top: 0, width: 1280,
height: 720, aspect: 1.77778
2010-12-06 21:30:11.923 VDP: LoadBestPreferences(1280x720, 59.9401)
2010-12-06 21:30:11.923 Player(0): Forcing decode extra audio option
on (Video method requires it).
2010-12-06 21:30:11.923 Player(0): LoadFilters(''..) -> 0x0
2010-12-06 21:30:11.926 Player(0): Decoder thread starting.
2010-12-06 21:30:11.951 OSD: Loaded window osd_message
2010-12-06 21:30:11.970 VidOutVDPAU: Created VDPAU decoder (4 ref frames)
2010-12-06 21:30:11.976 Detect Letterbox: The source is not a
supported frame format (was 11)
2010-12-06 21:30:11.976 OSD: Loaded window osd_input
2010-12-06 21:30:12.050 OSD: Loaded window program_info
2010-12-06 21:30:12.080 OSD: Loaded window browse_info
2010-12-06 21:30:12.103 OSD: Loaded window osd_status
2010-12-06 21:30:12.132 OSD: Loaded window osd_program_editor
2010-12-06 21:30:12.132 OSD: Loaded OSD: size 1680x1050 offset 0+0
2010-12-06 21:30:12.137 Player(0): ClearAfterSeek(0)
2010-12-06 21:30:12.139 VDP: GetFilteredDeint() : vdpau ->
'vdpaubasicdoublerate'
2010-12-06 21:30:12.141 VidOutVDPAU: Enabled deinterlacing.
2010-12-06 21:30:12.141 VSYNC: DRMVideoSync: Could not open device
/dev/dri/card0, No such file or directory
2010-12-06 21:30:12.141 VSYNC: RTCVideoSync: Could not open /dev/rtc,
Permission denied.
2010-12-06 21:30:12.142 Player(0): Video sync method can't support
double framerate (refresh rate too low for 2x deint)
2010-12-06 21:30:12.147 VDP: GetFilteredDeint(vdpaubasic) : vdpau ->
'vdpaubasic'
2010-12-06 21:30:12.148 VidOutVDPAU: Enabled deinterlacing.
2010-12-06 21:30:12.152 Player(0): Video timing method: USleep with busy wait
2010-12-06 21:30:12.152 Player(0): Display Refresh Rate: 59.956 Video
Frame Rate: 59.941
2010-12-06 21:30:12.153 playCtx: StartPlaying(): took 0 ms to start player.
2010-12-06 21:30:12.153 TV: StartPlayer(0, WatchingRecording, main) -- end ok
2010-12-06 21:30:12.157 TV: Changing from None to WatchingRecording
2010-12-06 21:30:12.171 TV: HandleStateChange(0) -- end
2010-12-06 21:30:12.176 AO: OutputAudioLoop: Play Event
2010-12-06 21:30:12.309 ScreenSaverX11Private: ResetTimer -- begin
2010-12-06 21:30:12.309 ScreenSaverX11Private: StopTimer
2010-12-06 21:30:12.311 ScreenSaverX11Private: StartTimer
2010-12-06 21:30:12.311 ScreenSaverX11Private: ResetTimer -- end
2010-12-06 21:30:12.312 Player(0): progressive frame seen after 2
interlaced  frames
2010-12-06 21:30:12.315 VDPAU: Added 2 output surfaces (total 4, max 4)
2010-12-06 21:30:12.359 Player(0): Disabled deinterlacing
2010-12-06 21:30:12.389 ALSA, Error: WriteAudio: buffer underrun
2010-12-06 21:30:12.392 Player(0): Video is 4.43565 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.392 Player(0): Video is 7.89726 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.392 Player(0): Video is 10.2387 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.413 Player(0): Video is 11.755 frames behind audio
(too slow), dropping frame to catch up.
2010-12-06 21:30:12.413 Player(0): Video is 13.3718 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.414 Player(0): Video is 14.3296 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.434 Player(0): Video is 14.8082 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.434 Player(0): Video is 15.302 frames behind audio
(too slow), dropping frame to catch up.
2010-12-06 21:30:12.435 Player(0): Video is 15.4176 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.455 Player(0): Video is 15.2646 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.455 Player(0): Video is 15.2547 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.456 Player(0): Video is 14.9925 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.476 Player(0): Video is 14.5561 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.476 Player(0): Video is 13.9741 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.477 Player(0): Video is 13.2828 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.497 Player(0): Video is 12.5096 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.498 Player(0): Video is 12.0795 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.498 Player(0): Video is 11.5022 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.518 Player(0): Video is 10.8145 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.519 Player(0): Video is 10.4186 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.519 Player(0): Video is 9.86687 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.539 Player(0): Video is 9.19835 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.540 Player(0): Video is 8.45723 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.540 Player(0): Video is 7.64665 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.561 Player(0): Video is 6.78391 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.561 Player(0): Video is 5.89714 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.562 Player(0): Video is 4.97728 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.582 Player(0): Video is 4.03267 frames behind
audio (too slow), dropping frame to catch up.
2010-12-06 21:30:12.582 Player(0): Waiting for video buffers...
2010-12-06 21:30:12.582 AO: Pause 1
2010-12-06 21:30:12.607 AO: Pause 0
2010-12-06 21:30:12.607 Player(0): Video is 3.0844 frames behind audio
(too slow), dropping frame to catch up.
2010-12-06 21:30:12.611 Player(0): Waiting for video buffers...
2010-12-06 21:30:12.611 AO: Pause 1
2010-12-06 21:30:12.621 AO: OutputAudioLoop: audio paused
2010-12-06 21:30:12.633 AO: Pause 0
2010-12-06 21:30:12.647 Player(0): Waiting for video buffers...
2010-12-06 21:30:12.647 AO: Pause 1
2010-12-06 21:30:12.669 AO: Pause 0
2010-12-06 21:30:12.670 AO: OutputAudioLoop: Play Event
2010-12-06 21:30:12.700 Player(0): Video is 3.93347 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:12.733 Player(0): Video is 4.37367 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:12.766 Player(0): Video is 4.82371 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:12.800 Player(0): Video is 4.74165 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:12.833 Player(0): Video is 4.23053 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:12.866 Player(0): Video is 3.96709 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:12.900 Player(0): Video is 3.33495 frames ahead of audio,
            doubling video frame interval to slow down.
'video_output' mean = '15463.87', std. dev. = '10026.89', fps = '64.67'
'video_output' mean = '16671.56', std. dev. = '5730.24', fps = '59.98'
'video_output' mean = '16743.69', std. dev. = '5613.96', fps = '59.72'
2010-12-06 21:30:18.866 Player(0): 400 progressive frames seen.
'video_output' mean = '16617.24', std. dev. = '5259.74', fps = '60.18'
'video_output' mean = '16681.45', std. dev. = '5135.31', fps = '59.95'
'video_output' mean = '16740.08', std. dev. = '5136.89', fps = '59.74'
'video_output' mean = '16619.49', std. dev. = '5145.76', fps = '60.17'
2010-12-06 21:30:25.525 Player(0): 800 progressive frames seen.
'video_output' mean = '16679.96', std. dev. = '5253.11', fps = '59.95'
'video_output' mean = '16744.17', std. dev. = '5178.42', fps = '59.72'
'video_output' mean = '16616.38', std. dev. = '5391.01', fps = '60.18'
2010-12-06 21:30:29.439 Player(0): Waiting for video buffers...
2010-12-06 21:30:29.440 AO: Pause 1
2010-12-06 21:30:29.446 AO: OutputAudioLoop: audio paused
2010-12-06 21:30:29.462 AO: Pause 0
2010-12-06 21:30:29.476 Player(0): Waiting for video buffers...
2010-12-06 21:30:29.476 AO: Pause 1
2010-12-06 21:30:29.499 AO: Pause 0
2010-12-06 21:30:29.499 AO: OutputAudioLoop: Play Event
2010-12-06 21:30:30.547 Player(0): Waiting for video buffers...
2010-12-06 21:30:30.547 AO: Pause 1
2010-12-06 21:30:30.551 AO: OutputAudioLoop: audio paused
2010-12-06 21:30:30.570 AO: Pause 0
2010-12-06 21:30:30.583 Player(0): Waiting for video buffers...
2010-12-06 21:30:30.583 AO: Pause 1
2010-12-06 21:30:30.606 AO: Pause 0
2010-12-06 21:30:30.606 AO: OutputAudioLoop: Play Event
'video_output' mean = '18037.81', std. dev. = '6812.47', fps = '55.44'
2010-12-06 21:30:31.604 Player(0): Waiting for video buffers...
2010-12-06 21:30:31.604 AO: Pause 1
2010-12-06 21:30:31.604 AO: OutputAudioLoop: audio paused
2010-12-06 21:30:31.627 AO: Pause 0
2010-12-06 21:30:31.666 AO: OutputAudioLoop: Play Event
2010-12-06 21:30:31.723 Player(0): Video is 3.27891 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:31.757 Player(0): Video is 3.4332 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:31.790 Player(0): Video is 3.29419 frames ahead of audio,
            doubling video frame interval to slow down.
2010-12-06 21:30:32.481 Player(0): 1200 progressive frames seen.
'video_output' mean = '18144.70', std. dev. = '6677.44', fps = '55.11'
2010-12-06 21:30:32.791 Player(0): Waiting for video buffers...
2010-12-06 21:30:32.791 AO: Pause 1
2010-12-06 21:30:32.796 AO: OutputAudioLoop: audio paused
2010-12-06 21:30:32.814 AO: Pause 0
2010-12-06 21:30:32.861 AO: OutputAudioLoop: Play Event


More information about the mythtv-users mailing list