Ticket #4635: crash.txt

File crash.txt, 127.8 KB (added by dstrang@…, 18 years ago)

mythfrontend -v most - 0.21-fixes rev.16183

Line 
12008-02-24 14:11:32.696 user: 0 effective user: 0 before privileged thread
22008-02-24 14:11:32.697 user: 0 effective user: 0 run_priv_thread
32008-02-24 14:11:32.697 user: 0 effective user: 0 after privileged thread
42008-02-24 14:11:32.698 New DB connection, total: 2
52008-02-24 14:11:32.698 Connected to database 'mythconverg' at host: localhost
62008-02-24 14:11:32.699 mythfrontend version: 0.21.20080213-1 www.mythtv.org
72008-02-24 14:11:32.699 Enabled verbose msgs: most
82008-02-24 14:11:32.809 max_width: 848 max_height: 480
92008-02-24 14:11:32.890 Total desktop dim: 848x480, with 1 screen[s].
102008-02-24 14:11:32.891 Using screen 0, 848x480 at 0,0
112008-02-24 14:11:32.892 Switching to square mode (Titivillus)
122008-02-24 14:11:32.907 Using the OpenGL painter
132008-02-24 14:11:32.909 lirc init success using configuration file: /root/.mythtv/lircrc
142008-02-24 14:11:33.092 generating cache image for: /usr/share/mythtv/themes/default/blank.png
152008-02-24 14:11:33.180 Loading from: /usr/share/mythtv/themes/Titivillus/base.xml
162008-02-24 14:11:33.190 Loading from: /usr/share/mythtv/themes/default/base.xml
172008-02-24 14:11:33.219 Registering Internal as a media playback plugin.
182008-02-24 14:11:33.228 Unable to initialize plugin 'mythappearance'.
192008-02-24 14:11:33.256 MythPlugin::init() dlerror: /usr/lib/mythtv/plugins/libmythdvd.so: undefined symbol: _ZN18ConfigurationGroup6byNameE7QString
202008-02-24 14:11:33.256 Unable to initialize plugin 'mythdvd'.
212008-02-24 14:11:33.309 Using NV NPOT texture extension
222008-02-24 14:11:33.355 Registering MythMusic Media Handler 1/2 as a media handler for MEDIATYPE_MIXED
232008-02-24 14:11:33.356 Creating inactive MediaMonitor and static device list
242008-02-24 14:11:33.356 IgnoreDevices=
252008-02-24 14:11:33.358 MediaMonitorUnix::GetCDROMBlockDevices() returning
262008-02-24 14:11:33.361 MediaMonitorUnix::AddDevice() - Added /dev/sdb
272008-02-24 14:11:33.361 Initial device list...
28/dev/sdb (TEAC USB HS-CF Card )
292008-02-24 14:11:33.362 Registering MythMusic Media Handler 2/2 as a media handler for MEDIATYPE_MMUSIC, ext(ogg,mp3,aac,flac)
302008-02-24 14:11:33.362 MonitorRegisterExtensions(0x40, ogg,mp3,aac,flac)
312008-02-24 14:11:33.363 MediaMonitor::selectDrivePopup(Select a CD drive) - No suitable devices
322008-02-24 14:11:33.385 Unable to initialize plugin 'mythnews'.
332008-02-24 14:11:33.416 Registering MythDVD DVD Media Handler as a media handler for MEDIATYPE_DVD
342008-02-24 14:11:33.416 Registering MythDVD VCD Media Handler as a media handler for MEDIATYPE_VCD
352008-02-24 14:11:34.695 MythEvent: PLAYBACK_START mythtv
362008-02-24 14:11:34.696 MythSocket(b36436b8:25): new socket
372008-02-24 14:11:34.697 Connecting to backend server: 192.168.0.50:6543 (try 1 of 5)
382008-02-24 14:11:34.697 MythSocket(b195e098:27): new socket
392008-02-24 14:11:34.697 MythSocket(b195e098:27): attempting connect() to (192.168.0.50:6543)
402008-02-24 14:11:34.698 MythSocket(b195e098:27): state change Idle -> Connected
412008-02-24 14:11:34.698 write -> 27 21 MYTH_PROTO_VERSION 40
422008-02-24 14:11:34.698 read <- 27 13 ACCEPT[]:[]40
432008-02-24 14:11:34.698 Using protocol version 40
442008-02-24 14:11:34.698 write -> 27 20 ANN Monitor mythtv 0
452008-02-24 14:11:34.701 read <- 27 2 OK
462008-02-24 14:11:34.701 MythSocket(b36436b8:25): attempting connect() to (192.168.0.50:6543)
472008-02-24 14:11:34.701 MythSocket(b36436b8:25): state change Idle -> Connected
482008-02-24 14:11:34.702 write -> 25 20 ANN Monitor mythtv 1
492008-02-24 14:11:34.703 read <- 25 2 OK
502008-02-24 14:11:34.704 MythSocket(b36436b8:25): UpRef: 1
512008-02-24 14:11:34.704 MythSocket: readyread thread start
522008-02-24 14:11:34.704 write -> 27 23 GET_FREE_RECORDER_COUNT
532008-02-24 14:11:34.704 read <- 27 1 4
542008-02-24 14:11:34.704 write -> 27 29 GET_NEXT_FREE_RECORDER[]:[]-1
552008-02-24 14:11:34.704 read <- 27 27 1[]:[]192.168.0.50[]:[]6543
562008-02-24 14:11:34.705 TV: Attempting to change from None to WatchingLiveTV
572008-02-24 14:11:34.705 MythSocket(82d8670:30): new socket
582008-02-24 14:11:34.705 MythSocket(82d8670:30): attempting connect() to (192.168.0.50:6543)
592008-02-24 14:11:34.705 MythSocket(82d8670:30): state change Idle -> Connected
602008-02-24 14:11:34.705 write -> 30 21 MYTH_PROTO_VERSION 40
612008-02-24 14:11:34.705 read <- 30 13 ACCEPT[]:[]40
622008-02-24 14:11:34.705 Using protocol version 40
632008-02-24 14:11:34.705 write -> 30 21 ANN Playback mythtv 0
642008-02-24 14:11:34.707 read <- 30 2 OK
652008-02-24 14:11:34.707 write -> 30 80 QUERY_RECORDER 1[]:[]SPAWN_LIVETV[]:[]live-mythtv-2008-02-24T14:1...
662008-02-24 14:11:36.156 read <- 30 2 ok
672008-02-24 14:11:36.157 LiveTVChain(live-mythtv-2008-02-24T14:11:34): ReloadAll(): Added new recording
682008-02-24 14:11:36.160 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group!
692008-02-24 14:11:36.160 SG(Default): FindRecordingFile: Searching for '1168_20080224141134.mpg'
702008-02-24 14:11:36.160 SG(Default): FindRecordingDir: Checking '/mnt/store'
712008-02-24 14:11:36.160 SG(Default): FindRecordingFile: Found '/mnt/store/1168_20080224141134.mpg'
722008-02-24 14:11:36.160 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/1168_20080224141134.mpg'
732008-02-24 14:11:36.160 write -> 30 33 QUERY_RECORDER 1[]:[]IS_RECORDING
742008-02-24 14:11:36.161 read <- 30 1 1
752008-02-24 14:11:36.161 write -> 30 33 QUERY_RECORDER 1[]:[]IS_RECORDING
762008-02-24 14:11:36.161 read <- 30 1 1
772008-02-24 14:11:36.161 TV: StartRecorder(): took 1 ms to start recorder.
782008-02-24 14:11:36.161 write -> 30 34 QUERY_RECORDER 1[]:[]GET_FRAMERATE
792008-02-24 14:11:36.161 read <- 30 2 -1
802008-02-24 14:11:36.162 MythSocket(b36436b8:25): socket is readable
812008-02-24 14:11:36.162 MythSocket(b36436b8:25): cb->readyRead()
822008-02-24 14:11:36.165 read <- 25 53 BACKEND_MESSAGE[]:[]QUERY_NEXT_LIVETV_DIR 1[]:[]empty
832008-02-24 14:11:36.165 MythEvent: QUERY_NEXT_LIVETV_DIR 1
842008-02-24 14:11:36.165 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
852008-02-24 14:11:36.165 MythEvent: RECORDING_LIST_CHANGE
862008-02-24 14:11:36.165 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
872008-02-24 14:11:36.167 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
882008-02-24 14:11:36.167 read <- 25 451 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
892008-02-24 14:11:36.167 MythEvent: SIGNAL 1
902008-02-24 14:11:36.229 DPMS Deactivated
912008-02-24 14:11:36.230 New DB connection, total: 3
922008-02-24 14:11:36.230 Connected to database 'mythconverg' at host: localhost
932008-02-24 14:11:36.231 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group!
942008-02-24 14:11:36.231 SG(Default): FindRecordingFile: Searching for '1168_20080224141134.mpg'
952008-02-24 14:11:36.231 SG(Default): FindRecordingDir: Checking '/mnt/store'
962008-02-24 14:11:36.231 SG(Default): FindRecordingFile: Found '/mnt/store/1168_20080224141134.mpg'
972008-02-24 14:11:36.231 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/1168_20080224141134.mpg'
982008-02-24 14:11:36.231 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
992008-02-24 14:11:36.232 read <- 27 2 OK
1002008-02-24 14:11:36.232 MythSocket(b36436b8:25): socket is readable
1012008-02-24 14:11:36.232 MythSocket(b36436b8:25): cb->readyRead()
1022008-02-24 14:11:36.232 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
1032008-02-24 14:11:36.232 MythEvent: RECORDING_LIST_CHANGE
1042008-02-24 14:11:36.238 detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
1052008-02-24 14:11:36.239 NVP: Disabling Audio, params(-1,2,44100)
1062008-02-24 14:11:36.241 VideoOutput: Allowed renderers: opengl,xv-blit,xshm,xlib
1072008-02-24 14:11:36.241 VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl
1082008-02-24 14:11:36.242 VDP: Accepting: cmp(> 0 0) dec(libmpeg2) cpus(1) rend(opengl) osd(opengl2) osdfade(enabled) deint(greedyhdeint,openglkerneldeint) filt()
1092008-02-24 14:11:36.242 VDP: LoadBestPreferences(2048x2048, 0)
1102008-02-24 14:11:36.242 VDP: LoadBestPreferences(2048x2048, 60)
1112008-02-24 14:11:36.242 VDP: LoadBestPreferences(720x576, 60)
1122008-02-24 14:11:36.242 VideoOutput: Preferred renderer: opengl
1132008-02-24 14:11:36.242 VideoOutput: Trying video renderer: opengl
1142008-02-24 14:11:36.243 VDP: Accepting: cmp(> 0 0) dec(libmpeg2) cpus(1) rend(opengl) osd(opengl2) osdfade(enabled) deint(greedyhdeint,openglkerneldeint) filt()
1152008-02-24 14:11:36.243 VDP: LoadBestPreferences(2048x2048, 0)
1162008-02-24 14:11:36.243 VDP: LoadBestPreferences(2048x2048, 60)
1172008-02-24 14:11:36.249 VideoOutputXv: ctor
1182008-02-24 14:11:36.250 XOff: 0, YOff: 0
1192008-02-24 14:11:36.250 VDP: LoadBestPreferences(720x576, 60)
1202008-02-24 14:11:36.250 Display Rect left: 0, top: 0, width: 848, height: 480, aspect: 1.33333
1212008-02-24 14:11:36.250 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
1222008-02-24 14:11:36.250 VideoOutputXv: Pixel dimensions: Screen 848x480, window 848x480
1232008-02-24 14:11:36.250 VideoOutputXv: Estimated display dimensions: 287x163 mm Aspect: 1.76074
1242008-02-24 14:11:36.250 VideoOutputXv: Estimated window dimensions: 287x163 mm Aspect: 1.76074
1252008-02-24 14:11:36.250 VideoOutputXv: InitSetupBuffers() render: opengl, allowed: opengl,xv-blit,xshm,xlib
1262008-02-24 14:11:36.252 GLCtx: Created window and context.
1272008-02-24 14:11:36.278 GLCtx: GLX Version: 1.3
1282008-02-24 14:11:36.279 GLCtx: Direct rendering: Yes
1292008-02-24 14:11:36.279 GLCtx: OpenGL vendor : NVIDIA Corporation
1302008-02-24 14:11:36.279 GLCtx: OpenGL renderer: GeForce 8300 GS/PCI/SSE2
1312008-02-24 14:11:36.279 GLCtx: OpenGL version : 2.1.1 NVIDIA 100.14.19
1322008-02-24 14:11:36.279 GLCtx: Max texture size: 8192 x 8192
1332008-02-24 14:11:36.279 GLVid: Viewport: 848x576
1342008-02-24 14:11:36.280 GLVid: Created main input texture 360x288
1352008-02-24 14:11:36.281 GLVid: Created main input texture 360x288
1362008-02-24 14:11:36.285 GLVid: Created main input texture 720x576
1372008-02-24 14:11:36.285 GLVid: Creating master filter.
1382008-02-24 14:11:36.285 GLVid: Created fragment program master.
1392008-02-24 14:11:36.285 GLVid: Creating resize filter.
1402008-02-24 14:11:36.292 GLCtx: Created frame buffer object (720x576).
1412008-02-24 14:11:36.292 GLVid: Turning off deinterlacing.
1422008-02-24 14:11:36.292 GLVid: Turning off deinterlacing.
1432008-02-24 14:11:36.292 Created data @0xad9d6020->0xada6de22
1442008-02-24 14:11:36.292 Created data @0xad93e020->0xad9d5e22
1452008-02-24 14:11:36.292 Created data @0xad8a6020->0xad93de22
1462008-02-24 14:11:36.292 Created data @0xad80e020->0xad8a5e22
1472008-02-24 14:11:36.292 Created data @0xad776020->0xad80de22
1482008-02-24 14:11:36.292 Created data @0xad6de020->0xad775e22
1492008-02-24 14:11:36.292 Created data @0xad646020->0xad6dde22
1502008-02-24 14:11:36.293 Created data @0xad5ae020->0xad645e22
1512008-02-24 14:11:36.293 Created data @0xad516020->0xad5ade22
1522008-02-24 14:11:36.293 Created data @0xad47e020->0xad515e22
1532008-02-24 14:11:36.293 Created data @0xad3e6020->0xad47de22
1542008-02-24 14:11:36.293 Created data @0xad34e020->0xad3e5e22
1552008-02-24 14:11:36.293 Created data @0xad2b6020->0xad34de22
1562008-02-24 14:11:36.293 Created data @0xad21e020->0xad2b5e22
1572008-02-24 14:11:36.293 Created data @0xad186020->0xad21de22
1582008-02-24 14:11:36.293 Created data @0xad0ee020->0xad185e22
1592008-02-24 14:11:36.293 Created data @0xad056020->0xad0ede22
1602008-02-24 14:11:36.293 Created data @0xacfbe020->0xad055e22
1612008-02-24 14:11:36.293 Created data @0xacf26020->0xacfbde22
1622008-02-24 14:11:36.293 Created data @0xace8e020->0xacf25e22
1632008-02-24 14:11:36.293 Created data @0xacdf6020->0xace8de22
1642008-02-24 14:11:36.293 Created data @0xacd5e020->0xacdf5e22
1652008-02-24 14:11:36.293 Created data @0xaccc6020->0xacd5de22
1662008-02-24 14:11:36.293 Created data @0xacc2e020->0xaccc5e22
1672008-02-24 14:11:36.293 Created data @0xacb96020->0xacc2de22
1682008-02-24 14:11:36.293 Created data @0xacafe020->0xacb95e22
1692008-02-24 14:11:36.293 Created data @0xaca66020->0xacafde22
1702008-02-24 14:11:36.293 Created data @0xac9ce020->0xaca65e22
1712008-02-24 14:11:36.293 Created data @0xac936020->0xac9cde22
1722008-02-24 14:11:36.293 Created data @0xac89e020->0xac935e22
1732008-02-24 14:11:36.293 Created data @0xac806020->0xac89de22
1742008-02-24 14:11:36.293 Created data @0xac76e020->0xac805e22
1752008-02-24 14:11:36.309 VDP: GetFilteredDeint() : -> 'greedyhdeint'
176greedyhdeint: size changed from 0 x 0 -> 720 x 576
1772008-02-24 14:11:36.311 Using deinterlace method greedyhdeint
1782008-02-24 14:11:36.311 VDP: SetVideoRenderer(opengl)
1792008-02-24 14:11:36.311 VDP: SetVideoRender(opengl) == GetVideoRenderer()
1802008-02-24 14:11:36.316 GLVid: Created main input texture 848x480
1812008-02-24 14:11:36.319 GLVid: Created main input texture 424x240
1822008-02-24 14:11:36.320 GLVid: Created main input texture 424x240
1832008-02-24 14:11:36.324 GLVid: Created main input texture 848x480
1842008-02-24 14:11:36.324 GLVid: Creating osd filter.
1852008-02-24 14:11:36.324 GLVid: Created fragment program osd.
1862008-02-24 14:11:36.324 GLVid: Turning off deinterlacing.
1872008-02-24 14:11:36.324 GLVid: Creating resize filter.
1882008-02-24 14:11:36.328 GLCtx: Created frame buffer object (848x480).
1892008-02-24 14:11:36.328 GLVid: Turning off deinterlacing.
1902008-02-24 14:11:36.329 Display Rect left: 106, top: 0, width: 636, height: 480, aspect: 1.77778
1912008-02-24 14:11:36.329 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
1922008-02-24 14:11:36.330 Over/underscan. V: 0.03, H: 0
1932008-02-24 14:11:36.330 Display Rect left: 106, top: 0, width: 636, height: 480, aspect: 1.77778
1942008-02-24 14:11:36.330 Video Rect left: 0, top: 17, width: 720, height: 541, aspect: 1.33333
1952008-02-24 14:11:36.330 VDP: LoadBestPreferences(720x576, 25)
1962008-02-24 14:11:36.330 NVP: LoadFilters(''..) -> 0
1972008-02-24 14:11:36.331 OSD Theme Dimensions W: 640 H: 480
1982008-02-24 14:11:36.370 OSDTypePositionImage::AddPosition[0](114x233 1, 1)
1992008-02-24 14:11:36.370 OSDTypePositionImage::AddPosition[1](114x263 1, 1)
2002008-02-24 14:11:36.370 OSDTypePositionImage::AddPosition[2](114x293 1, 1)
2012008-02-24 14:11:36.370 OSDTypePositionImage::AddPosition[3](114x323 1, 1)
2022008-02-24 14:11:36.370 OSDTypePositionImage::AddPosition[4](114x353 1, 1)
2032008-02-24 14:11:36.374 OSD: InitCC708() -- begin
2042008-02-24 14:11:36.434 OSD: InitCC708() -- end
2052008-02-24 14:11:36.456 MythSocket(b36436b8:25): socket is readable
2062008-02-24 14:11:36.456 MythSocket(b36436b8:25): cb->readyRead()
2072008-02-24 14:11:36.456 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
2082008-02-24 14:11:36.456 MythEvent: SIGNAL 1
2092008-02-24 14:11:36.528 NVP: ClearAfterSeek(1)
2102008-02-24 14:11:36.528 VideoOutputXv: ClearAfterSeek()
2112008-02-24 14:11:36.528 VideoOutputXv: DiscardFrames(0)
2122008-02-24 14:11:36.528 TV: StartPlayer(): took 291 ms to start player.
2132008-02-24 14:11:36.528 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2142008-02-24 14:11:36.529 TV: Changing from None to WatchingLiveTV
2152008-02-24 14:11:36.529 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2162008-02-24 14:11:36.529 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2172008-02-24 14:11:36.530 write -> 30 35 QUERY_RECORDER 1[]:[]FRONTEND_READY
2182008-02-24 14:11:36.531 read <- 30 2 ok
2192008-02-24 14:11:36.531 Using realtime priority.
2202008-02-24 14:11:36.531 VDP: GetFilteredDeint() : opengl -> 'greedyhdeint'
2212008-02-24 14:11:36.531 VDP: GetFilteredDeint() : opengl -> 'greedyhdeint'
2222008-02-24 14:11:36.532 rate: 25 speed: 1 skip: 1 = interval 40000
223greedyhdeint: size changed from 0 x 0 -> 720 x 576
2242008-02-24 14:11:36.533 Using deinterlace method greedyhdeint
2252008-02-24 14:11:36.535 write -> 30 30 QUERY_RECORDER 1[]:[]GET_INPUT
2262008-02-24 14:11:36.535 read <- 30 11 DVBInput #1
2272008-02-24 14:11:36.635 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver?
2282008-02-24 14:11:36.635 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2292008-02-24 14:11:36.635 OpenGLVideoSync()
2302008-02-24 14:11:36.635 OpenGLVideoSync: x,y -> 424, 240
2312008-02-24 14:11:36.657 XMLParse::LoadTheme using /usr/share/mythtv/themes/Titivillus/ui.xml
2322008-02-24 14:11:36.657 Using OpenGLVideoSync
2332008-02-24 14:11:36.671 Using audio as timebase
2342008-02-24 14:11:36.672 Video timing method: SGI OpenGL
2352008-02-24 14:11:36.672 Refresh rate: 16666, frame interval: 40000
2362008-02-24 14:11:36.731 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2372008-02-24 14:11:36.757 MythSocket(b36436b8:25): socket is readable
2382008-02-24 14:11:36.757 MythSocket(b36436b8:25): cb->readyRead()
2392008-02-24 14:11:36.757 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
2402008-02-24 14:11:36.757 MythEvent: SIGNAL 1
2412008-02-24 14:11:37.059 MythSocket(b36436b8:25): socket is readable
2422008-02-24 14:11:37.059 MythSocket(b36436b8:25): cb->readyRead()
2432008-02-24 14:11:37.059 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
2442008-02-24 14:11:37.059 MythEvent: SIGNAL 1
2452008-02-24 14:11:37.139 write -> 27 19 QUERY_GETALLPENDING
2462008-02-24 14:11:37.139 read <- 27 7 0[]:[]0
2472008-02-24 14:11:37.225 IsTunable(1166)
2482008-02-24 14:11:37.226 write -> 27 22 GET_FREE_RECORDER_LIST
2492008-02-24 14:11:37.227 read <- 27 13 2[]:[]5[]:[]7
2502008-02-24 14:11:37.227 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]1
2512008-02-24 14:11:37.230 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]...
2522008-02-24 14:11:37.230 IsTunable(1166) -> true
253
2542008-02-24 14:11:37.230 IsTunable(2167)
2552008-02-24 14:11:37.231 write -> 27 22 GET_FREE_RECORDER_LIST
2562008-02-24 14:11:37.232 read <- 27 13 2[]:[]5[]:[]7
2572008-02-24 14:11:37.232 IsTunable(2167) -> true
258
2592008-02-24 14:11:37.232 IsTunable(1168)
2602008-02-24 14:11:37.232 write -> 27 22 GET_FREE_RECORDER_LIST
2612008-02-24 14:11:37.233 read <- 27 13 2[]:[]5[]:[]7
2622008-02-24 14:11:37.233 IsTunable(1168) -> true
263
2642008-02-24 14:11:37.233 IsTunable(1169)
2652008-02-24 14:11:37.233 write -> 27 22 GET_FREE_RECORDER_LIST
2662008-02-24 14:11:37.234 read <- 27 13 2[]:[]5[]:[]7
2672008-02-24 14:11:37.234 IsTunable(1169) -> true
268
2692008-02-24 14:11:37.234 IsTunable(1170)
2702008-02-24 14:11:37.235 write -> 27 22 GET_FREE_RECORDER_LIST
2712008-02-24 14:11:37.236 read <- 27 13 2[]:[]5[]:[]7
2722008-02-24 14:11:37.236 IsTunable(1170) -> true
273
2742008-02-24 14:11:37.360 MythSocket(b36436b8:25): socket is readable
2752008-02-24 14:11:37.361 MythSocket(b36436b8:25): cb->readyRead()
2762008-02-24 14:11:37.361 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
2772008-02-24 14:11:37.361 MythEvent: SIGNAL 1
2782008-02-24 14:11:37.663 MythSocket(b36436b8:25): socket is readable
2792008-02-24 14:11:37.663 MythSocket(b36436b8:25): cb->readyRead()
2802008-02-24 14:11:37.663 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
2812008-02-24 14:11:37.663 MythEvent: SIGNAL 1
2822008-02-24 14:11:37.681 MythSocket(b36436b8:25): socket is readable
2832008-02-24 14:11:37.681 MythSocket(b36436b8:25): cb->readyRead()
2842008-02-24 14:11:37.681 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
2852008-02-24 14:11:37.681 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
2862008-02-24 14:11:38.721 MythSocket(b36436b8:25): socket is readable
2872008-02-24 14:11:38.722 MythSocket(b36436b8:25): cb->readyRead()
2882008-02-24 14:11:38.722 read <- 25 53 BACKEND_MESSAGE[]:[]QUERY_NEXT_LIVETV_DIR 1[]:[]empty
2892008-02-24 14:11:38.722 MythEvent: QUERY_NEXT_LIVETV_DIR 1
2902008-02-24 14:11:38.722 MythSocket(b36436b8:25): socket is readable
2912008-02-24 14:11:38.722 MythSocket(b36436b8:25): cb->readyRead()
2922008-02-24 14:11:38.722 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
2932008-02-24 14:11:38.722 MythEvent: RECORDING_LIST_CHANGE
2942008-02-24 14:11:38.722 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
2952008-02-24 14:11:38.722 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
2962008-02-24 14:11:38.722 MythSocket(b36436b8:25): socket is readable
2972008-02-24 14:11:38.722 MythSocket(b36436b8:25): cb->readyRead()
2982008-02-24 14:11:38.722 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
2992008-02-24 14:11:38.722 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
3002008-02-24 14:11:38.725 LiveTVChain(live-mythtv-2008-02-24T14:11:34): ReloadAll(): Added new recording
3012008-02-24 14:11:38.725 LiveTVChain(live-mythtv-2008-02-24T14:11:34): SwitchTo(1)
3022008-02-24 14:11:38.726 LiveTVChain(live-mythtv-2008-02-24T14:11:34): Entry@1: '1168_20080224141137'
3032008-02-24 14:11:38.726 JumpToProgram(void)
3042008-02-24 14:11:38.728 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
3052008-02-24 14:11:38.728 MythSocket(b36436b8:25): socket is readable
3062008-02-24 14:11:38.728 MythSocket(b36436b8:25): cb->readyRead()
3072008-02-24 14:11:38.728 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
3082008-02-24 14:11:38.728 MythEvent: RECORDING_LIST_CHANGE
3092008-02-24 14:11:38.728 read <- 27 2 OK
3102008-02-24 14:11:38.730 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group!
3112008-02-24 14:11:38.730 SG(Default): FindRecordingFile: Searching for '1168_20080224141137.mpg'
3122008-02-24 14:11:38.730 SG(Default): FindRecordingDir: Checking '/mnt/store'
3132008-02-24 14:11:38.730 SG(Default): FindRecordingFile: Found '/mnt/store/1168_20080224141137.mpg'
3142008-02-24 14:11:38.730 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/1168_20080224141137.mpg'
3152008-02-24 14:11:38.731 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
3162008-02-24 14:11:38.731 MythSocket(b36436b8:25): socket is readable
3172008-02-24 14:11:38.731 read <- 27 2 OK
3182008-02-24 14:11:38.731 MythSocket(b36436b8:25): cb->readyRead()
3192008-02-24 14:11:38.731 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
3202008-02-24 14:11:38.731 MythEvent: RECORDING_LIST_CHANGE
3212008-02-24 14:11:38.732 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group!
3222008-02-24 14:11:38.732 SG(Default): FindRecordingFile: Searching for '1168_20080224141137.mpg'
3232008-02-24 14:11:38.732 SG(Default): FindRecordingDir: Checking '/mnt/store'
3242008-02-24 14:11:38.732 SG(Default): FindRecordingFile: Found '/mnt/store/1168_20080224141137.mpg'
3252008-02-24 14:11:38.732 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/1168_20080224141137.mpg'
3262008-02-24 14:11:38.732 RingBuf(/mnt/store/1168_20080224141134.mpg): OpenFile(/mnt/store/1168_20080224141137.mpg, 12)
3272008-02-24 14:11:38.732 RingBuf(/mnt/store/1168_20080224141137.mpg): CalcReadAheadThresh(3086680064 KB)
328 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
3292008-02-24 14:11:38.737 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3302008-02-24 14:11:38.920 mpegts_add_stream: at pid 0x1722 with type 2
3312008-02-24 14:11:38.920 mpegts_add_stream: stream #0, has id 0x1722 and codec MPEG2VIDEO, type Video at 0x84ee780
3322008-02-24 14:11:38.920 mpegts_add_stream: at pid 0x1723 with type 4
3332008-02-24 14:11:38.920 mpegts_add_stream: stream #1, has id 0x1723 and codec MP3, type Audio at 0x85e0370
3342008-02-24 14:11:38.944 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3352008-02-24 14:11:38.954 NVP: Waiting for prebuffer.. 1 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3362008-02-24 14:11:39.162 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3372008-02-24 14:11:39.187 NVP: Waiting for prebuffer.. 2 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3382008-02-24 14:11:39.395 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3392008-02-24 14:11:39.420 NVP: Waiting for prebuffer.. 3 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3402008-02-24 14:11:39.627 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3412008-02-24 14:11:39.637 NVP: Waiting for prebuffer.. 4 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3422008-02-24 14:11:39.845 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3432008-02-24 14:11:39.870 NVP: Waiting for prebuffer.. 5 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3442008-02-24 14:11:39.972 AFD: Stream #0, has id 0x5922 codec id MPEG2VIDEO, type Video, bitrate 15000000 at 0x0x84ee780
3452008-02-24 14:11:39.973 VDP: Accepting: cmp(> 0 0) dec(libmpeg2) cpus(1) rend(opengl) osd(opengl2) osdfade(enabled) deint(greedyhdeint,openglkerneldeint) filt()
3462008-02-24 14:11:39.973 VDP: LoadBestPreferences(2048x2048, 0)
3472008-02-24 14:11:39.973 VDP: LoadBestPreferences(2048x2048, 60)
3482008-02-24 14:11:39.973 VDP: LoadBestPreferences(544x480, 60)
3492008-02-24 14:11:39.973 Using 1 CPUs for decoding
350QString::setNum: Invalid format char ''
3512008-02-24 14:11:39.974 AFD: Trying DirectShow for FOURCC 0x845639789
3522008-02-24 14:11:39.974 AFD: Using libmpeg2 for video decoding
3532008-02-24 14:11:39.974 AFD: InitVideoCodec() 0x85dbfd0 id(MPEG2VIDEO) type (Video).
3542008-02-24 14:11:39.974 VideoOutputXv: InputChanged(544,480,1.33333) 'None'->'MPEG2'
3552008-02-24 14:11:39.974 VDP: LoadBestPreferences(544x480, 25)
3562008-02-24 14:11:39.974 VideoOutputXv: DiscardFrames(1)
3572008-02-24 14:11:39.974 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3582008-02-24 14:11:39.974 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
3592008-02-24 14:11:39.974 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
3602008-02-24 14:11:39.974 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
3612008-02-24 14:11:39.974 VideoOutputXv: DiscardFrames(1)
3622008-02-24 14:11:39.974 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3632008-02-24 14:11:39.974 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
3642008-02-24 14:11:39.975 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
3652008-02-24 14:11:39.975 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
3662008-02-24 14:11:39.980 VideoOutputXv: InitSetupBuffers() render: opengl, allowed: opengl,xv-blit,xshm,xlib
3672008-02-24 14:11:39.980 GLVid: Viewport: 848x480
3682008-02-24 14:11:39.982 GLVid: Created main input texture 272x240
3692008-02-24 14:11:39.982 GLVid: Created main input texture 272x240
3702008-02-24 14:11:39.984 GLVid: Created main input texture 544x480
3712008-02-24 14:11:39.985 GLVid: Creating master filter.
3722008-02-24 14:11:39.985 GLVid: Created fragment program master.
3732008-02-24 14:11:39.985 GLVid: Creating resize filter.
3742008-02-24 14:11:39.988 GLCtx: Created frame buffer object (544x480).
3752008-02-24 14:11:39.988 GLVid: Turning off deinterlacing.
3762008-02-24 14:11:39.988 GLVid: Turning off deinterlacing.
3772008-02-24 14:11:39.988 Created data @0xae0654a0->0xae0c4ea2
3782008-02-24 14:11:39.988 Created data @0xa9489560->0xa94e8f62
3792008-02-24 14:11:39.988 Created data @0xad900030->0xad95fa32
3802008-02-24 14:11:39.988 Created data @0xad95faa0->0xad9bf4a2
3812008-02-24 14:11:39.988 Created data @0xad700030->0xad75fa32
3822008-02-24 14:11:39.988 Created data @0xad75faa0->0xad7bf4a2
3832008-02-24 14:11:39.988 Created data @0xad800030->0xad85fa32
3842008-02-24 14:11:39.988 Created data @0xad85faa0->0xad8bf4a2
3852008-02-24 14:11:39.988 Created data @0xad500030->0xad55fa32
3862008-02-24 14:11:39.988 Created data @0xad55faa0->0xad5bf4a2
3872008-02-24 14:11:39.988 Created data @0xad600030->0xad65fa32
3882008-02-24 14:11:39.988 Created data @0xad65faa0->0xad6bf4a2
3892008-02-24 14:11:39.988 Created data @0xad300030->0xad35fa32
3902008-02-24 14:11:39.988 Created data @0xad35faa0->0xad3bf4a2
3912008-02-24 14:11:39.988 Created data @0xad400030->0xad45fa32
3922008-02-24 14:11:39.988 Created data @0xad45faa0->0xad4bf4a2
3932008-02-24 14:11:39.989 Created data @0xad100030->0xad15fa32
3942008-02-24 14:11:39.989 Created data @0xad15faa0->0xad1bf4a2
3952008-02-24 14:11:39.989 Created data @0xad200030->0xad25fa32
3962008-02-24 14:11:39.989 Created data @0xad25faa0->0xad2bf4a2
3972008-02-24 14:11:39.989 Created data @0xacf00030->0xacf5fa32
3982008-02-24 14:11:39.989 Created data @0xacf5faa0->0xacfbf4a2
3992008-02-24 14:11:39.989 Created data @0xad000030->0xad05fa32
4002008-02-24 14:11:39.989 Created data @0xad05faa0->0xad0bf4a2
4012008-02-24 14:11:39.989 Created data @0xacd00030->0xacd5fa32
4022008-02-24 14:11:39.989 Created data @0xacd5faa0->0xacdbf4a2
4032008-02-24 14:11:39.989 Created data @0xace00030->0xace5fa32
4042008-02-24 14:11:39.989 Created data @0xace5faa0->0xacebf4a2
4052008-02-24 14:11:39.989 Created data @0xacb00030->0xacb5fa32
4062008-02-24 14:11:39.989 Created data @0xacb5faa0->0xacbbf4a2
4072008-02-24 14:11:39.989 Created data @0xacc00030->0xacc5fa32
4082008-02-24 14:11:39.989 Created data @0xacc5faa0->0xaccbf4a2
4092008-02-24 14:11:39.998 GLVid: Turning on deinterlacing.
4102008-02-24 14:11:39.999 VDP: SetVideoRenderer(opengl)
4112008-02-24 14:11:39.999 VDP: SetVideoRender(opengl) == GetVideoRenderer()
4122008-02-24 14:11:40.003 GLVid: Created main input texture 848x480
4132008-02-24 14:11:40.005 GLVid: Created main input texture 424x240
4142008-02-24 14:11:40.006 GLVid: Created main input texture 424x240
4152008-02-24 14:11:40.010 GLVid: Created main input texture 848x480
4162008-02-24 14:11:40.010 GLVid: Creating osd filter.
4172008-02-24 14:11:40.010 GLVid: Created fragment program osd.
4182008-02-24 14:11:40.011 GLVid: Turning off deinterlacing.
4192008-02-24 14:11:40.011 GLVid: Creating resize filter.
4202008-02-24 14:11:40.015 GLCtx: Created frame buffer object (848x480).
4212008-02-24 14:11:40.015 GLVid: Turning off deinterlacing.
4222008-02-24 14:11:40.015 Display Rect left: 106, top: 0, width: 636, height: 480, aspect: 1.77778
4232008-02-24 14:11:40.015 Video Rect left: 0, top: 14, width: 544, height: 451, aspect: 1.33333
4242008-02-24 14:11:40.015 VDP: LoadBestPreferences(544x480, 29.97)
4252008-02-24 14:11:40.071 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
4262008-02-24 14:11:40.109 NVP: ClearAfterSeek(1)
4272008-02-24 14:11:40.109 VideoOutputXv: ClearAfterSeek()
4282008-02-24 14:11:40.109 VideoOutputXv: DiscardFrames(0)
4292008-02-24 14:11:40.109 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
430greedyhdeint: size changed from 720 x 576 -> 544 x 480
4312008-02-24 14:11:40.109 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
4322008-02-24 14:11:40.109 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
4332008-02-24 14:11:40.120 NVP: LoadFilters(''..) -> 0
4342008-02-24 14:11:40.120 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
4352008-02-24 14:11:40.121 AFD: Using libmpeg2 for video decoding
4362008-02-24 14:11:40.121 AFD: Looking for decoder for MPEG2VIDEO
4372008-02-24 14:11:40.121 AFD: Opened codec 0x85dbfd0, id(MPEG2VIDEO) type(Video)
4382008-02-24 14:11:40.121 AFD: Stream #1, has id 0x5923 codec id MP3, type Audio, bitrate 160000 at 0x0x85e0370
4392008-02-24 14:11:40.121 AFD: codec MP3 has 2 channels
4402008-02-24 14:11:40.121 AFD: Looking for decoder for MP3
4412008-02-24 14:11:40.121 AFD: Opened codec 0x85fbba0, id(MP3) type(Audio)
4422008-02-24 14:11:40.121 AFD: Audio Track #1 is A/V stream #1 and has 2 channels in the English language(6647399).
4432008-02-24 14:11:40.136 NVP: Waiting for prebuffer.. 6 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
4442008-02-24 14:11:40.161 RingBuf(/mnt/store/1168_20080224141137.mpg): CalcReadAheadThresh(2920108280 KB)
445 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
4462008-02-24 14:11:40.161 AFD: Selected track 1: English MP2 2ch (A/V Stream #1)
4472008-02-24 14:11:40.162 AFD: Initializing audio parms from audio track #1
4482008-02-24 14:11:40.162 AFD: Audio format changed
449 from id(NONE) -1Hz -1ch 0bps ; id(NONE) -1Hz -1ch 0bps
450 to id( MP3) 48000Hz 2ch 16bps ; id( MP3) 48000Hz 2ch 16bps
4512008-02-24 14:11:40.164 AO: Killing AudioOutputDSP
4522008-02-24 14:11:40.164 Opening audio device 'default'. ch 2(2) sr 48000
4532008-02-24 14:11:40.164 Opening ALSA audio device 'default'.
4542008-02-24 14:11:40.192 in SetParameters(format=2, channels=2, rate=48000, buffer_time=100000, period_time=25000)
4552008-02-24 14:11:40.192 get_buffer_size returned 5120
4562008-02-24 14:11:40.192 set_period_time_near returned 21333
4572008-02-24 14:11:40.192 get_period_size returned 1024
4582008-02-24 14:11:40.193 Opening mixer default
4592008-02-24 14:11:40.194 Setting Master volume to 70
4602008-02-24 14:11:40.194 Mixer unable to find control Master
4612008-02-24 14:11:40.194 Setting Master volume to 70
4622008-02-24 14:11:40.194 Mixer unable to find control Master
4632008-02-24 14:11:40.194 Setting PCM volume to 100
4642008-02-24 14:11:40.194 Volume range is 0 to 255, mult=0.392157
4652008-02-24 14:11:40.194 channel 0 vol set to 255
4662008-02-24 14:11:40.194 Setting PCM volume to 100
4672008-02-24 14:11:40.194 Volume range is 0 to 255, mult=0.392157
4682008-02-24 14:11:40.195 channel 1 vol set to 255
4692008-02-24 14:11:40.195 Volume range is 0 to 255, mult=0.392157
4702008-02-24 14:11:40.195 AO: Audio fragment size: 6400
4712008-02-24 14:11:40.195 AO: Audio Stretch Factor: 1
4722008-02-24 14:11:40.195 Audio Codec Used: not set
4732008-02-24 14:11:40.197 AO: Ending reconfigure
4742008-02-24 14:11:40.197 AO: kickoffOutputAudioLoop: pid = 907
4752008-02-24 14:11:40.197 NVP: Enabling Audio
4762008-02-24 14:11:40.197 AO: OutputAudioLoop: Play Event
4772008-02-24 14:11:40.197 AO: no change exiting
4782008-02-24 14:11:40.197 Dec: Trying to select track (w/lang)
4792008-02-24 14:11:40.197 Dec: Selecting first track
4802008-02-24 14:11:40.197 Dec: Selected track #1 in the Unknown language(0)
4812008-02-24 14:11:40.197 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
4822008-02-24 14:11:40.198 SyncPositionMap watchingrecording, from DB: 0 entries
4832008-02-24 14:11:40.198 write -> 30 39 QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN
4842008-02-24 14:11:40.198 read <- 30 8 0[]:[]40
4852008-02-24 14:11:40.198 Filling position map from 0 to 40
4862008-02-24 14:11:40.198 write -> 30 51 QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]0[]:[]40
4872008-02-24 14:11:40.198 read <- 30 82 0[]:[]0[]:[]0[]:[]1880[]:[]0[]:[]10[]:[]0[]:[]282940[]:[]0[]:[]20...
4882008-02-24 14:11:40.198 Position map filled from Encoder to: 20
4892008-02-24 14:11:40.198 SyncPositionMap watchingrecording total: 3 entries
4902008-02-24 14:11:40.198 SyncPositionMap, new totframes: 20, new length: 0, posMap size: 3
4912008-02-24 14:11:40.198 Input #0, mpegts, from '/mnt/store/1168_20080224141137.mpg':
4922008-02-24 14:11:40.198 Duration: N/A, start: 32458.536800, bitrate: 15160 kb/s
4932008-02-24 14:11:40.199 Stream #0.0[0x1722], 1/90000: Video: mpeg2video, yuv420p, 544x480 [PAR 20:17 DAR 4:3], 1001/30000, 15000 kb/s, 29.97 tb(r)
4942008-02-24 14:11:40.199 Stream #0.1[0x1723](eng), 1/90000: Audio: mp2, 48000 Hz, stereo, 160 kb/s
4952008-02-24 14:11:40.199 AFD: Partial position map found
4962008-02-24 14:11:40.199 AFD: Successfully opened decoder for file: "/mnt/store/1168_20080224141137.mpg". novideo(0)
4972008-02-24 14:11:40.199 NVP: DoPlay: rate: 29.97 speed: 1 skip: 1 => new interval 33366
4982008-02-24 14:11:40.199 Set video sync frame interval to 33366
4992008-02-24 14:11:40.199 NVP: Stretch Factor 1, allow passthru
5002008-02-24 14:11:40.205 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
5012008-02-24 14:11:40.220 AO: Pause 1
5022008-02-24 14:11:40.220 NVP: Waiting for prebuffer.. 7 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
5032008-02-24 14:11:40.222 AO: OutputAudioLoop: audio paused
5042008-02-24 14:11:40.370 NVP: Waiting for prebuffer.. 8 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
5052008-02-24 14:11:40.409 RingBuf(/mnt/store/1168_20080224141137.mpg): CalcReadAheadThresh(0 KB)
506 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
5072008-02-24 14:11:40.409 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
5082008-02-24 14:11:40.409 SyncPositionMap watchingrecording, from DB: 3 entries
5092008-02-24 14:11:40.409 write -> 30 39 QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN
5102008-02-24 14:11:40.410 read <- 30 8 0[]:[]44
5112008-02-24 14:11:40.410 Filling position map from 21 to 44
5122008-02-24 14:11:40.410 write -> 30 52 QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]21[]:[]44
5132008-02-24 14:11:40.410 read <- 30 2 ok
5142008-02-24 14:11:40.410 Position map filled from Encoder to: 20
5152008-02-24 14:11:40.410 SyncPositionMap watchingrecording total: 3 entries
5162008-02-24 14:11:40.519 NVP: Waiting for prebuffer.. 9 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
5172008-02-24 14:11:40.653 NVP: Prebuffer wait timed out 10 times.
5182008-02-24 14:11:40.669 NVP: Waiting for prebuffer.. 0 ALLLAAAAAAAAAAAAAAAAAAAAAAAAAAA
5192008-02-24 14:11:40.819 NVP: Waiting for prebuffer.. 1 ALLLAAAAAAAAAAAAAAAAAAAAAAAAAAA
5202008-02-24 14:11:40.969 NVP: Waiting for prebuffer.. 2 AAuLLAAAAAAAAAAAAAAAAAAAAAAAAAA
5212008-02-24 14:11:41.119 NVP: Waiting for prebuffer.. 3 AAUUUUUUUUuLLAAAAAAAAAAAAAAAAAA
5222008-02-24 14:11:41.269 NVP: Waiting for prebuffer.. 4 AAUUUUUUUUuLLAAAAAAAAAAAAAAAAAA
5232008-02-24 14:11:41.286 NVP: progressive frame seen after 2 interlaced frames
5242008-02-24 14:11:41.289 AO: Pause 0
5252008-02-24 14:11:41.291 AO: OutputAudioLoop: Play Event
5262008-02-24 14:11:41.336 GLVid: Turning off deinterlacing.
5272008-02-24 14:11:41.336 Disabled deinterlacing
5282008-02-24 14:11:41.369 NVP: interlaced frame seen after 3 progressive frames
5292008-02-24 14:11:41.369 GLVid: Turning on deinterlacing.
5302008-02-24 14:11:41.369 Enabled deinterlacing
5312008-02-24 14:11:41.436 NVP: progressive frame seen after 4 interlaced frames
5322008-02-24 14:11:41.502 GLVid: Turning off deinterlacing.
5332008-02-24 14:11:41.503 Disabled deinterlacing
5342008-02-24 14:11:41.536 NVP: interlaced frame seen after 3 progressive frames
5352008-02-24 14:11:41.536 GLVid: Turning on deinterlacing.
5362008-02-24 14:11:41.536 Enabled deinterlacing
5372008-02-24 14:11:41.602 NVP: progressive frame seen after 4 interlaced frames
5382008-02-24 14:11:41.669 GLVid: Turning off deinterlacing.
5392008-02-24 14:11:41.669 Disabled deinterlacing
5402008-02-24 14:11:41.702 NVP: interlaced frame seen after 3 progressive frames
5412008-02-24 14:11:41.702 GLVid: Turning on deinterlacing.
5422008-02-24 14:11:41.702 Enabled deinterlacing
5432008-02-24 14:11:41.769 NVP: progressive frame seen after 4 interlaced frames
5442008-02-24 14:11:41.836 GLVid: Turning off deinterlacing.
5452008-02-24 14:11:41.836 Disabled deinterlacing
5462008-02-24 14:11:41.869 NVP: interlaced frame seen after 3 progressive frames
5472008-02-24 14:11:41.869 GLVid: Turning on deinterlacing.
5482008-02-24 14:11:41.869 Enabled deinterlacing
5492008-02-24 14:11:41.936 NVP: progressive frame seen after 4 interlaced frames
5502008-02-24 14:11:42.002 GLVid: Turning off deinterlacing.
5512008-02-24 14:11:42.002 Disabled deinterlacing
5522008-02-24 14:11:42.069 NVP: interlaced frame seen after 4 progressive frames
5532008-02-24 14:11:42.069 GLVid: Turning on deinterlacing.
5542008-02-24 14:11:42.069 Enabled deinterlacing
5552008-02-24 14:11:42.135 NVP: progressive frame seen after 4 interlaced frames
5562008-02-24 14:11:42.202 GLVid: Turning off deinterlacing.
5572008-02-24 14:11:42.202 Disabled deinterlacing
5582008-02-24 14:11:42.518 NVP: interlaced frame seen after 11 progressive frames
5592008-02-24 14:11:42.519 GLVid: Turning on deinterlacing.
5602008-02-24 14:11:42.519 Enabled deinterlacing
5612008-02-24 14:11:42.568 NVP: prebuffering pause
5622008-02-24 14:11:42.569 AO: Pause 1
5632008-02-24 14:11:42.569 NVP: Waiting for prebuffer.. 0 AAAAAAAAAuLLAAAAAAAAAAAAAAAAAAA
5642008-02-24 14:11:42.573 AO: OutputAudioLoop: audio paused
5652008-02-24 14:11:42.718 NVP: Waiting for prebuffer.. 1 AAAAAAAAAUUUUUUUUUUuLLAAAAAAAAA
5662008-02-24 14:11:42.772 AO: Pause 0
5672008-02-24 14:11:42.775 AO: OutputAudioLoop: Play Event
5682008-02-24 14:11:42.785 NVP: progressive frame seen after 4 interlaced frames
5692008-02-24 14:11:42.818 NVP: interlaced frame seen after 1 progressive frames
5702008-02-24 14:11:42.885 NVP: progressive frame seen after 4 interlaced frames
5712008-02-24 14:11:42.952 GLVid: Turning off deinterlacing.
5722008-02-24 14:11:42.952 Disabled deinterlacing
5732008-02-24 14:11:42.985 NVP: interlaced frame seen after 3 progressive frames
5742008-02-24 14:11:42.985 GLVid: Turning on deinterlacing.
5752008-02-24 14:11:42.985 Enabled deinterlacing
5762008-02-24 14:11:43.002 IsTunable(4562)
5772008-02-24 14:11:43.002 write -> 27 22 GET_FREE_RECORDER_LIST
5782008-02-24 14:11:43.003 read <- 27 13 2[]:[]5[]:[]7
5792008-02-24 14:11:43.004 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]1
5802008-02-24 14:11:43.006 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]...
5812008-02-24 14:11:43.006 write -> 27 42 QUERY_RECORDER 2[]:[]GET_FREE_INPUTS[]:[]1
5822008-02-24 14:11:43.007 read <- 27 32 DVBInput[]:[]4[]:[]4[]:[]2[]:[]0
5832008-02-24 14:11:43.007 IsTunable(4562) -> true
584
5852008-02-24 14:11:43.007 IsTunable(4563)
5862008-02-24 14:11:43.007 write -> 27 22 GET_FREE_RECORDER_LIST
5872008-02-24 14:11:43.008 read <- 27 13 2[]:[]5[]:[]7
5882008-02-24 14:11:43.008 IsTunable(4563) -> true
589
5902008-02-24 14:11:43.008 IsTunable(4621)
5912008-02-24 14:11:43.008 write -> 27 22 GET_FREE_RECORDER_LIST
5922008-02-24 14:11:43.009 read <- 27 13 2[]:[]5[]:[]7
5932008-02-24 14:11:43.009 IsTunable(4621) -> true
594
5952008-02-24 14:11:43.009 IsTunable(2100)
5962008-02-24 14:11:43.010 write -> 27 22 GET_FREE_RECORDER_LIST
5972008-02-24 14:11:43.010 read <- 27 13 2[]:[]5[]:[]7
5982008-02-24 14:11:43.010 IsTunable(2100) -> true
599
6002008-02-24 14:11:43.010 IsTunable(1101)
6012008-02-24 14:11:43.011 write -> 27 22 GET_FREE_RECORDER_LIST
6022008-02-24 14:11:43.011 read <- 27 13 2[]:[]5[]:[]7
6032008-02-24 14:11:43.012 IsTunable(1101) -> true
604
6052008-02-24 14:11:43.058 NVP: progressive frame seen after 4 interlaced frames
6062008-02-24 14:11:43.118 GLVid: Turning off deinterlacing.
6072008-02-24 14:11:43.118 Disabled deinterlacing
6082008-02-24 14:11:43.152 NVP: interlaced frame seen after 3 progressive frames
6092008-02-24 14:11:43.152 GLVid: Turning on deinterlacing.
6102008-02-24 14:11:43.152 Enabled deinterlacing
6112008-02-24 14:11:43.218 NVP: progressive frame seen after 4 interlaced frames
6122008-02-24 14:11:43.285 GLVid: Turning off deinterlacing.
6132008-02-24 14:11:43.285 Disabled deinterlacing
6142008-02-24 14:11:43.318 NVP: interlaced frame seen after 3 progressive frames
6152008-02-24 14:11:43.318 GLVid: Turning on deinterlacing.
6162008-02-24 14:11:43.318 Enabled deinterlacing
6172008-02-24 14:11:43.395 NVP: progressive frame seen after 4 interlaced frames
6182008-02-24 14:11:43.451 GLVid: Turning off deinterlacing.
6192008-02-24 14:11:43.452 Disabled deinterlacing
6202008-02-24 14:11:43.462 IsTunable(4563)
6212008-02-24 14:11:43.463 write -> 27 22 GET_FREE_RECORDER_LIST
6222008-02-24 14:11:43.464 read <- 27 13 2[]:[]5[]:[]7
6232008-02-24 14:11:43.464 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]1
6242008-02-24 14:11:43.466 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]...
6252008-02-24 14:11:43.466 write -> 27 42 QUERY_RECORDER 2[]:[]GET_FREE_INPUTS[]:[]1
6262008-02-24 14:11:43.466 read <- 27 32 DVBInput[]:[]4[]:[]4[]:[]2[]:[]0
6272008-02-24 14:11:43.467 IsTunable(4563) -> true
628
6292008-02-24 14:11:43.467 IsTunable(4621)
6302008-02-24 14:11:43.467 write -> 27 22 GET_FREE_RECORDER_LIST
6312008-02-24 14:11:43.468 read <- 27 13 2[]:[]5[]:[]7
6322008-02-24 14:11:43.468 IsTunable(4621) -> true
633
6342008-02-24 14:11:43.468 IsTunable(2100)
6352008-02-24 14:11:43.468 write -> 27 22 GET_FREE_RECORDER_LIST
6362008-02-24 14:11:43.469 read <- 27 13 2[]:[]5[]:[]7
6372008-02-24 14:11:43.469 IsTunable(2100) -> true
638
6392008-02-24 14:11:43.469 IsTunable(1101)
6402008-02-24 14:11:43.469 write -> 27 22 GET_FREE_RECORDER_LIST
6412008-02-24 14:11:43.470 read <- 27 13 2[]:[]5[]:[]7
6422008-02-24 14:11:43.470 IsTunable(1101) -> true
643
6442008-02-24 14:11:43.470 IsTunable(2102)
6452008-02-24 14:11:43.470 write -> 27 22 GET_FREE_RECORDER_LIST
6462008-02-24 14:11:43.471 read <- 27 13 2[]:[]5[]:[]7
6472008-02-24 14:11:43.471 IsTunable(2102) -> true
648
6492008-02-24 14:11:43.503 NVP: interlaced frame seen after 3 progressive frames
6502008-02-24 14:11:43.503 GLVid: Turning on deinterlacing.
6512008-02-24 14:11:43.504 Enabled deinterlacing
6522008-02-24 14:11:43.551 NVP: progressive frame seen after 4 interlaced frames
6532008-02-24 14:11:43.618 GLVid: Turning off deinterlacing.
6542008-02-24 14:11:43.618 Disabled deinterlacing
6552008-02-24 14:11:43.785 NVP: interlaced frame seen after 7 progressive frames
6562008-02-24 14:11:43.785 GLVid: Turning on deinterlacing.
6572008-02-24 14:11:43.785 Enabled deinterlacing
6582008-02-24 14:11:43.934 NVP: progressive frame seen after 6 interlaced frames
6592008-02-24 14:11:44.001 GLVid: Turning off deinterlacing.
6602008-02-24 14:11:44.001 Disabled deinterlacing
6612008-02-24 14:11:44.201 NVP: interlaced frame seen after 7 progressive frames
6622008-02-24 14:11:44.201 GLVid: Turning on deinterlacing.
6632008-02-24 14:11:44.201 Enabled deinterlacing
6642008-02-24 14:11:44.268 NVP: progressive frame seen after 4 interlaced frames
6652008-02-24 14:11:44.301 NVP: interlaced frame seen after 1 progressive frames
6662008-02-24 14:11:44.334 NVP: progressive frame seen after 3 interlaced frames
6672008-02-24 14:11:44.368 NVP: interlaced frame seen after 1 progressive frames
6682008-02-24 14:11:44.434 NVP: progressive frame seen after 4 interlaced frames
6692008-02-24 14:11:44.501 GLVid: Turning off deinterlacing.
6702008-02-24 14:11:44.501 Disabled deinterlacing
6712008-02-24 14:11:44.767 NVP: interlaced frame seen after 9 progressive frames
6722008-02-24 14:11:44.767 GLVid: Turning on deinterlacing.
6732008-02-24 14:11:44.767 Enabled deinterlacing
6742008-02-24 14:11:44.834 NVP: progressive frame seen after 4 interlaced frames
6752008-02-24 14:11:44.901 GLVid: Turning off deinterlacing.
6762008-02-24 14:11:44.901 Disabled deinterlacing
677'video_output' mean = '36313.26', std. dev. = '19162.17', fps = '27.54'
6782008-02-24 14:11:44.934 NVP: interlaced frame seen after 3 progressive frames
6792008-02-24 14:11:44.934 GLVid: Turning on deinterlacing.
6802008-02-24 14:11:44.934 Enabled deinterlacing
6812008-02-24 14:11:45.001 NVP: progressive frame seen after 4 interlaced frames
6822008-02-24 14:11:45.067 GLVid: Turning off deinterlacing.
6832008-02-24 14:11:45.067 Disabled deinterlacing
6842008-02-24 14:11:45.470 IsTunable(4563)
6852008-02-24 14:11:45.470 write -> 27 22 GET_FREE_RECORDER_LIST
6862008-02-24 14:11:45.471 read <- 27 13 2[]:[]5[]:[]7
6872008-02-24 14:11:45.471 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]1
6882008-02-24 14:11:45.473 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]...
6892008-02-24 14:11:45.473 write -> 27 42 QUERY_RECORDER 2[]:[]GET_FREE_INPUTS[]:[]1
6902008-02-24 14:11:45.473 read <- 27 32 DVBInput[]:[]4[]:[]4[]:[]2[]:[]0
6912008-02-24 14:11:45.474 IsTunable(4563) -> true
692
6932008-02-24 14:11:45.474 IsTunable(4621)
6942008-02-24 14:11:45.474 write -> 27 22 GET_FREE_RECORDER_LIST
6952008-02-24 14:11:45.474 read <- 27 13 2[]:[]5[]:[]7
6962008-02-24 14:11:45.475 IsTunable(4621) -> true
697
6982008-02-24 14:11:45.475 IsTunable(2100)
6992008-02-24 14:11:45.475 write -> 27 22 GET_FREE_RECORDER_LIST
7002008-02-24 14:11:45.476 read <- 27 13 2[]:[]5[]:[]7
7012008-02-24 14:11:45.476 IsTunable(2100) -> true
702
7032008-02-24 14:11:45.476 IsTunable(1101)
7042008-02-24 14:11:45.476 write -> 27 22 GET_FREE_RECORDER_LIST
7052008-02-24 14:11:45.477 read <- 27 13 2[]:[]5[]:[]7
7062008-02-24 14:11:45.477 IsTunable(1101) -> true
707
7082008-02-24 14:11:45.477 IsTunable(2102)
7092008-02-24 14:11:45.477 write -> 27 22 GET_FREE_RECORDER_LIST
7102008-02-24 14:11:45.478 read <- 27 13 2[]:[]5[]:[]7
7112008-02-24 14:11:45.478 IsTunable(2102) -> true
712
7132008-02-24 14:11:46.947 IsTunable(4563)
7142008-02-24 14:11:46.947 write -> 27 22 GET_FREE_RECORDER_LIST
7152008-02-24 14:11:46.948 read <- 27 13 2[]:[]5[]:[]7
7162008-02-24 14:11:46.949 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]1
7172008-02-24 14:11:46.951 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]...
7182008-02-24 14:11:46.952 write -> 27 42 QUERY_RECORDER 2[]:[]GET_FREE_INPUTS[]:[]1
7192008-02-24 14:11:46.952 read <- 27 32 DVBInput[]:[]4[]:[]4[]:[]2[]:[]0
7202008-02-24 14:11:46.952 IsTunable(4563) -> true
721
7222008-02-24 14:11:46.952 IsTunable(4621)
7232008-02-24 14:11:46.953 write -> 27 22 GET_FREE_RECORDER_LIST
7242008-02-24 14:11:46.953 read <- 27 13 2[]:[]5[]:[]7
7252008-02-24 14:11:46.954 IsTunable(4621) -> true
726
7272008-02-24 14:11:46.954 IsTunable(2100)
7282008-02-24 14:11:46.954 write -> 27 22 GET_FREE_RECORDER_LIST
7292008-02-24 14:11:46.955 read <- 27 13 2[]:[]5[]:[]7
7302008-02-24 14:11:46.955 IsTunable(2100) -> true
731
7322008-02-24 14:11:46.955 IsTunable(1101)
7332008-02-24 14:11:46.955 write -> 27 22 GET_FREE_RECORDER_LIST
7342008-02-24 14:11:46.956 read <- 27 13 2[]:[]5[]:[]7
7352008-02-24 14:11:46.956 IsTunable(1101) -> true
736
7372008-02-24 14:11:46.956 IsTunable(2102)
7382008-02-24 14:11:46.956 write -> 27 22 GET_FREE_RECORDER_LIST
7392008-02-24 14:11:46.957 read <- 27 13 2[]:[]5[]:[]7
7402008-02-24 14:11:46.957 IsTunable(2102) -> true
741
7422008-02-24 14:11:47.131 IsTunable(4621)
7432008-02-24 14:11:47.133 write -> 27 22 GET_FREE_RECORDER_LIST
7442008-02-24 14:11:47.135 read <- 27 13 2[]:[]5[]:[]7
7452008-02-24 14:11:47.137 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]1
7462008-02-24 14:11:47.139 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]...
7472008-02-24 14:11:47.140 write -> 27 42 QUERY_RECORDER 2[]:[]GET_FREE_INPUTS[]:[]1
7482008-02-24 14:11:47.140 read <- 27 32 DVBInput[]:[]4[]:[]4[]:[]2[]:[]0
7492008-02-24 14:11:47.140 IsTunable(4621) -> true
750
7512008-02-24 14:11:47.141 TV: CommitQueuedInput() livetv(1) qchannum(62_1) qchanid(4621)
7522008-02-24 14:11:47.141 TV: ChangeChannel(4621, '62_1')
7532008-02-24 14:11:47.142 write -> 30 48 QUERY_RECORDER 1[]:[]SHOULD_SWITCH_CARD[]:[]4621
7542008-02-24 14:11:47.143 read <- 30 1 1
7552008-02-24 14:11:47.143 write -> 27 22 GET_FREE_RECORDER_LIST
7562008-02-24 14:11:47.144 read <- 27 13 2[]:[]5[]:[]7
7572008-02-24 14:11:47.144 write -> 27 27 GET_RECORDER_FROM_NUM[]:[]2
7582008-02-24 14:11:47.144 read <- 27 21 192.168.0.50[]:[]6543
7592008-02-24 14:11:47.144 TV: SwitchCards(4621,'62_1',0)
7602008-02-24 14:11:47.144 write -> 27 22 GET_FREE_RECORDER_LIST
7612008-02-24 14:11:47.145 read <- 27 13 2[]:[]5[]:[]7
7622008-02-24 14:11:47.145 write -> 27 27 GET_RECORDER_FROM_NUM[]:[]2
7632008-02-24 14:11:47.145 read <- 27 21 192.168.0.50[]:[]6543
7642008-02-24 14:11:47.145 NVP: Changing speed to 0
7652008-02-24 14:11:47.145 rate: 29.97 speed: 1 skip: 1 = interval 33366
7662008-02-24 14:11:47.145 Set video sync frame interval to 33366
7672008-02-24 14:11:47.152 write -> 30 32 QUERY_RECORDER 1[]:[]STOP_LIVETV
7682008-02-24 14:11:47.155 MythSocket(b36436b8:25): socket is readable
7692008-02-24 14:11:47.155 MythSocket(b36436b8:25): cb->readyRead()
7702008-02-24 14:11:47.155 read <- 25 48 BACKEND_MESSAGE[]:[]DONE_RECORDING 1 8[]:[]empty
7712008-02-24 14:11:47.155 MythEvent: DONE_RECORDING 1 8
7722008-02-24 14:11:47.189 MythSocket(b36436b8:25): socket is readable
7732008-02-24 14:11:47.189 MythSocket(b36436b8:25): cb->readyRead()
7742008-02-24 14:11:47.189 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
7752008-02-24 14:11:47.189 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
7762008-02-24 14:11:47.191 MythSocket(b36436b8:25): socket is readable
7772008-02-24 14:11:47.191 MythSocket(b36436b8:25): cb->readyRead()
7782008-02-24 14:11:47.191 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
7792008-02-24 14:11:47.191 MythEvent: RECORDING_LIST_CHANGE
7802008-02-24 14:11:47.191 read <- 30 2 ok
7812008-02-24 14:11:47.198 NVP: Exited decoder loop.
7822008-02-24 14:11:47.216 ~OpenGLVideoSync() -- begin
7832008-02-24 14:11:47.216 ~OpenGLVideoSync() -- middle
7842008-02-24 14:11:47.216 ~OpenGLVideoSync() -- end
7852008-02-24 14:11:47.216 VideoOutputXv: dtor
7862008-02-24 14:11:47.216 VideoOutputXv: DiscardFrames(1)
7872008-02-24 14:11:47.217 VideoBuffers::DiscardFrames(1): AAAAAAAAUAAUAAUUUUUuUULUUALAAAA
7882008-02-24 14:11:47.217 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
7892008-02-24 14:11:47.217 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
7902008-02-24 14:11:47.217 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
791X Error: BadDrawable (invalid Pixmap or Window parameter) 9
792 Major opcode: 70
793 Minor opcode: 0
794 Resource id: 0x1e00044
7952008-02-24 14:11:47.253 AO: Killing AudioOutputDSP
7962008-02-24 14:11:47.257 AO: OutputAudioLoop: Stop Event
7972008-02-24 14:11:47.257 AO: kickoffOutputAudioLoop exiting
7982008-02-24 14:11:47.281 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
7992008-02-24 14:11:47.281 read <- 27 2 OK
8002008-02-24 14:11:47.281 MythSocket(b36436b8:25): socket is readable
8012008-02-24 14:11:47.281 MythSocket(b36436b8:25): cb->readyRead()
8022008-02-24 14:11:47.282 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
8032008-02-24 14:11:47.282 MythEvent: RECORDING_LIST_CHANGE
8042008-02-24 14:11:47.294 Closing PAT Filter: pid=0x0
8052008-02-24 14:11:47.295 Closing PMT Filter: pid=0x21
8062008-02-24 14:11:47.295 MythSocket(82d8670:30): DownRef: -1
8072008-02-24 14:11:47.295 MythSocket(82d8670:30): state change Connected -> Idle
8082008-02-24 14:11:47.295 MythSocket(82d8670:-1): delete socket
8092008-02-24 14:11:47.297 MythSocket(a77777b8:30): new socket
8102008-02-24 14:11:47.297 MythSocket(a77777b8:30): attempting connect() to (192.168.0.50:6543)
8112008-02-24 14:11:47.297 MythSocket(a77777b8:30): state change Idle -> Connected
8122008-02-24 14:11:47.298 write -> 30 21 MYTH_PROTO_VERSION 40
8132008-02-24 14:11:47.298 read <- 30 13 ACCEPT[]:[]40
8142008-02-24 14:11:47.298 Using protocol version 40
8152008-02-24 14:11:47.298 write -> 30 21 ANN Playback mythtv 0
8162008-02-24 14:11:47.301 read <- 30 2 OK
8172008-02-24 14:11:47.301 write -> 30 84 QUERY_RECORDER 2[]:[]SPAWN_LIVETV[]:[]live-mythtv-2008-02-24T14:1...
8182008-02-24 14:11:48.868 read <- 30 2 ok
8192008-02-24 14:11:48.869 LiveTVChain(live-mythtv-2008-02-24T14:11:34): ReloadAll(): Added new recording
8202008-02-24 14:11:48.870 MythSocket(b36436b8:25): socket is readable
8212008-02-24 14:11:48.871 MythSocket(b36436b8:25): cb->readyRead()
8222008-02-24 14:11:48.871 read <- 25 53 BACKEND_MESSAGE[]:[]QUERY_NEXT_LIVETV_DIR 2[]:[]empty
8232008-02-24 14:11:48.871 MythEvent: QUERY_NEXT_LIVETV_DIR 2
8242008-02-24 14:11:48.871 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
8252008-02-24 14:11:48.871 MythEvent: RECORDING_LIST_CHANGE
8262008-02-24 14:11:48.871 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
8272008-02-24 14:11:48.871 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
8282008-02-24 14:11:48.871 read <- 25 541 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
8292008-02-24 14:11:48.871 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group!
8302008-02-24 14:11:48.871 MythEvent: SIGNAL 2
8312008-02-24 14:11:48.871 SG(Default): FindRecordingFile: Searching for '4621_20080224141147.mpg'
8322008-02-24 14:11:48.872 SG(Default): FindRecordingDir: Checking '/mnt/store'
8332008-02-24 14:11:48.872 SG(Default): FindRecordingFile: Found '/mnt/store/4621_20080224141147.mpg'
8342008-02-24 14:11:48.872 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/4621_20080224141147.mpg'
8352008-02-24 14:11:48.872 RingBuf(/mnt/store/4621_20080224141147.mpg): OpenFile(/mnt/store/4621_20080224141147.mpg, 12)
8362008-02-24 14:11:48.918 MythSocket(b36436b8:25): socket is readable
8372008-02-24 14:11:48.918 MythSocket(b36436b8:25): cb->readyRead()
8382008-02-24 14:11:48.918 read <- 25 541 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
8392008-02-24 14:11:48.918 MythEvent: SIGNAL 2
8402008-02-24 14:11:48.970 MythSocket(b36436b8:25): socket is readable
8412008-02-24 14:11:48.970 MythSocket(b36436b8:25): cb->readyRead()
8422008-02-24 14:11:48.970 read <- 25 541 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
8432008-02-24 14:11:48.970 MythEvent: SIGNAL 2
8442008-02-24 14:11:49.021 MythSocket(b36436b8:25): socket is readable
8452008-02-24 14:11:49.021 MythSocket(b36436b8:25): cb->readyRead()
8462008-02-24 14:11:49.021 read <- 25 541 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
8472008-02-24 14:11:49.021 MythEvent: SIGNAL 2
8482008-02-24 14:11:49.073 MythSocket(b36436b8:25): socket is readable
8492008-02-24 14:11:49.073 MythSocket(b36436b8:25): cb->readyRead()
8502008-02-24 14:11:49.073 read <- 25 629 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
8512008-02-24 14:11:49.073 MythEvent: SIGNAL 2
8522008-02-24 14:11:49.124 MythSocket(b36436b8:25): socket is readable
8532008-02-24 14:11:49.124 MythSocket(b36436b8:25): cb->readyRead()
8542008-02-24 14:11:49.125 read <- 25 629 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
8552008-02-24 14:11:49.125 MythEvent: SIGNAL 2
8562008-02-24 14:11:49.175 MythSocket(b36436b8:25): socket is readable
8572008-02-24 14:11:49.175 MythSocket(b36436b8:25): cb->readyRead()
8582008-02-24 14:11:49.175 read <- 25 629 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
8592008-02-24 14:11:49.175 MythEvent: SIGNAL 2
8602008-02-24 14:11:49.227 MythSocket(b36436b8:25): socket is readable
8612008-02-24 14:11:49.227 MythSocket(b36436b8:25): cb->readyRead()
8622008-02-24 14:11:49.227 read <- 25 629 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
8632008-02-24 14:11:49.227 MythEvent: SIGNAL 2
8642008-02-24 14:11:49.233 MythSocket(b36436b8:25): socket is readable
8652008-02-24 14:11:49.233 MythSocket(b36436b8:25): cb->readyRead()
8662008-02-24 14:11:49.233 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
8672008-02-24 14:11:49.233 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
8682008-02-24 14:11:50.270 MythSocket(b36436b8:25): socket is readable
8692008-02-24 14:11:50.270 MythSocket(b36436b8:25): cb->readyRead()
8702008-02-24 14:11:50.270 read <- 25 53 BACKEND_MESSAGE[]:[]QUERY_NEXT_LIVETV_DIR 2[]:[]empty
8712008-02-24 14:11:50.270 MythEvent: QUERY_NEXT_LIVETV_DIR 2
8722008-02-24 14:11:50.270 MythSocket(b36436b8:25): socket is readable
8732008-02-24 14:11:50.270 MythSocket(b36436b8:25): cb->readyRead()
8742008-02-24 14:11:50.270 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
8752008-02-24 14:11:50.270 MythEvent: RECORDING_LIST_CHANGE
8762008-02-24 14:11:50.270 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
8772008-02-24 14:11:50.270 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
8782008-02-24 14:11:50.270 MythSocket(b36436b8:25): socket is readable
8792008-02-24 14:11:50.270 MythSocket(b36436b8:25): cb->readyRead()
8802008-02-24 14:11:50.270 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
8812008-02-24 14:11:50.270 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
8822008-02-24 14:11:55.372 RingBuf(/mnt/store/4621_20080224141147.mpg): Invalid file (fd -1) when opening '/mnt/store/4621_20080224141147.mpg'.
8832008-02-24 14:11:55.372 RingBuf(/mnt/store/4621_20080224141147.mpg): CalcReadAheadThresh(3050971124 KB)
884 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
8852008-02-24 14:11:55.372 write -> 30 33 QUERY_RECORDER 2[]:[]IS_RECORDING
8862008-02-24 14:11:55.372 read <- 30 1 1
8872008-02-24 14:11:55.372 write -> 30 33 QUERY_RECORDER 2[]:[]IS_RECORDING
8882008-02-24 14:11:55.372 read <- 30 1 1
8892008-02-24 14:11:55.372 TV: StartRecorder(): took 0 ms to start recorder.
8902008-02-24 14:11:55.372 write -> 30 34 QUERY_RECORDER 2[]:[]GET_FRAMERATE
8912008-02-24 14:11:55.372 read <- 30 5 29.97
8922008-02-24 14:11:55.374 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group!
8932008-02-24 14:11:55.375 SG(Default): FindRecordingFile: Searching for '4621_20080224141147.mpg'
8942008-02-24 14:11:55.375 SG(Default): FindRecordingDir: Checking '/mnt/store'
8952008-02-24 14:11:55.375 SG(Default): FindRecordingFile: Found '/mnt/store/4621_20080224141147.mpg'
8962008-02-24 14:11:55.375 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/4621_20080224141147.mpg'
8972008-02-24 14:11:55.375 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
8982008-02-24 14:11:55.375 read <- 27 2 OK
8992008-02-24 14:11:55.375 MythSocket(b36436b8:25): socket is readable
9002008-02-24 14:11:55.375 MythSocket(b36436b8:25): cb->readyRead()
9012008-02-24 14:11:55.376 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
9022008-02-24 14:11:55.376 MythEvent: RECORDING_LIST_CHANGE
9032008-02-24 14:11:55.382 detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
9042008-02-24 14:11:55.382 NVP: Disabling Audio, params(-1,2,44100)
9052008-02-24 14:11:55.382 VideoOutput: Allowed renderers: opengl,xv-blit,xshm,xlib
9062008-02-24 14:11:55.382 VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl
9072008-02-24 14:11:55.384 VDP: Accepting: cmp(> 0 0) dec(libmpeg2) cpus(1) rend(opengl) osd(opengl2) osdfade(enabled) deint(greedyhdeint,openglkerneldeint) filt()
9082008-02-24 14:11:55.384 VDP: LoadBestPreferences(2048x2048, 0)
9092008-02-24 14:11:55.384 VDP: LoadBestPreferences(2048x2048, 60)
9102008-02-24 14:11:55.384 VDP: LoadBestPreferences(720x576, 60)
9112008-02-24 14:11:55.384 VideoOutput: Preferred renderer: opengl
9122008-02-24 14:11:55.384 VideoOutput: Trying video renderer: opengl
9132008-02-24 14:11:55.385 VDP: Accepting: cmp(> 0 0) dec(libmpeg2) cpus(1) rend(opengl) osd(opengl2) osdfade(enabled) deint(greedyhdeint,openglkerneldeint) filt()
9142008-02-24 14:11:55.385 VDP: LoadBestPreferences(2048x2048, 0)
9152008-02-24 14:11:55.385 VDP: LoadBestPreferences(2048x2048, 60)
9162008-02-24 14:11:55.390 VideoOutputXv: ctor
9172008-02-24 14:11:55.392 XOff: 0, YOff: 0
9182008-02-24 14:11:55.392 VDP: LoadBestPreferences(720x576, 60)
9192008-02-24 14:11:55.392 Display Rect left: 0, top: 0, width: 848, height: 480, aspect: 1.33333
9202008-02-24 14:11:55.392 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
9212008-02-24 14:11:55.394 VideoOutputXv: Pixel dimensions: Screen 848x480, window 848x480
9222008-02-24 14:11:55.394 VideoOutputXv: Estimated display dimensions: 287x163 mm Aspect: 1.76074
9232008-02-24 14:11:55.394 VideoOutputXv: Estimated window dimensions: 287x163 mm Aspect: 1.76074
9242008-02-24 14:11:55.394 VideoOutputXv: InitSetupBuffers() render: opengl, allowed: opengl,xv-blit,xshm,xlib
9252008-02-24 14:11:55.396 GLCtx: Created window and context.
9262008-02-24 14:11:55.416 GLVid: Viewport: 848x576
9272008-02-24 14:11:55.417 GLVid: Created main input texture 360x288
9282008-02-24 14:11:55.418 GLVid: Created main input texture 360x288
9292008-02-24 14:11:55.422 GLVid: Created main input texture 720x576
9302008-02-24 14:11:55.422 GLVid: Creating master filter.
9312008-02-24 14:11:55.422 GLVid: Created fragment program master.
9322008-02-24 14:11:55.422 GLVid: Creating resize filter.
9332008-02-24 14:11:55.428 GLCtx: Created frame buffer object (720x576).
9342008-02-24 14:11:55.428 GLVid: Turning off deinterlacing.
9352008-02-24 14:11:55.428 GLVid: Turning off deinterlacing.
9362008-02-24 14:11:55.429 Created data @0xaa500030->0xaa597e32
9372008-02-24 14:11:55.429 Created data @0xacd00030->0xacd97e32
9382008-02-24 14:11:55.429 Created data @0xad000030->0xad097e32
9392008-02-24 14:11:55.429 Created data @0xad100030->0xad197e32
9402008-02-24 14:11:55.429 Created data @0xacf00030->0xacf97e32
9412008-02-24 14:11:55.429 Created data @0xacb00030->0xacb97e32
9422008-02-24 14:11:55.429 Created data @0xad300030->0xad397e32
9432008-02-24 14:11:55.429 Created data @0xad900030->0xad997e32
9442008-02-24 14:11:55.429 Created data @0xad800030->0xad897e32
9452008-02-24 14:11:55.429 Created data @0xad400030->0xad497e32
9462008-02-24 14:11:55.429 Created data @0xad500030->0xad597e32
9472008-02-24 14:11:55.429 Created data @0xad200030->0xad297e32
9482008-02-24 14:11:55.429 Created data @0xace00030->0xace97e32
9492008-02-24 14:11:55.429 Created data @0xad600030->0xad697e32
9502008-02-24 14:11:55.429 Created data @0xad700030->0xad797e32
9512008-02-24 14:11:55.429 Created data @0xacc00030->0xacc97e32
9522008-02-24 14:11:55.429 Created data @0xaca00030->0xaca97e32
9532008-02-24 14:11:55.429 Created data @0xa9300030->0xa9397e32
9542008-02-24 14:11:55.429 Created data @0xaa200030->0xaa297e32
9552008-02-24 14:11:55.429 Created data @0xab700030->0xab797e32
9562008-02-24 14:11:55.429 Created data @0xab900030->0xab997e32
9572008-02-24 14:11:55.429 Created data @0xaa800030->0xaa897e32
9582008-02-24 14:11:55.429 Created data @0xaaa00030->0xaaa97e32
9592008-02-24 14:11:55.429 Created data @0xaaf00030->0xaaf97e32
9602008-02-24 14:11:55.429 Created data @0xab200030->0xab297e32
9612008-02-24 14:11:55.430 Created data @0xab500030->0xab597e32
9622008-02-24 14:11:55.430 Created data @0xae121020->0xae1b8e22
9632008-02-24 14:11:55.430 Created data @0xac868020->0xac8ffe22
9642008-02-24 14:11:55.430 Created data @0xac7d0020->0xac867e22
9652008-02-24 14:11:55.430 Created data @0xac738020->0xac7cfe22
9662008-02-24 14:11:55.430 Created data @0xac6a0020->0xac737e22
9672008-02-24 14:11:55.430 Created data @0xac608020->0xac69fe22
9682008-02-24 14:11:55.441 VDP: GetFilteredDeint() : -> 'greedyhdeint'
969greedyhdeint: size changed from 0 x 0 -> 720 x 576
9702008-02-24 14:11:55.443 Using deinterlace method greedyhdeint
9712008-02-24 14:11:55.443 VDP: SetVideoRenderer(opengl)
9722008-02-24 14:11:55.443 VDP: SetVideoRender(opengl) == GetVideoRenderer()
9732008-02-24 14:11:55.924 GLVid: Created main input texture 848x480
9742008-02-24 14:11:55.925 GLVid: Created main input texture 424x240
9752008-02-24 14:11:55.926 GLVid: Created main input texture 424x240
9762008-02-24 14:11:55.930 GLVid: Created main input texture 848x480
9772008-02-24 14:11:55.930 GLVid: Creating osd filter.
9782008-02-24 14:11:55.930 GLVid: Created fragment program osd.
9792008-02-24 14:11:55.930 GLVid: Turning off deinterlacing.
9802008-02-24 14:11:55.930 GLVid: Creating resize filter.
9812008-02-24 14:11:55.934 GLCtx: Created frame buffer object (848x480).
9822008-02-24 14:11:55.934 GLVid: Turning off deinterlacing.
9832008-02-24 14:11:55.935 Display Rect left: 106, top: 0, width: 636, height: 480, aspect: 1.77778
9842008-02-24 14:11:55.935 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
9852008-02-24 14:11:55.936 Over/underscan. V: 0.03, H: 0
9862008-02-24 14:11:55.936 Display Rect left: 106, top: 0, width: 636, height: 480, aspect: 1.77778
9872008-02-24 14:11:55.936 Video Rect left: 0, top: 17, width: 720, height: 541, aspect: 1.33333
9882008-02-24 14:11:55.936 VDP: LoadBestPreferences(720x576, 25)
9892008-02-24 14:11:55.936 NVP: LoadFilters(''..) -> 0
9902008-02-24 14:11:55.937 OSD Theme Dimensions W: 640 H: 480
9912008-02-24 14:11:55.968 OSDTypePositionImage::AddPosition[0](114x233 1, 1)
9922008-02-24 14:11:55.968 OSDTypePositionImage::AddPosition[1](114x263 1, 1)
9932008-02-24 14:11:55.968 OSDTypePositionImage::AddPosition[2](114x293 1, 1)
9942008-02-24 14:11:55.968 OSDTypePositionImage::AddPosition[3](114x323 1, 1)
9952008-02-24 14:11:55.968 OSDTypePositionImage::AddPosition[4](114x353 1, 1)
9962008-02-24 14:11:55.972 OSD: InitCC708() -- begin
9972008-02-24 14:11:56.028 OSD: InitCC708() -- end
9982008-02-24 14:11:56.120 NVP: ClearAfterSeek(1)
9992008-02-24 14:11:56.120 VideoOutputXv: ClearAfterSeek()
10002008-02-24 14:11:56.120 VideoOutputXv: DiscardFrames(0)
10012008-02-24 14:11:56.120 TV: StartPlayer(): took 738 ms to start player.
10022008-02-24 14:11:56.120 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
10032008-02-24 14:11:56.120 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
10042008-02-24 14:11:56.120 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
10052008-02-24 14:11:56.122 TV: UnpauseLiveTV()
10062008-02-24 14:11:56.122 New DB connection, total: 4
10072008-02-24 14:11:56.122 Using realtime priority.
10082008-02-24 14:11:56.122 VDP: GetFilteredDeint() : opengl -> 'greedyhdeint'
10092008-02-24 14:11:56.123 Connected to database 'mythconverg' at host: localhost
10102008-02-24 14:11:56.123 rate: 25 speed: 1 skip: 1 = interval 40000
10112008-02-24 14:11:56.123 VDP: GetFilteredDeint() : opengl -> 'greedyhdeint'
10122008-02-24 14:11:56.123 LiveTVChain(live-mythtv-2008-02-24T14:11:34): ReloadAll(): Added new recording
1013greedyhdeint: size changed from 0 x 0 -> 720 x 576
10142008-02-24 14:11:56.124 Using deinterlace method greedyhdeint
10152008-02-24 14:11:56.125 LiveTVChain(live-mythtv-2008-02-24T14:11:34): SwitchTo(-1)
10162008-02-24 14:11:56.125 LiveTVChain(live-mythtv-2008-02-24T14:11:34): Entry@3: '4621_20080224141149'
10172008-02-24 14:11:56.125 NVP: Play( 1.0, normal 1, unpause audio 0)
10182008-02-24 14:11:56.125 LiveTVChain(live-mythtv-2008-02-24T14:11:34): SwitchTo(3)
10192008-02-24 14:11:56.125 LiveTVChain(live-mythtv-2008-02-24T14:11:34): Entry@3: '4621_20080224141149'
10202008-02-24 14:11:56.125 JumpToProgram(void)
10212008-02-24 14:11:56.127 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
10222008-02-24 14:11:56.127 read <- 27 2 OK
10232008-02-24 14:11:56.127 MythSocket(b36436b8:25): socket is readable
10242008-02-24 14:11:56.127 MythSocket(b36436b8:25): cb->readyRead()
10252008-02-24 14:11:56.127 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
10262008-02-24 14:11:56.127 MythEvent: RECORDING_LIST_CHANGE
10272008-02-24 14:11:56.128 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group!
10282008-02-24 14:11:56.128 SG(Default): FindRecordingFile: Searching for '4621_20080224141149.mpg'
10292008-02-24 14:11:56.128 SG(Default): FindRecordingDir: Checking '/mnt/store'
10302008-02-24 14:11:56.128 SG(Default): FindRecordingFile: Found '/mnt/store/4621_20080224141149.mpg'
10312008-02-24 14:11:56.128 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/4621_20080224141149.mpg'
10322008-02-24 14:11:56.129 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
10332008-02-24 14:11:56.129 read <- 27 2 OK
10342008-02-24 14:11:56.129 MythSocket(b36436b8:25): socket is readable
10352008-02-24 14:11:56.129 MythSocket(b36436b8:25): cb->readyRead()
10362008-02-24 14:11:56.129 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
10372008-02-24 14:11:56.129 MythEvent: RECORDING_LIST_CHANGE
10382008-02-24 14:11:56.129 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group!
10392008-02-24 14:11:56.130 SG(Default): FindRecordingFile: Searching for '4621_20080224141149.mpg'
10402008-02-24 14:11:56.130 SG(Default): FindRecordingDir: Checking '/mnt/store'
10412008-02-24 14:11:56.130 SG(Default): FindRecordingFile: Found '/mnt/store/4621_20080224141149.mpg'
10422008-02-24 14:11:56.130 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/4621_20080224141149.mpg'
10432008-02-24 14:11:56.130 RingBuf(/mnt/store/4621_20080224141147.mpg): OpenFile(/mnt/store/4621_20080224141149.mpg, 12)
10442008-02-24 14:11:56.130 RingBuf(/mnt/store/4621_20080224141149.mpg): CalcReadAheadThresh(3050971124 KB)
1045 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
1046nVidiaVideoSync::: Interrupted system call
10472008-02-24 14:11:56.131 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver?
10482008-02-24 14:11:56.131 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
10492008-02-24 14:11:56.131 OpenGLVideoSync()
10502008-02-24 14:11:56.131 OpenGLVideoSync: x,y -> 424, 240
10512008-02-24 14:11:56.134 write -> 30 30 QUERY_RECORDER 2[]:[]GET_INPUT
10522008-02-24 14:11:56.134 read <- 30 8 DVBInput
10532008-02-24 14:11:56.139 mpegts_add_stream: at pid 0x31 with type 2
10542008-02-24 14:11:56.140 mpegts_add_stream: stream #0, has id 0x31 and codec MPEG2VIDEO, type Video at 0xb2836cf0
10552008-02-24 14:11:56.140 mpegts_add_stream: at pid 0x34 with type 129
10562008-02-24 14:11:56.140 mpegts_add_stream: stream #1, has id 0x34 and codec AC3, type Audio at 0xb2836fa0
10572008-02-24 14:11:56.155 Using OpenGLVideoSync
10582008-02-24 14:11:56.161 Using audio as timebase
10592008-02-24 14:11:56.161 Video timing method: SGI OpenGL
10602008-02-24 14:11:56.161 Refresh rate: 16666, frame interval: 40000
10612008-02-24 14:11:56.172 AFD: Stream #0, has id 0x49 codec id MPEG2VIDEO, type Video, bitrate 18900000 at 0x0xb2836cf0
10622008-02-24 14:11:56.173 VDP: Accepting: cmp(> 0 0) dec(libmpeg2) cpus(1) rend(opengl) osd(opengl2) osdfade(enabled) deint(greedyhdeint,openglkerneldeint) filt()
10632008-02-24 14:11:56.173 VDP: LoadBestPreferences(2048x2048, 0)
10642008-02-24 14:11:56.173 VDP: LoadBestPreferences(2048x2048, 60)
10652008-02-24 14:11:56.173 VDP: LoadBestPreferences(1920x1080, 60)
10662008-02-24 14:11:56.173 Using 1 CPUs for decoding
1067QString::setNum: Invalid format char ''
10682008-02-24 14:11:56.173 AFD: Trying DirectShow for FOURCC 0x845639789
10692008-02-24 14:11:56.173 AFD: Using libmpeg2 for video decoding
10702008-02-24 14:11:56.173 AFD: InitVideoCodec() 0xb2837300 id(MPEG2VIDEO) type (Video).
10712008-02-24 14:11:56.173 VideoOutputXv: InputChanged(1920,1088,1.77778) 'None'->'MPEG2'
10722008-02-24 14:11:56.173 VDP: LoadBestPreferences(1920x1088, 25)
10732008-02-24 14:11:56.173 VideoOutputXv: DiscardFrames(1)
10742008-02-24 14:11:56.173 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
10752008-02-24 14:11:56.173 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
10762008-02-24 14:11:56.174 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
10772008-02-24 14:11:56.174 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
10782008-02-24 14:11:56.174 VideoOutputXv: DiscardFrames(1)
10792008-02-24 14:11:56.174 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
10802008-02-24 14:11:56.174 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
10812008-02-24 14:11:56.174 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
10822008-02-24 14:11:56.174 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
10832008-02-24 14:11:56.178 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
10842008-02-24 14:11:56.183 VideoOutputXv: InitSetupBuffers() render: opengl, allowed: opengl,xv-blit,xshm,xlib
10852008-02-24 14:11:56.188 GLVid: Viewport: 1920x1088
10862008-02-24 14:11:56.192 GLVid: Created main input texture 960x544
10872008-02-24 14:11:56.197 GLVid: Created main input texture 960x544
10882008-02-24 14:11:56.218 GLVid: Created main input texture 1920x1088
10892008-02-24 14:11:56.218 GLVid: Creating master filter.
10902008-02-24 14:11:56.218 GLVid: Created fragment program master.
10912008-02-24 14:11:56.218 GLVid: Creating resize filter.
10922008-02-24 14:11:56.231 GLCtx: Created frame buffer object (1920x1088).
10932008-02-24 14:11:56.231 GLVid: Turning off deinterlacing.
10942008-02-24 14:11:56.231 GLVid: Turning off deinterlacing.
10952008-02-24 14:11:56.231 Created data @0xabe8d020->0xac18a022
10962008-02-24 14:11:56.231 Created data @0xa62e5020->0xa65e2022
10972008-02-24 14:11:56.231 Created data @0xa5fe7020->0xa62e4022
10982008-02-24 14:11:56.232 Created data @0xa5ce9020->0xa5fe6022
10992008-02-24 14:11:56.232 Created data @0xa59eb020->0xa5ce8022
11002008-02-24 14:11:56.232 Created data @0xa56ed020->0xa59ea022
11012008-02-24 14:11:56.232 Created data @0xa53ef020->0xa56ec022
11022008-02-24 14:11:56.232 Created data @0xa50f1020->0xa53ee022
11032008-02-24 14:11:56.232 Created data @0xa4df3020->0xa50f0022
11042008-02-24 14:11:56.232 Created data @0xa4af5020->0xa4df2022
11052008-02-24 14:11:56.232 Created data @0xa47f7020->0xa4af4022
11062008-02-24 14:11:56.232 Created data @0xa44f9020->0xa47f6022
11072008-02-24 14:11:56.232 Created data @0xa41fb020->0xa44f8022
11082008-02-24 14:11:56.232 Created data @0xa3efd020->0xa41fa022
11092008-02-24 14:11:56.232 Created data @0xa3bff020->0xa3efc022
11102008-02-24 14:11:56.232 Created data @0xa3901020->0xa3bfe022
11112008-02-24 14:11:56.232 Created data @0xa3603020->0xa3900022
11122008-02-24 14:11:56.232 Created data @0xa3305020->0xa3602022
11132008-02-24 14:11:56.232 Created data @0xa3007020->0xa3304022
11142008-02-24 14:11:56.232 Created data @0xa2d09020->0xa3006022
11152008-02-24 14:11:56.232 Created data @0xa2a0b020->0xa2d08022
11162008-02-24 14:11:56.232 Created data @0xa270d020->0xa2a0a022
11172008-02-24 14:11:56.232 Created data @0xa240f020->0xa270c022
11182008-02-24 14:11:56.232 Created data @0xa2111020->0xa240e022
11192008-02-24 14:11:56.232 Created data @0xa1e13020->0xa2110022
11202008-02-24 14:11:56.232 Created data @0xa1b15020->0xa1e12022
11212008-02-24 14:11:56.232 Created data @0xa1817020->0xa1b14022
11222008-02-24 14:11:56.232 Created data @0xa1519020->0xa1816022
11232008-02-24 14:11:56.232 Created data @0xa121b020->0xa1518022
11242008-02-24 14:11:56.232 Created data @0xa0f1d020->0xa121a022
11252008-02-24 14:11:56.232 Created data @0xa0c1f020->0xa0f1c022
11262008-02-24 14:11:56.232 Created data @0xa0921020->0xa0c1e022
11272008-02-24 14:11:56.312 GLVid: Turning on deinterlacing.
11282008-02-24 14:11:56.328 VDP: SetVideoRenderer(opengl)
11292008-02-24 14:11:56.328 VDP: SetVideoRender(opengl) == GetVideoRenderer()
11302008-02-24 14:11:56.331 GLVid: Created main input texture 848x480
11312008-02-24 14:11:56.333 GLVid: Created main input texture 424x240
11322008-02-24 14:11:56.334 GLVid: Created main input texture 424x240
11332008-02-24 14:11:56.336 GLVid: Created main input texture 848x480
11342008-02-24 14:11:56.337 GLVid: Creating osd filter.
11352008-02-24 14:11:56.337 GLVid: Created fragment program osd.
11362008-02-24 14:11:56.337 GLVid: Turning off deinterlacing.
11372008-02-24 14:11:56.337 GLVid: Creating resize filter.
11382008-02-24 14:11:56.341 GLCtx: Created frame buffer object (848x480).
11392008-02-24 14:11:56.341 GLVid: Turning off deinterlacing.
11402008-02-24 14:11:56.342 Display Rect left: 0, top: 0, width: 848, height: 480, aspect: 1.77778
11412008-02-24 14:11:56.342 Video Rect left: 0, top: 32, width: 1920, height: 1015, aspect: 1.77778
11422008-02-24 14:11:56.342 VDP: LoadBestPreferences(1920x1088, 29.97)
11432008-02-24 14:11:56.362 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
11442008-02-24 14:11:56.434 NVP: ClearAfterSeek(1)
11452008-02-24 14:11:56.434 VideoOutputXv: ClearAfterSeek()
11462008-02-24 14:11:56.434 VideoOutputXv: DiscardFrames(0)
11472008-02-24 14:11:56.434 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
11482008-02-24 14:11:56.434 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
11492008-02-24 14:11:56.434 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
1150greedyhdeint: size changed from 720 x 576 -> 1920 x 1088
11512008-02-24 14:11:56.482 NVP: LoadFilters(''..) -> 0
11522008-02-24 14:11:56.482 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 1088) ->Interlaced Scan
11532008-02-24 14:11:56.482 AFD: Using libmpeg2 for video decoding
11542008-02-24 14:11:56.482 AFD: Looking for decoder for MPEG2VIDEO
11552008-02-24 14:11:56.482 AFD: Opened codec 0xb2837300, id(MPEG2VIDEO) type(Video)
11562008-02-24 14:11:56.482 AFD: Stream #1, has id 0x52 codec id AC3, type Audio, bitrate 384000 at 0x0xb2836fa0
11572008-02-24 14:11:56.482 AFD: codec AC3 has 6 channels
11582008-02-24 14:11:56.482 AFD: Looking for decoder for AC3
11592008-02-24 14:11:56.483 AFD: Opened codec 0xb2837670, id(AC3) type(Audio)
11602008-02-24 14:11:56.483 AFD: Audio Track #1 is A/V stream #1 and has 6 channels in the English language(6647399).
11612008-02-24 14:11:56.483 RingBuf(/mnt/store/4621_20080224141149.mpg): CalcReadAheadThresh(2948999504 KB)
1162 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
11632008-02-24 14:11:56.483 AFD: Selected track 1: English AC3 5.1ch (A/V Stream #1)
11642008-02-24 14:11:56.483 AFD: Initializing audio parms from audio track #1
11652008-02-24 14:11:56.483 AFD: Audio format changed
1166 from id(NONE) -1Hz -1ch 0bps ; id(NONE) -1Hz -1ch 0bps
1167 to id( AC3) 48000Hz 6ch 16bps ; id( AC3) 48000Hz 2ch 16bps
11682008-02-24 14:11:56.485 AO: Killing AudioOutputDSP
11692008-02-24 14:11:56.485 Opening audio device 'default'. ch 2(2) sr 48000
11702008-02-24 14:11:56.485 Opening ALSA audio device 'default'.
11712008-02-24 14:11:56.494 in SetParameters(format=2, channels=2, rate=48000, buffer_time=100000, period_time=25000)
11722008-02-24 14:11:56.495 get_buffer_size returned 5120
11732008-02-24 14:11:56.495 set_period_time_near returned 21333
11742008-02-24 14:11:56.495 get_period_size returned 1024
11752008-02-24 14:11:56.496 Opening mixer default
11762008-02-24 14:11:56.496 Setting Master volume to 70
11772008-02-24 14:11:56.496 Mixer unable to find control Master
11782008-02-24 14:11:56.496 Setting Master volume to 70
11792008-02-24 14:11:56.496 Mixer unable to find control Master
11802008-02-24 14:11:56.497 Setting PCM volume to 100
11812008-02-24 14:11:56.497 Volume range is 0 to 255, mult=0.392157
11822008-02-24 14:11:56.497 channel 0 vol set to 255
11832008-02-24 14:11:56.497 Setting PCM volume to 100
11842008-02-24 14:11:56.497 Volume range is 0 to 255, mult=0.392157
11852008-02-24 14:11:56.497 channel 1 vol set to 255
11862008-02-24 14:11:56.497 Volume range is 0 to 255, mult=0.392157
11872008-02-24 14:11:56.497 AO: Audio fragment size: 6400
11882008-02-24 14:11:56.497 AO: Audio Stretch Factor: 1
11892008-02-24 14:11:56.497 Audio Codec Used: not set
11902008-02-24 14:11:56.498 AO: Ending reconfigure
11912008-02-24 14:11:56.499 NVP: Enabling Audio
11922008-02-24 14:11:56.498 AO: kickoffOutputAudioLoop: pid = 907
11932008-02-24 14:11:56.499 AO: no change exiting
11942008-02-24 14:11:56.499 AO: OutputAudioLoop: Play Event
11952008-02-24 14:11:56.499 Dec: Trying to select track (w/lang)
11962008-02-24 14:11:56.499 Dec: Selecting first track
11972008-02-24 14:11:56.499 Dec: Selected track #1 in the Unknown language(0)
11982008-02-24 14:11:56.499 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
11992008-02-24 14:11:56.499 Position map filled from DB to: 75
12002008-02-24 14:11:56.499 SyncPositionMap watchingrecording, from DB: 6 entries
12012008-02-24 14:11:56.499 write -> 30 39 QUERY_RECORDER 2[]:[]GET_FRAMES_WRITTEN
12022008-02-24 14:11:56.499 read <- 30 9 0[]:[]178
12032008-02-24 14:11:56.499 Filling position map from 76 to 178
12042008-02-24 14:11:56.500 write -> 30 53 QUERY_RECORDER 2[]:[]FILL_POSITION_MAP[]:[]76[]:[]178
12052008-02-24 14:11:56.500 read <- 30 189 0[]:[]90[]:[]0[]:[]7078764[]:[]0[]:[]105[]:[]0[]:[]8286476[]:[]0[...
12062008-02-24 14:11:56.500 Position map filled from Encoder to: 165
12072008-02-24 14:11:56.500 SyncPositionMap watchingrecording total: 12 entries
12082008-02-24 14:11:56.500 SyncPositionMap, new totframes: 165, new length: 5, posMap size: 12
12092008-02-24 14:11:56.500 Input #0, mpegts, from '/mnt/store/4621_20080224141149.mpg':
12102008-02-24 14:11:56.500 Duration: N/A, start: 73955.032089, bitrate: 19284 kb/s
12112008-02-24 14:11:56.500 Stream #0.0[0x31], 1/90000: Video: mpeg2video, yuv420p, 1920x1080 [PAR 1:1 DAR 16:9], 1001/30000, 18900 kb/s, 29.97 tb(r)
12122008-02-24 14:11:56.500 Stream #0.1[0x34](eng), 1/90000: Audio: ac3, 48000 Hz, stereo, 384 kb/s
12132008-02-24 14:11:56.500 AFD: Partial position map found
12142008-02-24 14:11:56.501 AFD: Successfully opened decoder for file: "/mnt/store/4621_20080224141149.mpg". novideo(0)
12152008-02-24 14:11:56.501 NVP: DoPlay: rate: 29.97 speed: 1 skip: 1 => new interval 33366
12162008-02-24 14:11:56.501 Set video sync frame interval to 33366
12172008-02-24 14:11:56.501 NVP: Stretch Factor 1, allow passthru
12182008-02-24 14:11:56.501 RingBuf(/mnt/store/4621_20080224141149.mpg): CalcReadAheadThresh(0 KB)
1219 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
12202008-02-24 14:11:56.501 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
12212008-02-24 14:11:56.502 Position map filled from DB to: 75
12222008-02-24 14:11:56.502 SyncPositionMap watchingrecording, from DB: 6 entries
12232008-02-24 14:11:56.502 write -> 30 39 QUERY_RECORDER 2[]:[]GET_FRAMES_WRITTEN
12242008-02-24 14:11:56.502 read <- 30 9 0[]:[]178
12252008-02-24 14:11:56.502 Filling position map from 76 to 178
12262008-02-24 14:11:56.502 write -> 30 53 QUERY_RECORDER 2[]:[]FILL_POSITION_MAP[]:[]76[]:[]178
12272008-02-24 14:11:56.502 read <- 30 189 0[]:[]90[]:[]0[]:[]7078764[]:[]0[]:[]105[]:[]0[]:[]8286476[]:[]0[...
12282008-02-24 14:11:56.502 Position map filled from Encoder to: 165
12292008-02-24 14:11:56.502 SyncPositionMap watchingrecording total: 12 entries
12302008-02-24 14:11:56.544 AO: Pause 1
12312008-02-24 14:11:56.544 NVP: Waiting for prebuffer.. 1 LLLAAAAAAAAAAAAAAAAAAAAAAAAAAAA
12322008-02-24 14:11:56.547 AO: OutputAudioLoop: audio paused
12332008-02-24 14:11:56.694 NVP: Waiting for prebuffer.. 2 AAUUUuUULULAAAAAAAAAAAAAAAAAAAA
12342008-02-24 14:11:56.825 AO: Pause 0
12352008-02-24 14:11:56.828 AO: OutputAudioLoop: Play Event
12362008-02-24 14:11:57.815 NVP: Video is 3.04142 frames behind audio (too slow), dropping frame to catch up.
12372008-02-24 14:11:57.846 NVP: Video is 3.15771 frames behind audio (too slow), dropping frame to catch up.
12382008-02-24 14:11:57.877 NVP: Video is 3.22244 frames behind audio (too slow), dropping frame to catch up.
12392008-02-24 14:11:57.907 NVP: Video is 3.24852 frames behind audio (too slow), dropping frame to catch up.
12402008-02-24 14:11:57.937 NVP: Video is 3.25307 frames behind audio (too slow), dropping frame to catch up.
12412008-02-24 14:11:57.965 NVP: Video is 3.22652 frames behind audio (too slow), dropping frame to catch up.
12422008-02-24 14:11:57.996 NVP: Video is 3.16915 frames behind audio (too slow), dropping frame to catch up.
12432008-02-24 14:11:58.025 NVP: Video is 3.10364 frames behind audio (too slow), dropping frame to catch up.
12442008-02-24 14:11:58.054 NVP: Video is 3.01705 frames behind audio (too slow), dropping frame to catch up.
12452008-02-24 14:11:58.241 NVP: Video is 3.06492 frames behind audio (too slow), dropping frame to catch up.
12462008-02-24 14:11:58.271 NVP: Video is 3.11539 frames behind audio (too slow), dropping frame to catch up.
12472008-02-24 14:11:58.302 NVP: Video is 3.12327 frames behind audio (too slow), dropping frame to catch up.
12482008-02-24 14:11:58.334 NVP: Video is 3.1067 frames behind audio (too slow), dropping frame to catch up.
12492008-02-24 14:11:58.365 NVP: Video is 3.08677 frames behind audio (too slow), dropping frame to catch up.
12502008-02-24 14:11:58.396 NVP: Video is 3.04933 frames behind audio (too slow), dropping frame to catch up.
12512008-02-24 14:11:58.426 NVP: Video is 3.00629 frames behind audio (too slow), dropping frame to catch up.
12522008-02-24 14:11:58.573 NVP: Video is 3.07747 frames behind audio (too slow), dropping frame to catch up.
12532008-02-24 14:11:58.603 NVP: Video is 3.10232 frames behind audio (too slow), dropping frame to catch up.
12542008-02-24 14:11:58.632 NVP: Video is 3.09848 frames behind audio (too slow), dropping frame to catch up.
12552008-02-24 14:11:58.662 NVP: Video is 3.07313 frames behind audio (too slow), dropping frame to catch up.
12562008-02-24 14:11:58.692 NVP: Video is 3.01663 frames behind audio (too slow), dropping frame to catch up.
12572008-02-24 14:11:58.838 NVP: Video is 3.04397 frames behind audio (too slow), dropping frame to catch up.
12582008-02-24 14:11:58.869 NVP: Video is 3.06968 frames behind audio (too slow), dropping frame to catch up.
12592008-02-24 14:11:58.901 NVP: Video is 3.074 frames behind audio (too slow), dropping frame to catch up.
12602008-02-24 14:11:58.931 NVP: Video is 3.06225 frames behind audio (too slow), dropping frame to catch up.
12612008-02-24 14:11:58.962 NVP: Video is 3.03096 frames behind audio (too slow), dropping frame to catch up.
12622008-02-24 14:11:59.034 NVP: Video is 3.03111 frames behind audio (too slow), dropping frame to catch up.
12632008-02-24 14:11:59.064 NVP: Video is 3.03009 frames behind audio (too slow), dropping frame to catch up.
12642008-02-24 14:11:59.095 NVP: Video is 3.00683 frames behind audio (too slow), dropping frame to catch up.
12652008-02-24 14:11:59.203 NVP: Video is 3.03396 frames behind audio (too slow), dropping frame to catch up.
12662008-02-24 14:11:59.233 NVP: Video is 3.0472 frames behind audio (too slow), dropping frame to catch up.
12672008-02-24 14:11:59.263 NVP: Video is 3.03465 frames behind audio (too slow), dropping frame to catch up.
12682008-02-24 14:11:59.332 NVP: Video is 3.0107 frames behind audio (too slow), dropping frame to catch up.
12692008-02-24 14:11:59.403 NVP: Video is 3.02907 frames behind audio (too slow), dropping frame to catch up.
12702008-02-24 14:11:59.434 NVP: Video is 3.04355 frames behind audio (too slow), dropping frame to catch up.
12712008-02-24 14:11:59.464 NVP: Video is 3.03941 frames behind audio (too slow), dropping frame to catch up.
12722008-02-24 14:11:59.494 NVP: Video is 3.00632 frames behind audio (too slow), dropping frame to catch up.
12732008-02-24 14:11:59.601 NVP: Video is 3.00563 frames behind audio (too slow), dropping frame to catch up.
12742008-02-24 14:11:59.631 NVP: Video is 3.01097 frames behind audio (too slow), dropping frame to catch up.
12752008-02-24 14:11:59.737 NVP: Video is 3.04834 frames behind audio (too slow), dropping frame to catch up.
12762008-02-24 14:11:59.766 NVP: Video is 3.05799 frames behind audio (too slow), dropping frame to catch up.
12772008-02-24 14:11:59.795 NVP: Video is 3.03525 frames behind audio (too slow), dropping frame to catch up.
12782008-02-24 14:11:59.903 NVP: Video is 3.04265 frames behind audio (too slow), dropping frame to catch up.
12792008-02-24 14:11:59.967 NVP: Video is 3.04621 frames behind audio (too slow), dropping frame to catch up.
12802008-02-24 14:11:59.973 XMLParse::LoadTheme using /usr/share/mythtv/themes/Titivillus/ui.xml
12812008-02-24 14:12:00.014 NVP: Video is 3.28118 frames behind audio (too slow), dropping frame to catch up.
12822008-02-24 14:12:00.054 NVP: Video is 3.5698 frames behind audio (too slow), dropping frame to catch up.
12832008-02-24 14:12:00.091 NVP: Video is 3.82371 frames behind audio (too slow), dropping frame to catch up.
12842008-02-24 14:12:00.127 NVP: Video is 4.03662 frames behind audio (too slow), dropping frame to catch up.
12852008-02-24 14:12:00.165 NVP: Video is 4.22628 frames behind audio (too slow), dropping frame to catch up.
12862008-02-24 14:12:00.196 NVP: Video is 4.391 frames behind audio (too slow), dropping frame to catch up.
12872008-02-24 14:12:00.227 NVP: Video is 4.50704 frames behind audio (too slow), dropping frame to catch up.
12882008-02-24 14:12:00.258 NVP: Video is 4.5716 frames behind audio (too slow), dropping frame to catch up.
12892008-02-24 14:12:00.289 NVP: Video is 4.59755 frames behind audio (too slow), dropping frame to catch up.
12902008-02-24 14:12:00.319 NVP: Video is 4.60951 frames behind audio (too slow), dropping frame to catch up.
12912008-02-24 14:12:00.350 NVP: Video is 4.60349 frames behind audio (too slow), dropping frame to catch up.
1292'video_output' mean = '35154.39', std. dev. = '5536.26', fps = '28.45'
12932008-02-24 14:12:00.381 NVP: Video is 4.56902 frames behind audio (too slow), dropping frame to catch up.
12942008-02-24 14:12:00.412 NVP: Video is 4.53567 frames behind audio (too slow), dropping frame to catch up.
12952008-02-24 14:12:00.445 NVP: Video is 4.48067 frames behind audio (too slow), dropping frame to catch up.
12962008-02-24 14:12:00.477 NVP: Video is 4.43943 frames behind audio (too slow), dropping frame to catch up.
12972008-02-24 14:12:00.490 write -> 27 19 QUERY_GETALLPENDING
12982008-02-24 14:12:00.491 read <- 27 7 0[]:[]0
12992008-02-24 14:12:00.548 NVP: Video is 4.40101 frames behind audio (too slow), dropping frame to catch up.
13002008-02-24 14:12:00.562 IsTunable(4562)
13012008-02-24 14:12:00.562 write -> 27 22 GET_FREE_RECORDER_LIST
13022008-02-24 14:12:00.564 read <- 27 13 1[]:[]5[]:[]7
13032008-02-24 14:12:00.564 write -> 27 42 QUERY_RECORDER 2[]:[]GET_FREE_INPUTS[]:[]2
13042008-02-24 14:12:00.566 read <- 27 32 DVBInput[]:[]4[]:[]4[]:[]2[]:[]0
13052008-02-24 14:12:00.566 IsTunable(4562) -> true
1306
13072008-02-24 14:12:00.566 IsTunable(4563)
13082008-02-24 14:12:00.567 write -> 27 22 GET_FREE_RECORDER_LIST
13092008-02-24 14:12:00.568 read <- 27 13 1[]:[]5[]:[]7
13102008-02-24 14:12:00.568 IsTunable(4563) -> true
1311
13122008-02-24 14:12:00.568 IsTunable(4621)
13132008-02-24 14:12:00.568 write -> 27 22 GET_FREE_RECORDER_LIST
13142008-02-24 14:12:00.569 read <- 27 13 1[]:[]5[]:[]7
13152008-02-24 14:12:00.569 IsTunable(4621) -> true
1316
13172008-02-24 14:12:00.569 IsTunable(2100)
13182008-02-24 14:12:00.570 write -> 27 22 GET_FREE_RECORDER_LIST
13192008-02-24 14:12:00.570 read <- 27 13 1[]:[]5[]:[]7
13202008-02-24 14:12:00.570 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]2
13212008-02-24 14:12:00.571 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]...
13222008-02-24 14:12:00.571 IsTunable(2100) -> true
1323
13242008-02-24 14:12:00.571 IsTunable(1101)
13252008-02-24 14:12:00.572 write -> 27 22 GET_FREE_RECORDER_LIST
13262008-02-24 14:12:00.572 read <- 27 13 1[]:[]5[]:[]7
13272008-02-24 14:12:00.573 IsTunable(1101) -> true
1328
13292008-02-24 14:12:00.603 NVP: Video is 4.64943 frames behind audio (too slow), dropping frame to catch up.
13302008-02-24 14:12:00.634 NVP: Video is 5.00057 frames behind audio (too slow), dropping frame to catch up.
13312008-02-24 14:12:00.663 NVP: Video is 5.24144 frames behind audio (too slow), dropping frame to catch up.
13322008-02-24 14:12:00.694 NVP: Video is 5.39214 frames behind audio (too slow), dropping frame to catch up.
13332008-02-24 14:12:00.724 NVP: Video is 5.49017 frames behind audio (too slow), dropping frame to catch up.
13342008-02-24 14:12:00.753 NVP: Video is 5.54121 frames behind audio (too slow), dropping frame to catch up.
13352008-02-24 14:12:00.784 NVP: Video is 5.54954 frames behind audio (too slow), dropping frame to catch up.
13362008-02-24 14:12:00.819 NVP: Video is 5.5333 frames behind audio (too slow), dropping frame to catch up.
13372008-02-24 14:12:00.848 NVP: Video is 5.52113 frames behind audio (too slow), dropping frame to catch up.
13382008-02-24 14:12:00.878 NVP: Video is 5.497 frames behind audio (too slow), dropping frame to catch up.
13392008-02-24 14:12:00.908 NVP: Video is 5.44893 frames behind audio (too slow), dropping frame to catch up.
13402008-02-24 14:12:00.939 NVP: Video is 5.3829 frames behind audio (too slow), dropping frame to catch up.
13412008-02-24 14:12:00.969 NVP: Video is 5.31092 frames behind audio (too slow), dropping frame to catch up.
13422008-02-24 14:12:01.000 NVP: Video is 5.24945 frames behind audio (too slow), dropping frame to catch up.
13432008-02-24 14:12:01.030 NVP: Video is 5.18084 frames behind audio (too slow), dropping frame to catch up.
13442008-02-24 14:12:01.060 NVP: Video is 5.09944 frames behind audio (too slow), dropping frame to catch up.
13452008-02-24 14:12:01.091 NVP: Video is 5.01591 frames behind audio (too slow), dropping frame to catch up.
13462008-02-24 14:12:01.119 NVP: Video is 4.93077 frames behind audio (too slow), dropping frame to catch up.
13472008-02-24 14:12:01.148 NVP: Video is 4.82947 frames behind audio (too slow), dropping frame to catch up.
13482008-02-24 14:12:01.177 NVP: Video is 4.71603 frames behind audio (too slow), dropping frame to catch up.
13492008-02-24 14:12:01.235 NVP: Video is 4.60846 frames behind audio (too slow), dropping frame to catch up.
13502008-02-24 14:12:01.268 NVP: Video is 4.70761 frames behind audio (too slow), dropping frame to catch up.
13512008-02-24 14:12:01.299 NVP: Video is 4.77447 frames behind audio (too slow), dropping frame to catch up.
13522008-02-24 14:12:01.331 NVP: Video is 4.80963 frames behind audio (too slow), dropping frame to catch up.
13532008-02-24 14:12:01.361 NVP: Video is 4.836 frames behind audio (too slow), dropping frame to catch up.
13542008-02-24 14:12:01.392 NVP: Video is 4.82581 frames behind audio (too slow), dropping frame to catch up.
13552008-02-24 14:12:01.421 NVP: Video is 4.79569 frames behind audio (too slow), dropping frame to catch up.
13562008-02-24 14:12:01.451 NVP: Video is 4.73563 frames behind audio (too slow), dropping frame to catch up.
13572008-02-24 14:12:01.482 NVP: Video is 4.66811 frames behind audio (too slow), dropping frame to catch up.
13582008-02-24 14:12:01.513 NVP: Video is 4.6025 frames behind audio (too slow), dropping frame to catch up.
13592008-02-24 14:12:01.545 NVP: Video is 4.53081 frames behind audio (too slow), dropping frame to catch up.
13602008-02-24 14:12:01.576 NVP: Video is 4.46955 frames behind audio (too slow), dropping frame to catch up.
13612008-02-24 14:12:01.608 NVP: Video is 4.40862 frames behind audio (too slow), dropping frame to catch up.
13622008-02-24 14:12:01.638 NVP: Video is 4.34793 frames behind audio (too slow), dropping frame to catch up.
13632008-02-24 14:12:01.668 NVP: Video is 4.27993 frames behind audio (too slow), dropping frame to catch up.
13642008-02-24 14:12:01.698 NVP: Video is 4.21396 frames behind audio (too slow), dropping frame to catch up.
13652008-02-24 14:12:01.743 NVP: Video is 4.12702 frames behind audio (too slow), dropping frame to catch up.
13662008-02-24 14:12:01.773 NVP: Video is 4.15171 frames behind audio (too slow), dropping frame to catch up.
13672008-02-24 14:12:01.804 NVP: Video is 4.14776 frames behind audio (too slow), dropping frame to catch up.
13682008-02-24 14:12:01.835 NVP: Video is 4.1298 frames behind audio (too slow), dropping frame to catch up.
13692008-02-24 14:12:01.867 NVP: Video is 4.10136 frames behind audio (too slow), dropping frame to catch up.
13702008-02-24 14:12:01.898 NVP: Video is 4.05754 frames behind audio (too slow), dropping frame to catch up.
13712008-02-24 14:12:01.930 NVP: Video is 4.00971 frames behind audio (too slow), dropping frame to catch up.
13722008-02-24 14:12:01.963 NVP: Video is 3.97384 frames behind audio (too slow), dropping frame to catch up.
13732008-02-24 14:12:01.992 NVP: Video is 3.93194 frames behind audio (too slow), dropping frame to catch up.
13742008-02-24 14:12:02.024 NVP: Video is 3.87053 frames behind audio (too slow), dropping frame to catch up.
13752008-02-24 14:12:02.055 NVP: Video is 3.817 frames behind audio (too slow), dropping frame to catch up.
13762008-02-24 14:12:02.086 NVP: Video is 3.75436 frames behind audio (too slow), dropping frame to catch up.
13772008-02-24 14:12:02.118 NVP: Video is 3.6999 frames behind audio (too slow), dropping frame to catch up.
13782008-02-24 14:12:02.148 NVP: Video is 3.63658 frames behind audio (too slow), dropping frame to catch up.
13792008-02-24 14:12:02.176 NVP: Video is 3.56659 frames behind audio (too slow), dropping frame to catch up.
13802008-02-24 14:12:02.205 NVP: Video is 3.48415 frames behind audio (too slow), dropping frame to catch up.
13812008-02-24 14:12:02.235 NVP: Video is 3.37736 frames behind audio (too slow), dropping frame to catch up.
13822008-02-24 14:12:02.263 NVP: Video is 3.27477 frames behind audio (too slow), dropping frame to catch up.
13832008-02-24 14:12:02.292 NVP: Video is 3.16787 frames behind audio (too slow), dropping frame to catch up.
13842008-02-24 14:12:02.321 NVP: Video is 3.05023 frames behind audio (too slow), dropping frame to catch up.
13852008-02-24 14:12:02.610 NVP: Video is 3.05152 frames behind audio (too slow), dropping frame to catch up.
13862008-02-24 14:12:02.641 NVP: Video is 3.09782 frames behind audio (too slow), dropping frame to catch up.
13872008-02-24 14:12:02.670 NVP: Video is 3.11008 frames behind audio (too slow), dropping frame to catch up.
13882008-02-24 14:12:02.698 NVP: Video is 3.08182 frames behind audio (too slow), dropping frame to catch up.
13892008-02-24 14:12:02.745 NVP: Video is 3.03066 frames behind audio (too slow), dropping frame to catch up.
13902008-02-24 14:12:02.774 NVP: Video is 3.08218 frames behind audio (too slow), dropping frame to catch up.
13912008-02-24 14:12:02.803 NVP: Video is 3.09087 frames behind audio (too slow), dropping frame to catch up.
13922008-02-24 14:12:02.832 NVP: Video is 3.0674 frames behind audio (too slow), dropping frame to catch up.
13932008-02-24 14:12:02.860 NVP: Video is 3.01984 frames behind audio (too slow), dropping frame to catch up.
13942008-02-24 14:12:03.039 NVP: Video is 3.03228 frames behind audio (too slow), dropping frame to catch up.
13952008-02-24 14:12:03.068 NVP: Video is 3.03845 frames behind audio (too slow), dropping frame to catch up.
13962008-02-24 14:12:03.098 NVP: Video is 3.02059 frames behind audio (too slow), dropping frame to catch up.
13972008-02-24 14:12:03.204 NVP: Video is 3.02664 frames behind audio (too slow), dropping frame to catch up.
13982008-02-24 14:12:03.233 NVP: Video is 3.02673 frames behind audio (too slow), dropping frame to catch up.
13992008-02-24 14:12:03.300 NVP: Video is 3.00438 frames behind audio (too slow), dropping frame to catch up.
14002008-02-24 14:12:03.403 NVP: Video is 3.02634 frames behind audio (too slow), dropping frame to catch up.
14012008-02-24 14:12:03.432 NVP: Video is 3.019 frames behind audio (too slow), dropping frame to catch up.
14022008-02-24 14:12:03.535 NVP: Video is 3.0021 frames behind audio (too slow), dropping frame to catch up.
14032008-02-24 14:12:03.603 NVP: Video is 3.00926 frames behind audio (too slow), dropping frame to catch up.
1404'video_output' mean = '32907.72', std. dev. = '6329.74', fps = '30.39'
14052008-02-24 14:12:03.671 NVP: Video is 3.03575 frames behind audio (too slow), dropping frame to catch up.
14062008-02-24 14:12:03.700 NVP: Video is 3.02607 frames behind audio (too slow), dropping frame to catch up.
14072008-02-24 14:12:03.785 NVP: Video is 3.14638 frames behind audio (too slow), dropping frame to catch up.
14082008-02-24 14:12:03.814 NVP: Video is 3.22142 frames behind audio (too slow), dropping frame to catch up.
14092008-02-24 14:12:03.843 NVP: Video is 3.24024 frames behind audio (too slow), dropping frame to catch up.
14102008-02-24 14:12:03.872 NVP: Video is 3.22439 frames behind audio (too slow), dropping frame to catch up.
14112008-02-24 14:12:03.900 NVP: Video is 3.17503 frames behind audio (too slow), dropping frame to catch up.
14122008-02-24 14:12:03.929 NVP: Video is 3.10055 frames behind audio (too slow), dropping frame to catch up.
14132008-02-24 14:12:03.957 NVP: Video is 3.00722 frames behind audio (too slow), dropping frame to catch up.
14142008-02-24 14:12:04.177 NVP: Video is 3.04535 frames behind audio (too slow), dropping frame to catch up.
14152008-02-24 14:12:04.207 NVP: Video is 3.08572 frames behind audio (too slow), dropping frame to catch up.
14162008-02-24 14:12:04.236 NVP: Video is 3.09351 frames behind audio (too slow), dropping frame to catch up.
14172008-02-24 14:12:04.265 NVP: Video is 3.06189 frames behind audio (too slow), dropping frame to catch up.
14182008-02-24 14:12:04.294 NVP: Video is 3.00821 frames behind audio (too slow), dropping frame to catch up.
14192008-02-24 14:12:04.472 NVP: Video is 3.02104 frames behind audio (too slow), dropping frame to catch up.
14202008-02-24 14:12:04.502 NVP: Video is 3.02254 frames behind audio (too slow), dropping frame to catch up.
14212008-02-24 14:12:04.532 NVP: Video is 3.00117 frames behind audio (too slow), dropping frame to catch up.
14222008-02-24 14:12:04.675 NVP: Video is 3.0496 frames behind audio (too slow), dropping frame to catch up.
14232008-02-24 14:12:04.705 NVP: Video is 3.05892 frames behind audio (too slow), dropping frame to catch up.
14242008-02-24 14:12:04.746 NVP: Video is 3.05095 frames behind audio (too slow), dropping frame to catch up.
14252008-02-24 14:12:04.775 NVP: Video is 3.0974 frames behind audio (too slow), dropping frame to catch up.
14262008-02-24 14:12:04.804 NVP: Video is 3.10229 frames behind audio (too slow), dropping frame to catch up.
14272008-02-24 14:12:04.834 NVP: Video is 3.07598 frames behind audio (too slow), dropping frame to catch up.
14282008-02-24 14:12:04.863 NVP: Video is 3.02625 frames behind audio (too slow), dropping frame to catch up.
14292008-02-24 14:12:05.040 NVP: Video is 3.03602 frames behind audio (too slow), dropping frame to catch up.
14302008-02-24 14:12:05.069 NVP: Video is 3.04127 frames behind audio (too slow), dropping frame to catch up.
14312008-02-24 14:12:05.198 NVP: Video is 3.01523 frames behind audio (too slow), dropping frame to catch up.
14322008-02-24 14:12:05.250 NVP: Video is 3.71498 frames behind audio (too slow), dropping frame to catch up.
14332008-02-24 14:12:05.281 NVP: Video is 4.37466 frames behind audio (too slow), dropping frame to catch up.
14342008-02-24 14:12:05.311 NVP: Video is 4.85443 frames behind audio (too slow), dropping frame to catch up.
14352008-02-24 14:12:05.341 NVP: Video is 5.1768 frames behind audio (too slow), dropping frame to catch up.
14362008-02-24 14:12:05.370 NVP: Video is 5.39609 frames behind audio (too slow), dropping frame to catch up.
14372008-02-24 14:12:05.400 NVP: Video is 5.54559 frames behind audio (too slow), dropping frame to catch up.
14382008-02-24 14:12:05.430 NVP: Video is 5.62024 frames behind audio (too slow), dropping frame to catch up.
14392008-02-24 14:12:05.460 NVP: Video is 5.65375 frames behind audio (too slow), dropping frame to catch up.
14402008-02-24 14:12:05.490 NVP: Video is 5.64143 frames behind audio (too slow), dropping frame to catch up.
14412008-02-24 14:12:05.521 NVP: Video is 5.61722 frames behind audio (too slow), dropping frame to catch up.
14422008-02-24 14:12:05.551 NVP: Video is 5.58407 frames behind audio (too slow), dropping frame to catch up.
14432008-02-24 14:12:05.582 NVP: Video is 5.52922 frames behind audio (too slow), dropping frame to catch up.
14442008-02-24 14:12:05.612 NVP: Video is 5.46562 frames behind audio (too slow), dropping frame to catch up.
14452008-02-24 14:12:05.644 NVP: Video is 5.40293 frames behind audio (too slow), dropping frame to catch up.
14462008-02-24 14:12:05.675 NVP: Video is 5.34092 frames behind audio (too slow), dropping frame to catch up.
14472008-02-24 14:12:05.706 NVP: Video is 5.27942 frames behind audio (too slow), dropping frame to catch up.
14482008-02-24 14:12:05.736 NVP: Video is 5.21831 frames behind audio (too slow), dropping frame to catch up.
14492008-02-24 14:12:05.766 NVP: Video is 5.15 frames behind audio (too slow), dropping frame to catch up.
14502008-02-24 14:12:05.797 NVP: Video is 5.07631 frames behind audio (too slow), dropping frame to catch up.
14512008-02-24 14:12:05.826 NVP: Video is 4.99107 frames behind audio (too slow), dropping frame to catch up.
14522008-02-24 14:12:05.856 NVP: Video is 4.89717 frames behind audio (too slow), dropping frame to catch up.
14532008-02-24 14:12:05.887 NVP: Video is 4.81175 frames behind audio (too slow), dropping frame to catch up.
14542008-02-24 14:12:05.916 NVP: Video is 4.71771 frames behind audio (too slow), dropping frame to catch up.
14552008-02-24 14:12:05.947 NVP: Video is 4.61722 frames behind audio (too slow), dropping frame to catch up.
14562008-02-24 14:12:05.977 NVP: Video is 4.52685 frames behind audio (too slow), dropping frame to catch up.
14572008-02-24 14:12:06.006 NVP: Video is 4.42912 frames behind audio (too slow), dropping frame to catch up.
14582008-02-24 14:12:06.037 NVP: Video is 4.32584 frames behind audio (too slow), dropping frame to catch up.
14592008-02-24 14:12:06.067 NVP: Video is 4.23341 frames behind audio (too slow), dropping frame to catch up.
14602008-02-24 14:12:06.097 NVP: Video is 4.13412 frames behind audio (too slow), dropping frame to catch up.
14612008-02-24 14:12:06.126 NVP: Video is 4.03716 frames behind audio (too slow), dropping frame to catch up.
14622008-02-24 14:12:06.157 NVP: Video is 3.93448 frames behind audio (too slow), dropping frame to catch up.
14632008-02-24 14:12:06.187 NVP: Video is 3.83498 frames behind audio (too slow), dropping frame to catch up.
14642008-02-24 14:12:06.217 NVP: Video is 3.73788 frames behind audio (too slow), dropping frame to catch up.
14652008-02-24 14:12:06.246 NVP: Video is 3.62758 frames behind audio (too slow), dropping frame to catch up.
14662008-02-24 14:12:06.275 NVP: Video is 3.52988 frames behind audio (too slow), dropping frame to catch up.
14672008-02-24 14:12:06.304 NVP: Video is 3.41914 frames behind audio (too slow), dropping frame to catch up.
14682008-02-24 14:12:06.334 NVP: Video is 3.29863 frames behind audio (too slow), dropping frame to catch up.
14692008-02-24 14:12:06.364 NVP: Video is 3.19325 frames behind audio (too slow), dropping frame to catch up.
14702008-02-24 14:12:06.395 NVP: Video is 3.07675 frames behind audio (too slow), dropping frame to catch up.
14712008-02-24 14:12:06.485 NVP: Video is 3.10744 frames behind audio (too slow), dropping frame to catch up.
14722008-02-24 14:12:06.515 NVP: Video is 3.16975 frames behind audio (too slow), dropping frame to catch up.
14732008-02-24 14:12:06.543 NVP: Video is 3.194 frames behind audio (too slow), dropping frame to catch up.
14742008-02-24 14:12:06.572 NVP: Video is 3.17473 frames behind audio (too slow), dropping frame to catch up.
14752008-02-24 14:12:06.601 NVP: Video is 3.12282 frames behind audio (too slow), dropping frame to catch up.
14762008-02-24 14:12:06.630 NVP: Video is 3.05392 frames behind audio (too slow), dropping frame to catch up.
14772008-02-24 14:12:06.810 NVP: Video is 3.05581 frames behind audio (too slow), dropping frame to catch up.
14782008-02-24 14:12:06.839 NVP: Video is 3.07109 frames behind audio (too slow), dropping frame to catch up.
14792008-02-24 14:12:06.868 NVP: Video is 3.03758 frames behind audio (too slow), dropping frame to catch up.
14802008-02-24 14:12:06.971 NVP: Video is 3.02305 frames behind audio (too slow), dropping frame to catch up.
1481'video_output' mean = '33298.55', std. dev. = '11161.52', fps = '30.03'
14822008-02-24 14:12:07.000 NVP: Video is 3.00156 frames behind audio (too slow), dropping frame to catch up.
14832008-02-24 14:12:07.140 NVP: Video is 3.02293 frames behind audio (too slow), dropping frame to catch up.
14842008-02-24 14:12:07.169 NVP: Video is 3.01645 frames behind audio (too slow), dropping frame to catch up.
14852008-02-24 14:12:07.238 NVP: Video is 3.00608 frames behind audio (too slow), dropping frame to catch up.
14862008-02-24 14:12:07.304 NVP: Video is 3.01334 frames behind audio (too slow), dropping frame to catch up.
14872008-02-24 14:12:07.407 NVP: Video is 3.03435 frames behind audio (too slow), dropping frame to catch up.
14882008-02-24 14:12:07.435 NVP: Video is 3.02503 frames behind audio (too slow), dropping frame to catch up.
14892008-02-24 14:12:07.574 NVP: Video is 3.03222 frames behind audio (too slow), dropping frame to catch up.
14902008-02-24 14:12:07.603 NVP: Video is 3.02341 frames behind audio (too slow), dropping frame to catch up.
14912008-02-24 14:12:07.708 NVP: Video is 3.02688 frames behind audio (too slow), dropping frame to catch up.
14922008-02-24 14:12:07.739 NVP: Video is 3.03441 frames behind audio (too slow), dropping frame to catch up.
14932008-02-24 14:12:07.769 NVP: Video is 3.01007 frames behind audio (too slow), dropping frame to catch up.
14942008-02-24 14:12:07.877 NVP: Video is 3.2591 frames behind audio (too slow), dropping frame to catch up.
14952008-02-24 14:12:07.907 NVP: Video is 3.46332 frames behind audio (too slow), dropping frame to catch up.
14962008-02-24 14:12:07.936 NVP: Video is 3.60148 frames behind audio (too slow), dropping frame to catch up.
14972008-02-24 14:12:07.966 NVP: Video is 3.67515 frames behind audio (too slow), dropping frame to catch up.
14982008-02-24 14:12:07.995 NVP: Video is 3.70041 frames behind audio (too slow), dropping frame to catch up.
14992008-02-24 14:12:08.025 NVP: Video is 3.68941 frames behind audio (too slow), dropping frame to catch up.
15002008-02-24 14:12:08.055 NVP: Video is 3.65866 frames behind audio (too slow), dropping frame to catch up.
15012008-02-24 14:12:08.085 NVP: Video is 3.60565 frames behind audio (too slow), dropping frame to catch up.
15022008-02-24 14:12:08.115 NVP: Video is 3.5359 frames behind audio (too slow), dropping frame to catch up.
15032008-02-24 14:12:08.145 NVP: Video is 3.46113 frames behind audio (too slow), dropping frame to catch up.
15042008-02-24 14:12:08.175 NVP: Video is 3.38258 frames behind audio (too slow), dropping frame to catch up.
15052008-02-24 14:12:08.205 NVP: Video is 3.29368 frames behind audio (too slow), dropping frame to catch up.
15062008-02-24 14:12:08.249 NVP: Video is 3.19703 frames behind audio (too slow), dropping frame to catch up.
15072008-02-24 14:12:08.306 NVP: Video is 3.21447 frames behind audio (too slow), dropping frame to catch up.
15082008-02-24 14:12:08.339 NVP: Video is 3.40736 frames behind audio (too slow), dropping frame to catch up.
15092008-02-24 14:12:08.370 NVP: Video is 3.54454 frames behind audio (too slow), dropping frame to catch up.
15102008-02-24 14:12:08.400 NVP: Video is 3.62495 frames behind audio (too slow), dropping frame to catch up.
15112008-02-24 14:12:08.429 NVP: Video is 3.65528 frames behind audio (too slow), dropping frame to catch up.
15122008-02-24 14:12:08.459 NVP: Video is 3.64805 frames behind audio (too slow), dropping frame to catch up.
15132008-02-24 14:12:08.488 NVP: Video is 3.62765 frames behind audio (too slow), dropping frame to catch up.
15142008-02-24 14:12:08.518 NVP: Video is 3.5749 frames behind audio (too slow), dropping frame to catch up.
15152008-02-24 14:12:08.548 NVP: Video is 3.51286 frames behind audio (too slow), dropping frame to catch up.
15162008-02-24 14:12:08.577 NVP: Video is 3.43634 frames behind audio (too slow), dropping frame to catch up.
15172008-02-24 14:12:08.642 NVP: Video is 3.34898 frames behind audio (too slow), dropping frame to catch up.
15182008-02-24 14:12:08.675 NVP: Video is 3.51573 frames behind audio (too slow), dropping frame to catch up.
15192008-02-24 14:12:08.705 NVP: Video is 3.64829 frames behind audio (too slow), dropping frame to catch up.
15202008-02-24 14:12:08.736 NVP: Video is 3.71774 frames behind audio (too slow), dropping frame to catch up.
15212008-02-24 14:12:08.766 NVP: Video is 3.74735 frames behind audio (too slow), dropping frame to catch up.
15222008-02-24 14:12:08.797 NVP: Video is 3.73959 frames behind audio (too slow), dropping frame to catch up.
15232008-02-24 14:12:08.827 NVP: Video is 3.71879 frames behind audio (too slow), dropping frame to catch up.
15242008-02-24 14:12:08.857 NVP: Video is 3.68819 frames behind audio (too slow), dropping frame to catch up.
15252008-02-24 14:12:08.887 NVP: Video is 3.62779 frames behind audio (too slow), dropping frame to catch up.
15262008-02-24 14:12:08.917 NVP: Video is 3.56 frames behind audio (too slow), dropping frame to catch up.
15272008-02-24 14:12:08.947 NVP: Video is 3.49419 frames behind audio (too slow), dropping frame to catch up.
15282008-02-24 14:12:08.975 NVP: Video is 3.41485 frames behind audio (too slow), dropping frame to catch up.
15292008-02-24 14:12:09.005 NVP: Video is 3.31041 frames behind audio (too slow), dropping frame to catch up.
15302008-02-24 14:12:09.034 NVP: Video is 3.21708 frames behind audio (too slow), dropping frame to catch up.
15312008-02-24 14:12:09.063 NVP: Video is 3.1096 frames behind audio (too slow), dropping frame to catch up.
15322008-02-24 14:12:09.210 NVP: Video is 3.03908 frames behind audio (too slow), dropping frame to catch up.
15332008-02-24 14:12:09.240 NVP: Video is 3.04355 frames behind audio (too slow), dropping frame to catch up.
15342008-02-24 14:12:09.270 NVP: Video is 3.01693 frames behind audio (too slow), dropping frame to catch up.
15352008-02-24 14:12:09.379 NVP: Video is 3.04663 frames behind audio (too slow), dropping frame to catch up.
15362008-02-24 14:12:09.410 NVP: Video is 3.0642 frames behind audio (too slow), dropping frame to catch up.
15372008-02-24 14:12:09.441 NVP: Video is 3.0624 frames behind audio (too slow), dropping frame to catch up.
15382008-02-24 14:12:09.469 NVP: Video is 3.03857 frames behind audio (too slow), dropping frame to catch up.
15392008-02-24 14:12:09.577 NVP: Video is 3.03842 frames behind audio (too slow), dropping frame to catch up.
15402008-02-24 14:12:09.606 NVP: Video is 3.04307 frames behind audio (too slow), dropping frame to catch up.
15412008-02-24 14:12:09.635 NVP: Video is 3.00908 frames behind audio (too slow), dropping frame to catch up.
15422008-02-24 14:12:09.777 NVP: Video is 3.03713 frames behind audio (too slow), dropping frame to catch up.
15432008-02-24 14:12:09.806 NVP: Video is 3.04208 frames behind audio (too slow), dropping frame to catch up.
15442008-02-24 14:12:09.836 NVP: Video is 3.01582 frames behind audio (too slow), dropping frame to catch up.
15452008-02-24 14:12:09.944 NVP: Video is 3.0321 frames behind audio (too slow), dropping frame to catch up.
15462008-02-24 14:12:09.972 NVP: Video is 3.0383 frames behind audio (too slow), dropping frame to catch up.
15472008-02-24 14:12:10.000 NVP: Video is 3.00551 frames behind audio (too slow), dropping frame to catch up.
15482008-02-24 14:12:10.145 NVP: Video is 3.008 frames behind audio (too slow), dropping frame to catch up.
15492008-02-24 14:12:10.174 NVP: Video is 3.02023 frames behind audio (too slow), dropping frame to catch up.
15502008-02-24 14:12:10.174 NVP: 400 interlaced frames seen.
15512008-02-24 14:12:10.241 NVP: Video is 3.02131 frames behind audio (too slow), dropping frame to catch up.
15522008-02-24 14:12:10.270 NVP: Video is 3.00773 frames behind audio (too slow), dropping frame to catch up.
1553'video_output' mean = '33366.99', std. dev. = '7078.64', fps = '29.97'
15542008-02-24 14:12:10.377 NVP: Video is 3.00483 frames behind audio (too slow), dropping frame to catch up.
15552008-02-24 14:12:10.407 NVP: Video is 3.01037 frames behind audio (too slow), dropping frame to catch up.
15562008-02-24 14:12:10.475 NVP: Video is 3.02326 frames behind audio (too slow), dropping frame to catch up.
15572008-02-24 14:12:10.504 NVP: Video is 3.00171 frames behind audio (too slow), dropping frame to catch up.
15582008-02-24 14:12:10.614 NVP: Video is 3.02101 frames behind audio (too slow), dropping frame to catch up.
15592008-02-24 14:12:10.647 NVP: Video is 3.05248 frames behind audio (too slow), dropping frame to catch up.
15602008-02-24 14:12:10.680 NVP: Video is 3.07607 frames behind audio (too slow), dropping frame to catch up.
15612008-02-24 14:12:10.713 NVP: Video is 3.07879 frames behind audio (too slow), dropping frame to catch up.
15622008-02-24 14:12:10.746 NVP: Video is 3.08832 frames behind audio (too slow), dropping frame to catch up.
15632008-02-24 14:12:10.760 TV: CommitQueuedInput() livetv(1) qchannum(168) qchanid(0)
15642008-02-24 14:12:10.760 TV: ChangeChannel(0, '168')
15652008-02-24 14:12:10.760 write -> 30 49 QUERY_RECORDER 2[]:[]CHECK_CHANNEL_PREFIX[]:[]168
15662008-02-24 14:12:10.765 read <- 30 19 1[]:[]5[]:[]0[]:[]X
15672008-02-24 14:12:10.766 write -> 27 22 GET_FREE_RECORDER_LIST
15682008-02-24 14:12:10.767 read <- 27 13 1[]:[]5[]:[]7
15692008-02-24 14:12:10.768 write -> 27 27 GET_RECORDER_FROM_NUM[]:[]1
15702008-02-24 14:12:10.768 read <- 27 21 192.168.0.50[]:[]6543
15712008-02-24 14:12:10.768 TV: SwitchCards(0,'168',0)
15722008-02-24 14:12:10.768 write -> 27 22 GET_FREE_RECORDER_LIST
15732008-02-24 14:12:10.770 read <- 27 13 1[]:[]5[]:[]7
15742008-02-24 14:12:10.770 write -> 27 27 GET_RECORDER_FROM_NUM[]:[]1
15752008-02-24 14:12:10.770 read <- 27 21 192.168.0.50[]:[]6543
15762008-02-24 14:12:10.772 NVP: Changing speed to 0
15772008-02-24 14:12:10.772 rate: 29.97 speed: 1 skip: 1 = interval 33366
15782008-02-24 14:12:10.772 Set video sync frame interval to 33366
15792008-02-24 14:12:10.804 write -> 30 32 QUERY_RECORDER 2[]:[]STOP_LIVETV
15802008-02-24 14:12:10.806 NVP: Exited decoder loop.
15812008-02-24 14:12:10.816 MythSocket(b36436b8:25): socket is readable
15822008-02-24 14:12:10.816 MythSocket(b36436b8:25): cb->readyRead()
15832008-02-24 14:12:10.816 read <- 25 49 BACKEND_MESSAGE[]:[]DONE_RECORDING 2 20[]:[]empty
15842008-02-24 14:12:10.816 MythEvent: DONE_RECORDING 2 20
15852008-02-24 14:12:10.828 ~OpenGLVideoSync() -- begin
15862008-02-24 14:12:10.828 ~OpenGLVideoSync() -- middle
15872008-02-24 14:12:10.828 ~OpenGLVideoSync() -- end
15882008-02-24 14:12:10.828 VideoOutputXv: dtor
15892008-02-24 14:12:10.828 VideoOutputXv: DiscardFrames(1)
15902008-02-24 14:12:10.828 VideoBuffers::DiscardFrames(1): UUUUUUUUUUUuUULUULUUUUUUUUUAAUU
15912008-02-24 14:12:10.828 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
15922008-02-24 14:12:10.828 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
15932008-02-24 14:12:10.828 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
1594X Error: BadDrawable (invalid Pixmap or Window parameter) 9
1595 Major opcode: 70
1596 Minor opcode: 0
1597 Resource id: 0x1e0011a
15982008-02-24 14:12:10.875 AO: Killing AudioOutputDSP
15992008-02-24 14:12:10.876 AO: OutputAudioLoop: Stop Event
16002008-02-24 14:12:10.876 AO: kickoffOutputAudioLoop exiting
16012008-02-24 14:12:10.881 MythSocket(b36436b8:25): socket is readable
16022008-02-24 14:12:10.881 MythSocket(b36436b8:25): cb->readyRead()
16032008-02-24 14:12:10.881 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
16042008-02-24 14:12:10.881 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
16052008-02-24 14:12:10.885 MythSocket(b36436b8:25): socket is readable
16062008-02-24 14:12:10.885 MythSocket(b36436b8:25): cb->readyRead()
16072008-02-24 14:12:10.885 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
16082008-02-24 14:12:10.885 MythEvent: RECORDING_LIST_CHANGE
16092008-02-24 14:12:10.885 read <- 30 2 ok
16102008-02-24 14:12:10.902 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
16112008-02-24 14:12:10.902 read <- 27 2 OK
16122008-02-24 14:12:10.902 MythSocket(b36436b8:25): socket is readable
16132008-02-24 14:12:10.902 MythSocket(b36436b8:25): cb->readyRead()
16142008-02-24 14:12:10.902 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
16152008-02-24 14:12:10.902 MythEvent: RECORDING_LIST_CHANGE
16162008-02-24 14:12:10.917 Closing PAT Filter: pid=0x0
16172008-02-24 14:12:10.917 Closing PMT Filter: pid=0x30
16182008-02-24 14:12:10.917 MythSocket(a77777b8:30): DownRef: -1
16192008-02-24 14:12:10.917 MythSocket(a77777b8:30): state change Connected -> Idle
16202008-02-24 14:12:10.917 MythSocket(a77777b8:-1): delete socket
16212008-02-24 14:12:10.924 MythSocket(b3655380:30): new socket
16222008-02-24 14:12:10.924 MythSocket(b3655380:30): attempting connect() to (192.168.0.50:6543)
16232008-02-24 14:12:10.924 MythSocket(b3655380:30): state change Idle -> Connected
16242008-02-24 14:12:10.924 write -> 30 21 MYTH_PROTO_VERSION 40
16252008-02-24 14:12:10.924 read <- 30 13 ACCEPT[]:[]40
16262008-02-24 14:12:10.924 Using protocol version 40
16272008-02-24 14:12:10.924 write -> 30 21 ANN Playback mythtv 0
16282008-02-24 14:12:10.927 read <- 30 2 OK
16292008-02-24 14:12:10.927 write -> 30 83 QUERY_RECORDER 1[]:[]SPAWN_LIVETV[]:[]live-mythtv-2008-02-24T14:1...
16302008-02-24 14:12:14.313 read <- 30 2 ok
16312008-02-24 14:12:14.314 LiveTVChain(live-mythtv-2008-02-24T14:11:34): ReloadAll(): Added new recording
16322008-02-24 14:12:14.315 MythSocket(b36436b8:25): socket is readable
16332008-02-24 14:12:14.315 MythSocket(b36436b8:25): cb->readyRead()
16342008-02-24 14:12:14.315 read <- 25 53 BACKEND_MESSAGE[]:[]QUERY_NEXT_LIVETV_DIR 1[]:[]empty
16352008-02-24 14:12:14.315 MythEvent: QUERY_NEXT_LIVETV_DIR 1
16362008-02-24 14:12:14.316 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
16372008-02-24 14:12:14.316 MythEvent: RECORDING_LIST_CHANGE
16382008-02-24 14:12:14.316 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
16392008-02-24 14:12:14.316 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
16402008-02-24 14:12:14.316 read <- 25 451 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
16412008-02-24 14:12:14.316 MythEvent: SIGNAL 1
16422008-02-24 14:12:14.316 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group!
16432008-02-24 14:12:14.316 SG(Default): FindRecordingFile: Searching for '1168_20080224141212.mpg'
16442008-02-24 14:12:14.316 SG(Default): FindRecordingDir: Checking '/mnt/store'
16452008-02-24 14:12:14.317 SG(Default): FindRecordingFile: Found '/mnt/store/1168_20080224141212.mpg'
16462008-02-24 14:12:14.317 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/1168_20080224141212.mpg'
16472008-02-24 14:12:14.317 RingBuf(/mnt/store/1168_20080224141212.mpg): OpenFile(/mnt/store/1168_20080224141212.mpg, 12)
16482008-02-24 14:12:14.613 MythSocket(b36436b8:25): socket is readable
16492008-02-24 14:12:14.613 MythSocket(b36436b8:25): cb->readyRead()
16502008-02-24 14:12:14.613 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
16512008-02-24 14:12:14.613 MythEvent: SIGNAL 1
16522008-02-24 14:12:14.915 MythSocket(b36436b8:25): socket is readable
16532008-02-24 14:12:14.915 MythSocket(b36436b8:25): cb->readyRead()
16542008-02-24 14:12:14.915 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
16552008-02-24 14:12:14.915 MythEvent: SIGNAL 1
16562008-02-24 14:12:15.217 MythSocket(b36436b8:25): socket is readable
16572008-02-24 14:12:15.218 MythSocket(b36436b8:25): cb->readyRead()
16582008-02-24 14:12:15.218 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
16592008-02-24 14:12:15.218 MythEvent: SIGNAL 1
16602008-02-24 14:12:15.519 MythSocket(b36436b8:25): socket is readable
16612008-02-24 14:12:15.519 MythSocket(b36436b8:25): cb->readyRead()
16622008-02-24 14:12:15.519 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
16632008-02-24 14:12:15.519 MythEvent: SIGNAL 1
16642008-02-24 14:12:15.821 MythSocket(b36436b8:25): socket is readable
16652008-02-24 14:12:15.821 MythSocket(b36436b8:25): cb->readyRead()
16662008-02-24 14:12:15.821 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
16672008-02-24 14:12:15.821 MythEvent: SIGNAL 1
16682008-02-24 14:12:16.123 MythSocket(b36436b8:25): socket is readable
16692008-02-24 14:12:16.123 MythSocket(b36436b8:25): cb->readyRead()
16702008-02-24 14:12:16.123 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
16712008-02-24 14:12:16.123 MythEvent: SIGNAL 1
16722008-02-24 14:12:16.424 MythSocket(b36436b8:25): socket is readable
16732008-02-24 14:12:16.424 MythSocket(b36436b8:25): cb->readyRead()
16742008-02-24 14:12:16.424 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
16752008-02-24 14:12:16.424 MythEvent: SIGNAL 1
16762008-02-24 14:12:16.726 MythSocket(b36436b8:25): socket is readable
16772008-02-24 14:12:16.726 MythSocket(b36436b8:25): cb->readyRead()
16782008-02-24 14:12:16.726 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
16792008-02-24 14:12:16.726 MythEvent: SIGNAL 1
16802008-02-24 14:12:17.028 MythSocket(b36436b8:25): socket is readable
16812008-02-24 14:12:17.028 MythSocket(b36436b8:25): cb->readyRead()
16822008-02-24 14:12:17.028 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
16832008-02-24 14:12:17.028 MythEvent: SIGNAL 1
16842008-02-24 14:12:17.329 MythSocket(b36436b8:25): socket is readable
16852008-02-24 14:12:17.329 MythSocket(b36436b8:25): cb->readyRead()
16862008-02-24 14:12:17.329 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
16872008-02-24 14:12:17.329 MythEvent: SIGNAL 1
16882008-02-24 14:12:17.360 MythSocket(b36436b8:25): socket is readable
16892008-02-24 14:12:17.361 MythSocket(b36436b8:25): cb->readyRead()
16902008-02-24 14:12:17.361 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
16912008-02-24 14:12:17.361 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
16922008-02-24 14:12:18.398 MythSocket(b36436b8:25): socket is readable
16932008-02-24 14:12:18.399 MythSocket(b36436b8:25): cb->readyRead()
16942008-02-24 14:12:18.399 read <- 25 53 BACKEND_MESSAGE[]:[]QUERY_NEXT_LIVETV_DIR 1[]:[]empty
16952008-02-24 14:12:18.399 MythEvent: QUERY_NEXT_LIVETV_DIR 1
16962008-02-24 14:12:18.399 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
16972008-02-24 14:12:18.399 MythEvent: RECORDING_LIST_CHANGE
16982008-02-24 14:12:18.399 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
16992008-02-24 14:12:18.399 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
17002008-02-24 14:12:18.399 MythSocket(b36436b8:25): socket is readable
17012008-02-24 14:12:18.399 MythSocket(b36436b8:25): cb->readyRead()
17022008-02-24 14:12:18.399 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14...
17032008-02-24 14:12:18.399 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34
17042008-02-24 14:12:20.818 RingBuf(/mnt/store/1168_20080224141212.mpg): Invalid file (fd -1) when opening '/mnt/store/1168_20080224141212.mpg'.
17052008-02-24 14:12:20.818 RingBuf(/mnt/store/1168_20080224141212.mpg): CalcReadAheadThresh(3050971124 KB)
1706 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
17072008-02-24 14:12:20.819 write -> 30 33 QUERY_RECORDER 1[]:[]IS_RECORDING
17082008-02-24 14:12:20.819 read <- 30 1 1
17092008-02-24 14:12:20.819 write -> 30 33 QUERY_RECORDER 1[]:[]IS_RECORDING
17102008-02-24 14:12:20.819 read <- 30 1 1
17112008-02-24 14:12:20.819 TV: StartRecorder(): took 0 ms to start recorder.
17122008-02-24 14:12:20.819 write -> 30 34 QUERY_RECORDER 1[]:[]GET_FRAMERATE
17132008-02-24 14:12:20.819 read <- 30 5 29.97
17142008-02-24 14:12:20.821 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group!
17152008-02-24 14:12:20.822 SG(Default): FindRecordingFile: Searching for '1168_20080224141212.mpg'
17162008-02-24 14:12:20.822 SG(Default): FindRecordingDir: Checking '/mnt/store'
17172008-02-24 14:12:20.822 SG(Default): FindRecordingFile: Found '/mnt/store/1168_20080224141212.mpg'
17182008-02-24 14:12:20.822 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/1168_20080224141212.mpg'
17192008-02-24 14:12:20.822 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE
17202008-02-24 14:12:20.823 read <- 27 2 OK
17212008-02-24 14:12:20.823 MythSocket(b36436b8:25): socket is readable
17222008-02-24 14:12:20.823 MythSocket(b36436b8:25): cb->readyRead()
17232008-02-24 14:12:20.823 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
17242008-02-24 14:12:20.823 MythEvent: RECORDING_LIST_CHANGE
17252008-02-24 14:12:20.829 detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan
17262008-02-24 14:12:20.829 NVP: Disabling Audio, params(-1,2,44100)
17272008-02-24 14:12:20.830 VideoOutput: Allowed renderers: opengl,xv-blit,xshm,xlib
17282008-02-24 14:12:20.830 VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl
17292008-02-24 14:12:20.831 VDP: Accepting: cmp(> 0 0) dec(libmpeg2) cpus(1) rend(opengl) osd(opengl2) osdfade(enabled) deint(greedyhdeint,openglkerneldeint) filt()
17302008-02-24 14:12:20.831 VDP: LoadBestPreferences(2048x2048, 0)
17312008-02-24 14:12:20.831 VDP: LoadBestPreferences(2048x2048, 60)
17322008-02-24 14:12:20.831 VDP: LoadBestPreferences(720x576, 60)
17332008-02-24 14:12:20.831 VideoOutput: Preferred renderer: opengl
17342008-02-24 14:12:20.831 VideoOutput: Trying video renderer: opengl
17352008-02-24 14:12:20.832 VDP: Accepting: cmp(> 0 0) dec(libmpeg2) cpus(1) rend(opengl) osd(opengl2) osdfade(enabled) deint(greedyhdeint,openglkerneldeint) filt()
17362008-02-24 14:12:20.832 VDP: LoadBestPreferences(2048x2048, 0)
17372008-02-24 14:12:20.832 VDP: LoadBestPreferences(2048x2048, 60)
17382008-02-24 14:12:20.837 VideoOutputXv: ctor
17392008-02-24 14:12:20.838 XOff: 0, YOff: 0
17402008-02-24 14:12:20.838 VDP: LoadBestPreferences(720x576, 60)
17412008-02-24 14:12:20.838 Display Rect left: 0, top: 0, width: 848, height: 480, aspect: 1.33333
17422008-02-24 14:12:20.838 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.33333
17432008-02-24 14:12:20.840 VideoOutputXv: Pixel dimensions: Screen 848x480, window 848x480
17442008-02-24 14:12:20.840 VideoOutputXv: Estimated display dimensions: 287x163 mm Aspect: 1.76074
17452008-02-24 14:12:20.840 VideoOutputXv: Estimated window dimensions: 287x163 mm Aspect: 1.76074
17462008-02-24 14:12:20.840 VideoOutputXv: InitSetupBuffers() render: opengl, allowed: opengl,xv-blit,xshm,xlib
17472008-02-24 14:12:20.842 GLCtx: Created window and context.
17482008-02-24 14:12:20.864 GLVid: Viewport: 848x576
17492008-02-24 14:12:20.864 GLVid: Created main input texture 360x288
17502008-02-24 14:12:20.865 GLVid: Created main input texture 360x288
17512008-02-24 14:12:20.869 GLVid: Created main input texture 720x576
17522008-02-24 14:12:20.869 GLVid: Creating master filter.
17532008-02-24 14:12:20.869 GLVid: Created fragment program master.
17542008-02-24 14:12:20.869 GLVid: Creating resize filter.
1755mythfrontend: Fatal IO error: client killed
17562008-02-24 14:12:21.183 MythSocket: readyread thread exit