Ticket #11304: nojudder-29fps(livetv).txt

File nojudder-29fps(livetv).txt, 78.6 KB (added by Brian <knappster_1@…>, 11 years ago)
Line 
12012-12-25 21:45:21.628989 C  mythfrontend version: fixes/0.25 [v0.25.3-7-gfe9f6fa] www.mythtv.org
22012-12-25 21:45:21.629026 C  Qt version: compile: 4.7.3, runtime: 4.7.3
32012-12-25 21:45:21.629033 N  Enabled verbose msgs:  general playback
42012-12-25 21:45:21.629070 N  Setting Log Level to LOG_INFO
52012-12-25 21:45:21.629143 I  Added logging to the console
62012-12-25 21:45:21.629154 I  Added database logging to table logging
72012-12-25 21:45:21.629267 N  Setting up SIGHUP handler
82012-12-25 21:45:21.629403 N  Using runtime prefix = /usr
92012-12-25 21:45:21.629423 N  Using configuration directory = /home/mythtv/.mythtv
102012-12-25 21:45:21.629620 I  Assumed character encoding: en_US.utf-8
112012-12-25 21:45:21.630488 N  Empty LocalHostName.
122012-12-25 21:45:21.630502 I  Using localhost value of LinHES
132012-12-25 21:45:21.652099 N  Setting QT default locale to EN_US
142012-12-25 21:45:21.652118 I  Current locale EN_US
152012-12-25 21:45:21.652183 N  Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
162012-12-25 21:45:21.661692 I  Starting process manager
172012-12-25 21:45:21.663377 I  Starting IO manager (write)
182012-12-25 21:45:21.668106 I  Starting IO manager (read)
192012-12-25 21:45:21.668215 I  Starting process signal handler
202012-12-25 21:45:21.773066 I  ScreenSaverX11Private: DPMS is disabled.
212012-12-25 21:45:21.851784 N  Desktop video mode: 1280x720 60.073 Hz
222012-12-25 21:45:21.906991 I  max_width: 1280 max_height: 720
232012-12-25 21:45:21.914629 I  Listening on TCP 127.0.0.1:6547
242012-12-25 21:45:21.914714 I  Listening on TCP 192.168.1.210:6547
252012-12-25 21:45:21.914807 I  Listening on TCP [0:0:0:0:0:0:0:1]:6547
262012-12-25 21:45:21.914897 I  Listening on TCP [fe80:0:0:0:21a:92ff:fecb:5342%eth0]:6547
272012-12-25 21:45:22.640067 E  RAOP Conn: Failed to read key from: /home/mythtv/.mythtv/RAOPKey.rsa
282012-12-25 21:45:22.640098 E  RAOP Device: Aborting startup - no key found.
292012-12-25 21:45:22.646619 I  Loading en_us translation for module mythfrontend
302012-12-25 21:45:22.659832 I  LIRC: Successfully initialized '/var/run/lirc/lircd' using '/home/mythtv/.mythtv/lircrc' config
312012-12-25 21:45:22.660032 E  JoystickMenuThread: Joystick disabled - Failed to read /home/mythtv/.mythtv/joystickmenurc
322012-12-25 21:45:22.732540 E  CECAdapter: Failed to find any CEC devices.
332012-12-25 21:45:22.732884 I  CECAdapter: Closing down CEC.
342012-12-25 21:45:22.734913 E  Failed binding to UDP 127.0.0.1:6948 - Error 8: The bound address is already in use
352012-12-25 21:45:22.735077 E  Failed binding to UDP 192.168.1.210:6948 - Error 8: The bound address is already in use
362012-12-25 21:45:22.735249 E  Failed binding to UDP [0:0:0:0:0:0:0:1]:6948 - Error 8: The bound address is already in use
372012-12-25 21:45:22.735414 E  Failed binding to UDP [fe80:0:0:0:21a:92ff:fecb:5342%eth0]:6948 - Error 8: The bound address is already in use
382012-12-25 21:45:22.735566 E  Failed binding to UDP 192.168.1.255:6948 - Error 8: The bound address is already in use
392012-12-25 21:45:22.785919 I  Using Frameless Window
402012-12-25 21:45:22.786041 I  Using Full Screen Window
412012-12-25 21:45:22.960883 I  Trying the OpenGL painter
422012-12-25 21:45:22.963398 I  OpenGL: Sync to VBlank is enabled (good!)
432012-12-25 21:45:23.070183 I  OpenGL1: Fragment program support available
442012-12-25 21:45:23.070280 I  OpenGL: OpenGL vendor  : NVIDIA Corporation
452012-12-25 21:45:23.070290 I  OpenGL: OpenGL renderer: GeForce 6150/PCI/SSE2/3DNOW!
462012-12-25 21:45:23.070297 I  OpenGL: OpenGL version : 2.1.0 NVIDIA 96.43.19
472012-12-25 21:45:23.070316 I  OpenGL: Max texture size: 4096 x 4096
482012-12-25 21:45:23.070325 I  OpenGL: Max texture units: 4
492012-12-25 21:45:23.070337 I  OpenGL: Direct rendering: Yes
502012-12-25 21:45:23.070346 I  OpenGL: PixelBufferObject support available
512012-12-25 21:45:23.070357 I  OpenGL: Initialised MythRenderOpenGL
522012-12-25 21:45:23.193646 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
53                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 26
54                        Name: 'basemedium'      Type: 'fontdef'
552012-12-25 21:45:23.193780 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
56                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 30
57                        Name: 'basemediumbold'  Type: 'fontdef'
582012-12-25 21:45:23.208801 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
59                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 35
60                        Name: 'baselarge'       Type: 'fontdef'
612012-12-25 21:45:23.225492 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
62                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 43
63                        Name: 'baselargenormal' Type: 'fontdef'
642012-12-25 21:45:23.240295 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
65                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 47
66                        Name: 'baseextralarge'  Type: 'fontdef'
672012-12-25 21:45:23.241425 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
68                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 111
69                        Name: 'basemedium_normal'       Type: 'fontdef'
702012-12-25 21:45:23.241500 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
71                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 114
72                        Name: 'basemedium_normal_selected'      Type: 'fontdef'
732012-12-25 21:45:23.241572 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
74                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 117
75                        Name: 'basemedium_disabled'     Type: 'fontdef'
762012-12-25 21:45:23.241646 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
77                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 120
78                        Name: 'basemedium_highlight'    Type: 'fontdef'
792012-12-25 21:45:23.241719 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
80                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 123
81                        Name: 'basemedium_normal_button'        Type: 'fontdef'
822012-12-25 21:45:23.241793 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
83                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 126
84                        Name: 'basemedium_selected_button'      Type: 'fontdef'
852012-12-25 21:45:23.241866 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
86                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 129
87                        Name: 'basemedium_disabled_button'      Type: 'fontdef'
882012-12-25 21:45:23.258271 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
89                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 134
90                        Name: 'deleterecordings_filesize_font'  Type: 'fontdef'
912012-12-25 21:45:23.474355 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
92                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 1073
93                        Name: 'large'   Type: 'fontdef'
942012-12-25 21:45:23.474501 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
95                        Location: /usr/share/mythtv/themes/MythCenter-wide/base.xml @ 1079
96                        Name: 'clock'   Type: 'fontdef'
972012-12-25 21:45:23.519041 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
98                        Location: /usr/share/mythtv/themes/default-wide/base.xml @ 60
99                        Name: 'basemediumgrey'  Type: 'fontdef'
1002012-12-25 21:45:23.519129 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
101                        Location: /usr/share/mythtv/themes/default-wide/base.xml @ 64
102                        Name: 'basemediumgreen' Type: 'fontdef'
1032012-12-25 21:45:23.519203 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
104                        Location: /usr/share/mythtv/themes/default-wide/base.xml @ 68
105                        Name: 'basemediumred'   Type: 'fontdef'
1062012-12-25 21:45:23.519276 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
107                        Location: /usr/share/mythtv/themes/default-wide/base.xml @ 72
108                        Name: 'basemediumpurple'        Type: 'fontdef'
1092012-12-25 21:45:23.565783 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
110                        Location: /usr/share/mythtv/themes/default/base.xml @ 80
111                        Name: 'basemediumyellow'        Type: 'fontdef'
1122012-12-25 21:45:23.587107 I  Current MythTV Schema Version (DBSchemaVer): 1299
1132012-12-25 21:45:23.587702 I  VDP: decoder<->render support: ffmpeg      null xlib xshm xv-blit opengl opengl-lite vdpau nullvdpau nullvaapi openglvaapi
1142012-12-25 21:45:23.587715 I  VDP: decoder<->render support: vdpau       vdpau nullvdpau
1152012-12-25 21:45:23.587724 I  VDP: decoder<->render support: vaapi       nullvaapi openglvaapi
1162012-12-25 21:45:23.587735 I  VDP: decoder<->render support: crystalhd   null xlib xshm xv-blit opengl opengl-lite vdpau nullvdpau nullvaapi
1172012-12-25 21:45:23.590454 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))
1182012-12-25 21:45:23.590517 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))
1192012-12-25 21:45:23.591249 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))
1202012-12-25 21:45:23.591302 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))
1212012-12-25 21:45:23.593629 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))
1222012-12-25 21:45:23.593684 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))
1232012-12-25 21:45:23.833890 N  Registering Internal as a media playback plugin.
1242012-12-25 21:45:23.912921 I  Loading en_us translation for module mytharchive
1252012-12-25 21:45:23.917180 N  Registering WebBrowser as a media playback plugin.
1262012-12-25 21:45:23.917317 I  Loading en_us translation for module mythbrowser
1272012-12-25 21:45:23.935032 I  Loading en_us translation for module mythgallery
1282012-12-25 21:45:23.940265 I  Loading en_us translation for module mythgame
1292012-12-25 21:45:23.949542 I  Current MythMusic Schema Version (MusicDBSchemaVer): 1019
1302012-12-25 21:45:23.962584 I  Loading en_us translation for module mythmusic
1312012-12-25 21:45:23.964917 I  Loading en_us translation for module mythnetvision
1322012-12-25 21:45:23.968501 I  Loading en_us translation for module mythnews
1332012-12-25 21:45:23.969194 !  MythPlugin::init() dlerror: libmythdb-0.24.so.0: cannot open shared object file: No such file or directory
1342012-12-25 21:45:23.969222 E  Unable to initialize plugin 'mythsmolt'.
1352012-12-25 21:45:23.978406 !  Plugin mythstream (0.24.20110505-1) binary version does not match libraries (0.25.20120506-1)
1362012-12-25 21:45:23.978444 E  Unable to initialize plugin 'mythstream'.
1372012-12-25 21:45:23.983473 I  Loading en_us translation for module mythweather
1382012-12-25 21:45:23.985232 I  Listening on TCP 127.0.0.1:6546
1392012-12-25 21:45:23.985326 I  Listening on TCP 192.168.1.210:6546
1402012-12-25 21:45:23.985433 I  Listening on TCP [0:0:0:0:0:0:0:1]:6546
1412012-12-25 21:45:23.985530 I  Listening on TCP [fe80:0:0:0:21a:92ff:fecb:5342%eth0]:6546
1422012-12-25 21:45:23.992364 E  MythFontProperties: Failed to load 'Liberation Sans', got 'DejaVu Sans' instead
143                        Location: /usr/share/mythtv/themes/MythCenter-wide/menu-ui.xml @ 8
144                        Name: 'menufont'        Type: 'fontdef'
1452012-12-25 21:45:24.113310 N  Found mainmenu.xml for theme 'MythCenter-wide'
1462012-12-25 21:45:24.316843 I  MythCoreContext: Connecting to backend server: 192.168.1.210:6543 (try 1 of 1)
1472012-12-25 21:45:24.317811 I  Using protocol version 72
1482012-12-25 21:45:24.333321 I  Bonjour: Service registration complete: name 'Mythfrontend on LinHES' type '_mythfrontend._tcp.' domain: 'local.'
1492012-12-25 21:45:35.415799 I  TV: Creating TV object
1502012-12-25 21:45:35.471705 I  TV: Finished creating TV object
1512012-12-25 21:45:35.471827 I  TV: StartTV() -- begin
1522012-12-25 21:45:35.472093 N  Resuming idle timer
1532012-12-25 21:45:35.472139 N  Suspending idle timer
1542012-12-25 21:45:35.472163 I  TV: Init -- begin
1552012-12-25 21:45:35.478640 I  TV: Created TvPlayWindow.
1562012-12-25 21:45:35.479279 I  TV: DrawUnusedRects() -- begin
1572012-12-25 21:45:35.479306 I  TV: DrawUnusedRects() -- end
1582012-12-25 21:45:35.483337 I  TV: Init -- end
1592012-12-25 21:45:35.498507 I  TV: tv->LiveTV() -- begin
1602012-12-25 21:45:35.511552 I  TV: HandleStateChange(0) -- begin
1612012-12-25 21:45:35.511597 I  TV: Attempting to change from None to WatchingLiveTV
1622012-12-25 21:45:35.511664 I  MythCoreContext: Connecting to backend server: 192.168.1.210:6543 (try 1 of 1)
1632012-12-25 21:45:35.512859 I  Using protocol version 72
1642012-12-25 21:45:35.561742 I  MythCoreContext: Connecting to backend server: 192.168.1.210:6543 (try 1 of 1)
1652012-12-25 21:45:35.562621 I  Using protocol version 72
1662012-12-25 21:45:35.563447 N  TV: Spawning LiveTV Recorder -- begin
1672012-12-25 21:45:35.664070 N  TV: Spawning LiveTV Recorder -- end
1682012-12-25 21:45:35.665656 I  LiveTVChain(live-LinHES-2012-12-25T21:45:35): ReloadAll(): Added new recording
1692012-12-25 21:45:35.665743 I  LiveTVChain(live-LinHES-2012-12-25T21:45:35): LiveTVChain has 1 entries
170*  DUMMY: 3939 (21:45:35 to 23:28:00)
171
1722012-12-25 21:45:35.678249 I  TV: playbackURL(myth://192.168.1.210:6543/3939_20121225214535.mpg) cardtype(DUMMY)
1732012-12-25 21:45:35.680670 I  TV: StartRecorder(): took 0 ms to start recorder.
1742012-12-25 21:45:35.680720 I  TV: StartPlayer(0, WatchingLiveTV, main) -- begin
1752012-12-25 21:45:35.680750 I  TV: Elapsed time since TV constructor was called: 265 ms
1762012-12-25 21:45:35.899855 N  AudioPlayer: Enabling Audio
1772012-12-25 21:45:35.899994 I  Player(0): SetFrameInterval ps:1 scan:3
1782012-12-25 21:45:35.900050 I  Player(0): detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
1792012-12-25 21:45:35.903809 I  Player(0): Stretch Factor 1, allow passthru
1802012-12-25 21:45:35.905498 I  VideoOutput: Allowed renderers: xv-blit,xshm,xlib,opengl,opengl-lite,vdpau,openglvaapi
1812012-12-25 21:45:35.905570 I  VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,openglvaapi
1822012-12-25 21:45:35.908743 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
1832012-12-25 21:45:35.908826 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
1842012-12-25 21:45:35.908870 I  VDP: LoadBestPreferences(2048x2048, 0)
1852012-12-25 21:45:35.908925 I  VDP: LoadBestPreferences(2048x2048, 60)
1862012-12-25 21:45:35.908990 I  VDP: LoadBestPreferences(720x576, 60)
1872012-12-25 21:45:35.909052 I  VideoOutput: Preferred renderer: xv-blit
1882012-12-25 21:45:35.909093 I  VideoOutput: Trying video renderer: 'xv-blit'
1892012-12-25 21:45:35.926740 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
1902012-12-25 21:45:35.926825 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
1912012-12-25 21:45:35.926858 I  VDP: LoadBestPreferences(2048x2048, 0)
1922012-12-25 21:45:35.926908 I  VDP: LoadBestPreferences(2048x2048, 60)
1932012-12-25 21:45:35.926974 I  VideoOutputXv: ctor
1942012-12-25 21:45:35.927035 I  VideoOutWindow::SetPIPState. pip_state: 0]
1952012-12-25 21:45:35.927058 I  VDP: LoadBestPreferences(2048x2048, 25)
1962012-12-25 21:45:35.928833 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.33333
1972012-12-25 21:45:35.928871 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
1982012-12-25 21:45:35.928896 I  VDP: LoadBestPreferences(720x576, 25)
1992012-12-25 21:45:35.928991 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.33333
2002012-12-25 21:45:35.929020 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
2012012-12-25 21:45:35.931100 I  VideoOutput: Pixel dimensions: Screen 1280x720, window 1280x720
2022012-12-25 21:45:35.931149 I  VideoOutput: Actual display dimensions: 325x183 mm  Aspect: 1.77596
2032012-12-25 21:45:35.931175 I  VideoOutput: Estimated window dimensions: 325x183 mm  Aspect: 1.77596
2042012-12-25 21:45:35.931999 I  VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
2052012-12-25 21:45:35.934339 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2062012-12-25 21:45:35.934422 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
2072012-12-25 21:45:35.934453 I  VDP: LoadBestPreferences(2048x2048, 0)
2082012-12-25 21:45:35.934501 I  VDP: LoadBestPreferences(2048x2048, 60)
2092012-12-25 21:45:35.934540 I  VDP: LoadBestPreferences(720x576, 60)
2102012-12-25 21:45:35.934835 I  VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  2
2112012-12-25 21:45:35.934862 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
2122012-12-25 21:45:35.934875 I  VideoOutputXv: Has XVideo flags...
2132012-12-25 21:45:35.935038 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2142012-12-25 21:45:35.935077 I  VideoOutputXv: Adaptor#1: NV05 Video Blitter has flag[s]: XvInputMask XvImageMask
2152012-12-25 21:45:35.935093 I  VideoOutputXv: Has XVideo flags...
2162012-12-25 21:45:35.935204 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
2172012-12-25 21:45:35.935230 I  VideoOutputXv: @ j=1 Looking for flag[s]: XvInputMask XvImageMask  0
2182012-12-25 21:45:35.935260 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
2192012-12-25 21:45:35.935275 I  VideoOutputXv: Has XVideo flags...
2202012-12-25 21:45:35.935399 I  VideoOutputXv: Grabbed xv port 281
2212012-12-25 21:45:35.935538 I  VideoOutputXv: XVideo surface found on port 281
2222012-12-25 21:45:35.935552 I  VideoOutputXv: XV_SET_DEFAULTS is supported on this port
2232012-12-25 21:45:35.935833 I  VideoOutputXv: XV_SYNC_TO_VBLANK supported
2242012-12-25 21:45:35.935860 I  VideoOutputXv: XVideo Sync to VBlank set
2252012-12-25 21:45:35.935902 I  VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2262012-12-25 21:45:35.936062 I  VideoOutputXv: XVideo Format #0 is 'YUY2'
2272012-12-25 21:45:35.936099 I  VideoOutputXv: XVideo Format #1 is 'YV12'
2282012-12-25 21:45:35.936125 I  VideoOutputXv: XVideo Format #2 is 'UYVY'
2292012-12-25 21:45:35.936173 I  VideoOutputXv: XVideo Format #3 is 'I420'
2302012-12-25 21:45:35.936198 I  VideoOutputXv: Using XVideo Format 'YV12'
2312012-12-25 21:45:35.936239 I  VideoOutputXv: CreateShmImages(32): video_dim: 720x576
2322012-12-25 21:45:36.012850 I  VDP: SetVideoRenderer(xv-blit)
2332012-12-25 21:45:36.012890 I  VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2342012-12-25 21:45:36.013751 I  ColourSpace: PictureAttributes:
2352012-12-25 21:45:36.014031 I  VideoOutputXv: Chromakeying not possible with this XVideo port.
2362012-12-25 21:45:36.014158 I  Display Rect  left: 160, top: 0, width: 960, height: 720, aspect: 1.77778
2372012-12-25 21:45:36.014201 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
2382012-12-25 21:45:36.017806 I  Over/underscan. V: 0.02, H: 0.02
2392012-12-25 21:45:36.017919 I  Display Rect  left: 160, top: 0, width: 960, height: 720, aspect: 1.77778
2402012-12-25 21:45:36.017977 I  Video Rect    left: 14, top: 12, width: 691, height: 553, aspect: 1.33333
2412012-12-25 21:45:36.018035 I  Player(0): LoadFilters(''..) -> 0x0
2422012-12-25 21:45:36.020529 I  OSD: Base theme size: 1280x720
2432012-12-25 21:45:36.020577 I  OSD: Scaling factors: 0.540625x0.769444
2442012-12-25 21:45:36.024443 I  Player(0): Decoder thread starting.
2452012-12-25 21:45:36.095388 I  OSD: Loaded window osd_message
2462012-12-25 21:45:36.104352 I  OSD: Loaded window osd_input
2472012-12-25 21:45:36.208255 I  OSD: Loaded window program_info
2482012-12-25 21:45:36.213606 I  OSD: Loaded window browse_info
2492012-12-25 21:45:36.219145 I  OSD: Loaded window osd_status
2502012-12-25 21:45:36.224071 I  OSD: Loaded window osd_program_editor
2512012-12-25 21:45:36.266907 I  OSD: Loaded window osd_debug
2522012-12-25 21:45:36.266965 I  OSD: Loaded OSD: size 692x554 offset 14+11
2532012-12-25 21:45:36.267024 I  OSD: Base theme size: 1280x720
2542012-12-25 21:45:36.267041 I  OSD: Scaling factors: 0.540625x0.769444
2552012-12-25 21:45:36.268928 I  OSD: Created window aa_OSD_SUBTITLES
2562012-12-25 21:45:36.269009 I  Player(0): ClearAfterSeek(0)
2572012-12-25 21:45:36.270276 I  VDP: GetFilteredDeint() : xv-blit -> 'greedyhdoubleprocessdeint'
2582012-12-25 21:45:36.271274 I  FilterManager: GetFilterInfo(convert) returning: 0x0
2592012-12-25 21:45:36.271302 I  FilterManager: GetFilterInfo(greedyhdoubleprocessdeint) returning: 0xffffffffa5972ce8
2602012-12-25 21:45:36.273805 I  VideoOutput: Using deinterlace method greedyhdoubleprocessdeint
2612012-12-25 21:45:36.273899 I  VSYNC: DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2622012-12-25 21:45:36.273964 E  VSYNC: RTCVideoSync: Could not set RTC frequency:
263                        eno: Permission denied (13)
2642012-12-25 21:45:36.275228 I  Player(0): Video timing method: USleep with busy wait
2652012-12-25 21:45:36.275252 I  Player(0): Display Refresh Rate: 60.074 Video Frame Rate: 25.000
2662012-12-25 21:45:36.275274 I  Player(0): SetFrameInterval ps:1 scan:1
2672012-12-25 21:45:36.275485 I  playCtx: StartPlaying(): took 0 ms to start player.
2682012-12-25 21:45:36.275517 I  TV: Created player.
2692012-12-25 21:45:36.275547 I  TV: StartPlayer(0, WatchingLiveTV, main) -- end ok
2702012-12-25 21:45:36.275570 I  TV: Changing from None to WatchingLiveTV
2712012-12-25 21:45:36.275581 I  TV: State is LiveTV & mctx == ctx
2722012-12-25 21:45:36.276898 I  TV: UpdateOSDInput done
2732012-12-25 21:45:36.276923 I  TV: UpdateLCD done
2742012-12-25 21:45:36.277215 I  TV: ITVRestart done
2752012-12-25 21:45:36.300365 I  TV: DrawUnusedRects() -- begin
2762012-12-25 21:45:36.300385 I  TV: DrawUnusedRects() -- end
2772012-12-25 21:45:36.300464 I  TV: Main UI disabled.
2782012-12-25 21:45:36.300476 I  TV: HandleStateChange(0) -- end
2792012-12-25 21:45:36.301039 I  TV: tv->LiveTV() -- end
2802012-12-25 21:45:36.301048 I  TV: Entering main playback loop.
2812012-12-25 21:45:36.378159 I  VideoOutput: Created YV12 OSD.
2822012-12-25 21:45:36.773519 I  LiveTVChain(live-LinHES-2012-12-25T21:45:35): ReloadAll(): Added new recording
2832012-12-25 21:45:36.773617 I  LiveTVChain(live-LinHES-2012-12-25T21:45:35): LiveTVChain has 2 entries
284*  DUMMY: 3939 (21:45:35 to 21:45:36)
285  HDHOMERUN: 3939 (21:45:36 to 23:28:00) discontinuous
286
2872012-12-25 21:45:36.775011 I  LiveTVChain(live-LinHES-2012-12-25T21:45:35): SwitchTo(1)
2882012-12-25 21:45:36.775073 I  Player(0): JumpToProgram - start
2892012-12-25 21:45:36.783998 I  Player(0): LoadFilters(''..) -> 0x0
2902012-12-25 21:45:36.784925 I  Player(0): Play speed: rate: 25 speed: 0 skip: 0 => new interval 40000
2912012-12-25 21:45:36.785757 I  VDP: GetFilteredDeint(linearblend) : xv-blit -> 'linearblend'
2922012-12-25 21:45:36.787706 I  FilterManager: GetFilterInfo(convert) returning: 0x0
2932012-12-25 21:45:36.787743 I  FilterManager: GetFilterInfo(linearblend) returning: 0x9de2890
2942012-12-25 21:45:36.787809 I  VideoOutput: Using deinterlace method linearblend
2952012-12-25 21:45:36.790528 I  FileRingBuf(myth://192.168.1.210:6543/3939_20121225214535.mpg): OpenFile(myth://192.168.1.210:6543/3939_20121225214536.mpg, 10000 ms)
2962012-12-25 21:45:36.792046 I  Using protocol version 72
2972012-12-25 21:45:36.793635 I  Using protocol version 72
2982012-12-25 21:45:38.338166 I  Player(0): Stretch Factor 1, allow passthru
2992012-12-25 21:45:38.338905 I  AFD: Buffer size: 32768, streamed 1
3002012-12-25 21:45:39.045784 I  AFD: Stream #0, has id 0x840 codec id MPEG2VIDEO, type Video, bitrate 20000000 at 0x9de0400
3012012-12-25 21:45:39.048435 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
3022012-12-25 21:45:39.048521 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
3032012-12-25 21:45:39.048554 I  VDP: LoadBestPreferences(2048x2048, 0)
3042012-12-25 21:45:39.048605 I  VDP: LoadBestPreferences(2048x2048, 60)
3052012-12-25 21:45:39.048644 I  VDP: LoadBestPreferences(1920x1080, 60)
3062012-12-25 21:45:39.048740 I  AFD: Using 2 CPUs for decoding
3072012-12-25 21:45:39.048770 I  AFD: InitVideoCodec() 0xa7ca460 id(MPEG2VIDEO) type (Video).
3082012-12-25 21:45:39.048873 I  AFD: Selected FPS is 29.97 (avg 29.97 codec 29.97 container 90000 estimated 29.97)
3092012-12-25 21:45:39.048901 I  Player(0): SetFrameInterval ps:0 scan:3
3102012-12-25 21:45:39.051345 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
3112012-12-25 21:45:39.051429 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
3122012-12-25 21:45:39.051457 I  VDP: LoadBestPreferences(2048x2048, 0)
3132012-12-25 21:45:39.051503 I  VDP: LoadBestPreferences(2048x2048, 60)
3142012-12-25 21:45:39.051562 I  VDP: LoadBestPreferences(1920x1080, 60)
3152012-12-25 21:45:39.051653 I  VDP: LoadBestPreferences(720x576, 29.97)
3162012-12-25 21:45:39.051720 I  VideoOutputXv: InputChanged(1920,1080,1.33333) 'None'->'MPEG2'
3172012-12-25 21:45:39.051746 I  VDP: LoadBestPreferences(1920x1088, 29.97)
3182012-12-25 21:45:39.051891 I  VDP: GetFilteredDeint() : xv-blit -> 'bobdeint'
3192012-12-25 21:45:39.053813 I  FilterManager: GetFilterInfo(convert) returning: 0x0
3202012-12-25 21:45:39.053849 I  FilterManager: GetFilterInfo(bobdeint) returning: 0x9dbd258
3212012-12-25 21:45:39.053911 I  VideoOutput: Using deinterlace method bobdeint
3222012-12-25 21:45:39.053928 I  VideoOutputXv: DiscardFrames(1)
3232012-12-25 21:45:39.054056 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
3242012-12-25 21:45:39.054138 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
3252012-12-25 21:45:39.054202 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
3262012-12-25 21:45:39.054216 I  VideoOutputXv: DiscardFrames(1)
3272012-12-25 21:45:39.054274 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
3282012-12-25 21:45:39.054335 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
3292012-12-25 21:45:39.054393 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
3302012-12-25 21:45:39.059114 I  VideoOutputXv: Closing XVideo port 281
3312012-12-25 21:45:39.067508 I  VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
3322012-12-25 21:45:39.070242 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
3332012-12-25 21:45:39.070330 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
3342012-12-25 21:45:39.070362 I  VDP: LoadBestPreferences(2048x2048, 0)
3352012-12-25 21:45:39.070411 I  VDP: LoadBestPreferences(2048x2048, 60)
3362012-12-25 21:45:39.070450 I  VDP: LoadBestPreferences(1920x1088, 60)
3372012-12-25 21:45:39.070717 I  VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  2
3382012-12-25 21:45:39.070740 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
3392012-12-25 21:45:39.070753 I  VideoOutputXv: Has XVideo flags...
3402012-12-25 21:45:39.070857 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
3412012-12-25 21:45:39.070877 I  VideoOutputXv: Adaptor#1: NV05 Video Blitter has flag[s]: XvInputMask XvImageMask
3422012-12-25 21:45:39.070889 I  VideoOutputXv: Has XVideo flags...
3432012-12-25 21:45:39.071097 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
3442012-12-25 21:45:39.071155 I  VideoOutputXv: @ j=1 Looking for flag[s]: XvInputMask XvImageMask  0
3452012-12-25 21:45:39.071184 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
3462012-12-25 21:45:39.071197 I  VideoOutputXv: Has XVideo flags...
3472012-12-25 21:45:39.071353 I  VideoOutputXv: Grabbed xv port 281
3482012-12-25 21:45:39.071623 I  VideoOutputXv: XVideo surface found on port 281
3492012-12-25 21:45:39.071640 I  VideoOutputXv: XV_SET_DEFAULTS is supported on this port
3502012-12-25 21:45:39.071852 I  VideoOutputXv: XV_SYNC_TO_VBLANK supported
3512012-12-25 21:45:39.071868 I  VideoOutputXv: XVideo Sync to VBlank set
3522012-12-25 21:45:39.071913 I  VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
3532012-12-25 21:45:39.072084 I  VideoOutputXv: XVideo Format #0 is 'YUY2'
3542012-12-25 21:45:39.072105 I  VideoOutputXv: XVideo Format #1 is 'YV12'
3552012-12-25 21:45:39.072124 I  VideoOutputXv: XVideo Format #2 is 'UYVY'
3562012-12-25 21:45:39.072142 I  VideoOutputXv: XVideo Format #3 is 'I420'
3572012-12-25 21:45:39.072160 I  VideoOutputXv: Using XVideo Format 'YV12'
3582012-12-25 21:45:39.072180 I  VideoOutputXv: CreateShmImages(32): video_dim: 1920x1088
3592012-12-25 21:45:39.360086 I  VDP: SetVideoRenderer(xv-blit)
3602012-12-25 21:45:39.360113 I  VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
3612012-12-25 21:45:39.360513 I  ColourSpace: PictureAttributes:
3622012-12-25 21:45:39.360630 I  VideoOutputXv: Chromakeying not possible with this XVideo port.
3632012-12-25 21:45:39.360673 I  Display Rect  left: 160, top: 0, width: 960, height: 720, aspect: 1.77778
3642012-12-25 21:45:39.360688 I  Video Rect    left: 38, top: 22, width: 1843, height: 1037, aspect: 1.33333
3652012-12-25 21:45:39.362937 I  OSD: Base theme size: 1280x720
3662012-12-25 21:45:39.362982 I  OSD: Scaling factors: 1.44062x1.44167
3672012-12-25 21:45:39.397169 I  OSD: Loaded window osd_message
3682012-12-25 21:45:39.401716 I  OSD: Loaded window osd_input
3692012-12-25 21:45:39.525908 I  OSD: Loaded window program_info
3702012-12-25 21:45:39.532572 I  OSD: Loaded window browse_info
3712012-12-25 21:45:39.545982 I  OSD: Loaded window osd_status
3722012-12-25 21:45:39.557294 I  OSD: Loaded window osd_program_editor
3732012-12-25 21:45:39.605735 I  OSD: Loaded window osd_debug
3742012-12-25 21:45:39.605766 I  OSD: Loaded OSD: size 1844x1038 offset 38+21
3752012-12-25 21:45:39.605814 I  EnableCaptions(0) msg:  On
3762012-12-25 21:45:39.605829 I  Player(0): ClearAfterSeek(1)
3772012-12-25 21:45:39.605836 I  VideoOutputXv: ClearAfterSeek()
3782012-12-25 21:45:39.605844 I  VideoOutputXv: DiscardFrames(0)
3792012-12-25 21:45:39.605882 I  VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
3802012-12-25 21:45:39.605915 I  VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
3812012-12-25 21:45:39.605968 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
3822012-12-25 21:45:39.605991 I  Player(0): LoadFilters(''..) -> 0x0
3832012-12-25 21:45:39.606018 I  Player(0): detectInterlace(Detect Scan, Interlaced Scan, 29.97, 1080) ->Interlaced Scan
3842012-12-25 21:45:39.606033 I  Player(0): Enabled deinterlacing
3852012-12-25 21:45:39.606102 I  AFD: Using ffmpeg for video decoding
3862012-12-25 21:45:39.606112 I  AFD: Looking for decoder for MPEG2VIDEO
3872012-12-25 21:45:39.610602 I  AFD: Opened codec 0xa7ca460, id(MPEG2VIDEO) type(Video)
3882012-12-25 21:45:39.610638 I  AFD: Stream #1, has id 0x841 codec id AC3, type Audio, bitrate 384000 at 0xa113bc0
3892012-12-25 21:45:39.610650 I  AFD: codec AC3 has 6 channels
3902012-12-25 21:45:39.610658 I  AFD: Looking for decoder for AC3
3912012-12-25 21:45:39.611165 I  AFD: Opened codec 0x9e194c0, id(AC3) type(Audio)
3922012-12-25 21:45:39.611217 I  AFD: Stream #2, has id 0x842 codec id AC3, type Audio, bitrate 384000 at 0xa51c820
3932012-12-25 21:45:39.611226 I  AFD: codec AC3 has 2 channels
3942012-12-25 21:45:39.611233 I  AFD: Looking for decoder for AC3
3952012-12-25 21:45:39.611727 I  AFD: Opened codec 0xa4c4b20, id(AC3) type(Audio)
3962012-12-25 21:45:39.618866 I  AO: Opening audio device 'iec958:CARD=NVidia,DEV=0' ch 2(6) sr 48000 sf signed 16 bit reenc 0
3972012-12-25 21:45:39.621158 E  ALSA: Setting hardware audio buffer size to 128
3982012-12-25 21:45:39.621278 E  ALSA: Error opening /proc/asound/card0/pcm1p/sub0/prealloc: Permission denied.
3992012-12-25 21:45:39.621291 E  ALSA: Try to manually increase audio buffer with: echo 128 | sudo tee /proc/asound/card0/pcm1p/sub0/prealloc
4002012-12-25 21:45:39.621314 E  ALSA: Unable to sufficiently increase ALSA hardware buffer size - underruns are likely
4012012-12-25 21:45:39.630243 I  Dec: Selected track #1 in the Unknown language(0)
4022012-12-25 21:45:39.630297 I  Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
4032012-12-25 21:45:39.631173 I  Dec: Position map filled from DB to: 0
4042012-12-25 21:45:39.631186 I  Dec: SyncPositionMap watchingrecording, from DB: 1 entries
4052012-12-25 21:45:39.631205 I  Player(0): Filling position map from 1 to end
4062012-12-25 21:45:39.631696 I  Dec: Position map filled from Encoder to: 36
4072012-12-25 21:45:39.631715 I  Dec: SyncPositionMap watchingrecording total: 2 entries
4082012-12-25 21:45:39.631731 I  Dec: SyncPositionMap, new totframes: 36, new length: 1, posMap size: 2
4092012-12-25 21:45:39.633193 I  AFD: Partial position map found
4102012-12-25 21:45:39.633209 I  AFD: Successfully opened decoder for file: "myth://192.168.1.210:6543/3939_20121225214536.mpg". novideo(0)
4112012-12-25 21:45:39.637470 N  AFD: Resetting byte context eof (livetv 1 was eof 0)
4122012-12-25 21:45:40.065503 I  Player(0): Play(  1.0, normal 1, unpause audio 1)
4132012-12-25 21:45:40.065909 I  Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
4142012-12-25 21:45:40.066035 I  Player(0): Play speed: rate: 29.97 speed: 1 skip: 1 => new interval 33366
4152012-12-25 21:45:40.066098 I  Player(0): Stretch Factor 1, allow passthru
4162012-12-25 21:45:40.066119 I  Player(0): JumpToProgram - end
4172012-12-25 21:45:40.066154 I  Player(0): Waiting for video buffers...
4182012-12-25 21:45:40.067969 I  Dec: Position map filled from DB to: 0
4192012-12-25 21:45:40.067996 I  Dec: SyncPositionMap watchingrecording, from DB: 1 entries
4202012-12-25 21:45:40.068024 I  Player(0): Filling position map from 1 to end
4212012-12-25 21:45:40.068868 I  Dec: Position map filled from Encoder to: 36
4222012-12-25 21:45:40.068898 I  Dec: SyncPositionMap watchingrecording total: 2 entries
4232012-12-25 21:45:40.105652 I  Detect Letterbox: YV12 frame format detected
4242012-12-25 21:45:40.118790 I  Detect Letterbox: YV12 frame format detected
4252012-12-25 21:45:40.167762 N  Player(0): Waited 101ms for video buffers UuUULAAAAAAAAAAAAAAAAAAAAAAAAAAP
4262012-12-25 21:45:40.272367 N  Player(0): Waited 206ms for video buffers UuUULAAAAAAAAAAAAAAAAAAAAAAAAAAP
4272012-12-25 21:45:40.376240 N  Player(0): Waited 310ms for video buffers UuUULAAAAAAAAAAAAAAAAAAAAAAAAAAP
4282012-12-25 21:45:40.480182 N  Player(0): Waited 414ms for video buffers UuUULAAAAAAAAAAAAAAAAAAAAAAAAAAP
4292012-12-25 21:45:40.585441 N  Player(0): Waited 519ms for video buffers UuUULAAAAAAAAAAAAAAAAAAAAAAAAAAP
4302012-12-25 21:45:40.689610 N  Player(0): Waited 623ms for video buffers UuUULAAAAAAAAAAAAAAAAAAAAAAAAAAP
4312012-12-25 21:45:40.792969 N  Player(0): Waited 726ms for video buffers UUUULUULAAAAAAAAAAAAAAAAAAAAAAAP
4322012-12-25 21:45:40.887742 I  Player(0): Video Aspect ratio changed from 1.33333 to 1.77778
4332012-12-25 21:45:40.887808 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
4342012-12-25 21:45:40.887841 I  Video Rect    left: 38, top: 22, width: 1843, height: 1037, aspect: 1.77778
4352012-12-25 21:45:40.890097 I  OSD: Base theme size: 1280x720
4362012-12-25 21:45:40.890153 I  OSD: Scaling factors: 1.44062x1.44167
4372012-12-25 21:45:40.903660 I  OSD: Loaded window osd_message
4382012-12-25 21:45:40.908091 I  Detect Letterbox: The source is already in widescreen (aspect: 1.77778)
4392012-12-25 21:45:40.913712 I  OSD: Loaded window osd_input
4402012-12-25 21:45:40.951288 I  OSD: Loaded window program_info
4412012-12-25 21:45:40.967078 I  OSD: Loaded window browse_info
4422012-12-25 21:45:40.983858 I  OSD: Loaded window osd_status
4432012-12-25 21:45:40.996253 I  OSD: Loaded window osd_program_editor
4442012-12-25 21:45:41.034105 I  OSD: Loaded window osd_debug
4452012-12-25 21:45:41.034135 I  OSD: Loaded OSD: size 1844x1038 offset 38+21
4462012-12-25 21:45:41.034186 I  EnableCaptions(0) msg:  On
4472012-12-25 21:45:41.034205 I  Player(0): Enabled deinterlacing
4482012-12-25 21:45:41.034222 I  VideoOutput: OSD size changed.
4492012-12-25 21:45:41.120446 I  VideoOutput: Created YV12 OSD.
4502012-12-25 21:45:41.166930 I  Player(0): Video is 3.91117 frames behind audio (too slow), dropping frame to catch up.
4512012-12-25 21:45:41.170627 I  Player(0): Video is 6.61976 frames behind audio (too slow), dropping frame to catch up.
4522012-12-25 21:45:41.174586 I  Player(0): Video is 8.7561 frames behind audio (too slow), dropping frame to catch up.
4532012-12-25 21:45:41.180360 I  Player(0): Video is 10.1036 frames behind audio (too slow), dropping frame to catch up.
4542012-12-25 21:45:41.184395 I  Player(0): Video is 10.6122 frames behind audio (too slow), dropping frame to catch up.
4552012-12-25 21:45:41.188269 I  Player(0): Video is 10.7464 frames behind audio (too slow), dropping frame to catch up.
4562012-12-25 21:45:41.192248 I  Player(0): Video is 10.5773 frames behind audio (too slow), dropping frame to catch up.
4572012-12-25 21:45:41.196335 I  Player(0): Video is 10.2033 frames behind audio (too slow), dropping frame to catch up.
4582012-12-25 21:45:41.199904 I  Player(0): Video is 9.66796 frames behind audio (too slow), dropping frame to catch up.
4592012-12-25 21:45:41.203146 I  Player(0): Video is 9.01921 frames behind audio (too slow), dropping frame to catch up.
4602012-12-25 21:45:41.206495 I  Player(0): Video is 8.27792 frames behind audio (too slow), dropping frame to catch up.
4612012-12-25 21:45:41.209718 I  Player(0): Video is 7.46718 frames behind audio (too slow), dropping frame to catch up.
4622012-12-25 21:45:41.213089 I  Player(0): Video is 6.61188 frames behind audio (too slow), dropping frame to catch up.
4632012-12-25 21:45:41.216703 I  Player(0): Video is 5.72316 frames behind audio (too slow), dropping frame to catch up.
4642012-12-25 21:45:41.220534 I  Player(0): Video is 4.80186 frames behind audio (too slow), dropping frame to catch up.
4652012-12-25 21:45:41.224058 I  Player(0): Video is 3.8936 frames behind audio (too slow), dropping frame to catch up.
4662012-12-25 21:45:41.748746 I  TV: Pausing player: 0
4672012-12-25 21:45:41.815069 I  Display Rect  left: 940, top: 50, width: 300, height: 170, aspect: 1.77778
4682012-12-25 21:45:41.815099 I  Video Rect    left: 38, top: 22, width: 1843, height: 1037, aspect: 1.77778
4692012-12-25 21:45:41.815118 I  Display Rect  left: 940, top: 50, width: 300, height: 170, aspect: 1.77778
4702012-12-25 21:45:41.815135 I  Video Rect    left: 38, top: 22, width: 1843, height: 1037, aspect: 1.77778
4712012-12-25 21:45:41.815566 I  TV: DrawUnusedRects() -- begin
4722012-12-25 21:45:41.815579 I  TV: DrawUnusedRects() -- end
4732012-12-25 21:45:41.847919 I  TV: DrawUnusedRects() -- begin
4742012-12-25 21:45:41.847935 I  TV: DrawUnusedRects() -- end
4752012-12-25 21:45:41.847999 I  TV: DrawUnusedRects() -- begin
4762012-12-25 21:45:41.848006 I  TV: DrawUnusedRects() -- end
4772012-12-25 21:45:42.068862 I  Player(0): Video is 3.38093 frames behind audio (too slow), dropping frame to catch up.
4782012-12-25 21:45:42.074151 I  Player(0): Video is 3.73452 frames behind audio (too slow), dropping frame to catch up.
4792012-12-25 21:45:42.078090 I  Player(0): Video is 3.75244 frames behind audio (too slow), dropping frame to catch up.
4802012-12-25 21:45:42.084139 I  Player(0): Video is 3.51115 frames behind audio (too slow), dropping frame to catch up.
4812012-12-25 21:45:42.090274 I  Player(0): Video is 3.0829 frames behind audio (too slow), dropping frame to catch up.
4822012-12-25 21:45:42.378881 I  Player(0): Video is 3.52437 frames behind audio (too slow), dropping frame to catch up.
4832012-12-25 21:45:42.385199 I  Player(0): Video is 3.75217 frames behind audio (too slow), dropping frame to catch up.
4842012-12-25 21:45:42.391604 I  Player(0): Video is 3.66829 frames behind audio (too slow), dropping frame to catch up.
4852012-12-25 21:45:42.397857 I  Player(0): Video is 3.35812 frames behind audio (too slow), dropping frame to catch up.
4862012-12-25 21:45:43.590646 I  Player(0): Video is 3.00719 frames behind audio (too slow), dropping frame to catch up.
4872012-12-25 21:45:43.849247 I  Player(0): Video is 3.23773 frames behind audio (too slow), dropping frame to catch up.
4882012-12-25 21:45:43.853399 I  Player(0): Video is 3.80693 frames behind audio (too slow), dropping frame to catch up.
4892012-12-25 21:45:43.862770 I  Player(0): Video is 3.98657 frames behind audio (too slow), dropping frame to catch up.
4902012-12-25 21:45:43.868692 I  Player(0): Video is 3.86657 frames behind audio (too slow), dropping frame to catch up.
4912012-12-25 21:45:43.874498 I  Player(0): Video is 3.61173 frames behind audio (too slow), dropping frame to catch up.
4922012-12-25 21:45:43.879270 I  Player(0): Video is 3.21828 frames behind audio (too slow), dropping frame to catch up.
4932012-12-25 21:45:44.089593 I  Player(0): FPS:   34.18 Mean: 29252 Std.Dev: 25651 CPUs: 297% 54%
4942012-12-25 21:45:47.486188 I  Player(0): FPS:   29.45 Mean: 33955 Std.Dev:  5002 CPUs: 85% 86%
4952012-12-25 21:45:49.173413 I  Player(0): Video is 3.02149 frames behind audio (too slow), dropping frame to catch up.
4962012-12-25 21:45:49.178891 I  Player(0): Video is 3.12776 frames behind audio (too slow), dropping frame to catch up.
4972012-12-25 21:45:49.214483 I  Player(0): Video is 3.19247 frames behind audio (too slow), dropping frame to catch up.
4982012-12-25 21:45:49.921894 I  Player(0): Video is 3.20914 frames behind audio (too slow), dropping frame to catch up.
4992012-12-25 21:45:49.927892 I  Player(0): Video is 3.66562 frames behind audio (too slow), dropping frame to catch up.
5002012-12-25 21:45:49.952917 I  Player(0): Video is 3.80567 frames behind audio (too slow), dropping frame to catch up.
5012012-12-25 21:45:49.964012 I  Player(0): Video is 3.84328 frames behind audio (too slow), dropping frame to catch up.
5022012-12-25 21:45:49.972485 I  Player(0): Video is 3.66918 frames behind audio (too slow), dropping frame to catch up.
5032012-12-25 21:45:49.975575 I  Player(0): Video is 3.35129 frames behind audio (too slow), dropping frame to catch up.
5042012-12-25 21:45:50.916468 I  Player(0): FPS:   29.16 Mean: 34291 Std.Dev: 14311 CPUs: 79% 90%
5052012-12-25 21:45:51.172263 I  Player(0): Video is 3.15276 frames behind audio (too slow), dropping frame to catch up.
5062012-12-25 21:45:51.178142 I  Player(0): Video is 3.33112 frames behind audio (too slow), dropping frame to catch up.
5072012-12-25 21:45:51.183913 I  Player(0): Video is 3.21762 frames behind audio (too slow), dropping frame to catch up.
5082012-12-25 21:45:52.157890 I  Player(0): Video is 3.21621 frames behind audio (too slow), dropping frame to catch up.
5092012-12-25 21:45:52.178649 I  Player(0): Video is 3.35623 frames behind audio (too slow), dropping frame to catch up.
5102012-12-25 21:45:52.185285 I  Player(0): Video is 3.68603 frames behind audio (too slow), dropping frame to catch up.
5112012-12-25 21:45:52.211178 I  Player(0): Video is 3.4014 frames behind audio (too slow), dropping frame to catch up.
5122012-12-25 21:45:53.362195 I  Player(0): Video is 3.03848 frames behind audio (too slow), dropping frame to catch up.
5132012-12-25 21:45:53.576404 I  AFD: EIA-708 caption service #1 is in the Undetermined language.
5142012-12-25 21:45:53.594722 I  Dec: Selected track #1 in the Undetermined language(7695972)
5152012-12-25 21:45:53.838136 I  AFD: EIA-708 caption service #1 is in the Undetermined language.
5162012-12-25 21:45:53.838160 I  AFD: EIA-608 caption service #1 is in the Undetermined language.
5172012-12-25 21:45:53.860715 I  Dec: Selected track #1 in the Undetermined language(7695972)
5182012-12-25 21:45:54.195557 I  Player(0): 400 interlaced frames seen.
5192012-12-25 21:45:54.329908 I  Player(0): FPS:   29.31 Mean: 34123 Std.Dev: 14552 CPUs: 67% 74%
5202012-12-25 21:45:54.530271 I  Player(0): progressive frame seen after 409 interlaced frames
5212012-12-25 21:45:54.597090 I  Player(0): Disabled deinterlacing
5222012-12-25 21:45:56.696036 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
5232012-12-25 21:45:56.696087 I  Video Rect    left: 38, top: 22, width: 1843, height: 1037, aspect: 1.77778
5242012-12-25 21:45:56.696120 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
5252012-12-25 21:45:56.696148 I  Video Rect    left: 38, top: 22, width: 1843, height: 1037, aspect: 1.77778
5262012-12-25 21:45:56.696194 I  Clearing OpenGL painter cache.
5272012-12-25 21:45:56.891377 I  TV: DrawUnusedRects() -- begin
5282012-12-25 21:45:56.891407 I  TV: DrawUnusedRects() -- end
5292012-12-25 21:45:56.891604 I  TV: CommitQueuedInput() livetv(1) qchannum(31.1) qchanid(2161)
5302012-12-25 21:45:57.007604 I  Player(0): StopPlaying - begin
5312012-12-25 21:45:57.009283 I  Player(0): Decoder thread exiting.
5322012-12-25 21:45:57.009673 I  Player(0): Exited decoder loop.
5332012-12-25 21:45:57.010767 I  VideoOutputXv: dtor
5342012-12-25 21:45:57.010798 I  VideoOutputXv: DiscardFrames(1)
5352012-12-25 21:45:57.010878 I  VideoBuffers::DiscardFrames(1): UUUUUUUUUUUUUuUUUUuUULUUUUUUUUUP
5362012-12-25 21:45:57.011022 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
5372012-12-25 21:45:57.011092 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
5382012-12-25 21:45:57.021376 I  VideoOutputXv: Closing XVideo port 281
5392012-12-25 21:45:57.073821 W  MythPainter: 16 images not yet de-allocated.
5402012-12-25 21:45:57.086157 I  Player(0): StopPlaying - end
5412012-12-25 21:45:57.163918 I  Player(0): StopPlaying - begin
5422012-12-25 21:45:57.163986 I  Player(0): Exited decoder loop.
5432012-12-25 21:45:57.164006 I  Player(0): StopPlaying - end
5442012-12-25 21:45:57.169277 I  MythCoreContext: Connecting to backend server: 192.168.1.210:6543 (try 1 of 1)
5452012-12-25 21:45:57.170505 I  Using protocol version 72
5462012-12-25 21:45:57.279408 I  LiveTVChain(live-LinHES-2012-12-25T21:45:35): ReloadAll(): Added new recording
5472012-12-25 21:45:57.279554 I  LiveTVChain(live-LinHES-2012-12-25T21:45:35): LiveTVChain has 3 entries
548   DUMMY: 3939 (21:45:35 to 21:45:36)
549* HDHOMERUN: 3939 (21:45:36 to 21:45:57) discontinuous
550   DUMMY: 2161 (21:45:57 to 22:00:00) discontinuous
551
5522012-12-25 21:45:57.320578 I  TV: StartRecorder(): took 26 ms to start recorder.
5532012-12-25 21:45:57.501520 N  AudioPlayer: Enabling Audio
5542012-12-25 21:45:57.501620 I  Player(1): SetFrameInterval ps:1 scan:3
5552012-12-25 21:45:57.501670 I  Player(1): detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
5562012-12-25 21:45:57.501815 I  Player(1): Stretch Factor 1, allow passthru
5572012-12-25 21:45:57.503535 I  VideoOutput: Allowed renderers: xv-blit,xshm,xlib,opengl,opengl-lite,vdpau,openglvaapi
5582012-12-25 21:45:57.503605 I  VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,openglvaapi
5592012-12-25 21:45:57.506374 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
5602012-12-25 21:45:57.506468 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
5612012-12-25 21:45:57.506505 I  VDP: LoadBestPreferences(2048x2048, 0)
5622012-12-25 21:45:57.506556 I  VDP: LoadBestPreferences(2048x2048, 60)
5632012-12-25 21:45:57.506702 I  VDP: LoadBestPreferences(720x576, 60)
5642012-12-25 21:45:57.506748 I  VideoOutput: Preferred renderer: xv-blit
5652012-12-25 21:45:57.506784 I  VideoOutput: Trying video renderer: 'xv-blit'
5662012-12-25 21:45:57.510971 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
5672012-12-25 21:45:57.511071 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
5682012-12-25 21:45:57.511124 I  VDP: LoadBestPreferences(2048x2048, 0)
5692012-12-25 21:45:57.511177 I  VDP: LoadBestPreferences(2048x2048, 60)
5702012-12-25 21:45:57.511209 I  VideoOutputXv: ctor
5712012-12-25 21:45:57.511244 I  VideoOutWindow::SetPIPState. pip_state: 0]
5722012-12-25 21:45:57.511267 I  VDP: LoadBestPreferences(2048x2048, 25)
5732012-12-25 21:45:57.512999 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.33333
5742012-12-25 21:45:57.513038 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
5752012-12-25 21:45:57.513063 I  VDP: LoadBestPreferences(720x576, 25)
5762012-12-25 21:45:57.513136 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.33333
5772012-12-25 21:45:57.513167 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
5782012-12-25 21:45:57.515179 I  VideoOutput: Pixel dimensions: Screen 1280x720, window 1280x720
5792012-12-25 21:45:57.515230 I  VideoOutput: Actual display dimensions: 325x183 mm  Aspect: 1.77596
5802012-12-25 21:45:57.515257 I  VideoOutput: Estimated window dimensions: 325x183 mm  Aspect: 1.77596
5812012-12-25 21:45:57.516059 I  VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
5822012-12-25 21:45:57.518586 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
5832012-12-25 21:45:57.518673 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
5842012-12-25 21:45:57.518709 I  VDP: LoadBestPreferences(2048x2048, 0)
5852012-12-25 21:45:57.518762 I  VDP: LoadBestPreferences(2048x2048, 60)
5862012-12-25 21:45:57.518804 I  VDP: LoadBestPreferences(720x576, 60)
5872012-12-25 21:45:57.519112 I  VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  2
5882012-12-25 21:45:57.519140 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
5892012-12-25 21:45:57.519153 I  VideoOutputXv: Has XVideo flags...
5902012-12-25 21:45:57.519270 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
5912012-12-25 21:45:57.519292 I  VideoOutputXv: Adaptor#1: NV05 Video Blitter has flag[s]: XvInputMask XvImageMask
5922012-12-25 21:45:57.519303 I  VideoOutputXv: Has XVideo flags...
5932012-12-25 21:45:57.519406 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
5942012-12-25 21:45:57.519431 I  VideoOutputXv: @ j=1 Looking for flag[s]: XvInputMask XvImageMask  0
5952012-12-25 21:45:57.519452 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
5962012-12-25 21:45:57.519463 I  VideoOutputXv: Has XVideo flags...
5972012-12-25 21:45:57.519568 I  VideoOutputXv: Grabbed xv port 281
5982012-12-25 21:45:57.519677 I  VideoOutputXv: XVideo surface found on port 281
5992012-12-25 21:45:57.519695 I  VideoOutputXv: XV_SET_DEFAULTS is supported on this port
6002012-12-25 21:45:57.519972 I  VideoOutputXv: XV_SYNC_TO_VBLANK supported
6012012-12-25 21:45:57.519991 I  VideoOutputXv: XVideo Sync to VBlank set
6022012-12-25 21:45:57.520031 I  VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
6032012-12-25 21:45:57.520160 I  VideoOutputXv: XVideo Format #0 is 'YUY2'
6042012-12-25 21:45:57.520190 I  VideoOutputXv: XVideo Format #1 is 'YV12'
6052012-12-25 21:45:57.520210 I  VideoOutputXv: XVideo Format #2 is 'UYVY'
6062012-12-25 21:45:57.520231 I  VideoOutputXv: XVideo Format #3 is 'I420'
6072012-12-25 21:45:57.520250 I  VideoOutputXv: Using XVideo Format 'YV12'
6082012-12-25 21:45:57.520276 I  VideoOutputXv: CreateShmImages(32): video_dim: 720x576
6092012-12-25 21:45:57.599106 I  VDP: SetVideoRenderer(xv-blit)
6102012-12-25 21:45:57.599149 I  VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
6112012-12-25 21:45:57.599972 I  ColourSpace: PictureAttributes:
6122012-12-25 21:45:57.600164 I  VideoOutputXv: Chromakeying not possible with this XVideo port.
6132012-12-25 21:45:57.600234 I  Display Rect  left: 160, top: 0, width: 960, height: 720, aspect: 1.77778
6142012-12-25 21:45:57.600265 I  Video Rect    left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
6152012-12-25 21:45:57.600327 I  Over/underscan. V: 0.02, H: 0.02
6162012-12-25 21:45:57.600357 I  Display Rect  left: 160, top: 0, width: 960, height: 720, aspect: 1.77778
6172012-12-25 21:45:57.600384 I  Video Rect    left: 14, top: 12, width: 691, height: 553, aspect: 1.33333
6182012-12-25 21:45:57.600422 I  Player(1): LoadFilters(''..) -> 0x0
6192012-12-25 21:45:57.603027 I  OSD: Base theme size: 1280x720
6202012-12-25 21:45:57.603067 I  Player(1): Decoder thread starting.
6212012-12-25 21:45:57.603103 I  OSD: Scaling factors: 0.540625x0.769444
6222012-12-25 21:45:57.612851 I  OSD: Loaded window osd_message
6232012-12-25 21:45:57.621323 I  OSD: Loaded window osd_input
6242012-12-25 21:45:57.636104 I  OSD: Loaded window program_info
6252012-12-25 21:45:57.646216 I  OSD: Loaded window browse_info
6262012-12-25 21:45:57.656880 I  OSD: Loaded window osd_status
6272012-12-25 21:45:57.667625 I  OSD: Loaded window osd_program_editor
6282012-12-25 21:45:57.692313 I  OSD: Loaded window osd_debug
6292012-12-25 21:45:57.692402 I  OSD: Loaded OSD: size 692x554 offset 14+11
6302012-12-25 21:45:57.692486 I  OSD: Base theme size: 1280x720
6312012-12-25 21:45:57.692519 I  OSD: Scaling factors: 0.540625x0.769444
6322012-12-25 21:45:57.692590 I  OSD: Created window aa_OSD_SUBTITLES
6332012-12-25 21:45:57.692659 I  Player(1): ClearAfterSeek(0)
6342012-12-25 21:45:57.695075 I  VDP: GetFilteredDeint() : xv-blit -> 'greedyhdoubleprocessdeint'
6352012-12-25 21:45:57.697090 I  FilterManager: GetFilterInfo(convert) returning: 0x0
6362012-12-25 21:45:57.697132 I  FilterManager: GetFilterInfo(greedyhdoubleprocessdeint) returning: 0xa7cb640
6372012-12-25 21:45:57.701822 I  VideoOutput: Using deinterlace method greedyhdoubleprocessdeint
6382012-12-25 21:45:57.701971 I  VSYNC: DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
6392012-12-25 21:45:57.702050 E  VSYNC: RTCVideoSync: Could not set RTC frequency:
640                        eno: Permission denied (13)
6412012-12-25 21:45:57.706439 I  Player(1): Video timing method: USleep with busy wait
6422012-12-25 21:45:57.706467 I  Player(1): Display Refresh Rate: 60.074 Video Frame Rate: 25.000
6432012-12-25 21:45:57.706479 I  Player(1): SetFrameInterval ps:1 scan:1
6442012-12-25 21:45:57.707005 I  playCtx: StartPlaying(): took 0 ms to start player.
6452012-12-25 21:45:57.708782 I  TV: UnpauseLiveTV() player ctx 0
6462012-12-25 21:45:57.712117 I  LiveTVChain(live-LinHES-2012-12-25T21:45:35): SwitchTo(-1)
6472012-12-25 21:45:57.712129 E  LiveTVChain(live-LinHES-2012-12-25T21:45:35): SwitchTo() not switching to current
6482012-12-25 21:45:57.712151 I  Player(1): Play(  1.0, normal 1, unpause audio 0)
6492012-12-25 21:45:57.739574 I  TV: DrawUnusedRects() -- begin
6502012-12-25 21:45:57.739595 I  TV: DrawUnusedRects() -- end
6512012-12-25 21:45:57.797045 I  VideoOutput: Created YV12 OSD.
6522012-12-25 21:45:57.966921 I  LiveTVChain(live-LinHES-2012-12-25T21:45:35): ReloadAll(): Added new recording
6532012-12-25 21:45:57.967038 I  LiveTVChain(live-LinHES-2012-12-25T21:45:35): LiveTVChain has 4 entries
654   DUMMY: 3939 (21:45:35 to 21:45:36)
655  HDHOMERUN: 3939 (21:45:36 to 21:45:57) discontinuous
656*  DUMMY: 2161 (21:45:57 to 21:45:57) discontinuous
657  HDHOMERUN: 2161 (21:45:58 to 22:00:00) discontinuous
658
6592012-12-25 21:45:57.967786 I  LiveTVChain(live-LinHES-2012-12-25T21:45:35): SwitchTo(3)
6602012-12-25 21:45:57.967806 I  Player(1): JumpToProgram - start
6612012-12-25 21:45:57.973297 I  Player(1): LoadFilters(''..) -> 0x0
6622012-12-25 21:45:57.974417 I  Player(1): Play speed: rate: 25 speed: 0 skip: 0 => new interval 40000
6632012-12-25 21:45:57.974885 I  VDP: GetFilteredDeint(linearblend) : xv-blit -> 'linearblend'
6642012-12-25 21:45:57.976054 I  FilterManager: GetFilterInfo(convert) returning: 0x0
6652012-12-25 21:45:57.976081 I  FilterManager: GetFilterInfo(linearblend) returning: 0xa2c69e8
6662012-12-25 21:45:57.976123 I  VideoOutput: Using deinterlace method linearblend
6672012-12-25 21:45:57.976829 I  FileRingBuf(myth://192.168.1.210:6543/2161_20121225214557.mpg): OpenFile(myth://192.168.1.210:6543/2161_20121225214558.mpg, 10000 ms)
6682012-12-25 21:45:57.977515 I  Using protocol version 72
6692012-12-25 21:45:57.978358 I  Using protocol version 72
6702012-12-25 21:45:58.577209 I  Player(1): Stretch Factor 1, allow passthru
6712012-12-25 21:45:58.578053 I  AFD: Buffer size: 32768, streamed 1
6722012-12-25 21:45:59.234131 I  AFD: Stream #0, has id 0x800 codec id MPEG2VIDEO, type Video, bitrate 20000000 at 0xa113bc0
6732012-12-25 21:45:59.237760 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
6742012-12-25 21:45:59.237869 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
6752012-12-25 21:45:59.237902 I  VDP: LoadBestPreferences(2048x2048, 0)
6762012-12-25 21:45:59.237974 I  VDP: LoadBestPreferences(2048x2048, 60)
6772012-12-25 21:45:59.238013 I  VDP: LoadBestPreferences(1920x1080, 60)
6782012-12-25 21:45:59.238087 I  AFD: Using 2 CPUs for decoding
6792012-12-25 21:45:59.238111 I  AFD: InitVideoCodec() 0xad06e50 id(MPEG2VIDEO) type (Video).
6802012-12-25 21:45:59.238202 I  AFD: Selected FPS is 29.97 (avg 81.3472 codec 29.97 container 90000 estimated 29.97)
6812012-12-25 21:45:59.238255 I  Player(1): SetFrameInterval ps:0 scan:3
6822012-12-25 21:45:59.240435 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
6832012-12-25 21:45:59.240516 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
6842012-12-25 21:45:59.240540 I  VDP: LoadBestPreferences(2048x2048, 0)
6852012-12-25 21:45:59.240580 I  VDP: LoadBestPreferences(2048x2048, 60)
6862012-12-25 21:45:59.240616 I  VDP: LoadBestPreferences(1920x1080, 60)
6872012-12-25 21:45:59.240685 I  VDP: LoadBestPreferences(720x576, 29.97)
6882012-12-25 21:45:59.240744 I  VideoOutputXv: InputChanged(1920,1080,1.33333) 'None'->'MPEG2'
6892012-12-25 21:45:59.240771 I  VDP: LoadBestPreferences(1920x1088, 29.97)
6902012-12-25 21:45:59.240927 I  VDP: GetFilteredDeint() : xv-blit -> 'bobdeint'
6912012-12-25 21:45:59.243103 I  FilterManager: GetFilterInfo(convert) returning: 0x0
6922012-12-25 21:45:59.243143 I  FilterManager: GetFilterInfo(bobdeint) returning: 0xa51c658
6932012-12-25 21:45:59.243209 I  VideoOutput: Using deinterlace method bobdeint
6942012-12-25 21:45:59.243228 I  VideoOutputXv: DiscardFrames(1)
6952012-12-25 21:45:59.243316 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
6962012-12-25 21:45:59.243387 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
6972012-12-25 21:45:59.243466 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
6982012-12-25 21:45:59.243482 I  VideoOutputXv: DiscardFrames(1)
6992012-12-25 21:45:59.243538 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
7002012-12-25 21:45:59.243597 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
7012012-12-25 21:45:59.243654 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
7022012-12-25 21:45:59.248071 I  VideoOutputXv: Closing XVideo port 281
7032012-12-25 21:45:59.256349 I  VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
7042012-12-25 21:45:59.259399 I  VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(2) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
7052012-12-25 21:45:59.259489 I  VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(0) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,linearblend) filt()
7062012-12-25 21:45:59.259523 I  VDP: LoadBestPreferences(2048x2048, 0)
7072012-12-25 21:45:59.259573 I  VDP: LoadBestPreferences(2048x2048, 60)
7082012-12-25 21:45:59.259612 I  VDP: LoadBestPreferences(1920x1088, 60)
7092012-12-25 21:45:59.259938 I  VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  2
7102012-12-25 21:45:59.259985 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
7112012-12-25 21:45:59.259998 I  VideoOutputXv: Has XVideo flags...
7122012-12-25 21:45:59.260110 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
7132012-12-25 21:45:59.260130 I  VideoOutputXv: Adaptor#1: NV05 Video Blitter has flag[s]: XvInputMask XvImageMask
7142012-12-25 21:45:59.260141 I  VideoOutputXv: Has XVideo flags...
7152012-12-25 21:45:59.260239 E  VideoOutputXv: Missing XV_BRIGHTNESS, rejecting.
7162012-12-25 21:45:59.260259 I  VideoOutputXv: @ j=1 Looking for flag[s]: XvInputMask XvImageMask  0
7172012-12-25 21:45:59.260279 I  VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
7182012-12-25 21:45:59.260289 I  VideoOutputXv: Has XVideo flags...
7192012-12-25 21:45:59.260391 I  VideoOutputXv: Grabbed xv port 281
7202012-12-25 21:45:59.260499 I  VideoOutputXv: XVideo surface found on port 281
7212012-12-25 21:45:59.260512 I  VideoOutputXv: XV_SET_DEFAULTS is supported on this port
7222012-12-25 21:45:59.260691 I  VideoOutputXv: XV_SYNC_TO_VBLANK supported
7232012-12-25 21:45:59.260707 I  VideoOutputXv: XVideo Sync to VBlank set
7242012-12-25 21:45:59.260750 I  VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
7252012-12-25 21:45:59.260878 I  VideoOutputXv: XVideo Format #0 is 'YUY2'
7262012-12-25 21:45:59.260910 I  VideoOutputXv: XVideo Format #1 is 'YV12'
7272012-12-25 21:45:59.260930 I  VideoOutputXv: XVideo Format #2 is 'UYVY'
7282012-12-25 21:45:59.260969 I  VideoOutputXv: XVideo Format #3 is 'I420'
7292012-12-25 21:45:59.260988 I  VideoOutputXv: Using XVideo Format 'YV12'
7302012-12-25 21:45:59.261010 I  VideoOutputXv: CreateShmImages(32): video_dim: 1920x1088
7312012-12-25 21:45:59.554446 I  VDP: SetVideoRenderer(xv-blit)
7322012-12-25 21:45:59.554469 I  VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
7332012-12-25 21:45:59.554818 I  ColourSpace: PictureAttributes:
7342012-12-25 21:45:59.554924 I  VideoOutputXv: Chromakeying not possible with this XVideo port.
7352012-12-25 21:45:59.554978 I  Display Rect  left: 160, top: 0, width: 960, height: 720, aspect: 1.77778
7362012-12-25 21:45:59.554994 I  Video Rect    left: 38, top: 22, width: 1843, height: 1037, aspect: 1.33333
7372012-12-25 21:45:59.556645 I  OSD: Base theme size: 1280x720
7382012-12-25 21:45:59.556673 I  OSD: Scaling factors: 1.44062x1.44167
7392012-12-25 21:45:59.561059 I  OSD: Loaded window osd_message
7402012-12-25 21:45:59.565173 I  OSD: Loaded window osd_input
7412012-12-25 21:45:59.572210 I  OSD: Loaded window program_info
7422012-12-25 21:45:59.577790 I  OSD: Loaded window browse_info
7432012-12-25 21:45:59.582805 I  OSD: Loaded window osd_status
7442012-12-25 21:45:59.587787 I  OSD: Loaded window osd_program_editor
7452012-12-25 21:45:59.599656 I  OSD: Loaded window osd_debug
7462012-12-25 21:45:59.599683 I  OSD: Loaded OSD: size 1844x1038 offset 38+21
7472012-12-25 21:45:59.599725 I  EnableCaptions(0) msg:  On
7482012-12-25 21:45:59.599739 I  Player(1): ClearAfterSeek(1)
7492012-12-25 21:45:59.599745 I  VideoOutputXv: ClearAfterSeek()
7502012-12-25 21:45:59.599752 I  VideoOutputXv: DiscardFrames(0)
7512012-12-25 21:45:59.599786 I  VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
7522012-12-25 21:45:59.599816 I  VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
7532012-12-25 21:45:59.599845 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
7542012-12-25 21:45:59.599866 I  Player(1): LoadFilters(''..) -> 0x0
7552012-12-25 21:45:59.599890 I  Player(1): detectInterlace(Detect Scan, Interlaced Scan, 29.97, 1080) ->Interlaced Scan
7562012-12-25 21:45:59.599903 I  Player(1): Enabled deinterlacing
7572012-12-25 21:45:59.599985 I  AFD: EIA-708 caption service #1 is in the English language.
7582012-12-25 21:45:59.599994 I  AFD: Using ffmpeg for video decoding
7592012-12-25 21:45:59.600003 I  AFD: Looking for decoder for MPEG2VIDEO
7602012-12-25 21:45:59.600132 I  AFD: Opened codec 0xad06e50, id(MPEG2VIDEO) type(Video)
7612012-12-25 21:45:59.600150 I  AFD: Stream #1, has id 0x801 codec id AC3, type Audio, bitrate 384000 at 0xad05760
7622012-12-25 21:45:59.600160 I  AFD: codec AC3 has 6 channels
7632012-12-25 21:45:59.600167 I  AFD: Looking for decoder for AC3
7642012-12-25 21:45:59.600616 I  AFD: Opened codec 0xadde8e0, id(AC3) type(Audio)
7652012-12-25 21:45:59.609124 I  AO: Opening audio device 'iec958:CARD=NVidia,DEV=0' ch 2(6) sr 48000 sf signed 16 bit reenc 0
7662012-12-25 21:45:59.610018 E  ALSA: Setting hardware audio buffer size to 128
7672012-12-25 21:45:59.610105 E  ALSA: Error opening /proc/asound/card0/pcm1p/sub0/prealloc: Permission denied.
7682012-12-25 21:45:59.610118 E  ALSA: Try to manually increase audio buffer with: echo 128 | sudo tee /proc/asound/card0/pcm1p/sub0/prealloc
7692012-12-25 21:45:59.610136 E  ALSA: Unable to sufficiently increase ALSA hardware buffer size - underruns are likely
7702012-12-25 21:45:59.618188 I  Dec: Selected track #1 in the Unknown language(0)
7712012-12-25 21:45:59.618269 I  Dec: Selected track #1 in the English language(6647399)
7722012-12-25 21:45:59.618289 I  Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
7732012-12-25 21:45:59.619038 I  Dec: Position map filled from DB to: 15
7742012-12-25 21:45:59.619051 I  Dec: SyncPositionMap watchingrecording, from DB: 2 entries
7752012-12-25 21:45:59.619065 I  Player(1): Filling position map from 16 to end
7762012-12-25 21:45:59.619536 I  Dec: Position map filled from Encoder to: 30
7772012-12-25 21:45:59.619551 I  Dec: SyncPositionMap watchingrecording total: 3 entries
7782012-12-25 21:45:59.619566 I  Dec: SyncPositionMap, new totframes: 30, new length: 1, posMap size: 3
7792012-12-25 21:45:59.621037 I  AFD: Partial position map found
7802012-12-25 21:45:59.621052 I  AFD: Successfully opened decoder for file: "myth://192.168.1.210:6543/2161_20121225214558.mpg". novideo(0)
7812012-12-25 21:45:59.625203 N  AFD: Resetting byte context eof (livetv 1 was eof 0)
7822012-12-25 21:45:59.929590 I  Player(1): Play(  1.0, normal 1, unpause audio 1)
7832012-12-25 21:45:59.930348 I  Dec: Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
7842012-12-25 21:45:59.930371 I  Player(1): Play speed: rate: 29.97 speed: 1 skip: 1 => new interval 33366
7852012-12-25 21:45:59.930421 I  Player(1): Stretch Factor 1, allow passthru
7862012-12-25 21:45:59.930442 I  Player(1): JumpToProgram - end
7872012-12-25 21:45:59.930476 I  Player(1): Waiting for video buffers...
7882012-12-25 21:45:59.932642 I  Dec: Position map filled from DB to: 15
7892012-12-25 21:45:59.932671 I  Dec: SyncPositionMap watchingrecording, from DB: 2 entries
7902012-12-25 21:45:59.932701 I  Player(1): Filling position map from 16 to end
7912012-12-25 21:45:59.933791 I  Dec: Position map filled from Encoder to: 45
7922012-12-25 21:45:59.933831 I  Dec: SyncPositionMap watchingrecording total: 4 entries
7932012-12-25 21:45:59.933859 I  Dec: SyncPositionMap, new totframes: 45, new length: 1, posMap size: 4
7942012-12-25 21:45:59.978597 I  Detect Letterbox: YV12 frame format detected
7952012-12-25 21:46:00.034640 N  Player(1): Waited 104ms for video buffers uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
7962012-12-25 21:46:00.139017 N  Player(1): Waited 208ms for video buffers uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
7972012-12-25 21:46:00.243487 N  Player(1): Waited 313ms for video buffers uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
7982012-12-25 21:46:00.348310 N  Player(1): Waited 418ms for video buffers uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
7992012-12-25 21:46:00.452221 N  Player(1): Waited 522ms for video buffers uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
8002012-12-25 21:46:00.556282 N  Player(1): Waited 626ms for video buffers uLAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP
8012012-12-25 21:46:00.585670 I  Detect Letterbox: YV12 frame format detected
8022012-12-25 21:46:00.657226 N  Player(1): Waited 727ms for video buffers UUUULUULAAAAAAAAAAAAAAAAAAAAAAAP
8032012-12-25 21:46:00.759765 N  Player(1): Waited 829ms for video buffers UUUUUUUuUULLAAAAAAAAAAAAAAAAAAAP
8042012-12-25 21:46:00.782373 I  Player(1): Video Aspect ratio changed from 1.33333 to 1.77778
8052012-12-25 21:46:00.782430 I  Display Rect  left: 0, top: 0, width: 1280, height: 720, aspect: 1.77778
8062012-12-25 21:46:00.782463 I  Video Rect    left: 38, top: 22, width: 1843, height: 1037, aspect: 1.77778
8072012-12-25 21:46:00.783981 I  OSD: Base theme size: 1280x720
8082012-12-25 21:46:00.784028 I  OSD: Scaling factors: 1.44062x1.44167
8092012-12-25 21:46:00.798323 I  OSD: Loaded window osd_message
8102012-12-25 21:46:00.814874 I  OSD: Loaded window osd_input
8112012-12-25 21:46:00.820221 I  Detect Letterbox: The source is already in widescreen (aspect: 1.77778)
8122012-12-25 21:46:00.836684 I  OSD: Loaded window program_info
8132012-12-25 21:46:00.849061 I  OSD: Loaded window browse_info
8142012-12-25 21:46:00.861908 I  OSD: Loaded window osd_status
8152012-12-25 21:46:00.869321 I  OSD: Loaded window osd_program_editor
8162012-12-25 21:46:00.887259 I  OSD: Loaded window osd_debug
8172012-12-25 21:46:00.887290 I  OSD: Loaded OSD: size 1844x1038 offset 38+21
8182012-12-25 21:46:00.887344 I  EnableCaptions(0) msg:  On
8192012-12-25 21:46:00.887362 I  Player(1): Enabled deinterlacing
8202012-12-25 21:46:00.887372 I  VideoOutput: OSD size changed.
8212012-12-25 21:46:00.979110 I  VideoOutput: Created YV12 OSD.
8222012-12-25 21:46:01.045030 I  Player(1): Video is 5.23737 frames behind audio (too slow), dropping frame to catch up.
8232012-12-25 21:46:01.048625 I  Player(1): Video is 6.6928 frames behind audio (too slow), dropping frame to catch up.
8242012-12-25 21:46:01.052105 I  Player(1): Video is 7.8818 frames behind audio (too slow), dropping frame to catch up.
8252012-12-25 21:46:01.056874 I  Player(1): Video is 8.52628 frames behind audio (too slow), dropping frame to catch up.
8262012-12-25 21:46:01.060808 I  Player(1): Video is 8.48516 frames behind audio (too slow), dropping frame to catch up.
8272012-12-25 21:46:01.065737 I  Player(1): Video is 8.19957 frames behind audio (too slow), dropping frame to catch up.
8282012-12-25 21:46:01.068768 I  Player(1): Video is 7.74561 frames behind audio (too slow), dropping frame to catch up.
8292012-12-25 21:46:01.071792 I  Player(1): Video is 7.15789 frames behind audio (too slow), dropping frame to catch up.
8302012-12-25 21:46:01.074751 I  Player(1): Video is 6.43985 frames behind audio (too slow), dropping frame to catch up.
8312012-12-25 21:46:01.078065 I  Player(1): Video is 5.65408 frames behind audio (too slow), dropping frame to catch up.
8322012-12-25 21:46:01.081062 I  Player(1): Video is 4.80999 frames behind audio (too slow), dropping frame to catch up.
8332012-12-25 21:46:01.084790 I  Player(1): Video is 3.92966 frames behind audio (too slow), dropping frame to catch up.
8342012-12-25 21:46:01.088596 I  Player(1): Video is 3.02964 frames behind audio (too slow), dropping frame to catch up.
8352012-12-25 21:46:01.393676 I  Player(1): Waiting for video buffers...
8362012-12-25 21:46:01.497600 N  Player(1): Waited 104ms for video buffers uLUUAuAAAAAAAAAAAAAAAAUAAUUUUUUP
8372012-12-25 21:46:01.729650 I  Player(1): Waiting for video buffers...
8382012-12-25 21:46:01.994694 I  OSD: Base theme size: 1280x720
8392012-12-25 21:46:01.994726 I  OSD: Scaling factors: 1.44062x1.44167
8402012-12-25 21:46:02.155287 I  Player(1): Video is 3.59681 frames behind audio (too slow), dropping frame to catch up.
8412012-12-25 21:46:02.157343 I  AFD: EIA-708 caption service #1 is in the English language.
8422012-12-25 21:46:02.157370 I  AFD: EIA-608 caption service #1 is in the English language.
8432012-12-25 21:46:02.157474 I  Dec: Selected track #1 in the English language(6647399)
8442012-12-25 21:46:02.163355 I  Player(1): Video is 4.36097 frames behind audio (too slow), dropping frame to catch up.
8452012-12-25 21:46:02.172270 I  Player(1): Video is 4.59692 frames behind audio (too slow), dropping frame to catch up.
8462012-12-25 21:46:02.180616 I  Player(1): Video is 4.52661 frames behind audio (too slow), dropping frame to catch up.
8472012-12-25 21:46:02.189104 I  Player(1): Video is 4.22664 frames behind audio (too slow), dropping frame to catch up.
8482012-12-25 21:46:02.195307 I  Player(1): Video is 3.75439 frames behind audio (too slow), dropping frame to catch up.
8492012-12-25 21:46:02.201186 I  Player(1): Video is 3.13046 frames behind audio (too slow), dropping frame to catch up.
8502012-12-25 21:46:04.053217 I  Player(1): FPS:   32.85 Mean: 30439 Std.Dev: 20372 CPUs: 100% 100%
8512012-12-25 21:46:06.746837 I  Player(1): FPS:   28.88 Mean: 34625 Std.Dev:  6282 CPUs: 79% 77%
8522012-12-25 21:46:07.717008 I  Player(1): FPS:   29.92 Mean: 33425 Std.Dev:   363 CPUs: 82% 81%
8532012-12-25 21:46:08.687383 I  Player(1): FPS:   29.91 Mean: 33431 Std.Dev:  1299 CPUs: 87% 68%
8542012-12-25 21:46:09.482337 I  RingBuf(myth://192.168.1.210:6543/2161_20121225214558.mpg): Waited 0.2 seconds for data
855                        to become available... 0 < 32768
8562012-12-25 21:46:09.594260 I  Player(1): Video is 3.02095 frames behind audio (too slow), dropping frame to catch up.
8572012-12-25 21:46:10.586855 I  AFD: EIA-708 caption service #1 is in the English language.
8582012-12-25 21:46:10.586885 I  AFD: EIA-708 caption service #2 is in the Undetermined language.
8592012-12-25 21:46:10.586895 I  AFD: EIA-608 caption service #1 is in the English language.
8602012-12-25 21:46:10.640837 I  AFD: EIA-708 caption service #1 is in the English language.
8612012-12-25 21:46:10.640859 I  AFD: EIA-708 caption service #2 is in the Undetermined language.
8622012-12-25 21:46:10.640869 I  AFD: EIA-608 caption service #1 is in the English language.
8632012-12-25 21:46:10.640880 I  AFD: EIA-608 caption service #3 is in the Undetermined language.
8642012-12-25 21:46:12.971346 I  Player(1): FPS:   30.14 Mean: 33181 Std.Dev:  2767 CPUs: 75% 81%
8652012-12-25 21:46:13.974307 I  Player(1): 400 interlaced frames seen.
8662012-12-25 21:46:16.953331 I  Player(1): FPS:   29.89 Mean: 33452 Std.Dev:   224 CPUs: 85% 68%
8672012-12-25 21:46:20.936037 I  Player(1): FPS:   29.89 Mean: 33458 Std.Dev:  1489 CPUs: 84% 70%
8682012-12-25 21:46:21.082198 I  Player(1): Video is 3.00477 frames behind audio (too slow), dropping frame to catch up.
8692012-12-25 21:46:24.884540 I  Player(1): FPS:   30.15 Mean: 33171 Std.Dev:  3190 CPUs: 83% 72%
8702012-12-25 21:46:26.249124 I  RingBuf(myth://192.168.1.210:6543/2161_20121225214558.mpg): Waited 0.2 seconds for data
871                        to become available... 0 < 32768
8722012-12-25 21:46:26.279482 I  AFD: EIA-708 caption service #1 is in the English language.
8732012-12-25 21:46:26.279501 I  AFD: EIA-608 caption service #1 is in the English language.
8742012-12-25 21:46:26.772269 I  AFD: EIA-708 caption service #1 is in the English language.
8752012-12-25 21:46:26.772302 I  AFD: EIA-708 caption service #2 is in the Undetermined language.
8762012-12-25 21:46:26.772314 I  AFD: EIA-608 caption service #1 is in the English language.
8772012-12-25 21:46:27.326481 I  Player(1): 800 interlaced frames seen.
8782012-12-25 21:46:28.866400 I  Player(1): FPS:   29.89 Mean: 33452 Std.Dev:   340 CPUs: 76% 76%
8792012-12-25 21:46:32.849099 I  Player(1): FPS:   29.89 Mean: 33459 Std.Dev:  1029 CPUs: 78% 73%
8802012-12-25 21:46:33.152710 I  Player(1): Video is 3.00683 frames behind audio (too slow), dropping frame to catch up.
8812012-12-25 21:46:36.797383 I  Player(1): FPS:   30.15 Mean: 33170 Std.Dev:  2987 CPUs: 67% 82%
8822012-12-25 21:46:40.678794 I  Player(1): 1200 interlaced frames seen.
8832012-12-25 21:46:40.779629 I  Player(1): FPS:   29.89 Mean: 33455 Std.Dev:   270 CPUs: 76% 78%
8842012-12-25 21:46:44.762267 I  Player(1): FPS:   29.89 Mean: 33458 Std.Dev:   463 CPUs: 74% 75%
8852012-12-25 21:46:45.333334 I  Player(1): Video is 3.01436 frames behind audio (too slow), dropping frame to catch up.
8862012-12-25 21:46:45.958111 I  RingBuf(myth://192.168.1.210:6543/2161_20121225214558.mpg): Waited 0.2 seconds for data
887                        to become available... 0 < 32768
8882012-12-25 21:46:47.806698 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
8892012-12-25 21:46:47.806729 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- end
8902012-12-25 21:46:47.841611 I  TV: HandleStateChange(0) -- begin
8912012-12-25 21:46:47.841638 I  TV: Attempting to change from WatchingLiveTV to None
8922012-12-25 21:46:47.841661 I  TV: StopStuff() for player ctx 0 -- begin
8932012-12-25 21:46:47.841678 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
8942012-12-25 21:46:47.841699 I  TV: SetActive(0,w/o OSD) 0 -> 0 -- end
8952012-12-25 21:46:47.841712 I  Player(1): StopPlaying - begin
8962012-12-25 21:46:47.844210 I  Player(1): Decoder thread exiting.
8972012-12-25 21:46:47.844573 I  Player(1): Exited decoder loop.
8982012-12-25 21:46:47.845219 I  VideoOutputXv: dtor
8992012-12-25 21:46:47.845238 I  VideoOutputXv: DiscardFrames(1)
9002012-12-25 21:46:47.845285 I  VideoBuffers::DiscardFrames(1): UUAUUUUUUUuUULUUAUUAUUAAAUAAUAAP
9012012-12-25 21:46:47.845344 I  VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done
9022012-12-25 21:46:47.845387 I  VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAP -- done()
9032012-12-25 21:46:47.857253 I  VideoOutputXv: Closing XVideo port 281
9042012-12-25 21:46:47.890681 W  MythPainter: 9 images not yet de-allocated.
9052012-12-25 21:46:47.937224 I  Player(1): StopPlaying - end
9062012-12-25 21:46:47.937244 I  TV: StopStuff(): stopping ring buffer
9072012-12-25 21:46:48.090459 I  TV: StopStuff(): stopping player
9082012-12-25 21:46:48.090485 I  TV: StopStuff(): stopping recorder
9092012-12-25 21:46:48.168863 I  TV: StopStuff() -- end
9102012-12-25 21:46:48.168911 I  TV: Changing from WatchingLiveTV to None
9112012-12-25 21:46:48.169474 I  TV: HandleStateChange(0) -- end
9122012-12-25 21:46:48.169508 I  TV: Exiting main playback loop.
9132012-12-25 21:46:48.169526 I  TV: StartTV -- process events 2 begin
9142012-12-25 21:46:48.170275 I  TV: StartTV -- process events 2 end
9152012-12-25 21:46:48.170320 I  TV::~TV() -- begin
9162012-12-25 21:46:48.180887 I  TV::~TV() -- lock
9172012-12-25 21:46:48.182320 I  Player(1): StopPlaying - begin
9182012-12-25 21:46:48.182364 I  Player(1): Exited decoder loop.
9192012-12-25 21:46:48.182382 I  Player(1): StopPlaying - end
9202012-12-25 21:46:48.192126 I  TV::~TV() -- end
9212012-12-25 21:46:48.215755 N  Resuming idle timer
9222012-12-25 21:46:48.215790 I  TV: StartTV -- end
9232012-12-25 21:46:50.269066 N  Resuming idle timer
9242012-12-25 21:46:50.273451 N  Resuming idle timer
9252012-12-25 21:46:50.279376 I  Bonjour: De-registering service '_mythfrontend._tcp.' on 'Mythfrontend on LinHES'
9262012-12-25 21:46:50.280218 I  RAOP Device: Cleaning up.
9272012-12-25 21:46:50.280256 I  AirPay: Cleaning up.
9282012-12-25 21:46:50.280275 I  Deleting UPnP client...
9292012-12-25 21:46:51.939256 I  OpenGL1: Deleting OpenGL Resources
9302012-12-25 21:46:51.939362 I  OpenGL: Deleting OpenGL Resources
9312012-12-25 21:46:51.971409 I  Waiting for threads to exit.
932greedyhdeint: size changed from 0 x 0 -> 720 x 576
933greedyhdeint: size changed from 0 x 0 -> 720 x 576