2010-06-13 00:24:51.710 mythfrontend version: trunk [25100] www.mythtv.org
2010-06-13 00:24:51.711 Using runtime prefix = /usr/local
2010-06-13 00:24:51.711 Using configuration directory = /home/danielk/.mythtv
2010-06-13 00:24:52.719 Empty LocalHostName.
2010-06-13 00:24:52.719 Using localhost value of t61
2010-06-13 00:24:52.719 Testing network connectivity to '192.168.1.160'
2010-06-13 00:24:52.728 New DB connection, total: 1
2010-06-13 00:24:52.735 Connected to database 'mythconverg' at host: 192.168.1.160
2010-06-13 00:24:52.736 Closing DB connection named 'DBManager0'
2010-06-13 00:24:52.736 Current locale en_US
2010-06-13 00:24:52.736 No locale defaults file for en_US, skipping
2010-06-13 00:24:52.742 DPMS is disabled.
2010-06-13 00:24:52.749 Connected to database 'mythconverg' at host: 192.168.1.160
2010-06-13 00:24:52.786 Desktop video mode: 1680x1050 60.1106 Hz
2010-06-13 00:24:52.882 max_width: 1680 max_height: 1050
2010-06-13 00:24:52.907 user: 502 effective user: 502 before privileged thread
2010-06-13 00:24:52.908 user: 502 effective user: 502 after privileged thread
2010-06-13 00:24:52.908 user: 502 effective user: 502 run_priv_thread
2010-06-13 00:24:52.908 Enabled verbose msgs:  important general playback audio
2010-06-13 00:24:52.997 LIRC, Error: Failed to connect to Unix socket '/dev/lircd'
			eno: No such file or directory (2)
2010-06-13 00:24:52.997 JoystickMenuThread: Joystick disabled - Failed to read /home/danielk/.mythtv/joystickmenurc
2010-06-13 00:24:53.346 Using the OpenGL painter
2010-06-13 00:24:53.605 OpenGL: OpenGL vendor  : NVIDIA Corporation
2010-06-13 00:24:53.605 OpenGL: OpenGL renderer: Quadro NVS 140M/PCI/SSE2
2010-06-13 00:24:53.605 OpenGL: OpenGL version : 3.2.0 NVIDIA 195.36.15
2010-06-13 00:24:53.605 OpenGL: Max texture size: 8192 x 8192
2010-06-13 00:24:53.605 OpenGL: Max texture units: 4
2010-06-13 00:24:53.605 OpenGL: Direct rendering: Yes
2010-06-13 00:24:53.605 Initialised MythRenderOpenGL
2010-06-13 00:24:53.796 Current MythTV Schema Version (DBSchemaVer): 1259
2010-06-13 00:24:53.797 VDP: decoder<->render support: ffmpeg      null directfb xlib xshm xv-blit opengl vdpau
2010-06-13 00:24:53.797 VDP: decoder<->render support: libmpeg2    null directfb xlib xshm xv-blit opengl vdpau
2010-06-13 00:24:53.797 VDP: decoder<->render support: xvmc        xvmc-blit
2010-06-13 00:24:53.797 VDP: decoder<->render support: xvmc-vld    xvmc-blit
2010-06-13 00:24:53.797 VDP: decoder<->render support: vdpau       vdpau
2010-06-13 00:24:53.825 VDP: Ignoring profile item 102 (OSD Renderer opengl is not supported w/renderer xvmc-blit (supported: chromakey,ia44blend))
2010-06-13 00:24:53.834 VDP: Ignoring profile item 100 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-06-13 00:24:53.842 VDP: Ignoring profile item 106 (decoder ivtv is not supported (supported: ffmpeg,libmpeg2,xvmc,xvmc-vld,vdpau))
2010-06-13 00:24:53.850 VDP: Ignoring profile item 113 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-06-13 00:24:53.850 VDP: Ignoring profile item 114 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-06-13 00:24:53.860 VDP: Ignoring profile item 117 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-06-13 00:24:53.860 VDP: Ignoring profile item 118 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-06-13 00:24:53.870 VDP: Ignoring profile item 121 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-06-13 00:24:53.870 VDP: Ignoring profile item 122 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-06-13 00:24:57.190 ALSA: Found ALSA device: null, (Discard all samples (playback) or generate zero samples (capture))
2010-06-13 00:24:57.190 ALSA: Found ALSA device: default:CARD=Intel, (HDA Intel, AD198x Analog
Default Audio Device)
2010-06-13 00:24:57.190 ALSA: Found ALSA device: front:CARD=Intel,DEV=0, (HDA Intel, AD198x Analog
Front speakers)
2010-06-13 00:24:57.190 ALSA: Found ALSA device: surround40:CARD=Intel,DEV=0, (HDA Intel, AD198x Analog
4.0 Surround output to Front and Rear speakers)
2010-06-13 00:24:57.190 ALSA: Found ALSA device: surround41:CARD=Intel,DEV=0, (HDA Intel, AD198x Analog
4.1 Surround output to Front, Rear and Subwoofer speakers)
2010-06-13 00:24:57.190 ALSA: Found ALSA device: surround50:CARD=Intel,DEV=0, (HDA Intel, AD198x Analog
5.0 Surround output to Front, Center and Rear speakers)
2010-06-13 00:24:57.190 ALSA: Found ALSA device: surround51:CARD=Intel,DEV=0, (HDA Intel, AD198x Analog
5.1 Surround output to Front, Center, Rear and Subwoofer speakers)
2010-06-13 00:24:57.190 ALSA: Found ALSA device: surround71:CARD=Intel,DEV=0, (HDA Intel, AD198x Analog
7.1 Surround output to Front, Center, Side, Rear and Woofer speakers)
2010-06-13 00:24:57.190 ALSA: Found ALSA device: iec958:CARD=Intel,DEV=0, (HDA Intel, AD198x Digital
IEC958 (S/PDIF) Digital Audio Output)
2010-06-13 00:24:58.245 Registering Internal as a media playback plugin.
2010-06-13 00:24:58.250 Plugin mytharchive (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
2010-06-13 00:24:58.251 Test Popup Version Failed 
2010-06-13 00:24:58.251 Unable to initialize plugin 'mytharchive'.
2010-06-13 00:24:58.252 Plugin mythbrowser (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
2010-06-13 00:24:58.252 Unable to initialize plugin 'mythbrowser'.
2010-06-13 00:24:58.254 Plugin mythgallery (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
2010-06-13 00:24:58.254 Unable to initialize plugin 'mythgallery'.
2010-06-13 00:24:58.256 Plugin mythgame (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
2010-06-13 00:24:58.256 libmythgame.so/main.o: binary version mismatch
2010-06-13 00:24:58.256 Unable to initialize plugin 'mythgame'.
2010-06-13 00:24:58.257 Plugin mythmovies (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
2010-06-13 00:24:58.257 libmythmovies.so/main.o: binary version mismatch
2010-06-13 00:24:58.257 Unable to initialize plugin 'mythmovies'.
2010-06-13 00:24:58.268 Plugin mythmusic (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
2010-06-13 00:24:58.268 Unable to initialize plugin 'mythmusic'.
2010-06-13 00:24:58.269 Plugin mythnetvision (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
2010-06-13 00:24:58.269 Unable to initialize plugin 'mythnetvision'.
2010-06-13 00:24:58.271 Plugin mythnews (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
2010-06-13 00:24:58.271 Unable to initialize plugin 'mythnews'.
2010-06-13 00:24:58.273 Plugin mythvideo (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
2010-06-13 00:24:58.273 Unable to initialize plugin 'mythvideo'.
2010-06-13 00:24:58.275 Plugin mythweather (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
2010-06-13 00:24:58.275 Unable to initialize plugin 'mythweather'.
2010-06-13 00:24:58.276 Plugin mythzoneminder (0.23.20100526-2) binary version does not match libraries (0.23.201000612-1)
2010-06-13 00:24:58.276 Unable to initialize plugin 'mythzoneminder'.
2010-06-13 00:24:58.298 MMUnix::AddDevice() Error: failed to stat /dev/bdi, 
			eno: No such file or directory (2)
2010-06-13 00:24:58.302 MMUnix::AddDevice() Error: failed to stat /dev/power, 
			eno: No such file or directory (2)
2010-06-13 00:24:58.305 MMUnix::AddDevice() Error: failed to stat /dev/trace, 
			eno: No such file or directory (2)
2010-06-13 00:24:58.403 Loading menu theme from /usr/local/share/mythtv/themes/defaultmenu//mainmenu.xml
2010-06-13 00:24:58.408 Found mainmenu.xml for theme 'MythCenter-wide'
2010-06-13 00:24:58.477 MythCoreContext: Connecting to backend server: 192.168.1.160:6543 (try 1 of 1)
2010-06-13 00:24:58.486 Using protocol version 57
2010-06-13 00:24:58.525 SendReceiveStringList(QUERY_TIME_ZONE) called from UI thread
2010-06-13 00:24:59.207 Loading menu theme from /usr/local/share/mythtv/themes/defaultmenu//library.xml
2010-06-13 00:24:59.690 SendReceiveStringList(QUERY_HOSTNAME) called from UI thread
2010-06-13 00:24:59.703 New DB connection, total: 2
2010-06-13 00:24:59.708 Connected to database 'mythconverg' at host: 192.168.1.160
2010-06-13 00:25:01.243 TV: StartTV() -- begin
2010-06-13 00:25:01.243 TV: ctor -- begin
2010-06-13 00:25:01.267 TV: ctor -- end
2010-06-13 00:25:01.267 TV: Init -- begin
2010-06-13 00:25:01.270 TV: Init -- end channel groups
2010-06-13 00:25:01.297 TV: DrawUnusedRects() -- begin
2010-06-13 00:25:01.297 TV: DrawUnusedRects() -- end
2010-06-13 00:25:01.297 TV: Init -- end
2010-06-13 00:25:01.297 SendReceiveStringList(MESSAGE,SYSTEM_EVENT PLAY_STARTED HOSTNAME t61 CHANID 4019 STARTTIME 2010-06-11T19:00:00 SENDER t61) called from UI thread
2010-06-13 00:25:01.306 TV: tv->Playback() -- begin
2010-06-13 00:25:01.338 TV: tv->Playback() -- end
2010-06-13 00:25:01.338 TV: StartTV -- process events begin
2010-06-13 00:25:01.357 TV: HandleStateChange(0) -- begin
2010-06-13 00:25:01.357 TV: Attempting to change from None to WatchingPreRecorded
2010-06-13 00:25:01.378 SendReceiveStringList(QUERY_CHECKFILE,0...) called from UI thread
2010-06-13 00:25:01.409 RingBuf(myth://192.168.1.160:6543/4019_20100611190000.mpg): OpenFile(myth://192.168.1.160:6543/4019_20100611190000.mpg, 12)
2010-06-13 00:25:01.472 RingBuf(myth://192.168.1.160:6543/4019_20100611190000.mpg): CalcReadAheadThresh(0 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2010-06-13 00:25:01.534 TV: StartPlayer(0, WatchingPreRecorded, main) -- begin
2010-06-13 00:25:01.534 TV: Elapsed time since TV constructor was called: 291 ms
2010-06-13 00:25:01.624 AudioPlayer: Disabling Audio, params(0,2,44100)
2010-06-13 00:25:01.636 AO: Killing AudioOutputDSP
2010-06-13 00:25:01.636 ALSA: GetOutputSettings() opening default
2010-06-13 00:25:01.672 AO: Sample rate 48000 is supported
2010-06-13 00:25:01.672 AO: Original codec was NONE, unknown, 44 kHz, 2 channels
2010-06-13 00:25:01.672 AO: Resampling from 44 kHz to 48 kHz with quality medium
2010-06-13 00:25:01.672 AO: Audio processing enabled
2010-06-13 00:25:01.672 AO: Opening audio device 'default' ch 2(2) sr 48000 sf signed 32 bit reenc 0
2010-06-13 00:25:01.672 ALSA: Setting IEC958 status: audio
2010-06-13 00:25:01.711 Opening ALSA audio device 'default'.
2010-06-13 00:25:01.751 ALSA: SetParameters(format=10, channels=2, rate=48000, buffer_time=100000, period_time=50000)
2010-06-13 00:25:01.751 ALSA: Buffer time = 106666 us
2010-06-13 00:25:01.751 ALSA: Period time = 21333 us
2010-06-13 00:25:01.752 ALSA: Buffer size = 5120 | Period size = 1024
2010-06-13 00:25:01.756 ALSA: found playback control PCM on mixer device default
2010-06-13 00:25:01.756 ALSA: mixer volume range on mixer device default/PCM - min 0, max 39, range 39
2010-06-13 00:25:01.756 ALSA: mixer device default/PCM set up successfully
2010-06-13 00:25:01.759 ALSA: channel 0 volume set 100 => 39
2010-06-13 00:25:01.760 ALSA: channel 1 volume set 100 => 39
2010-06-13 00:25:01.760 ALSA: get volume channel 0: 100
2010-06-13 00:25:01.760 ALSA: channel 0 volume set 100 => 39
2010-06-13 00:25:01.760 ALSA: channel 1 volume set 100 => 39
2010-06-13 00:25:01.760 AO: Audio fragment size: 4096
2010-06-13 00:25:01.760 AO: Audio Stretch Factor: 1
2010-06-13 00:25:01.760 AO: Ending Reconfigure()
2010-06-13 00:25:01.760 AudioPlayer: Enabling Audio
2010-06-13 00:25:01.760 AO: kickoffOutputAudioLoop: pid = 29730
2010-06-13 00:25:01.760 AO: Reconfigure(): No change -> exiting
2010-06-13 00:25:01.760 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:02.718 AFD: Stream #0, has id 0x1011 codec id H264, type Video, bitrate 0 at 0x7fcb781f2210
2010-06-13 00:25:02.732 VDP: Ignoring profile item 100 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-06-13 00:25:02.733 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-06-13 00:25:02.733 VDP: LoadBestPreferences(2048x2048, 0)
2010-06-13 00:25:02.733 VDP: LoadBestPreferences(2048x2048, 60)
2010-06-13 00:25:02.733 VDP: LoadBestPreferences(1920x1080, 60)
2010-06-13 00:25:02.768 VDP: Ignoring profile item 100 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-06-13 00:25:02.768 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-06-13 00:25:02.768 VDP: LoadBestPreferences(2048x2048, 0)
2010-06-13 00:25:02.768 VDP: LoadBestPreferences(2048x2048, 60)
2010-06-13 00:25:02.768 VDP: LoadBestPreferences(1920x1080, 60)
2010-06-13 00:25:02.768 AFD: Using 1 CPUs for decoding
2010-06-13 00:25:02.768 AFD: InitVideoCodec() 0x7fcb781f2430 id(H264) type (Video).
2010-06-13 00:25:02.768 NVP(0): detectInterlace(Detect Scan, Interlaced Scan, 29.97, 1080) ->Interlaced Scan
2010-06-13 00:25:02.768 AFD: Using ffmpeg for video decoding
2010-06-13 00:25:02.768 AFD: Looking for decoder for H264
2010-06-13 00:25:02.769 AFD: Opened codec 0x7fcb781f2430, id(H264) type(Video)
2010-06-13 00:25:02.769 AFD: Stream #1, has id 0x1100 codec id AAC, type Audio, bitrate 142125 at 0x7fcb781f2a30
2010-06-13 00:25:02.769 AFD: codec AAC has 2 channels
2010-06-13 00:25:02.769 AFD: Looking for decoder for AAC
2010-06-13 00:25:02.770 AFD: Opened codec 0x7fcb78455240, id(AAC) type(Audio)
2010-06-13 00:25:02.770 AFD: Audio Track #1 is A/V stream #1 and has 2 channels in the Undetermined language(7695972).
2010-06-13 00:25:02.785 RingBuf(myth://192.168.1.160:6543/4019_20100611190000.mpg): CalcReadAheadThresh(0 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2010-06-13 00:25:02.785 AFD: Selected track 1: Undetermined AAC 2ch (A/V Stream #1)
2010-06-13 00:25:02.785 AFD: Initializing audio parms from audio track #1
2010-06-13 00:25:02.785 AFD: Audio format changed 
			from id(NONE)     -1Hz -1ch -1bps     to id( AAC)  48000Hz  2ch 16bps    
2010-06-13 00:25:02.785 AO: SetEffDsp: 4800000
2010-06-13 00:25:02.785 AO: Killing AudioOutputDSP
2010-06-13 00:25:02.791 AO: OutputAudioLoop: Stop Event
2010-06-13 00:25:02.791 AO: kickoffOutputAudioLoop exiting
2010-06-13 00:25:02.792 AO: Original codec was AAC, signed 16 bit, 48 kHz, 2 channels
2010-06-13 00:25:02.792 AO: Audio processing enabled
2010-06-13 00:25:02.792 AO: Opening audio device 'default' ch 2(2) sr 48000 sf signed 32 bit reenc 0
2010-06-13 00:25:02.792 ALSA: Setting IEC958 status: audio
2010-06-13 00:25:02.832 Opening ALSA audio device 'default'.
2010-06-13 00:25:02.871 ALSA: SetParameters(format=10, channels=2, rate=48000, buffer_time=100000, period_time=50000)
2010-06-13 00:25:02.871 ALSA: Buffer time = 106666 us
2010-06-13 00:25:02.871 ALSA: Period time = 21333 us
2010-06-13 00:25:02.872 ALSA: Buffer size = 5120 | Period size = 1024
2010-06-13 00:25:02.872 ALSA: found playback control PCM on mixer device default
2010-06-13 00:25:02.872 ALSA: mixer volume range on mixer device default/PCM - min 0, max 39, range 39
2010-06-13 00:25:02.872 ALSA: mixer device default/PCM set up successfully
2010-06-13 00:25:02.872 ALSA: channel 0 volume set 100 => 39
2010-06-13 00:25:02.872 ALSA: channel 1 volume set 100 => 39
2010-06-13 00:25:02.872 ALSA: get volume channel 0: 100
2010-06-13 00:25:02.872 ALSA: channel 0 volume set 100 => 39
2010-06-13 00:25:02.872 ALSA: channel 1 volume set 100 => 39
2010-06-13 00:25:02.872 AO: Audio fragment size: 4096
2010-06-13 00:25:02.872 AO: Audio Stretch Factor: 1
2010-06-13 00:25:02.872 AO: Ending Reconfigure()
2010-06-13 00:25:02.872 Dec: Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2010-06-13 00:25:02.872 AO: kickoffOutputAudioLoop: pid = 29730
2010-06-13 00:25:02.873 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:02.905 Position map filled from DB to: 56317
2010-06-13 00:25:02.905 Dec: SyncPositionMap prerecorded, from DB: 441 entries
2010-06-13 00:25:02.905 Dec: SyncPositionMap, new totframes: 56317, new length: 1879, posMap size: 441
2010-06-13 00:25:02.905 AFD: Position map found
2010-06-13 00:25:02.905 AFD: Successfully opened decoder for file: "myth://192.168.1.160:6543/4019_20100611190000.mpg". novideo(0)
2010-06-13 00:25:02.948 VideoOutput: Allowed renderers: directfb,xv-blit,xshm,xlib,opengl,vdpau
2010-06-13 00:25:02.948 VideoOutput: Allowed renderers (filt: ffmpeg): directfb,xlib,xshm,xv-blit,opengl,vdpau
2010-06-13 00:25:02.998 VDP: Ignoring profile item 100 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-06-13 00:25:02.998 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-06-13 00:25:02.998 VDP: LoadBestPreferences(2048x2048, 0)
2010-06-13 00:25:02.998 VDP: LoadBestPreferences(2048x2048, 60)
2010-06-13 00:25:02.998 VDP: LoadBestPreferences(1920x1080, 60)
2010-06-13 00:25:02.998 VideoOutput: Preferred renderer: xv-blit
2010-06-13 00:25:02.998 VideoOutput: Trying video renderer: 'xv-blit'
2010-06-13 00:25:03.375 VDP: Ignoring profile item 100 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-06-13 00:25:03.375 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-06-13 00:25:03.375 VDP: LoadBestPreferences(2048x2048, 0)
2010-06-13 00:25:03.375 VDP: LoadBestPreferences(2048x2048, 60)
2010-06-13 00:25:03.504 VideoOutputXv: ctor
2010-06-13 00:25:03.504 VideoOutWindow::SetPIPState. pip_state: 0]
2010-06-13 00:25:03.504 VideoOutputXv: Creating gc
2010-06-13 00:25:03.504 VideoOutputXv: XJ_screen_num: '0'
2010-06-13 00:25:03.504 VideoOutputXv: XJ_curwin:     '67108870'
2010-06-13 00:25:03.504 VideoOutputXv: XJ_win:        '67108870'
2010-06-13 00:25:03.504 VideoOutputXv: XJ_root:       '291'
2010-06-13 00:25:03.504 VideoOutputXv: XJ_gc:         '0x7fcb784147f0'
2010-06-13 00:25:03.505 Display Rect  left: 0, top: 61, width: 720, height: 364, aspect: 1.33333
2010-06-13 00:25:03.505 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-06-13 00:25:03.505 VDP: LoadBestPreferences(1920x1088, 60)
2010-06-13 00:25:03.505 Display Rect  left: 0, top: 61, width: 720, height: 364, aspect: 1.33333
2010-06-13 00:25:03.505 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-06-13 00:25:03.505 VideoOutput: Pixel dimensions: Screen 1680x1050, window 720x486
2010-06-13 00:25:03.505 VideoOutput: Actual display dimensions: 331x210 mm  Aspect: 1.57619
2010-06-13 00:25:03.505 VideoOutput: Estimated window dimensions: 141x97 mm  Aspect: 1.45361
2010-06-13 00:25:03.505 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
2010-06-13 00:25:03.569 VDP: Ignoring profile item 100 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,directfb,xlib,xshm,xv-blit,opengl,vdpau))
2010-06-13 00:25:03.569 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) skiploop(enabled) rend(xv-blit) osd(softblend) osdfade(enabled) deint(none,none) filt()
2010-06-13 00:25:03.569 VDP: LoadBestPreferences(2048x2048, 0)
2010-06-13 00:25:03.569 VDP: LoadBestPreferences(2048x2048, 60)
2010-06-13 00:25:03.569 VDP: LoadBestPreferences(1920x1088, 60)
2010-06-13 00:25:03.569 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  10
2010-06-13 00:25:03.569 VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask 
2010-06-13 00:25:03.569 VideoOutputXv: Has XVideo flags...
2010-06-13 00:25:03.570 VideoOutputXv: Has XV_BRIGHTNESS...
2010-06-13 00:25:03.570 VideoOutputXv: Here...
2010-06-13 00:25:03.570 VideoOutputXv: Grabbed xv port 254
2010-06-13 00:25:03.570 VideoOutputXv: XVideo surface found on port 254
2010-06-13 00:25:03.570 VideoOutputXv: XV_SET_DEFAULTS is supported on this port
2010-06-13 00:25:03.570 VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
2010-06-13 00:25:03.570 VideoOutputXv: XVideo Format #0 is 'YUY2'
2010-06-13 00:25:03.570 VideoOutputXv: XVideo Format #1 is 'YV12'
2010-06-13 00:25:03.570 VideoOutputXv: XVideo Format #2 is 'UYVY'
2010-06-13 00:25:03.570 VideoOutputXv: XVideo Format #3 is 'I420'
2010-06-13 00:25:03.570 VideoOutputXv: Using XVideo Format 'YV12'
2010-06-13 00:25:03.570 VideoOutputXv: CreateShmImages(32): video_dim: 1920x1088
2010-06-13 00:25:03.708 VDP: SetVideoRenderer(xv-blit)
2010-06-13 00:25:03.708 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2010-06-13 00:25:03.708 VideoOutputXv: Chromakeying not possible with this XVideo port.
2010-06-13 00:25:03.709 Display Rect  left: 0, top: 44, width: 720, height: 397, aspect: 1.45361
2010-06-13 00:25:03.709 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-06-13 00:25:03.724 Over/underscan. V: 0, H: 0
2010-06-13 00:25:03.724 Display Rect  left: 0, top: 44, width: 720, height: 397, aspect: 1.45361
2010-06-13 00:25:03.724 Video Rect    left: 0, top: 0, width: 1920, height: 1080, aspect: 1.77778
2010-06-13 00:25:03.724 VDP: LoadBestPreferences(1920x1088, 29.97)
2010-06-13 00:25:03.724 NVP(0): LoadFilters(''..) -> 0x0
2010-06-13 00:25:03.731 OSD: Base theme size: 1280x720
2010-06-13 00:25:03.731 OSD: Scaling factors: 1.5x1.5
2010-06-13 00:25:03.731 NVP(0): Decoder thread starting.
2010-06-13 00:25:03.741 OSD: Loaded window osd_message
2010-06-13 00:25:03.744 OSD: Loaded window osd_input
2010-06-13 00:25:03.759 OSD: Loaded window program_info
2010-06-13 00:25:03.763 OSD: Loaded window browse_info
2010-06-13 00:25:03.768 OSD: Loaded window osd_status
2010-06-13 00:25:03.772 OSD: Loaded window osd_program_editor
2010-06-13 00:25:03.772 OSD: Loaded OSD: size 1920x1080 offset 0+0
2010-06-13 00:25:03.772 OSD: Base theme size: 1280x720
2010-06-13 00:25:03.772 OSD: Scaling factors: 1.5x1.5
2010-06-13 00:25:03.803 OSD: Base theme size: 1280x720
2010-06-13 00:25:03.803 OSD: Scaling factors: 1.5x1.5
2010-06-13 00:25:03.830 NVP(0): ClearAfterSeek(1)
2010-06-13 00:25:03.830 VideoOutputXv: ClearAfterSeek()
2010-06-13 00:25:03.830 VideoOutputXv: DiscardFrames(0)
2010-06-13 00:25:03.830 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAuULL
2010-06-13 00:25:03.830 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAaALL -- done
2010-06-13 00:25:03.830 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAaALL -- done()
2010-06-13 00:25:03.831 VDP: GetFilteredDeint() : xv-blit -> 'none'
2010-06-13 00:25:03.832 Couldn't load deinterlace filter none
2010-06-13 00:25:03.832 Using deinterlace method 
2010-06-13 00:25:03.834 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:03.875 VSYNC: DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
2010-06-13 00:25:03.875 VSYNC: RTCVideoSync: Could not open /dev/rtc, Permission denied.
2010-06-13 00:25:03.875 NVP(0): Video timing method: USleep with busy wait
2010-06-13 00:25:03.875 NVP(0): Refresh rate: 16636, frame interval: 33366
2010-06-13 00:25:03.875 playCtx: StartPlaying(): took 0 ms to start player.
2010-06-13 00:25:03.875 TV: StartPlayer(0, WatchingPreRecorded, main) -- end ok
2010-06-13 00:25:03.876 SendReceiveStringList(MESSAGE,COMMFLAG_REQUEST 4019_2010-06-11T19:00:00) called from UI thread
2010-06-13 00:25:03.888 TV: Changing from None to WatchingPreRecorded
2010-06-13 00:25:03.893 TV: HandleStateChange(0) -- end
2010-06-13 00:25:03.925 NVP(0): Waiting for video buffers...
2010-06-13 00:25:03.925 AO: Pause 1
2010-06-13 00:25:03.925 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:04.025 NVP(0): Waited 100ms for video buffers ULUUULAAAAAAAAAAAAAAAAAAAAAAAuU
2010-06-13 00:25:04.034 NVP(0): Waited 100ms for video buffers UuUUULLAAAAAAAAAAAAAAAAAAAAAAUU
2010-06-13 00:25:04.162 NVP(0): Waited 100ms for video buffers UUUUUuUUULLAAAAAAAAAAAAAAAAAAUU
2010-06-13 00:25:04.187 AO: Pause 0
2010-06-13 00:25:04.187 VDP: GetFilteredDeint() : xv-blit -> 'none'
2010-06-13 00:25:04.188 Couldn't load deinterlace filter none
2010-06-13 00:25:04.189 Using deinterlace method 
2010-06-13 00:25:04.189 NVP(0): Failed to enable deinterlacing
2010-06-13 00:25:04.193 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:04.276 VideoOutput: Created YV12 OSD.
2010-06-13 00:25:06.304 TV: DoNVPSeek() -- begin
2010-06-13 00:25:06.304 AO: Pause 1
2010-06-13 00:25:06.304 TV: DoNVPSeek() -- ff
2010-06-13 00:25:06.304 TV: DoNVPSeek() -- end
2010-06-13 00:25:06.304 UpdateOSDSeekMessage(Skip Ahead, 1)
2010-06-13 00:25:06.305 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:06.347 AFD: DoFastForward(965 (85), do discard frames)
2010-06-13 00:25:06.347 Dec: DoFastForward(965 (85), do discard frames)
2010-06-13 00:25:06.347 Dec: FindPosition(965, search not adjusted) --> 
			[7:893(31791928),8:1021(36834464)]
2010-06-13 00:25:06.350 AFD: SeekReset(1021, 0, do flush, do discard)
2010-06-13 00:25:06.350 AFD: SeekReset() flushing
2010-06-13 00:25:06.350 VideoOutputXv: DiscardFrames(1)
2010-06-13 00:25:06.350 VideoBuffers::DiscardFrames(1): AUAUUUUUUUUUUUUUUAUAUAAAAAAAAUA
2010-06-13 00:25:06.350 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-06-13 00:25:06.350 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-06-13 00:25:06.350 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-06-13 00:25:06.351 NVP(0): ClearAfterSeek(0)
2010-06-13 00:25:06.351 NVP(0): Waiting for video buffers...
2010-06-13 00:25:06.454 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAAAALAAAAAAAA
2010-06-13 00:25:06.459 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAAAALAAAAAAAA
2010-06-13 00:25:06.549 AO: Pause 0
2010-06-13 00:25:06.561 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:06.587 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUAULAUALAAAA
2010-06-13 00:25:06.596 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAUAULAUALAAAA
2010-06-13 00:25:06.734 NVP(0): Waited 100ms for video buffers LAAAAAAAAAAAAAAAAAAUAUuUUULAUAA
2010-06-13 00:25:06.838 NVP(0): Waited 100ms for video buffers LALAAAAAAAAAAAAAAAAUAUUUUUuUUAA
2010-06-13 00:25:06.843 NVP(0): Waited 100ms for video buffers LALAAAAAAAAAAAAAAAAUAUUUUUuUUAA
2010-06-13 00:25:06.934 AO: Pause 0
2010-06-13 00:25:06.976 NVP(0): Video is 3.54663 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:06.976 NVP(0): Waiting for video buffers...
2010-06-13 00:25:06.976 AO: Pause 1
2010-06-13 00:25:06.988 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.001 AO: Pause 0
2010-06-13 00:25:07.002 NVP(0): Video is 5.31985 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:07.002 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.002 AO: Pause 1
2010-06-13 00:25:07.009 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.031 AO: Pause 0
2010-06-13 00:25:07.032 NVP(0): Video is 6.43248 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:07.032 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.032 AO: Pause 1
2010-06-13 00:25:07.052 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.099 AO: Pause 0
2010-06-13 00:25:07.100 NVP(0): Video is 6.92229 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:07.100 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.100 AO: Pause 1
2010-06-13 00:25:07.116 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.129 AO: Pause 0
2010-06-13 00:25:07.130 NVP(0): Video is 7.07235 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:07.130 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.130 AO: Pause 1
2010-06-13 00:25:07.137 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.159 AO: Pause 0
2010-06-13 00:25:07.160 NVP(0): Video is 6.99011 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:07.160 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.160 AO: Pause 1
2010-06-13 00:25:07.180 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.194 AO: Pause 0
2010-06-13 00:25:07.195 NVP(0): Video is 6.59126 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:07.195 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.195 AO: Pause 1
2010-06-13 00:25:07.201 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.233 AO: Pause 0
2010-06-13 00:25:07.234 NVP(0): Video is 6.14227 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:07.244 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:07.254 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.254 AO: Pause 1
2010-06-13 00:25:07.265 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.292 AO: Pause 0
2010-06-13 00:25:07.293 NVP(0): Video is 5.52829 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:07.293 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.293 AO: Pause 1
2010-06-13 00:25:07.308 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.322 AO: Pause 0
2010-06-13 00:25:07.323 NVP(0): Video is 4.94042 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:07.323 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.323 AO: Pause 1
2010-06-13 00:25:07.329 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.352 AO: Pause 0
2010-06-13 00:25:07.353 NVP(0): Video is 4.3122 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:07.354 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.354 AO: Pause 1
2010-06-13 00:25:07.372 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.420 AO: Pause 0
2010-06-13 00:25:07.421 NVP(0): Video is 3.50387 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:07.421 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.421 AO: Pause 1
2010-06-13 00:25:07.436 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.450 AO: Pause 0
2010-06-13 00:25:07.454 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.454 AO: Pause 1
2010-06-13 00:25:07.457 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.487 AO: Pause 0
2010-06-13 00:25:07.491 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.491 AO: Pause 1
2010-06-13 00:25:07.500 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.520 AO: Pause 0
2010-06-13 00:25:07.521 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:07.524 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.524 AO: Pause 1
2010-06-13 00:25:07.542 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.591 AO: Pause 0
2010-06-13 00:25:07.595 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.595 AO: Pause 1
2010-06-13 00:25:07.606 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.624 AO: Pause 0
2010-06-13 00:25:07.627 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.627 AO: Pause 1
2010-06-13 00:25:07.628 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.656 AO: Pause 0
2010-06-13 00:25:07.660 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.660 AO: Pause 1
2010-06-13 00:25:07.670 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.693 AO: Pause 0
2010-06-13 00:25:07.696 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.697 AO: Pause 1
2010-06-13 00:25:07.713 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.730 AO: Pause 0
2010-06-13 00:25:07.733 NVP(0): Video is 3.31184 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:07.733 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.733 AO: Pause 1
2010-06-13 00:25:07.734 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.767 AO: Pause 0
2010-06-13 00:25:07.777 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:07.786 NVP(0): Video is 4.17722 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:07.786 NVP(0): Waiting for video buffers...
2010-06-13 00:25:07.786 AO: Pause 1
2010-06-13 00:25:07.799 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:07.811 AO: Pause 0
2010-06-13 00:25:07.814 NVP(0): Video is 4.99856 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:07.820 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:07.860 NVP(0): Video is 5.81688 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:07.910 NVP(0): Video is 6.33321 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:07.960 NVP(0): Video is 6.60058 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:08.012 NVP(0): Video is 6.67374 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:08.060 NVP(0): Video is 6.57876 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:08.110 NVP(0): Video is 6.41012 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:08.160 NVP(0): Video is 6.15627 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:08.211 NVP(0): Video is 5.84598 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:08.260 NVP(0): Video is 5.4709 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:08.310 NVP(0): Video is 5.0847 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:08.360 NVP(0): Video is 4.66769 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:08.410 NVP(0): Video is 4.22754 frames ahead of audio,
			doubling video frame interval to slow down.
'video_output' mean = '41669.73', std. dev. = '64520.90', fps = '24.00'
2010-06-13 00:25:08.461 NVP(0): Video is 3.77007 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:08.510 NVP(0): Video is 3.29959 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:09.511 TV: DoNVPSeek() -- begin
2010-06-13 00:25:09.512 AO: Pause 1
2010-06-13 00:25:09.512 TV: DoNVPSeek() -- ff
2010-06-13 00:25:09.512 TV: DoNVPSeek() -- end
2010-06-13 00:25:09.512 UpdateOSDSeekMessage(Skip Ahead, 1)
2010-06-13 00:25:09.516 AFD: DoFastForward(1985 (1115), do discard frames)
2010-06-13 00:25:09.516 Dec: DoFastForward(1985 (1115), do discard frames)
2010-06-13 00:25:09.516 Dec: FindPosition(1985, search not adjusted) --> 
			[15:1917(69522588),16:2045(74940936)]
2010-06-13 00:25:09.519 AFD: SeekReset(2045, 0, do flush, do discard)
2010-06-13 00:25:09.519 AFD: SeekReset() flushing
2010-06-13 00:25:09.519 VideoOutputXv: DiscardFrames(1)
2010-06-13 00:25:09.519 VideoBuffers::DiscardFrames(1): UUUUUUUUUUUUUUUAUUUUUUUAAUUUUUU
2010-06-13 00:25:09.520 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-06-13 00:25:09.520 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-06-13 00:25:09.520 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-06-13 00:25:09.520 NVP(0): ClearAfterSeek(0)
2010-06-13 00:25:09.520 NVP(0): Waiting for video buffers...
2010-06-13 00:25:09.527 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:09.623 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-06-13 00:25:09.628 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-06-13 00:25:09.742 AO: Pause 0
2010-06-13 00:25:09.761 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:09.765 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAUAAAAAALLUUAAAAA
2010-06-13 00:25:09.895 NVP(0): Waited 100ms for video buffers AAAAAAAAAAAAAAAUAAAAAALuUULUUAU
2010-06-13 00:25:10.013 NVP(0): Waited 100ms for video buffers AAUAUALAAAAAAAAUAAAAAAuUUULUUAU
2010-06-13 00:25:10.066 AO: Pause 0
2010-06-13 00:25:10.072 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.072 AO: Pause 1
2010-06-13 00:25:10.081 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.102 AO: Pause 0
2010-06-13 00:25:10.103 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:10.106 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.106 AO: Pause 1
2010-06-13 00:25:10.124 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.140 AO: Pause 0
2010-06-13 00:25:10.141 NVP(0): Video is 3.45379 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:10.141 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.141 AO: Pause 1
2010-06-13 00:25:10.145 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.170 AO: Pause 0
2010-06-13 00:25:10.171 NVP(0): Video is 4.47848 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:10.171 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.171 AO: Pause 1
2010-06-13 00:25:10.188 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.200 AO: Pause 0
2010-06-13 00:25:10.201 NVP(0): Video is 4.89483 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:10.201 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.201 AO: Pause 1
2010-06-13 00:25:10.209 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.230 AO: Pause 0
2010-06-13 00:25:10.230 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:10.231 NVP(0): Video is 5.02727 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:10.231 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.231 AO: Pause 1
2010-06-13 00:25:10.252 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.260 AO: Pause 0
2010-06-13 00:25:10.261 NVP(0): Video is 4.9468 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:10.261 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.262 AO: Pause 1
2010-06-13 00:25:10.273 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.295 AO: Pause 0
2010-06-13 00:25:10.296 NVP(0): Video is 4.69912 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:10.296 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.296 AO: Pause 1
2010-06-13 00:25:10.316 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.325 AO: Pause 0
2010-06-13 00:25:10.326 NVP(0): Video is 4.20617 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:10.327 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.327 AO: Pause 1
2010-06-13 00:25:10.337 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.356 AO: Pause 0
2010-06-13 00:25:10.357 NVP(0): Video is 3.65663 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:10.357 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.357 AO: Pause 1
2010-06-13 00:25:10.359 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.386 AO: Pause 0
2010-06-13 00:25:10.387 NVP(0): Video is 3.05715 frames behind audio (too slow), dropping frame to catch up.
2010-06-13 00:25:10.387 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.388 AO: Pause 1
2010-06-13 00:25:10.401 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.416 AO: Pause 0
2010-06-13 00:25:10.420 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.420 AO: Pause 1
2010-06-13 00:25:10.423 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.453 AO: Pause 0
2010-06-13 00:25:10.458 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.458 AO: Pause 1
2010-06-13 00:25:10.465 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.487 AO: Pause 0
2010-06-13 00:25:10.490 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.490 AO: Pause 1
2010-06-13 00:25:10.495 AO: Pause 0
2010-06-13 00:25:10.508 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:10.531 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.531 AO: Pause 1
2010-06-13 00:25:10.551 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.564 AO: Pause 0
2010-06-13 00:25:10.568 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.568 AO: Pause 1
2010-06-13 00:25:10.572 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.597 AO: Pause 0
2010-06-13 00:25:10.601 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.601 AO: Pause 1
2010-06-13 00:25:10.615 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.630 AO: Pause 0
2010-06-13 00:25:10.635 NVP(0): Video is 3.35066 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:10.635 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.635 AO: Pause 1
2010-06-13 00:25:10.636 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.668 AO: Pause 0
2010-06-13 00:25:10.672 NVP(0): Video is 4.1239 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:10.672 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.672 AO: Pause 1
2010-06-13 00:25:10.679 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.705 AO: Pause 0
2010-06-13 00:25:10.721 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:10.724 NVP(0): Video is 4.98855 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:10.725 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.725 AO: Pause 1
2010-06-13 00:25:10.743 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.749 AO: Pause 0
2010-06-13 00:25:10.753 NVP(0): Video is 5.82437 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:10.753 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.753 AO: Pause 1
2010-06-13 00:25:10.764 AO: OutputAudioLoop: audio paused
2010-06-13 00:25:10.782 AO: Pause 0
2010-06-13 00:25:10.785 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:10.786 NVP(0): Video is 6.63855 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:10.786 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.786 AO: Pause 1
2010-06-13 00:25:10.795 AO: Pause 0
2010-06-13 00:25:10.807 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:10.815 NVP(0): Video is 7.41401 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:10.835 NVP(0): Waiting for video buffers...
2010-06-13 00:25:10.835 AO: Pause 1
2010-06-13 00:25:10.840 AO: Pause 0
2010-06-13 00:25:10.849 AO: OutputAudioLoop: Play Event
2010-06-13 00:25:10.859 NVP(0): Video is 8.02559 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:10.909 NVP(0): Video is 8.40185 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:10.959 NVP(0): Video is 8.57166 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.009 NVP(0): Video is 8.56414 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.058 NVP(0): Video is 8.43862 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.108 NVP(0): Video is 8.2246 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.159 NVP(0): Video is 7.9367 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.208 NVP(0): Video is 7.59342 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.259 NVP(0): Video is 7.21606 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.308 NVP(0): Video is 6.80567 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.359 NVP(0): Video is 6.37799 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.409 NVP(0): Video is 5.92238 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.458 NVP(0): Video is 5.45328 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.508 NVP(0): Video is 4.98906 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.559 NVP(0): Video is 4.51352 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.608 NVP(0): Video is 4.022 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.658 NVP(0): Video is 3.54097 frames ahead of audio,
			doubling video frame interval to slow down.
2010-06-13 00:25:11.709 NVP(0): Video is 3.05284 frames ahead of audio,
			doubling video frame interval to slow down.
'video_output' mean = '42152.03', std. dev. = '52971.03', fps = '23.72'
2010-06-13 00:25:14.980 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2010-06-13 00:25:14.980 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2010-06-13 00:25:15.079 TV: HandleStateChange(0) -- begin
2010-06-13 00:25:15.079 TV: Attempting to change from WatchingPreRecorded to None
2010-06-13 00:25:15.079 TV: StopStuff() for player ctx 0 -- begin
2010-06-13 00:25:15.079 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2010-06-13 00:25:15.079 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2010-06-13 00:25:15.079 NVP(0): StopPlaying - begin
2010-06-13 00:25:15.100 NVP(0): Decoder thread exiting.
2010-06-13 00:25:15.100 NVP(0): Exited decoder loop.
2010-06-13 00:25:15.100 VideoOutputXv: dtor
2010-06-13 00:25:15.100 VideoOutputXv: DiscardFrames(1)
2010-06-13 00:25:15.100 VideoBuffers::DiscardFrames(1): UULUUUUUUUUUUUUUAUUUUUUUUUuUuAU
2010-06-13 00:25:15.100 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-06-13 00:25:15.101 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-06-13 00:25:15.101 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-06-13 00:25:15.101 VideoOutputXv: DiscardFrames(1)
2010-06-13 00:25:15.101 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-06-13 00:25:15.101 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-06-13 00:25:15.101 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-06-13 00:25:15.101 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-06-13 00:25:15.106 VideoOutputXv: Closing XVideo port 254
2010-06-13 00:25:15.126 AO: Killing AudioOutputDSP
2010-06-13 00:25:15.137 AO: OutputAudioLoop: Stop Event
2010-06-13 00:25:15.137 AO: kickoffOutputAudioLoop exiting
2010-06-13 00:25:15.138 ALSA: Setting IEC958 status: audio
2010-06-13 00:25:15.172 NVP(0): StopPlaying - end
2010-06-13 00:25:15.172 TV: StopStuff(): stopping ring buffer
2010-06-13 00:25:15.208 TV: StopStuff(): stopping player
2010-06-13 00:25:15.208 TV: StopStuff() -- end
2010-06-13 00:25:15.208 TV: Changing from WatchingPreRecorded to None
2010-06-13 00:25:15.208 TV: HandleStateChange(0) -- end
2010-06-13 00:25:15.208 TV: StartTV -- process events end
2010-06-13 00:25:15.208 TV: StartTV -- process events 2 begin
2010-06-13 00:25:15.208 TV: StartTV -- process events 2 end
2010-06-13 00:25:15.212 TV::~TV() -- begin
2010-06-13 00:25:15.273 TV::~TV() -- lock
2010-06-13 00:25:15.273 NVP(0): StopPlaying - begin
2010-06-13 00:25:15.273 NVP(0): Exited decoder loop.
2010-06-13 00:25:15.273 NVP(0): StopPlaying - end
2010-06-13 00:25:15.292 TV::~TV() -- end
2010-06-13 00:25:15.292 SendReceiveStringList(MESSAGE,SYSTEM_EVENT PLAY_STOPPED HOSTNAME t61 CHANID 4019 STARTTIME 2010-06-11T19:00:00 SENDER t61) called from UI thread
2010-06-13 00:25:15.312 TV: StartTV -- end
2010-06-13 00:25:16.487 SendReceiveStringList(QUERY_IS_ACTIVE_BACKEND,t61) called from UI thread
2010-06-13 00:25:17.276 OpenGL: Deleting OpenGL Resources
2010-06-13 00:25:17.280 Deleting UPnP client...