[mythtv] r9061 still fails at program boundary

[Robin Gilks]

Just upgraded to SVN r9061 before breakfast today to check out the fixes
that Isaac checked in last night. Still broken for me:-(

I've not had a chance to dump a detailed log yet (work calls!) but here is
the standard (non-verbose) frontend log from starting LiveTV a couple of
minutes before a program change. Note that it normally seems to take 3
goes at finding the file...

The file created at the program change *IS* 34 seconds long and has the
correct name (2013_20060221090000.mpg) which indicates to me that the
backend did its stuff OK. After ESCaping out and re-entering LiveTV, it
works fine.

I noticed that several entries are not timestamped and the non-verbose
backend log is useless (so I've not included it here) as there are no
timestamps in it.



2006-02-21 08:58:09.361 TV: Attempting to change from None to WatchingLiveTV
2006-02-21 08:58:09.368 Using protocol version 27
2006-02-21 08:58:11.061 RingBuf(/mnt/store//2013_20060221085810.mpg):
Invalid file (fd 16) when opening '/mnt/store//2013_20060221085810.mpg'.
12 retries remaining.
2006-02-21 08:58:11.563 RingBuf(/mnt/store//2013_20060221085810.mpg):
Invalid file (fd 16) when opening '/mnt/store//2013_20060221085810.mpg'.
11 retries remaining.
2006-02-21 08:58:12.065 RingBuf(/mnt/store//2013_20060221085810.mpg):
Invalid file (fd 16) when opening '/mnt/store//2013_20060221085810.mpg'.
10 retries remaining.
[mpeg @ 0xb769e360]Parser not found for Codec Id: 94210 !
0: start_time: 0.036 duration: 0.184
1: start_time: 7.972 duration: 0.191
2: start_time: 0.025 duration: 0.136
stream: start_time: 0.276 duration: 90.424 bitrate=93 kb/s
2006-02-21 08:58:14.761 AFD: Opened codec 0x82905a0,
id(MPEG2VIDEO_XVMC_VLD) type(Video)
2006-02-21 08:58:14.882 AFD: Opened codec 0x8279b20, id(MP2) type(Audio)
2006-02-21 08:58:15.118 Opening audio device 'default'.
2006-02-21 08:58:16.286 TV: Changing from None to WatchingLiveTV
2006-02-21 08:58:16.292 Realtime priority would require SUID as root.
2006-02-21 08:58:16.400 Video timing method: DRM
2006-02-21 09:00:04.864 NVP: prebuffering pause
2006-02-21 09:00:05.398 RingBuf(/mnt/store//2013_20060221090000.mpg):
Taking too long to be allowed to read..
2006-02-21 09:00:06.400 RingBuf(/mnt/store//2013_20060221090000.mpg):
Taking too long to be allowed to read..
2006-02-21 09:00:06.644 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:07.401 RingBuf(/mnt/store//2013_20060221090000.mpg):
Taking too long to be allowed to read..
2006-02-21 09:00:07.402 restarting readhead thread..
2006-02-21 09:00:08.445 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:08.509 RingBuf(/mnt/store//2013_20060221090000.mpg):
Taking too long to be allowed to read..
2006-02-21 09:00:09.516 RingBuf(/mnt/store//2013_20060221090000.mpg):
Taking too long to be allowed to read..
2006-02-21 09:00:09.517 restarting readhead thread..
2006-02-21 09:00:10.245 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:10.686 RingBuf(/mnt/store//2013_20060221090000.mpg):
Taking too long to be allowed to read..
2006-02-21 09:00:11.687 RingBuf(/mnt/store//2013_20060221090000.mpg):
Taking too long to be allowed to read..
2006-02-21 09:00:11.688 restarting readhead thread..
2006-02-21 09:00:12.044 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:12.861 RingBuf(/mnt/store//2013_20060221090000.mpg):
Taking too long to be allowed to read..
2006-02-21 09:00:13.845 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:13.864 RingBuf(/mnt/store//2013_20060221090000.mpg):
Taking too long to be allowed to read..
2006-02-21 09:00:13.864 restarting readhead thread..
2006-02-21 09:00:15.040 RingBuf(/mnt/store//2013_20060221090000.mpg):
Taking too long to be allowed to read..
2006-02-21 09:00:15.645 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:16.041 RingBuf(/mnt/store//2013_20060221090000.mpg):
Taking too long to be allowed to read..
2006-02-21 09:00:16.042 restarting readhead thread..
2006-02-21 09:00:16.213 RingBuf(/mnt/store//2013_20060221090000.mpg)
Error: Took more than 10 seconds to be allowed to read, aborting.
2006-02-21 09:00:16.266 AddInheritence past     e    NOT in used or in
done. AAAAAAAAAAAAaDLL
2006-02-21 09:00:17.983 NVP: prebuffering pause
2006-02-21 09:00:17.987 WriteAudio: buffer underrun
2006-02-21 09:00:19.765 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:21.564 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:23.364 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:25.165 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:26.964 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:28.764 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:30.564 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:32.364 NVP: Prebuffer wait timed out 10 times.
2006-02-21 09:00:33.944 TV: Attempting to change from WatchingLiveTV to None
2006-02-21 09:00:34.298 TV: Changing from WatchingLiveTV to None
2006-02-21 09:00:35.982 TV: Attempting to change from None to WatchingLiveTV
2006-02-21 09:00:35.991 Using protocol version 27
2006-02-21 09:00:37.306 RingBuf(/mnt/store//2013_20060221090037.mpg):
Invalid file (fd 16) when opening '/mnt/store//2013_20060221090037.mpg'.
12 retries remaining.
2006-02-21 09:00:37.808 RingBuf(/mnt/store//2013_20060221090037.mpg):
Invalid file (fd 16) when opening '/mnt/store//2013_20060221090037.mpg'.
11 retries remaining.
2006-02-21 09:00:38.310 RingBuf(/mnt/store//2013_20060221090037.mpg):
Invalid file (fd 16) when opening '/mnt/store//2013_20060221090037.mpg'.
10 retries remaining.
[mpeg @ 0xb769e360]Parser not found for Codec Id: 94210 !
0: start_time: 0.036 duration: 0.184
1: start_time: 21.127 duration: 0.187
2: start_time: 0.025 duration: 0.136
stream: start_time: 0.276 duration: 236.543 bitrate=36 kb/s
2006-02-21 09:00:40.887 AFD: Opened codec 0x84e85a0,
id(MPEG2VIDEO_XVMC_VLD) type(Video)
2006-02-21 09:00:40.888 AFD: Opened codec 0x85a4740, id(MP2) type(Audio)
2006-02-21 09:00:40.891 Opening audio device 'default'.
2006-02-21 09:00:41.802 TV: Changing from None to WatchingLiveTV
2006-02-21 09:00:41.807 Realtime priority would require SUID as root.
2006-02-21 09:00:41.862 Video timing method: DRM
2006-02-21 09:00:45.892 TV: Attempting to change from WatchingLiveTV to None
2006-02-21 09:00:46.111 TV: Changing from WatchingLiveTV to None



-- 
Robin Gilks