Ticket #3986: output

File output, 35.0 KB (added by bhuffman@…, 13 years ago)
Line 
12007-10-02 23:09:31.670 Using runtime prefix = /usr
22007-10-02 23:09:43.595 DPMS is active.
32007-10-02 23:09:43.697 New DB connection, total: 1
42007-10-02 23:09:43.713 Connected to database 'mythconverg' at host: localhost
52007-10-02 23:09:43.715 Total desktop dim: 1360x765, with 1 screen[s].
62007-10-02 23:09:43.738 Using screen 0, 1360x765 at 0,0
72007-10-02 23:09:43.744 user: 500 effective user: 500 before privileged thread
82007-10-02 23:09:43.744 user: 500 effective user: 500 after privileged thread
92007-10-02 23:09:43.746 user: 500 effective user: 500 run_priv_thread
102007-10-02 23:09:43.749 Current Schema Version: 1199
112007-10-02 23:09:43.759 mythfrontend version: 0.21.20070910-2 www.mythtv.org
122007-10-02 23:09:43.759 Enabled verbose msgs:  important general playback
132007-10-02 23:09:44.038 The theme (G.A.N.T.) is missing a themeinfo.xml file
142007-10-02 23:09:44.396 The theme (G.A.N.T.) is missing a themeinfo.xml file
152007-10-02 23:09:44.825 max_width: 1360 max_height: 765
162007-10-02 23:09:44.973 Total desktop dim: 1360x765, with 1 screen[s].
172007-10-02 23:09:44.975 Using screen 0, 1360x765 at 0,0
182007-10-02 23:09:44.990 Switching to wide mode (MythCenter-wide)
192007-10-02 23:09:45.023 Using the OpenGL painter
202007-10-02 23:09:45.080 lirc init success using configuration file: /home/mythtv/.mythtv/lircrc
212007-10-02 23:09:46.058 Loading from: /usr/share/mythtv/themes/MythCenter-wide/base.xml
222007-10-02 23:09:46.078 Loading from: /usr/share/mythtv/themes/default/base.xml
232007-10-02 23:09:46.121 Key Z,End is bound to multiple actions in context TV Playback.
242007-10-02 23:09:46.122 Key Q,Home is bound to multiple actions in context TV Playback.
252007-10-02 23:09:46.157 Registering Internal as a media playback plugin.
262007-10-02 23:09:46.514 Using NV NPOT texture extension
272007-10-02 23:09:46.646 MythMusic adding CD-Writer: ATA:1,1,0 -- DVD-RW  DVR-106D
282007-10-02 23:09:46.696 MythMusic adding CD-Writer: ATAPI:0,1,0 -- DVD-RW  DVR-106D
292007-10-02 23:09:46.838 MonitorRegisterExtensions(0x40, ogg,mp3,aac,flac)
302007-10-02 23:09:46.981 Starting media monitor.
31mount: block device /dev/hdd is write-protected, mounting read-only
322007-10-02 23:09:48.350 Detected MediaType MEDIATYPE_DVD
332007-10-02 23:09:48.352 Found a handler
342007-10-02 23:09:52.759 New DB connection, total: 2
352007-10-02 23:09:52.760 Connected to database 'mythconverg' at host: localhost
362007-10-02 23:09:52.812 Connecting to backend server: 192.168.10.20:6543 (try 1 of 5)
372007-10-02 23:09:52.813 Using protocol version 36
382007-10-02 23:09:52.849 TV: Attempting to change from None to WatchingLiveTV
392007-10-02 23:09:52.851 Using protocol version 36
402007-10-02 23:09:56.278 LiveTVChain(live-xyz.graze.net-2007-10-02T23:09:52): ReloadAll(): Added new recording
412007-10-02 23:09:56.289 RingBuf(/MediaFS/Video/Recordings/1107_20071002230956.mpg): OpenFile(/MediaFS/Video/Recordings/1107_20071002230956.mpg, 12)
422007-10-02 23:09:57.360 RingBuf(/MediaFS/Video/Recordings/1107_20071002230956.mpg): CalcReadAheadThresh(1170114 KB)
43                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
442007-10-02 23:09:57.361 TV: StartRecorder(): took 1 ms to start recorder.
452007-10-02 23:09:57.383 DPMS Deactivated
462007-10-02 23:09:58.220 AFD: Stream #0, has id 0x480 codec id MPEG2VIDEO, type Video, bitrate 8000000 at 0x0x8fa33a0
472007-10-02 23:09:58.224 VDP: Accepting: cmp(<= 720 576) dec(ffmpeg) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,none) filt()
482007-10-02 23:09:58.243 VDP: Accepting: cmp(> 0 0,> 0 0) dec(xvmc) rend(xvmc-blit) osd(ia44blend) osdfade(disabled) deint(bobdeint,none) filt()
492007-10-02 23:09:58.243 VDP: LoadBestPreferences(2048x2048, 0)
502007-10-02 23:09:58.243 VDP: LoadBestPreferences(2048x2048, 60)
512007-10-02 23:09:58.243 VDP: LoadBestPreferences(720x480, 60)
522007-10-02 23:09:58.247 VDP: Accepting: cmp(<= 720 576) dec(ffmpeg) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,none) filt()
532007-10-02 23:09:58.247 VDP: Accepting: cmp(> 0 0,> 0 0) dec(xvmc) rend(xvmc-blit) osd(ia44blend) osdfade(disabled) deint(bobdeint,none) filt()
542007-10-02 23:09:58.247 VDP: LoadBestPreferences(2048x2048, 0)
552007-10-02 23:09:58.248 VDP: LoadBestPreferences(2048x2048, 60)
562007-10-02 23:09:58.248 VDP: LoadBestPreferences(720x480, 60)
572007-10-02 23:09:58.248 AFD: InitVideoCodec() 0x90a44d0 id(MPEG2VIDEO) type (Video).
582007-10-02 23:09:58.248 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 480) ->Interlaced Scan
592007-10-02 23:09:58.248 AFD: Using ffmpeg for video decoding
602007-10-02 23:09:58.258 AFD: Looking for decoder for MPEG2VIDEO
612007-10-02 23:09:58.258 AFD: Opened codec 0x90a44d0, id(MPEG2VIDEO) type(Video)
622007-10-02 23:09:58.258 AFD: Stream #1, has id 0x448 codec id MP2, type Audio, bitrate 384000 at 0x0x8faae90
632007-10-02 23:09:58.258 AFD: Looking for decoder for MP2
642007-10-02 23:09:58.259 AFD: Opened codec 0x9290940, id(MP2) type(Audio)
652007-10-02 23:09:58.282 RingBuf(/MediaFS/Video/Recordings/1107_20071002230956.mpg): CalcReadAheadThresh(19963 KB)
66                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
672007-10-02 23:09:58.286 Opening ALSA audio device 'spdif'.
682007-10-02 23:09:58.355 Dec: Trying to select track (w/lang)
692007-10-02 23:09:58.355 Dec: Selecting first track
702007-10-02 23:09:58.355 Dec: Selected track #1 in the Unknown language(0)
712007-10-02 23:09:58.355 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(0)
722007-10-02 23:09:58.374 SyncPositionMap watchingrecording, from DB: 0 entries
732007-10-02 23:09:58.375 Filling position map from 0 to 30
742007-10-02 23:09:58.375 Position map filled from Encoder to: 3
752007-10-02 23:09:58.376 SyncPositionMap watchingrecording total: 3 entries
762007-10-02 23:09:58.376 SyncPositionMap, new totframes: 45, new length: 1, posMap size: 3
772007-10-02 23:09:58.378 AFD: Partial position map found
782007-10-02 23:09:58.378 AFD: Successfully opened decoder for file: "/MediaFS/Video/Recordings/1107_20071002230956.mpg". novideo(0)
792007-10-02 23:09:58.386 VideoOutput: Allowed renderers: ivtv,xv-blit,xshm,xlib
802007-10-02 23:09:58.387 VideoOutput: Allowed renderers (filt: ffmpeg): xlib,xshm,xv-blit
812007-10-02 23:09:58.390 VDP: Accepting: cmp(<= 720 576) dec(ffmpeg) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,none) filt()
822007-10-02 23:09:58.391 VDP: Accepting: cmp(> 0 0,> 0 0) dec(xvmc) rend(xvmc-blit) osd(ia44blend) osdfade(disabled) deint(bobdeint,none) filt()
832007-10-02 23:09:58.391 VDP: LoadBestPreferences(2048x2048, 0)
842007-10-02 23:09:58.391 VDP: LoadBestPreferences(2048x2048, 60)
852007-10-02 23:09:58.391 VDP: LoadBestPreferences(720x480, 60)
862007-10-02 23:09:58.391 VideoOutput: Preferred renderer: xv-blit
872007-10-02 23:09:58.392 VideoOutput: Trying video renderer: xv-blit
882007-10-02 23:09:58.394 VDP: Accepting: cmp(<= 720 576) dec(ffmpeg) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,none) filt()
892007-10-02 23:09:58.394 VDP: Accepting: cmp(> 0 0,> 0 0) dec(xvmc) rend(xvmc-blit) osd(ia44blend) osdfade(disabled) deint(bobdeint,none) filt()
902007-10-02 23:09:58.395 VDP: LoadBestPreferences(2048x2048, 0)
912007-10-02 23:09:58.395 VDP: LoadBestPreferences(2048x2048, 60)
922007-10-02 23:09:58.421 VideoOutputXv: ctor
932007-10-02 23:09:58.426 XOff: 0, YOff: 0
942007-10-02 23:09:58.426 VDP: LoadBestPreferences(720x480, 60)
952007-10-02 23:09:58.426 Display Rect  left: 0, top: 0, width: 1360, height: 765, aspect: 1.33333
962007-10-02 23:09:58.426 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
972007-10-02 23:09:58.426 VideoOutputXv: Pixel dimensions: Screen 1360x765, window 1360x765
982007-10-02 23:09:58.428 VideoOutputXv: Estimated display dimensions: 1114x627 mm  Aspect: 1.77671
992007-10-02 23:09:58.428 VideoOutputXv: Estimated window dimensions: 1114x627 mm  Aspect: 1.77671
1002007-10-02 23:09:58.428 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
1012007-10-02 23:09:58.433 VDP: Accepting: cmp(<= 720 576) dec(ffmpeg) rend(xv-blit) osd(softblend) osdfade(enabled) deint(bobdeint,none) filt()
1022007-10-02 23:09:58.433 VDP: Accepting: cmp(> 0 0,> 0 0) dec(xvmc) rend(xvmc-blit) osd(ia44blend) osdfade(disabled) deint(bobdeint,none) filt()
1032007-10-02 23:09:58.434 VDP: LoadBestPreferences(2048x2048, 0)
1042007-10-02 23:09:58.434 VDP: LoadBestPreferences(2048x2048, 60)
1052007-10-02 23:09:58.434 VDP: LoadBestPreferences(720x480, 60)
1062007-10-02 23:09:58.435 VideoOutputXv: @ j=3 Looking for flag[s]: XvInputMask XvImageMask
1072007-10-02 23:09:58.435 VideoOutputXv: Adaptor#0: NV17 Video Texture has flag[s]: XvInputMask XvImageMask
1082007-10-02 23:09:58.436 VideoOutputXv: Grabbed xv port 245
1092007-10-02 23:09:58.436 VideoOutputXv: XVideo surface found on port 245
1102007-10-02 23:09:58.436 VideoOutputXv: XVideo Adaptor Name: 'NV17 Video Texture'
1112007-10-02 23:09:58.438 VideoOutputXv: XVideo Format #0 is 'YUY2'
1122007-10-02 23:09:58.438 VideoOutputXv: XVideo Format #1 is 'YV12'
1132007-10-02 23:09:58.438 VideoOutputXv: XVideo Format #2 is 'UYVY'
1142007-10-02 23:09:58.439 VideoOutputXv: XVideo Format #3 is 'I420'
1152007-10-02 23:09:58.439 VideoOutputXv: Using XVideo Format 'I420'
1162007-10-02 23:09:58.439 VideoOutputXv: CreateShmImages(32): video_dim: 720x480
1172007-10-02 23:09:58.512 VDP: SetVideoRenderer(xv-blit)
1182007-10-02 23:09:58.512 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
1192007-10-02 23:09:58.512 VideoOutputXv: Chromakeying not possible with this XVideo port.
1202007-10-02 23:09:58.512 Display Rect  left: 170, top: 0, width: 1020, height: 765, aspect: 1.77778
1212007-10-02 23:09:58.513 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
1222007-10-02 23:09:58.515 Over/underscan. V: 0, H: 0
1232007-10-02 23:09:58.515 Display Rect  left: 170, top: 0, width: 1020, height: 765, aspect: 1.77778
1242007-10-02 23:09:58.516 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
1252007-10-02 23:09:58.516 VDP: LoadBestPreferences(720x480, 29.97)
1262007-10-02 23:10:00.104 NVP: ClearAfterSeek(1)
1272007-10-02 23:10:00.104 TV: StartPlayer(): took 2635 ms to start player.
1282007-10-02 23:10:00.105 TV: Changing from None to WatchingLiveTV
1292007-10-02 23:10:00.110 VideoOutputXv: ClearAfterSeek()
1302007-10-02 23:10:00.111 VideoOutputXv: DiscardFrames(0)
1312007-10-02 23:10:00.111 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
1322007-10-02 23:10:00.111 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
1332007-10-02 23:10:00.111 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
1342007-10-02 23:10:00.113 The realtime priority setting is not enabled.
1352007-10-02 23:10:00.128 VDP: GetFilteredDeint() : xv-blit -> 'bobdeint'
1362007-10-02 23:10:00.139 Using deinterlace method bobdeint
1372007-10-02 23:10:00.230 AFD: HandleGopStart: gopset not set, syncing positionMap
1382007-10-02 23:10:00.231 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
1392007-10-02 23:10:00.232 Position map filled from DB to: 6
1402007-10-02 23:10:00.232 SyncPositionMap watchingrecording, from DB: 6 entries
1412007-10-02 23:10:00.233 Filling position map from 7 to 5
1422007-10-02 23:10:00.234 Position map filled from Encoder to: 6
1432007-10-02 23:10:00.234 SyncPositionMap watchingrecording total: 6 entries
1442007-10-02 23:10:00.234 SyncPositionMap, new totframes: 90, new length: 3, posMap size: 6
1452007-10-02 23:10:00.234 AFD: HandleGopStart: Initial key frame distance: 15.
1462007-10-02 23:10:00.243 nVidiaVideoSync: VBlank ioctl did not work, unimplemented in this driver?
1472007-10-02 23:10:00.244 DRMVideoSync: Could not open device /dev/dri/card0, No such file or directory
1482007-10-02 23:10:00.244 RTCVideoSync: Could not set RTC frequency, Invalid argument.
1492007-10-02 23:10:00.244 Set video sync frame interval to 33366
1502007-10-02 23:10:00.244 Using audio as timebase
1512007-10-02 23:10:00.244 Video timing method: USleep with busy wait
1522007-10-02 23:10:00.245 Refresh rate: 16667, frame interval: 33366
153'video_output' mean = '33366.27', std. dev. = '422.39', fps = '29.97'
1542007-10-02 23:10:04.892 XMLParse::LoadTheme using /usr/share/mythtv/themes/MythCenter-wide/ui.xml
155'video_output' mean = '34082.85', std. dev. = '5710.12', fps = '29.34'
1562007-10-02 23:10:07.612 NVP: Video is 3.1845 frames behind audio (too slow), dropping frame to catch up.
1572007-10-02 23:10:07.613 NVP: Video is 3.13763 frames behind audio (too slow), dropping frame to catch up.
1582007-10-02 23:10:08.034 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
1592007-10-02 23:10:08.034 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
1602007-10-02 23:10:08.640 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
1612007-10-02 23:10:08.640 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
162'video_output' mean = '33662.02', std. dev. = '23607.18', fps = '29.71'
1632007-10-02 23:10:10.709 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
1642007-10-02 23:10:10.710 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
1652007-10-02 23:10:10.908 NVP: Video is 3.10589 frames behind audio (too slow), dropping frame to catch up.
1662007-10-02 23:10:10.909 NVP: Video is 3.02622 frames behind audio (too slow), dropping frame to catch up.
1672007-10-02 23:10:11.724 NVP: Video is 3.22946 frames behind audio (too slow), dropping frame to catch up.
1682007-10-02 23:10:11.726 NVP: Video is 3.23878 frames behind audio (too slow), dropping frame to catch up.
1692007-10-02 23:10:11.929 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
1702007-10-02 23:10:11.929 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
1712007-10-02 23:10:12.044 NVP: Video is 3.25106 frames behind audio (too slow), dropping frame to catch up.
1722007-10-02 23:10:12.046 NVP: Video is 3.39735 frames behind audio (too slow), dropping frame to catch up.
1732007-10-02 23:10:12.048 NVP: Video is 3.26731 frames behind audio (too slow), dropping frame to catch up.
1742007-10-02 23:10:13.075 NVP: Video is 3.40514 frames behind audio (too slow), dropping frame to catch up.
1752007-10-02 23:10:13.078 NVP: Video is 3.48295 frames behind audio (too slow), dropping frame to catch up.
1762007-10-02 23:10:13.082 NVP: Video is 3.32401 frames behind audio (too slow), dropping frame to catch up.
1772007-10-02 23:10:13.278 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
1782007-10-02 23:10:13.278 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
1792007-10-02 23:10:13.392 NVP: Video is 3.23263 frames behind audio (too slow), dropping frame to catch up.
1802007-10-02 23:10:13.393 NVP: Video is 3.22619 frames behind audio (too slow), dropping frame to catch up.
1812007-10-02 23:10:13.591 NVP: 400 interlaced frames seen.
182'video_output' mean = '33491.67', std. dev. = '20913.49', fps = '29.86'
1832007-10-02 23:10:13.992 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
1842007-10-02 23:10:13.992 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
1852007-10-02 23:10:14.225 NVP: Video is 3.0333 frames behind audio (too slow), dropping frame to catch up.
1862007-10-02 23:10:14.775 NVP: Video is 3.06171 frames behind audio (too slow), dropping frame to catch up.
1872007-10-02 23:10:15.011 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
1882007-10-02 23:10:15.011 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
1892007-10-02 23:10:15.087 NVP: Video is 3.01726 frames behind audio (too slow), dropping frame to catch up.
1902007-10-02 23:10:15.091 NVP: Video is 3.01969 frames behind audio (too slow), dropping frame to catch up.
1912007-10-02 23:10:15.677 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
1922007-10-02 23:10:15.678 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
1932007-10-02 23:10:15.767 NVP: Video is 3.08826 frames behind audio (too slow), dropping frame to catch up.
1942007-10-02 23:10:15.770 NVP: Video is 3.17035 frames behind audio (too slow), dropping frame to catch up.
1952007-10-02 23:10:15.773 NVP: Video is 3.00713 frames behind audio (too slow), dropping frame to catch up.
1962007-10-02 23:10:16.492 NVP: Video is 3.00474 frames behind audio (too slow), dropping frame to catch up.
1972007-10-02 23:10:16.697 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
1982007-10-02 23:10:16.697 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
1992007-10-02 23:10:16.842 NVP: Video is 3.07993 frames behind audio (too slow), dropping frame to catch up.
200'video_output' mean = '33503.79', std. dev. = '15811.90', fps = '29.85'
2012007-10-02 23:10:18.762 NVP: Video is 3.24141 frames behind audio (too slow), dropping frame to catch up.
2022007-10-02 23:10:18.763 NVP: Video is 3.21777 frames behind audio (too slow), dropping frame to catch up.
2032007-10-02 23:10:18.961 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2042007-10-02 23:10:18.961 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2052007-10-02 23:10:19.047 NVP: Video is 3.0591 frames behind audio (too slow), dropping frame to catch up.
2062007-10-02 23:10:19.126 NVP: Video is 3.01151 frames behind audio (too slow), dropping frame to catch up.
2072007-10-02 23:10:19.662 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2082007-10-02 23:10:19.663 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2092007-10-02 23:10:19.826 NVP: Video is 3.23233 frames behind audio (too slow), dropping frame to catch up.
2102007-10-02 23:10:19.829 NVP: Video is 3.19598 frames behind audio (too slow), dropping frame to catch up.
211'video_output' mean = '33342.05', std. dev. = '12385.65', fps = '29.99'
2122007-10-02 23:10:20.726 NVP: Video is 3.16412 frames behind audio (too slow), dropping frame to catch up.
2132007-10-02 23:10:20.728 NVP: Video is 3.38458 frames behind audio (too slow), dropping frame to catch up.
2142007-10-02 23:10:20.732 NVP: Video is 3.33264 frames behind audio (too slow), dropping frame to catch up.
2152007-10-02 23:10:20.735 NVP: Video is 3.05392 frames behind audio (too slow), dropping frame to catch up.
2162007-10-02 23:10:21.093 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2172007-10-02 23:10:21.093 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2182007-10-02 23:10:21.465 NVP: Video is 3.25439 frames behind audio (too slow), dropping frame to catch up.
2192007-10-02 23:10:21.466 NVP: Video is 3.24249 frames behind audio (too slow), dropping frame to catch up.
2202007-10-02 23:10:21.660 NVP: Video is 3.00276 frames behind audio (too slow), dropping frame to catch up.
2212007-10-02 23:10:21.863 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2222007-10-02 23:10:21.863 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2232007-10-02 23:10:21.963 NVP: Video is 3.22247 frames behind audio (too slow), dropping frame to catch up.
2242007-10-02 23:10:21.964 NVP: Video is 3.19607 frames behind audio (too slow), dropping frame to catch up.
2252007-10-02 23:10:22.782 NVP: Video is 3.06513 frames behind audio (too slow), dropping frame to catch up.
2262007-10-02 23:10:22.980 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2272007-10-02 23:10:22.981 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2282007-10-02 23:10:23.026 NVP: Video is 3.22079 frames behind audio (too slow), dropping frame to catch up.
2292007-10-02 23:10:23.029 NVP: Video is 3.15735 frames behind audio (too slow), dropping frame to catch up.
230'video_output' mean = '33330.64', std. dev. = '17139.53', fps = '30.00'
2312007-10-02 23:10:25.547 NVP: Video is 3.27108 frames behind audio (too slow), dropping frame to catch up.
2322007-10-02 23:10:25.552 NVP: Video is 3.36741 frames behind audio (too slow), dropping frame to catch up.
2332007-10-02 23:10:25.553 NVP: Video is 3.19987 frames behind audio (too slow), dropping frame to catch up.
2342007-10-02 23:10:25.985 NVP: Video is 3.04594 frames behind audio (too slow), dropping frame to catch up.
2352007-10-02 23:10:26.943 NVP: 800 interlaced frames seen.
236'video_output' mean = '33337.28', std. dev. = '9933.25', fps = '30.00'
2372007-10-02 23:10:29.413 NVP: Video is 3.08101 frames behind audio (too slow), dropping frame to catch up.
238'video_output' mean = '33342.33', std. dev. = '4609.12', fps = '29.99'
2392007-10-02 23:10:31.345 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2402007-10-02 23:10:31.345 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2412007-10-02 23:10:31.546 NVP: Video is 3.05176 frames behind audio (too slow), dropping frame to catch up.
2422007-10-02 23:10:31.648 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2432007-10-02 23:10:31.648 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2442007-10-02 23:10:31.708 NVP: Video is 3.17764 frames behind audio (too slow), dropping frame to catch up.
2452007-10-02 23:10:31.712 NVP: Video is 3.17743 frames behind audio (too slow), dropping frame to catch up.
2462007-10-02 23:10:32.132 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2472007-10-02 23:10:32.133 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2482007-10-02 23:10:32.212 NVP: Video is 3.13867 frames behind audio (too slow), dropping frame to catch up.
2492007-10-02 23:10:32.216 NVP: Video is 3.17818 frames behind audio (too slow), dropping frame to catch up.
2502007-10-02 23:10:33.033 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2512007-10-02 23:10:33.034 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2522007-10-02 23:10:33.132 NVP: Video is 3.24714 frames behind audio (too slow), dropping frame to catch up.
2532007-10-02 23:10:33.135 NVP: Video is 3.4094 frames behind audio (too slow), dropping frame to catch up.
2542007-10-02 23:10:33.136 NVP: Video is 3.29131 frames behind audio (too slow), dropping frame to catch up.
2552007-10-02 23:10:33.733 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2562007-10-02 23:10:33.733 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
257'video_output' mean = '34001.05', std. dev. = '14515.73', fps = '29.41'
2582007-10-02 23:10:33.813 NVP: Video is 3.1034 frames behind audio (too slow), dropping frame to catch up.
2592007-10-02 23:10:33.817 NVP: Video is 3.16673 frames behind audio (too slow), dropping frame to catch up.
2602007-10-02 23:10:34.229 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2612007-10-02 23:10:34.229 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2622007-10-02 23:10:34.308 NVP: Video is 3.07984 frames behind audio (too slow), dropping frame to catch up.
2632007-10-02 23:10:34.311 NVP: Video is 3.0966 frames behind audio (too slow), dropping frame to catch up.
2642007-10-02 23:10:35.693 NVP: Video is 3.15519 frames behind audio (too slow), dropping frame to catch up.
2652007-10-02 23:10:35.694 NVP: Video is 3.2805 frames behind audio (too slow), dropping frame to catch up.
2662007-10-02 23:10:35.697 NVP: Video is 3.13469 frames behind audio (too slow), dropping frame to catch up.
2672007-10-02 23:10:36.284 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2682007-10-02 23:10:36.284 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2692007-10-02 23:10:36.429 NVP: Video is 3.09102 frames behind audio (too slow), dropping frame to catch up.
2702007-10-02 23:10:36.434 NVP: Video is 3.01508 frames behind audio (too slow), dropping frame to catch up.
271'video_output' mean = '32521.36', std. dev. = '13847.66', fps = '30.75'
2722007-10-02 23:10:37.396 NVP: Video is 3.00201 frames behind audio (too slow), dropping frame to catch up.
2732007-10-02 23:10:37.633 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2742007-10-02 23:10:37.633 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2752007-10-02 23:10:37.708 NVP: Video is 3.01016 frames behind audio (too slow), dropping frame to catch up.
2762007-10-02 23:10:37.712 NVP: Video is 3.01436 frames behind audio (too slow), dropping frame to catch up.
2772007-10-02 23:10:38.133 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2782007-10-02 23:10:38.133 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2792007-10-02 23:10:38.232 NVP: Video is 3.1518 frames behind audio (too slow), dropping frame to catch up.
2802007-10-02 23:10:38.234 NVP: Video is 3.30043 frames behind audio (too slow), dropping frame to catch up.
2812007-10-02 23:10:38.239 NVP: Video is 3.1946 frames behind audio (too slow), dropping frame to catch up.
2822007-10-02 23:10:39.381 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2832007-10-02 23:10:39.381 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2842007-10-02 23:10:39.646 NVP: Video is 3.00249 frames behind audio (too slow), dropping frame to catch up.
2852007-10-02 23:10:40.279 NVP: 1200 interlaced frames seen.
286'video_output' mean = '33484.48', std. dev. = '14120.16', fps = '29.86'
2872007-10-02 23:10:41.030 NVP: Video is 3.13936 frames behind audio (too slow), dropping frame to catch up.
2882007-10-02 23:10:41.031 NVP: Video is 3.00638 frames behind audio (too slow), dropping frame to catch up.
2892007-10-02 23:10:41.173 NVP: Video is 3.11101 frames behind audio (too slow), dropping frame to catch up.
2902007-10-02 23:10:41.174 NVP: Video is 3.05254 frames behind audio (too slow), dropping frame to catch up.
2912007-10-02 23:10:41.897 NVP: Video is 3.09704 frames behind audio (too slow), dropping frame to catch up.
292'video_output' mean = '33500.49', std. dev. = '11796.08', fps = '29.85'
2932007-10-02 23:10:44.998 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2942007-10-02 23:10:44.998 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2952007-10-02 23:10:45.138 NVP: Video is 3.13885 frames behind audio (too slow), dropping frame to catch up.
2962007-10-02 23:10:45.140 NVP: Video is 3.03596 frames behind audio (too slow), dropping frame to catch up.
2972007-10-02 23:10:45.652 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
2982007-10-02 23:10:45.652 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
2992007-10-02 23:10:45.730 NVP: Video is 3.33189 frames behind audio (too slow), dropping frame to catch up.
3002007-10-02 23:10:45.731 NVP: Video is 3.36058 frames behind audio (too slow), dropping frame to catch up.
3012007-10-02 23:10:45.735 NVP: Video is 3.1423 frames behind audio (too slow), dropping frame to catch up.
3022007-10-02 23:10:46.651 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
3032007-10-02 23:10:46.651 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
3042007-10-02 23:10:46.866 NVP: Video is 3.11251 frames behind audio (too slow), dropping frame to catch up.
305'video_output' mean = '33338.11', std. dev. = '12314.35', fps = '30.00'
306'video_output' mean = '33336.51', std. dev. = '537.96', fps = '30.00'
3072007-10-02 23:10:52.495 NVP: Video is 3.06701 frames behind audio (too slow), dropping frame to catch up.
3082007-10-02 23:10:52.499 NVP: Video is 3.10196 frames behind audio (too slow), dropping frame to catch up.
3092007-10-02 23:10:52.791 NVP: Video is 3.14173 frames behind audio (too slow), dropping frame to catch up.
3102007-10-02 23:10:52.792 NVP: Video is 3.12803 frames behind audio (too slow), dropping frame to catch up.
3112007-10-02 23:10:53.592 NVP: Video is 3.12333 frames behind audio (too slow), dropping frame to catch up.
3122007-10-02 23:10:53.596 NVP: Video is 3.12171 frames behind audio (too slow), dropping frame to catch up.
3132007-10-02 23:10:53.649 NVP: 1600 interlaced frames seen.
314'video_output' mean = '33501.95', std. dev. = '11830.27', fps = '29.85'
3152007-10-02 23:10:54.120 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
3162007-10-02 23:10:54.120 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
3172007-10-02 23:10:54.192 NVP: Video is 3.10744 frames behind audio (too slow), dropping frame to catch up.
3182007-10-02 23:10:54.193 NVP: Video is 3.10232 frames behind audio (too slow), dropping frame to catch up.
3192007-10-02 23:10:55.117 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
3202007-10-02 23:10:55.117 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
3212007-10-02 23:10:55.233 NVP: Video is 3.08428 frames behind audio (too slow), dropping frame to catch up.
3222007-10-02 23:10:55.234 NVP: Video is 3.12989 frames behind audio (too slow), dropping frame to catch up.
3232007-10-02 23:10:56.856 NVP: Video is 3.15651 frames behind audio (too slow), dropping frame to catch up.
3242007-10-02 23:10:56.859 NVP: Video is 3.10166 frames behind audio (too slow), dropping frame to catch up.
325'video_output' mean = '33171.47', std. dev. = '11432.43', fps = '30.15'
3262007-10-02 23:10:58.567 NVP: Video is 3.03276 frames behind audio (too slow), dropping frame to catch up.
327'video_output' mean = '33335.88', std. dev. = '3792.81', fps = '30.00'
3282007-10-02 23:11:00.633 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
3292007-10-02 23:11:00.633 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
3302007-10-02 23:11:00.902 NVP: Video is 3.00878 frames behind audio (too slow), dropping frame to catch up.
3312007-10-02 23:11:01.438 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
3322007-10-02 23:11:01.438 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
3332007-10-02 23:11:01.737 NVP: Video is 3.01394 frames behind audio (too slow), dropping frame to catch up.
3342007-10-02 23:11:02.439 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
3352007-10-02 23:11:02.439 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
3362007-10-02 23:11:02.601 NVP: Video is 3.26215 frames behind audio (too slow), dropping frame to catch up.
3372007-10-02 23:11:02.602 NVP: Video is 3.22583 frames behind audio (too slow), dropping frame to catch up.
3382007-10-02 23:11:03.439 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
3392007-10-02 23:11:03.439 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
3402007-10-02 23:11:03.618 NVP: Video is 3.13187 frames behind audio (too slow), dropping frame to catch up.
341'video_output' mean = '33503.65', std. dev. = '12804.55', fps = '29.85'
3422007-10-02 23:11:05.384 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
3432007-10-02 23:11:05.384 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
3442007-10-02 23:11:05.519 NVP: Video is 3.12501 frames behind audio (too slow), dropping frame to catch up.
3452007-10-02 23:11:05.601 NVP: Video is 3.0051 frames behind audio (too slow), dropping frame to catch up.
3462007-10-02 23:11:05.722 Display Rect  left: 969, top: 39, width: 308, height: 230, aspect: 1.77778
3472007-10-02 23:11:05.722 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
3482007-10-02 23:11:05.792 Display Rect  left: 170, top: 0, width: 1020, height: 765, aspect: 1.77778
3492007-10-02 23:11:05.793 Video Rect    left: 0, top: 0, width: 720, height: 480, aspect: 1.33333
3502007-10-02 23:11:06.541 NVP: Video is 8.51283 frames behind audio (too slow), dropping frame to catch up.
3512007-10-02 23:11:06.542 NVP: Video is 12.4387 frames behind audio (too slow), dropping frame to catch up.
3522007-10-02 23:11:06.546 NVP: Video is 15.1433 frames behind audio (too slow), dropping frame to catch up.
3532007-10-02 23:11:06.549 NVP: Video is 16.962 frames behind audio (too slow), dropping frame to catch up.
3542007-10-02 23:11:06.553 NVP: Video is 18.0937 frames behind audio (too slow), dropping frame to catch up.
3552007-10-02 23:11:06.555 NVP: Video is 18.7102 frames behind audio (too slow), dropping frame to catch up.
3562007-10-02 23:11:06.557 NVP: Video is 18.9328 frames behind audio (too slow), dropping frame to catch up.
3572007-10-02 23:11:06.559 NVP: Video is 18.875 frames behind audio (too slow), dropping frame to catch up.
3582007-10-02 23:11:06.561 NVP: Video is 18.5919 frames behind audio (too slow), dropping frame to catch up.
3592007-10-02 23:11:06.563 NVP: Video is 18.1473 frames behind audio (too slow), dropping frame to catch up.
3602007-10-02 23:11:06.565 NVP: Video is 17.5816 frames behind audio (too slow), dropping frame to catch up.
3612007-10-02 23:11:06.569 NVP: Video is 16.9175 frames behind audio (too slow), dropping frame to catch up.
3622007-10-02 23:11:06.571 NVP: Video is 16.2022 frames behind audio (too slow), dropping frame to catch up.
3632007-10-02 23:11:06.573 NVP: Video is 15.4334 frames behind audio (too slow), dropping frame to catch up.
3642007-10-02 23:11:06.574 NVP: Video is 14.6246 frames behind audio (too slow), dropping frame to catch up.
3652007-10-02 23:11:06.579 NVP: Video is 13.7857 frames behind audio (too slow), dropping frame to catch up.
3662007-10-02 23:11:06.581 NVP: Video is 12.9317 frames behind audio (too slow), dropping frame to catch up.
3672007-10-02 23:11:06.582 NVP: Video is 12.0515 frames behind audio (too slow), dropping frame to catch up.
3682007-10-02 23:11:06.585 NVP: Video is 11.1515 frames behind audio (too slow), dropping frame to catch up.
3692007-10-02 23:11:06.588 NVP: Video is 10.2592 frames behind audio (too slow), dropping frame to catch up.
3702007-10-02 23:11:06.591 NVP: Video is 9.36528 frames behind audio (too slow), dropping frame to catch up.
3712007-10-02 23:11:06.594 NVP: Video is 8.46254 frames behind audio (too slow), dropping frame to catch up.
3722007-10-02 23:11:06.595 NVP: Video is 7.54571 frames behind audio (too slow), dropping frame to catch up.
3732007-10-02 23:11:06.598 NVP: Video is 6.61832 frames behind audio (too slow), dropping frame to catch up.
3742007-10-02 23:11:06.602 NVP: Video is 5.69802 frames behind audio (too slow), dropping frame to catch up.
3752007-10-02 23:11:06.606 NVP: Video is 4.78301 frames behind audio (too slow), dropping frame to catch up.
3762007-10-02 23:11:06.609 NVP: Video is 3.87946 frames behind audio (too slow), dropping frame to catch up.
3772007-10-02 23:11:06.854 NVP: 2000 interlaced frames seen.
378'video_output' mean = '32027.29', std. dev. = '71188.88', fps = '31.22'
3792007-10-02 23:11:07.762 TV: Attempting to change from WatchingLiveTV to None
3802007-10-02 23:11:07.762 TV: StopStuff() -- begin
3812007-10-02 23:11:07.762 TV: StopStuff(): stopping ring buffer[s]
3822007-10-02 23:11:07.802 TV: StopStuff(): stopping player[s] (1/2)
3832007-10-02 23:11:07.802 TV: StopStuff(): stopping recorder[s]
3842007-10-02 23:11:07.802 NVP: Exited decoder loop.
3852007-10-02 23:11:07.821 VideoOutputXv: dtor
3862007-10-02 23:11:07.821 VideoOutputXv: DiscardFrames(1)
3872007-10-02 23:11:07.821 VideoBuffers::DiscardFrames(1): UUUUuUULUUAUUUUuUUUUUUUUUUUUUUU
3882007-10-02 23:11:07.821 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
3892007-10-02 23:11:07.821 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
3902007-10-02 23:11:07.821 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
3912007-10-02 23:11:07.822 VideoOutputXv: DiscardFrames(1)
3922007-10-02 23:11:07.822 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
3932007-10-02 23:11:07.822 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
3942007-10-02 23:11:07.822 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
3952007-10-02 23:11:07.822 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
3962007-10-02 23:11:07.829 VideoOutputXv: Closing XVideo port 245
3972007-10-02 23:11:07.887 TV: StopStuff(): stopping player[s] (2/2)
3982007-10-02 23:11:07.935 TV: StopStuff() -- end
3992007-10-02 23:11:07.935 TV: Changing from WatchingLiveTV to None
4002007-10-02 23:11:07.963 DPMS Reactivated.