2008-09-27 17:00:44.795 Using runtime prefix = /usr/local
2008-09-27 17:00:45.220 XScreenSaver support enabled
2008-09-27 17:00:45.221 DPMS is active.
2008-09-27 17:00:45.221 Empty LocalHostName.
2008-09-27 17:00:45.221 Using localhost value of blah
2008-09-27 17:00:45.434 Testing network connectivity to 192.168.123.19
2008-09-27 17:00:45.442 New DB connection, total: 1
2008-09-27 17:00:45.446 Connected to database 'mythconverg' at host: 192.168.123.19
2008-09-27 17:00:45.447 Closing DB connection named 'DBManager0'
2008-09-27 17:00:45.448 Primary screen 0.
2008-09-27 17:00:45.449 Connected to database 'mythconverg' at host: 192.168.123.19
2008-09-27 17:00:45.449 Using screen 0, 1680x1050 at 0,0
2008-09-27 17:00:45.454 user: 1000 effective user: 1000 before privileged thread
2008-09-27 17:00:45.454 user: 1000 effective user: 1000 run_priv_thread
2008-09-27 17:00:45.454 user: 1000 effective user: 1000 after privileged thread
2008-09-27 17:00:45.456 New DB connection, total: 2
2008-09-27 17:00:45.457 Connected to database 'mythconverg' at host: 192.168.123.19
2008-09-27 17:00:45.459 mythfrontend version: 0.21.20080304-1 www.mythtv.org
2008-09-27 17:00:45.459 Enabled verbose msgs:  important general playback audio libav
2008-09-27 17:00:45.994 max_width: 1680 max_height: 1050
2008-09-27 17:00:46.073 No theme dir: /home/bryan/.mythtv/themes/ProjectGrayhem-wide
2008-09-27 17:00:46.074 Primary screen 0.
2008-09-27 17:00:46.075 Using screen 0, 1680x1050 at 0,0
2008-09-27 17:00:46.076 No theme dir: /home/bryan/.mythtv/themes/ProjectGrayhem-wide
2008-09-27 17:00:46.076 Switching to wide mode (ProjectGrayhem-wide)
2008-09-27 17:00:46.090 Using the Qt painter
2008-09-27 17:00:46.091 lirc_init failed for mythtv, see preceding messages
2008-09-27 17:00:46.091 JoystickMenuClient Error: Joystick disabled - Failed to read /home/bryan/.mythtv/joystickmenurc
2008-09-27 17:00:48.025 Loading from: /usr/local/share/mythtv/themes/ProjectGrayhem-wide/base.xml
2008-09-27 17:00:48.081 Loading from: /usr/local/share/mythtv/themes/default/base.xml
2008-09-27 17:00:48.150 Registering Internal as a media playback plugin.
2008-09-27 17:00:48.217 MonitorRegisterExtensions(0x100, gif,jpg,png)
2008-09-27 17:00:48.268 MythMusic adding CD-Writer: 7,0,0 -- DVD16+/-DL4RWlD2
2008-09-27 17:00:48.304 Key q is bound to multiple actions in context Music.
2008-09-27 17:00:48.305 Key z is bound to multiple actions in context Music.
2008-09-27 17:00:48.307 MonitorRegisterExtensions(0x40, ogg,mp3,aac,flac)
2008-09-27 17:00:48.355 No theme dir: /home/bryan/.mythtv/themes/ProjectGrayhem-wide
2008-09-27 17:00:51.774 XMLParse::LoadTheme using /usr/local/share/mythtv/themes/ProjectGrayhem-wide/video-ui.xml
2008-09-27 17:00:56.541 XMLParse::LoadTheme using /usr/local/share/mythtv/themes/ProjectGrayhem-wide/video-ui.xml
2008-09-27 17:01:01.362 Connecting to backend server: 192.168.123.19:6543 (try 1 of 5)
2008-09-27 17:01:01.363 Using protocol version 40
2008-09-27 17:01:01.438 TV: Attempting to change from None to WatchingPreRecorded
2008-09-27 17:01:01.438 RingBuf(/mnt/movies/Movies/movie2.avi): OpenFile(/mnt/movies/Movies/movie2.avi, 12)
2008-09-27 17:01:01.440 RingBuf(/mnt/movies/Movies/movie2.avi): CalcReadAheadThresh(1 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2008-09-27 17:01:01.540 DPMS Deactivated 
2008-09-27 17:01:01.967 AFD: Stream #0, has id 0x0 codec id H264, type Video, bitrate 0 at 0x0x45f8cc0
2008-09-27 17:01:01.970 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
2008-09-27 17:01:01.970 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,kerneldeint) filt()
2008-09-27 17:01:01.970 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
2008-09-27 17:01:01.970 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,kerneldeint) filt()
2008-09-27 17:01:01.970 VDP: LoadBestPreferences(2048x2048, 0)
2008-09-27 17:01:01.971 VDP: LoadBestPreferences(2048x2048, 60)
2008-09-27 17:01:01.971 VDP: LoadBestPreferences(720x480, 60)
2008-09-27 17:01:01.971 Using 1 CPUs for decoding
2008-09-27 17:01:01.973 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
2008-09-27 17:01:01.974 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,kerneldeint) filt()
2008-09-27 17:01:01.974 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
2008-09-27 17:01:01.974 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,kerneldeint) filt()
2008-09-27 17:01:01.974 VDP: LoadBestPreferences(2048x2048, 0)
2008-09-27 17:01:01.974 VDP: LoadBestPreferences(2048x2048, 60)
2008-09-27 17:01:01.975 VDP: LoadBestPreferences(720x480, 60)
2008-09-27 17:01:01.975 AFD: InitVideoCodec() 0x45f8e90 id(H264) type (Video).
2008-09-27 17:01:01.975 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
2008-09-27 17:01:01.975 AFD: Using ffmpeg for video decoding
2008-09-27 17:01:01.975 AFD: Looking for decoder for H264
2008-09-27 17:01:01.975 AFD: Opened codec 0x45f8e90, id(H264) type(Video)
2008-09-27 17:01:01.975 AFD: Stream #1, has id 0x1 codec id AC3, type Audio, bitrate 384000 at 0x0x45f9da0
2008-09-27 17:01:01.976 AFD: codec AC3 has 2 channels
2008-09-27 17:01:01.976 AFD: Looking for decoder for AC3
2008-09-27 17:01:01.977 AFD: Opened codec 0x45f92b0, id(AC3) type(Audio)
2008-09-27 17:01:01.977 AFD: Audio Track #1 is A/V stream #1 and has 2 channels in the Undetermined language(7695972).
2008-09-27 17:01:01.978 RingBuf(/mnt/movies/Movies/movie2.avi): CalcReadAheadThresh(3712391616 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(64 KB)
2008-09-27 17:01:01.978 AFD: Selected track 1: Undetermined AC3 2ch (A/V Stream #1)
2008-09-27 17:01:01.978 AFD: Initializing audio parms from audio track #1
2008-09-27 17:01:01.978 AFD: Audio format changed 
			from id(NONE)    -1Hz -1ch   0bps    ; id(NONE)    -1Hz -1ch   0bps   
			to   id( AC3) 48000Hz  2ch  16bps    ; id( AC3) 48000Hz  2ch  16bps   
2008-09-27 17:01:01.984 AO: Killing AudioOutputDSP
2008-09-27 17:01:01.984 Opening audio device 'default'. ch 2(2) sr 48000
2008-09-27 17:01:01.984 Opening ALSA audio device 'default'.
2008-09-27 17:01:02.029 in SetParameters(format=2, channels=2, rate=48000, buffer_time=500000, period_time=125000)
2008-09-27 17:01:02.029 get_buffer_size returned 16384
2008-09-27 17:01:02.030 set_period_time_near returned 21333
2008-09-27 17:01:02.030 get_period_size returned 1024
2008-09-27 17:01:02.031 Opening mixer default
2008-09-27 17:01:02.032 Setting Master volume to 70
2008-09-27 17:01:02.032 Volume range is 0 to 31, mult=3.22581
2008-09-27 17:01:02.032 channel 0 vol set to 22
2008-09-27 17:01:02.032 Volume range is 0 to 255, mult=0.392157
2008-09-27 17:01:02.032 Volume range is 0 to 255, mult=0.392157
2008-09-27 17:01:02.033 ALSA: channel 0 playback switch set to 1
2008-09-27 17:01:02.033 Setting Master volume to 70
2008-09-27 17:01:02.033 mixer unable to find control Master 1
2008-09-27 17:01:02.033 Setting PCM volume to 96
2008-09-27 17:01:02.033 Volume range is 0 to 255, mult=0.392157
2008-09-27 17:01:02.033 channel 0 vol set to 245
2008-09-27 17:01:02.033 Setting PCM volume to 96
2008-09-27 17:01:02.033 Volume range is 0 to 255, mult=0.392157
2008-09-27 17:01:02.034 channel 1 vol set to 245
2008-09-27 17:01:02.034 Volume range is 0 to 255, mult=0.392157
2008-09-27 17:01:02.034 AO: Audio fragment size: 6144
2008-09-27 17:01:02.034 AO: Audio Stretch Factor: 1
2008-09-27 17:01:02.034 Audio Codec Used: not set
2008-09-27 17:01:02.034 AO: kickoffOutputAudioLoop: pid = 8754
2008-09-27 17:01:02.035 AO: OutputAudioLoop: Play Event
2008-09-27 17:01:02.035 AO: Ending reconfigure
2008-09-27 17:01:02.035 AO: no change exiting
2008-09-27 17:01:02.035 Dec: Trying to select track (w/lang)
2008-09-27 17:01:02.035 Dec: Selecting first track
2008-09-27 17:01:02.035 Dec: Selected track #1 in the Unknown language(0)
2008-09-27 17:01:02.035 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2008-09-27 17:01:02.036 SyncPositionMap prerecorded, from DB: 0 entries
2008-09-27 17:01:02.037 AFD: Recording has no position -- using libavformat seeking.
2008-09-27 17:01:02.037 Input #0, avi, from '/mnt/movies/Movies/movie2.avi':
2008-09-27 17:01:02.037   Duration: 02:15:00.6, start: 0.000000, bitrate: 1351 kb/s
2008-09-27 17:01:02.037     Stream #0.0, 1001/30000: Video: h264, yuv420p, 720x480 [PAR 0:1 DAR 0:1], 1001/30000, 500 kb/s, 29.97 tb(r)
2008-09-27 17:01:02.037     Stream #0.1, 1/48000: Audio: ac3, 48000 Hz, stereo, 384 kb/s
2008-09-27 17:01:02.037 AFD: Successfully opened decoder for file: "/mnt/movies/Movies/movie2.avi". novideo(0)
2008-09-27 17:01:02.038 AO: no change exiting
2008-09-27 17:01:02.038 AO: Pause 1
2008-09-27 17:01:02.038 VideoOutput: Allowed renderers: directfb,xv-blit,xshm,xlib
2008-09-27 17:01:02.038 VideoOutput: Allowed renderers (filt: ffmpeg): xlib,xshm,directfb,xv-blit
2008-09-27 17:01:02.039 AO: OutputAudioLoop: audio paused
2008-09-27 17:01:02.040 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
2008-09-27 17:01:02.041 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,kerneldeint) filt()
2008-09-27 17:01:02.041 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
2008-09-27 17:01:02.041 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,kerneldeint) filt()
2008-09-27 17:01:02.041 VDP: LoadBestPreferences(2048x2048, 0)
2008-09-27 17:01:02.041 VDP: LoadBestPreferences(2048x2048, 60)
2008-09-27 17:01:02.041 VDP: LoadBestPreferences(720x480, 60)
2008-09-27 17:01:02.041 VideoOutput: Preferred renderer: xv-blit
2008-09-27 17:01:02.041 VideoOutput: Trying video renderer: xv-blit
2008-09-27 17:01:02.043 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
2008-09-27 17:01:02.043 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,kerneldeint) filt()
2008-09-27 17:01:02.044 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
2008-09-27 17:01:02.044 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,kerneldeint) filt()
2008-09-27 17:01:02.044 VDP: LoadBestPreferences(2048x2048, 0)
2008-09-27 17:01:02.049 VDP: LoadBestPreferences(2048x2048, 60)
2008-09-27 17:01:02.061 VideoOutputXv: ctor
2008-09-27 17:01:02.062 XOff: 0, YOff: 0
2008-09-27 17:01:02.062 VDP: LoadBestPreferences(720x480, 60)
2008-09-27 17:01:02.062 Display Rect  left: 0, top: 131, width: 1680, height: 787, aspect: 1.33333
2008-09-27 17:01:02.062 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.77778
2008-09-27 17:01:02.063 VideoOutputXv: Pixel dimensions: Screen 1680x1050, window 1680x1050
2008-09-27 17:01:02.063 VideoOutputXv: Estimated display dimensions: 410x256 mm  Aspect: 1.60156
2008-09-27 17:01:02.063 VideoOutputXv: Estimated window dimensions: 410x256 mm  Aspect: 1.60156
2008-09-27 17:01:02.063 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
2008-09-27 17:01:02.065 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
2008-09-27 17:01:02.065 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(xv-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,kerneldeint) filt()
2008-09-27 17:01:02.065 VDP: Accepting: cmp(>= 1280 720) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(disabled) deint(linearblend,linearblend) filt()
2008-09-27 17:01:02.065 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) cpus(1) rend(quartz-blit) osd(softblend) osdfade(enabled) deint(greedyhdoubleprocessdeint,kerneldeint) filt()
2008-09-27 17:01:02.065 VDP: LoadBestPreferences(2048x2048, 0)
2008-09-27 17:01:02.065 VDP: LoadBestPreferences(2048x2048, 60)
2008-09-27 17:01:02.065 VDP: LoadBestPreferences(720x480, 60)
2008-09-27 17:01:02.065 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  10
2008-09-27 17:01:02.066 VideoOutputXv: Adaptor#0: Intel(R) Textured Video has flag[s]: XvInputMask XvImageMask 
2008-09-27 17:01:02.066 VideoOutputXv: Has XVideo flags...
2008-09-27 17:01:02.066 VideoOutputXv: Has XV_BRIGHTNESS...
2008-09-27 17:01:02.066 VideoOutputXv: Here...
2008-09-27 17:01:02.066 VideoOutputXv: Grabbed xv port 94
2008-09-27 17:01:02.066 VideoOutputXv: XVideo surface found on port 94
2008-09-27 17:01:02.066 VideoOutputXv: XVideo Adaptor Name: 'Intel(R) Textured Video'
2008-09-27 17:01:02.066 VideoOutputXv: XVideo Format #0 is 'YUY2'
2008-09-27 17:01:02.066 VideoOutputXv: XVideo Format #1 is 'YV12'
2008-09-27 17:01:02.066 VideoOutputXv: XVideo Format #2 is 'I420'
2008-09-27 17:01:02.066 VideoOutputXv: XVideo Format #3 is 'UYVY'
2008-09-27 17:01:02.066 VideoOutputXv: XVideo Format #4 is 'XVMC'
2008-09-27 17:01:02.066 VideoOutputXv: Using XVideo Format 'YV12'
2008-09-27 17:01:02.066 VideoOutputXv: CreateShmImages(32): video_dim: 720x480
2008-09-27 17:01:02.081 VDP: SetVideoRenderer(xv-blit)
2008-09-27 17:01:02.081 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
2008-09-27 17:01:02.081 VideoOutputXv: Chromakeying not possible with this XVideo port.
2008-09-27 17:01:02.081 Display Rect  left: 0, top: 52, width: 1680, height: 946, aspect: 1.60156
2008-09-27 17:01:02.081 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.77778
2008-09-27 17:01:02.082 Over/underscan. V: 0, H: 0
2008-09-27 17:01:02.082 Display Rect  left: 0, top: 52, width: 1680, height: 946, aspect: 1.60156
2008-09-27 17:01:02.082 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.77778
2008-09-27 17:01:02.082 VDP: LoadBestPreferences(720x480, 29.97)
2008-09-27 17:01:02.083 NVP: LoadFilters(''..) -> 0
2008-09-27 17:01:02.084 OSD Theme Dimensions W: 640 H: 480
2008-09-27 17:01:02.242 TV: StartPlayer(): took 784 ms to start player.
2008-09-27 17:01:02.242 TV: Changing from None to WatchingPreRecorded
2008-09-27 17:01:02.242 NVP: ClearAfterSeek(1)
2008-09-27 17:01:02.243 VideoOutputXv: ClearAfterSeek()
2008-09-27 17:01:02.243 VideoOutputXv: DiscardFrames(0)
2008-09-27 17:01:02.243 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-09-27 17:01:02.243 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-09-27 17:01:02.243 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-09-27 17:01:02.243 AO: Pause 1
2008-09-27 17:01:02.244 New DB connection, total: 3
2008-09-27 17:01:02.244 Realtime priority would require SUID as root.
2008-09-27 17:01:02.244 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdoubleprocessdeint'
2008-09-27 17:01:02.245 AO: OutputAudioLoop: audio paused
2008-09-27 17:01:02.245 Connected to database 'mythconverg' at host: 192.168.123.19
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-09-27 17:01:02.247 Using deinterlace method greedyhdoubleprocessdeint
2008-09-27 17:01:02.258 nVidiaVideoSync: Could not open device /dev/nvidia0, No such device or address
2008-09-27 17:01:02.261 [ac3 @ 0x7f83dce60d50]frame CRC mismatch
2008-09-27 17:01:02.281 Set video sync frame interval to 33366
2008-09-27 17:01:02.297 Using audio as timebase
2008-09-27 17:01:02.298 Video timing method: DRM
2008-09-27 17:01:02.298 Refresh rate: 16560, frame interval: 33366
2008-09-27 17:01:02.314 NVP: progressive frame seen after 2 interlaced  frames
2008-09-27 17:01:02.315 AO: Pause 0
2008-09-27 17:01:02.316 AO: OutputAudioLoop: Play Event
2008-09-27 17:01:02.358 NVP: Video is 3.30426 frames behind audio (too slow), dropping frame to catch up.
2008-09-27 17:01:02.358 Set video sync frame interval to 33366
2008-09-27 17:01:02.358 Enabled deinterlacing
2008-09-27 17:01:02.359 NVP: Video is 5.55017 frames behind audio (too slow), dropping frame to catch up.
2008-09-27 17:01:02.361 NVP: Video is 6.99484 frames behind audio (too slow), dropping frame to catch up.
2008-09-27 17:01:02.362 NVP: Video is 7.83858 frames behind audio (too slow), dropping frame to catch up.
2008-09-27 17:01:02.364 NVP: Video is 8.23161 frames behind audio (too slow), dropping frame to catch up.
2008-09-27 17:01:02.365 NVP: Video is 8.29413 frames behind audio (too slow), dropping frame to catch up.
2008-09-27 17:01:02.366 NVP: Video is 8.09375 frames behind audio (too slow), dropping frame to catch up.
2008-09-27 17:01:02.368 NVP: Video is 7.70371 frames behind audio (too slow), dropping frame to catch up.
2008-09-27 17:01:02.369 NVP: Video is 7.1714 frames behind audio (too slow), dropping frame to catch up.
2008-09-27 17:01:02.371 NVP: Video is 6.5324 frames behind audio (too slow), dropping frame to catch up.
2008-09-27 17:01:02.372 NVP: Video is 5.55865 frames behind audio (too slow), dropping frame to catch up.
2008-09-27 17:01:02.373 NVP: Video is 4.59606 frames behind audio (too slow), dropping frame to catch up.
2008-09-27 17:01:02.375 NVP: Video is 3.64185 frames behind audio (too slow), dropping frame to catch up.
'video_output' mean = '33379.41', std. dev. = '18296.63', fps = '29.96'
'video_output' mean = '37754.51', std. dev. = '13186.27', fps = '26.49'
2008-09-27 17:01:10.155 VideoOutputXv: InputChanged(720,480,1.77917) 'H.264'->'H.264'
2008-09-27 17:01:10.156 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdoubleprocessdeint'
greedyhdeint: size changed from 0 x 0 -> 720 x 480
2008-09-27 17:01:10.157 Using deinterlace method greedyhdoubleprocessdeint
2008-09-27 17:01:10.157 VideoOutputXv: DiscardFrames(1)
2008-09-27 17:01:10.158 VideoBuffers::DiscardFrames(1): uuAUUUUUUUUUUUUUUUUUUUUUUUUUUUU
2008-09-27 17:01:10.158 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-09-27 17:01:10.158 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-09-27 17:01:10.158 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-09-27 17:01:10.161 Display Rect  left: 0, top: 52, width: 1680, height: 946, aspect: 1.60156
2008-09-27 17:01:10.161 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.77778
2008-09-27 17:01:10.191 NVP: prebuffering pause
2008-09-27 17:01:10.191 AO: Pause 1
2008-09-27 17:01:10.191 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-09-27 17:01:10.193 AO: OutputAudioLoop: audio paused
2008-09-27 17:01:10.238 NVP: ClearAfterSeek(1)
2008-09-27 17:01:10.238 VideoOutputXv: ClearAfterSeek()
2008-09-27 17:01:10.238 VideoOutputXv: DiscardFrames(0)
2008-09-27 17:01:10.238 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-09-27 17:01:10.239 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-09-27 17:01:10.239 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-09-27 17:01:10.239 NVP: LoadFilters(''..) -> 0
2008-09-27 17:01:10.239 detectInterlace(Detect Scan, Progressive Scan, 29.97, 480) ->Interlaced Scan
2008-09-27 17:01:10.239 Set video sync frame interval to 33366
2008-09-27 17:01:10.239 Enabled deinterlacing
2008-09-27 17:01:10.346 NVP: progressive frame seen after 2 interlaced  frames
2008-09-27 17:01:10.347 AO: Pause 0
2008-09-27 17:01:10.419 Set video sync frame interval to 33366
2008-09-27 17:01:10.419 Enabled deinterlacing
'video_output' mean = '40410.45', std. dev. = '22027.89', fps = '24.75'
'video_output' mean = '40910.35', std. dev. = '14803.11', fps = '24.44'
'video_output' mean = '41887.56', std. dev. = '15970.89', fps = '23.87'
'video_output' mean = '41563.54', std. dev. = '16238.38', fps = '24.06'
2008-09-27 17:01:26.758 NVP: 400 progressive frames seen.
'video_output' mean = '41732.98', std. dev. = '16098.11', fps = '23.96'
'video_output' mean = '41730.89', std. dev. = '15668.29', fps = '23.96'
'video_output' mean = '41728.99', std. dev. = '16210.45', fps = '23.96'
'video_output' mean = '41730.96', std. dev. = '15511.27', fps = '23.96'
2008-09-27 17:01:43.440 NVP: 800 progressive frames seen.
'video_output' mean = '41733.55', std. dev. = '16036.98', fps = '23.96'
'video_output' mean = '41572.74', std. dev. = '16000.82', fps = '24.05'
'video_output' mean = '41725.77', std. dev. = '19790.63', fps = '23.97'
'video_output' mean = '41901.07', std. dev. = '16438.61', fps = '23.87'
2008-09-27 17:02:00.116 NVP: 1200 progressive frames seen.
'video_output' mean = '41611.92', std. dev. = '16126.18', fps = '24.03'
'video_output' mean = '41611.41', std. dev. = '16223.93', fps = '24.03'
'video_output' mean = '41846.84', std. dev. = '15537.83', fps = '23.90'
'video_output' mean = '41681.23', std. dev. = '16752.39', fps = '23.99'
2008-09-27 17:02:16.804 NVP: 1600 progressive frames seen.
'video_output' mean = '41564.07', std. dev. = '15961.95', fps = '24.06'
'video_output' mean = '41898.36', std. dev. = '16973.87', fps = '23.87'
'video_output' mean = '41563.59', std. dev. = '16079.70', fps = '24.06'
'video_output' mean = '41738.31', std. dev. = '16224.07', fps = '23.96'
2008-09-27 17:02:33.496 NVP: 2000 progressive frames seen.
'video_output' mean = '41727.66', std. dev. = '15986.97', fps = '23.96'
2008-09-27 17:02:40.375 TV: Attempting to change from WatchingPreRecorded to None
2008-09-27 17:02:40.375 TV: StopStuff() -- begin
2008-09-27 17:02:40.375 TV: StopStuff(): stopping ring buffer[s]
2008-09-27 17:02:40.405 TV: StopStuff(): stopping player[s] (1/2)
2008-09-27 17:02:40.405 TV: StopStuff(): stopping player[s] (2/2)
2008-09-27 17:02:40.407 NVP: Exited decoder loop.
2008-09-27 17:02:40.452 VideoOutputXv: dtor
2008-09-27 17:02:40.452 VideoOutputXv: DiscardFrames(1)
2008-09-27 17:02:40.452 VideoBuffers::DiscardFrames(1): UUUUUUUUUUUUUUUUUUUUUUUUUUuuAUU
2008-09-27 17:02:40.452 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-09-27 17:02:40.452 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-09-27 17:02:40.452 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-09-27 17:02:40.452 VideoOutputXv: DiscardFrames(1)
2008-09-27 17:02:40.452 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2008-09-27 17:02:40.452 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-09-27 17:02:40.452 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2008-09-27 17:02:40.452 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2008-09-27 17:02:40.453 VideoOutputXv: Closing XVideo port 94
2008-09-27 17:02:40.455 AO: Killing AudioOutputDSP
2008-09-27 17:02:40.458 AO: OutputAudioLoop: Stop Event
2008-09-27 17:02:40.458 AO: kickoffOutputAudioLoop exiting
2008-09-27 17:02:40.515 TV: StopStuff() -- end
2008-09-27 17:02:40.515 TV: Changing from WatchingPreRecorded to None
2008-09-27 17:02:41.602 DPMS Reactivated.
