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.