2007-07-19 09:00:07.102 NVP: IsReallyNearEnd() br(798KB) fps(29) sz(449KB) vfl(4) frh(16) ne:0
2007-07-19 09:00:07.113 NVP: IsReallyNearEnd() br(798KB) fps(29) sz(449KB) vfl(4) frh(16) ne:0
2007-07-19 09:00:07.123 NVP: IsReallyNearEnd() br(798KB) fps(29) sz(416KB) vfl(4) frh(15) ne:0
2007-07-19 09:00:07.126 NVP: IsReallyNearEnd() br(798KB) fps(29) sz(383KB) vfl(5) frh(14) ne:1
2007-07-19 09:00:07.126 SwitchToProgram(void)
2007-07-19 09:00:07.179 RingBuf(/video/recordings/1006_20070719075204.mpg): OpenFile(/video/recordings/1006_20070719090000.mpg, 10)
2007-07-19 09:00:07.179 RingBuf(/video/recordings/1006_20070719090000.mpg): CalcReadAheadThresh(2897423528 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2007-07-19 09:00:07.180 RingBuf(/video/recordings/1006_20070719090000.mpg): CalcReadAheadThresh(3076815772 KB)
			 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
2007-07-19 09:00:07.207 Avg read interval was 184 msec. 64K block size
2007-07-19 09:00:07.623 FileChangedCallback
2007-07-19 09:00:07.673 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
2007-07-19 09:00:07.674 Position map filled from DB to: 1
2007-07-19 09:00:07.674 SyncPositionMap watchingrecording, from DB: 1 entries
2007-07-19 09:00:07.679 Filling position map from 2 to 8
2007-07-19 09:00:07.686 Position map filled from Encoder to: 8
2007-07-19 09:00:07.686 SyncPositionMap watchingrecording total: 8 entries
2007-07-19 09:00:07.686 SyncPositionMap, new totframes: 120, new length: 4, posMap size: 8
2007-07-19 09:00:07.805 Avg read interval was 187 msec. 224K block size
'video_output' mean = '33209.48', std. dev. = '139.87', fps = '30.11'
2007-07-19 09:00:09.285 Avg read interval was 188 msec. 256K block size
2007-07-19 09:00:10.958 Avg read interval was 190 msec. 288K block size
'video_output' mean = '33210.13', std. dev. = '159.30', fps = '30.11'
2007-07-19 09:00:14.791 NVP: 122400 interlaced frames seen.
'video_output' mean = '33203.93', std. dev. = '210.50', fps = '30.12'
'video_output' mean = '33375.99', std. dev. = '1667.30', fps = '29.96'
'video_output' mean = '33373.53', std. dev. = '1653.68', fps = '29.96'
'video_output' mean = '33372.28', std. dev. = '1696.70', fps = '29.96'
2007-07-19 09:01:30.084 Avg read interval was 187 msec. 288K block size
2007-07-19 09:01:30.508 NVP: prebuffering pause
2007-07-19 09:01:30.508 NVP: Waiting for prebuffer.. 0 dAAAALAd
...
...
...
2007-07-19 09:01:31.702 RingBuf(/video/recordings/1006_20070719090000.mpg): Waited 1.0 seconds for data to become available...
2007-07-19 09:01:31.702 Checking to see if there's a new livetv program to switch to..
2007-07-19 09:01:31.703 NVP: Waiting for prebuffer.. 8 dAAAALAd
2007-07-19 09:01:31.852 NVP: Waiting for prebuffer.. 9 dAAAALAd
2007-07-19 09:01:32.707 RingBuf(/video/recordings/1006_20070719090000.mpg): Waited 2.0 seconds for data to become available...
2007-07-19 09:01:32.707 Checking to see if there's a new livetv program to switch to..
2007-07-19 09:01:32.749 NVP: Waiting for prebuffer.. 5 dAAAALAd
2007-07-19 09:01:34.717 RingBuf(/video/recordings/1006_20070719090000.mpg): Waited 4.0 seconds for data to become available...
2007-07-19 09:01:34.717 Checking to see if there's a new livetv program to switch to..
2007-07-19 09:01:34.841 NVP: Waiting for prebuffer.. 9 dAAAALAd
...
...
...
2007-07-19 09:01:38.727 NVP: Waiting for prebuffer.. 5 dAAAALAd
2007-07-19 09:01:38.735 RingBuf(/video/recordings/1006_20070719090000.mpg): Waited 8.0 seconds for data to become available...
2007-07-19 09:01:38.735 Checking to see if there's a new livetv program to switch to..
2007-07-19 09:01:38.876 NVP: Waiting for prebuffer.. 6 dAAAALAd
2007-07-19 09:01:46.769 RingBuf(/video/recordings/1006_20070719090000.mpg) Error: Waited 16 seconds for data, aborting.
2007-07-19 09:01:46.797 NVP: Waiting for prebuffer.. 9 dAAAALAd
2007-07-19 09:01:46.829 Ignoring livetv eof in decoder loop
2007-07-19 09:01:46.883 Avg read interval was 196 msec. 64K block size
2007-07-19 09:01:46.883 Ignoring livetv eof in decoder loop
2007-07-19 09:01:46.885 Avg read interval was 196 msec. 64K block size
2007-07-19 09:01:46.885 Avg read interval was 196 msec. 96K block size
2007-07-19 09:01:46.885 Avg read interval was 196 msec. 128K block size
2007-07-19 09:01:46.885 Avg read interval was 196 msec. 160K block size
2007-07-19 09:01:46.885 Avg read interval was 196 msec. 192K block size
2007-07-19 09:01:46.885 Avg read interval was 196 msec. 224K block size
2007-07-19 09:01:46.886 Avg read interval was 196 msec. 256K block size
2007-07-19 09:01:46.886 Avg read interval was 196 msec. 288K block size
2007-07-19 09:01:46.886 Avg read interval was 196 msec. 320K block size
2007-07-19 09:01:46.886 Avg read interval was 196 msec. 352K block size
2007-07-19 09:01:46.886 Avg read interval was 196 msec. 384K block size
2007-07-19 09:01:46.886 Avg read interval was 196 msec. 416K block size
2007-07-19 09:01:46.886 Avg read interval was 196 msec. 448K block size
2007-07-19 09:01:46.886 Avg read interval was 196 msec. 480K block size
2007-07-19 09:01:46.886 Avg read interval was 196 msec. 512K block size
2007-07-19 09:01:46.886 Avg read interval was 196 msec. 544K block size
2007-07-19 09:01:46.886 Avg read interval was 196 msec. 576K block size
2007-07-19 09:01:46.886 Avg read interval was 196 msec. 608K block size
2007-07-19 09:01:46.886 Avg read interval was 196 msec. 640K block size
2007-07-19 09:01:46.931 NVP: Prebuffer wait timed out 10 times.
2007-07-19 09:01:46.939 Avg read interval was 0 msec. 64K block size
2007-07-19 09:01:46.939 Avg read interval was 196 msec. 96K block size
2007-07-19 09:01:46.939 Avg read interval was 196 msec. 128K block size
2007-07-19 09:01:46.939 Avg read interval was 196 msec. 160K block size
2007-07-19 09:01:46.939 Avg read interval was 196 msec. 192K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 224K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 256K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 288K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 320K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 352K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 384K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 416K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 448K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 480K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 512K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 544K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 576K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 608K block size
2007-07-19 09:01:46.940 Avg read interval was 196 msec. 640K block size
2007-07-19 09:01:46.942 Ignoring livetv eof in decoder loop
2007-07-19 09:01:46.946 NVP: Waiting for prebuffer.. 0 DULAAuAD
2007-07-19 09:01:46.997 Ignoring livetv eof in decoder loop
2007-07-19 09:01:46.999 Avg read interval was 0 msec. 64K block size
2007-07-19 09:01:46.999 Avg read interval was 196 msec. 96K block size
...
...
...
2007-07-19 09:01:47.052 Ignoring livetv eof in decoder loop
2007-07-19 09:01:47.055 Avg read interval was 196 msec. 640K block size
2007-07-19 09:01:47.096 NVP: Waiting for prebuffer.. 1 DULuAuUD
2007-07-19 09:01:47.115 Ignoring livetv eof in decoder loop
2007-07-19 09:01:47.171 Ignoring livetv eof in decoder loop
2007-07-19 09:01:47.173 Avg read interval was 0 msec. 64K block size
2007-07-19 09:01:47.173 Avg read interval was 196 msec. 96K block size
2007-07-19 09:01:47.614 Avg read interval was 196 msec. 576K block size
2007-07-19 09:01:47.615 Avg read interval was 196 msec. 608K block size
2007-07-19 09:01:47.615 Avg read interval was 196 msec. 640K block size
2007-07-19 09:01:47.673 Ignoring livetv eof in decoder loop
2007-07-19 09:01:47.735 Ignoring livetv eof in decoder loop
[mpegvideo_xvmc @ 0xb74a7e88]ac-tex damaged at 19 4
[mpegvideo_xvmc @ 0xb74a7e88]Warning MVs not available
2007-07-19 09:01:47.801 Ignoring livetv eof in decoder loop
2007-07-19 09:01:47.803 Avg read interval was 0 msec. 64K block size
2007-07-19 09:01:47.803 Avg read interval was 196 msec. 96K block size
...
...
...
2007-07-19 09:01:47.804 Avg read interval was 196 msec. 640K block size
2007-07-19 09:01:47.853 Ignoring livetv eof in decoder loop
2007-07-19 09:01:47.855 Avg read interval was 0 msec. 64K block size
2007-07-19 09:01:47.877 NVP: prebuffering pause
2007-07-19 09:01:47.877 NVP: Waiting for prebuffer.. 0 AALAAdAA
2007-07-19 09:01:47.904 Ignoring livetv eof in decoder loop
2007-07-19 09:01:47.906 Avg read interval was 0 msec. 64K block size
2007-07-19 09:01:47.906 Avg read interval was 196 msec. 96K block size
2007-07-19 09:01:48.618 Ignoring livetv eof in decoder loop
2007-07-19 09:01:48.669 Ignoring livetv eof in decoder loop
2007-07-19 09:01:49.181 Avg read interval was 196 msec. 288K block size
...
...
...
2007-07-19 09:02:15.081 TV: Attempting to change from WatchingLiveTV to None
2007-07-19 09:02:15.081 TV: StopStuff() -- begin
2007-07-19 09:02:15.081 TV: StopStuff(): stopping ring buffer[s]
2007-07-19 09:02:15.088 TV: StopStuff(): stopping player[s] (1/2)
2007-07-19 09:02:15.088 TV: StopStuff(): stopping recorder[s]
2007-07-19 09:02:15.108 NVP: Waiting for prebuffer.. 2 AALAAdAA
2007-07-19 09:02:15.124 Avg read interval was 4 msec. 64K block size
2007-07-19 09:02:15.257 NVP: Waiting for prebuffer.. 3 AALAAdAA
2007-07-19 09:02:15.407 NVP: Waiting for prebuffer.. 4 AALAAdAA
2007-07-19 09:02:15.469 TV: StopStuff(): stopping player[s] (2/2)
2007-07-19 09:02:15.556 NVP: Waiting for prebuffer.. 5 AALAAdAA
2007-07-19 09:02:15.705 NVP: Waiting for prebuffer.. 6 AALAAdAA
2007-07-19 09:02:15.855 NVP: Waiting for prebuffer.. 7 AALAAdAA
2007-07-19 09:02:16.004 NVP: Waiting for prebuffer.. 8 AALAAdAA
2007-07-19 09:02:16.123 RingBuf(/video/recordings/1006_20070719090000.mpg): Waited 1.0 seconds for data to become available...
2007-07-19 09:02:16.123 Checking to see if there's a new livetv program to switch to..
2007-07-19 09:02:16.154 NVP: Waiting for prebuffer.. 9 AALAAdAA
2007-07-19 09:02:16.288 NVP: Prebuffer wait timed out 10 times.
2007-07-19 09:02:16.303 NVP: Waiting for prebuffer.. 0 AALAAdAA
2007-07-19 09:02:16.453 NVP: Waiting for prebuffer.. 1 AALAAdAA
2007-07-19 09:02:16.602 NVP: Waiting for prebuffer.. 2 AALAAdAA
2007-07-19 09:02:16.752 NVP: Waiting for prebuffer.. 3 AALAAdAA
2007-07-19 09:02:16.901 NVP: Waiting for prebuffer.. 4 AALAAdAA
2007-07-19 09:02:17.050 NVP: Waiting for prebuffer.. 5 AALAAdAA
2007-07-19 09:02:17.128 RingBuf(/video/recordings/1006_20070719090000.mpg): Waited 2.0 seconds for data to become available...
2007-07-19 09:02:17.128 Checking to see if there's a new livetv program to switch to..
2007-07-19 09:02:30.949 NVP: Waiting for prebuffer.. 8 AALAAdAA
2007-07-19 09:02:31.098 NVP: Waiting for prebuffer.. 9 AALAAdAA
2007-07-19 09:02:31.188 RingBuf(/video/recordings/1006_20070719090000.mpg) Error: Waited 16 seconds for data, aborting.
2007-07-19 09:02:31.189 NVP: Exited decoder loop.
2007-07-19 09:02:31.232 NVP: Prebuffer wait timed out 10 times.
2007-07-19 09:02:31.248 VideoOutputXv: dtor
2007-07-19 09:02:31.248 VideoOutputXv: DiscardFrames(1)
2007-07-19 09:02:31.248 VideoOutputXv: DiscardFrames() 1: AALAAdAA
2007-07-19 09:02:31.249 VideoOutputXv: DiscardFrames() 2: AADAAdAA
2007-07-19 09:02:31.250 VideoOutputXv: Frame F is in use by avlib and so is being held for later discarding.
2007-07-19 09:02:31.250 VideoOutputXv: Frame F is in use by avlib and so is being held for later discarding.
2007-07-19 09:02:31.250 VideoOutputXv: DiscardFrames() 3: AAAAAdAA -- done()
2007-07-19 09:02:31.250 VideoOutputXv: DiscardFrames(1)
2007-07-19 09:02:31.250 VideoOutputXv: DiscardFrames() 1: AAAAAdAA
2007-07-19 09:02:31.250 VideoOutputXv: Frame F is in use by avlib and so is being held for later discarding.
2007-07-19 09:02:31.250 VideoOutputXv: DiscardFrames() 2: AAAAAdAA
2007-07-19 09:02:31.250 VideoOutputXv: Frame F is in use by avlib and so is being held for later discarding.
2007-07-19 09:02:31.250 VideoOutputXv: DiscardFrames() 3: AAAAAdAA -- done()
2007-07-19 09:02:31.258 VideoOutputXv: Closing XVideo port 355
2007-07-19 09:02:31.356 TV: StopStuff() -- end
2007-07-19 09:02:31.356 TV: Changing from WatchingLiveTV to None