2008-02-24 14:11:32.696 user: 0 effective user: 0 before privileged thread 2008-02-24 14:11:32.697 user: 0 effective user: 0 run_priv_thread 2008-02-24 14:11:32.697 user: 0 effective user: 0 after privileged thread 2008-02-24 14:11:32.698 New DB connection, total: 2 2008-02-24 14:11:32.698 Connected to database 'mythconverg' at host: localhost 2008-02-24 14:11:32.699 mythfrontend version: 0.21.20080213-1 www.mythtv.org 2008-02-24 14:11:32.699 Enabled verbose msgs: most 2008-02-24 14:11:32.809 max_width: 848 max_height: 480 2008-02-24 14:11:32.890 Total desktop dim: 848x480, with 1 screen[s]. 2008-02-24 14:11:32.891 Using screen 0, 848x480 at 0,0 2008-02-24 14:11:32.892 Switching to square mode (Titivillus) 2008-02-24 14:11:32.907 Using the OpenGL painter 2008-02-24 14:11:32.909 lirc init success using configuration file: /root/.mythtv/lircrc 2008-02-24 14:11:33.092 generating cache image for: /usr/share/mythtv/themes/default/blank.png 2008-02-24 14:11:33.180 Loading from: /usr/share/mythtv/themes/Titivillus/base.xml 2008-02-24 14:11:33.190 Loading from: /usr/share/mythtv/themes/default/base.xml 2008-02-24 14:11:33.219 Registering Internal as a media playback plugin. 2008-02-24 14:11:33.228 Unable to initialize plugin 'mythappearance'. 2008-02-24 14:11:33.256 MythPlugin::init() dlerror: /usr/lib/mythtv/plugins/libmythdvd.so: undefined symbol: _ZN18ConfigurationGroup6byNameE7QString 2008-02-24 14:11:33.256 Unable to initialize plugin 'mythdvd'. 2008-02-24 14:11:33.309 Using NV NPOT texture extension 2008-02-24 14:11:33.355 Registering MythMusic Media Handler 1/2 as a media handler for MEDIATYPE_MIXED 2008-02-24 14:11:33.356 Creating inactive MediaMonitor and static device list 2008-02-24 14:11:33.356 IgnoreDevices= 2008-02-24 14:11:33.358 MediaMonitorUnix::GetCDROMBlockDevices() returning 2008-02-24 14:11:33.361 MediaMonitorUnix::AddDevice() - Added /dev/sdb 2008-02-24 14:11:33.361 Initial device list... /dev/sdb (TEAC USB HS-CF Card ) 2008-02-24 14:11:33.362 Registering MythMusic Media Handler 2/2 as a media handler for MEDIATYPE_MMUSIC, ext(ogg,mp3,aac,flac) 2008-02-24 14:11:33.362 MonitorRegisterExtensions(0x40, ogg,mp3,aac,flac) 2008-02-24 14:11:33.363 MediaMonitor::selectDrivePopup(Select a CD drive) - No suitable devices 2008-02-24 14:11:33.385 Unable to initialize plugin 'mythnews'. 2008-02-24 14:11:33.416 Registering MythDVD DVD Media Handler as a media handler for MEDIATYPE_DVD 2008-02-24 14:11:33.416 Registering MythDVD VCD Media Handler as a media handler for MEDIATYPE_VCD 2008-02-24 14:11:34.695 MythEvent: PLAYBACK_START mythtv 2008-02-24 14:11:34.696 MythSocket(b36436b8:25): new socket 2008-02-24 14:11:34.697 Connecting to backend server: 192.168.0.50:6543 (try 1 of 5) 2008-02-24 14:11:34.697 MythSocket(b195e098:27): new socket 2008-02-24 14:11:34.697 MythSocket(b195e098:27): attempting connect() to (192.168.0.50:6543) 2008-02-24 14:11:34.698 MythSocket(b195e098:27): state change Idle -> Connected 2008-02-24 14:11:34.698 write -> 27 21 MYTH_PROTO_VERSION 40 2008-02-24 14:11:34.698 read <- 27 13 ACCEPT[]:[]40 2008-02-24 14:11:34.698 Using protocol version 40 2008-02-24 14:11:34.698 write -> 27 20 ANN Monitor mythtv 0 2008-02-24 14:11:34.701 read <- 27 2 OK 2008-02-24 14:11:34.701 MythSocket(b36436b8:25): attempting connect() to (192.168.0.50:6543) 2008-02-24 14:11:34.701 MythSocket(b36436b8:25): state change Idle -> Connected 2008-02-24 14:11:34.702 write -> 25 20 ANN Monitor mythtv 1 2008-02-24 14:11:34.703 read <- 25 2 OK 2008-02-24 14:11:34.704 MythSocket(b36436b8:25): UpRef: 1 2008-02-24 14:11:34.704 MythSocket: readyread thread start 2008-02-24 14:11:34.704 write -> 27 23 GET_FREE_RECORDER_COUNT 2008-02-24 14:11:34.704 read <- 27 1 4 2008-02-24 14:11:34.704 write -> 27 29 GET_NEXT_FREE_RECORDER[]:[]-1 2008-02-24 14:11:34.704 read <- 27 27 1[]:[]192.168.0.50[]:[]6543 2008-02-24 14:11:34.705 TV: Attempting to change from None to WatchingLiveTV 2008-02-24 14:11:34.705 MythSocket(82d8670:30): new socket 2008-02-24 14:11:34.705 MythSocket(82d8670:30): attempting connect() to (192.168.0.50:6543) 2008-02-24 14:11:34.705 MythSocket(82d8670:30): state change Idle -> Connected 2008-02-24 14:11:34.705 write -> 30 21 MYTH_PROTO_VERSION 40 2008-02-24 14:11:34.705 read <- 30 13 ACCEPT[]:[]40 2008-02-24 14:11:34.705 Using protocol version 40 2008-02-24 14:11:34.705 write -> 30 21 ANN Playback mythtv 0 2008-02-24 14:11:34.707 read <- 30 2 OK 2008-02-24 14:11:34.707 write -> 30 80 QUERY_RECORDER 1[]:[]SPAWN_LIVETV[]:[]live-mythtv-2008-02-24T14:1... 2008-02-24 14:11:36.156 read <- 30 2 ok 2008-02-24 14:11:36.157 LiveTVChain(live-mythtv-2008-02-24T14:11:34): ReloadAll(): Added new recording 2008-02-24 14:11:36.160 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group! 2008-02-24 14:11:36.160 SG(Default): FindRecordingFile: Searching for '1168_20080224141134.mpg' 2008-02-24 14:11:36.160 SG(Default): FindRecordingDir: Checking '/mnt/store' 2008-02-24 14:11:36.160 SG(Default): FindRecordingFile: Found '/mnt/store/1168_20080224141134.mpg' 2008-02-24 14:11:36.160 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/1168_20080224141134.mpg' 2008-02-24 14:11:36.160 write -> 30 33 QUERY_RECORDER 1[]:[]IS_RECORDING 2008-02-24 14:11:36.161 read <- 30 1 1 2008-02-24 14:11:36.161 write -> 30 33 QUERY_RECORDER 1[]:[]IS_RECORDING 2008-02-24 14:11:36.161 read <- 30 1 1 2008-02-24 14:11:36.161 TV: StartRecorder(): took 1 ms to start recorder. 2008-02-24 14:11:36.161 write -> 30 34 QUERY_RECORDER 1[]:[]GET_FRAMERATE 2008-02-24 14:11:36.161 read <- 30 2 -1 2008-02-24 14:11:36.162 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:36.162 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:36.165 read <- 25 53 BACKEND_MESSAGE[]:[]QUERY_NEXT_LIVETV_DIR 1[]:[]empty 2008-02-24 14:11:36.165 MythEvent: QUERY_NEXT_LIVETV_DIR 1 2008-02-24 14:11:36.165 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:11:36.165 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:11:36.165 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:11:36.167 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:11:36.167 read <- 25 451 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:36.167 MythEvent: SIGNAL 1 2008-02-24 14:11:36.229 DPMS Deactivated 2008-02-24 14:11:36.230 New DB connection, total: 3 2008-02-24 14:11:36.230 Connected to database 'mythconverg' at host: localhost 2008-02-24 14:11:36.231 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group! 2008-02-24 14:11:36.231 SG(Default): FindRecordingFile: Searching for '1168_20080224141134.mpg' 2008-02-24 14:11:36.231 SG(Default): FindRecordingDir: Checking '/mnt/store' 2008-02-24 14:11:36.231 SG(Default): FindRecordingFile: Found '/mnt/store/1168_20080224141134.mpg' 2008-02-24 14:11:36.231 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/1168_20080224141134.mpg' 2008-02-24 14:11:36.231 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE 2008-02-24 14:11:36.232 read <- 27 2 OK 2008-02-24 14:11:36.232 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:36.232 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:36.232 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:11:36.232 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:11:36.238 detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan 2008-02-24 14:11:36.239 NVP: Disabling Audio, params(-1,2,44100) 2008-02-24 14:11:36.241 VideoOutput: Allowed renderers: opengl,xv-blit,xshm,xlib 2008-02-24 14:11:36.241 VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl 2008-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() 2008-02-24 14:11:36.242 VDP: LoadBestPreferences(2048x2048, 0) 2008-02-24 14:11:36.242 VDP: LoadBestPreferences(2048x2048, 60) 2008-02-24 14:11:36.242 VDP: LoadBestPreferences(720x576, 60) 2008-02-24 14:11:36.242 VideoOutput: Preferred renderer: opengl 2008-02-24 14:11:36.242 VideoOutput: Trying video renderer: opengl 2008-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() 2008-02-24 14:11:36.243 VDP: LoadBestPreferences(2048x2048, 0) 2008-02-24 14:11:36.243 VDP: LoadBestPreferences(2048x2048, 60) 2008-02-24 14:11:36.249 VideoOutputXv: ctor 2008-02-24 14:11:36.250 XOff: 0, YOff: 0 2008-02-24 14:11:36.250 VDP: LoadBestPreferences(720x576, 60) 2008-02-24 14:11:36.250 Display Rect left: 0, top: 0, width: 848, height: 480, aspect: 1.33333 2008-02-24 14:11:36.250 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.33333 2008-02-24 14:11:36.250 VideoOutputXv: Pixel dimensions: Screen 848x480, window 848x480 2008-02-24 14:11:36.250 VideoOutputXv: Estimated display dimensions: 287x163 mm Aspect: 1.76074 2008-02-24 14:11:36.250 VideoOutputXv: Estimated window dimensions: 287x163 mm Aspect: 1.76074 2008-02-24 14:11:36.250 VideoOutputXv: InitSetupBuffers() render: opengl, allowed: opengl,xv-blit,xshm,xlib 2008-02-24 14:11:36.252 GLCtx: Created window and context. 2008-02-24 14:11:36.278 GLCtx: GLX Version: 1.3 2008-02-24 14:11:36.279 GLCtx: Direct rendering: Yes 2008-02-24 14:11:36.279 GLCtx: OpenGL vendor : NVIDIA Corporation 2008-02-24 14:11:36.279 GLCtx: OpenGL renderer: GeForce 8300 GS/PCI/SSE2 2008-02-24 14:11:36.279 GLCtx: OpenGL version : 2.1.1 NVIDIA 100.14.19 2008-02-24 14:11:36.279 GLCtx: Max texture size: 8192 x 8192 2008-02-24 14:11:36.279 GLVid: Viewport: 848x576 2008-02-24 14:11:36.280 GLVid: Created main input texture 360x288 2008-02-24 14:11:36.281 GLVid: Created main input texture 360x288 2008-02-24 14:11:36.285 GLVid: Created main input texture 720x576 2008-02-24 14:11:36.285 GLVid: Creating master filter. 2008-02-24 14:11:36.285 GLVid: Created fragment program master. 2008-02-24 14:11:36.285 GLVid: Creating resize filter. 2008-02-24 14:11:36.292 GLCtx: Created frame buffer object (720x576). 2008-02-24 14:11:36.292 GLVid: Turning off deinterlacing. 2008-02-24 14:11:36.292 GLVid: Turning off deinterlacing. 2008-02-24 14:11:36.292 Created data @0xad9d6020->0xada6de22 2008-02-24 14:11:36.292 Created data @0xad93e020->0xad9d5e22 2008-02-24 14:11:36.292 Created data @0xad8a6020->0xad93de22 2008-02-24 14:11:36.292 Created data @0xad80e020->0xad8a5e22 2008-02-24 14:11:36.292 Created data @0xad776020->0xad80de22 2008-02-24 14:11:36.292 Created data @0xad6de020->0xad775e22 2008-02-24 14:11:36.292 Created data @0xad646020->0xad6dde22 2008-02-24 14:11:36.293 Created data @0xad5ae020->0xad645e22 2008-02-24 14:11:36.293 Created data @0xad516020->0xad5ade22 2008-02-24 14:11:36.293 Created data @0xad47e020->0xad515e22 2008-02-24 14:11:36.293 Created data @0xad3e6020->0xad47de22 2008-02-24 14:11:36.293 Created data @0xad34e020->0xad3e5e22 2008-02-24 14:11:36.293 Created data @0xad2b6020->0xad34de22 2008-02-24 14:11:36.293 Created data @0xad21e020->0xad2b5e22 2008-02-24 14:11:36.293 Created data @0xad186020->0xad21de22 2008-02-24 14:11:36.293 Created data @0xad0ee020->0xad185e22 2008-02-24 14:11:36.293 Created data @0xad056020->0xad0ede22 2008-02-24 14:11:36.293 Created data @0xacfbe020->0xad055e22 2008-02-24 14:11:36.293 Created data @0xacf26020->0xacfbde22 2008-02-24 14:11:36.293 Created data @0xace8e020->0xacf25e22 2008-02-24 14:11:36.293 Created data @0xacdf6020->0xace8de22 2008-02-24 14:11:36.293 Created data @0xacd5e020->0xacdf5e22 2008-02-24 14:11:36.293 Created data @0xaccc6020->0xacd5de22 2008-02-24 14:11:36.293 Created data @0xacc2e020->0xaccc5e22 2008-02-24 14:11:36.293 Created data @0xacb96020->0xacc2de22 2008-02-24 14:11:36.293 Created data @0xacafe020->0xacb95e22 2008-02-24 14:11:36.293 Created data @0xaca66020->0xacafde22 2008-02-24 14:11:36.293 Created data @0xac9ce020->0xaca65e22 2008-02-24 14:11:36.293 Created data @0xac936020->0xac9cde22 2008-02-24 14:11:36.293 Created data @0xac89e020->0xac935e22 2008-02-24 14:11:36.293 Created data @0xac806020->0xac89de22 2008-02-24 14:11:36.293 Created data @0xac76e020->0xac805e22 2008-02-24 14:11:36.309 VDP: GetFilteredDeint() : -> 'greedyhdeint' greedyhdeint: size changed from 0 x 0 -> 720 x 576 2008-02-24 14:11:36.311 Using deinterlace method greedyhdeint 2008-02-24 14:11:36.311 VDP: SetVideoRenderer(opengl) 2008-02-24 14:11:36.311 VDP: SetVideoRender(opengl) == GetVideoRenderer() 2008-02-24 14:11:36.316 GLVid: Created main input texture 848x480 2008-02-24 14:11:36.319 GLVid: Created main input texture 424x240 2008-02-24 14:11:36.320 GLVid: Created main input texture 424x240 2008-02-24 14:11:36.324 GLVid: Created main input texture 848x480 2008-02-24 14:11:36.324 GLVid: Creating osd filter. 2008-02-24 14:11:36.324 GLVid: Created fragment program osd. 2008-02-24 14:11:36.324 GLVid: Turning off deinterlacing. 2008-02-24 14:11:36.324 GLVid: Creating resize filter. 2008-02-24 14:11:36.328 GLCtx: Created frame buffer object (848x480). 2008-02-24 14:11:36.328 GLVid: Turning off deinterlacing. 2008-02-24 14:11:36.329 Display Rect left: 106, top: 0, width: 636, height: 480, aspect: 1.77778 2008-02-24 14:11:36.329 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.33333 2008-02-24 14:11:36.330 Over/underscan. V: 0.03, H: 0 2008-02-24 14:11:36.330 Display Rect left: 106, top: 0, width: 636, height: 480, aspect: 1.77778 2008-02-24 14:11:36.330 Video Rect left: 0, top: 17, width: 720, height: 541, aspect: 1.33333 2008-02-24 14:11:36.330 VDP: LoadBestPreferences(720x576, 25) 2008-02-24 14:11:36.330 NVP: LoadFilters(''..) -> 0 2008-02-24 14:11:36.331 OSD Theme Dimensions W: 640 H: 480 2008-02-24 14:11:36.370 OSDTypePositionImage::AddPosition[0](114x233 1, 1) 2008-02-24 14:11:36.370 OSDTypePositionImage::AddPosition[1](114x263 1, 1) 2008-02-24 14:11:36.370 OSDTypePositionImage::AddPosition[2](114x293 1, 1) 2008-02-24 14:11:36.370 OSDTypePositionImage::AddPosition[3](114x323 1, 1) 2008-02-24 14:11:36.370 OSDTypePositionImage::AddPosition[4](114x353 1, 1) 2008-02-24 14:11:36.374 OSD: InitCC708() -- begin 2008-02-24 14:11:36.434 OSD: InitCC708() -- end 2008-02-24 14:11:36.456 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:36.456 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:36.456 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:36.456 MythEvent: SIGNAL 1 2008-02-24 14:11:36.528 NVP: ClearAfterSeek(1) 2008-02-24 14:11:36.528 VideoOutputXv: ClearAfterSeek() 2008-02-24 14:11:36.528 VideoOutputXv: DiscardFrames(0) 2008-02-24 14:11:36.528 TV: StartPlayer(): took 291 ms to start player. 2008-02-24 14:11:36.528 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:36.529 TV: Changing from None to WatchingLiveTV 2008-02-24 14:11:36.529 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done 2008-02-24 14:11:36.529 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() 2008-02-24 14:11:36.530 write -> 30 35 QUERY_RECORDER 1[]:[]FRONTEND_READY 2008-02-24 14:11:36.531 read <- 30 2 ok 2008-02-24 14:11:36.531 Using realtime priority. 2008-02-24 14:11:36.531 VDP: GetFilteredDeint() : opengl -> 'greedyhdeint' 2008-02-24 14:11:36.531 VDP: GetFilteredDeint() : opengl -> 'greedyhdeint' 2008-02-24 14:11:36.532 rate: 25 speed: 1 skip: 1 = interval 40000 greedyhdeint: size changed from 0 x 0 -> 720 x 576 2008-02-24 14:11:36.533 Using deinterlace method greedyhdeint 2008-02-24 14:11:36.535 write -> 30 30 QUERY_RECORDER 1[]:[]GET_INPUT 2008-02-24 14:11:36.535 read <- 30 11 DVBInput #1 2008-02-24 14:11:36.635 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver? 2008-02-24 14:11:36.635 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory 2008-02-24 14:11:36.635 OpenGLVideoSync() 2008-02-24 14:11:36.635 OpenGLVideoSync: x,y -> 424, 240 2008-02-24 14:11:36.657 XMLParse::LoadTheme using /usr/share/mythtv/themes/Titivillus/ui.xml 2008-02-24 14:11:36.657 Using OpenGLVideoSync 2008-02-24 14:11:36.671 Using audio as timebase 2008-02-24 14:11:36.672 Video timing method: SGI OpenGL 2008-02-24 14:11:36.672 Refresh rate: 16666, frame interval: 40000 2008-02-24 14:11:36.731 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:36.757 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:36.757 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:36.757 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:36.757 MythEvent: SIGNAL 1 2008-02-24 14:11:37.059 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:37.059 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:37.059 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:37.059 MythEvent: SIGNAL 1 2008-02-24 14:11:37.139 write -> 27 19 QUERY_GETALLPENDING 2008-02-24 14:11:37.139 read <- 27 7 0[]:[]0 2008-02-24 14:11:37.225 IsTunable(1166) 2008-02-24 14:11:37.226 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:37.227 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:37.227 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]1 2008-02-24 14:11:37.230 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]... 2008-02-24 14:11:37.230 IsTunable(1166) -> true 2008-02-24 14:11:37.230 IsTunable(2167) 2008-02-24 14:11:37.231 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:37.232 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:37.232 IsTunable(2167) -> true 2008-02-24 14:11:37.232 IsTunable(1168) 2008-02-24 14:11:37.232 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:37.233 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:37.233 IsTunable(1168) -> true 2008-02-24 14:11:37.233 IsTunable(1169) 2008-02-24 14:11:37.233 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:37.234 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:37.234 IsTunable(1169) -> true 2008-02-24 14:11:37.234 IsTunable(1170) 2008-02-24 14:11:37.235 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:37.236 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:37.236 IsTunable(1170) -> true 2008-02-24 14:11:37.360 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:37.361 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:37.361 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:37.361 MythEvent: SIGNAL 1 2008-02-24 14:11:37.663 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:37.663 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:37.663 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:37.663 MythEvent: SIGNAL 1 2008-02-24 14:11:37.681 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:37.681 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:37.681 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:11:37.681 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:11:38.721 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:38.722 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:38.722 read <- 25 53 BACKEND_MESSAGE[]:[]QUERY_NEXT_LIVETV_DIR 1[]:[]empty 2008-02-24 14:11:38.722 MythEvent: QUERY_NEXT_LIVETV_DIR 1 2008-02-24 14:11:38.722 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:38.722 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:38.722 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:11:38.722 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:11:38.722 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:11:38.722 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:11:38.722 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:38.722 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:38.722 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:11:38.722 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:11:38.725 LiveTVChain(live-mythtv-2008-02-24T14:11:34): ReloadAll(): Added new recording 2008-02-24 14:11:38.725 LiveTVChain(live-mythtv-2008-02-24T14:11:34): SwitchTo(1) 2008-02-24 14:11:38.726 LiveTVChain(live-mythtv-2008-02-24T14:11:34): Entry@1: '1168_20080224141137' 2008-02-24 14:11:38.726 JumpToProgram(void) 2008-02-24 14:11:38.728 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE 2008-02-24 14:11:38.728 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:38.728 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:38.728 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:11:38.728 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:11:38.728 read <- 27 2 OK 2008-02-24 14:11:38.730 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group! 2008-02-24 14:11:38.730 SG(Default): FindRecordingFile: Searching for '1168_20080224141137.mpg' 2008-02-24 14:11:38.730 SG(Default): FindRecordingDir: Checking '/mnt/store' 2008-02-24 14:11:38.730 SG(Default): FindRecordingFile: Found '/mnt/store/1168_20080224141137.mpg' 2008-02-24 14:11:38.730 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/1168_20080224141137.mpg' 2008-02-24 14:11:38.731 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE 2008-02-24 14:11:38.731 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:38.731 read <- 27 2 OK 2008-02-24 14:11:38.731 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:38.731 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:11:38.731 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:11:38.732 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group! 2008-02-24 14:11:38.732 SG(Default): FindRecordingFile: Searching for '1168_20080224141137.mpg' 2008-02-24 14:11:38.732 SG(Default): FindRecordingDir: Checking '/mnt/store' 2008-02-24 14:11:38.732 SG(Default): FindRecordingFile: Found '/mnt/store/1168_20080224141137.mpg' 2008-02-24 14:11:38.732 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/1168_20080224141137.mpg' 2008-02-24 14:11:38.732 RingBuf(/mnt/store/1168_20080224141134.mpg): OpenFile(/mnt/store/1168_20080224141137.mpg, 12) 2008-02-24 14:11:38.732 RingBuf(/mnt/store/1168_20080224141137.mpg): CalcReadAheadThresh(3086680064 KB) -> threshhold(64 KB) min read(0 KB) blk size(32 KB) 2008-02-24 14:11:38.737 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:38.920 mpegts_add_stream: at pid 0x1722 with type 2 2008-02-24 14:11:38.920 mpegts_add_stream: stream #0, has id 0x1722 and codec MPEG2VIDEO, type Video at 0x84ee780 2008-02-24 14:11:38.920 mpegts_add_stream: at pid 0x1723 with type 4 2008-02-24 14:11:38.920 mpegts_add_stream: stream #1, has id 0x1723 and codec MP3, type Audio at 0x85e0370 2008-02-24 14:11:38.944 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:38.954 NVP: Waiting for prebuffer.. 1 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:39.162 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:39.187 NVP: Waiting for prebuffer.. 2 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:39.395 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:39.420 NVP: Waiting for prebuffer.. 3 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:39.627 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:39.637 NVP: Waiting for prebuffer.. 4 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:39.845 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:39.870 NVP: Waiting for prebuffer.. 5 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:39.972 AFD: Stream #0, has id 0x5922 codec id MPEG2VIDEO, type Video, bitrate 15000000 at 0x0x84ee780 2008-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() 2008-02-24 14:11:39.973 VDP: LoadBestPreferences(2048x2048, 0) 2008-02-24 14:11:39.973 VDP: LoadBestPreferences(2048x2048, 60) 2008-02-24 14:11:39.973 VDP: LoadBestPreferences(544x480, 60) 2008-02-24 14:11:39.973 Using 1 CPUs for decoding QString::setNum: Invalid format char '' 2008-02-24 14:11:39.974 AFD: Trying DirectShow for FOURCC 0x845639789 2008-02-24 14:11:39.974 AFD: Using libmpeg2 for video decoding 2008-02-24 14:11:39.974 AFD: InitVideoCodec() 0x85dbfd0 id(MPEG2VIDEO) type (Video). 2008-02-24 14:11:39.974 VideoOutputXv: InputChanged(544,480,1.33333) 'None'->'MPEG2' 2008-02-24 14:11:39.974 VDP: LoadBestPreferences(544x480, 25) 2008-02-24 14:11:39.974 VideoOutputXv: DiscardFrames(1) 2008-02-24 14:11:39.974 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:39.974 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() 2008-02-24 14:11:39.974 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done 2008-02-24 14:11:39.974 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() 2008-02-24 14:11:39.974 VideoOutputXv: DiscardFrames(1) 2008-02-24 14:11:39.974 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:39.974 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() 2008-02-24 14:11:39.975 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done 2008-02-24 14:11:39.975 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() 2008-02-24 14:11:39.980 VideoOutputXv: InitSetupBuffers() render: opengl, allowed: opengl,xv-blit,xshm,xlib 2008-02-24 14:11:39.980 GLVid: Viewport: 848x480 2008-02-24 14:11:39.982 GLVid: Created main input texture 272x240 2008-02-24 14:11:39.982 GLVid: Created main input texture 272x240 2008-02-24 14:11:39.984 GLVid: Created main input texture 544x480 2008-02-24 14:11:39.985 GLVid: Creating master filter. 2008-02-24 14:11:39.985 GLVid: Created fragment program master. 2008-02-24 14:11:39.985 GLVid: Creating resize filter. 2008-02-24 14:11:39.988 GLCtx: Created frame buffer object (544x480). 2008-02-24 14:11:39.988 GLVid: Turning off deinterlacing. 2008-02-24 14:11:39.988 GLVid: Turning off deinterlacing. 2008-02-24 14:11:39.988 Created data @0xae0654a0->0xae0c4ea2 2008-02-24 14:11:39.988 Created data @0xa9489560->0xa94e8f62 2008-02-24 14:11:39.988 Created data @0xad900030->0xad95fa32 2008-02-24 14:11:39.988 Created data @0xad95faa0->0xad9bf4a2 2008-02-24 14:11:39.988 Created data @0xad700030->0xad75fa32 2008-02-24 14:11:39.988 Created data @0xad75faa0->0xad7bf4a2 2008-02-24 14:11:39.988 Created data @0xad800030->0xad85fa32 2008-02-24 14:11:39.988 Created data @0xad85faa0->0xad8bf4a2 2008-02-24 14:11:39.988 Created data @0xad500030->0xad55fa32 2008-02-24 14:11:39.988 Created data @0xad55faa0->0xad5bf4a2 2008-02-24 14:11:39.988 Created data @0xad600030->0xad65fa32 2008-02-24 14:11:39.988 Created data @0xad65faa0->0xad6bf4a2 2008-02-24 14:11:39.988 Created data @0xad300030->0xad35fa32 2008-02-24 14:11:39.988 Created data @0xad35faa0->0xad3bf4a2 2008-02-24 14:11:39.988 Created data @0xad400030->0xad45fa32 2008-02-24 14:11:39.988 Created data @0xad45faa0->0xad4bf4a2 2008-02-24 14:11:39.989 Created data @0xad100030->0xad15fa32 2008-02-24 14:11:39.989 Created data @0xad15faa0->0xad1bf4a2 2008-02-24 14:11:39.989 Created data @0xad200030->0xad25fa32 2008-02-24 14:11:39.989 Created data @0xad25faa0->0xad2bf4a2 2008-02-24 14:11:39.989 Created data @0xacf00030->0xacf5fa32 2008-02-24 14:11:39.989 Created data @0xacf5faa0->0xacfbf4a2 2008-02-24 14:11:39.989 Created data @0xad000030->0xad05fa32 2008-02-24 14:11:39.989 Created data @0xad05faa0->0xad0bf4a2 2008-02-24 14:11:39.989 Created data @0xacd00030->0xacd5fa32 2008-02-24 14:11:39.989 Created data @0xacd5faa0->0xacdbf4a2 2008-02-24 14:11:39.989 Created data @0xace00030->0xace5fa32 2008-02-24 14:11:39.989 Created data @0xace5faa0->0xacebf4a2 2008-02-24 14:11:39.989 Created data @0xacb00030->0xacb5fa32 2008-02-24 14:11:39.989 Created data @0xacb5faa0->0xacbbf4a2 2008-02-24 14:11:39.989 Created data @0xacc00030->0xacc5fa32 2008-02-24 14:11:39.989 Created data @0xacc5faa0->0xaccbf4a2 2008-02-24 14:11:39.998 GLVid: Turning on deinterlacing. 2008-02-24 14:11:39.999 VDP: SetVideoRenderer(opengl) 2008-02-24 14:11:39.999 VDP: SetVideoRender(opengl) == GetVideoRenderer() 2008-02-24 14:11:40.003 GLVid: Created main input texture 848x480 2008-02-24 14:11:40.005 GLVid: Created main input texture 424x240 2008-02-24 14:11:40.006 GLVid: Created main input texture 424x240 2008-02-24 14:11:40.010 GLVid: Created main input texture 848x480 2008-02-24 14:11:40.010 GLVid: Creating osd filter. 2008-02-24 14:11:40.010 GLVid: Created fragment program osd. 2008-02-24 14:11:40.011 GLVid: Turning off deinterlacing. 2008-02-24 14:11:40.011 GLVid: Creating resize filter. 2008-02-24 14:11:40.015 GLCtx: Created frame buffer object (848x480). 2008-02-24 14:11:40.015 GLVid: Turning off deinterlacing. 2008-02-24 14:11:40.015 Display Rect left: 106, top: 0, width: 636, height: 480, aspect: 1.77778 2008-02-24 14:11:40.015 Video Rect left: 0, top: 14, width: 544, height: 451, aspect: 1.33333 2008-02-24 14:11:40.015 VDP: LoadBestPreferences(544x480, 29.97) 2008-02-24 14:11:40.071 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:40.109 NVP: ClearAfterSeek(1) 2008-02-24 14:11:40.109 VideoOutputXv: ClearAfterSeek() 2008-02-24 14:11:40.109 VideoOutputXv: DiscardFrames(0) 2008-02-24 14:11:40.109 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA greedyhdeint: size changed from 720 x 576 -> 544 x 480 2008-02-24 14:11:40.109 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done 2008-02-24 14:11:40.109 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() 2008-02-24 14:11:40.120 NVP: LoadFilters(''..) -> 0 2008-02-24 14:11:40.120 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan 2008-02-24 14:11:40.121 AFD: Using libmpeg2 for video decoding 2008-02-24 14:11:40.121 AFD: Looking for decoder for MPEG2VIDEO 2008-02-24 14:11:40.121 AFD: Opened codec 0x85dbfd0, id(MPEG2VIDEO) type(Video) 2008-02-24 14:11:40.121 AFD: Stream #1, has id 0x5923 codec id MP3, type Audio, bitrate 160000 at 0x0x85e0370 2008-02-24 14:11:40.121 AFD: codec MP3 has 2 channels 2008-02-24 14:11:40.121 AFD: Looking for decoder for MP3 2008-02-24 14:11:40.121 AFD: Opened codec 0x85fbba0, id(MP3) type(Audio) 2008-02-24 14:11:40.121 AFD: Audio Track #1 is A/V stream #1 and has 2 channels in the English language(6647399). 2008-02-24 14:11:40.136 NVP: Waiting for prebuffer.. 6 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:40.161 RingBuf(/mnt/store/1168_20080224141137.mpg): CalcReadAheadThresh(2920108280 KB) -> threshhold(64 KB) min read(0 KB) blk size(32 KB) 2008-02-24 14:11:40.161 AFD: Selected track 1: English MP2 2ch (A/V Stream #1) 2008-02-24 14:11:40.162 AFD: Initializing audio parms from audio track #1 2008-02-24 14:11:40.162 AFD: Audio format changed from id(NONE) -1Hz -1ch 0bps ; id(NONE) -1Hz -1ch 0bps to id( MP3) 48000Hz 2ch 16bps ; id( MP3) 48000Hz 2ch 16bps 2008-02-24 14:11:40.164 AO: Killing AudioOutputDSP 2008-02-24 14:11:40.164 Opening audio device 'default'. ch 2(2) sr 48000 2008-02-24 14:11:40.164 Opening ALSA audio device 'default'. 2008-02-24 14:11:40.192 in SetParameters(format=2, channels=2, rate=48000, buffer_time=100000, period_time=25000) 2008-02-24 14:11:40.192 get_buffer_size returned 5120 2008-02-24 14:11:40.192 set_period_time_near returned 21333 2008-02-24 14:11:40.192 get_period_size returned 1024 2008-02-24 14:11:40.193 Opening mixer default 2008-02-24 14:11:40.194 Setting Master volume to 70 2008-02-24 14:11:40.194 Mixer unable to find control Master 2008-02-24 14:11:40.194 Setting Master volume to 70 2008-02-24 14:11:40.194 Mixer unable to find control Master 2008-02-24 14:11:40.194 Setting PCM volume to 100 2008-02-24 14:11:40.194 Volume range is 0 to 255, mult=0.392157 2008-02-24 14:11:40.194 channel 0 vol set to 255 2008-02-24 14:11:40.194 Setting PCM volume to 100 2008-02-24 14:11:40.194 Volume range is 0 to 255, mult=0.392157 2008-02-24 14:11:40.195 channel 1 vol set to 255 2008-02-24 14:11:40.195 Volume range is 0 to 255, mult=0.392157 2008-02-24 14:11:40.195 AO: Audio fragment size: 6400 2008-02-24 14:11:40.195 AO: Audio Stretch Factor: 1 2008-02-24 14:11:40.195 Audio Codec Used: not set 2008-02-24 14:11:40.197 AO: Ending reconfigure 2008-02-24 14:11:40.197 AO: kickoffOutputAudioLoop: pid = 907 2008-02-24 14:11:40.197 NVP: Enabling Audio 2008-02-24 14:11:40.197 AO: OutputAudioLoop: Play Event 2008-02-24 14:11:40.197 AO: no change exiting 2008-02-24 14:11:40.197 Dec: Trying to select track (w/lang) 2008-02-24 14:11:40.197 Dec: Selecting first track 2008-02-24 14:11:40.197 Dec: Selected track #1 in the Unknown language(0) 2008-02-24 14:11:40.197 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0) 2008-02-24 14:11:40.198 SyncPositionMap watchingrecording, from DB: 0 entries 2008-02-24 14:11:40.198 write -> 30 39 QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN 2008-02-24 14:11:40.198 read <- 30 8 0[]:[]40 2008-02-24 14:11:40.198 Filling position map from 0 to 40 2008-02-24 14:11:40.198 write -> 30 51 QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]0[]:[]40 2008-02-24 14:11:40.198 read <- 30 82 0[]:[]0[]:[]0[]:[]1880[]:[]0[]:[]10[]:[]0[]:[]282940[]:[]0[]:[]20... 2008-02-24 14:11:40.198 Position map filled from Encoder to: 20 2008-02-24 14:11:40.198 SyncPositionMap watchingrecording total: 3 entries 2008-02-24 14:11:40.198 SyncPositionMap, new totframes: 20, new length: 0, posMap size: 3 2008-02-24 14:11:40.198 Input #0, mpegts, from '/mnt/store/1168_20080224141137.mpg': 2008-02-24 14:11:40.198 Duration: N/A, start: 32458.536800, bitrate: 15160 kb/s 2008-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) 2008-02-24 14:11:40.199 Stream #0.1[0x1723](eng), 1/90000: Audio: mp2, 48000 Hz, stereo, 160 kb/s 2008-02-24 14:11:40.199 AFD: Partial position map found 2008-02-24 14:11:40.199 AFD: Successfully opened decoder for file: "/mnt/store/1168_20080224141137.mpg". novideo(0) 2008-02-24 14:11:40.199 NVP: DoPlay: rate: 29.97 speed: 1 skip: 1 => new interval 33366 2008-02-24 14:11:40.199 Set video sync frame interval to 33366 2008-02-24 14:11:40.199 NVP: Stretch Factor 1, allow passthru 2008-02-24 14:11:40.205 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:40.220 AO: Pause 1 2008-02-24 14:11:40.220 NVP: Waiting for prebuffer.. 7 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:40.222 AO: OutputAudioLoop: audio paused 2008-02-24 14:11:40.370 NVP: Waiting for prebuffer.. 8 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:40.409 RingBuf(/mnt/store/1168_20080224141137.mpg): CalcReadAheadThresh(0 KB) -> threshhold(64 KB) min read(0 KB) blk size(32 KB) 2008-02-24 14:11:40.409 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1) 2008-02-24 14:11:40.409 SyncPositionMap watchingrecording, from DB: 3 entries 2008-02-24 14:11:40.409 write -> 30 39 QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN 2008-02-24 14:11:40.410 read <- 30 8 0[]:[]44 2008-02-24 14:11:40.410 Filling position map from 21 to 44 2008-02-24 14:11:40.410 write -> 30 52 QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]21[]:[]44 2008-02-24 14:11:40.410 read <- 30 2 ok 2008-02-24 14:11:40.410 Position map filled from Encoder to: 20 2008-02-24 14:11:40.410 SyncPositionMap watchingrecording total: 3 entries 2008-02-24 14:11:40.519 NVP: Waiting for prebuffer.. 9 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:40.653 NVP: Prebuffer wait timed out 10 times. 2008-02-24 14:11:40.669 NVP: Waiting for prebuffer.. 0 ALLLAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:40.819 NVP: Waiting for prebuffer.. 1 ALLLAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:40.969 NVP: Waiting for prebuffer.. 2 AAuLLAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:41.119 NVP: Waiting for prebuffer.. 3 AAUUUUUUUUuLLAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:41.269 NVP: Waiting for prebuffer.. 4 AAUUUUUUUUuLLAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:41.286 NVP: progressive frame seen after 2 interlaced frames 2008-02-24 14:11:41.289 AO: Pause 0 2008-02-24 14:11:41.291 AO: OutputAudioLoop: Play Event 2008-02-24 14:11:41.336 GLVid: Turning off deinterlacing. 2008-02-24 14:11:41.336 Disabled deinterlacing 2008-02-24 14:11:41.369 NVP: interlaced frame seen after 3 progressive frames 2008-02-24 14:11:41.369 GLVid: Turning on deinterlacing. 2008-02-24 14:11:41.369 Enabled deinterlacing 2008-02-24 14:11:41.436 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:41.502 GLVid: Turning off deinterlacing. 2008-02-24 14:11:41.503 Disabled deinterlacing 2008-02-24 14:11:41.536 NVP: interlaced frame seen after 3 progressive frames 2008-02-24 14:11:41.536 GLVid: Turning on deinterlacing. 2008-02-24 14:11:41.536 Enabled deinterlacing 2008-02-24 14:11:41.602 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:41.669 GLVid: Turning off deinterlacing. 2008-02-24 14:11:41.669 Disabled deinterlacing 2008-02-24 14:11:41.702 NVP: interlaced frame seen after 3 progressive frames 2008-02-24 14:11:41.702 GLVid: Turning on deinterlacing. 2008-02-24 14:11:41.702 Enabled deinterlacing 2008-02-24 14:11:41.769 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:41.836 GLVid: Turning off deinterlacing. 2008-02-24 14:11:41.836 Disabled deinterlacing 2008-02-24 14:11:41.869 NVP: interlaced frame seen after 3 progressive frames 2008-02-24 14:11:41.869 GLVid: Turning on deinterlacing. 2008-02-24 14:11:41.869 Enabled deinterlacing 2008-02-24 14:11:41.936 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:42.002 GLVid: Turning off deinterlacing. 2008-02-24 14:11:42.002 Disabled deinterlacing 2008-02-24 14:11:42.069 NVP: interlaced frame seen after 4 progressive frames 2008-02-24 14:11:42.069 GLVid: Turning on deinterlacing. 2008-02-24 14:11:42.069 Enabled deinterlacing 2008-02-24 14:11:42.135 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:42.202 GLVid: Turning off deinterlacing. 2008-02-24 14:11:42.202 Disabled deinterlacing 2008-02-24 14:11:42.518 NVP: interlaced frame seen after 11 progressive frames 2008-02-24 14:11:42.519 GLVid: Turning on deinterlacing. 2008-02-24 14:11:42.519 Enabled deinterlacing 2008-02-24 14:11:42.568 NVP: prebuffering pause 2008-02-24 14:11:42.569 AO: Pause 1 2008-02-24 14:11:42.569 NVP: Waiting for prebuffer.. 0 AAAAAAAAAuLLAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:42.573 AO: OutputAudioLoop: audio paused 2008-02-24 14:11:42.718 NVP: Waiting for prebuffer.. 1 AAAAAAAAAUUUUUUUUUUuLLAAAAAAAAA 2008-02-24 14:11:42.772 AO: Pause 0 2008-02-24 14:11:42.775 AO: OutputAudioLoop: Play Event 2008-02-24 14:11:42.785 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:42.818 NVP: interlaced frame seen after 1 progressive frames 2008-02-24 14:11:42.885 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:42.952 GLVid: Turning off deinterlacing. 2008-02-24 14:11:42.952 Disabled deinterlacing 2008-02-24 14:11:42.985 NVP: interlaced frame seen after 3 progressive frames 2008-02-24 14:11:42.985 GLVid: Turning on deinterlacing. 2008-02-24 14:11:42.985 Enabled deinterlacing 2008-02-24 14:11:43.002 IsTunable(4562) 2008-02-24 14:11:43.002 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:43.003 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:43.004 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]1 2008-02-24 14:11:43.006 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]... 2008-02-24 14:11:43.006 write -> 27 42 QUERY_RECORDER 2[]:[]GET_FREE_INPUTS[]:[]1 2008-02-24 14:11:43.007 read <- 27 32 DVBInput[]:[]4[]:[]4[]:[]2[]:[]0 2008-02-24 14:11:43.007 IsTunable(4562) -> true 2008-02-24 14:11:43.007 IsTunable(4563) 2008-02-24 14:11:43.007 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:43.008 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:43.008 IsTunable(4563) -> true 2008-02-24 14:11:43.008 IsTunable(4621) 2008-02-24 14:11:43.008 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:43.009 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:43.009 IsTunable(4621) -> true 2008-02-24 14:11:43.009 IsTunable(2100) 2008-02-24 14:11:43.010 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:43.010 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:43.010 IsTunable(2100) -> true 2008-02-24 14:11:43.010 IsTunable(1101) 2008-02-24 14:11:43.011 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:43.011 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:43.012 IsTunable(1101) -> true 2008-02-24 14:11:43.058 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:43.118 GLVid: Turning off deinterlacing. 2008-02-24 14:11:43.118 Disabled deinterlacing 2008-02-24 14:11:43.152 NVP: interlaced frame seen after 3 progressive frames 2008-02-24 14:11:43.152 GLVid: Turning on deinterlacing. 2008-02-24 14:11:43.152 Enabled deinterlacing 2008-02-24 14:11:43.218 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:43.285 GLVid: Turning off deinterlacing. 2008-02-24 14:11:43.285 Disabled deinterlacing 2008-02-24 14:11:43.318 NVP: interlaced frame seen after 3 progressive frames 2008-02-24 14:11:43.318 GLVid: Turning on deinterlacing. 2008-02-24 14:11:43.318 Enabled deinterlacing 2008-02-24 14:11:43.395 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:43.451 GLVid: Turning off deinterlacing. 2008-02-24 14:11:43.452 Disabled deinterlacing 2008-02-24 14:11:43.462 IsTunable(4563) 2008-02-24 14:11:43.463 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:43.464 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:43.464 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]1 2008-02-24 14:11:43.466 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]... 2008-02-24 14:11:43.466 write -> 27 42 QUERY_RECORDER 2[]:[]GET_FREE_INPUTS[]:[]1 2008-02-24 14:11:43.466 read <- 27 32 DVBInput[]:[]4[]:[]4[]:[]2[]:[]0 2008-02-24 14:11:43.467 IsTunable(4563) -> true 2008-02-24 14:11:43.467 IsTunable(4621) 2008-02-24 14:11:43.467 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:43.468 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:43.468 IsTunable(4621) -> true 2008-02-24 14:11:43.468 IsTunable(2100) 2008-02-24 14:11:43.468 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:43.469 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:43.469 IsTunable(2100) -> true 2008-02-24 14:11:43.469 IsTunable(1101) 2008-02-24 14:11:43.469 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:43.470 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:43.470 IsTunable(1101) -> true 2008-02-24 14:11:43.470 IsTunable(2102) 2008-02-24 14:11:43.470 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:43.471 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:43.471 IsTunable(2102) -> true 2008-02-24 14:11:43.503 NVP: interlaced frame seen after 3 progressive frames 2008-02-24 14:11:43.503 GLVid: Turning on deinterlacing. 2008-02-24 14:11:43.504 Enabled deinterlacing 2008-02-24 14:11:43.551 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:43.618 GLVid: Turning off deinterlacing. 2008-02-24 14:11:43.618 Disabled deinterlacing 2008-02-24 14:11:43.785 NVP: interlaced frame seen after 7 progressive frames 2008-02-24 14:11:43.785 GLVid: Turning on deinterlacing. 2008-02-24 14:11:43.785 Enabled deinterlacing 2008-02-24 14:11:43.934 NVP: progressive frame seen after 6 interlaced frames 2008-02-24 14:11:44.001 GLVid: Turning off deinterlacing. 2008-02-24 14:11:44.001 Disabled deinterlacing 2008-02-24 14:11:44.201 NVP: interlaced frame seen after 7 progressive frames 2008-02-24 14:11:44.201 GLVid: Turning on deinterlacing. 2008-02-24 14:11:44.201 Enabled deinterlacing 2008-02-24 14:11:44.268 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:44.301 NVP: interlaced frame seen after 1 progressive frames 2008-02-24 14:11:44.334 NVP: progressive frame seen after 3 interlaced frames 2008-02-24 14:11:44.368 NVP: interlaced frame seen after 1 progressive frames 2008-02-24 14:11:44.434 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:44.501 GLVid: Turning off deinterlacing. 2008-02-24 14:11:44.501 Disabled deinterlacing 2008-02-24 14:11:44.767 NVP: interlaced frame seen after 9 progressive frames 2008-02-24 14:11:44.767 GLVid: Turning on deinterlacing. 2008-02-24 14:11:44.767 Enabled deinterlacing 2008-02-24 14:11:44.834 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:44.901 GLVid: Turning off deinterlacing. 2008-02-24 14:11:44.901 Disabled deinterlacing 'video_output' mean = '36313.26', std. dev. = '19162.17', fps = '27.54' 2008-02-24 14:11:44.934 NVP: interlaced frame seen after 3 progressive frames 2008-02-24 14:11:44.934 GLVid: Turning on deinterlacing. 2008-02-24 14:11:44.934 Enabled deinterlacing 2008-02-24 14:11:45.001 NVP: progressive frame seen after 4 interlaced frames 2008-02-24 14:11:45.067 GLVid: Turning off deinterlacing. 2008-02-24 14:11:45.067 Disabled deinterlacing 2008-02-24 14:11:45.470 IsTunable(4563) 2008-02-24 14:11:45.470 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:45.471 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:45.471 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]1 2008-02-24 14:11:45.473 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]... 2008-02-24 14:11:45.473 write -> 27 42 QUERY_RECORDER 2[]:[]GET_FREE_INPUTS[]:[]1 2008-02-24 14:11:45.473 read <- 27 32 DVBInput[]:[]4[]:[]4[]:[]2[]:[]0 2008-02-24 14:11:45.474 IsTunable(4563) -> true 2008-02-24 14:11:45.474 IsTunable(4621) 2008-02-24 14:11:45.474 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:45.474 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:45.475 IsTunable(4621) -> true 2008-02-24 14:11:45.475 IsTunable(2100) 2008-02-24 14:11:45.475 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:45.476 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:45.476 IsTunable(2100) -> true 2008-02-24 14:11:45.476 IsTunable(1101) 2008-02-24 14:11:45.476 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:45.477 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:45.477 IsTunable(1101) -> true 2008-02-24 14:11:45.477 IsTunable(2102) 2008-02-24 14:11:45.477 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:45.478 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:45.478 IsTunable(2102) -> true 2008-02-24 14:11:46.947 IsTunable(4563) 2008-02-24 14:11:46.947 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:46.948 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:46.949 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]1 2008-02-24 14:11:46.951 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]... 2008-02-24 14:11:46.952 write -> 27 42 QUERY_RECORDER 2[]:[]GET_FREE_INPUTS[]:[]1 2008-02-24 14:11:46.952 read <- 27 32 DVBInput[]:[]4[]:[]4[]:[]2[]:[]0 2008-02-24 14:11:46.952 IsTunable(4563) -> true 2008-02-24 14:11:46.952 IsTunable(4621) 2008-02-24 14:11:46.953 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:46.953 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:46.954 IsTunable(4621) -> true 2008-02-24 14:11:46.954 IsTunable(2100) 2008-02-24 14:11:46.954 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:46.955 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:46.955 IsTunable(2100) -> true 2008-02-24 14:11:46.955 IsTunable(1101) 2008-02-24 14:11:46.955 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:46.956 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:46.956 IsTunable(1101) -> true 2008-02-24 14:11:46.956 IsTunable(2102) 2008-02-24 14:11:46.956 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:46.957 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:46.957 IsTunable(2102) -> true 2008-02-24 14:11:47.131 IsTunable(4621) 2008-02-24 14:11:47.133 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:47.135 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:47.137 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]1 2008-02-24 14:11:47.139 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]... 2008-02-24 14:11:47.140 write -> 27 42 QUERY_RECORDER 2[]:[]GET_FREE_INPUTS[]:[]1 2008-02-24 14:11:47.140 read <- 27 32 DVBInput[]:[]4[]:[]4[]:[]2[]:[]0 2008-02-24 14:11:47.140 IsTunable(4621) -> true 2008-02-24 14:11:47.141 TV: CommitQueuedInput() livetv(1) qchannum(62_1) qchanid(4621) 2008-02-24 14:11:47.141 TV: ChangeChannel(4621, '62_1') 2008-02-24 14:11:47.142 write -> 30 48 QUERY_RECORDER 1[]:[]SHOULD_SWITCH_CARD[]:[]4621 2008-02-24 14:11:47.143 read <- 30 1 1 2008-02-24 14:11:47.143 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:47.144 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:47.144 write -> 27 27 GET_RECORDER_FROM_NUM[]:[]2 2008-02-24 14:11:47.144 read <- 27 21 192.168.0.50[]:[]6543 2008-02-24 14:11:47.144 TV: SwitchCards(4621,'62_1',0) 2008-02-24 14:11:47.144 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:11:47.145 read <- 27 13 2[]:[]5[]:[]7 2008-02-24 14:11:47.145 write -> 27 27 GET_RECORDER_FROM_NUM[]:[]2 2008-02-24 14:11:47.145 read <- 27 21 192.168.0.50[]:[]6543 2008-02-24 14:11:47.145 NVP: Changing speed to 0 2008-02-24 14:11:47.145 rate: 29.97 speed: 1 skip: 1 = interval 33366 2008-02-24 14:11:47.145 Set video sync frame interval to 33366 2008-02-24 14:11:47.152 write -> 30 32 QUERY_RECORDER 1[]:[]STOP_LIVETV 2008-02-24 14:11:47.155 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:47.155 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:47.155 read <- 25 48 BACKEND_MESSAGE[]:[]DONE_RECORDING 1 8[]:[]empty 2008-02-24 14:11:47.155 MythEvent: DONE_RECORDING 1 8 2008-02-24 14:11:47.189 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:47.189 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:47.189 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:11:47.189 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:11:47.191 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:47.191 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:47.191 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:11:47.191 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:11:47.191 read <- 30 2 ok 2008-02-24 14:11:47.198 NVP: Exited decoder loop. 2008-02-24 14:11:47.216 ~OpenGLVideoSync() -- begin 2008-02-24 14:11:47.216 ~OpenGLVideoSync() -- middle 2008-02-24 14:11:47.216 ~OpenGLVideoSync() -- end 2008-02-24 14:11:47.216 VideoOutputXv: dtor 2008-02-24 14:11:47.216 VideoOutputXv: DiscardFrames(1) 2008-02-24 14:11:47.217 VideoBuffers::DiscardFrames(1): AAAAAAAAUAAUAAUUUUUuUULUUALAAAA 2008-02-24 14:11:47.217 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() 2008-02-24 14:11:47.217 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done 2008-02-24 14:11:47.217 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() X Error: BadDrawable (invalid Pixmap or Window parameter) 9 Major opcode: 70 Minor opcode: 0 Resource id: 0x1e00044 2008-02-24 14:11:47.253 AO: Killing AudioOutputDSP 2008-02-24 14:11:47.257 AO: OutputAudioLoop: Stop Event 2008-02-24 14:11:47.257 AO: kickoffOutputAudioLoop exiting 2008-02-24 14:11:47.281 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE 2008-02-24 14:11:47.281 read <- 27 2 OK 2008-02-24 14:11:47.281 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:47.281 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:47.282 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:11:47.282 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:11:47.294 Closing PAT Filter: pid=0x0 2008-02-24 14:11:47.295 Closing PMT Filter: pid=0x21 2008-02-24 14:11:47.295 MythSocket(82d8670:30): DownRef: -1 2008-02-24 14:11:47.295 MythSocket(82d8670:30): state change Connected -> Idle 2008-02-24 14:11:47.295 MythSocket(82d8670:-1): delete socket 2008-02-24 14:11:47.297 MythSocket(a77777b8:30): new socket 2008-02-24 14:11:47.297 MythSocket(a77777b8:30): attempting connect() to (192.168.0.50:6543) 2008-02-24 14:11:47.297 MythSocket(a77777b8:30): state change Idle -> Connected 2008-02-24 14:11:47.298 write -> 30 21 MYTH_PROTO_VERSION 40 2008-02-24 14:11:47.298 read <- 30 13 ACCEPT[]:[]40 2008-02-24 14:11:47.298 Using protocol version 40 2008-02-24 14:11:47.298 write -> 30 21 ANN Playback mythtv 0 2008-02-24 14:11:47.301 read <- 30 2 OK 2008-02-24 14:11:47.301 write -> 30 84 QUERY_RECORDER 2[]:[]SPAWN_LIVETV[]:[]live-mythtv-2008-02-24T14:1... 2008-02-24 14:11:48.868 read <- 30 2 ok 2008-02-24 14:11:48.869 LiveTVChain(live-mythtv-2008-02-24T14:11:34): ReloadAll(): Added new recording 2008-02-24 14:11:48.870 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:48.871 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:48.871 read <- 25 53 BACKEND_MESSAGE[]:[]QUERY_NEXT_LIVETV_DIR 2[]:[]empty 2008-02-24 14:11:48.871 MythEvent: QUERY_NEXT_LIVETV_DIR 2 2008-02-24 14:11:48.871 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:11:48.871 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:11:48.871 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:11:48.871 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:11:48.871 read <- 25 541 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:48.871 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group! 2008-02-24 14:11:48.871 MythEvent: SIGNAL 2 2008-02-24 14:11:48.871 SG(Default): FindRecordingFile: Searching for '4621_20080224141147.mpg' 2008-02-24 14:11:48.872 SG(Default): FindRecordingDir: Checking '/mnt/store' 2008-02-24 14:11:48.872 SG(Default): FindRecordingFile: Found '/mnt/store/4621_20080224141147.mpg' 2008-02-24 14:11:48.872 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/4621_20080224141147.mpg' 2008-02-24 14:11:48.872 RingBuf(/mnt/store/4621_20080224141147.mpg): OpenFile(/mnt/store/4621_20080224141147.mpg, 12) 2008-02-24 14:11:48.918 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:48.918 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:48.918 read <- 25 541 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:48.918 MythEvent: SIGNAL 2 2008-02-24 14:11:48.970 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:48.970 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:48.970 read <- 25 541 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:48.970 MythEvent: SIGNAL 2 2008-02-24 14:11:49.021 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:49.021 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:49.021 read <- 25 541 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:49.021 MythEvent: SIGNAL 2 2008-02-24 14:11:49.073 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:49.073 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:49.073 read <- 25 629 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:49.073 MythEvent: SIGNAL 2 2008-02-24 14:11:49.124 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:49.124 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:49.125 read <- 25 629 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:49.125 MythEvent: SIGNAL 2 2008-02-24 14:11:49.175 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:49.175 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:49.175 read <- 25 629 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:49.175 MythEvent: SIGNAL 2 2008-02-24 14:11:49.227 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:49.227 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:49.227 read <- 25 629 BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:11:49.227 MythEvent: SIGNAL 2 2008-02-24 14:11:49.233 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:49.233 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:49.233 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:11:49.233 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:11:50.270 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:50.270 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:50.270 read <- 25 53 BACKEND_MESSAGE[]:[]QUERY_NEXT_LIVETV_DIR 2[]:[]empty 2008-02-24 14:11:50.270 MythEvent: QUERY_NEXT_LIVETV_DIR 2 2008-02-24 14:11:50.270 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:50.270 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:50.270 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:11:50.270 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:11:50.270 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:11:50.270 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:11:50.270 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:50.270 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:50.270 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:11:50.270 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:11:55.372 RingBuf(/mnt/store/4621_20080224141147.mpg): Invalid file (fd -1) when opening '/mnt/store/4621_20080224141147.mpg'. 2008-02-24 14:11:55.372 RingBuf(/mnt/store/4621_20080224141147.mpg): CalcReadAheadThresh(3050971124 KB) -> threshhold(64 KB) min read(0 KB) blk size(32 KB) 2008-02-24 14:11:55.372 write -> 30 33 QUERY_RECORDER 2[]:[]IS_RECORDING 2008-02-24 14:11:55.372 read <- 30 1 1 2008-02-24 14:11:55.372 write -> 30 33 QUERY_RECORDER 2[]:[]IS_RECORDING 2008-02-24 14:11:55.372 read <- 30 1 1 2008-02-24 14:11:55.372 TV: StartRecorder(): took 0 ms to start recorder. 2008-02-24 14:11:55.372 write -> 30 34 QUERY_RECORDER 2[]:[]GET_FRAMERATE 2008-02-24 14:11:55.372 read <- 30 5 29.97 2008-02-24 14:11:55.374 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group! 2008-02-24 14:11:55.375 SG(Default): FindRecordingFile: Searching for '4621_20080224141147.mpg' 2008-02-24 14:11:55.375 SG(Default): FindRecordingDir: Checking '/mnt/store' 2008-02-24 14:11:55.375 SG(Default): FindRecordingFile: Found '/mnt/store/4621_20080224141147.mpg' 2008-02-24 14:11:55.375 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/4621_20080224141147.mpg' 2008-02-24 14:11:55.375 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE 2008-02-24 14:11:55.375 read <- 27 2 OK 2008-02-24 14:11:55.375 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:55.375 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:55.376 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:11:55.376 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:11:55.382 detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan 2008-02-24 14:11:55.382 NVP: Disabling Audio, params(-1,2,44100) 2008-02-24 14:11:55.382 VideoOutput: Allowed renderers: opengl,xv-blit,xshm,xlib 2008-02-24 14:11:55.382 VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl 2008-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() 2008-02-24 14:11:55.384 VDP: LoadBestPreferences(2048x2048, 0) 2008-02-24 14:11:55.384 VDP: LoadBestPreferences(2048x2048, 60) 2008-02-24 14:11:55.384 VDP: LoadBestPreferences(720x576, 60) 2008-02-24 14:11:55.384 VideoOutput: Preferred renderer: opengl 2008-02-24 14:11:55.384 VideoOutput: Trying video renderer: opengl 2008-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() 2008-02-24 14:11:55.385 VDP: LoadBestPreferences(2048x2048, 0) 2008-02-24 14:11:55.385 VDP: LoadBestPreferences(2048x2048, 60) 2008-02-24 14:11:55.390 VideoOutputXv: ctor 2008-02-24 14:11:55.392 XOff: 0, YOff: 0 2008-02-24 14:11:55.392 VDP: LoadBestPreferences(720x576, 60) 2008-02-24 14:11:55.392 Display Rect left: 0, top: 0, width: 848, height: 480, aspect: 1.33333 2008-02-24 14:11:55.392 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.33333 2008-02-24 14:11:55.394 VideoOutputXv: Pixel dimensions: Screen 848x480, window 848x480 2008-02-24 14:11:55.394 VideoOutputXv: Estimated display dimensions: 287x163 mm Aspect: 1.76074 2008-02-24 14:11:55.394 VideoOutputXv: Estimated window dimensions: 287x163 mm Aspect: 1.76074 2008-02-24 14:11:55.394 VideoOutputXv: InitSetupBuffers() render: opengl, allowed: opengl,xv-blit,xshm,xlib 2008-02-24 14:11:55.396 GLCtx: Created window and context. 2008-02-24 14:11:55.416 GLVid: Viewport: 848x576 2008-02-24 14:11:55.417 GLVid: Created main input texture 360x288 2008-02-24 14:11:55.418 GLVid: Created main input texture 360x288 2008-02-24 14:11:55.422 GLVid: Created main input texture 720x576 2008-02-24 14:11:55.422 GLVid: Creating master filter. 2008-02-24 14:11:55.422 GLVid: Created fragment program master. 2008-02-24 14:11:55.422 GLVid: Creating resize filter. 2008-02-24 14:11:55.428 GLCtx: Created frame buffer object (720x576). 2008-02-24 14:11:55.428 GLVid: Turning off deinterlacing. 2008-02-24 14:11:55.428 GLVid: Turning off deinterlacing. 2008-02-24 14:11:55.429 Created data @0xaa500030->0xaa597e32 2008-02-24 14:11:55.429 Created data @0xacd00030->0xacd97e32 2008-02-24 14:11:55.429 Created data @0xad000030->0xad097e32 2008-02-24 14:11:55.429 Created data @0xad100030->0xad197e32 2008-02-24 14:11:55.429 Created data @0xacf00030->0xacf97e32 2008-02-24 14:11:55.429 Created data @0xacb00030->0xacb97e32 2008-02-24 14:11:55.429 Created data @0xad300030->0xad397e32 2008-02-24 14:11:55.429 Created data @0xad900030->0xad997e32 2008-02-24 14:11:55.429 Created data @0xad800030->0xad897e32 2008-02-24 14:11:55.429 Created data @0xad400030->0xad497e32 2008-02-24 14:11:55.429 Created data @0xad500030->0xad597e32 2008-02-24 14:11:55.429 Created data @0xad200030->0xad297e32 2008-02-24 14:11:55.429 Created data @0xace00030->0xace97e32 2008-02-24 14:11:55.429 Created data @0xad600030->0xad697e32 2008-02-24 14:11:55.429 Created data @0xad700030->0xad797e32 2008-02-24 14:11:55.429 Created data @0xacc00030->0xacc97e32 2008-02-24 14:11:55.429 Created data @0xaca00030->0xaca97e32 2008-02-24 14:11:55.429 Created data @0xa9300030->0xa9397e32 2008-02-24 14:11:55.429 Created data @0xaa200030->0xaa297e32 2008-02-24 14:11:55.429 Created data @0xab700030->0xab797e32 2008-02-24 14:11:55.429 Created data @0xab900030->0xab997e32 2008-02-24 14:11:55.429 Created data @0xaa800030->0xaa897e32 2008-02-24 14:11:55.429 Created data @0xaaa00030->0xaaa97e32 2008-02-24 14:11:55.429 Created data @0xaaf00030->0xaaf97e32 2008-02-24 14:11:55.429 Created data @0xab200030->0xab297e32 2008-02-24 14:11:55.430 Created data @0xab500030->0xab597e32 2008-02-24 14:11:55.430 Created data @0xae121020->0xae1b8e22 2008-02-24 14:11:55.430 Created data @0xac868020->0xac8ffe22 2008-02-24 14:11:55.430 Created data @0xac7d0020->0xac867e22 2008-02-24 14:11:55.430 Created data @0xac738020->0xac7cfe22 2008-02-24 14:11:55.430 Created data @0xac6a0020->0xac737e22 2008-02-24 14:11:55.430 Created data @0xac608020->0xac69fe22 2008-02-24 14:11:55.441 VDP: GetFilteredDeint() : -> 'greedyhdeint' greedyhdeint: size changed from 0 x 0 -> 720 x 576 2008-02-24 14:11:55.443 Using deinterlace method greedyhdeint 2008-02-24 14:11:55.443 VDP: SetVideoRenderer(opengl) 2008-02-24 14:11:55.443 VDP: SetVideoRender(opengl) == GetVideoRenderer() 2008-02-24 14:11:55.924 GLVid: Created main input texture 848x480 2008-02-24 14:11:55.925 GLVid: Created main input texture 424x240 2008-02-24 14:11:55.926 GLVid: Created main input texture 424x240 2008-02-24 14:11:55.930 GLVid: Created main input texture 848x480 2008-02-24 14:11:55.930 GLVid: Creating osd filter. 2008-02-24 14:11:55.930 GLVid: Created fragment program osd. 2008-02-24 14:11:55.930 GLVid: Turning off deinterlacing. 2008-02-24 14:11:55.930 GLVid: Creating resize filter. 2008-02-24 14:11:55.934 GLCtx: Created frame buffer object (848x480). 2008-02-24 14:11:55.934 GLVid: Turning off deinterlacing. 2008-02-24 14:11:55.935 Display Rect left: 106, top: 0, width: 636, height: 480, aspect: 1.77778 2008-02-24 14:11:55.935 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.33333 2008-02-24 14:11:55.936 Over/underscan. V: 0.03, H: 0 2008-02-24 14:11:55.936 Display Rect left: 106, top: 0, width: 636, height: 480, aspect: 1.77778 2008-02-24 14:11:55.936 Video Rect left: 0, top: 17, width: 720, height: 541, aspect: 1.33333 2008-02-24 14:11:55.936 VDP: LoadBestPreferences(720x576, 25) 2008-02-24 14:11:55.936 NVP: LoadFilters(''..) -> 0 2008-02-24 14:11:55.937 OSD Theme Dimensions W: 640 H: 480 2008-02-24 14:11:55.968 OSDTypePositionImage::AddPosition[0](114x233 1, 1) 2008-02-24 14:11:55.968 OSDTypePositionImage::AddPosition[1](114x263 1, 1) 2008-02-24 14:11:55.968 OSDTypePositionImage::AddPosition[2](114x293 1, 1) 2008-02-24 14:11:55.968 OSDTypePositionImage::AddPosition[3](114x323 1, 1) 2008-02-24 14:11:55.968 OSDTypePositionImage::AddPosition[4](114x353 1, 1) 2008-02-24 14:11:55.972 OSD: InitCC708() -- begin 2008-02-24 14:11:56.028 OSD: InitCC708() -- end 2008-02-24 14:11:56.120 NVP: ClearAfterSeek(1) 2008-02-24 14:11:56.120 VideoOutputXv: ClearAfterSeek() 2008-02-24 14:11:56.120 VideoOutputXv: DiscardFrames(0) 2008-02-24 14:11:56.120 TV: StartPlayer(): took 738 ms to start player. 2008-02-24 14:11:56.120 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:56.120 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done 2008-02-24 14:11:56.120 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() 2008-02-24 14:11:56.122 TV: UnpauseLiveTV() 2008-02-24 14:11:56.122 New DB connection, total: 4 2008-02-24 14:11:56.122 Using realtime priority. 2008-02-24 14:11:56.122 VDP: GetFilteredDeint() : opengl -> 'greedyhdeint' 2008-02-24 14:11:56.123 Connected to database 'mythconverg' at host: localhost 2008-02-24 14:11:56.123 rate: 25 speed: 1 skip: 1 = interval 40000 2008-02-24 14:11:56.123 VDP: GetFilteredDeint() : opengl -> 'greedyhdeint' 2008-02-24 14:11:56.123 LiveTVChain(live-mythtv-2008-02-24T14:11:34): ReloadAll(): Added new recording greedyhdeint: size changed from 0 x 0 -> 720 x 576 2008-02-24 14:11:56.124 Using deinterlace method greedyhdeint 2008-02-24 14:11:56.125 LiveTVChain(live-mythtv-2008-02-24T14:11:34): SwitchTo(-1) 2008-02-24 14:11:56.125 LiveTVChain(live-mythtv-2008-02-24T14:11:34): Entry@3: '4621_20080224141149' 2008-02-24 14:11:56.125 NVP: Play( 1.0, normal 1, unpause audio 0) 2008-02-24 14:11:56.125 LiveTVChain(live-mythtv-2008-02-24T14:11:34): SwitchTo(3) 2008-02-24 14:11:56.125 LiveTVChain(live-mythtv-2008-02-24T14:11:34): Entry@3: '4621_20080224141149' 2008-02-24 14:11:56.125 JumpToProgram(void) 2008-02-24 14:11:56.127 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE 2008-02-24 14:11:56.127 read <- 27 2 OK 2008-02-24 14:11:56.127 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:56.127 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:56.127 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:11:56.127 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:11:56.128 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group! 2008-02-24 14:11:56.128 SG(Default): FindRecordingFile: Searching for '4621_20080224141149.mpg' 2008-02-24 14:11:56.128 SG(Default): FindRecordingDir: Checking '/mnt/store' 2008-02-24 14:11:56.128 SG(Default): FindRecordingFile: Found '/mnt/store/4621_20080224141149.mpg' 2008-02-24 14:11:56.128 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/4621_20080224141149.mpg' 2008-02-24 14:11:56.129 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE 2008-02-24 14:11:56.129 read <- 27 2 OK 2008-02-24 14:11:56.129 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:11:56.129 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:11:56.129 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:11:56.129 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:11:56.129 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group! 2008-02-24 14:11:56.130 SG(Default): FindRecordingFile: Searching for '4621_20080224141149.mpg' 2008-02-24 14:11:56.130 SG(Default): FindRecordingDir: Checking '/mnt/store' 2008-02-24 14:11:56.130 SG(Default): FindRecordingFile: Found '/mnt/store/4621_20080224141149.mpg' 2008-02-24 14:11:56.130 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/4621_20080224141149.mpg' 2008-02-24 14:11:56.130 RingBuf(/mnt/store/4621_20080224141147.mpg): OpenFile(/mnt/store/4621_20080224141149.mpg, 12) 2008-02-24 14:11:56.130 RingBuf(/mnt/store/4621_20080224141149.mpg): CalcReadAheadThresh(3050971124 KB) -> threshhold(64 KB) min read(0 KB) blk size(32 KB) nVidiaVideoSync::: Interrupted system call 2008-02-24 14:11:56.131 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver? 2008-02-24 14:11:56.131 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory 2008-02-24 14:11:56.131 OpenGLVideoSync() 2008-02-24 14:11:56.131 OpenGLVideoSync: x,y -> 424, 240 2008-02-24 14:11:56.134 write -> 30 30 QUERY_RECORDER 2[]:[]GET_INPUT 2008-02-24 14:11:56.134 read <- 30 8 DVBInput 2008-02-24 14:11:56.139 mpegts_add_stream: at pid 0x31 with type 2 2008-02-24 14:11:56.140 mpegts_add_stream: stream #0, has id 0x31 and codec MPEG2VIDEO, type Video at 0xb2836cf0 2008-02-24 14:11:56.140 mpegts_add_stream: at pid 0x34 with type 129 2008-02-24 14:11:56.140 mpegts_add_stream: stream #1, has id 0x34 and codec AC3, type Audio at 0xb2836fa0 2008-02-24 14:11:56.155 Using OpenGLVideoSync 2008-02-24 14:11:56.161 Using audio as timebase 2008-02-24 14:11:56.161 Video timing method: SGI OpenGL 2008-02-24 14:11:56.161 Refresh rate: 16666, frame interval: 40000 2008-02-24 14:11:56.172 AFD: Stream #0, has id 0x49 codec id MPEG2VIDEO, type Video, bitrate 18900000 at 0x0xb2836cf0 2008-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() 2008-02-24 14:11:56.173 VDP: LoadBestPreferences(2048x2048, 0) 2008-02-24 14:11:56.173 VDP: LoadBestPreferences(2048x2048, 60) 2008-02-24 14:11:56.173 VDP: LoadBestPreferences(1920x1080, 60) 2008-02-24 14:11:56.173 Using 1 CPUs for decoding QString::setNum: Invalid format char '' 2008-02-24 14:11:56.173 AFD: Trying DirectShow for FOURCC 0x845639789 2008-02-24 14:11:56.173 AFD: Using libmpeg2 for video decoding 2008-02-24 14:11:56.173 AFD: InitVideoCodec() 0xb2837300 id(MPEG2VIDEO) type (Video). 2008-02-24 14:11:56.173 VideoOutputXv: InputChanged(1920,1088,1.77778) 'None'->'MPEG2' 2008-02-24 14:11:56.173 VDP: LoadBestPreferences(1920x1088, 25) 2008-02-24 14:11:56.173 VideoOutputXv: DiscardFrames(1) 2008-02-24 14:11:56.173 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:56.173 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() 2008-02-24 14:11:56.174 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done 2008-02-24 14:11:56.174 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() 2008-02-24 14:11:56.174 VideoOutputXv: DiscardFrames(1) 2008-02-24 14:11:56.174 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:56.174 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() 2008-02-24 14:11:56.174 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done 2008-02-24 14:11:56.174 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() 2008-02-24 14:11:56.178 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:56.183 VideoOutputXv: InitSetupBuffers() render: opengl, allowed: opengl,xv-blit,xshm,xlib 2008-02-24 14:11:56.188 GLVid: Viewport: 1920x1088 2008-02-24 14:11:56.192 GLVid: Created main input texture 960x544 2008-02-24 14:11:56.197 GLVid: Created main input texture 960x544 2008-02-24 14:11:56.218 GLVid: Created main input texture 1920x1088 2008-02-24 14:11:56.218 GLVid: Creating master filter. 2008-02-24 14:11:56.218 GLVid: Created fragment program master. 2008-02-24 14:11:56.218 GLVid: Creating resize filter. 2008-02-24 14:11:56.231 GLCtx: Created frame buffer object (1920x1088). 2008-02-24 14:11:56.231 GLVid: Turning off deinterlacing. 2008-02-24 14:11:56.231 GLVid: Turning off deinterlacing. 2008-02-24 14:11:56.231 Created data @0xabe8d020->0xac18a022 2008-02-24 14:11:56.231 Created data @0xa62e5020->0xa65e2022 2008-02-24 14:11:56.231 Created data @0xa5fe7020->0xa62e4022 2008-02-24 14:11:56.232 Created data @0xa5ce9020->0xa5fe6022 2008-02-24 14:11:56.232 Created data @0xa59eb020->0xa5ce8022 2008-02-24 14:11:56.232 Created data @0xa56ed020->0xa59ea022 2008-02-24 14:11:56.232 Created data @0xa53ef020->0xa56ec022 2008-02-24 14:11:56.232 Created data @0xa50f1020->0xa53ee022 2008-02-24 14:11:56.232 Created data @0xa4df3020->0xa50f0022 2008-02-24 14:11:56.232 Created data @0xa4af5020->0xa4df2022 2008-02-24 14:11:56.232 Created data @0xa47f7020->0xa4af4022 2008-02-24 14:11:56.232 Created data @0xa44f9020->0xa47f6022 2008-02-24 14:11:56.232 Created data @0xa41fb020->0xa44f8022 2008-02-24 14:11:56.232 Created data @0xa3efd020->0xa41fa022 2008-02-24 14:11:56.232 Created data @0xa3bff020->0xa3efc022 2008-02-24 14:11:56.232 Created data @0xa3901020->0xa3bfe022 2008-02-24 14:11:56.232 Created data @0xa3603020->0xa3900022 2008-02-24 14:11:56.232 Created data @0xa3305020->0xa3602022 2008-02-24 14:11:56.232 Created data @0xa3007020->0xa3304022 2008-02-24 14:11:56.232 Created data @0xa2d09020->0xa3006022 2008-02-24 14:11:56.232 Created data @0xa2a0b020->0xa2d08022 2008-02-24 14:11:56.232 Created data @0xa270d020->0xa2a0a022 2008-02-24 14:11:56.232 Created data @0xa240f020->0xa270c022 2008-02-24 14:11:56.232 Created data @0xa2111020->0xa240e022 2008-02-24 14:11:56.232 Created data @0xa1e13020->0xa2110022 2008-02-24 14:11:56.232 Created data @0xa1b15020->0xa1e12022 2008-02-24 14:11:56.232 Created data @0xa1817020->0xa1b14022 2008-02-24 14:11:56.232 Created data @0xa1519020->0xa1816022 2008-02-24 14:11:56.232 Created data @0xa121b020->0xa1518022 2008-02-24 14:11:56.232 Created data @0xa0f1d020->0xa121a022 2008-02-24 14:11:56.232 Created data @0xa0c1f020->0xa0f1c022 2008-02-24 14:11:56.232 Created data @0xa0921020->0xa0c1e022 2008-02-24 14:11:56.312 GLVid: Turning on deinterlacing. 2008-02-24 14:11:56.328 VDP: SetVideoRenderer(opengl) 2008-02-24 14:11:56.328 VDP: SetVideoRender(opengl) == GetVideoRenderer() 2008-02-24 14:11:56.331 GLVid: Created main input texture 848x480 2008-02-24 14:11:56.333 GLVid: Created main input texture 424x240 2008-02-24 14:11:56.334 GLVid: Created main input texture 424x240 2008-02-24 14:11:56.336 GLVid: Created main input texture 848x480 2008-02-24 14:11:56.337 GLVid: Creating osd filter. 2008-02-24 14:11:56.337 GLVid: Created fragment program osd. 2008-02-24 14:11:56.337 GLVid: Turning off deinterlacing. 2008-02-24 14:11:56.337 GLVid: Creating resize filter. 2008-02-24 14:11:56.341 GLCtx: Created frame buffer object (848x480). 2008-02-24 14:11:56.341 GLVid: Turning off deinterlacing. 2008-02-24 14:11:56.342 Display Rect left: 0, top: 0, width: 848, height: 480, aspect: 1.77778 2008-02-24 14:11:56.342 Video Rect left: 0, top: 32, width: 1920, height: 1015, aspect: 1.77778 2008-02-24 14:11:56.342 VDP: LoadBestPreferences(1920x1088, 29.97) 2008-02-24 14:11:56.362 VideoOutputXv: UpdatePauseFrame() AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:56.434 NVP: ClearAfterSeek(1) 2008-02-24 14:11:56.434 VideoOutputXv: ClearAfterSeek() 2008-02-24 14:11:56.434 VideoOutputXv: DiscardFrames(0) 2008-02-24 14:11:56.434 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:56.434 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done 2008-02-24 14:11:56.434 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() greedyhdeint: size changed from 720 x 576 -> 1920 x 1088 2008-02-24 14:11:56.482 NVP: LoadFilters(''..) -> 0 2008-02-24 14:11:56.482 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 1088) ->Interlaced Scan 2008-02-24 14:11:56.482 AFD: Using libmpeg2 for video decoding 2008-02-24 14:11:56.482 AFD: Looking for decoder for MPEG2VIDEO 2008-02-24 14:11:56.482 AFD: Opened codec 0xb2837300, id(MPEG2VIDEO) type(Video) 2008-02-24 14:11:56.482 AFD: Stream #1, has id 0x52 codec id AC3, type Audio, bitrate 384000 at 0x0xb2836fa0 2008-02-24 14:11:56.482 AFD: codec AC3 has 6 channels 2008-02-24 14:11:56.482 AFD: Looking for decoder for AC3 2008-02-24 14:11:56.483 AFD: Opened codec 0xb2837670, id(AC3) type(Audio) 2008-02-24 14:11:56.483 AFD: Audio Track #1 is A/V stream #1 and has 6 channels in the English language(6647399). 2008-02-24 14:11:56.483 RingBuf(/mnt/store/4621_20080224141149.mpg): CalcReadAheadThresh(2948999504 KB) -> threshhold(64 KB) min read(0 KB) blk size(32 KB) 2008-02-24 14:11:56.483 AFD: Selected track 1: English AC3 5.1ch (A/V Stream #1) 2008-02-24 14:11:56.483 AFD: Initializing audio parms from audio track #1 2008-02-24 14:11:56.483 AFD: Audio format changed from id(NONE) -1Hz -1ch 0bps ; id(NONE) -1Hz -1ch 0bps to id( AC3) 48000Hz 6ch 16bps ; id( AC3) 48000Hz 2ch 16bps 2008-02-24 14:11:56.485 AO: Killing AudioOutputDSP 2008-02-24 14:11:56.485 Opening audio device 'default'. ch 2(2) sr 48000 2008-02-24 14:11:56.485 Opening ALSA audio device 'default'. 2008-02-24 14:11:56.494 in SetParameters(format=2, channels=2, rate=48000, buffer_time=100000, period_time=25000) 2008-02-24 14:11:56.495 get_buffer_size returned 5120 2008-02-24 14:11:56.495 set_period_time_near returned 21333 2008-02-24 14:11:56.495 get_period_size returned 1024 2008-02-24 14:11:56.496 Opening mixer default 2008-02-24 14:11:56.496 Setting Master volume to 70 2008-02-24 14:11:56.496 Mixer unable to find control Master 2008-02-24 14:11:56.496 Setting Master volume to 70 2008-02-24 14:11:56.496 Mixer unable to find control Master 2008-02-24 14:11:56.497 Setting PCM volume to 100 2008-02-24 14:11:56.497 Volume range is 0 to 255, mult=0.392157 2008-02-24 14:11:56.497 channel 0 vol set to 255 2008-02-24 14:11:56.497 Setting PCM volume to 100 2008-02-24 14:11:56.497 Volume range is 0 to 255, mult=0.392157 2008-02-24 14:11:56.497 channel 1 vol set to 255 2008-02-24 14:11:56.497 Volume range is 0 to 255, mult=0.392157 2008-02-24 14:11:56.497 AO: Audio fragment size: 6400 2008-02-24 14:11:56.497 AO: Audio Stretch Factor: 1 2008-02-24 14:11:56.497 Audio Codec Used: not set 2008-02-24 14:11:56.498 AO: Ending reconfigure 2008-02-24 14:11:56.499 NVP: Enabling Audio 2008-02-24 14:11:56.498 AO: kickoffOutputAudioLoop: pid = 907 2008-02-24 14:11:56.499 AO: no change exiting 2008-02-24 14:11:56.499 AO: OutputAudioLoop: Play Event 2008-02-24 14:11:56.499 Dec: Trying to select track (w/lang) 2008-02-24 14:11:56.499 Dec: Selecting first track 2008-02-24 14:11:56.499 Dec: Selected track #1 in the Unknown language(0) 2008-02-24 14:11:56.499 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1) 2008-02-24 14:11:56.499 Position map filled from DB to: 75 2008-02-24 14:11:56.499 SyncPositionMap watchingrecording, from DB: 6 entries 2008-02-24 14:11:56.499 write -> 30 39 QUERY_RECORDER 2[]:[]GET_FRAMES_WRITTEN 2008-02-24 14:11:56.499 read <- 30 9 0[]:[]178 2008-02-24 14:11:56.499 Filling position map from 76 to 178 2008-02-24 14:11:56.500 write -> 30 53 QUERY_RECORDER 2[]:[]FILL_POSITION_MAP[]:[]76[]:[]178 2008-02-24 14:11:56.500 read <- 30 189 0[]:[]90[]:[]0[]:[]7078764[]:[]0[]:[]105[]:[]0[]:[]8286476[]:[]0[... 2008-02-24 14:11:56.500 Position map filled from Encoder to: 165 2008-02-24 14:11:56.500 SyncPositionMap watchingrecording total: 12 entries 2008-02-24 14:11:56.500 SyncPositionMap, new totframes: 165, new length: 5, posMap size: 12 2008-02-24 14:11:56.500 Input #0, mpegts, from '/mnt/store/4621_20080224141149.mpg': 2008-02-24 14:11:56.500 Duration: N/A, start: 73955.032089, bitrate: 19284 kb/s 2008-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) 2008-02-24 14:11:56.500 Stream #0.1[0x34](eng), 1/90000: Audio: ac3, 48000 Hz, stereo, 384 kb/s 2008-02-24 14:11:56.500 AFD: Partial position map found 2008-02-24 14:11:56.501 AFD: Successfully opened decoder for file: "/mnt/store/4621_20080224141149.mpg". novideo(0) 2008-02-24 14:11:56.501 NVP: DoPlay: rate: 29.97 speed: 1 skip: 1 => new interval 33366 2008-02-24 14:11:56.501 Set video sync frame interval to 33366 2008-02-24 14:11:56.501 NVP: Stretch Factor 1, allow passthru 2008-02-24 14:11:56.501 RingBuf(/mnt/store/4621_20080224141149.mpg): CalcReadAheadThresh(0 KB) -> threshhold(64 KB) min read(0 KB) blk size(32 KB) 2008-02-24 14:11:56.501 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1) 2008-02-24 14:11:56.502 Position map filled from DB to: 75 2008-02-24 14:11:56.502 SyncPositionMap watchingrecording, from DB: 6 entries 2008-02-24 14:11:56.502 write -> 30 39 QUERY_RECORDER 2[]:[]GET_FRAMES_WRITTEN 2008-02-24 14:11:56.502 read <- 30 9 0[]:[]178 2008-02-24 14:11:56.502 Filling position map from 76 to 178 2008-02-24 14:11:56.502 write -> 30 53 QUERY_RECORDER 2[]:[]FILL_POSITION_MAP[]:[]76[]:[]178 2008-02-24 14:11:56.502 read <- 30 189 0[]:[]90[]:[]0[]:[]7078764[]:[]0[]:[]105[]:[]0[]:[]8286476[]:[]0[... 2008-02-24 14:11:56.502 Position map filled from Encoder to: 165 2008-02-24 14:11:56.502 SyncPositionMap watchingrecording total: 12 entries 2008-02-24 14:11:56.544 AO: Pause 1 2008-02-24 14:11:56.544 NVP: Waiting for prebuffer.. 1 LLLAAAAAAAAAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:56.547 AO: OutputAudioLoop: audio paused 2008-02-24 14:11:56.694 NVP: Waiting for prebuffer.. 2 AAUUUuUULULAAAAAAAAAAAAAAAAAAAA 2008-02-24 14:11:56.825 AO: Pause 0 2008-02-24 14:11:56.828 AO: OutputAudioLoop: Play Event 2008-02-24 14:11:57.815 NVP: Video is 3.04142 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:57.846 NVP: Video is 3.15771 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:57.877 NVP: Video is 3.22244 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:57.907 NVP: Video is 3.24852 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:57.937 NVP: Video is 3.25307 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:57.965 NVP: Video is 3.22652 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:57.996 NVP: Video is 3.16915 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.025 NVP: Video is 3.10364 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.054 NVP: Video is 3.01705 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.241 NVP: Video is 3.06492 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.271 NVP: Video is 3.11539 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.302 NVP: Video is 3.12327 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.334 NVP: Video is 3.1067 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.365 NVP: Video is 3.08677 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.396 NVP: Video is 3.04933 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.426 NVP: Video is 3.00629 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.573 NVP: Video is 3.07747 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.603 NVP: Video is 3.10232 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.632 NVP: Video is 3.09848 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.662 NVP: Video is 3.07313 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.692 NVP: Video is 3.01663 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.838 NVP: Video is 3.04397 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.869 NVP: Video is 3.06968 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.901 NVP: Video is 3.074 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.931 NVP: Video is 3.06225 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:58.962 NVP: Video is 3.03096 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.034 NVP: Video is 3.03111 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.064 NVP: Video is 3.03009 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.095 NVP: Video is 3.00683 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.203 NVP: Video is 3.03396 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.233 NVP: Video is 3.0472 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.263 NVP: Video is 3.03465 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.332 NVP: Video is 3.0107 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.403 NVP: Video is 3.02907 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.434 NVP: Video is 3.04355 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.464 NVP: Video is 3.03941 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.494 NVP: Video is 3.00632 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.601 NVP: Video is 3.00563 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.631 NVP: Video is 3.01097 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.737 NVP: Video is 3.04834 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.766 NVP: Video is 3.05799 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.795 NVP: Video is 3.03525 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.903 NVP: Video is 3.04265 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.967 NVP: Video is 3.04621 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:11:59.973 XMLParse::LoadTheme using /usr/share/mythtv/themes/Titivillus/ui.xml 2008-02-24 14:12:00.014 NVP: Video is 3.28118 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.054 NVP: Video is 3.5698 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.091 NVP: Video is 3.82371 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.127 NVP: Video is 4.03662 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.165 NVP: Video is 4.22628 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.196 NVP: Video is 4.391 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.227 NVP: Video is 4.50704 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.258 NVP: Video is 4.5716 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.289 NVP: Video is 4.59755 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.319 NVP: Video is 4.60951 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.350 NVP: Video is 4.60349 frames behind audio (too slow), dropping frame to catch up. 'video_output' mean = '35154.39', std. dev. = '5536.26', fps = '28.45' 2008-02-24 14:12:00.381 NVP: Video is 4.56902 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.412 NVP: Video is 4.53567 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.445 NVP: Video is 4.48067 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.477 NVP: Video is 4.43943 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.490 write -> 27 19 QUERY_GETALLPENDING 2008-02-24 14:12:00.491 read <- 27 7 0[]:[]0 2008-02-24 14:12:00.548 NVP: Video is 4.40101 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.562 IsTunable(4562) 2008-02-24 14:12:00.562 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:12:00.564 read <- 27 13 1[]:[]5[]:[]7 2008-02-24 14:12:00.564 write -> 27 42 QUERY_RECORDER 2[]:[]GET_FREE_INPUTS[]:[]2 2008-02-24 14:12:00.566 read <- 27 32 DVBInput[]:[]4[]:[]4[]:[]2[]:[]0 2008-02-24 14:12:00.566 IsTunable(4562) -> true 2008-02-24 14:12:00.566 IsTunable(4563) 2008-02-24 14:12:00.567 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:12:00.568 read <- 27 13 1[]:[]5[]:[]7 2008-02-24 14:12:00.568 IsTunable(4563) -> true 2008-02-24 14:12:00.568 IsTunable(4621) 2008-02-24 14:12:00.568 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:12:00.569 read <- 27 13 1[]:[]5[]:[]7 2008-02-24 14:12:00.569 IsTunable(4621) -> true 2008-02-24 14:12:00.569 IsTunable(2100) 2008-02-24 14:12:00.570 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:12:00.570 read <- 27 13 1[]:[]5[]:[]7 2008-02-24 14:12:00.570 write -> 27 42 QUERY_RECORDER 1[]:[]GET_FREE_INPUTS[]:[]2 2008-02-24 14:12:00.571 read <- 27 115 DVBInput #1[]:[]1[]:[]1[]:[]1[]:[]0[]:[]DVBInput #2[]:[]2[]:[]2[]... 2008-02-24 14:12:00.571 IsTunable(2100) -> true 2008-02-24 14:12:00.571 IsTunable(1101) 2008-02-24 14:12:00.572 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:12:00.572 read <- 27 13 1[]:[]5[]:[]7 2008-02-24 14:12:00.573 IsTunable(1101) -> true 2008-02-24 14:12:00.603 NVP: Video is 4.64943 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.634 NVP: Video is 5.00057 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.663 NVP: Video is 5.24144 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.694 NVP: Video is 5.39214 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.724 NVP: Video is 5.49017 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.753 NVP: Video is 5.54121 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.784 NVP: Video is 5.54954 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.819 NVP: Video is 5.5333 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.848 NVP: Video is 5.52113 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.878 NVP: Video is 5.497 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.908 NVP: Video is 5.44893 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.939 NVP: Video is 5.3829 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:00.969 NVP: Video is 5.31092 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.000 NVP: Video is 5.24945 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.030 NVP: Video is 5.18084 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.060 NVP: Video is 5.09944 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.091 NVP: Video is 5.01591 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.119 NVP: Video is 4.93077 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.148 NVP: Video is 4.82947 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.177 NVP: Video is 4.71603 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.235 NVP: Video is 4.60846 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.268 NVP: Video is 4.70761 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.299 NVP: Video is 4.77447 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.331 NVP: Video is 4.80963 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.361 NVP: Video is 4.836 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.392 NVP: Video is 4.82581 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.421 NVP: Video is 4.79569 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.451 NVP: Video is 4.73563 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.482 NVP: Video is 4.66811 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.513 NVP: Video is 4.6025 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.545 NVP: Video is 4.53081 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.576 NVP: Video is 4.46955 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.608 NVP: Video is 4.40862 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.638 NVP: Video is 4.34793 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.668 NVP: Video is 4.27993 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.698 NVP: Video is 4.21396 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.743 NVP: Video is 4.12702 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.773 NVP: Video is 4.15171 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.804 NVP: Video is 4.14776 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.835 NVP: Video is 4.1298 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.867 NVP: Video is 4.10136 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.898 NVP: Video is 4.05754 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.930 NVP: Video is 4.00971 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.963 NVP: Video is 3.97384 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:01.992 NVP: Video is 3.93194 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.024 NVP: Video is 3.87053 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.055 NVP: Video is 3.817 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.086 NVP: Video is 3.75436 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.118 NVP: Video is 3.6999 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.148 NVP: Video is 3.63658 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.176 NVP: Video is 3.56659 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.205 NVP: Video is 3.48415 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.235 NVP: Video is 3.37736 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.263 NVP: Video is 3.27477 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.292 NVP: Video is 3.16787 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.321 NVP: Video is 3.05023 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.610 NVP: Video is 3.05152 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.641 NVP: Video is 3.09782 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.670 NVP: Video is 3.11008 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.698 NVP: Video is 3.08182 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.745 NVP: Video is 3.03066 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.774 NVP: Video is 3.08218 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.803 NVP: Video is 3.09087 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.832 NVP: Video is 3.0674 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:02.860 NVP: Video is 3.01984 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.039 NVP: Video is 3.03228 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.068 NVP: Video is 3.03845 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.098 NVP: Video is 3.02059 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.204 NVP: Video is 3.02664 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.233 NVP: Video is 3.02673 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.300 NVP: Video is 3.00438 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.403 NVP: Video is 3.02634 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.432 NVP: Video is 3.019 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.535 NVP: Video is 3.0021 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.603 NVP: Video is 3.00926 frames behind audio (too slow), dropping frame to catch up. 'video_output' mean = '32907.72', std. dev. = '6329.74', fps = '30.39' 2008-02-24 14:12:03.671 NVP: Video is 3.03575 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.700 NVP: Video is 3.02607 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.785 NVP: Video is 3.14638 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.814 NVP: Video is 3.22142 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.843 NVP: Video is 3.24024 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.872 NVP: Video is 3.22439 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.900 NVP: Video is 3.17503 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.929 NVP: Video is 3.10055 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:03.957 NVP: Video is 3.00722 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.177 NVP: Video is 3.04535 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.207 NVP: Video is 3.08572 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.236 NVP: Video is 3.09351 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.265 NVP: Video is 3.06189 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.294 NVP: Video is 3.00821 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.472 NVP: Video is 3.02104 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.502 NVP: Video is 3.02254 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.532 NVP: Video is 3.00117 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.675 NVP: Video is 3.0496 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.705 NVP: Video is 3.05892 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.746 NVP: Video is 3.05095 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.775 NVP: Video is 3.0974 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.804 NVP: Video is 3.10229 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.834 NVP: Video is 3.07598 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:04.863 NVP: Video is 3.02625 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.040 NVP: Video is 3.03602 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.069 NVP: Video is 3.04127 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.198 NVP: Video is 3.01523 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.250 NVP: Video is 3.71498 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.281 NVP: Video is 4.37466 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.311 NVP: Video is 4.85443 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.341 NVP: Video is 5.1768 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.370 NVP: Video is 5.39609 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.400 NVP: Video is 5.54559 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.430 NVP: Video is 5.62024 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.460 NVP: Video is 5.65375 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.490 NVP: Video is 5.64143 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.521 NVP: Video is 5.61722 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.551 NVP: Video is 5.58407 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.582 NVP: Video is 5.52922 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.612 NVP: Video is 5.46562 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.644 NVP: Video is 5.40293 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.675 NVP: Video is 5.34092 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.706 NVP: Video is 5.27942 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.736 NVP: Video is 5.21831 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.766 NVP: Video is 5.15 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.797 NVP: Video is 5.07631 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.826 NVP: Video is 4.99107 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.856 NVP: Video is 4.89717 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.887 NVP: Video is 4.81175 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.916 NVP: Video is 4.71771 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.947 NVP: Video is 4.61722 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:05.977 NVP: Video is 4.52685 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.006 NVP: Video is 4.42912 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.037 NVP: Video is 4.32584 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.067 NVP: Video is 4.23341 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.097 NVP: Video is 4.13412 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.126 NVP: Video is 4.03716 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.157 NVP: Video is 3.93448 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.187 NVP: Video is 3.83498 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.217 NVP: Video is 3.73788 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.246 NVP: Video is 3.62758 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.275 NVP: Video is 3.52988 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.304 NVP: Video is 3.41914 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.334 NVP: Video is 3.29863 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.364 NVP: Video is 3.19325 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.395 NVP: Video is 3.07675 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.485 NVP: Video is 3.10744 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.515 NVP: Video is 3.16975 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.543 NVP: Video is 3.194 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.572 NVP: Video is 3.17473 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.601 NVP: Video is 3.12282 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.630 NVP: Video is 3.05392 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.810 NVP: Video is 3.05581 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.839 NVP: Video is 3.07109 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.868 NVP: Video is 3.03758 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:06.971 NVP: Video is 3.02305 frames behind audio (too slow), dropping frame to catch up. 'video_output' mean = '33298.55', std. dev. = '11161.52', fps = '30.03' 2008-02-24 14:12:07.000 NVP: Video is 3.00156 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.140 NVP: Video is 3.02293 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.169 NVP: Video is 3.01645 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.238 NVP: Video is 3.00608 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.304 NVP: Video is 3.01334 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.407 NVP: Video is 3.03435 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.435 NVP: Video is 3.02503 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.574 NVP: Video is 3.03222 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.603 NVP: Video is 3.02341 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.708 NVP: Video is 3.02688 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.739 NVP: Video is 3.03441 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.769 NVP: Video is 3.01007 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.877 NVP: Video is 3.2591 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.907 NVP: Video is 3.46332 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.936 NVP: Video is 3.60148 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.966 NVP: Video is 3.67515 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:07.995 NVP: Video is 3.70041 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.025 NVP: Video is 3.68941 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.055 NVP: Video is 3.65866 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.085 NVP: Video is 3.60565 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.115 NVP: Video is 3.5359 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.145 NVP: Video is 3.46113 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.175 NVP: Video is 3.38258 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.205 NVP: Video is 3.29368 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.249 NVP: Video is 3.19703 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.306 NVP: Video is 3.21447 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.339 NVP: Video is 3.40736 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.370 NVP: Video is 3.54454 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.400 NVP: Video is 3.62495 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.429 NVP: Video is 3.65528 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.459 NVP: Video is 3.64805 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.488 NVP: Video is 3.62765 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.518 NVP: Video is 3.5749 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.548 NVP: Video is 3.51286 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.577 NVP: Video is 3.43634 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.642 NVP: Video is 3.34898 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.675 NVP: Video is 3.51573 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.705 NVP: Video is 3.64829 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.736 NVP: Video is 3.71774 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.766 NVP: Video is 3.74735 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.797 NVP: Video is 3.73959 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.827 NVP: Video is 3.71879 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.857 NVP: Video is 3.68819 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.887 NVP: Video is 3.62779 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.917 NVP: Video is 3.56 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.947 NVP: Video is 3.49419 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:08.975 NVP: Video is 3.41485 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.005 NVP: Video is 3.31041 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.034 NVP: Video is 3.21708 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.063 NVP: Video is 3.1096 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.210 NVP: Video is 3.03908 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.240 NVP: Video is 3.04355 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.270 NVP: Video is 3.01693 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.379 NVP: Video is 3.04663 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.410 NVP: Video is 3.0642 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.441 NVP: Video is 3.0624 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.469 NVP: Video is 3.03857 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.577 NVP: Video is 3.03842 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.606 NVP: Video is 3.04307 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.635 NVP: Video is 3.00908 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.777 NVP: Video is 3.03713 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.806 NVP: Video is 3.04208 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.836 NVP: Video is 3.01582 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.944 NVP: Video is 3.0321 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:09.972 NVP: Video is 3.0383 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.000 NVP: Video is 3.00551 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.145 NVP: Video is 3.008 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.174 NVP: Video is 3.02023 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.174 NVP: 400 interlaced frames seen. 2008-02-24 14:12:10.241 NVP: Video is 3.02131 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.270 NVP: Video is 3.00773 frames behind audio (too slow), dropping frame to catch up. 'video_output' mean = '33366.99', std. dev. = '7078.64', fps = '29.97' 2008-02-24 14:12:10.377 NVP: Video is 3.00483 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.407 NVP: Video is 3.01037 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.475 NVP: Video is 3.02326 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.504 NVP: Video is 3.00171 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.614 NVP: Video is 3.02101 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.647 NVP: Video is 3.05248 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.680 NVP: Video is 3.07607 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.713 NVP: Video is 3.07879 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.746 NVP: Video is 3.08832 frames behind audio (too slow), dropping frame to catch up. 2008-02-24 14:12:10.760 TV: CommitQueuedInput() livetv(1) qchannum(168) qchanid(0) 2008-02-24 14:12:10.760 TV: ChangeChannel(0, '168') 2008-02-24 14:12:10.760 write -> 30 49 QUERY_RECORDER 2[]:[]CHECK_CHANNEL_PREFIX[]:[]168 2008-02-24 14:12:10.765 read <- 30 19 1[]:[]5[]:[]0[]:[]X 2008-02-24 14:12:10.766 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:12:10.767 read <- 27 13 1[]:[]5[]:[]7 2008-02-24 14:12:10.768 write -> 27 27 GET_RECORDER_FROM_NUM[]:[]1 2008-02-24 14:12:10.768 read <- 27 21 192.168.0.50[]:[]6543 2008-02-24 14:12:10.768 TV: SwitchCards(0,'168',0) 2008-02-24 14:12:10.768 write -> 27 22 GET_FREE_RECORDER_LIST 2008-02-24 14:12:10.770 read <- 27 13 1[]:[]5[]:[]7 2008-02-24 14:12:10.770 write -> 27 27 GET_RECORDER_FROM_NUM[]:[]1 2008-02-24 14:12:10.770 read <- 27 21 192.168.0.50[]:[]6543 2008-02-24 14:12:10.772 NVP: Changing speed to 0 2008-02-24 14:12:10.772 rate: 29.97 speed: 1 skip: 1 = interval 33366 2008-02-24 14:12:10.772 Set video sync frame interval to 33366 2008-02-24 14:12:10.804 write -> 30 32 QUERY_RECORDER 2[]:[]STOP_LIVETV 2008-02-24 14:12:10.806 NVP: Exited decoder loop. 2008-02-24 14:12:10.816 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:10.816 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:10.816 read <- 25 49 BACKEND_MESSAGE[]:[]DONE_RECORDING 2 20[]:[]empty 2008-02-24 14:12:10.816 MythEvent: DONE_RECORDING 2 20 2008-02-24 14:12:10.828 ~OpenGLVideoSync() -- begin 2008-02-24 14:12:10.828 ~OpenGLVideoSync() -- middle 2008-02-24 14:12:10.828 ~OpenGLVideoSync() -- end 2008-02-24 14:12:10.828 VideoOutputXv: dtor 2008-02-24 14:12:10.828 VideoOutputXv: DiscardFrames(1) 2008-02-24 14:12:10.828 VideoBuffers::DiscardFrames(1): UUUUUUUUUUUuUULUULUUUUUUUUUAAUU 2008-02-24 14:12:10.828 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() 2008-02-24 14:12:10.828 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done 2008-02-24 14:12:10.828 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done() X Error: BadDrawable (invalid Pixmap or Window parameter) 9 Major opcode: 70 Minor opcode: 0 Resource id: 0x1e0011a 2008-02-24 14:12:10.875 AO: Killing AudioOutputDSP 2008-02-24 14:12:10.876 AO: OutputAudioLoop: Stop Event 2008-02-24 14:12:10.876 AO: kickoffOutputAudioLoop exiting 2008-02-24 14:12:10.881 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:10.881 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:10.881 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:12:10.881 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:12:10.885 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:10.885 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:10.885 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:12:10.885 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:12:10.885 read <- 30 2 ok 2008-02-24 14:12:10.902 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE 2008-02-24 14:12:10.902 read <- 27 2 OK 2008-02-24 14:12:10.902 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:10.902 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:10.902 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:12:10.902 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:12:10.917 Closing PAT Filter: pid=0x0 2008-02-24 14:12:10.917 Closing PMT Filter: pid=0x30 2008-02-24 14:12:10.917 MythSocket(a77777b8:30): DownRef: -1 2008-02-24 14:12:10.917 MythSocket(a77777b8:30): state change Connected -> Idle 2008-02-24 14:12:10.917 MythSocket(a77777b8:-1): delete socket 2008-02-24 14:12:10.924 MythSocket(b3655380:30): new socket 2008-02-24 14:12:10.924 MythSocket(b3655380:30): attempting connect() to (192.168.0.50:6543) 2008-02-24 14:12:10.924 MythSocket(b3655380:30): state change Idle -> Connected 2008-02-24 14:12:10.924 write -> 30 21 MYTH_PROTO_VERSION 40 2008-02-24 14:12:10.924 read <- 30 13 ACCEPT[]:[]40 2008-02-24 14:12:10.924 Using protocol version 40 2008-02-24 14:12:10.924 write -> 30 21 ANN Playback mythtv 0 2008-02-24 14:12:10.927 read <- 30 2 OK 2008-02-24 14:12:10.927 write -> 30 83 QUERY_RECORDER 1[]:[]SPAWN_LIVETV[]:[]live-mythtv-2008-02-24T14:1... 2008-02-24 14:12:14.313 read <- 30 2 ok 2008-02-24 14:12:14.314 LiveTVChain(live-mythtv-2008-02-24T14:11:34): ReloadAll(): Added new recording 2008-02-24 14:12:14.315 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:14.315 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:14.315 read <- 25 53 BACKEND_MESSAGE[]:[]QUERY_NEXT_LIVETV_DIR 1[]:[]empty 2008-02-24 14:12:14.315 MythEvent: QUERY_NEXT_LIVETV_DIR 1 2008-02-24 14:12:14.316 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:12:14.316 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:12:14.316 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:12:14.316 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:12:14.316 read <- 25 451 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:12:14.316 MythEvent: SIGNAL 1 2008-02-24 14:12:14.316 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group! 2008-02-24 14:12:14.316 SG(Default): FindRecordingFile: Searching for '1168_20080224141212.mpg' 2008-02-24 14:12:14.316 SG(Default): FindRecordingDir: Checking '/mnt/store' 2008-02-24 14:12:14.317 SG(Default): FindRecordingFile: Found '/mnt/store/1168_20080224141212.mpg' 2008-02-24 14:12:14.317 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/1168_20080224141212.mpg' 2008-02-24 14:12:14.317 RingBuf(/mnt/store/1168_20080224141212.mpg): OpenFile(/mnt/store/1168_20080224141212.mpg, 12) 2008-02-24 14:12:14.613 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:14.613 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:14.613 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:12:14.613 MythEvent: SIGNAL 1 2008-02-24 14:12:14.915 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:14.915 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:14.915 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:12:14.915 MythEvent: SIGNAL 1 2008-02-24 14:12:15.217 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:15.218 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:15.218 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:12:15.218 MythEvent: SIGNAL 1 2008-02-24 14:12:15.519 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:15.519 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:15.519 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:12:15.519 MythEvent: SIGNAL 1 2008-02-24 14:12:15.821 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:15.821 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:15.821 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:12:15.821 MythEvent: SIGNAL 1 2008-02-24 14:12:16.123 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:16.123 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:16.123 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:12:16.123 MythEvent: SIGNAL 1 2008-02-24 14:12:16.424 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:16.424 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:16.424 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:12:16.424 MythEvent: SIGNAL 1 2008-02-24 14:12:16.726 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:16.726 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:16.726 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:12:16.726 MythEvent: SIGNAL 1 2008-02-24 14:12:17.028 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:17.028 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:17.028 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:12:17.028 MythEvent: SIGNAL 1 2008-02-24 14:12:17.329 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:17.329 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:17.329 read <- 25 547 BACKEND_MESSAGE[]:[]SIGNAL 1[]:[]Signal Lock[]:[]slock 1 1 0 1 30... 2008-02-24 14:12:17.329 MythEvent: SIGNAL 1 2008-02-24 14:12:17.360 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:17.361 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:17.361 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:12:17.361 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:12:18.398 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:18.399 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:18.399 read <- 25 53 BACKEND_MESSAGE[]:[]QUERY_NEXT_LIVETV_DIR 1[]:[]empty 2008-02-24 14:12:18.399 MythEvent: QUERY_NEXT_LIVETV_DIR 1 2008-02-24 14:12:18.399 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:12:18.399 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:12:18.399 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:12:18.399 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:12:18.399 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:18.399 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:18.399 read <- 25 81 BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14... 2008-02-24 14:12:18.399 MythEvent: LIVETV_CHAIN UPDATE live-mythtv-2008-02-24T14:11:34 2008-02-24 14:12:20.818 RingBuf(/mnt/store/1168_20080224141212.mpg): Invalid file (fd -1) when opening '/mnt/store/1168_20080224141212.mpg'. 2008-02-24 14:12:20.818 RingBuf(/mnt/store/1168_20080224141212.mpg): CalcReadAheadThresh(3050971124 KB) -> threshhold(64 KB) min read(0 KB) blk size(32 KB) 2008-02-24 14:12:20.819 write -> 30 33 QUERY_RECORDER 1[]:[]IS_RECORDING 2008-02-24 14:12:20.819 read <- 30 1 1 2008-02-24 14:12:20.819 write -> 30 33 QUERY_RECORDER 1[]:[]IS_RECORDING 2008-02-24 14:12:20.819 read <- 30 1 1 2008-02-24 14:12:20.819 TV: StartRecorder(): took 0 ms to start recorder. 2008-02-24 14:12:20.819 write -> 30 34 QUERY_RECORDER 1[]:[]GET_FRAMERATE 2008-02-24 14:12:20.819 read <- 30 5 29.97 2008-02-24 14:12:20.821 SG(LiveTV): Unable to find storage group 'LiveTV', trying 'Default' group! 2008-02-24 14:12:20.822 SG(Default): FindRecordingFile: Searching for '1168_20080224141212.mpg' 2008-02-24 14:12:20.822 SG(Default): FindRecordingDir: Checking '/mnt/store' 2008-02-24 14:12:20.822 SG(Default): FindRecordingFile: Found '/mnt/store/1168_20080224141212.mpg' 2008-02-24 14:12:20.822 ProgramInfo: GetPlaybackURL: File is local: '/mnt/store/1168_20080224141212.mpg' 2008-02-24 14:12:20.822 write -> 27 33 MESSAGE[]:[]RECORDING_LIST_CHANGE 2008-02-24 14:12:20.823 read <- 27 2 OK 2008-02-24 14:12:20.823 MythSocket(b36436b8:25): socket is readable 2008-02-24 14:12:20.823 MythSocket(b36436b8:25): cb->readyRead() 2008-02-24 14:12:20.823 read <- 25 51 BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty 2008-02-24 14:12:20.823 MythEvent: RECORDING_LIST_CHANGE 2008-02-24 14:12:20.829 detectInterlace(Ignore Scan, Interlaced Scan, 25, 576) ->Interlaced Scan 2008-02-24 14:12:20.829 NVP: Disabling Audio, params(-1,2,44100) 2008-02-24 14:12:20.830 VideoOutput: Allowed renderers: opengl,xv-blit,xshm,xlib 2008-02-24 14:12:20.830 VideoOutput: Allowed renderers (filt: dummy): xlib,xshm,xv-blit,opengl 2008-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() 2008-02-24 14:12:20.831 VDP: LoadBestPreferences(2048x2048, 0) 2008-02-24 14:12:20.831 VDP: LoadBestPreferences(2048x2048, 60) 2008-02-24 14:12:20.831 VDP: LoadBestPreferences(720x576, 60) 2008-02-24 14:12:20.831 VideoOutput: Preferred renderer: opengl 2008-02-24 14:12:20.831 VideoOutput: Trying video renderer: opengl 2008-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() 2008-02-24 14:12:20.832 VDP: LoadBestPreferences(2048x2048, 0) 2008-02-24 14:12:20.832 VDP: LoadBestPreferences(2048x2048, 60) 2008-02-24 14:12:20.837 VideoOutputXv: ctor 2008-02-24 14:12:20.838 XOff: 0, YOff: 0 2008-02-24 14:12:20.838 VDP: LoadBestPreferences(720x576, 60) 2008-02-24 14:12:20.838 Display Rect left: 0, top: 0, width: 848, height: 480, aspect: 1.33333 2008-02-24 14:12:20.838 Video Rect left: 0, top: 0, width: 720, height: 576, aspect: 1.33333 2008-02-24 14:12:20.840 VideoOutputXv: Pixel dimensions: Screen 848x480, window 848x480 2008-02-24 14:12:20.840 VideoOutputXv: Estimated display dimensions: 287x163 mm Aspect: 1.76074 2008-02-24 14:12:20.840 VideoOutputXv: Estimated window dimensions: 287x163 mm Aspect: 1.76074 2008-02-24 14:12:20.840 VideoOutputXv: InitSetupBuffers() render: opengl, allowed: opengl,xv-blit,xshm,xlib 2008-02-24 14:12:20.842 GLCtx: Created window and context. 2008-02-24 14:12:20.864 GLVid: Viewport: 848x576 2008-02-24 14:12:20.864 GLVid: Created main input texture 360x288 2008-02-24 14:12:20.865 GLVid: Created main input texture 360x288 2008-02-24 14:12:20.869 GLVid: Created main input texture 720x576 2008-02-24 14:12:20.869 GLVid: Creating master filter. 2008-02-24 14:12:20.869 GLVid: Created fragment program master. 2008-02-24 14:12:20.869 GLVid: Creating resize filter. mythfrontend: Fatal IO error: client killed 2008-02-24 14:12:21.183 MythSocket: readyread thread exit