Ticket #2335: mythlog.txt

File mythlog.txt, 12.1 KB (added by eric.bosch@…, 18 years ago)

Frontend Log file during failed program transition

Line 
12007-07-19 09:00:07.102 NVP: IsReallyNearEnd() br(798KB) fps(29) sz(449KB) vfl(4) frh(16) ne:0
22007-07-19 09:00:07.113 NVP: IsReallyNearEnd() br(798KB) fps(29) sz(449KB) vfl(4) frh(16) ne:0
32007-07-19 09:00:07.123 NVP: IsReallyNearEnd() br(798KB) fps(29) sz(416KB) vfl(4) frh(15) ne:0
42007-07-19 09:00:07.126 NVP: IsReallyNearEnd() br(798KB) fps(29) sz(383KB) vfl(5) frh(14) ne:1
52007-07-19 09:00:07.126 SwitchToProgram(void)
62007-07-19 09:00:07.179 RingBuf(/video/recordings/1006_20070719075204.mpg): OpenFile(/video/recordings/1006_20070719090000.mpg, 10)
72007-07-19 09:00:07.179 RingBuf(/video/recordings/1006_20070719090000.mpg): CalcReadAheadThresh(2897423528 KB)
8 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
92007-07-19 09:00:07.180 RingBuf(/video/recordings/1006_20070719090000.mpg): CalcReadAheadThresh(3076815772 KB)
10 -> threshhold(64 KB) min read(0 KB) blk size(32 KB)
112007-07-19 09:00:07.207 Avg read interval was 184 msec. 64K block size
122007-07-19 09:00:07.623 FileChangedCallback
132007-07-19 09:00:07.673 Resyncing position map. posmapStarted = 0 livetv(1) watchingRec(1)
142007-07-19 09:00:07.674 Position map filled from DB to: 1
152007-07-19 09:00:07.674 SyncPositionMap watchingrecording, from DB: 1 entries
162007-07-19 09:00:07.679 Filling position map from 2 to 8
172007-07-19 09:00:07.686 Position map filled from Encoder to: 8
182007-07-19 09:00:07.686 SyncPositionMap watchingrecording total: 8 entries
192007-07-19 09:00:07.686 SyncPositionMap, new totframes: 120, new length: 4, posMap size: 8
202007-07-19 09:00:07.805 Avg read interval was 187 msec. 224K block size
21'video_output' mean = '33209.48', std. dev. = '139.87', fps = '30.11'
222007-07-19 09:00:09.285 Avg read interval was 188 msec. 256K block size
232007-07-19 09:00:10.958 Avg read interval was 190 msec. 288K block size
24'video_output' mean = '33210.13', std. dev. = '159.30', fps = '30.11'
252007-07-19 09:00:14.791 NVP: 122400 interlaced frames seen.
26'video_output' mean = '33203.93', std. dev. = '210.50', fps = '30.12'
27'video_output' mean = '33375.99', std. dev. = '1667.30', fps = '29.96'
28'video_output' mean = '33373.53', std. dev. = '1653.68', fps = '29.96'
29'video_output' mean = '33372.28', std. dev. = '1696.70', fps = '29.96'
302007-07-19 09:01:30.084 Avg read interval was 187 msec. 288K block size
312007-07-19 09:01:30.508 NVP: prebuffering pause
322007-07-19 09:01:30.508 NVP: Waiting for prebuffer.. 0 dAAAALAd
33...
34...
35...
362007-07-19 09:01:31.702 RingBuf(/video/recordings/1006_20070719090000.mpg): Waited 1.0 seconds for data to become available...
372007-07-19 09:01:31.702 Checking to see if there's a new livetv program to switch to..
382007-07-19 09:01:31.703 NVP: Waiting for prebuffer.. 8 dAAAALAd
392007-07-19 09:01:31.852 NVP: Waiting for prebuffer.. 9 dAAAALAd
402007-07-19 09:01:32.707 RingBuf(/video/recordings/1006_20070719090000.mpg): Waited 2.0 seconds for data to become available...
412007-07-19 09:01:32.707 Checking to see if there's a new livetv program to switch to..
422007-07-19 09:01:32.749 NVP: Waiting for prebuffer.. 5 dAAAALAd
432007-07-19 09:01:34.717 RingBuf(/video/recordings/1006_20070719090000.mpg): Waited 4.0 seconds for data to become available...
442007-07-19 09:01:34.717 Checking to see if there's a new livetv program to switch to..
452007-07-19 09:01:34.841 NVP: Waiting for prebuffer.. 9 dAAAALAd
46...
47...
48...
492007-07-19 09:01:38.727 NVP: Waiting for prebuffer.. 5 dAAAALAd
502007-07-19 09:01:38.735 RingBuf(/video/recordings/1006_20070719090000.mpg): Waited 8.0 seconds for data to become available...
512007-07-19 09:01:38.735 Checking to see if there's a new livetv program to switch to..
522007-07-19 09:01:38.876 NVP: Waiting for prebuffer.. 6 dAAAALAd
532007-07-19 09:01:46.769 RingBuf(/video/recordings/1006_20070719090000.mpg) Error: Waited 16 seconds for data, aborting.
542007-07-19 09:01:46.797 NVP: Waiting for prebuffer.. 9 dAAAALAd
552007-07-19 09:01:46.829 Ignoring livetv eof in decoder loop
562007-07-19 09:01:46.883 Avg read interval was 196 msec. 64K block size
572007-07-19 09:01:46.883 Ignoring livetv eof in decoder loop
582007-07-19 09:01:46.885 Avg read interval was 196 msec. 64K block size
592007-07-19 09:01:46.885 Avg read interval was 196 msec. 96K block size
602007-07-19 09:01:46.885 Avg read interval was 196 msec. 128K block size
612007-07-19 09:01:46.885 Avg read interval was 196 msec. 160K block size
622007-07-19 09:01:46.885 Avg read interval was 196 msec. 192K block size
632007-07-19 09:01:46.885 Avg read interval was 196 msec. 224K block size
642007-07-19 09:01:46.886 Avg read interval was 196 msec. 256K block size
652007-07-19 09:01:46.886 Avg read interval was 196 msec. 288K block size
662007-07-19 09:01:46.886 Avg read interval was 196 msec. 320K block size
672007-07-19 09:01:46.886 Avg read interval was 196 msec. 352K block size
682007-07-19 09:01:46.886 Avg read interval was 196 msec. 384K block size
692007-07-19 09:01:46.886 Avg read interval was 196 msec. 416K block size
702007-07-19 09:01:46.886 Avg read interval was 196 msec. 448K block size
712007-07-19 09:01:46.886 Avg read interval was 196 msec. 480K block size
722007-07-19 09:01:46.886 Avg read interval was 196 msec. 512K block size
732007-07-19 09:01:46.886 Avg read interval was 196 msec. 544K block size
742007-07-19 09:01:46.886 Avg read interval was 196 msec. 576K block size
752007-07-19 09:01:46.886 Avg read interval was 196 msec. 608K block size
762007-07-19 09:01:46.886 Avg read interval was 196 msec. 640K block size
772007-07-19 09:01:46.931 NVP: Prebuffer wait timed out 10 times.
782007-07-19 09:01:46.939 Avg read interval was 0 msec. 64K block size
792007-07-19 09:01:46.939 Avg read interval was 196 msec. 96K block size
802007-07-19 09:01:46.939 Avg read interval was 196 msec. 128K block size
812007-07-19 09:01:46.939 Avg read interval was 196 msec. 160K block size
822007-07-19 09:01:46.939 Avg read interval was 196 msec. 192K block size
832007-07-19 09:01:46.940 Avg read interval was 196 msec. 224K block size
842007-07-19 09:01:46.940 Avg read interval was 196 msec. 256K block size
852007-07-19 09:01:46.940 Avg read interval was 196 msec. 288K block size
862007-07-19 09:01:46.940 Avg read interval was 196 msec. 320K block size
872007-07-19 09:01:46.940 Avg read interval was 196 msec. 352K block size
882007-07-19 09:01:46.940 Avg read interval was 196 msec. 384K block size
892007-07-19 09:01:46.940 Avg read interval was 196 msec. 416K block size
902007-07-19 09:01:46.940 Avg read interval was 196 msec. 448K block size
912007-07-19 09:01:46.940 Avg read interval was 196 msec. 480K block size
922007-07-19 09:01:46.940 Avg read interval was 196 msec. 512K block size
932007-07-19 09:01:46.940 Avg read interval was 196 msec. 544K block size
942007-07-19 09:01:46.940 Avg read interval was 196 msec. 576K block size
952007-07-19 09:01:46.940 Avg read interval was 196 msec. 608K block size
962007-07-19 09:01:46.940 Avg read interval was 196 msec. 640K block size
972007-07-19 09:01:46.942 Ignoring livetv eof in decoder loop
982007-07-19 09:01:46.946 NVP: Waiting for prebuffer.. 0 DULAAuAD
992007-07-19 09:01:46.997 Ignoring livetv eof in decoder loop
1002007-07-19 09:01:46.999 Avg read interval was 0 msec. 64K block size
1012007-07-19 09:01:46.999 Avg read interval was 196 msec. 96K block size
102...
103...
104...
1052007-07-19 09:01:47.052 Ignoring livetv eof in decoder loop
1062007-07-19 09:01:47.055 Avg read interval was 196 msec. 640K block size
1072007-07-19 09:01:47.096 NVP: Waiting for prebuffer.. 1 DULuAuUD
1082007-07-19 09:01:47.115 Ignoring livetv eof in decoder loop
1092007-07-19 09:01:47.171 Ignoring livetv eof in decoder loop
1102007-07-19 09:01:47.173 Avg read interval was 0 msec. 64K block size
1112007-07-19 09:01:47.173 Avg read interval was 196 msec. 96K block size
1122007-07-19 09:01:47.614 Avg read interval was 196 msec. 576K block size
1132007-07-19 09:01:47.615 Avg read interval was 196 msec. 608K block size
1142007-07-19 09:01:47.615 Avg read interval was 196 msec. 640K block size
1152007-07-19 09:01:47.673 Ignoring livetv eof in decoder loop
1162007-07-19 09:01:47.735 Ignoring livetv eof in decoder loop
117[mpegvideo_xvmc @ 0xb74a7e88]ac-tex damaged at 19 4
118[mpegvideo_xvmc @ 0xb74a7e88]Warning MVs not available
1192007-07-19 09:01:47.801 Ignoring livetv eof in decoder loop
1202007-07-19 09:01:47.803 Avg read interval was 0 msec. 64K block size
1212007-07-19 09:01:47.803 Avg read interval was 196 msec. 96K block size
122...
123...
124...
1252007-07-19 09:01:47.804 Avg read interval was 196 msec. 640K block size
1262007-07-19 09:01:47.853 Ignoring livetv eof in decoder loop
1272007-07-19 09:01:47.855 Avg read interval was 0 msec. 64K block size
1282007-07-19 09:01:47.877 NVP: prebuffering pause
1292007-07-19 09:01:47.877 NVP: Waiting for prebuffer.. 0 AALAAdAA
1302007-07-19 09:01:47.904 Ignoring livetv eof in decoder loop
1312007-07-19 09:01:47.906 Avg read interval was 0 msec. 64K block size
1322007-07-19 09:01:47.906 Avg read interval was 196 msec. 96K block size
1332007-07-19 09:01:48.618 Ignoring livetv eof in decoder loop
1342007-07-19 09:01:48.669 Ignoring livetv eof in decoder loop
1352007-07-19 09:01:49.181 Avg read interval was 196 msec. 288K block size
136...
137...
138...
1392007-07-19 09:02:15.081 TV: Attempting to change from WatchingLiveTV to None
1402007-07-19 09:02:15.081 TV: StopStuff() -- begin
1412007-07-19 09:02:15.081 TV: StopStuff(): stopping ring buffer[s]
1422007-07-19 09:02:15.088 TV: StopStuff(): stopping player[s] (1/2)
1432007-07-19 09:02:15.088 TV: StopStuff(): stopping recorder[s]
1442007-07-19 09:02:15.108 NVP: Waiting for prebuffer.. 2 AALAAdAA
1452007-07-19 09:02:15.124 Avg read interval was 4 msec. 64K block size
1462007-07-19 09:02:15.257 NVP: Waiting for prebuffer.. 3 AALAAdAA
1472007-07-19 09:02:15.407 NVP: Waiting for prebuffer.. 4 AALAAdAA
1482007-07-19 09:02:15.469 TV: StopStuff(): stopping player[s] (2/2)
1492007-07-19 09:02:15.556 NVP: Waiting for prebuffer.. 5 AALAAdAA
1502007-07-19 09:02:15.705 NVP: Waiting for prebuffer.. 6 AALAAdAA
1512007-07-19 09:02:15.855 NVP: Waiting for prebuffer.. 7 AALAAdAA
1522007-07-19 09:02:16.004 NVP: Waiting for prebuffer.. 8 AALAAdAA
1532007-07-19 09:02:16.123 RingBuf(/video/recordings/1006_20070719090000.mpg): Waited 1.0 seconds for data to become available...
1542007-07-19 09:02:16.123 Checking to see if there's a new livetv program to switch to..
1552007-07-19 09:02:16.154 NVP: Waiting for prebuffer.. 9 AALAAdAA
1562007-07-19 09:02:16.288 NVP: Prebuffer wait timed out 10 times.
1572007-07-19 09:02:16.303 NVP: Waiting for prebuffer.. 0 AALAAdAA
1582007-07-19 09:02:16.453 NVP: Waiting for prebuffer.. 1 AALAAdAA
1592007-07-19 09:02:16.602 NVP: Waiting for prebuffer.. 2 AALAAdAA
1602007-07-19 09:02:16.752 NVP: Waiting for prebuffer.. 3 AALAAdAA
1612007-07-19 09:02:16.901 NVP: Waiting for prebuffer.. 4 AALAAdAA
1622007-07-19 09:02:17.050 NVP: Waiting for prebuffer.. 5 AALAAdAA
1632007-07-19 09:02:17.128 RingBuf(/video/recordings/1006_20070719090000.mpg): Waited 2.0 seconds for data to become available...
1642007-07-19 09:02:17.128 Checking to see if there's a new livetv program to switch to..
1652007-07-19 09:02:30.949 NVP: Waiting for prebuffer.. 8 AALAAdAA
1662007-07-19 09:02:31.098 NVP: Waiting for prebuffer.. 9 AALAAdAA
1672007-07-19 09:02:31.188 RingBuf(/video/recordings/1006_20070719090000.mpg) Error: Waited 16 seconds for data, aborting.
1682007-07-19 09:02:31.189 NVP: Exited decoder loop.
1692007-07-19 09:02:31.232 NVP: Prebuffer wait timed out 10 times.
1702007-07-19 09:02:31.248 VideoOutputXv: dtor
1712007-07-19 09:02:31.248 VideoOutputXv: DiscardFrames(1)
1722007-07-19 09:02:31.248 VideoOutputXv: DiscardFrames() 1: AALAAdAA
1732007-07-19 09:02:31.249 VideoOutputXv: DiscardFrames() 2: AADAAdAA
1742007-07-19 09:02:31.250 VideoOutputXv: Frame F is in use by avlib and so is being held for later discarding.
1752007-07-19 09:02:31.250 VideoOutputXv: Frame F is in use by avlib and so is being held for later discarding.
1762007-07-19 09:02:31.250 VideoOutputXv: DiscardFrames() 3: AAAAAdAA -- done()
1772007-07-19 09:02:31.250 VideoOutputXv: DiscardFrames(1)
1782007-07-19 09:02:31.250 VideoOutputXv: DiscardFrames() 1: AAAAAdAA
1792007-07-19 09:02:31.250 VideoOutputXv: Frame F is in use by avlib and so is being held for later discarding.
1802007-07-19 09:02:31.250 VideoOutputXv: DiscardFrames() 2: AAAAAdAA
1812007-07-19 09:02:31.250 VideoOutputXv: Frame F is in use by avlib and so is being held for later discarding.
1822007-07-19 09:02:31.250 VideoOutputXv: DiscardFrames() 3: AAAAAdAA -- done()
1832007-07-19 09:02:31.258 VideoOutputXv: Closing XVideo port 355
1842007-07-19 09:02:31.356 TV: StopStuff() -- end
1852007-07-19 09:02:31.356 TV: Changing from WatchingLiveTV to None