Ticket #4214: good.txt

File good.txt, 17.5 KB (added by Mike Rice <mikerice1969@…>, 12 years ago)
Line 
12007-11-23 11:32:42.774 TV: Attempting to change from None to WatchingPreRecorded
22007-11-23 11:32:42.774 RingBuf(/content/Video/Temporary/swear_jar_300k.wmv): OpenFile(/content/Video/Temporary/swear_jar_300k.wmv, 12)
32007-11-23 11:32:42.774 RingBuf(/content/Video/Temporary/swear_jar_300k.wmv): CalcReadAheadThresh(9420020 KB)
4                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
52007-11-23 11:32:42.804 AFD: Stream #0, has id 0x1 codec id WMAV2, type Audio, bitrate 32048 at 0x0xaa740b0
62007-11-23 11:32:42.804 AFD: Looking for decoder for WMAV2
72007-11-23 11:32:42.805 AFD: Opened codec 0xaa743a0, id(WMAV2) type(Audio)
82007-11-23 11:32:42.805 AFD: Stream #1, has id 0x31 codec id WMV3, type Video, bitrate 274432 at 0x0xaa741b0
92007-11-23 11:32:42.807 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) rend(xv-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
102007-11-23 11:32:42.807 VDP: LoadBestPreferences(2048x2048, 0)
112007-11-23 11:32:42.808 VDP: LoadBestPreferences(2048x2048, 60)
122007-11-23 11:32:42.808 VDP: LoadBestPreferences(400x300, 60)
132007-11-23 11:32:42.808 AFD: InitVideoCodec() 0xaa739e0 id(WMV3) type (Video).
142007-11-23 11:32:42.808 detectInterlace(Detect Scan, Interlaced Scan, 29.97, 304) ->Interlaced Scan
152007-11-23 11:32:42.808 AFD: Using ffmpeg for video decoding
162007-11-23 11:32:42.808 AFD: Looking for decoder for WMV3
172007-11-23 11:32:42.808 AFD: Opened codec 0xaa739e0, id(WMV3) type(Video)
182007-11-23 11:32:42.867 RingBuf(/content/Video/Temporary/swear_jar_300k.wmv): CalcReadAheadThresh(179225280 KB)
19                         -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
202007-11-23 11:32:42.868 Opening OSS audio device '/dev/dsp'.
212007-11-23 11:32:42.872 Dec: Trying to select track (w/lang)
222007-11-23 11:32:42.872 Dec: Selecting first track
232007-11-23 11:32:42.872 Dec: Selected track #1 in the Unknown language(0)
242007-11-23 11:32:42.872 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
252007-11-23 11:32:42.874 DPMS Deactivated
262007-11-23 11:32:42.875 SyncPositionMap prerecorded, from DB: 0 entries
272007-11-23 11:32:42.875 AFD: Recording has no position -- using libavformat seeking.
282007-11-23 11:32:42.875 AFD: Successfully opened decoder for file: "/content/Video/Temporary/swear_jar_300k.wmv". novideo(0)
292007-11-23 11:32:42.876 VideoOutput: Allowed renderers: ivtv,xv-blit,xshm,xlib
302007-11-23 11:32:42.877 VideoOutput: Allowed renderers (filt: ffmpeg): xlib,xshm,xv-blit
312007-11-23 11:32:42.878 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) rend(xv-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
322007-11-23 11:32:42.878 VDP: LoadBestPreferences(2048x2048, 0)
332007-11-23 11:32:42.878 VDP: LoadBestPreferences(2048x2048, 60)
342007-11-23 11:32:42.878 VDP: LoadBestPreferences(400x304, 60)
352007-11-23 11:32:42.878 VideoOutput: Preferred renderer: xv-blit
362007-11-23 11:32:42.878 VideoOutput: Trying video renderer: xv-blit
372007-11-23 11:32:42.879 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) rend(xv-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
382007-11-23 11:32:42.879 VDP: LoadBestPreferences(2048x2048, 0)
392007-11-23 11:32:42.880 VDP: LoadBestPreferences(2048x2048, 60)
402007-11-23 11:32:42.888 VideoOutputXv: ctor
412007-11-23 11:32:42.888 XOff: 0, YOff: 0
422007-11-23 11:32:42.888 VDP: LoadBestPreferences(400x304, 60)
432007-11-23 11:32:42.888 Display Rect  left: 0, top: 0, width: 800, height: 600, aspect: 1.33333
442007-11-23 11:32:42.888 Video Rect    left: 0, top: 0, width: 400, height: 304, aspect: 1.33333
452007-11-23 11:32:42.889 VideoOutputXv: Pixel dimensions: Screen 800x600, window 800x600
462007-11-23 11:32:42.889 VideoOutputXv: Estimated display dimensions: 271x203 mm  Aspect: 1.33498
472007-11-23 11:32:42.889 VideoOutputXv: Estimated window dimensions: 271x203 mm  Aspect: 1.33498
482007-11-23 11:32:42.889 VideoOutputXv: InitSetupBuffers() render: xv-blit, allowed: xv-blit,xshm,xlib
492007-11-23 11:32:42.891 VDP: Accepting: cmp(> 0 0) dec(ffmpeg) rend(xv-blit) osd(softblend) osdfade(enabled) deint(linearblend,linearblend) filt()
502007-11-23 11:32:42.891 VDP: LoadBestPreferences(2048x2048, 0)
512007-11-23 11:32:42.891 VDP: LoadBestPreferences(2048x2048, 60)
522007-11-23 11:32:42.891 VDP: LoadBestPreferences(400x304, 60)
532007-11-23 11:32:42.891 VideoOutputXv: @ j=0 Looking for flag[s]: XvInputMask XvImageMask  10
542007-11-23 11:32:42.891 VideoOutputXv: Adaptor#0: Intel(R) Textured Video has flag[s]: XvInputMask XvImageMask
552007-11-23 11:32:42.891 VideoOutputXv: Has XVideo flags...
562007-11-23 11:32:42.891 VideoOutputXv: Has XV_BRIGHTNESS...
572007-11-23 11:32:42.891 VideoOutputXv: Here...
582007-11-23 11:32:42.892 VideoOutputXv: Grabbed xv port 73
592007-11-23 11:32:42.892 VideoOutputXv: XVideo surface found on port 73
602007-11-23 11:32:42.892 VideoOutputXv: XVideo Adaptor Name: 'Intel(R) Textured Video'
612007-11-23 11:32:42.892 VideoOutputXv: XVideo Format #0 is 'YUY2'
622007-11-23 11:32:42.892 VideoOutputXv: XVideo Format #1 is 'YV12'
632007-11-23 11:32:42.892 VideoOutputXv: XVideo Format #2 is 'I420'
642007-11-23 11:32:42.892 VideoOutputXv: XVideo Format #3 is 'UYVY'
652007-11-23 11:32:42.892 VideoOutputXv: XVideo Using ids: GUID_I420_PLANAR
662007-11-23 11:32:42.892 VideoOutputXv: Using XVideo Format 'I420'
672007-11-23 11:32:42.892 VideoOutputXv: CreateShmImages(32): video_dim: 400x304
682007-11-23 11:32:42.902 VDP: SetVideoRenderer(xv-blit)
692007-11-23 11:32:42.902 VDP: SetVideoRender(xv-blit) == GetVideoRenderer()
702007-11-23 11:32:42.902 VideoOutputXv: Chromakeying not possible with this XVideo port.
712007-11-23 11:32:42.902 Display Rect  left: 0, top: 0, width: 800, height: 600, aspect: 1.33333
722007-11-23 11:32:42.902 Video Rect    left: 0, top: 0, width: 400, height: 304, aspect: 1.33333
732007-11-23 11:32:42.903 Over/underscan. V: 0, H: 0
742007-11-23 11:32:42.903 Display Rect  left: 0, top: 0, width: 800, height: 600, aspect: 1.33333
752007-11-23 11:32:42.903 Video Rect    left: 0, top: 0, width: 400, height: 304, aspect: 1.33333
762007-11-23 11:32:42.903 VDP: LoadBestPreferences(400x304, 29.97)
772007-11-23 11:32:42.904 NVP: LoadFilters(''..) -> 0
782007-11-23 11:32:43.669 NVP: ClearAfterSeek(1)
792007-11-23 11:32:43.669 VideoOutputXv: ClearAfterSeek()
802007-11-23 11:32:43.669 VideoOutputXv: DiscardFrames(0)
812007-11-23 11:32:43.669 TV: StartPlayer(): took 884 ms to start player.
822007-11-23 11:32:43.669 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
832007-11-23 11:32:43.670 TV: Changing from None to WatchingPreRecorded
842007-11-23 11:32:43.670 VideoBuffers::DiscardFrames(0): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
852007-11-23 11:32:43.670 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
862007-11-23 11:32:43.671 VDP: GetFilteredDeint() : xv-blit -> 'linearblend'
872007-11-23 11:32:43.671 Using realtime priority.
882007-11-23 11:32:43.670 AFD: HandleGopStart: gopset not set, syncing positionMap
892007-11-23 11:32:43.671 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
902007-11-23 11:32:43.671 AFD: HandleGopStart: gopset not set, syncing positionMap
912007-11-23 11:32:43.671 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
922007-11-23 11:32:43.671 AFD: HandleGopStart: gopset not set, syncing positionMap
932007-11-23 11:32:43.671 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
942007-11-23 11:32:43.671 AFD: HandleGopStart: gopset not set, syncing positionMap
952007-11-23 11:32:43.671 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
962007-11-23 11:32:43.671 AFD: HandleGopStart: gopset not set, syncing positionMap
972007-11-23 11:32:43.671 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
982007-11-23 11:32:43.671 AFD: HandleGopStart: gopset not set, syncing positionMap
992007-11-23 11:32:43.671 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1002007-11-23 11:32:43.671 AFD: HandleGopStart: gopset not set, syncing positionMap
1012007-11-23 11:32:43.671 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1022007-11-23 11:32:43.672 AFD: HandleGopStart: gopset not set, syncing positionMap
1032007-11-23 11:32:43.672 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1042007-11-23 11:32:43.672 AFD: HandleGopStart: gopset not set, syncing positionMap
1052007-11-23 11:32:43.672 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1062007-11-23 11:32:43.672 AFD: HandleGopStart: gopset not set, syncing positionMap
1072007-11-23 11:32:43.672 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1082007-11-23 11:32:43.672 AFD: HandleGopStart: gopset not set, syncing positionMap
1092007-11-23 11:32:43.672 Using deinterlace method linearblend
1102007-11-23 11:32:43.672 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1112007-11-23 11:32:43.672 AFD: HandleGopStart: gopset not set, syncing positionMap
1122007-11-23 11:32:43.672 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1132007-11-23 11:32:43.672 AFD: HandleGopStart: gopset not set, syncing positionMap
1142007-11-23 11:32:43.672 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1152007-11-23 11:32:43.672 AFD: HandleGopStart: gopset not set, syncing positionMap
1162007-11-23 11:32:43.672 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1172007-11-23 11:32:43.672 AFD: HandleGopStart: gopset not set, syncing positionMap
1182007-11-23 11:32:43.672 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1192007-11-23 11:32:43.672 AFD: HandleGopStart: gopset not set, syncing positionMap
1202007-11-23 11:32:43.672 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1212007-11-23 11:32:43.672 AFD: HandleGopStart: gopset not set, syncing positionMap
1222007-11-23 11:32:43.672 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1232007-11-23 11:32:43.672 nVidiaVideoSync: Could not open device /dev/nvidia0, No such file or directory
1242007-11-23 11:32:43.672 AFD: HandleGopStart: gopset not set, syncing positionMap
1252007-11-23 11:32:43.672 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1262007-11-23 11:32:43.672 AFD: HandleGopStart: gopset not set, syncing positionMap
1272007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1282007-11-23 11:32:43.673 AFD: HandleGopStart: gopset not set, syncing positionMap
1292007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1302007-11-23 11:32:43.673 AFD: HandleGopStart: gopset not set, syncing positionMap
1312007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1322007-11-23 11:32:43.673 AFD: HandleGopStart: gopset not set, syncing positionMap
1332007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1342007-11-23 11:32:43.673 AFD: HandleGopStart: gopset not set, syncing positionMap
1352007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1362007-11-23 11:32:43.673 AFD: HandleGopStart: gopset not set, syncing positionMap
1372007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1382007-11-23 11:32:43.673 AFD: HandleGopStart: gopset not set, syncing positionMap
1392007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1402007-11-23 11:32:43.673 AFD: HandleGopStart: gopset not set, syncing positionMap
1412007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1422007-11-23 11:32:43.673 AFD: HandleGopStart: gopset not set, syncing positionMap
1432007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1442007-11-23 11:32:43.673 AFD: HandleGopStart: gopset not set, syncing positionMap
1452007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1462007-11-23 11:32:43.673 AFD: HandleGopStart: gopset not set, syncing positionMap
1472007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1482007-11-23 11:32:43.673 AFD: HandleGopStart: gopset not set, syncing positionMap
1492007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1502007-11-23 11:32:43.673 AFD: HandleGopStart: gopset not set, syncing positionMap
1512007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1522007-11-23 11:32:43.673 AFD: HandleGopStart: gopset not set, syncing positionMap
1532007-11-23 11:32:43.673 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1542007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1552007-11-23 11:32:43.674 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1562007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1572007-11-23 11:32:43.674 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1582007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1592007-11-23 11:32:43.674 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1602007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1612007-11-23 11:32:43.674 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1622007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1632007-11-23 11:32:43.674 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1642007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1652007-11-23 11:32:43.674 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1662007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1672007-11-23 11:32:43.674 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1682007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1692007-11-23 11:32:43.674 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1702007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1712007-11-23 11:32:43.674 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1722007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1732007-11-23 11:32:43.674 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1742007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1752007-11-23 11:32:43.674 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1762007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1772007-11-23 11:32:43.674 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1782007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1792007-11-23 11:32:43.674 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1802007-11-23 11:32:43.674 AFD: HandleGopStart: gopset not set, syncing positionMap
1812007-11-23 11:32:43.675 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1822007-11-23 11:32:43.675 AFD: HandleGopStart: gopset not set, syncing positionMap
1832007-11-23 11:32:43.675 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1842007-11-23 11:32:43.675 AFD: HandleGopStart: gopset not set, syncing positionMap
1852007-11-23 11:32:43.675 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1862007-11-23 11:32:43.675 AFD: HandleGopStart: gopset not set, syncing positionMap
1872007-11-23 11:32:43.675 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1882007-11-23 11:32:43.675 AFD: HandleGopStart: gopset not set, syncing positionMap
1892007-11-23 11:32:43.675 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1902007-11-23 11:32:43.675 AFD: HandleGopStart: gopset not set, syncing positionMap
1912007-11-23 11:32:43.675 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1922007-11-23 11:32:43.675 AFD: HandleGopStart: gopset not set, syncing positionMap
1932007-11-23 11:32:43.675 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1942007-11-23 11:32:43.675 AFD: HandleGopStart: gopset not set, syncing positionMap
1952007-11-23 11:32:43.675 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1962007-11-23 11:32:43.675 AFD: HandleGopStart: gopset not set, syncing positionMap
1972007-11-23 11:32:43.675 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
1982007-11-23 11:32:43.675 AFD: HandleGopStart: gopset not set, syncing positionMap
1992007-11-23 11:32:43.675 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2002007-11-23 11:32:43.678 AFD: DoFastForward(124 (1), do discard frames)
2012007-11-23 11:32:43.689 Using audio as timebase
2022007-11-23 11:32:43.689 Video timing method: DRM
2032007-11-23 11:32:43.689 Refresh rate: 16579, frame interval: 33366
2042007-11-23 11:32:43.706 NVP: Waiting for prebuffer.. 0 uAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2052007-11-23 11:32:43.830 AFD: SeekReset(128720178, 0, do flush, do discard)
2062007-11-23 11:32:43.830 AFD: SeekReset() flushing
2072007-11-23 11:32:43.830 VideoOutputXv: DiscardFrames(1)
2082007-11-23 11:32:43.830 VideoBuffers::DiscardFrames(1): uAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2092007-11-23 11:32:43.830 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2102007-11-23 11:32:43.830 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2112007-11-23 11:32:43.830 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2122007-11-23 11:32:43.830 NVP: ClearAfterSeek(0)
2132007-11-23 11:32:43.855 NVP: Waiting for prebuffer.. 1 AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2142007-11-23 11:32:43.885 AFD: HandleGopStart: gopset not set, syncing positionMap
2152007-11-23 11:32:43.885 Resyncing position map. posmapStarted = 0 livetv(0) watchingRec(0)
2162007-11-23 11:32:43.885 AFD: HandleGopStart: Initial key frame distance: 15.
2172007-11-23 11:32:43.905 NVP: progressive frame seen after 2 interlaced  frames
2182007-11-23 11:32:43.957 Disabled deinterlacing
219'video_output' mean = '33814.66', std. dev. = '3268.12', fps = '29.57'