Ticket #4214: bad.txt

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