mythfrontend --verbose playback
2007-08-12 15:37:53.392 Using runtime prefix = /usr
2007-08-12 15:37:53.394 DPMS is active.
2007-08-12 15:37:53.406 New DB connection, total: 1
2007-08-12 15:37:53.409 Connected to database 'mythconverg' at host: localhost
2007-08-12 15:37:53.410 Total desktop dim: 952x510, with 1 screen[s].
2007-08-12 15:37:53.411 Using screen 0, 952x510 at 0,0
2007-08-12 15:37:53.414 user: 103 effective user: 103 before privileged thread
2007-08-12 15:37:53.415 user: 103 effective user: 103 after privileged thread
2007-08-12 15:37:53.415 user: 103 effective user: 103 run_priv_thread
2007-08-12 15:37:53.415 Current Schema Version: 1194
2007-08-12 15:37:53.415 mythfrontend version: 0.20.20070717-1 www.mythtv.org
2007-08-12 15:37:53.415 Enabled verbose msgs:  important general playback
2007-08-12 15:37:54.880 max_width: 1920 max_height: 1080
2007-08-12 15:37:54.962 max_width: 1920 max_height: 1080
2007-08-12 15:37:54.962 Trying 952x510 53 Hz
2007-08-12 15:37:54.962 SwitchToGUI: Switched to 952 x 510
2007-08-12 15:37:54.962 Total desktop dim: 952x510, with 1 screen[s].
2007-08-12 15:37:54.963 Using screen 0, 952x510 at 0,0
2007-08-12 15:37:54.963 Switching to wide mode (blootube-wide)
2007-08-12 15:37:54.971 Using the OpenGL painter
2007-08-12 15:37:54.975 New DB connection, total: 2
2007-08-12 15:37:54.976 Connected to database 'mythconverg' at host: localhost
2007-08-12 15:37:54.976 lirc init success using configuration file: /home/mythtv/.mythtv/lircrc
2007-08-12 15:37:54.976 Joystick disabled.
2007-08-12 15:37:56.421 Error loading image file: /usr/share/mythtv/themes/default/NOTHING.png
2007-08-12 15:37:56.533 Loading from: /usr/share/mythtv/themes/blootube-wide/base.xml
2007-08-12 15:37:56.632 Loading from: /usr/share/mythtv/themes/default/base.xml
2007-08-12 15:37:56.689 Registering Internal as a media playback plugin.
Test Popup Version Failed 
2007-08-12 15:37:56.748 Unable to initialize plugin 'mytharchive'.
2007-08-12 15:37:56.765 Unable to initialize plugin 'mythcontrols'.
2007-08-12 15:37:56.784 Unable to initialize plugin 'mythflix'.
2007-08-12 15:37:56.837 Unable to initialize plugin 'mythgallery'.
2007-08-12 15:37:56.869 Unable to initialize plugin 'mythvideo'.
2007-08-12 15:37:56.872 Connecting to backend server: 192.168.1.9:6543 (try 1 of 5)
2007-08-12 15:37:56.873 Using protocol version 35
2007-08-12 15:37:56.923 Starting media monitor.
2007-08-12 15:37:58.380 Using NV NPOT texture extension
2007-08-12 15:38:29.502 Received a remote 'Clear Cache' request
2007-08-12 15:38:32.364 XMLParse::LoadTheme using /usr/share/mythtv/themes/blootube-wide/ui.xml
2007-08-12 15:38:33.939 TV: Attempting to change from None to WatchingPreRecorded
2007-08-12 15:38:33.941 RingBuf(/home/mythtv/recordings/1443_20070812065700.mpg): OpenFile(/home/mythtv/recordings/1443_20070812065700.mpg, 12)
2007-08-12 15:38:34.100 RingBuf(/home/mythtv/recordings/1443_20070812065700.mpg): CalcReadAheadThresh(139465472 KB)
                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2007-08-12 15:38:34.123 DPMS Deactivated 
0: start_time: -9223372036854.775 duration: -9223372036854.775
1: start_time: -9223372036854.775 duration: -9223372036854.775
2: start_time: -9223372036854.775 duration: -9223372036854.775
stream: start_time: -9223372036854.775 duration: -9223372036854.775 bitrate=15320 kb/s
0: start_time: 7619.785 duration: 194.135
1: start_time: 7619.755 duration: 194.126
2: start_time: 7619.747 duration: 194.126
stream: start_time: 84663.853 duration: 2157.478 bitrate=4435 kb/s
2007-08-12 15:38:34.524 AFD: Stream #0, has id 0x65 codec id MPEG2VIDEO, type Video, bitrate 15000000 at 0x0x85079f0
2007-08-12 15:38:34.526 VDP: Accepting: cmp(<= 768 512) dec(ffmpeg) rend(opengl) osd(softblend) osdfade(disabled) deint(openglbobdeint,openglonefield) filt()
2007-08-12 15:38:34.526 VDP: Accepting: cmp(> 768 512) dec(ffmpeg) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2007-08-12 15:38:34.526 VDP: LoadBestPreferences(2048x2048, 0)
2007-08-12 15:38:34.526 VDP: LoadBestPreferences(2048x2048, 60)
2007-08-12 15:38:34.527 VDP: LoadBestPreferences(704x480, 60)
2007-08-12 15:38:34.528 VDP: Accepting: cmp(<= 768 512) dec(ffmpeg) rend(opengl) osd(softblend) osdfade(disabled) deint(openglbobdeint,openglonefield) filt()
2007-08-12 15:38:34.528 VDP: Accepting: cmp(> 768 512) dec(ffmpeg) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2007-08-12 15:38:34.528 VDP: LoadBestPreferences(2048x2048, 0)
2007-08-12 15:38:34.528 VDP: LoadBestPreferences(2048x2048, 60)
2007-08-12 15:38:34.528 VDP: LoadBestPreferences(704x480, 60)
2007-08-12 15:38:34.528 AFD: InitVideoCodec() 0x8558030 id(MPEG2VIDEO) type (Video).
2007-08-12 15:38:34.529 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2007-08-12 15:38:34.529 AFD: EIA-708 caption service #1 is in the English language.
2007-08-12 15:38:34.529 AFD: Using ffmpeg for video decoding
2007-08-12 15:38:34.529 AFD: Looking for decoder for MPEG2VIDEO
2007-08-12 15:38:34.529 AFD: Opened codec 0x8558030, id(MPEG2VIDEO) type(Video)
2007-08-12 15:38:34.529 AFD: Stream #1, has id 0x68 codec id AC3, type Audio, bitrate 192000 at 0x0x85d7ce0
2007-08-12 15:38:34.529 AFD: Looking for decoder for AC3
2007-08-12 15:38:34.530 AFD: Opened codec 0x84dd2d0, id(AC3) type(Audio)
2007-08-12 15:38:34.530 AFD: Stream #2, has id 0x69 codec id AC3, type Audio, bitrate 128000 at 0x0x82e2a10
2007-08-12 15:38:34.530 AFD: Looking for decoder for AC3
2007-08-12 15:38:34.531 AFD: Opened codec 0x82f2e60, id(AC3) type(Audio)
2007-08-12 15:38:34.531 RingBuf(/home/mythtv/recordings/1443_20070812065700.mpg): CalcReadAheadThresh(3060839852 KB)
                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2007-08-12 15:38:34.533 Opening ALSA audio device 'default'.
2007-08-12 15:38:34.654 Dec: Trying to select track (w/lang)
2007-08-12 15:38:34.655 Dec: Selecting first track
2007-08-12 15:38:34.655 Dec: Selected track #1 in the Unknown language(0)
2007-08-12 15:38:34.655 Dec: Selected track #1 in the English language(6647399)
2007-08-12 15:38:34.655 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2007-08-12 15:38:35.336 Position map filled from DB to: 4309
2007-08-12 15:38:35.337 SyncPositionMap prerecorded, from DB: 4310 entries
2007-08-12 15:38:35.337 SyncPositionMap, new totframes: 64635, new length: 2156, posMap size: 4310
2007-08-12 15:38:35.337 AFD: Position map found
2007-08-12 15:38:35.337 AFD: Successfully opened decoder for file: "/home/mythtv/recordings/1443_20070812065700.mpg". novideo(0)
2007-08-12 15:38:35.341 VideoOutput: Allowed renderers: ivtv,directfb,opengl,xv-blit,xshm,xlib
2007-08-12 15:38:35.341 VideoOutput: Allowed renderers (filt: ffmpeg): xlib,xshm,directfb,xv-blit,opengl
2007-08-12 15:38:35.343 VDP: Accepting: cmp(<= 768 512) dec(ffmpeg) rend(opengl) osd(softblend) osdfade(disabled) deint(openglbobdeint,openglonefield) filt()
2007-08-12 15:38:35.343 VDP: Accepting: cmp(> 768 512) dec(ffmpeg) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2007-08-12 15:38:35.343 VDP: LoadBestPreferences(2048x2048, 0)
2007-08-12 15:38:35.343 VDP: LoadBestPreferences(2048x2048, 60)
2007-08-12 15:38:35.343 VDP: LoadBestPreferences(704x480, 60)
2007-08-12 15:38:35.343 VideoOutput: Preferred renderer: opengl
2007-08-12 15:38:35.343 VideoOutput: Trying video renderer: opengl
2007-08-12 15:38:35.344 VDP: Accepting: cmp(<= 768 512) dec(ffmpeg) rend(opengl) osd(softblend) osdfade(disabled) deint(openglbobdeint,openglonefield) filt()
2007-08-12 15:38:35.344 VDP: Accepting: cmp(> 768 512) dec(ffmpeg) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,onefield) filt()
2007-08-12 15:38:35.345 VDP: LoadBestPreferences(2048x2048, 0)
2007-08-12 15:38:35.345 VDP: LoadBestPreferences(2048x2048, 60)
2007-08-12 15:38:35.351 VideoOutputXv: ctor
2007-08-12 15:38:35.352 XOff: 0, YOff: 0
2007-08-12 15:38:35.352 VDP: LoadBestPreferences(704x480, 60)
2007-08-12 15:38:35.352 Display Rect  left: 0, top: 0, width: 1920, height: 1080, aspect: 1.33333
2007-08-12 15:38:35.352 Video Rect    left: 0, top: 0, width: 704, height: 480, aspect: 1.33333
2007-08-12 15:38:35.352 Trying 1920x540 51 Hz
2007-08-12 15:38:35.447 SwitchToVideo: Video size 704 x 480: 
    Switched to displaying resolution 1920 x 540, 488mm x 274mm
2007-08-12 15:38:35.447 VideoOutputXv: Pixel dimensions: Screen 952x510, window 952x510
2007-08-12 15:38:35.551 VideoOutputXv: Estimated display dimensions: 487x274 mm  Aspect: 1.77737
2007-08-12 15:38:35.551 VideoOutputXv: Estimated window dimensions: 487x274 mm  Aspect: 1.77778
2007-08-12 15:38:35.588 VideoOutputXv: InitSetupBuffers() render: opengl, allowed: opengl,xv-blit,xshm,xlib
2007-08-12 15:38:35.715 GLCtx: Created window and context.
2007-08-12 15:38:35.747 GLCtx: Maximum supported texture size: 4096 x 4096
2007-08-12 15:38:35.747 GLVid: Viewport: 1920x540
2007-08-12 15:38:35.748 GLVid: Created main input texture 352x240
2007-08-12 15:38:35.750 GLVid: Created main input texture 352x240
2007-08-12 15:38:35.754 GLVid: Created main input texture 704x480
2007-08-12 15:38:35.755 GLVid: Creating master filter.
2007-08-12 15:38:35.755 GLVid: Created fragment program master.
2007-08-12 15:38:35.755 GLVid: Creating resize filter.
2007-08-12 15:38:35.763 GLCtx: Created frame buffer object (704x480).
2007-08-12 15:38:35.763 GLVid: Turning off deinterlacing.
2007-08-12 15:38:35.763 GLVid: Turning off deinterlacing.
2007-08-12 15:38:35.763 Created data @0xac36f020->0xac3eac22
2007-08-12 15:38:35.763 Created data @0xac2f3020->0xac36ec22
2007-08-12 15:38:35.763 Created data @0xac277020->0xac2f2c22
2007-08-12 15:38:35.764 Created data @0xac1fb020->0xac276c22
2007-08-12 15:38:35.764 Created data @0xac17f020->0xac1fac22
2007-08-12 15:38:35.764 Created data @0xac103020->0xac17ec22
2007-08-12 15:38:35.764 Created data @0xac087020->0xac102c22
2007-08-12 15:38:35.764 Created data @0xac00b020->0xac086c22
2007-08-12 15:38:35.764 Created data @0xabf8f020->0xac00ac22
2007-08-12 15:38:35.764 Created data @0xabf13020->0xabf8ec22
2007-08-12 15:38:35.764 Created data @0xabe97020->0xabf12c22
2007-08-12 15:38:35.764 Created data @0xabe1b020->0xabe96c22
2007-08-12 15:38:35.764 Created data @0xabd9f020->0xabe1ac22
2007-08-12 15:38:35.764 Created data @0xabd23020->0xabd9ec22
2007-08-12 15:38:35.764 Created data @0xabca7020->0xabd22c22
2007-08-12 15:38:35.764 Created data @0xabc2b020->0xabca6c22
2007-08-12 15:38:35.765 Created data @0xabbaf020->0xabc2ac22
2007-08-12 15:38:35.765 Created data @0xabb33020->0xabbaec22
2007-08-12 15:38:35.765 Created data @0xabab7020->0xabb32c22
2007-08-12 15:38:35.765 Created data @0xaba3b020->0xabab6c22
2007-08-12 15:38:35.765 Created data @0xab9bf020->0xaba3ac22
2007-08-12 15:38:35.765 Created data @0xab943020->0xab9bec22
2007-08-12 15:38:35.765 Created data @0xab8c7020->0xab942c22
2007-08-12 15:38:35.765 Created data @0xab84b020->0xab8c6c22
2007-08-12 15:38:35.765 Created data @0xab7cf020->0xab84ac22
2007-08-12 15:38:35.765 Created data @0xab753020->0xab7cec22
2007-08-12 15:38:35.765 Created data @0xab6d7020->0xab752c22
2007-08-12 15:38:35.765 Created data @0xab65b020->0xab6d6c22
2007-08-12 15:38:35.765 Created data @0xab5df020->0xab65ac22
2007-08-12 15:38:35.765 Created data @0xab563020->0xab5dec22
2007-08-12 15:38:35.766 Created data @0xab4e7020->0xab562c22
2007-08-12 15:38:35.766 Created data @0xab46b020->0xab4e6c22
2007-08-12 15:38:35.786 VDP: GetFilteredDeint() :  -> 'openglbobdeint'
2007-08-12 15:38:35.788 Failed to approve 'openglbobdeint' deinterlacer
2007-08-12 15:38:35.789 Couldn't load deinterlace filter 
2007-08-12 15:38:35.789 Using deinterlace method 
2007-08-12 15:38:35.789 VDP: SetVideoRenderer(opengl)
2007-08-12 15:38:35.789 VDP: SetVideoRender(opengl) == GetVideoRenderer()
2007-08-12 15:38:35.789 Display Rect  left: 240, top: 0, width: 1440, height: 540, aspect: 1.77778
2007-08-12 15:38:35.789 Video Rect    left: 0, top: 0, width: 704, height: 480, aspect: 1.33333
2007-08-12 15:38:35.790 Over/underscan. V: 0, H: 0
2007-08-12 15:38:35.790 Display Rect  left: 240, top: 0, width: 1440, height: 540, aspect: 1.77778
2007-08-12 15:38:35.790 Video Rect    left: 0, top: 0, width: 704, height: 480, aspect: 1.33333
2007-08-12 15:38:35.790 VDP: LoadBestPreferences(704x480, 29.97)
2007-08-12 15:38:36.506 TV: StartPlayer(): took 2219 ms to start player.
2007-08-12 15:38:36.507 TV: Changing from None to WatchingPreRecorded
2007-08-12 15:38:36.506 NVP: ClearAfterSeek(1)
2007-08-12 15:38:36.508 VideoOutputXv: ClearAfterSeek()
2007-08-12 15:38:36.509 VideoOutputXv: DiscardFrames(0)
2007-08-12 15:38:36.509 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2007-08-12 15:38:36.509 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2007-08-12 15:38:36.509 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2007-08-12 15:38:36.517 AFD: DoFastForward(1847 (1), do discard frames)
2007-08-12 15:38:36.517 Dec: DoFastForward(1847 (1), do discard frames)
2007-08-12 15:38:36.517 AFD: SeekReset(1845, 0, do flush, do discard)
2007-08-12 15:38:36.518 AFD: SeekReset() flushing
2007-08-12 15:38:36.518 VideoOutputXv: DiscardFrames(1)
2007-08-12 15:38:36.518 VideoBuffers::DiscardFrames(1): UAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2007-08-12 15:38:36.518 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2007-08-12 15:38:36.518 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2007-08-12 15:38:36.518 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2007-08-12 15:38:36.518 NVP: ClearAfterSeek(0)
2007-08-12 15:38:36.520 VDP: GetFilteredDeint() : opengl -> 'openglbobdeint'
2007-08-12 15:38:36.520 GLVid: Creating openglbobdeint filter.
2007-08-12 15:38:36.536 GLVid: Created fragment program openglbobdeint.
2007-08-12 15:38:36.541 GLCtx: Created frame buffer object (704x480).
2007-08-12 15:38:36.541 GLVid: Turning off deinterlacing.
2007-08-12 15:38:36.541 VideoOutputXv: Using deinterlace method openglbobdeint
2007-08-12 15:38:36.541 GLVid: Turning on deinterlacing.
2007-08-12 15:38:36.542 Using realtime priority.
2007-08-12 15:38:36.641 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver?
2007-08-12 15:38:36.641 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2007-08-12 15:38:36.642 OpenGLVideoSync()
2007-08-12 15:38:36.644 OpenGLVideoSync: x,y -> 960, 270
2007-08-12 15:38:36.647 Using OpenGLVideoSync
2007-08-12 15:38:36.647 Set video sync frame interval to 33366
2007-08-12 15:38:36.658 AFD: HandleGopStart: gopset not set, syncing positionMap
2007-08-12 15:38:36.658 Resyncing position map. posmapStarted = 1 livetv(0) watchingRec(0)
2007-08-12 15:38:36.658 AFD: HandleGopStart: Initial key frame distance: 15.
2007-08-12 15:38:36.661 Using audio as timebase
2007-08-12 15:38:36.661 Video timing method: SGI OpenGL
2007-08-12 15:38:36.661 Refresh rate: 16683, frame interval: 33366
2007-08-12 15:38:36.727 AO: dropping back audio_buffer_unused
2007-08-12 15:38:36.781 AO: dropping back audio_buffer_unused
2007-08-12 15:38:36.855 AO: dropping back audio_buffer_unused
2007-08-12 15:38:36.893 AO: dropping back audio_buffer_unused
2007-08-12 15:38:36.940 AO: dropping back audio_buffer_unused
2007-08-12 15:38:37.031 AO: dropping back audio_buffer_unused
2007-08-12 15:38:37.062 AO: dropping back audio_buffer_unused
2007-08-12 15:38:37.153 AO: dropping back audio_buffer_unused
2007-08-12 15:38:37.187 AO: dropping back audio_buffer_unused
2007-08-12 15:38:37.248 AO: dropping back audio_buffer_unused
2007-08-12 15:38:37.315 AO: dropping back audio_buffer_unused
2007-08-12 15:38:37.374 AO: dropping back audio_buffer_unused
2007-08-12 15:38:37.406 AO: dropping back audio_buffer_unused
2007-08-12 15:38:37.441 AO: dropping back audio_buffer_unused
2007-08-12 15:38:37.474 AO: dropping back audio_buffer_unused
2007-08-12 15:38:37.507 AO: dropping back audio_buffer_unused
2007-08-12 15:38:37.563 NVP: Play(  1.0, normal 1, unpause audio 1)
2007-08-12 15:38:38.965 NVP: Play(  1.0, normal 1, unpause audio 1)
2007-08-12 15:38:38.966 NVP: Changing speed to 1.05
2007-08-12 15:38:38.966 RingBuf(/home/mythtv/recordings/1443_20070812065700.mpg): CalcReadAheadThresh(3060839852 KB)
                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2007-08-12 15:38:38.966 NVP: DoPlay: rate: 29.97 speed: 1.05 skip: 1 => new interval 31777
2007-08-12 15:38:38.966 VDP: LoadBestPreferences(704x480, 31.4685)
2007-08-12 15:38:38.966 VDP: GetFilteredDeint() : opengl -> 'openglbobdeint'
2007-08-12 15:38:38.966 GLVid: Turning off deinterlacing.
2007-08-12 15:38:38.966 VDP: GetFilteredDeint(openglonefield) : opengl -> 'openglonefield'
2007-08-12 15:38:38.967 GLVid: Creating openglonefield filter.
2007-08-12 15:38:38.967 GLVid: Created fragment program openglonefield.
2007-08-12 15:38:38.977 GLCtx: Created frame buffer object (704x480).
2007-08-12 15:38:38.977 GLVid: Turning off deinterlacing.
2007-08-12 15:38:38.977 VideoOutputXv: Using deinterlace method openglonefield
2007-08-12 15:38:38.977 GLVid: Turning on deinterlacing.
2007-08-12 15:38:38.977 Set video sync frame interval to 31777
2007-08-12 15:38:38.977 NVP: Stretch Factor 1.05, disable passthru 
2007-08-12 15:38:38.982 Opening ALSA audio device 'default'.
2007-08-12 15:38:38.984 AO: Using time stretch 1.05
2007-08-12 15:38:39.042 NVP: Video is 8.17415 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.047 NVP: Video is 14.0766 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.051 NVP: Video is 18.2674 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.055 NVP: Video is 21.1824 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.059 NVP: Video is 23.1404 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.063 NVP: Video is 24.3808 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.067 NVP: Video is 25.083 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.071 NVP: Video is 25.3736 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.075 NVP: Video is 25.3634 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.079 NVP: Video is 25.1276 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.083 NVP: Video is 24.7304 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.087 NVP: Video is 24.2044 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.091 NVP: Video is 23.5818 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.096 NVP: Video is 22.8787 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.100 NVP: Video is 22.1233 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.104 NVP: Video is 21.3365 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.108 NVP: Video is 20.5103 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.112 NVP: Video is 19.6626 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.116 NVP: Video is 18.7907 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.120 NVP: Video is 17.9087 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.125 NVP: Video is 17.0269 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.129 NVP: Video is 16.1374 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.133 NVP: Video is 15.2421 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.137 NVP: Video is 14.3424 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.141 NVP: Video is 13.4317 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.145 NVP: Video is 12.5284 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.149 NVP: Video is 11.6227 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.153 NVP: Video is 10.7075 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.153 NVP: prebuffering pause
2007-08-12 15:38:39.153 NVP: Waiting for prebuffer.. 0 AAuLAAAAAAAAAAAAAAAAAAAAAAAAAAA
2007-08-12 15:38:39.215 NVP: Video is 9.7929 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.220 NVP: Video is 9.35869 frames behind audio (too slow), dropping frame to catch up.
'video_output' mean = '25094.77', std. dev. = '13922.21', fps = '39.85'
2007-08-12 15:38:39.224 NVP: Video is 8.81276 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.228 NVP: Video is 8.17516 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.232 NVP: Video is 7.46093 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.236 NVP: Video is 6.69711 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.240 NVP: Video is 5.89609 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.244 NVP: Video is 5.06719 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.248 NVP: Video is 4.21736 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.252 NVP: Video is 3.34396 frames behind audio (too slow), dropping frame to catch up.
2007-08-12 15:38:39.388 AO: dropping back audio_buffer_unused
2007-08-12 15:38:39.471 NVP: Video is 3.31214 frames ahead of audio,
                        doubling video frame interval to slow down.
2007-08-12 15:38:39.504 AO: dropping back audio_buffer_unused
2007-08-12 15:38:39.521 NVP: Video is 4.17557 frames ahead of audio,
                        doubling video frame interval to slow down.
2007-08-12 15:38:39.579 AO: dropping back audio_buffer_unused
2007-08-12 15:38:39.587 NVP: Video is 4.68153 frames ahead of audio,
                        doubling video frame interval to slow down.
2007-08-12 15:38:39.620 AO: dropping back audio_buffer_unused
2007-08-12 15:38:39.654 NVP: Video is 4.7699 frames ahead of audio,
                        doubling video frame interval to slow down.
2007-08-12 15:38:39.700 AO: dropping back audio_buffer_unused
2007-08-12 15:38:39.720 NVP: Video is 4.54508 frames ahead of audio,
                        doubling video frame interval to slow down.
2007-08-12 15:38:39.759 AO: dropping back audio_buffer_unused
2007-08-12 15:38:39.787 NVP: Video is 4.09324 frames ahead of audio,
                        doubling video frame interval to slow down.
2007-08-12 15:38:39.820 AO: dropping back audio_buffer_unused
2007-08-12 15:38:39.850 AO: dropping back audio_buffer_unused
2007-08-12 15:38:39.853 NVP: Video is 3.47116 frames ahead of audio,
                        doubling video frame interval to slow down.
2007-08-12 15:38:39.885 AO: dropping back audio_buffer_unused
2007-08-12 15:38:39.946 AO: dropping back audio_buffer_unused
2007-08-12 15:38:40.011 AO: dropping back audio_buffer_unused
2007-08-12 15:38:40.108 AO: dropping back audio_buffer_unused
2007-08-12 15:38:40.139 AO: dropping back audio_buffer_unused
2007-08-12 15:38:40.235 AO: dropping back audio_buffer_unused
2007-08-12 15:38:40.364 AO: dropping back audio_buffer_unused
2007-08-12 15:38:40.468 NVP: Play(  1.0, normal 1, unpause audio 1)
2007-08-12 15:38:40.478 NVP: Changing speed to 1
2007-08-12 15:38:40.478 RingBuf(/home/mythtv/recordings/1443_20070812065700.mpg): CalcReadAheadThresh(3060839852 KB)
                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2007-08-12 15:38:40.478 NVP: DoPlay: rate: 29.97 speed: 1 skip: 1 => new interval 33366
2007-08-12 15:38:40.478 VDP: LoadBestPreferences(704x480, 29.97)
2007-08-12 15:38:40.478 VDP: GetFilteredDeint() : opengl -> 'openglbobdeint'
2007-08-12 15:38:40.478 GLVid: Turning off deinterlacing.
2007-08-12 15:38:40.479 VDP: GetFilteredDeint() : opengl -> 'openglbobdeint'
2007-08-12 15:38:40.482 GLVid: Creating openglbobdeint filter.
2007-08-12 15:38:40.482 GLVid: Created fragment program openglbobdeint.