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