2012-12-25 21:37:35.316161 C  mythfrontend version: fixes/0.25 [v0.25.3-7-gfe9f6fa] www.mythtv.org
2012-12-25 21:37:35.316201 C  Qt version: compile: 4.7.3, runtime: 4.7.3
2012-12-25 21:37:35.316207 N  Enabled verbose msgs:  general playback
2012-12-25 21:37:35.316246 N  Setting Log Level to LOG_INFO
2012-12-25 21:37:35.316313 I  Added logging to the console
2012-12-25 21:37:35.316324 I  Added database logging to table logging
2012-12-25 21:37:35.316438 N  Setting up SIGHUP handler
2012-12-25 21:37:35.316689 N  Using runtime prefix = /usr
2012-12-25 21:37:35.316728 N  Using configuration directory = /home/mythtv/.mythtv
2012-12-25 21:37:35.316924 I  Assumed character encoding: en_US.utf-8
2012-12-25 21:37:35.317719 N  Empty LocalHostName.
2012-12-25 21:37:35.317736 I  Using localhost value of LinHES
2012-12-25 21:37:35.351835 N  Setting QT default locale to EN_US
2012-12-25 21:37:35.351866 I  Current locale EN_US
2012-12-25 21:37:35.352004 N  Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
2012-12-25 21:37:35.360673 I  Starting process manager
2012-12-25 21:37:35.363240 I  Starting process signal handler
2012-12-25 21:37:35.363578 I  Starting IO manager (read)
2012-12-25 21:37:35.363609 I  Starting IO manager (write)
2012-12-25 21:37:35.464991 I  ScreenSaverX11Private: DPMS is disabled.
2012-12-25 21:37:35.601995 N  Desktop video mode: 1280x720 60.073 Hz
2012-12-25 21:37:35.644650 I  max_width: 1280 max_height: 720
2012-12-25 21:37:35.684496 I  Listening on TCP 127.0.0.1:6547
2012-12-25 21:37:35.684671 I  Listening on TCP 192.168.1.210:6547
2012-12-25 21:37:35.684856 I  Listening on TCP [0:0:0:0:0:0:0:1]:6547
2012-12-25 21:37:35.685072 I  Listening on TCP [fe80:0:0:0:21a:92ff:fecb:5342%eth0]:6547
2012-12-25 21:37:36.718444 E  RAOP Conn: Failed to read key from: /home/mythtv/.mythtv/RAOPKey.rsa
2012-12-25 21:37:36.718475 E  RAOP Device: Aborting startup - no key found.
2012-12-25 21:37:36.724005 I  Loading en_us translation for module mythfrontend
2012-12-25 21:37:36.795892 I  LIRC: Successfully initialized '/var/run/lirc/lircd' using '/home/mythtv/.mythtv/lircrc' config
2012-12-25 21:37:36.796154 E  JoystickMenuThread: Joystick disabled - Failed to read /home/mythtv/.mythtv/joystickmenurc
2012-12-25 21:37:36.894033 E  CECAdapter: Failed to find any CEC devices.
2012-12-25 21:37:36.894383 I  CECAdapter: Closing down CEC.
2012-12-25 21:37:36.896631 E  Failed binding to UDP 127.0.0.1:6948 - Error 8: The bound address is already in use
2012-12-25 21:37:36.896772 E  Failed binding to UDP 192.168.1.210:6948 - Error 8: The bound address is already in use
2012-12-25 21:37:36.896976 E  Failed binding to UDP [0:0:0:0:0:0:0:1]:6948 - Error 8: The bound address is already in use
2012-12-25 21:37:36.897145 E  Failed binding to UDP [fe80:0:0:0:21a:92ff:fecb:5342%eth0]:6948 - Error 8: The bound address is already in use
2012-12-25 21:37:36.897296 E  Failed binding to UDP 192.168.1.255:6948 - Error 8: The bound address is already in use
2012-12-25 21:37:36.948211 I  Using Frameless Window
2012-12-25 21:37:36.948316 I  Using Full Screen Window
2012-12-25 21:37:37.184768 I  Trying the OpenGL painter
2012-12-25 21:37:37.186524 I  OpenGL: Sync to VBlank is enabled (good!)
2012-12-25 21:37:37.772400 I  OpenGL1: Fragment program support available
2012-12-25 21:37:37.772485 I  OpenGL: OpenGL vendor  : NVIDIA Corporation
2012-12-25 21:37:37.772495 I  OpenGL: OpenGL renderer: GeForce 6150/PCI/SSE2/3DNOW!
2012-12-25 21:37:37.772502 I  OpenGL: OpenGL version : 2.1.0 NVIDIA 96.43.19
2012-12-25 21:37:37.772524 I  OpenGL: Max texture size: 4096 x 4096
2012-12-25 21:37:37.772531 I  OpenGL: Max texture units: 4
2012-12-25 21:37:37.772543 I  OpenGL: Direct rendering: Yes
2012-12-25 21:37:37.772551 I  OpenGL: PixelBufferObject support available
2012-12-25 21:37:37.772561 I  OpenGL: Initialised MythRenderOpenGL
2012-12-25 21:37:37.991341 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 26
			Name: 'basemedium'	Type: 'fontdef'
2012-12-25 21:37:37.991485 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 30
			Name: 'basemediumbold'	Type: 'fontdef'
2012-12-25 21:37:38.008882 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 35
			Name: 'baselarge'	Type: 'fontdef'
2012-12-25 21:37:38.034848 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 43
			Name: 'baselargenormal'	Type: 'fontdef'
2012-12-25 21:37:38.051802 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 47
			Name: 'baseextralarge'	Type: 'fontdef'
2012-12-25 21:37:38.053195 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 111
			Name: 'basemedium_normal'	Type: 'fontdef'
2012-12-25 21:37:38.053285 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 114
			Name: 'basemedium_normal_selected'	Type: 'fontdef'
2012-12-25 21:37:38.053372 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 117
			Name: 'basemedium_disabled'	Type: 'fontdef'
2012-12-25 21:37:38.053458 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 120
			Name: 'basemedium_highlight'	Type: 'fontdef'
2012-12-25 21:37:38.053545 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 123
			Name: 'basemedium_normal_button'	Type: 'fontdef'
2012-12-25 21:37:38.053632 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 126
			Name: 'basemedium_selected_button'	Type: 'fontdef'
2012-12-25 21:37:38.053719 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 129
			Name: 'basemedium_disabled_button'	Type: 'fontdef'
2012-12-25 21:37:38.071118 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 134
			Name: 'deleterecordings_filesize_font'	Type: 'fontdef'
2012-12-25 21:37:38.277293 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 1073
			Name: 'large'	Type: 'fontdef'
2012-12-25 21:37:38.277460 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 1079
			Name: 'clock'	Type: 'fontdef'
2012-12-25 21:37:38.302038 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/default-wide/base.xml @ 60
			Name: 'basemediumgrey'	Type: 'fontdef'
2012-12-25 21:37:38.302134 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/default-wide/base.xml @ 64
			Name: 'basemediumgreen'	Type: 'fontdef'
2012-12-25 21:37:38.302219 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/default-wide/base.xml @ 68
			Name: 'basemediumred'	Type: 'fontdef'
2012-12-25 21:37:38.302304 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/default-wide/base.xml @ 72
			Name: 'basemediumpurple'	Type: 'fontdef'
2012-12-25 21:37:38.360299 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/default/base.xml @ 80
			Name: 'basemediumyellow'	Type: 'fontdef'
2012-12-25 21:37:38.407052 I  Current MythTV Schema Version (DBSchemaVer): 1299
2012-12-25 21:37:38.429174 I  VDP: decoder<->render support: ffmpeg      null xlib xshm xv-blit opengl opengl-lite vdpau nullvdpau nullvaapi openglvaapi
2012-12-25 21:37:38.429193 I  VDP: decoder<->render support: vdpau       vdpau nullvdpau
2012-12-25 21:37:38.429204 I  VDP: decoder<->render support: vaapi       nullvaapi openglvaapi
2012-12-25 21:37:38.429217 I  VDP: decoder<->render support: crystalhd   null xlib xshm xv-blit opengl opengl-lite vdpau nullvdpau nullvaapi
2012-12-25 21:37:38.432616 N  VDP: Ignoring profile item 15 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2012-12-25 21:37:38.432689 N  VDP: Ignoring profile item 16 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2012-12-25 21:37:38.433614 N  VDP: Ignoring profile item 19 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2012-12-25 21:37:38.433677 N  VDP: Ignoring profile item 20 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2012-12-25 21:37:38.436392 N  VDP: Ignoring profile item 23 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2012-12-25 21:37:38.436454 N  VDP: Ignoring profile item 24 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi))
2012-12-25 21:37:41.199433 N  Registering Internal as a media playback plugin.
2012-12-25 21:37:41.379680 I  Loading en_us translation for module mytharchive
2012-12-25 21:37:41.387442 N  Registering WebBrowser as a media playback plugin.
2012-12-25 21:37:41.398652 I  Loading en_us translation for module mythbrowser
2012-12-25 21:37:41.482853 I  Loading en_us translation for module mythgallery
2012-12-25 21:37:41.503170 I  Loading en_us translation for module mythgame
2012-12-25 21:37:41.592308 I  Current MythMusic Schema Version (MusicDBSchemaVer): 1019
2012-12-25 21:37:41.616681 I  Loading en_us translation for module mythmusic
2012-12-25 21:37:41.620902 I  Loading en_us translation for module mythnetvision
2012-12-25 21:37:41.632365 I  Loading en_us translation for module mythnews
2012-12-25 21:37:41.641189 !  MythPlugin::init() dlerror: libmythdb-0.24.so.0: cannot open shared object file: No such file or directory
2012-12-25 21:37:41.641246 E  Unable to initialize plugin 'mythsmolt'.
2012-12-25 21:37:41.694534 !  Plugin mythstream (0.24.20110505-1) binary version does not match libraries (0.25.20120506-1)
2012-12-25 21:37:41.694602 E  Unable to initialize plugin 'mythstream'.
2012-12-25 21:37:41.707051 I  Loading en_us translation for module mythweather
2012-12-25 21:37:41.710257 I  Listening on TCP 127.0.0.1:6546
2012-12-25 21:37:41.710410 I  Listening on TCP 192.168.1.210:6546
2012-12-25 21:37:41.710618 I  Listening on TCP [0:0:0:0:0:0:0:1]:6546
2012-12-25 21:37:41.710795 I  Listening on TCP [fe80:0:0:0:21a:92ff:fecb:5342%eth0]:6546
2012-12-25 21:37:41.727939 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
			Location: /usr/share/mythtv/themes/MythCenter-wide/menu-ui.xml @ 8
			Name: 'menufont'	Type: 'fontdef'
2012-12-25 21:37:41.928193 N  Found mainmenu.xml for theme 'MythCenter-wide'
2012-12-25 21:37:42.176511 I  MythCoreContext: Connecting to backend server: 192.168.1.210:6543 (try 1 of 1)
2012-12-25 21:37:42.178233 I  Using protocol version 72
2012-12-25 21:37:42.223911 I  Bonjour: Service registration complete: name 'Mythfrontend on LinHES' type '_mythfrontend._tcp.' domain: 'local.'
2012-12-25 21:37:50.172151 I  TV: Creating TV object
2012-12-25 21:37:50.244421 I  TV: Finished creating TV object
2012-12-25 21:37:50.244549 I  TV: StartTV() -- begin
2012-12-25 21:37:50.244748 N  Resuming idle timer
2012-12-25 21:37:50.244817 N  Suspending idle timer
2012-12-25 21:37:50.244841 I  TV: Init -- begin
2012-12-25 21:37:50.251503 I  TV: Created TvPlayWindow.
2012-12-25 21:37:50.252164 I  TV: DrawUnusedRects() -- begin
2012-12-25 21:37:50.252192 I  TV: DrawUnusedRects() -- end
2012-12-25 21:37:50.256529 I  TV: Init -- end
2012-12-25 21:37:50.270013 I  TV: tv->LiveTV() -- begin
2012-12-25 21:37:50.282484 I  TV: HandleStateChange(0) -- begin
2012-12-25 21:37:50.282529 I  TV: Attempting to change from None to WatchingLiveTV
2012-12-25 21:37:50.282595 I  MythCoreContext: Connecting to backend server: 192.168.1.210:6543 (try 1 of 1)
2012-12-25 21:37:50.283764 I  Using protocol version 72
2012-12-25 21:37:50.352311 I  MythCoreContext: Connecting to backend server: 192.168.1.210:6543 (try 1 of 1)
2012-12-25 21:37:50.353231 I  Using protocol version 72
2012-12-25 21:37:50.353907 N  TV: Spawning LiveTV Recorder -- begin
2012-12-25 21:37:50.462363 N  TV: Spawning LiveTV Recorder -- end
2012-12-25 21:37:50.463804 I  LiveTVChain(live-LinHES-2012-12-25T21:37:50): ReloadAll(): Added new recording
2012-12-25 21:37:50.463893 I  LiveTVChain(live-LinHES-2012-12-25T21:37:50): LiveTVChain has 1 entries
*  DUMMY: 3923 (21:37:50 to 00:00:00)

2012-12-25 21:37:50.479566 I  TV: playbackURL(myth://192.168.1.210:6543/3923_20121225213750.mpg) cardtype(DUMMY)
2012-12-25 21:37:50.482068 I  TV: StartRecorder(): took 1 ms to start recorder.
2012-12-25 21:37:50.482127 I  TV: StartPlayer(0, WatchingLiveTV, main) -- begin
2012-12-25 21:37:50.482158 I  TV: Elapsed time since TV constructor was called: 310 ms
2012-12-25 21:37:50.831366 N  AudioPlayer: Enabling Audio
2012-12-25 21:37:50.831487 I  Player(0): SetFrameInterval ps:1 scan:3
2012-12-25 21:37:50.831542 I  Player(0): detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
2012-12-25 21:37:50.834188 I  Player(0): Stretch Factor 1, allow passthru 
2012-12-25 21:37:50.835805 I  VideoOutput: Allowed renderers: xv-blit,xshm,xlib,opengl,opengl-lite,vdpau,openglvaapi
2012-12-25 21:37:50.835868 I  VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,openglvaapi
2012-12-25 21:37:50.840635 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:37:50.840725 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:37:50.840776 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:37:50.840829 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:37:50.840866 I  VDP: LoadBestPreferences(720x576, 60)
2012-12-25 21:37:50.840929 I  VideoOutput: Preferred renderer: xv-blit
2012-12-25 21:37:50.840984 I  VideoOutput: Trying video renderer: 'xv-blit'
2012-12-25 21:37:50.863785 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:37:50.864048 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:37:50.864081 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:37:50.864128 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:37:50.864192 I  VideoOutputXv: ctor
2012-12-25 21:37:50.864243 I  VideoOutWindow::SetPIPState. pip_state: 0]
2012-12-25 21:37:50.864272 I  VDP: LoadBestPreferences(2048x2048, 25)
2012-12-25 21:37:50.866548 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.33333
2012-12-25 21:37:50.866588 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
2012-12-25 21:37:50.866614 I  VDP: LoadBestPreferences(720x576, 25)
2012-12-25 21:37:50.866686 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.33333
2012-12-25 21:37:50.866714 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
2012-12-25 21:37:50.869020 I  VideoOutput: Pixel dimensions: Screen 1280x720, window 1280x720
2012-12-25 21:37:50.869070 I  VideoOutput: Actual display dimensions: 325x183 mm  Aspect: 1.77596
2012-12-25 21:37:50.869096 I  VideoOutput: Estimated window dimensions: 325x183 mm  Aspect: 1.77596
2012-12-25 21:37:50.869934 I  VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
2012-12-25 21:37:50.872277 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:37:50.872359 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:37:50.872390 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:37:50.872439 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:37:50.872478 I  VDP: LoadBestPreferences(720x576, 60)
2012-12-25 21:37:50.872769 I  VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  2
2012-12-25 21:37:50.872798 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask 
2012-12-25 21:37:50.872811 I  VideoOutputXv: Has XVideo flags...
2012-12-25 21:37:50.873165 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2012-12-25 21:37:50.873193 I  VideoOutputXv: Adaptor#1: NV05 Video Blitter has flag[s]: XvInputMask XvImageMask 
2012-12-25 21:37:50.873204 I  VideoOutputXv: Has XVideo flags...
2012-12-25 21:37:50.873478 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2012-12-25 21:37:50.873505 I  VideoOutputXv: @ j=1 Looking for flag[s]: XvInputMask XvImageMask  0
2012-12-25 21:37:50.873528 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask 
2012-12-25 21:37:50.873539 I  VideoOutputXv: Has XVideo flags...
2012-12-25 21:37:50.873723 I  VideoOutputXv: Grabbed xv port 281
2012-12-25 21:37:50.874102 I  VideoOutputXv: XVideo surface found on port 281
2012-12-25 21:37:50.874120 I  VideoOutputXv: XV_SET_DEFAULTS is supported on this port
2012-12-25 21:37:50.874400 I  VideoOutputXv: XV_SYNC_TO_VBLANK supported
2012-12-25 21:37:50.874423 I  VideoOutputXv: XVideo Sync to VBlank set
2012-12-25 21:37:50.874466 I  VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2012-12-25 21:37:50.874606 I  VideoOutputXv: XVideo Format #0 is 'YUY2'
2012-12-25 21:37:50.874632 I  VideoOutputXv: XVideo Format #1 is 'YV12'
2012-12-25 21:37:50.874652 I  VideoOutputXv: XVideo Format #2 is 'UYVY'
2012-12-25 21:37:50.874675 I  VideoOutputXv: XVideo Format #3 is 'I420'
2012-12-25 21:37:50.874694 I  VideoOutputXv: Using XVideo Format 'YV12'
2012-12-25 21:37:50.874726 I  VideoOutputXv: CreateShmImages(32): video_dim: 720x576
2012-12-25 21:37:50.958313 I  VDP: SetVideoRenderer(xv-blit)
2012-12-25 21:37:50.958353 I  VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2012-12-25 21:37:50.959360 I  ColourSpace: PictureAttributes: 
2012-12-25 21:37:50.959629 I  VideoOutputXv: Chromakeying not possible with this XVideo port.
2012-12-25 21:37:50.959697 I  Display Rect  left: 160, top: 0, width: 960, height: 720, aspect: 1.77778
2012-12-25 21:37:50.959726 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
2012-12-25 21:37:50.962871 I  Over/underscan. V: 0.02, H: 0.02
2012-12-25 21:37:50.970759 I  Display Rect  left: 160, top: 0, width: 960, height: 720, aspect: 1.77778
2012-12-25 21:37:50.970798 I  Video Rect    left: 14, top: 12, width: 691, height: 553, aspect: 1.33333
2012-12-25 21:37:50.970856 I  Player(0): LoadFilters(''..) -> 0x0
2012-12-25 21:37:50.977499 I  OSD: Base theme size: 1280x720
2012-12-25 21:37:50.977561 I  OSD: Scaling factors: 0.540625x0.769444
2012-12-25 21:37:50.977795 I  Player(0): Decoder thread starting.
2012-12-25 21:37:51.064759 I  OSD: Loaded window osd_message
2012-12-25 21:37:51.074888 I  OSD: Loaded window osd_input
2012-12-25 21:37:51.316320 I  OSD: Loaded window program_info
2012-12-25 21:37:51.322850 I  OSD: Loaded window browse_info
2012-12-25 21:37:51.331878 I  OSD: Loaded window osd_status
2012-12-25 21:37:51.339856 I  OSD: Loaded window osd_program_editor
2012-12-25 21:37:51.494382 I  OSD: Loaded window osd_debug
2012-12-25 21:37:51.494532 I  OSD: Loaded OSD: size 692x554 offset 14+11
2012-12-25 21:37:51.494668 I  OSD: Base theme size: 1280x720
2012-12-25 21:37:51.494706 I  OSD: Scaling factors: 0.540625x0.769444
2012-12-25 21:37:51.498798 I  OSD: Created window aa_OSD_SUBTITLES
2012-12-25 21:37:51.498933 I  Player(0): ClearAfterSeek(0)
2012-12-25 21:37:51.501450 I  VDP: GetFilteredDeint() : xv-blit -> 'greedyhdoubleprocessdeint'
2012-12-25 21:37:51.503613 I  FilterManager: GetFilterInfo(convert) returning: 0x0
2012-12-25 21:37:51.503660 I  FilterManager: GetFilterInfo(greedyhdoubleprocessdeint) returning: 0x9a74c58
2012-12-25 21:37:51.508798 I  VideoOutput: Using deinterlace method greedyhdoubleprocessdeint
2012-12-25 21:37:51.509048 I  VSYNC: DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2012-12-25 21:37:51.509173 E  VSYNC: RTCVideoSync: Could not set RTC frequency: 
			eno: Permission denied (13)
2012-12-25 21:37:51.512197 I  Player(0): Video timing method: USleep with busy wait
2012-12-25 21:37:51.512251 I  Player(0): Display Refresh Rate: 60.074 Video Frame Rate: 25.000
2012-12-25 21:37:51.512277 I  Player(0): SetFrameInterval ps:1 scan:1
2012-12-25 21:37:51.513122 I  playCtx: StartPlaying(): took 0 ms to start player.
2012-12-25 21:37:51.513181 I  TV: Created player.
2012-12-25 21:37:51.513236 I  TV: StartPlayer(0, WatchingLiveTV, main) -- end ok
2012-12-25 21:37:51.513287 I  TV: Changing from None to WatchingLiveTV
2012-12-25 21:37:51.513307 I  TV: State is LiveTV & mctx == ctx
2012-12-25 21:37:51.516710 I  TV: UpdateOSDInput done
2012-12-25 21:37:51.516799 I  TV: UpdateLCD done
2012-12-25 21:37:51.517710 I  TV: ITVRestart done
2012-12-25 21:37:51.537885 I  TV: DrawUnusedRects() -- begin
2012-12-25 21:37:51.537918 I  TV: DrawUnusedRects() -- end
2012-12-25 21:37:51.538113 I  TV: Main UI disabled.
2012-12-25 21:37:51.538139 I  TV: HandleStateChange(0) -- end
2012-12-25 21:37:51.539048 I  TV: tv->LiveTV() -- end
2012-12-25 21:37:51.539063 I  TV: Entering main playback loop.
2012-12-25 21:37:51.582512 I  LiveTVChain(live-LinHES-2012-12-25T21:37:50): ReloadAll(): Added new recording
2012-12-25 21:37:51.582619 I  LiveTVChain(live-LinHES-2012-12-25T21:37:50): LiveTVChain has 2 entries
*  DUMMY: 3923 (21:37:50 to 21:37:51)
  HDHOMERUN: 3923 (21:37:51 to 00:00:00) discontinuous

2012-12-25 21:37:51.586703 I  LiveTVChain(live-LinHES-2012-12-25T21:37:50): SwitchTo(1)
2012-12-25 21:37:51.586752 I  Player(0): JumpToProgram - start
2012-12-25 21:37:51.595405 I  Player(0): LoadFilters(''..) -> 0x0
2012-12-25 21:37:51.596209 I  Player(0): Play speed: rate: 25 speed: 0 skip: 0 => new interval 40000
2012-12-25 21:37:51.596919 I  VDP: GetFilteredDeint(linearblend) : xv-blit -> 'linearblend'
2012-12-25 21:37:51.598837 I  FilterManager: GetFilterInfo(convert) returning: 0x0
2012-12-25 21:37:51.598875 I  FilterManager: GetFilterInfo(linearblend) returning: 0x9a74d60
2012-12-25 21:37:51.598981 I  VideoOutput: Using deinterlace method linearblend
2012-12-25 21:37:51.602915 I  FileRingBuf(myth://192.168.1.210:6543/3923_20121225213750.mpg): OpenFile(myth://192.168.1.210:6543/3923_20121225213751.mpg, 10000 ms)
2012-12-25 21:37:51.604580 I  Using protocol version 72
2012-12-25 21:37:51.606338 I  Using protocol version 72
2012-12-25 21:37:52.047113 I  Player(0): Stretch Factor 1, allow passthru 
2012-12-25 21:37:52.047897 I  AFD: Buffer size: 32768, streamed 1
2012-12-25 21:37:52.264262 I  AFD: Stream #0, has id 0x7c0 codec id MPEG2VIDEO, type Video, bitrate 20000000 at 0x9c06f60
2012-12-25 21:37:52.267055 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:37:52.267219 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:37:52.267258 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:37:52.267312 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:37:52.267351 I  VDP: LoadBestPreferences(1280x720, 60)
2012-12-25 21:37:52.267716 I  AFD: Using 2 CPUs for decoding
2012-12-25 21:37:52.267748 I  AFD: InitVideoCodec() 0x9e95030 id(MPEG2VIDEO) type (Video).
2012-12-25 21:37:52.267877 I  AFD: Selected FPS is 59.9401 (avg 59.96 codec 59.9401 container 90000 estimated 59.9401)
2012-12-25 21:37:52.267907 I  Player(0): SetFrameInterval ps:0 scan:3
2012-12-25 21:37:52.270321 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:37:52.270409 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:37:52.270440 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:37:52.270490 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:37:52.270529 I  VDP: LoadBestPreferences(1280x720, 60)
2012-12-25 21:37:52.270689 I  VDP: LoadBestPreferences(720x576, 59.9401)
2012-12-25 21:37:52.270779 I  VideoOutputXv: InputChanged(1280,720,1.33333) 'None'->'MPEG2'
2012-12-25 21:37:52.270806 I  VDP: LoadBestPreferences(1280x720, 59.9401)
2012-12-25 21:37:52.270938 I  VDP: GetFilteredDeint() : xv-blit -> 'bobdeint'
2012-12-25 21:37:52.274582 I  FilterManager: GetFilterInfo(convert) returning: 0x0
2012-12-25 21:37:52.274633 I  FilterManager: GetFilterInfo(bobdeint) returning: 0xa0a8698
2012-12-25 21:37:52.274700 I  VideoOutput: Using deinterlace method bobdeint
2012-12-25 21:37:52.274719 I  VideoOutputXv: DiscardFrames(1)
2012-12-25 21:37:52.274834 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
2012-12-25 21:37:52.274912 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
2012-12-25 21:37:52.275020 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
2012-12-25 21:37:52.275035 I  VideoOutputXv: DiscardFrames(1)
2012-12-25 21:37:52.275117 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
2012-12-25 21:37:52.275181 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
2012-12-25 21:37:52.275241 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
2012-12-25 21:37:52.279556 I  VideoOutputXv: Closing XVideo port 281
2012-12-25 21:37:52.310335 I  VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
2012-12-25 21:37:52.314165 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:37:52.314251 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:37:52.314282 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:37:52.314330 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:37:52.314367 I  VDP: LoadBestPreferences(1280x720, 60)
2012-12-25 21:37:52.319406 I  VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  2
2012-12-25 21:37:52.319452 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask 
2012-12-25 21:37:52.319465 I  VideoOutputXv: Has XVideo flags...
2012-12-25 21:37:52.324066 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2012-12-25 21:37:52.324120 I  VideoOutputXv: Adaptor#1: NV05 Video Blitter has flag[s]: XvInputMask XvImageMask 
2012-12-25 21:37:52.324134 I  VideoOutputXv: Has XVideo flags...
2012-12-25 21:37:52.331687 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2012-12-25 21:37:52.331734 I  VideoOutputXv: @ j=1 Looking for flag[s]: XvInputMask XvImageMask  0
2012-12-25 21:37:52.331748 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask 
2012-12-25 21:37:52.331755 I  VideoOutputXv: Has XVideo flags...
2012-12-25 21:37:52.333729 I  VideoOutputXv: Grabbed xv port 281
2012-12-25 21:37:52.333818 I  VideoOutputXv: XVideo surface found on port 281
2012-12-25 21:37:52.333827 I  VideoOutputXv: XV_SET_DEFAULTS is supported on this port
2012-12-25 21:37:52.333931 I  VideoOutputXv: XV_SYNC_TO_VBLANK supported
2012-12-25 21:37:52.333977 I  VideoOutputXv: XVideo Sync to VBlank set
2012-12-25 21:37:52.334010 I  VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2012-12-25 21:37:52.334119 I  VideoOutputXv: XVideo Format #0 is 'YUY2'
2012-12-25 21:37:52.334130 I  VideoOutputXv: XVideo Format #1 is 'YV12'
2012-12-25 21:37:52.334139 I  VideoOutputXv: XVideo Format #2 is 'UYVY'
2012-12-25 21:37:52.334148 I  VideoOutputXv: XVideo Format #3 is 'I420'
2012-12-25 21:37:52.334157 I  VideoOutputXv: Using XVideo Format 'YV12'
2012-12-25 21:37:52.334169 I  VideoOutputXv: CreateShmImages(32): video_dim: 1280x720
2012-12-25 21:37:52.440106 I  VDP: SetVideoRenderer(xv-blit)
2012-12-25 21:37:52.440126 I  VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2012-12-25 21:37:52.440545 I  ColourSpace: PictureAttributes: 
2012-12-25 21:37:52.440661 I  VideoOutputXv: Chromakeying not possible with this XVideo port.
2012-12-25 21:37:52.440697 I  Display Rect  left: 160, top: 0, width: 960, height: 720, aspect: 1.77778
2012-12-25 21:37:52.440712 I  Video Rect    left: 26, top: 14, width: 1229, height: 691, aspect: 1.33333
2012-12-25 21:37:52.442375 I  OSD: Base theme size: 1280x720
2012-12-25 21:37:52.442408 I  OSD: Scaling factors: 0.960938x0.961111
2012-12-25 21:37:52.474670 I  OSD: Loaded window osd_message
2012-12-25 21:37:52.486343 I  OSD: Loaded window osd_input
2012-12-25 21:37:52.612630 I  OSD: Loaded window program_info
2012-12-25 21:37:52.624916 I  OSD: Loaded window browse_info
2012-12-25 21:37:52.634434 I  OSD: Loaded window osd_status
2012-12-25 21:37:52.639395 I  OSD: Loaded window osd_program_editor
2012-12-25 21:37:52.693313 I  OSD: Loaded window osd_debug
2012-12-25 21:37:52.693392 I  OSD: Loaded OSD: size 1230x692 offset 25+14
2012-12-25 21:37:52.693442 I  EnableCaptions(0) msg:  On
2012-12-25 21:37:52.693455 I  Player(0): ClearAfterSeek(1)
2012-12-25 21:37:52.693461 I  VideoOutputXv: ClearAfterSeek()
2012-12-25 21:37:52.693468 I  VideoOutputXv: DiscardFrames(0)
2012-12-25 21:37:52.693503 I  VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
2012-12-25 21:37:52.693534 I  VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
2012-12-25 21:37:52.693565 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
2012-12-25 21:37:52.693585 I  Player(0): LoadFilters(''..) -> 0x0
2012-12-25 21:37:52.693610 I  Player(0): detectInterlace(Detect Scan, Interlaced Scan, 59.9401, 720) ->Progressive Scan
2012-12-25 21:37:52.693621 I  Player(0): Disabled deinterlacing
2012-12-25 21:37:52.693715 I  AFD: EIA-608 caption service #1 is in the English language.
2012-12-25 21:37:52.693724 I  AFD: EIA-608 caption service #1 is in the English language.
2012-12-25 21:37:52.693734 I  AFD: Using ffmpeg for video decoding
2012-12-25 21:37:52.693742 I  AFD: Looking for decoder for MPEG2VIDEO
2012-12-25 21:37:52.693997 I  AFD: Opened codec 0x9e95030, id(MPEG2VIDEO) type(Video)
2012-12-25 21:37:52.694017 I  AFD: Stream #1, has id 0x7c1 codec id AC3, type Audio, bitrate 448000 at 0x9c1d920
2012-12-25 21:37:52.694028 I  AFD: codec AC3 has 6 channels
2012-12-25 21:37:52.694034 I  AFD: Looking for decoder for AC3
2012-12-25 21:37:52.694523 I  AFD: Opened codec 0x9c1db10, id(AC3) type(Audio)
2012-12-25 21:37:52.721385 I  AO: Opening audio device 'iec958:CARD=NVidia,DEV=0' ch 2(6) sr 48000 sf signed 16 bit reenc 0
2012-12-25 21:37:52.729836 E  ALSA: Setting hardware audio buffer size to 128
2012-12-25 21:37:52.729975 E  ALSA: Error opening /proc/asound/card0/pcm1p/sub0/prealloc: Permission denied. 
2012-12-25 21:37:52.729988 E  ALSA: Try to manually increase audio buffer with: echo 128 | sudo tee /proc/asound/card0/pcm1p/sub0/prealloc
2012-12-25 21:37:52.730005 E  ALSA: Unable to sufficiently increase ALSA hardware buffer size - underruns are likely
2012-12-25 21:37:52.738239 I  Dec: Selected track #1 in the Unknown language(0)
2012-12-25 21:37:52.738259 I  Dec: Trying to select track (w/lang & forced)
2012-12-25 21:37:52.738271 I  Dec: Selected track #1 in the English language(6647399)
2012-12-25 21:37:52.738291 I  Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
2012-12-25 21:37:52.739125 I  Dec: Position map filled from DB to: 28
2012-12-25 21:37:52.739141 I  Dec: SyncPositionMap watchingrecording, from DB: 3 entries
2012-12-25 21:37:52.739165 I  Player(0): Filling position map from 29 to end
2012-12-25 21:37:52.739704 I  Dec: Position map filled from Encoder to: 49
2012-12-25 21:37:52.739716 I  Dec: SyncPositionMap watchingrecording total: 4 entries
2012-12-25 21:37:52.739732 I  Dec: SyncPositionMap, new totframes: 49, new length: 0, posMap size: 4
2012-12-25 21:37:52.742155 I  AFD: Partial position map found
2012-12-25 21:37:52.742178 I  AFD: Successfully opened decoder for file: "myth://192.168.1.210:6543/3923_20121225213751.mpg". novideo(0)
2012-12-25 21:37:52.757500 N  AFD: Resetting byte context eof (livetv 1 was eof 0)
2012-12-25 21:37:52.913973 I  Player(0): Play(  1.0, normal 1, unpause audio 1)
2012-12-25 21:37:52.914387 I  Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
2012-12-25 21:37:52.915478 I  Dec: Position map filled from DB to: 28
2012-12-25 21:37:52.915494 I  Dec: SyncPositionMap watchingrecording, from DB: 3 entries
2012-12-25 21:37:52.915509 I  Player(0): Filling position map from 29 to end
2012-12-25 21:37:52.915639 I  Player(0): Play speed: rate: 59.9401 speed: 1 skip: 1 => new interval 16683
2012-12-25 21:37:52.915677 I  Player(0): Stretch Factor 1, allow passthru 
2012-12-25 21:37:52.915687 I  Player(0): JumpToProgram - end
2012-12-25 21:37:52.915719 I  Player(0): Waiting for video buffers...
2012-12-25 21:37:52.916084 I  Dec: Position map filled from Encoder to: 62
2012-12-25 21:37:52.916096 I  Dec: SyncPositionMap watchingrecording total: 5 entries
2012-12-25 21:37:52.916110 I  Dec: SyncPositionMap, new totframes: 62, new length: 1, posMap size: 5
2012-12-25 21:37:52.932622 I  Detect Letterbox: YV12 frame format detected
2012-12-25 21:37:53.018055 N  Player(0): Waited 102ms for video buffers uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
2012-12-25 21:37:53.119791 N  Player(0): Waited 204ms for video buffers uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
2012-12-25 21:37:53.183456 I  Detect Letterbox: YV12 frame format detected
2012-12-25 21:37:53.220685 N  Player(0): Waited 305ms for video buffers UUUUuUULLAAAAAAAAAAAAAAAAAAAAAAP
2012-12-25 21:37:53.253431 I  Player(0): Video Aspect ratio changed from 1.33333 to 1.77778
2012-12-25 21:37:53.253464 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2012-12-25 21:37:53.253483 I  Video Rect    left: 26, top: 14, width: 1229, height: 691, aspect: 1.77778
2012-12-25 21:37:53.254030 I  OSD: Base theme size: 1280x720
2012-12-25 21:37:53.254068 I  OSD: Scaling factors: 0.960938x0.961111
2012-12-25 21:37:53.259783 I  Detect Letterbox: The source is already in widescreen (aspect: 1.77778)
2012-12-25 21:37:53.268388 I  OSD: Loaded window osd_message
2012-12-25 21:37:53.286814 I  OSD: Loaded window osd_input
2012-12-25 21:37:53.311466 I  OSD: Loaded window program_info
2012-12-25 21:37:53.326410 I  OSD: Loaded window browse_info
2012-12-25 21:37:53.338472 I  OSD: Loaded window osd_status
2012-12-25 21:37:53.343658 I  OSD: Loaded window osd_program_editor
2012-12-25 21:37:53.356469 I  OSD: Loaded window osd_debug
2012-12-25 21:37:53.356496 I  OSD: Loaded OSD: size 1230x692 offset 25+14
2012-12-25 21:37:53.356540 I  EnableCaptions(0) msg:  On
2012-12-25 21:37:53.390405 I  VideoOutput: Created YV12 OSD.
2012-12-25 21:37:53.394993 I  Player(0): Video is 3.13193 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:37:53.395070 I  Player(0): Video is 5.24108 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:37:53.395129 I  Player(0): Video is 6.58317 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:37:53.395225 I  Player(0): Video is 7.33501 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:37:53.395284 I  Player(0): Video is 7.64413 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:37:53.395341 I  Player(0): Video is 7.63622 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:37:53.395423 I  Player(0): Video is 7.39052 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:37:53.395490 I  Player(0): Video is 6.95151 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:37:53.395549 I  Player(0): Video is 6.38249 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:37:53.395622 I  Player(0): Video is 5.70095 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:37:53.395680 I  Player(0): Video is 4.93502 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:37:53.395737 I  Player(0): Video is 4.10586 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:37:53.395795 I  Player(0): Video is 3.2442 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:37:54.847065 I  Player(0): FPS:   68.90 Mean: 14513 Std.Dev:  6087 
2012-12-25 21:37:56.515501 I  Player(0): FPS:   60.01 Mean: 16663 Std.Dev:   230 CPUs: 45% 47% 
2012-12-25 21:37:58.015718 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2012-12-25 21:37:58.015781 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2012-12-25 21:37:58.033884 I  TV: HandleStateChange(0) -- begin
2012-12-25 21:37:58.033934 I  TV: Attempting to change from WatchingLiveTV to None
2012-12-25 21:37:58.033994 I  TV: StopStuff() for player ctx 0 -- begin
2012-12-25 21:37:58.034024 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2012-12-25 21:37:58.034060 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2012-12-25 21:37:58.034084 I  Player(0): StopPlaying - begin
2012-12-25 21:37:58.043517 I  Player(0): Decoder thread exiting.
2012-12-25 21:37:58.043975 I  Player(0): Exited decoder loop.
2012-12-25 21:37:58.044902 I  VideoOutputXv: dtor
2012-12-25 21:37:58.044930 I  VideoOutputXv: DiscardFrames(1)
2012-12-25 21:37:58.045040 I  VideoBuffers::DiscardFrames(1): UUUAUAAAAUAAUAUUUUUAUUUAUUUuUULP
2012-12-25 21:37:58.045147 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
2012-12-25 21:37:58.045218 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
2012-12-25 21:37:58.052372 I  VideoOutputXv: Closing XVideo port 281
2012-12-25 21:37:58.134744 I  Player(0): StopPlaying - end
2012-12-25 21:37:58.134775 I  TV: StopStuff(): stopping ring buffer
2012-12-25 21:37:58.255168 I  TV: StopStuff(): stopping player
2012-12-25 21:37:58.255200 I  TV: StopStuff(): stopping recorder
2012-12-25 21:37:58.350820 I  TV: StopStuff() -- end
2012-12-25 21:37:58.350864 I  TV: Changing from WatchingLiveTV to None
2012-12-25 21:37:58.351551 I  TV: HandleStateChange(0) -- end
2012-12-25 21:37:58.351592 I  TV: Exiting main playback loop.
2012-12-25 21:37:58.351611 I  TV: StartTV -- process events 2 begin
2012-12-25 21:37:58.352388 I  TV: StartTV -- process events 2 end
2012-12-25 21:37:58.352439 I  TV::~TV() -- begin
2012-12-25 21:37:58.363103 I  TV::~TV() -- lock
2012-12-25 21:37:58.364322 I  Player(0): StopPlaying - begin
2012-12-25 21:37:58.364369 I  Player(0): Exited decoder loop.
2012-12-25 21:37:58.364392 I  Player(0): StopPlaying - end
2012-12-25 21:37:58.372338 I  TV::~TV() -- end
2012-12-25 21:37:58.380349 N  Resuming idle timer
2012-12-25 21:37:58.380384 I  TV: StartTV -- end
2012-12-25 21:38:31.523336 I  TV: Creating TV object
2012-12-25 21:38:31.562847 I  TV: Finished creating TV object
2012-12-25 21:38:31.563007 I  TV: StartTV() -- begin
2012-12-25 21:38:31.563126 N  Resuming idle timer
2012-12-25 21:38:31.563187 N  Suspending idle timer
2012-12-25 21:38:31.563199 I  TV: Init -- begin
2012-12-25 21:38:31.566889 I  TV: Created TvPlayWindow.
2012-12-25 21:38:31.567467 I  TV: DrawUnusedRects() -- begin
2012-12-25 21:38:31.567487 I  TV: DrawUnusedRects() -- end
2012-12-25 21:38:31.568600 I  TV: Init -- end
2012-12-25 21:38:31.581938 I  TV: tv->LiveTV() -- begin
2012-12-25 21:38:31.594144 I  TV: HandleStateChange(0) -- begin
2012-12-25 21:38:31.594184 I  TV: Attempting to change from None to WatchingLiveTV
2012-12-25 21:38:31.594242 I  MythCoreContext: Connecting to backend server: 192.168.1.210:6543 (try 1 of 1)
2012-12-25 21:38:31.595846 I  Using protocol version 72
2012-12-25 21:38:31.646914 I  MythCoreContext: Connecting to backend server: 192.168.1.210:6543 (try 1 of 1)
2012-12-25 21:38:31.647822 I  Using protocol version 72
2012-12-25 21:38:31.648678 N  TV: Spawning LiveTV Recorder -- begin
2012-12-25 21:38:31.710445 N  TV: Spawning LiveTV Recorder -- end
2012-12-25 21:38:31.712659 I  LiveTVChain(live-LinHES-2012-12-25T21:38:31): ReloadAll(): Added new recording
2012-12-25 21:38:31.712732 I  LiveTVChain(live-LinHES-2012-12-25T21:38:31): LiveTVChain has 1 entries
*  DUMMY: 3923 (21:38:31 to 00:00:00)

2012-12-25 21:38:31.721913 I  TV: playbackURL(myth://192.168.1.210:6543/3923_20121225213831.mpg) cardtype(DUMMY)
2012-12-25 21:38:31.724359 I  TV: StartRecorder(): took 1 ms to start recorder.
2012-12-25 21:38:31.724397 I  TV: StartPlayer(0, WatchingLiveTV, main) -- begin
2012-12-25 21:38:31.724423 I  TV: Elapsed time since TV constructor was called: 201 ms
2012-12-25 21:38:31.838168 N  AudioPlayer: Enabling Audio
2012-12-25 21:38:31.838250 I  Player(1): SetFrameInterval ps:1 scan:3
2012-12-25 21:38:31.838304 I  Player(1): detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
2012-12-25 21:38:31.838419 I  Player(1): Stretch Factor 1, allow passthru 
2012-12-25 21:38:31.840318 I  VideoOutput: Allowed renderers: xv-blit,xshm,xlib,opengl,opengl-lite,vdpau,openglvaapi
2012-12-25 21:38:31.840389 I  VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,openglvaapi
2012-12-25 21:38:31.842634 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:38:31.842725 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:38:31.842762 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:38:31.842818 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:38:31.842863 I  VDP: LoadBestPreferences(720x576, 60)
2012-12-25 21:38:31.842912 I  VideoOutput: Preferred renderer: xv-blit
2012-12-25 21:38:31.843061 I  VideoOutput: Trying video renderer: 'xv-blit'
2012-12-25 21:38:31.847268 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:38:31.847366 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:38:31.847402 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:38:31.847460 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:38:31.847499 I  VideoOutputXv: ctor
2012-12-25 21:38:31.847537 I  VideoOutWindow::SetPIPState. pip_state: 0]
2012-12-25 21:38:31.847564 I  VDP: LoadBestPreferences(2048x2048, 25)
2012-12-25 21:38:31.848983 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.33333
2012-12-25 21:38:31.849024 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
2012-12-25 21:38:31.849054 I  VDP: LoadBestPreferences(720x576, 25)
2012-12-25 21:38:31.849121 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.33333
2012-12-25 21:38:31.849152 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
2012-12-25 21:38:31.851457 I  VideoOutput: Pixel dimensions: Screen 1280x720, window 1280x720
2012-12-25 21:38:31.851525 I  VideoOutput: Actual display dimensions: 325x183 mm  Aspect: 1.77596
2012-12-25 21:38:31.851552 I  VideoOutput: Estimated window dimensions: 325x183 mm  Aspect: 1.77596
2012-12-25 21:38:31.852355 I  VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
2012-12-25 21:38:31.855232 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:38:31.855326 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:38:31.855358 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:38:31.855411 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:38:31.855452 I  VDP: LoadBestPreferences(720x576, 60)
2012-12-25 21:38:31.855738 I  VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  2
2012-12-25 21:38:31.855763 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask 
2012-12-25 21:38:31.855781 I  VideoOutputXv: Has XVideo flags...
2012-12-25 21:38:31.855885 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2012-12-25 21:38:31.855909 I  VideoOutputXv: Adaptor#1: NV05 Video Blitter has flag[s]: XvInputMask XvImageMask 
2012-12-25 21:38:31.855920 I  VideoOutputXv: Has XVideo flags...
2012-12-25 21:38:31.856043 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2012-12-25 21:38:31.856065 I  VideoOutputXv: @ j=1 Looking for flag[s]: XvInputMask XvImageMask  0
2012-12-25 21:38:31.856090 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask 
2012-12-25 21:38:31.856101 I  VideoOutputXv: Has XVideo flags...
2012-12-25 21:38:31.856211 I  VideoOutputXv: Grabbed xv port 281
2012-12-25 21:38:31.856322 I  VideoOutputXv: XVideo surface found on port 281
2012-12-25 21:38:31.856335 I  VideoOutputXv: XV_SET_DEFAULTS is supported on this port
2012-12-25 21:38:31.856596 I  VideoOutputXv: XV_SYNC_TO_VBLANK supported
2012-12-25 21:38:31.856611 I  VideoOutputXv: XVideo Sync to VBlank set
2012-12-25 21:38:31.856652 I  VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2012-12-25 21:38:31.856784 I  VideoOutputXv: XVideo Format #0 is 'YUY2'
2012-12-25 21:38:31.856809 I  VideoOutputXv: XVideo Format #1 is 'YV12'
2012-12-25 21:38:31.856828 I  VideoOutputXv: XVideo Format #2 is 'UYVY'
2012-12-25 21:38:31.856848 I  VideoOutputXv: XVideo Format #3 is 'I420'
2012-12-25 21:38:31.856866 I  VideoOutputXv: Using XVideo Format 'YV12'
2012-12-25 21:38:31.856887 I  VideoOutputXv: CreateShmImages(32): video_dim: 720x576
2012-12-25 21:38:31.934600 I  VDP: SetVideoRenderer(xv-blit)
2012-12-25 21:38:31.934645 I  VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2012-12-25 21:38:31.935568 I  ColourSpace: PictureAttributes: 
2012-12-25 21:38:31.935781 I  VideoOutputXv: Chromakeying not possible with this XVideo port.
2012-12-25 21:38:31.935853 I  Display Rect  left: 160, top: 0, width: 960, height: 720, aspect: 1.77778
2012-12-25 21:38:31.935884 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
2012-12-25 21:38:31.935966 I  Over/underscan. V: 0.02, H: 0.02
2012-12-25 21:38:31.935999 I  Display Rect  left: 160, top: 0, width: 960, height: 720, aspect: 1.77778
2012-12-25 21:38:31.936027 I  Video Rect    left: 14, top: 12, width: 691, height: 553, aspect: 1.33333
2012-12-25 21:38:31.936065 I  Player(1): LoadFilters(''..) -> 0x0
2012-12-25 21:38:31.938683 I  OSD: Base theme size: 1280x720
2012-12-25 21:38:31.938737 I  OSD: Scaling factors: 0.540625x0.769444
2012-12-25 21:38:31.938934 I  Player(1): Decoder thread starting.
2012-12-25 21:38:31.948146 I  OSD: Loaded window osd_message
2012-12-25 21:38:31.956132 I  OSD: Loaded window osd_input
2012-12-25 21:38:31.970841 I  OSD: Loaded window program_info
2012-12-25 21:38:31.982111 I  OSD: Loaded window browse_info
2012-12-25 21:38:31.994468 I  OSD: Loaded window osd_status
2012-12-25 21:38:32.005625 I  OSD: Loaded window osd_program_editor
2012-12-25 21:38:32.017475 I  OSD: Loaded window osd_debug
2012-12-25 21:38:32.017502 I  OSD: Loaded OSD: size 692x554 offset 14+11
2012-12-25 21:38:32.017547 I  OSD: Base theme size: 1280x720
2012-12-25 21:38:32.017564 I  OSD: Scaling factors: 0.540625x0.769444
2012-12-25 21:38:32.017600 I  OSD: Created window aa_OSD_SUBTITLES
2012-12-25 21:38:32.017639 I  Player(1): ClearAfterSeek(0)
2012-12-25 21:38:32.018768 I  VDP: GetFilteredDeint() : xv-blit -> 'greedyhdoubleprocessdeint'
2012-12-25 21:38:32.019754 I  FilterManager: GetFilterInfo(convert) returning: 0x0
2012-12-25 21:38:32.019778 I  FilterManager: GetFilterInfo(greedyhdoubleprocessdeint) returning: 0xffffffff9fb39d90
2012-12-25 21:38:32.022281 I  VideoOutput: Using deinterlace method greedyhdoubleprocessdeint
2012-12-25 21:38:32.022347 I  VSYNC: DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2012-12-25 21:38:32.022386 E  VSYNC: RTCVideoSync: Could not set RTC frequency: 
			eno: Permission denied (13)
2012-12-25 21:38:32.023998 I  Player(1): Video timing method: USleep with busy wait
2012-12-25 21:38:32.024028 I  Player(1): Display Refresh Rate: 60.074 Video Frame Rate: 25.000
2012-12-25 21:38:32.024043 I  Player(1): SetFrameInterval ps:1 scan:1
2012-12-25 21:38:32.025129 I  playCtx: StartPlaying(): took 0 ms to start player.
2012-12-25 21:38:32.025154 I  TV: Created player.
2012-12-25 21:38:32.025174 I  TV: StartPlayer(0, WatchingLiveTV, main) -- end ok
2012-12-25 21:38:32.025196 I  TV: Changing from None to WatchingLiveTV
2012-12-25 21:38:32.025205 I  TV: State is LiveTV & mctx == ctx
2012-12-25 21:38:32.026094 I  TV: UpdateOSDInput done
2012-12-25 21:38:32.026113 I  TV: UpdateLCD done
2012-12-25 21:38:32.026497 I  TV: ITVRestart done
2012-12-25 21:38:32.036896 I  TV: DrawUnusedRects() -- begin
2012-12-25 21:38:32.036915 I  TV: DrawUnusedRects() -- end
2012-12-25 21:38:32.037008 I  TV: Main UI disabled.
2012-12-25 21:38:32.037021 I  TV: HandleStateChange(0) -- end
2012-12-25 21:38:32.037420 I  TV: tv->LiveTV() -- end
2012-12-25 21:38:32.037439 I  TV: Entering main playback loop.
2012-12-25 21:38:32.105263 I  VideoOutput: Created YV12 OSD.
2012-12-25 21:38:32.677492 I  LiveTVChain(live-LinHES-2012-12-25T21:38:31): ReloadAll(): Added new recording
2012-12-25 21:38:32.677554 I  LiveTVChain(live-LinHES-2012-12-25T21:38:31): LiveTVChain has 2 entries
*  DUMMY: 3923 (21:38:31 to 21:38:32)
  HDHOMERUN: 3923 (21:38:32 to 00:00:00) discontinuous

2012-12-25 21:38:32.679398 I  LiveTVChain(live-LinHES-2012-12-25T21:38:31): SwitchTo(1)
2012-12-25 21:38:32.679417 I  Player(1): JumpToProgram - start
2012-12-25 21:38:32.684723 I  Player(1): LoadFilters(''..) -> 0x0
2012-12-25 21:38:32.685664 I  Player(1): Play speed: rate: 25 speed: 0 skip: 0 => new interval 40000
2012-12-25 21:38:32.686168 I  VDP: GetFilteredDeint(linearblend) : xv-blit -> 'linearblend'
2012-12-25 21:38:32.687448 I  FilterManager: GetFilterInfo(convert) returning: 0x0
2012-12-25 21:38:32.687474 I  FilterManager: GetFilterInfo(linearblend) returning: 0x96215c0
2012-12-25 21:38:32.687517 I  VideoOutput: Using deinterlace method linearblend
2012-12-25 21:38:32.688256 I  FileRingBuf(myth://192.168.1.210:6543/3923_20121225213831.mpg): OpenFile(myth://192.168.1.210:6543/3923_20121225213832.mpg, 10000 ms)
2012-12-25 21:38:32.688960 I  Using protocol version 72
2012-12-25 21:38:32.689822 I  Using protocol version 72
2012-12-25 21:38:32.946358 I  Player(1): Stretch Factor 1, allow passthru 
2012-12-25 21:38:32.946738 I  AFD: Buffer size: 32768, streamed 1
2012-12-25 21:38:33.278452 I  AFD: Stream #0, has id 0x7c0 codec id MPEG2VIDEO, type Video, bitrate 20000000 at 0xa05c1a0
2012-12-25 21:38:33.280097 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:38:33.280148 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:38:33.280167 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:38:33.280195 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:38:33.280217 I  VDP: LoadBestPreferences(1280x720, 60)
2012-12-25 21:38:33.280264 I  AFD: Using 2 CPUs for decoding
2012-12-25 21:38:33.280278 I  AFD: InitVideoCodec() 0x9c04540 id(MPEG2VIDEO) type (Video).
2012-12-25 21:38:33.280325 I  AFD: Selected FPS is 59.9401 (avg 59.96 codec 59.9401 container 90000 estimated 59.9401)
2012-12-25 21:38:33.280340 I  Player(1): SetFrameInterval ps:0 scan:3
2012-12-25 21:38:33.281423 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:38:33.281469 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:38:33.281482 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:38:33.281505 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:38:33.281527 I  VDP: LoadBestPreferences(1280x720, 60)
2012-12-25 21:38:33.281565 I  VDP: LoadBestPreferences(720x576, 59.9401)
2012-12-25 21:38:33.281597 I  VideoOutputXv: InputChanged(1280,720,1.33333) 'None'->'MPEG2'
2012-12-25 21:38:33.281613 I  VDP: LoadBestPreferences(1280x720, 59.9401)
2012-12-25 21:38:33.281689 I  VDP: GetFilteredDeint() : xv-blit -> 'bobdeint'
2012-12-25 21:38:33.282778 I  FilterManager: GetFilterInfo(convert) returning: 0x0
2012-12-25 21:38:33.282803 I  FilterManager: GetFilterInfo(bobdeint) returning: 0x960fca8
2012-12-25 21:38:33.282839 I  VideoOutput: Using deinterlace method bobdeint
2012-12-25 21:38:33.282850 I  VideoOutputXv: DiscardFrames(1)
2012-12-25 21:38:33.282897 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
2012-12-25 21:38:33.282936 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
2012-12-25 21:38:33.282994 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
2012-12-25 21:38:33.283003 I  VideoOutputXv: DiscardFrames(1)
2012-12-25 21:38:33.283037 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
2012-12-25 21:38:33.283073 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
2012-12-25 21:38:33.283107 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
2012-12-25 21:38:33.284998 I  VideoOutputXv: Closing XVideo port 281
2012-12-25 21:38:33.289779 I  VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
2012-12-25 21:38:33.291385 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:38:33.291435 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:38:33.291454 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:38:33.291482 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:38:33.291504 I  VDP: LoadBestPreferences(1280x720, 60)
2012-12-25 21:38:33.291733 I  VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  2
2012-12-25 21:38:33.291748 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask 
2012-12-25 21:38:33.291755 I  VideoOutputXv: Has XVideo flags...
2012-12-25 21:38:33.291820 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2012-12-25 21:38:33.291832 I  VideoOutputXv: Adaptor#1: NV05 Video Blitter has flag[s]: XvInputMask XvImageMask 
2012-12-25 21:38:33.291838 I  VideoOutputXv: Has XVideo flags...
2012-12-25 21:38:33.291899 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2012-12-25 21:38:33.291911 I  VideoOutputXv: @ j=1 Looking for flag[s]: XvInputMask XvImageMask  0
2012-12-25 21:38:33.291922 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask 
2012-12-25 21:38:33.291928 I  VideoOutputXv: Has XVideo flags...
2012-12-25 21:38:33.292014 I  VideoOutputXv: Grabbed xv port 281
2012-12-25 21:38:33.292086 I  VideoOutputXv: XVideo surface found on port 281
2012-12-25 21:38:33.292094 I  VideoOutputXv: XV_SET_DEFAULTS is supported on this port
2012-12-25 21:38:33.292283 I  VideoOutputXv: XV_SYNC_TO_VBLANK supported
2012-12-25 21:38:33.292298 I  VideoOutputXv: XVideo Sync to VBlank set
2012-12-25 21:38:33.292335 I  VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2012-12-25 21:38:33.292437 I  VideoOutputXv: XVideo Format #0 is 'YUY2'
2012-12-25 21:38:33.292452 I  VideoOutputXv: XVideo Format #1 is 'YV12'
2012-12-25 21:38:33.292464 I  VideoOutputXv: XVideo Format #2 is 'UYVY'
2012-12-25 21:38:33.292481 I  VideoOutputXv: XVideo Format #3 is 'I420'
2012-12-25 21:38:33.292492 I  VideoOutputXv: Using XVideo Format 'YV12'
2012-12-25 21:38:33.292505 I  VideoOutputXv: CreateShmImages(32): video_dim: 1280x720
2012-12-25 21:38:33.451331 I  VDP: SetVideoRenderer(xv-blit)
2012-12-25 21:38:33.451354 I  VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2012-12-25 21:38:33.451780 I  ColourSpace: PictureAttributes: 
2012-12-25 21:38:33.451887 I  VideoOutputXv: Chromakeying not possible with this XVideo port.
2012-12-25 21:38:33.451924 I  Display Rect  left: 160, top: 0, width: 960, height: 720, aspect: 1.77778
2012-12-25 21:38:33.451956 I  Video Rect    left: 26, top: 14, width: 1229, height: 691, aspect: 1.33333
2012-12-25 21:38:33.453583 I  OSD: Base theme size: 1280x720
2012-12-25 21:38:33.453613 I  OSD: Scaling factors: 0.960938x0.961111
2012-12-25 21:38:33.458025 I  OSD: Loaded window osd_message
2012-12-25 21:38:33.462041 I  OSD: Loaded window osd_input
2012-12-25 21:38:33.469110 I  OSD: Loaded window program_info
2012-12-25 21:38:33.473687 I  OSD: Loaded window browse_info
2012-12-25 21:38:33.479474 I  OSD: Loaded window osd_status
2012-12-25 21:38:33.484798 I  OSD: Loaded window osd_program_editor
2012-12-25 21:38:33.497116 I  OSD: Loaded window osd_debug
2012-12-25 21:38:33.497144 I  OSD: Loaded OSD: size 1230x692 offset 25+14
2012-12-25 21:38:33.497190 I  EnableCaptions(0) msg:  On
2012-12-25 21:38:33.497205 I  Player(1): ClearAfterSeek(1)
2012-12-25 21:38:33.497212 I  VideoOutputXv: ClearAfterSeek()
2012-12-25 21:38:33.497219 I  VideoOutputXv: DiscardFrames(0)
2012-12-25 21:38:33.497258 I  VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
2012-12-25 21:38:33.497292 I  VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
2012-12-25 21:38:33.497325 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
2012-12-25 21:38:33.497347 I  Player(1): LoadFilters(''..) -> 0x0
2012-12-25 21:38:33.497376 I  Player(1): detectInterlace(Detect Scan, Interlaced Scan, 59.9401, 720) ->Progressive Scan
2012-12-25 21:38:33.497387 I  Player(1): Disabled deinterlacing
2012-12-25 21:38:33.497437 I  AFD: EIA-608 caption service #1 is in the English language.
2012-12-25 21:38:33.497448 I  AFD: EIA-608 caption service #1 is in the English language.
2012-12-25 21:38:33.497457 I  AFD: Using ffmpeg for video decoding
2012-12-25 21:38:33.497466 I  AFD: Looking for decoder for MPEG2VIDEO
2012-12-25 21:38:33.497602 I  AFD: Opened codec 0x9c04540, id(MPEG2VIDEO) type(Video)
2012-12-25 21:38:33.497619 I  AFD: Stream #1, has id 0x7c1 codec id AC3, type Audio, bitrate 448000 at 0x95fcab0
2012-12-25 21:38:33.497630 I  AFD: codec AC3 has 6 channels
2012-12-25 21:38:33.497637 I  AFD: Looking for decoder for AC3
2012-12-25 21:38:33.498112 I  AFD: Opened codec 0x9e99ea0, id(AC3) type(Audio)
2012-12-25 21:38:33.544443 I  AO: Opening audio device 'iec958:CARD=NVidia,DEV=0' ch 2(6) sr 48000 sf signed 16 bit reenc 0
2012-12-25 21:38:33.545377 E  ALSA: Setting hardware audio buffer size to 128
2012-12-25 21:38:33.545463 E  ALSA: Error opening /proc/asound/card0/pcm1p/sub0/prealloc: Permission denied. 
2012-12-25 21:38:33.545475 E  ALSA: Try to manually increase audio buffer with: echo 128 | sudo tee /proc/asound/card0/pcm1p/sub0/prealloc
2012-12-25 21:38:33.545494 E  ALSA: Unable to sufficiently increase ALSA hardware buffer size - underruns are likely
2012-12-25 21:38:33.554194 I  Dec: Selected track #1 in the Unknown language(0)
2012-12-25 21:38:33.554214 I  Dec: Trying to select track (w/lang & forced)
2012-12-25 21:38:33.554225 I  Dec: Selected track #1 in the English language(6647399)
2012-12-25 21:38:33.554237 I  Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
2012-12-25 21:38:33.555638 I  Dec: SyncPositionMap watchingrecording, from DB: 0 entries
2012-12-25 21:38:33.555647 I  Dec: SyncPositionMap watchingrecording no entries from encoder, try DB
2012-12-25 21:38:33.556417 I  Dec: SyncPositionMap watchingrecording total: 0 entries
2012-12-25 21:38:33.557729 I  AFD: Successfully opened decoder for file: "myth://192.168.1.210:6543/3923_20121225213832.mpg". novideo(0)
2012-12-25 21:38:33.559792 N  AFD: Resetting byte context eof (livetv 1 was eof 0)
2012-12-25 21:38:34.059053 I  Player(1): Play(  1.0, normal 1, unpause audio 1)
2012-12-25 21:38:34.060049 I  Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
2012-12-25 21:38:34.060085 I  Player(1): Play speed: rate: 59.9401 speed: 1 skip: 1 => new interval 16683
2012-12-25 21:38:34.060133 I  Player(1): Stretch Factor 1, allow passthru 
2012-12-25 21:38:34.060154 I  Player(1): JumpToProgram - end
2012-12-25 21:38:34.060187 I  Player(1): Waiting for video buffers...
2012-12-25 21:38:34.062810 I  Dec: Position map filled from DB to: 28
2012-12-25 21:38:34.062840 I  Dec: SyncPositionMap watchingrecording, from DB: 3 entries
2012-12-25 21:38:34.062869 I  Player(1): Filling position map from 29 to end
2012-12-25 21:38:34.063916 I  Dec: Position map filled from Encoder to: 73
2012-12-25 21:38:34.063967 I  Dec: SyncPositionMap watchingrecording total: 6 entries
2012-12-25 21:38:34.063995 I  Dec: SyncPositionMap, new totframes: 73, new length: 1, posMap size: 6
2012-12-25 21:38:34.102093 I  Detect Letterbox: YV12 frame format detected
2012-12-25 21:38:34.117020 I  Detect Letterbox: YV12 frame format detected
2012-12-25 21:38:34.163539 N  Player(1): Waited 102ms for video buffers UUUULUULAAAAAAAAAAAAAAAAAAAAAAAP
2012-12-25 21:38:34.246581 I  Player(1): Video Aspect ratio changed from 1.33333 to 1.77778
2012-12-25 21:38:34.246646 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2012-12-25 21:38:34.246681 I  Video Rect    left: 26, top: 14, width: 1229, height: 691, aspect: 1.77778
2012-12-25 21:38:34.247798 I  OSD: Base theme size: 1280x720
2012-12-25 21:38:34.247841 I  OSD: Scaling factors: 0.960938x0.961111
2012-12-25 21:38:34.257528 I  OSD: Loaded window osd_message
2012-12-25 21:38:34.267013 I  Detect Letterbox: The source is already in widescreen (aspect: 1.77778)
2012-12-25 21:38:34.273058 I  OSD: Loaded window osd_input
2012-12-25 21:38:34.294589 I  OSD: Loaded window program_info
2012-12-25 21:38:34.304171 I  OSD: Loaded window browse_info
2012-12-25 21:38:34.310184 I  OSD: Loaded window osd_status
2012-12-25 21:38:34.319894 I  OSD: Loaded window osd_program_editor
2012-12-25 21:38:34.334110 I  OSD: Loaded window osd_debug
2012-12-25 21:38:34.334143 I  OSD: Loaded OSD: size 1230x692 offset 25+14
2012-12-25 21:38:34.334189 I  EnableCaptions(0) msg:  On
2012-12-25 21:38:34.334243 I  VideoOutput: OSD size changed.
2012-12-25 21:38:34.373142 I  VideoOutput: Created YV12 OSD.
2012-12-25 21:38:34.386923 I  Player(1): Video is 3.91117 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.387022 I  Player(1): Video is 6.60475 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.387081 I  Player(1): Video is 8.38518 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.387139 I  Player(1): Video is 9.46574 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.387197 I  Player(1): Video is 10.0214 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.387274 I  Player(1): Video is 10.1984 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.387331 I  Player(1): Video is 10.0764 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.387387 I  Player(1): Video is 9.73014 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.387443 I  Player(1): Video is 9.23071 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.387499 I  Player(1): Video is 8.60139 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.387555 I  Player(1): Video is 7.8746 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.387612 I  Player(1): Video is 7.08979 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.387668 I  Player(1): Video is 6.24642 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.387710 I  Player(1): Waiting for video buffers...
2012-12-25 21:38:34.393914 I  Player(1): Video is 5.35911 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.394047 I  Player(1): Waiting for video buffers...
2012-12-25 21:38:34.520165 I  AFD: EIA-608 caption service #1 is in the English language.
2012-12-25 21:38:34.520185 I  AFD: EIA-608 caption service #1 is in the English language.
2012-12-25 21:38:34.520201 I  AFD: EIA-708 caption service #1 is in the Undetermined language.
2012-12-25 21:38:34.961517 I  Player(1): Video is 6.13637 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.961596 I  Player(1): Video is 10.4765 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.961657 I  Player(1): Video is 13.4918 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.961731 I  Player(1): Video is 15.4986 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.961788 I  Player(1): Video is 16.7639 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.961846 I  Player(1): Video is 17.4731 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.961902 I  Player(1): Video is 17.7503 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.961976 I  Player(1): Video is 17.7034 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.962035 I  Player(1): Video is 17.4285 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.962092 I  Player(1): Video is 16.9675 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.962150 I  Player(1): Video is 16.367 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.962208 I  Player(1): Video is 15.6769 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.962278 I  Player(1): Video is 14.9046 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.962336 I  Player(1): Video is 14.0706 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.962394 I  Player(1): Video is 13.2053 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.962452 I  Player(1): Video is 12.3016 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.962509 I  Player(1): Video is 11.3691 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.962566 I  Player(1): Video is 10.43 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.962609 I  Player(1): Waiting for video buffers...
2012-12-25 21:38:34.963038 I  Dec: Selected track #1 in the Undetermined language(7695972)
2012-12-25 21:38:34.970865 I  Player(1): Video is 9.47084 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.970922 I  Player(1): Waiting for video buffers...
2012-12-25 21:38:34.975700 I  Player(1): Video is 5.81436 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:34.975772 I  Player(1): Waiting for video buffers...
2012-12-25 21:38:36.170249 I  Player(1): FPS:   56.12 Mean: 17818 Std.Dev: 54005 CPUs: 116% 
2012-12-25 21:38:36.823283 I  Player(1): Video is 8.32584 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.823357 I  Player(1): Video is 14.0367 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.823417 I  Player(1): Video is 18.0652 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.823474 I  Player(1): Video is 20.8317 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.823541 I  Player(1): Video is 22.6668 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.823612 I  Player(1): Video is 23.7885 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.823670 I  Player(1): Video is 24.3749 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.823727 I  Player(1): Video is 24.56 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.823783 I  Player(1): Video is 24.4741 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.823839 I  Player(1): Video is 24.1548 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.823895 I  Player(1): Video is 23.6607 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.823967 I  Player(1): Video is 23.0503 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.824025 I  Player(1): Video is 22.3378 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.824080 I  Player(1): Video is 21.5486 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.824136 I  Player(1): Video is 20.717 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.824204 I  Player(1): Video is 19.8385 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.824261 I  Player(1): Video is 18.9248 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.824317 I  Player(1): Video is 17.9999 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.824358 I  Player(1): Waiting for video buffers...
2012-12-25 21:38:36.833476 I  Player(1): Video is 17.0514 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.833760 I  Player(1): Waiting for video buffers...
2012-12-25 21:38:36.838430 I  Player(1): Video is 11.6047 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.838500 I  Player(1): Waiting for video buffers...
2012-12-25 21:38:36.840917 I  Player(1): Video is 7.2499 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.841057 I  Player(1): Waiting for video buffers...
2012-12-25 21:38:36.843395 I  Player(1): Video is 3.77402 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:36.843452 I  Player(1): Waiting for video buffers...
2012-12-25 21:38:36.965810 I  Player(1): Video is 3.7311 frames ahead of audio,
			doubling video frame interval to slow down.
2012-12-25 21:38:36.999297 I  Player(1): Video is 4.04208 frames ahead of audio,
			doubling video frame interval to slow down.
2012-12-25 21:38:37.034231 I  Player(1): Video is 4.03555 frames ahead of audio,
			doubling video frame interval to slow down.
2012-12-25 21:38:37.067026 I  Player(1): Video is 4.12054 frames ahead of audio,
			doubling video frame interval to slow down.
2012-12-25 21:38:37.100544 I  Player(1): Video is 3.82467 frames ahead of audio,
			doubling video frame interval to slow down.
2012-12-25 21:38:37.134298 I  Player(1): Video is 3.19817 frames ahead of audio,
			doubling video frame interval to slow down.
2012-12-25 21:38:38.169818 I  Player(1): FPS:   50.06 Mean: 19976 Std.Dev: 53017 CPUs: 48% 71% 
2012-12-25 21:38:38.760028 I  TV: Pausing player: 0
2012-12-25 21:38:38.924510 I  Display Rect  left: 940, top: 50, width: 300, height: 170, aspect: 1.77778
2012-12-25 21:38:38.924552 I  Video Rect    left: 26, top: 14, width: 1229, height: 691, aspect: 1.77778
2012-12-25 21:38:38.924581 I  Display Rect  left: 940, top: 50, width: 300, height: 170, aspect: 1.77778
2012-12-25 21:38:38.924608 I  Video Rect    left: 26, top: 14, width: 1229, height: 691, aspect: 1.77778
2012-12-25 21:38:38.925192 I  TV: DrawUnusedRects() -- begin
2012-12-25 21:38:38.925211 I  TV: DrawUnusedRects() -- end
2012-12-25 21:38:38.947730 I  TV: DrawUnusedRects() -- begin
2012-12-25 21:38:38.947760 I  TV: DrawUnusedRects() -- end
2012-12-25 21:38:38.947846 I  TV: DrawUnusedRects() -- begin
2012-12-25 21:38:38.947858 I  TV: DrawUnusedRects() -- end
2012-12-25 21:38:39.144760 I  Player(1): Video is 3.35341 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.157548 I  Player(1): Video is 8.17946 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.157640 I  Player(1): Video is 9.07169 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.157693 I  Player(1): Video is 9.50111 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.157745 I  Player(1): Video is 9.56842 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.157794 I  Player(1): Video is 9.36414 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.157843 I  Player(1): Video is 8.97117 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.157892 I  Player(1): Video is 8.42169 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.157958 I  Player(1): Video is 7.75484 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.158010 I  Player(1): Video is 7.01493 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.158078 I  Player(1): Video is 6.20524 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.158129 I  Player(1): Video is 5.34322 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.158178 I  Player(1): Video is 4.45693 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.158226 I  Player(1): Video is 3.53749 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.462027 I  Player(1): Video is 3.15657 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:39.462110 I  Player(1): Video is 3.14662 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:40.047209 I  Player(1): FPS:   53.30 Mean: 18763 Std.Dev: 26959 CPUs: 69% 70% 
2012-12-25 21:38:40.499346 I  Player(1): Video is 3.23101 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:40.499686 I  Player(1): Video is 3.87682 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:40.516207 I  Player(1): Video is 4.1064 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:40.517008 I  Player(1): Video is 4.27861 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:40.517114 I  Player(1): Video is 4.183 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:40.517177 I  Player(1): Video is 3.8565 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:40.517235 I  Player(1): Video is 3.35689 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:41.117688 I  Player(1): Video is 3.18576 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:41.117757 I  Player(1): Video is 3.09363 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:41.504314 I  Player(1): Video is 3.55134 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:41.537066 I  Player(1): Video is 4.14704 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:41.537508 I  Player(1): Video is 4.50387 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:41.537580 I  Player(1): Video is 4.51675 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:41.537640 I  Player(1): Video is 4.28664 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:41.537703 I  Player(1): Video is 3.85932 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:41.537761 I  Player(1): Video is 3.28406 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:41.653107 I  Player(1): 400 progressive frames seen.
2012-12-25 21:38:41.687120 I  Player(1): FPS:   61.02 Mean: 16387 Std.Dev: 12461 CPUs: 70% 74% 
2012-12-25 21:38:42.311507 I  Player(1): Video is 4.22154 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:42.342691 I  Player(1): Video is 5.53384 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:42.343812 I  Player(1): Video is 6.74279 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:42.344064 I  Player(1): Video is 7.40976 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:42.344174 I  Player(1): Video is 7.65522 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:42.344273 I  Player(1): Video is 7.59959 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:42.344371 I  Player(1): Video is 7.30312 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:42.344465 I  Player(1): Video is 6.82599 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:42.344648 I  Player(1): Video is 6.22838 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:42.344746 I  Player(1): Video is 5.54043 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:42.344840 I  Player(1): Video is 4.76971 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:42.344936 I  Player(1): Video is 3.93688 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:42.345061 I  Player(1): Video is 3.07253 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:42.891154 I  Player(1): Video is 3.0639 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:43.461482 I  Player(1): FPS:   56.40 Mean: 17731 Std.Dev: 18735 CPUs: 71% 68% 
2012-12-25 21:38:43.494636 I  Player(1): Video is 3.43278 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:43.495116 I  Player(1): Video is 4.19295 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:43.495184 I  Player(1): Video is 4.50836 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:43.495245 I  Player(1): Video is 4.49014 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:43.495303 I  Player(1): Video is 4.23671 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:43.495360 I  Player(1): Video is 3.79188 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:43.495520 I  Player(1): Video is 3.20356 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:43.981213 I  Player(1): Video is 3.52077 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:43.981309 I  Player(1): Video is 3.77942 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:43.981380 I  Player(1): Video is 3.7187 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:43.981440 I  Player(1): Video is 3.41839 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:44.506762 I  Player(1): Video is 3.44171 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:44.506860 I  Player(1): Video is 3.72013 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:44.506924 I  Player(1): Video is 3.6892 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:44.507006 I  Player(1): Video is 3.41126 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:44.929831 I  Player(1): Video is 3.29155 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:44.935141 I  Player(1): Video is 3.78733 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:44.935390 I  Player(1): Video is 3.91944 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:44.935455 I  Player(1): Video is 3.76377 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:44.935596 I  Player(1): Video is 3.39226 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:45.050232 I  Player(1): FPS:   62.99 Mean: 15875 Std.Dev: 14916 CPUs: 69% 70% 
2012-12-25 21:38:45.352063 I  Player(1): Video is 3.20542 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:45.352425 I  Player(1): Video is 4.47204 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:45.352492 I  Player(1): Video is 5.18222 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:45.352553 I  Player(1): Video is 5.46011 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:45.352611 I  Player(1): Video is 5.41377 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:45.352670 I  Player(1): Video is 5.12426 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:45.352726 I  Player(1): Video is 4.66739 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:45.352784 I  Player(1): Video is 4.06995 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:45.352862 I  Player(1): Video is 3.36714 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.012702 I  Player(1): Video is 4.84062 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.047721 I  Player(1): Video is 6.14799 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.048982 I  Player(1): Video is 7.1435 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.049170 I  Player(1): Video is 7.65036 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.049281 I  Player(1): Video is 7.77576 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.049381 I  Player(1): Video is 7.64503 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.049520 I  Player(1): Video is 7.29221 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.049622 I  Player(1): Video is 6.77288 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.049718 I  Player(1): Video is 6.12863 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.049870 I  Player(1): Video is 5.40568 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.050039 I  Player(1): Video is 4.6087 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.050137 I  Player(1): Video is 3.75622 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.486533 I  Player(1): Video is 3.27561 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.501522 I  Player(1): Video is 4.74945 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.501839 I  Player(1): Video is 5.68998 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.523273 I  Player(1): Video is 6.17059 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.523626 I  Player(1): Video is 7.2204 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.523692 I  Player(1): Video is 7.75298 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.523751 I  Player(1): Video is 7.91267 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.523832 I  Player(1): Video is 7.77768 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.523898 I  Player(1): Video is 7.42169 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.523975 I  Player(1): Video is 6.3455 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.524035 I  Player(1): Video is 5.28358 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.524093 I  Player(1): Video is 4.23239 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.524151 I  Player(1): Video is 3.20422 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:46.752780 I  Player(1): FPS:   58.80 Mean: 17007 Std.Dev: 22863 CPUs: 68% 63% 
2012-12-25 21:38:47.070257 I  Player(1): Video is 3.72829 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:47.071330 I  Player(1): Video is 5.38866 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:47.099700 I  Player(1): Video is 6.37919 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:47.275240 I  Player(1): Video is 7.55667 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:47.446068 I  Player(1): Video is 7.76545 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:47.509601 I  Player(1): Video is 7.66727 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:47.510321 I  Player(1): Video is 7.35383 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:47.531040 I  Player(1): Video is 6.87904 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:47.531750 I  Player(1): Video is 6.25319 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:47.531864 I  Player(1): Video is 5.52904 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:47.531993 I  Player(1): Video is 4.73116 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:47.532093 I  Player(1): Video is 3.90799 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:47.532191 I  Player(1): Video is 3.03584 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:47.844154 I  Player(1): Video is 3.06785 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:48.596428 I  Player(1): Video is 3.33657 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:48.599766 I  Player(1): Video is 3.73122 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:48.599856 I  Player(1): Video is 3.77246 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:48.599910 I  Player(1): Video is 3.54864 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:48.599982 I  Player(1): Video is 3.14098 frames behind audio (too slow), dropping frame to catch up.
2012-12-25 21:38:48.864690 I  Player(1): 800 progressive frames seen.
2012-12-25 21:38:48.898569 I  Player(1): FPS:   46.63 Mean: 21446 Std.Dev: 29454 CPUs: 71% 73% 
2012-12-25 21:38:49.185414 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2012-12-25 21:38:49.185442 I  Video Rect    left: 26, top: 14, width: 1229, height: 691, aspect: 1.77778
2012-12-25 21:38:49.185459 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2012-12-25 21:38:49.185475 I  Video Rect    left: 26, top: 14, width: 1229, height: 691, aspect: 1.77778
2012-12-25 21:38:49.185498 I  Clearing OpenGL painter cache.
2012-12-25 21:38:49.350474 I  TV: DrawUnusedRects() -- begin
2012-12-25 21:38:49.350494 I  TV: DrawUnusedRects() -- end
2012-12-25 21:38:49.350600 I  TV: CommitQueuedInput() livetv(1) qchannum(939) qchanid(3939)
2012-12-25 21:38:49.364639 I  TV: PauseLiveTV() player ctx 0
2012-12-25 21:38:49.919811 I  TV: UnpauseLiveTV() player ctx 0
2012-12-25 21:38:49.921519 I  LiveTVChain(live-LinHES-2012-12-25T21:38:31): ReloadAll(): Added new recording
2012-12-25 21:38:49.921651 I  LiveTVChain(live-LinHES-2012-12-25T21:38:31): LiveTVChain has 3 entries
   DUMMY: 3923 (21:38:31 to 21:38:32)
* HDHOMERUN: 3923 (21:38:32 to 21:38:49) discontinuous
   DUMMY: 3939 (21:38:49 to 23:28:00) discontinuous

2012-12-25 21:38:49.929632 I  LiveTVChain(live-LinHES-2012-12-25T21:38:31): SwitchTo(-1)
2012-12-25 21:38:49.929708 I  Player(1): Play(  1.0, normal 1, unpause audio 0)
2012-12-25 21:38:52.459096 I  LiveTVChain(live-LinHES-2012-12-25T21:38:31): SwitchTo(2)
2012-12-25 21:38:52.488184 I  TV: DrawUnusedRects() -- begin
2012-12-25 21:38:52.488226 I  TV: DrawUnusedRects() -- end
2012-12-25 21:38:52.488330 I  Player(1): JumpToProgram - start
2012-12-25 21:38:52.489828 I  LiveTVChain(live-LinHES-2012-12-25T21:38:31): ReloadAll(): Added new recording
2012-12-25 21:38:52.490000 I  LiveTVChain(live-LinHES-2012-12-25T21:38:31): LiveTVChain has 4 entries
   DUMMY: 3923 (21:38:31 to 21:38:32)
* HDHOMERUN: 3923 (21:38:32 to 21:38:49) discontinuous
   DUMMY: 3939 (21:38:49 to 21:38:50) discontinuous
  HDHOMERUN: 3939 (21:38:50 to 23:28:00) discontinuous

2012-12-25 21:38:52.496377 I  Player(1): LoadFilters(''..) -> 0x0
2012-12-25 21:38:52.504746 I  Player(1): Play speed: rate: 59.9401 speed: 0 skip: 0 => new interval 16683
2012-12-25 21:38:52.505802 I  FileRingBuf(myth://192.168.1.210:6543/3923_20121225213832.mpg): OpenFile(myth://192.168.1.210:6543/3939_20121225213850.mpg, 10000 ms)
2012-12-25 21:38:52.515217 I  Using protocol version 72
2012-12-25 21:38:52.516846 I  Using protocol version 72
2012-12-25 21:38:52.533344 W  RingBuf(myth://192.168.1.210:6543/3939_20121225213850.mpg): Not starting read ahead thread, already running
2012-12-25 21:38:52.560000 I  Player(1): Stretch Factor 1, allow passthru 
2012-12-25 21:38:52.560695 I  AFD: Buffer size: 32768, streamed 1
2012-12-25 21:38:52.694043 I  AFD: Stream #0, has id 0x840 codec id MPEG2VIDEO, type Video, bitrate 20000000 at 0xffffffffa735cbd0
2012-12-25 21:38:52.697444 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:38:52.697537 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:38:52.697570 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:38:52.697623 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:38:52.697663 I  VDP: LoadBestPreferences(1920x1080, 60)
2012-12-25 21:38:52.697748 I  AFD: Using 2 CPUs for decoding
2012-12-25 21:38:52.697774 I  AFD: InitVideoCodec() 0xffffffff9ff3da20 id(MPEG2VIDEO) type (Video).
2012-12-25 21:38:52.697887 I  AFD: Selected FPS is 29.97 (avg 749.25 codec 29.97 container 90000 estimated 59.9401)
2012-12-25 21:38:52.697918 I  Player(1): SetFrameInterval ps:0 scan:3
2012-12-25 21:38:52.700024 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2012-12-25 21:38:52.700107 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2012-12-25 21:38:52.700158 I  VDP: LoadBestPreferences(2048x2048, 0)
2012-12-25 21:38:52.700205 I  VDP: LoadBestPreferences(2048x2048, 60)
2012-12-25 21:38:52.700244 I  VDP: LoadBestPreferences(1920x1080, 60)
2012-12-25 21:38:52.700316 I  VDP: LoadBestPreferences(1280x720, 29.97)
2012-12-25 21:38:52.700377 I  VideoOutputXv: InputChanged(1920,1080,1.77778) 'MPEG2'->'MPEG2'
2012-12-25 21:38:52.700403 I  VDP: LoadBestPreferences(1920x1088, 29.97)
2012-12-25 21:38:52.700536 I  VDP: GetFilteredDeint() : xv-blit -> 'bobdeint'
2012-12-25 21:38:52.702682 I  FilterManager: GetFilterInfo(convert) returning: 0x0
2012-12-25 21:38:52.702728 I  FilterManager: GetFilterInfo(bobdeint) returning: 0xffffffffa008aea8
2012-12-25 21:38:52.702791 I  VideoOutput: Using deinterlace method bobdeint
2012-12-25 21:38:52.702811 I  VideoOutputXv: DiscardFrames(1)
2012-12-25 21:38:52.702917 I  VideoBuffers::DiscardFrames(1): UUUUUUUUUUUUUUAUUUUUUUUUUUUUUUUP
2012-12-25 21:38:52.703043 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
2012-12-25 21:38:52.703108 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
2012-12-25 21:38:52.703123 I  VideoOutputXv: DiscardFrames(1)
2012-12-25 21:38:52.703180 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
2012-12-25 21:38:52.703244 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
2012-12-25 21:38:52.703304 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
2012-12-25 21:38:52.708294 I  VideoOutputXv: CreateShmImages(32): video_dim: 1920x1088
2012-12-25 21:38:53.030153 I  VideoOutputXv: Chromakeying not possible with this XVideo port.
2012-12-25 21:38:53.030207 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
2012-12-25 21:38:53.030223 I  Video Rect    left: 38, top: 22, width: 1843, height: 1037, aspect: 1.77778
2012-12-25 21:38:53.032113 I  OSD: Base theme size: 1280x720
2012-12-25 21:38:53.032143 I  OSD: Scaling factors: 1.44062x1.44167
2012-12-25 21:38:53.051034 I  OSD: Loaded window osd_message
2012-12-25 21:38:53.056012 I  OSD: Loaded window osd_input
2012-12-25 21:38:53.115510 I  OSD: Loaded window program_info
2012-12-25 21:38:53.133141 I  OSD: Loaded window browse_info
2012-12-25 21:38:53.140452 I  OSD: Loaded window osd_status
2012-12-25 21:38:53.145777 I  OSD: Loaded window osd_program_editor
2012-12-25 21:38:53.173667 I  OSD: Loaded window osd_debug
2012-12-25 21:38:53.173694 I  OSD: Loaded OSD: size 1844x1038 offset 38+21
2012-12-25 21:38:53.173737 I  EnableCaptions(0) msg:  On
2012-12-25 21:38:53.173751 I  Player(1): ClearAfterSeek(1)
2012-12-25 21:38:53.173759 I  VideoOutputXv: ClearAfterSeek()
2012-12-25 21:38:53.173767 I  VideoOutputXv: DiscardFrames(0)
2012-12-25 21:38:53.173805 I  VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
2012-12-25 21:38:53.173838 I  VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
2012-12-25 21:38:53.173871 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
2012-12-25 21:38:53.173892 I  Player(1): LoadFilters(''..) -> 0x0
2012-12-25 21:38:53.173918 I  Player(1): detectInterlace(Detect Scan, Progressive Scan, 29.97, 1080) ->Interlaced Scan
2012-12-25 21:38:53.173931 I  Player(1): Enabled deinterlacing
2012-12-25 21:38:53.173972 I  AFD: Using ffmpeg for video decoding
2012-12-25 21:38:53.173982 I  AFD: Looking for decoder for MPEG2VIDEO
2012-12-25 21:38:53.174101 I  AFD: Opened codec 0xffffffff9ff3da20, id(MPEG2VIDEO) type(Video)
2012-12-25 21:38:53.174121 I  AFD: Stream #1, has id 0x841 codec id AC3, type Audio, bitrate 384000 at 0xffffffffb05836a0
2012-12-25 21:38:53.174131 I  AFD: codec AC3 has 6 channels
2012-12-25 21:38:53.174138 I  AFD: Looking for decoder for AC3
2012-12-25 21:38:53.174600 I  AFD: Opened codec 0xffffffffa43e3a00, id(AC3) type(Audio)
2012-12-25 21:38:53.174625 I  AFD: Stream #2, has id 0x842 codec id AC3, type Audio, bitrate 384000 at 0xffffffffa59a6f50
2012-12-25 21:38:53.174634 I  AFD: codec AC3 has 2 channels
2012-12-25 21:38:53.174641 I  AFD: Looking for decoder for AC3
2012-12-25 21:38:53.175077 I  AFD: Opened codec 0xffffffffb05862c0, id(AC3) type(Audio)
2012-12-25 21:38:53.182615 I  Dec: Selected track #1 in the Unknown language(0)
2012-12-25 21:38:53.182633 I  Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
2012-12-25 21:38:53.183221 I  Dec: Position map filled from DB to: 26
2012-12-25 21:38:53.183233 I  Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2012-12-25 21:38:53.183247 I  Player(1): Filling position map from 27 to end
2012-12-25 21:38:53.183715 I  Dec: Position map filled from Encoder to: 26
2012-12-25 21:38:53.183731 I  Dec: SyncPositionMap watchingrecording total: 1 entries
2012-12-25 21:38:53.183746 I  Dec: SyncPositionMap, new totframes: 26, new length: 0, posMap size: 1
2012-12-25 21:38:53.185569 I  AFD: Partial position map found
2012-12-25 21:38:53.185588 I  AFD: Successfully opened decoder for file: "myth://192.168.1.210:6543/3939_20121225213850.mpg". novideo(0)
2012-12-25 21:38:53.190683 N  AFD: Resetting byte context eof (livetv 1 was eof 0)
2012-12-25 21:38:53.369540 I  Player(1): Play(  1.0, normal 1, unpause audio 1)
2012-12-25 21:38:53.370280 I  Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
2012-12-25 21:38:53.370322 I  Player(1): Play speed: rate: 29.97 speed: 1 skip: 1 => new interval 33366
2012-12-25 21:38:53.370407 I  Player(1): Stretch Factor 1, allow passthru 
2012-12-25 21:38:53.370428 I  Player(1): JumpToProgram - end
2012-12-25 21:38:53.370461 I  Player(1): Waiting for video buffers...
2012-12-25 21:38:53.371593 I  Dec: Position map filled from DB to: 26
2012-12-25 21:38:53.371621 I  Dec: SyncPositionMap watchingrecording, from DB: 1 entries
2012-12-25 21:38:53.371652 I  Player(1): Filling position map from 27 to end
2012-12-25 21:38:53.372534 I  Dec: Position map filled from Encoder to: 26
2012-12-25 21:38:53.372563 I  Dec: SyncPositionMap watchingrecording total: 1 entries
2012-12-25 21:38:53.389376 I  OSD: Base theme size: 1280x720
2012-12-25 21:38:53.389423 I  OSD: Scaling factors: 1.44062x1.44167
2012-12-25 21:38:53.532051 N  Player(1): Waited 161ms for video buffers AAAAAAAAAAAAAALAAAAAAAAAAAAAAAAP
2012-12-25 21:38:53.637313 N  Player(1): Waited 267ms for video buffers AAAAAAAAAAAAAALAAAAAAAAAAAAALAAP
2012-12-25 21:38:53.639276 I  AFD: EIA-708 caption service #1 is in the Undetermined language.
2012-12-25 21:38:53.644905 I  Dec: Selected track #1 in the Undetermined language(7695972)
2012-12-25 21:38:53.740526 N  Player(1): Waited 370ms for video buffers ULALAAAAAAAUAUUAAAUAAAAAAAAAUAAP
2012-12-25 21:38:53.827155 I  Player(1): progressive frame seen after 2 interlaced frames
2012-12-25 21:38:53.827191 I  VideoOutput: OSD size changed.
2012-12-25 21:38:53.916684 I  AFD: EIA-708 caption service #1 is in the Undetermined language.
2012-12-25 21:38:53.916720 I  AFD: EIA-608 caption service #1 is in the Undetermined language.
2012-12-25 21:38:53.918289 I  Dec: Selected track #1 in the Undetermined language(7695972)
2012-12-25 21:38:53.980918 I  VideoOutput: Created YV12 OSD.
2012-12-25 21:38:54.120939 I  Player(1): Disabled deinterlacing
2012-12-25 21:38:57.507776 I  Player(1): FPS:   11.62 Mean: 86072 Std.Dev: 487611 CPUs: 47% 48% 
2012-12-25 21:39:01.678675 I  Player(1): FPS:   23.99 Mean: 41690 Std.Dev:  9774 CPUs: 49% 56% 
2012-12-25 21:39:02.386966 I  AFD: EIA-708 caption service #1 is in the Undetermined language.
2012-12-25 21:39:02.387010 I  AFD: EIA-708 caption service #2 is in the Undetermined language.
2012-12-25 21:39:02.387031 I  AFD: EIA-608 caption service #1 is in the Undetermined language.
2012-12-25 21:39:05.866032 I  Player(1): FPS:   23.89 Mean: 41854 Std.Dev:  9071 CPUs: 48% 52% 
2012-12-25 21:39:10.036968 I  Player(1): FPS:   23.99 Mean: 41689 Std.Dev:  9813 CPUs: 43% 44% 
2012-12-25 21:39:10.619499 I  Player(1): 400 progressive frames seen.
2012-12-25 21:39:14.208034 I  Player(1): FPS:   23.99 Mean: 41691 Std.Dev:  8126 CPUs: 56% 58% 
2012-12-25 21:39:18.009495 I  AFD: EIA-708 caption service #1 is in the Undetermined language.
2012-12-25 21:39:18.009514 I  AFD: EIA-608 caption service #1 is in the Undetermined language.
2012-12-25 21:39:18.378300 I  Player(1): FPS:   23.99 Mean: 41683 Std.Dev:  8195 CPUs: 58% 60% 
2012-12-25 21:39:18.849745 I  AFD: EIA-708 caption service #1 is in the Undetermined language.
2012-12-25 21:39:18.849782 I  AFD: EIA-708 caption service #2 is in the Undetermined language.
2012-12-25 21:39:18.849802 I  AFD: EIA-608 caption service #1 is in the Undetermined language.
2012-12-25 21:39:22.549217 I  Player(1): FPS:   23.99 Mean: 41688 Std.Dev:  8837 CPUs: 56% 60% 
2012-12-25 21:39:26.716927 I  Player(1): FPS:   24.00 Mean: 41666 Std.Dev:  8494 CPUs: 65% 63% 
2012-12-25 21:39:27.300275 I  Player(1): 800 progressive frames seen.
2012-12-25 21:39:30.871133 I  Player(1): FPS:   24.08 Mean: 41530 Std.Dev:  9155 CPUs: 64% 62% 
2012-12-25 21:39:35.044761 I  Player(1): FPS:   23.97 Mean: 41715 Std.Dev:  9898 CPUs: 72% 55% 
2012-12-25 21:39:35.842350 I  Player(1): interlaced frame seen after 1004 progressive frames
2012-12-25 21:39:35.842367 I  Player(1): Locking scan to Interlaced.
2012-12-25 21:39:35.842381 I  Player(1): Enabled deinterlacing
2012-12-25 21:39:37.026303 I  AFD: EIA-708 caption service #2 is in the Undetermined language.
2012-12-25 21:39:37.715313 I  AFD: EIA-708 caption service #2 is in the Undetermined language.
2012-12-25 21:39:37.715357 I  AFD: EIA-608 caption service #1 is in the Unknown language.
2012-12-25 21:39:37.731633 I  Dec: Selected track #1 in the Unknown language(-1)
2012-12-25 21:39:39.167469 I  Player(1): FPS:   24.27 Mean: 41196 Std.Dev: 12337 CPUs: 70% 79% 
2012-12-25 21:39:39.192826 I  AFD: EIA-708 caption service #1 is in the Undetermined language.
2012-12-25 21:39:39.192859 I  AFD: EIA-708 caption service #2 is in the Undetermined language.
2012-12-25 21:39:39.192880 I  AFD: EIA-608 caption service #1 is in the Undetermined language.
2012-12-25 21:39:43.349963 I  Player(1): FPS:   23.92 Mean: 41806 Std.Dev: 13156 CPUs: 65% 65% 
2012-12-25 21:39:47.501726 I  Player(1): FPS:   24.10 Mean: 41496 Std.Dev: 13402 CPUs: 65% 65% 
2012-12-25 21:39:51.656204 I  Player(1): FPS:   24.08 Mean: 41520 Std.Dev: 12783 CPUs: 69% 60% 
2012-12-25 21:39:55.761934 I  Player(1): FPS:   24.36 Mean: 41046 Std.Dev: 12364 CPUs: 67% 63% 
2012-12-25 21:39:58.008353 I  AFD: EIA-708 caption service #2 is in the Undetermined language.
2012-12-25 21:39:58.114487 I  AFD: EIA-708 caption service #2 is in the Undetermined language.
2012-12-25 21:39:58.114509 I  AFD: EIA-608 caption service #1 is in the Unknown language.
2012-12-25 21:39:58.155009 I  Dec: Selected track #1 in the Unknown language(-1)
2012-12-25 21:39:59.989107 I  Player(1): FPS:   23.67 Mean: 42252 Std.Dev:  9033 CPUs: 64% 61% 
2012-12-25 21:40:04.111114 I  Player(1): FPS:   24.27 Mean: 41209 Std.Dev:  9027 CPUs: 76% 72% 
2012-12-25 21:40:08.284867 I  Player(1): FPS:   23.97 Mean: 41715 Std.Dev: 12089 CPUs: 62% 62% 
2012-12-25 21:40:11.718777 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2012-12-25 21:40:11.718865 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2012-12-25 21:40:11.794663 I  TV: HandleStateChange(0) -- begin
2012-12-25 21:40:11.794710 I  TV: Attempting to change from WatchingLiveTV to None
2012-12-25 21:40:11.794740 I  TV: StopStuff() for player ctx 0 -- begin
2012-12-25 21:40:11.794768 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2012-12-25 21:40:11.794805 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2012-12-25 21:40:11.794825 I  Player(1): StopPlaying - begin
2012-12-25 21:40:11.815715 I  Player(1): Decoder thread exiting.
2012-12-25 21:40:11.816104 I  Player(1): Exited decoder loop.
2012-12-25 21:40:11.817251 I  VideoOutputXv: dtor
2012-12-25 21:40:11.817281 I  VideoOutputXv: DiscardFrames(1)
2012-12-25 21:40:11.817363 I  VideoBuffers::DiscardFrames(1): UUUUUUUUUUUuUULUUUUUUUUUUUUUUUUP
2012-12-25 21:40:11.817470 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
2012-12-25 21:40:11.817541 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
2012-12-25 21:40:11.828811 I  VideoOutputXv: Closing XVideo port 281
2012-12-25 21:40:11.886141 W  MythPainter: 25 images not yet de-allocated.
2012-12-25 21:40:11.903413 I  Player(1): StopPlaying - end
2012-12-25 21:40:11.903445 I  TV: StopStuff(): stopping ring buffer
2012-12-25 21:40:12.052352 I  TV: StopStuff(): stopping player
2012-12-25 21:40:12.052377 I  TV: StopStuff(): stopping recorder
2012-12-25 21:40:12.132931 I  TV: StopStuff() -- end
2012-12-25 21:40:12.133004 I  TV: Changing from WatchingLiveTV to None
2012-12-25 21:40:12.133642 I  TV: HandleStateChange(0) -- end
2012-12-25 21:40:12.133687 I  TV: Exiting main playback loop.
2012-12-25 21:40:12.133710 I  TV: StartTV -- process events 2 begin
2012-12-25 21:40:12.134639 I  TV: StartTV -- process events 2 end
2012-12-25 21:40:12.134667 I  TV::~TV() -- begin
2012-12-25 21:40:12.145255 I  TV::~TV() -- lock
2012-12-25 21:40:12.146368 I  Player(1): StopPlaying - begin
2012-12-25 21:40:12.146411 I  Player(1): Exited decoder loop.
2012-12-25 21:40:12.146488 I  Player(1): StopPlaying - end
2012-12-25 21:40:12.154897 I  TV::~TV() -- end
2012-12-25 21:40:12.178765 N  Resuming idle timer
2012-12-25 21:40:12.178806 I  TV: StartTV -- end
2012-12-25 21:40:18.172055 N  Resuming idle timer
2012-12-25 21:40:18.178554 N  Resuming idle timer
2012-12-25 21:40:18.186365 I  Bonjour: De-registering service '_mythfrontend._tcp.' on 'Mythfrontend on LinHES'
2012-12-25 21:40:18.189713 I  RAOP Device: Cleaning up.
2012-12-25 21:40:18.189762 I  AirPay: Cleaning up.
2012-12-25 21:40:18.189787 I  Deleting UPnP client...
2012-12-25 21:40:18.805375 I  OpenGL1: Deleting OpenGL Resources
2012-12-25 21:40:18.805459 I  OpenGL: Deleting OpenGL Resources
2012-12-25 21:40:18.930984 I  Waiting for threads to exit.
greedyhdeint: size changed from 0 x 0 -> 720 x 576
greedyhdeint: size changed from 0 x 0 -> 720 x 576
