2009-10-24 12:20:45.915 mythfrontend version: trunk [22582] www.mythtv.org
2009-10-24 12:20:45.970 Using runtime prefix = /usr/local
2009-10-24 12:20:45.971 Using configuration directory = /root/.mythtv
2009-10-24 12:20:46.762 Empty LocalHostName.
2009-10-24 12:20:46.763 Using localhost value of linux-8wqa
2009-10-24 12:20:46.783 New DB connection, total: 1
2009-10-24 12:20:46.791 Connected to database 'mythconverg' at host: localhost
2009-10-24 12:20:46.793 Closing DB connection named 'DBManager0'
2009-10-24 12:20:46.820 DPMS is disabled.
2009-10-24 12:20:46.825 Primary screen: 0.
2009-10-24 12:20:46.826 Connected to database 'mythconverg' at host: localhost
2009-10-24 12:20:46.829 Using screen 0, 1920x1080 at 0,0
2009-10-24 12:20:46.980 MythUI Image Cache size set to 20971520 bytes
2009-10-24 12:20:46.981 user: 0 effective user: 0 before privileged thread
2009-10-24 12:20:46.981 user: 0 effective user: 0 after privileged thread
2009-10-24 12:20:46.981 user: 0 effective user: 0 run_priv_thread
2009-10-24 12:20:46.982 Enabled verbose msgs:  important general playback
2009-10-24 12:20:46.996 Primary screen: 0.
2009-10-24 12:20:46.998 Using screen 0, 1920x1080 at 0,0
2009-10-24 12:20:47.001 Using theme base resolution of 800x600
2009-10-24 12:20:47.022 LIRC: Successfully initialized '/dev/lircd' using '/root/.mythtv/lircrc' config
2009-10-24 12:20:47.222 Using the Qt painter
2009-10-24 12:20:47.232 Theme error: Unknown tag in font 
Type: 'size:small'
Name: ''
Line: 11
2009-10-24 12:20:47.232 Theme error: Specified base font 'small' does not exist for font medium
Type: 'font'
Name: 'medium'
Line: 16
2009-10-24 12:20:47.232 Theme error: Specified base font 'small' does not exist for font large
Type: 'font'
Name: 'large'
Line: 22
2009-10-24 12:20:47.234 Loaded base theme from /usr/local/share/mythtv/themes/G.A.N.T/base.xml
2009-10-24 12:20:47.800 Loaded base theme from /usr/local/share/mythtv/themes/default/base.xml
2009-10-24 12:20:47.819 Current MythTV Schema Version (DBSchemaVer): 1244
2009-10-24 12:20:48.231 Desktop video mode: 1920x1080 59.9341 Hz
2009-10-24 12:20:48.290 max_width: 1920 max_height: 1080
2009-10-24 12:20:48.686 Registering Internal as a media playback plugin.
2009-10-24 12:20:48.757 Registering WebBrowser as a media playback plugin.
2009-10-24 12:20:48.845 MMUnix::AddDevice() Error: failed to stat /dev/bdi, 
			eno: No such file or directory (2)
2009-10-24 12:20:48.856 MMUnix::AddDevice() Error: failed to stat /dev/power, 
			eno: No such file or directory (2)
2009-10-24 12:20:48.869 MMUnix::AddDevice() Error: failed to stat /dev/trace, 
			eno: No such file or directory (2)
2009-10-24 12:20:48.878 MonitorRegisterExtensions(0x100, gif,jpg,png)
2009-10-24 12:20:48.988 Current MythMusic Schema Version (MusicDBSchemaVer): 1017
2009-10-24 12:20:49.102 MonitorRegisterExtensions(0x40, mp3,mp2,ogg,oga,flac,wma,wav,ac3,oma,omg,atp,ra,dts,aac,m4a,aa3,tta,mka,aiff,swa,wv)
2009-10-24 12:20:49.146 Current MythVideo Schema Version (mythvideo.DBSchemaVer): 1028
2009-10-24 12:20:49.287 Loading window theme from /usr/local/share/mythtv/themes/G.A.N.T/menu-ui.xml
2009-10-24 12:20:49.298 Theme error: Specified base font 'small' does not exist for font clock
Type: 'font'
Name: 'clock'
Line: 17
2009-10-24 12:20:49.662 Loading menu theme from /usr/local/share/mythtv/themes/defaultmenu//mainmenu.xml
2009-10-24 12:20:49.667 Found mainmenu.xml for theme 'G.A.N.T'
2009-10-24 12:20:49.848 MythContext: Connecting to backend server: 10.0.0.28:6543 (try 1 of 1)
2009-10-24 12:20:49.849 Using protocol version 50
2009-10-24 12:20:52.207 TV: StartTV() -- begin
2009-10-24 12:20:52.208 TV: ctor
2009-10-24 12:20:52.259 New DB connection, total: 2
2009-10-24 12:20:52.260 Connected to database 'mythconverg' at host: localhost
2009-10-24 12:20:52.366 TV: DrawUnusedRects() -- begin
2009-10-24 12:20:52.367 TV: DrawUnusedRects() -- end
2009-10-24 12:20:52.367 TV: DrawUnusedRects() -- begin
2009-10-24 12:20:52.367 TV: DrawUnusedRects() -- end
2009-10-24 12:20:52.399 TV: tv->LiveTV() -- begin
2009-10-24 12:20:52.407 TV: tv->LiveTV() -- end
2009-10-24 12:20:52.407 TV: StartTV -- process events begin
2009-10-24 12:20:52.448 TV: HandleStateChange(0) -- begin
2009-10-24 12:20:52.448 TV: Attempting to change from None to Watching WatchingLiveTV
2009-10-24 12:20:52.452 MythContext: Connecting to backend server: 10.0.0.28:6543 (try 1 of 1)
2009-10-24 12:20:52.453 Using protocol version 50
2009-10-24 12:20:52.454 Spawning LiveTV Recorder -- begin
2009-10-24 12:20:53.105 Spawning LiveTV Recorder -- end
2009-10-24 12:20:53.107 LiveTVChain(live-linux-8wqa-2009-10-24T12:20:52): ReloadAll(): Added new recording
2009-10-24 12:20:53.128 We have a playbackURL(/mythtv/12100_20091024122052.mpg) & cardtype(DUMMY)
2009-10-24 12:20:53.128 We have a RingBuffer
2009-10-24 12:20:53.180 TV: StartRecorder(): took 1 ms to start recorder.
2009-10-24 12:20:53.181 TV: StartPlayer(0, Watching WatchingLiveTV, main) -- begin
2009-10-24 12:20:53.232 detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
2009-10-24 12:20:53.238 NVP(0): Disabling Audio, params(-1,2,44100)
2009-10-24 12:20:53.242 VideoOutput: Allowed renderers: xv-blit,xshm,xlib,opengl,vdpau
2009-10-24 12:20:53.243 VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl,vdpau
2009-10-24 12:20:53.247 VDP: Accepting: cmp(<= 720 999) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(disabled) deint(vdpauadvanced,none) filt(vdpaubuffersize=22)
2009-10-24 12:20:53.248 VDP: Accepting: cmp() dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt(vdpaubuffersize=32)
2009-10-24 12:20:53.248 VDP: LoadBestPreferences(2048x2048, 0)
2009-10-24 12:20:53.248 VDP: LoadBestPreferences(2048x2048, 60)
2009-10-24 12:20:53.248 VDP: LoadBestPreferences(720x576, 60)
2009-10-24 12:20:53.249 VideoOutput: Preferred renderer: vdpau
2009-10-24 12:20:53.249 VideoOutput: Trying video renderer: 'vdpau'
2009-10-24 12:20:53.270 VDP: Accepting: cmp(<= 720 999) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(disabled) deint(vdpauadvanced,none) filt(vdpaubuffersize=22)
2009-10-24 12:20:53.271 VDP: Accepting: cmp() dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt(vdpaubuffersize=32)
2009-10-24 12:20:53.271 VDP: LoadBestPreferences(2048x2048, 0)
2009-10-24 12:20:53.271 VDP: LoadBestPreferences(2048x2048, 60)
2009-10-24 12:20:53.283 VideoOutWindow::SetPIPState. pip_state: 0]
2009-10-24 12:20:53.283 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.33333
2009-10-24 12:20:53.283 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.3333
2009-10-24 12:20:53.283 VDP: LoadBestPreferences(720x576, 60)
2009-10-24 12:20:53.283 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.33333
2009-10-24 12:20:53.284 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.3333
2009-10-24 12:20:53.284 VDP: SetVideoRenderer(vdpau)
2009-10-24 12:20:53.284 VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2009-10-24 12:20:53.286 VidOutVDPAU: VDPAU Colorkey: 0x20202 (depth 24)
2009-10-24 12:20:53.290 VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
2009-10-24 12:20:53.290 VideoOutput: Actual display dimensions: 524x323 mm  Aspect: 1.62229
2009-10-24 12:20:53.291 VideoOutput: Estimated window dimensions: 524x323 mm  Aspect: 1.62229
2009-10-24 12:20:53.332 VDPAU: Version 0
2009-10-24 12:20:53.333 VDPAU: Information NVIDIA VDPAU Driver Shared Library  185.18.36  Fri Aug 14 17:50:51 PDT 2009
2009-10-24 12:20:53.360 VidOutVDPAU: VDPAU video buffer size: 22 (default 17)
2009-10-24 12:20:53.433 Display Rect  left: 171, top: 0, width: 1578, height: 1080, aspect: 1.62229
2009-10-24 12:20:53.433 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.3333
2009-10-24 12:20:53.433 VidOutVDPAU: Created VDPAU context (software decode)
2009-10-24 12:20:53.513 VDPAU: Created OSD (1920x1080)
2009-10-24 12:20:53.519 Over/underscan. V: 0, H: 0
2009-10-24 12:20:53.519 Display Rect  left: 171, top: 0, width: 1578, height: 1080, aspect: 1.62229
2009-10-24 12:20:53.519 Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.3333
2009-10-24 12:20:53.520 VDP: LoadBestPreferences(720x576, 25)
2009-10-24 12:20:53.522 FilterManager: GetFilterInfo(convert) returning: 0x0
2009-10-24 12:20:53.522 NVP(0): LoadFilters('vdpaubuffersize=22'..) -> 0x0
2009-10-24 12:20:53.525 OSD Theme Dimensions W: 640 H: 480
2009-10-24 12:20:53.570 New DB connection, total: 3
2009-10-24 12:20:53.572 Connected to database 'mythconverg' at host: localhost
2009-10-24 12:20:54.400 NVP(0): ClearAfterSeek(1)
2009-10-24 12:20:54.400 playCtx: StartDecoderThread(): took 1162 ms to start player.
2009-10-24 12:20:54.401 VidOutVDPAU: ClearAfterSeek()
2009-10-24 12:20:54.401 VidOutVDPAU: DiscardFrames(0)
2009-10-24 12:20:54.401 TV: StartPlayer(0, Watching WatchingLiveTV, main) -- end ok
2009-10-24 12:20:54.401 TV: Changing from None to Watching WatchingLiveTV
2009-10-24 12:20:54.402 TV: State is LiveTV & mctx == ctx
2009-10-24 12:20:54.401 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAA
2009-10-24 12:20:54.404 TV: UpdateOSDInput done
2009-10-24 12:20:54.404 TV: UpdateLCD done
2009-10-24 12:20:54.404 TV: ITVRestart done
2009-10-24 12:20:54.403 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAA -- done
2009-10-24 12:20:54.405 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAA -- done()
2009-10-24 12:20:54.409 The realtime priority setting is not enabled.
2009-10-24 12:20:54.412 NVP(0): DoPause() -- begin
2009-10-24 12:20:54.413 rate: 25 speed: 1 skip: 1 = interval 40000
2009-10-24 12:20:54.413 NVP(0): DoPause() -- setting paused
2009-10-24 12:20:54.413 VDP: GetFilteredDeint() : vdpau -> 'vdpauadvanced'
2009-10-24 12:20:54.450 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2009-10-24 12:20:54.450 OpenGLVideoSync()
2009-10-24 12:20:54.466 TV: HandleStateChange(0) -- end
2009-10-24 12:20:54.476 Forcing GLX version to 1.2 (orig 1.3)
2009-10-24 12:20:54.502 GLCtx: Created OpenGL window.
2009-10-24 12:20:54.519 TV: DrawUnusedRects() -- begin
2009-10-24 12:20:54.552 TV: DrawUnusedRects() -- end
2009-10-24 12:20:54.552 GLCtx: Created GLX context.
2009-10-24 12:20:54.553 GLCtx: GLX Version: 1.2
2009-10-24 12:20:54.553 GLCtx: Direct rendering: Yes
2009-10-24 12:20:54.596 LiveTVChain(live-linux-8wqa-2009-10-24T12:20:52): ReloadAll(): Added new recording
2009-10-24 12:20:54.597 LiveTVChain(live-linux-8wqa-2009-10-24T12:20:52): SwitchTo(1)
2009-10-24 12:20:54.597 LiveTVChain(live-linux-8wqa-2009-10-24T12:20:52): Entry@1: '12100_20091024122054'
2009-10-24 12:20:54.597 JumpToProgram(void)
2009-10-24 12:20:54.638 FilterManager: GetFilterInfo(convert) returning: 0x0
2009-10-24 12:20:54.639 NVP(0): LoadFilters('vdpaubuffersize=22'..) -> 0x0
2009-10-24 12:20:54.644 RingBuf(/mythtv/12100_20091024122052.mpg): OpenFile(/mythtv/12100_20091024122054.mpg, 12)
2009-10-24 12:20:54.647 GLCtx: OpenGL vendor  : NVIDIA Corporation
2009-10-24 12:20:54.648 GLCtx: OpenGL renderer: ION/PCI/SSE2
2009-10-24 12:20:54.648 GLCtx: OpenGL version : 3.0.0 NVIDIA 185.18.36
2009-10-24 12:20:54.648 GLCtx: Max texture size: 8192 x 8192
2009-10-24 12:20:54.649 GLCtx: Max texture units: 4
2009-10-24 12:20:54.658 RingBuf(/mythtv/12100_20091024122054.mpg): CalcReadAheadThresh(0 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2009-10-24 12:20:54.659 Using audio as timebase
2009-10-24 12:20:54.659 Video timing method: SGI OpenGL
2009-10-24 12:20:54.659 Refresh rate: 16685, frame interval: 40000
2009-10-24 12:20:55.211 VDPAU: Using 4 output surfaces (max 4)
2009-10-24 12:20:55.273 [h264 @ 0xb66b9708]mmco: unref short failure
2009-10-24 12:20:55.273 [h264 @ 0xb66b9708]mmco: unref short failure
2009-10-24 12:20:55.573 AFD: Stream #0, has id 0x177a codec id H264, type Video, bitrate 0 at 0x89a54b0
2009-10-24 12:20:55.578 VDP: Accepting: cmp(<= 720 999) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(disabled) deint(vdpauadvanced,none) filt(vdpaubuffersize=22)
2009-10-24 12:20:55.579 VDP: Accepting: cmp() dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt(vdpaubuffersize=32)
2009-10-24 12:20:55.579 VDP: LoadBestPreferences(2048x2048, 0)
2009-10-24 12:20:55.579 VDP: LoadBestPreferences(2048x2048, 60)
2009-10-24 12:20:55.580 VDP: LoadBestPreferences(1280x720, 60)
2009-10-24 12:20:55.584 VDP: Accepting: cmp(<= 720 999) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(disabled) deint(vdpauadvanced,none) filt(vdpaubuffersize=22)
2009-10-24 12:20:55.584 VDP: Accepting: cmp() dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt(vdpaubuffersize=32)
2009-10-24 12:20:55.585 VDP: LoadBestPreferences(2048x2048, 0)
2009-10-24 12:20:55.585 VDP: LoadBestPreferences(2048x2048, 60)
2009-10-24 12:20:55.585 VDP: LoadBestPreferences(1280x720, 60)
2009-10-24 12:20:55.585 Using 1 CPUs for decoding
2009-10-24 12:20:55.585 AFD: InitVideoCodec() 0x89dc0f0 id(H264) type (Video).
2009-10-24 12:20:55.590 VDP: Accepting: cmp(<= 720 999) dec(vdpau) cpus(1) rend(vdpau) osd(vdpau) osdfade(disabled) deint(vdpauadvanced,none) filt(vdpaubuffersize=22)
2009-10-24 12:20:55.590 VDP: Accepting: cmp() dec(vdpau) cpus(0) rend(vdpau) osd(vdpau) osdfade(disabled) deint(none,none) filt(vdpaubuffersize=32)
2009-10-24 12:20:55.590 VDP: LoadBestPreferences(2048x2048, 0)
2009-10-24 12:20:55.590 VDP: LoadBestPreferences(2048x2048, 60)
2009-10-24 12:20:55.591 VDP: LoadBestPreferences(1280x720, 60)
2009-10-24 12:20:55.591 VidOutVDPAU: InputChanged(1280,720,1.77778) 'None'->'H.264 VDPAU'
2009-10-24 12:20:55.591 VidOutVDPAU: DiscardFrames(1)
2009-10-24 12:20:55.592 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAA
2009-10-24 12:20:55.592 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAA -- done()
2009-10-24 12:20:55.593 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAA -- done
2009-10-24 12:20:55.593 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAA -- done()
2009-10-24 12:20:55.761 Display Rect  left: 0, top: 47, width: 1920, height: 986, aspect: 1.62229
2009-10-24 12:20:55.762 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2009-10-24 12:20:55.762 VDP: LoadBestPreferences(1280x720, 25)
2009-10-24 12:20:55.762 Display Rect  left: 0, top: 47, width: 1920, height: 986, aspect: 1.62229
2009-10-24 12:20:55.762 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2009-10-24 12:20:55.762 VDP: SetVideoRenderer(vdpau)
2009-10-24 12:20:55.763 VDP: SetVideoRender(vdpau) == GetVideoRenderer()
2009-10-24 12:20:55.769 VidOutVDPAU: VDPAU Colorkey: 0x20202 (depth 24)
2009-10-24 12:20:55.774 VideoOutput: Pixel dimensions: Screen 1920x1080, window 1920x1080
2009-10-24 12:20:55.774 VideoOutput: Actual display dimensions: 524x323 mm  Aspect: 1.62229
2009-10-24 12:20:55.774 VideoOutput: Estimated window dimensions: 524x323 mm  Aspect: 1.62229
2009-10-24 12:20:55.833 VidOutVDPAU: VDPAU video buffer size: 32 (default 17)
2009-10-24 12:20:56.523 Display Rect  left: 0, top: 47, width: 1920, height: 986, aspect: 1.62229
2009-10-24 12:20:56.523 Video Rect    left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2009-10-24 12:20:56.523 VidOutVDPAU: Created VDPAU context (GPU decode)
2009-10-24 12:20:56.624 VDPAU: Created OSD (1920x1080)
2009-10-24 12:20:56.625 VDP: GetFilteredDeint() : vdpau -> 'none'
2009-10-24 12:20:56.625 VDP: GetFilteredDeint() : vdpau -> 'none'
2009-10-24 12:20:56.625 VDP: LoadBestPreferences(1280x720, 50)
2009-10-24 12:20:56.625 NVP(0): Forcing decode extra audio option on (Video method requires it).
2009-10-24 12:20:57.055 NVP(0): ClearAfterSeek(1)
2009-10-24 12:20:57.055 VidOutVDPAU: ClearAfterSeek()
2009-10-24 12:20:57.056 VidOutVDPAU: DiscardFrames(0)
2009-10-24 12:20:57.058 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAA          
2009-10-24 12:20:57.058 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAA           -- done
2009-10-24 12:20:57.059 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAA           -- done()
2009-10-24 12:20:57.059 FilterManager: GetFilterInfo(convert) returning: 0x0
2009-10-24 12:20:57.060 NVP(0): LoadFilters('vdpaubuffersize=32'..) -> 0x0
2009-10-24 12:20:57.060 detectInterlace(Detect Scan, Interlaced Scan, 50, 720) ->Progressive Scan
2009-10-24 12:20:57.060 VDP: GetFilteredDeint() : vdpau -> 'none'
2009-10-24 12:20:57.060 Disabled deinterlacing
2009-10-24 12:20:57.061 AFD: Using vdpau for video decoding
2009-10-24 12:20:57.061 AFD: Looking for decoder for H264
2009-10-24 12:20:57.062 AFD: Opened codec 0x89dc0f0, id(H264) type(Video)
2009-10-24 12:20:57.063 AFD: Stream #1, has id 0x1784 codec id MP2, type Audio, bitrate 256000 at 0x89a99c0
2009-10-24 12:20:57.063 AFD: codec MP2 has 2 channels
2009-10-24 12:20:57.063 AFD: Looking for decoder for MP2
2009-10-24 12:20:57.063 AFD: Opened codec 0x94fcbb0, id(MP2) type(Audio)
2009-10-24 12:20:57.066 AFD: Stream #2, has id 0x1786 codec id AC3, type Audio, bitrate 192000 at 0x89a9020
2009-10-24 12:20:57.066 AFD: codec AC3 has 2 channels
2009-10-24 12:20:57.066 AFD: Looking for decoder for AC3
2009-10-24 12:20:57.068 AFD: Opened codec 0x94fcf70, id(AC3) type(Audio)
2009-10-24 12:20:57.068 AFD: Stream #3, has id 0x178e codec id DVB_VBI, type Data, bitrate 0 at 0x89d4f80
2009-10-24 12:20:57.069 AFD: data codec (Data)
2009-10-24 12:20:57.184 RingBuf(/mythtv/12100_20091024122054.mpg): CalcReadAheadThresh(0 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2009-10-24 12:20:57.218 Opening audio device 'default'. ch 2(2) sr 48000 (reenc 0)
2009-10-24 12:20:57.219 Opening ALSA audio device 'default'.
2009-10-24 12:20:57.234 Mixer unable to find control Master
2009-10-24 12:20:57.234 Mixer unable to find control Master
2009-10-24 12:20:57.236 NVP(0): Enabling Audio
2009-10-24 12:20:57.237 Dec: Trying to select track (w/lang)
2009-10-24 12:20:57.237 Dec: Selecting first track
2009-10-24 12:20:57.237 Dec: Selected track #1 in the Unknown language(0)
2009-10-24 12:20:57.237 Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
2009-10-24 12:20:57.240 Position map filled from DB to: 0
2009-10-24 12:20:57.240 Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2009-10-24 12:20:57.241 NVP(0): Filling position map from 1 to end
2009-10-24 12:20:57.242 Dec: Position map filled from Encoder to: 96
2009-10-24 12:20:57.242 Dec: SyncPositionMap watchingrecording total: 4 entries
2009-10-24 12:20:57.243 Dec: SyncPositionMap, new totframes: 96, new length: 1, posMap size: 4
2009-10-24 12:20:57.243 AFD: Partial position map found
2009-10-24 12:20:57.243 AFD: Successfully opened decoder for file: "/mythtv/12100_20091024122054.mpg". novideo(0)
2009-10-24 12:20:57.249 NVP(0): DoPlay() -- begin
2009-10-24 12:20:57.249 NVP(0): DoPlay: rate: 50 speed: 1 skip: 1 => new interval 20000
2009-10-24 12:20:57.250 Set video sync frame interval to 20000
2009-10-24 12:20:57.250 NVP(0): Stretch Factor 1, allow passthru 
2009-10-24 12:20:57.250 NVP(0): DoPlay() -- setting unpaused
2009-10-24 12:20:57.257 NVP(0): Waiting for prebuffer..  0 AAAAAAAAAAAAAAAAAAAAAA          
2009-10-24 12:20:57.307 RingBuf(/mythtv/12100_20091024122054.mpg): CalcReadAheadThresh(0 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2009-10-24 12:20:57.307 Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
2009-10-24 12:20:57.308 Position map filled from DB to: 0
2009-10-24 12:20:57.309 Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2009-10-24 12:20:57.309 NVP(0): Filling position map from 1 to end
2009-10-24 12:20:57.311 Dec: Position map filled from Encoder to: 96
2009-10-24 12:20:57.311 Dec: SyncPositionMap watchingrecording total: 4 entries
2009-10-24 12:20:57.317 [h264_vdpau @ 0xb66b9708]mmco: unref short failure
2009-10-24 12:20:57.317 [h264_vdpau @ 0xb66b9708]mmco: unref short failure
2009-10-24 12:20:57.401 NVP(0): Waiting for prebuffer..  1 (AL)AAAAAAAAAAAAAAAAAAAAA          
2009-10-24 12:20:57.500 NVP(0): Waiting for prebuffer..  2 (AL)AAAAAAAAAAAAAAAAAAAAA          
2009-10-24 12:20:57.541 VDPAU: Created VDPAU decoder (5 ref frames)
2009-10-24 12:20:57.556 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.557 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.557 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.557 AFD Error: Unknown decoding error
2009-10-24 12:20:57.558 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.558 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.558 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.558 AFD Error: Unknown decoding error
2009-10-24 12:20:57.559 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.559 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.559 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.559 AFD Error: Unknown decoding error
2009-10-24 12:20:57.559 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.560 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.560 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.560 AFD Error: Unknown decoding error
2009-10-24 12:20:57.560 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.560 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.560 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.560 AFD Error: Unknown decoding error
2009-10-24 12:20:57.561 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.561 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.561 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.561 AFD Error: Unknown decoding error
2009-10-24 12:20:57.561 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.562 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.562 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.562 AFD Error: Unknown decoding error
2009-10-24 12:20:57.562 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.562 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.562 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.562 AFD Error: Unknown decoding error
2009-10-24 12:20:57.563 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.563 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.563 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.563 AFD Error: Unknown decoding error
2009-10-24 12:20:57.564 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.564 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.564 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.564 AFD Error: Unknown decoding error
2009-10-24 12:20:57.564 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.564 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.565 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.565 AFD Error: Unknown decoding error
2009-10-24 12:20:57.565 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.565 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.565 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.565 AFD Error: Unknown decoding error
2009-10-24 12:20:57.566 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.566 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.566 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.566 AFD Error: Unknown decoding error
2009-10-24 12:20:57.566 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.567 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.567 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.567 AFD Error: Unknown decoding error
2009-10-24 12:20:57.567 [h264_vdpau @ 0xb66b9708]B picture before any references, skipping
2009-10-24 12:20:57.568 [h264_vdpau @ 0xb66b9708]decode_slice_header error
2009-10-24 12:20:57.568 [h264_vdpau @ 0xb66b9708]no frame!
2009-10-24 12:20:57.568 AFD Error: Unknown decoding error
2009-10-24 12:20:57.579 [h264_vdpau @ 0xb66b9708]number of reference frames exceeds max (probably corrupt input), discarding one
2009-10-24 12:20:57.582 [h264_vdpau @ 0xb66b9708]number of reference frames exceeds max (probably corrupt input), discarding one
2009-10-24 12:20:57.583 [h264_vdpau @ 0xb66b9708]number of reference frames exceeds max (probably corrupt input), discarding one
2009-10-24 12:20:57.584 [h264_vdpau @ 0xb66b9708]reference picture missing during reorder
2009-10-24 12:20:57.584 [h264_vdpau @ 0xb66b9708]Missing reference picture
2009-10-24 12:20:57.590 NVP(0): Waiting for prebuffer..  3 (au)AA(AL)(AL)(AL)(AL)(AL)(AL)AAAAAAAAAAAAA          
2009-10-24 12:20:57.636 [h264_vdpau @ 0xb66b9708]number of reference frames exceeds max (probably corrupt input), discarding one
2009-10-24 12:20:57.637 [h264_vdpau @ 0xb66b9708]number of reference frames exceeds max (probably corrupt input), discarding one
2009-10-24 12:20:57.666 [h264_vdpau @ 0xb66b9708]mmco: unref short failure
2009-10-24 12:20:57.682 NVP(0): prebuffering pause
2009-10-24 12:20:57.683 NVP(0): Waiting for prebuffer..  0 AAAAAA(AL)A(AL)aLAAALLAAAAAA          
2009-10-24 12:20:57.795 VDPAU: Using 4 output surfaces (max 4)
2009-10-24 12:20:57.799 [h264_vdpau @ 0xb66b9708]number of reference frames exceeds max (probably corrupt input), discarding one
2009-10-24 12:20:57.899 NVP(0): Video is 3.36255 frames ahead of audio,
			doubling video frame interval to slow down.
2009-10-24 12:20:57.931 NVP(0): Video is 3.3094 frames ahead of audio,
			doubling video frame interval to slow down.
2009-10-24 12:20:57.965 NVP(0): Video is 3.10705 frames ahead of audio,
			doubling video frame interval to slow down.
'video_output' mean = '25100.74', std. dev. = '12635.59', fps = '39.84'
'video_output' mean = '23607.55', std. dev. = '10161.06', fps = '42.36'
'video_output' mean = '23516.79', std. dev. = '10500.41', fps = '42.52'
2009-10-24 12:21:07.202 NVP(0): 400 progressive frames seen.
'video_output' mean = '23688.16', std. dev. = '10021.62', fps = '42.22'
'video_output' mean = '23692.99', std. dev. = '10945.15', fps = '42.21'
'video_output' mean = '24022.48', std. dev. = '10009.22', fps = '41.63'
'video_output' mean = '23029.88', std. dev. = '10409.29', fps = '43.42'
2009-10-24 12:21:16.695 NVP(0): 800 progressive frames seen.
'video_output' mean = '25037.38', std. dev. = '14957.88', fps = '39.94'
2009-10-24 12:21:16.888 NVP(0): Video is 3.00275 frames behind audio (too slow), dropping frame to catch up.
2009-10-24 12:21:17.123 NVP(0): Video is 3.00015 frames ahead of audio,
			doubling video frame interval to slow down.
2009-10-24 12:21:17.145 NVP(0): Video is 3.2126 frames ahead of audio,
			doubling video frame interval to slow down.
2009-10-24 12:21:17.179 NVP(0): Video is 3.35945 frames ahead of audio,
			doubling video frame interval to slow down.
2009-10-24 12:21:17.212 NVP(0): Video is 3.29455 frames ahead of audio,
			doubling video frame interval to slow down.
2009-10-24 12:21:17.262 NVP(0): Video is 3.0584 frames ahead of audio,
			doubling video frame interval to slow down.
'video_output' mean = '22998.57', std. dev. = '10862.62', fps = '43.48'
'video_output' mean = '23348.59', std. dev. = '10075.75', fps = '42.83'
'video_output' mean = '23693.63', std. dev. = '10606.92', fps = '42.21'
2009-10-24 12:21:26.189 NVP(0): 1200 progressive frames seen.
'video_output' mean = '24021.52', std. dev. = '9850.19', fps = '41.63'
'video_output' mean = '23682.90', std. dev. = '10400.20', fps = '42.22'
'video_output' mean = '23360.20', std. dev. = '10744.93', fps = '42.81'
'video_output' mean = '23857.40', std. dev. = '10244.08', fps = '41.92'
2009-10-24 12:21:34.056 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2009-10-24 12:21:34.056 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2009-10-24 12:21:34.097 TV: HandleStateChange(0) -- begin
2009-10-24 12:21:34.098 TV: Attempting to change from Watching WatchingLiveTV to None
2009-10-24 12:21:34.098 TV: StopStuff() for player ctx 0 -- begin
2009-10-24 12:21:34.098 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2009-10-24 12:21:34.098 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2009-10-24 12:21:34.098 TV: StopStuff(): stopping ring buffer
2009-10-24 12:21:34.107 TV: StopStuff(): stopping player
2009-10-24 12:21:34.108 TV: StopStuff(): stopping recorder
2009-10-24 12:21:34.109 NVP(0): Exited decoder loop.
2009-10-24 12:21:34.114 ~OpenGLVideoSync() -- closing opengl vsync
2009-10-24 12:21:34.115 GLCtx: Deleting OpenGL Resources
2009-10-24 12:21:34.116 GLCtx: Unmapping gl window
2009-10-24 12:21:34.117 GLCtx: Destroying gl window
2009-10-24 12:21:34.117 GLCtx: Destroying glx context
2009-10-24 12:21:34.142 GLCtx: Closing display
2009-10-24 12:21:34.144 VidOutVDPAU: DiscardFrames(1)
2009-10-24 12:21:34.144 VideoBuffers::DiscardFrames(1): U(AU)Uu(AL)UUUUUUUUULUUUUUUu          
2009-10-24 12:21:34.145 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAA           -- done()
2009-10-24 12:21:34.145 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAA           -- done
2009-10-24 12:21:34.146 VidOutVDPAU: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAA           -- done()
2009-10-24 12:21:34.438 TV: StopStuff() -- end
2009-10-24 12:21:34.438 TV: Changing from Watching WatchingLiveTV to None
2009-10-24 12:21:34.440 TV: HandleStateChange(0) -- end
2009-10-24 12:21:34.440 TV: StartTV -- process events end
2009-10-24 12:21:34.440 TV: StartTV -- process events 2 begin
2009-10-24 12:21:34.447 TV: StartTV -- process events 2 end
2009-10-24 12:21:34.452 TV::~TV() -- begin
2009-10-24 12:21:34.504 TV::~TV() -- lock
2009-10-24 12:21:34.912 TV::~TV() -- end
2009-10-24 12:21:34.913 TV: StartTV -- end
2009-10-24 12:21:37.662 Deleting UPnP client...