Ticket #5750: good_playback.txt

File good_playback.txt, 23.1 KB (added by anonymous, 12 years ago)

log of video that does not have the issue

Line 
12008-09-27 17:00:44.795 Using runtime prefix = /usr/local
22008-09-27 17:00:45.220 XScreenSaver support enabled
32008-09-27 17:00:45.221 DPMS is active.
42008-09-27 17:00:45.221 Empty LocalHostName.
52008-09-27 17:00:45.221 Using localhost value of blah
62008-09-27 17:00:45.434 Testing network connectivity to 192.168.123.19
72008-09-27 17:00:45.442 New DB connection, total: 1
82008-09-27 17:00:45.446 Connected to database 'mythconverg' at host: 192.168.123.19
92008-09-27 17:00:45.447 Closing DB connection named 'DBManager0'
102008-09-27 17:00:45.448 Primary screen 0.
112008-09-27 17:00:45.449 Connected to database 'mythconverg' at host: 192.168.123.19
122008-09-27 17:00:45.449 Using screen 0, 1680x1050 at 0,0
132008-09-27 17:00:45.454 user: 1000 effective user: 1000 before privileged thread
142008-09-27 17:00:45.454 user: 1000 effective user: 1000 run_priv_thread
152008-09-27 17:00:45.454 user: 1000 effective user: 1000 after privileged thread
162008-09-27 17:00:45.456 New DB connection, total: 2
172008-09-27 17:00:45.457 Connected to database 'mythconverg' at host: 192.168.123.19
182008-09-27 17:00:45.459 mythfrontend version: 0.21.20080304-1 www.mythtv.org
192008-09-27 17:00:45.459 Enabled verbose msgs:  important general playback audio libav
202008-09-27 17:00:45.994 max_width: 1680 max_height: 1050
212008-09-27 17:00:46.073 No theme dir: /home/bryan/.mythtv/themes/ProjectGrayhem-wide
222008-09-27 17:00:46.074 Primary screen 0.
232008-09-27 17:00:46.075 Using screen 0, 1680x1050 at 0,0
242008-09-27 17:00:46.076 No theme dir: /home/bryan/.mythtv/themes/ProjectGrayhem-wide
252008-09-27 17:00:46.076 Switching to wide mode (ProjectGrayhem-wide)
262008-09-27 17:00:46.090 Using the Qt painter
272008-09-27 17:00:46.091 lirc_init failed for mythtv, see preceding messages
282008-09-27 17:00:46.091 JoystickMenuClient Error: Joystick disabled - Failed to read /home/bryan/.mythtv/joystickmenurc
292008-09-27 17:00:48.025 Loading from: /usr/local/share/mythtv/themes/ProjectGrayhem-wide/base.xml
302008-09-27 17:00:48.081 Loading from: /usr/local/share/mythtv/themes/default/base.xml
312008-09-27 17:00:48.150 Registering Internal as a media playback plugin.
322008-09-27 17:00:48.217 MonitorRegisterExtensions(0x100, gif,jpg,png)
332008-09-27 17:00:48.268 MythMusic adding CD-Writer: 7,0,0 -- DVD16+/-DL4RWlD2
342008-09-27 17:00:48.304 Key q is bound to multiple actions in context Music.
352008-09-27 17:00:48.305 Key z is bound to multiple actions in context Music.
362008-09-27 17:00:48.307 MonitorRegisterExtensions(0x40, ogg,mp3,aac,flac)
372008-09-27 17:00:48.355 No theme dir: /home/bryan/.mythtv/themes/ProjectGrayhem-wide
382008-09-27 17:00:51.774 XMLParse::LoadTheme using /usr/local/share/mythtv/themes/ProjectGrayhem-wide/video-ui.xml
392008-09-27 17:00:56.541 XMLParse::LoadTheme using /usr/local/share/mythtv/themes/ProjectGrayhem-wide/video-ui.xml
402008-09-27 17:01:01.362 Connecting to backend server: 192.168.123.19:6543 (try 1 of 5)
412008-09-27 17:01:01.363 Using protocol version 40
422008-09-27 17:01:01.438 TV: Attempting to change from None to WatchingPreRecorded
432008-09-27 17:01:01.438 RingBuf(/mnt/movies/Movies/movie2.avi): OpenFile(/mnt/movies/Movies/movie2.avi, 12)
442008-09-27 17:01:01.440 RingBuf(/mnt/movies/Movies/movie2.avi): CalcReadAheadThresh(1 KB)
45                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
462008-09-27 17:01:01.540 DPMS Deactivated
472008-09-27 17:01:01.967 AFD: Stream #0, has id 0x0 codec id H264, type Video, bitrate 0 at 0x0x45f8cc0
482008-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()
492008-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()
502008-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()
512008-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()
522008-09-27 17:01:01.970 VDP: LoadBestPreferences(2048x2048, 0)
532008-09-27 17:01:01.971 VDP: LoadBestPreferences(2048x2048, 60)
542008-09-27 17:01:01.971 VDP: LoadBestPreferences(720x480, 60)
552008-09-27 17:01:01.971 Using 1 CPUs for decoding
562008-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()
572008-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()
582008-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()
592008-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()
602008-09-27 17:01:01.974 VDP: LoadBestPreferences(2048x2048, 0)
612008-09-27 17:01:01.974 VDP: LoadBestPreferences(2048x2048, 60)
622008-09-27 17:01:01.975 VDP: LoadBestPreferences(720x480, 60)
632008-09-27 17:01:01.975 AFD: InitVideoCodec() 0x45f8e90 id(H264) type (Video).
642008-09-27 17:01:01.975 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
652008-09-27 17:01:01.975 AFD: Using ffmpeg for video decoding
662008-09-27 17:01:01.975 AFD: Looking for decoder for H264
672008-09-27 17:01:01.975 AFD: Opened codec 0x45f8e90, id(H264) type(Video)
682008-09-27 17:01:01.975 AFD: Stream #1, has id 0x1 codec id AC3, type Audio, bitrate 384000 at 0x0x45f9da0
692008-09-27 17:01:01.976 AFD: codec AC3 has 2 channels
702008-09-27 17:01:01.976 AFD: Looking for decoder for AC3
712008-09-27 17:01:01.977 AFD: Opened codec 0x45f92b0, id(AC3) type(Audio)
722008-09-27 17:01:01.977 AFD: Audio Track #1 is A/V stream #1 and has 2 channels in the Undetermined language(7695972).
732008-09-27 17:01:01.978 RingBuf(/mnt/movies/Movies/movie2.avi): CalcReadAheadThresh(3712391616 KB)
74                         -> threshhold(64 KB) min read(0 KB) blk size(64 KB)
752008-09-27 17:01:01.978 AFD: Selected track 1: Undetermined AC3 2ch (A/V Stream #1)
762008-09-27 17:01:01.978 AFD: Initializing audio parms from audio track #1
772008-09-27 17:01:01.978 AFD: Audio format changed
78                        from id(NONE)    -1Hz -1ch   0bps    ; id(NONE)    -1Hz -1ch   0bps   
79                        to   id( AC3) 48000Hz  2ch  16bps    ; id( AC3) 48000Hz  2ch  16bps   
802008-09-27 17:01:01.984 AO: Killing AudioOutputDSP
812008-09-27 17:01:01.984 Opening audio device 'default'. ch 2(2) sr 48000
822008-09-27 17:01:01.984 Opening ALSA audio device 'default'.
832008-09-27 17:01:02.029 in SetParameters(format=2, channels=2, rate=48000, buffer_time=500000, period_time=125000)
842008-09-27 17:01:02.029 get_buffer_size returned 16384
852008-09-27 17:01:02.030 set_period_time_near returned 21333
862008-09-27 17:01:02.030 get_period_size returned 1024
872008-09-27 17:01:02.031 Opening mixer default
882008-09-27 17:01:02.032 Setting Master volume to 70
892008-09-27 17:01:02.032 Volume range is 0 to 31, mult=3.22581
902008-09-27 17:01:02.032 channel 0 vol set to 22
912008-09-27 17:01:02.032 Volume range is 0 to 255, mult=0.392157
922008-09-27 17:01:02.032 Volume range is 0 to 255, mult=0.392157
932008-09-27 17:01:02.033 ALSA: channel 0 playback switch set to 1
942008-09-27 17:01:02.033 Setting Master volume to 70
952008-09-27 17:01:02.033 mixer unable to find control Master 1
962008-09-27 17:01:02.033 Setting PCM volume to 96
972008-09-27 17:01:02.033 Volume range is 0 to 255, mult=0.392157
982008-09-27 17:01:02.033 channel 0 vol set to 245
992008-09-27 17:01:02.033 Setting PCM volume to 96
1002008-09-27 17:01:02.033 Volume range is 0 to 255, mult=0.392157
1012008-09-27 17:01:02.034 channel 1 vol set to 245
1022008-09-27 17:01:02.034 Volume range is 0 to 255, mult=0.392157
1032008-09-27 17:01:02.034 AO: Audio fragment size: 6144
1042008-09-27 17:01:02.034 AO: Audio Stretch Factor: 1
1052008-09-27 17:01:02.034 Audio Codec Used: not set
1062008-09-27 17:01:02.034 AO: kickoffOutputAudioLoop: pid = 8754
1072008-09-27 17:01:02.035 AO: OutputAudioLoop: Play Event
1082008-09-27 17:01:02.035 AO: Ending reconfigure
1092008-09-27 17:01:02.035 AO: no change exiting
1102008-09-27 17:01:02.035 Dec: Trying to select track (w/lang)
1112008-09-27 17:01:02.035 Dec: Selecting first track
1122008-09-27 17:01:02.035 Dec: Selected track #1 in the Unknown language(0)
1132008-09-27 17:01:02.035 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1142008-09-27 17:01:02.036 SyncPositionMap prerecorded, from DB: 0 entries
1152008-09-27 17:01:02.037 AFD: Recording has no position -- using libavformat seeking.
1162008-09-27 17:01:02.037 Input #0, avi, from '/mnt/movies/Movies/movie2.avi':
1172008-09-27 17:01:02.037   Duration: 02:15:00.6, start: 0.000000, bitrate: 1351 kb/s
1182008-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)
1192008-09-27 17:01:02.037     Stream #0.1, 1/48000: Audio: ac3, 48000 Hz, stereo, 384 kb/s
1202008-09-27 17:01:02.037 AFD: Successfully opened decoder for file: "/mnt/movies/Movies/movie2.avi". novideo(0)
1212008-09-27 17:01:02.038 AO: no change exiting
1222008-09-27 17:01:02.038 AO: Pause 1
1232008-09-27 17:01:02.038 VideoOutput: Allowed renderers: directfb,xv-blit,xshm,xlib
1242008-09-27 17:01:02.038 VideoOutput: Allowed renderers (filt: ffmpeg): xlib,xshm,directfb,xv-blit
1252008-09-27 17:01:02.039 AO: OutputAudioLoop: audio paused
1262008-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()
1272008-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()
1282008-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()
1292008-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()
1302008-09-27 17:01:02.041 VDP: LoadBestPreferences(2048x2048, 0)
1312008-09-27 17:01:02.041 VDP: LoadBestPreferences(2048x2048, 60)
1322008-09-27 17:01:02.041 VDP: LoadBestPreferences(720x480, 60)
1332008-09-27 17:01:02.041 VideoOutput: Preferred renderer: xv-blit
1342008-09-27 17:01:02.041 VideoOutput: Trying video renderer: xv-blit
1352008-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()
1362008-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()
1372008-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()
1382008-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()
1392008-09-27 17:01:02.044 VDP: LoadBestPreferences(2048x2048, 0)
1402008-09-27 17:01:02.049 VDP: LoadBestPreferences(2048x2048, 60)
1412008-09-27 17:01:02.061 VideoOutputXv: ctor
1422008-09-27 17:01:02.062 XOff: 0, YOff: 0
1432008-09-27 17:01:02.062 VDP: LoadBestPreferences(720x480, 60)
1442008-09-27 17:01:02.062 Display Rect  left: 0, top: 131, width: 1680, height: 787, aspect: 1.33333
1452008-09-27 17:01:02.062 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.77778
1462008-09-27 17:01:02.063 VideoOutputXv: Pixel dimensions: Screen 1680x1050, window 1680x1050
1472008-09-27 17:01:02.063 VideoOutputXv: Estimated display dimensions: 410x256 mm  Aspect: 1.60156
1482008-09-27 17:01:02.063 VideoOutputXv: Estimated window dimensions: 410x256 mm  Aspect: 1.60156
1492008-09-27 17:01:02.063 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
1502008-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()
1512008-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()
1522008-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()
1532008-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()
1542008-09-27 17:01:02.065 VDP: LoadBestPreferences(2048x2048, 0)
1552008-09-27 17:01:02.065 VDP: LoadBestPreferences(2048x2048, 60)
1562008-09-27 17:01:02.065 VDP: LoadBestPreferences(720x480, 60)
1572008-09-27 17:01:02.065 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  10
1582008-09-27 17:01:02.066 VideoOutputXv: Adaptor#0: Intel(R) Textured Video has flag[s]: XvInputMask XvImageMask
1592008-09-27 17:01:02.066 VideoOutputXv: Has XVideo flags...
1602008-09-27 17:01:02.066 VideoOutputXv: Has XV_BRIGHTNESS...
1612008-09-27 17:01:02.066 VideoOutputXv: Here...
1622008-09-27 17:01:02.066 VideoOutputXv: Grabbed xv port 94
1632008-09-27 17:01:02.066 VideoOutputXv: XVideo surface found on port 94
1642008-09-27 17:01:02.066 VideoOutputXv: XVideo Adaptor Name: 'Intel(R) Textured Video'
1652008-09-27 17:01:02.066 VideoOutputXv: XVideo Format #0 is 'YUY2'
1662008-09-27 17:01:02.066 VideoOutputXv: XVideo Format #1 is 'YV12'
1672008-09-27 17:01:02.066 VideoOutputXv: XVideo Format #2 is 'I420'
1682008-09-27 17:01:02.066 VideoOutputXv: XVideo Format #3 is 'UYVY'
1692008-09-27 17:01:02.066 VideoOutputXv: XVideo Format #4 is 'XVMC'
1702008-09-27 17:01:02.066 VideoOutputXv: Using XVideo Format 'YV12'
1712008-09-27 17:01:02.066 VideoOutputXv: CreateShmImages(32): video_dim: 720x480
1722008-09-27 17:01:02.081 VDP: SetVideoRenderer(xv-blit)
1732008-09-27 17:01:02.081 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
1742008-09-27 17:01:02.081 VideoOutputXv: Chromakeying not possible with this XVideo port.
1752008-09-27 17:01:02.081 Display Rect  left: 0, top: 52, width: 1680, height: 946, aspect: 1.60156
1762008-09-27 17:01:02.081 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.77778
1772008-09-27 17:01:02.082 Over/underscan. V: 0, H: 0
1782008-09-27 17:01:02.082 Display Rect  left: 0, top: 52, width: 1680, height: 946, aspect: 1.60156
1792008-09-27 17:01:02.082 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.77778
1802008-09-27 17:01:02.082 VDP: LoadBestPreferences(720x480, 29.97)
1812008-09-27 17:01:02.083 NVP: LoadFilters(''..) -> 0
1822008-09-27 17:01:02.084 OSD Theme Dimensions W: 640 H: 480
1832008-09-27 17:01:02.242 TV: StartPlayer(): took 784 ms to start player.
1842008-09-27 17:01:02.242 TV: Changing from None to WatchingPreRecorded
1852008-09-27 17:01:02.242 NVP: ClearAfterSeek(1)
1862008-09-27 17:01:02.243 VideoOutputXv: ClearAfterSeek()
1872008-09-27 17:01:02.243 VideoOutputXv: DiscardFrames(0)
1882008-09-27 17:01:02.243 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
1892008-09-27 17:01:02.243 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
1902008-09-27 17:01:02.243 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
1912008-09-27 17:01:02.243 AO: Pause 1
1922008-09-27 17:01:02.244 New DB connection, total: 3
1932008-09-27 17:01:02.244 Realtime priority would require SUID as root.
1942008-09-27 17:01:02.244 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdoubleprocessdeint'
1952008-09-27 17:01:02.245 AO: OutputAudioLoop: audio paused
1962008-09-27 17:01:02.245 Connected to database 'mythconverg' at host: 192.168.123.19
197greedyhdeint: size changed from 0 x 0 -> 720 x 480
1982008-09-27 17:01:02.247 Using deinterlace method greedyhdoubleprocessdeint
1992008-09-27 17:01:02.258 nVidiaVideoSync: Could not open device /dev/nvidia0, No such device or address
2002008-09-27 17:01:02.261 [ac3 @ 0x7f83dce60d50]frame CRC mismatch
2012008-09-27 17:01:02.281 Set video sync frame interval to 33366
2022008-09-27 17:01:02.297 Using audio as timebase
2032008-09-27 17:01:02.298 Video timing method: DRM
2042008-09-27 17:01:02.298 Refresh rate: 16560, frame interval: 33366
2052008-09-27 17:01:02.314 NVP: progressive frame seen after 2 interlaced  frames
2062008-09-27 17:01:02.315 AO: Pause 0
2072008-09-27 17:01:02.316 AO: OutputAudioLoop: Play Event
2082008-09-27 17:01:02.358 NVP: Video is 3.30426 frames behind audio (too slow), dropping frame to catch up.
2092008-09-27 17:01:02.358 Set video sync frame interval to 33366
2102008-09-27 17:01:02.358 Enabled deinterlacing
2112008-09-27 17:01:02.359 NVP: Video is 5.55017 frames behind audio (too slow), dropping frame to catch up.
2122008-09-27 17:01:02.361 NVP: Video is 6.99484 frames behind audio (too slow), dropping frame to catch up.
2132008-09-27 17:01:02.362 NVP: Video is 7.83858 frames behind audio (too slow), dropping frame to catch up.
2142008-09-27 17:01:02.364 NVP: Video is 8.23161 frames behind audio (too slow), dropping frame to catch up.
2152008-09-27 17:01:02.365 NVP: Video is 8.29413 frames behind audio (too slow), dropping frame to catch up.
2162008-09-27 17:01:02.366 NVP: Video is 8.09375 frames behind audio (too slow), dropping frame to catch up.
2172008-09-27 17:01:02.368 NVP: Video is 7.70371 frames behind audio (too slow), dropping frame to catch up.
2182008-09-27 17:01:02.369 NVP: Video is 7.1714 frames behind audio (too slow), dropping frame to catch up.
2192008-09-27 17:01:02.371 NVP: Video is 6.5324 frames behind audio (too slow), dropping frame to catch up.
2202008-09-27 17:01:02.372 NVP: Video is 5.55865 frames behind audio (too slow), dropping frame to catch up.
2212008-09-27 17:01:02.373 NVP: Video is 4.59606 frames behind audio (too slow), dropping frame to catch up.
2222008-09-27 17:01:02.375 NVP: Video is 3.64185 frames behind audio (too slow), dropping frame to catch up.
223'video_output' mean = '33379.41', std. dev. = '18296.63', fps = '29.96'
224'video_output' mean = '37754.51', std. dev. = '13186.27', fps = '26.49'
2252008-09-27 17:01:10.155 VideoOutputXv: InputChanged(720,480,1.77917) 'H.264'->'H.264'
2262008-09-27 17:01:10.156 VDP: GetFilteredDeint() : xv-blit -> 'greedyhdoubleprocessdeint'
227greedyhdeint: size changed from 0 x 0 -> 720 x 480
2282008-09-27 17:01:10.157 Using deinterlace method greedyhdoubleprocessdeint
2292008-09-27 17:01:10.157 VideoOutputXv: DiscardFrames(1)
2302008-09-27 17:01:10.158 VideoBuffers::DiscardFrames(1): uuAUUUUUUUUUUUUUUUUUUUUUUUUUUUU
2312008-09-27 17:01:10.158 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2322008-09-27 17:01:10.158 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2332008-09-27 17:01:10.158 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2342008-09-27 17:01:10.161 Display Rect  left: 0, top: 52, width: 1680, height: 946, aspect: 1.60156
2352008-09-27 17:01:10.161 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.77778
2362008-09-27 17:01:10.191 NVP: prebuffering pause
2372008-09-27 17:01:10.191 AO: Pause 1
2382008-09-27 17:01:10.191 NVP: Waiting for prebuffer.. 0 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2392008-09-27 17:01:10.193 AO: OutputAudioLoop: audio paused
2402008-09-27 17:01:10.238 NVP: ClearAfterSeek(1)
2412008-09-27 17:01:10.238 VideoOutputXv: ClearAfterSeek()
2422008-09-27 17:01:10.238 VideoOutputXv: DiscardFrames(0)
2432008-09-27 17:01:10.238 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2442008-09-27 17:01:10.239 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2452008-09-27 17:01:10.239 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2462008-09-27 17:01:10.239 NVP: LoadFilters(''..) -> 0
2472008-09-27 17:01:10.239 detectInterlace(Detect Scan, Progressive Scan, 29.97, 480) ->Interlaced Scan
2482008-09-27 17:01:10.239 Set video sync frame interval to 33366
2492008-09-27 17:01:10.239 Enabled deinterlacing
2502008-09-27 17:01:10.346 NVP: progressive frame seen after 2 interlaced  frames
2512008-09-27 17:01:10.347 AO: Pause 0
2522008-09-27 17:01:10.419 Set video sync frame interval to 33366
2532008-09-27 17:01:10.419 Enabled deinterlacing
254'video_output' mean = '40410.45', std. dev. = '22027.89', fps = '24.75'
255'video_output' mean = '40910.35', std. dev. = '14803.11', fps = '24.44'
256'video_output' mean = '41887.56', std. dev. = '15970.89', fps = '23.87'
257'video_output' mean = '41563.54', std. dev. = '16238.38', fps = '24.06'
2582008-09-27 17:01:26.758 NVP: 400 progressive frames seen.
259'video_output' mean = '41732.98', std. dev. = '16098.11', fps = '23.96'
260'video_output' mean = '41730.89', std. dev. = '15668.29', fps = '23.96'
261'video_output' mean = '41728.99', std. dev. = '16210.45', fps = '23.96'
262'video_output' mean = '41730.96', std. dev. = '15511.27', fps = '23.96'
2632008-09-27 17:01:43.440 NVP: 800 progressive frames seen.
264'video_output' mean = '41733.55', std. dev. = '16036.98', fps = '23.96'
265'video_output' mean = '41572.74', std. dev. = '16000.82', fps = '24.05'
266'video_output' mean = '41725.77', std. dev. = '19790.63', fps = '23.97'
267'video_output' mean = '41901.07', std. dev. = '16438.61', fps = '23.87'
2682008-09-27 17:02:00.116 NVP: 1200 progressive frames seen.
269'video_output' mean = '41611.92', std. dev. = '16126.18', fps = '24.03'
270'video_output' mean = '41611.41', std. dev. = '16223.93', fps = '24.03'
271'video_output' mean = '41846.84', std. dev. = '15537.83', fps = '23.90'
272'video_output' mean = '41681.23', std. dev. = '16752.39', fps = '23.99'
2732008-09-27 17:02:16.804 NVP: 1600 progressive frames seen.
274'video_output' mean = '41564.07', std. dev. = '15961.95', fps = '24.06'
275'video_output' mean = '41898.36', std. dev. = '16973.87', fps = '23.87'
276'video_output' mean = '41563.59', std. dev. = '16079.70', fps = '24.06'
277'video_output' mean = '41738.31', std. dev. = '16224.07', fps = '23.96'
2782008-09-27 17:02:33.496 NVP: 2000 progressive frames seen.
279'video_output' mean = '41727.66', std. dev. = '15986.97', fps = '23.96'
2802008-09-27 17:02:40.375 TV: Attempting to change from WatchingPreRecorded to None
2812008-09-27 17:02:40.375 TV: StopStuff() -- begin
2822008-09-27 17:02:40.375 TV: StopStuff(): stopping ring buffer[s]
2832008-09-27 17:02:40.405 TV: StopStuff(): stopping player[s] (1/2)
2842008-09-27 17:02:40.405 TV: StopStuff(): stopping player[s] (2/2)
2852008-09-27 17:02:40.407 NVP: Exited decoder loop.
2862008-09-27 17:02:40.452 VideoOutputXv: dtor
2872008-09-27 17:02:40.452 VideoOutputXv: DiscardFrames(1)
2882008-09-27 17:02:40.452 VideoBuffers::DiscardFrames(1): UUUUUUUUUUUUUUUUUUUUUUUUUUuuAUU
2892008-09-27 17:02:40.452 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2902008-09-27 17:02:40.452 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2912008-09-27 17:02:40.452 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2922008-09-27 17:02:40.452 VideoOutputXv: DiscardFrames(1)
2932008-09-27 17:02:40.452 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2942008-09-27 17:02:40.452 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2952008-09-27 17:02:40.452 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2962008-09-27 17:02:40.452 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2972008-09-27 17:02:40.453 VideoOutputXv: Closing XVideo port 94
2982008-09-27 17:02:40.455 AO: Killing AudioOutputDSP
2992008-09-27 17:02:40.458 AO: OutputAudioLoop: Stop Event
3002008-09-27 17:02:40.458 AO: kickoffOutputAudioLoop exiting
3012008-09-27 17:02:40.515 TV: StopStuff() -- end
3022008-09-27 17:02:40.515 TV: Changing from WatchingPreRecorded to None
3032008-09-27 17:02:41.602 DPMS Reactivated.