[mythtv] mythtv-vid branch needs testing

Fri Aug 17 01:15:42 UTC 2007

Tom Lichti wrote:
> Daniel Kristjansson wrote:
>   
>> Aside from a memory leak it looks like most of the OSX issues have
>> been addressed. A few of us have been using this branch for a while,
>> but it would be good to get some people to test this before we merge
>> it into the trunk.
>>
>>   
>>     
> The mythtv-vid branch using CPU+ on my dual core 2.8 P4 with an nVidia 
> 6200 AGP card is giving good results so far.
>
> I haven't explored all the options yet, but it plays all my source 
> material very nicely (PVR-x50's and an HDHR).
>   
I spoke too soon. I am also seeing 'prebuffer pauses' with ivtv 
recordings, recordings from pre-ffmpeg sync and also after. It seems to 
be related to the fps being reported in the logs. If the 'video_output' 
fps is 29.97, playback is perfect, but as soon as it strays from that, I 
get pauses:

'video_output' mean = '50039.38', std. dev. = '135464.06', fps = '19.98'
'video_output' mean = '33366.18', std. dev. = '888.38', fps = '29.97'
2007-08-16 21:05:24.760 NVP: prebuffering pause
2007-08-16 21:05:24.760 NVP: Waiting for prebuffer.. 0 
AAAAAAAuAAAAAAAAAAAAAAAALAAAAAA
2007-08-16 21:05:24.909 NVP: Waiting for prebuffer.. 1 
AAAAAAAuAAAAAAAAAAAAAAAALAAAAAA
2007-08-16 21:05:25.059 NVP: Waiting for prebuffer.. 2 
AAAAAAAuAAAAAAAAAAAAAAAALAAAAAA
2007-08-16 21:05:25.209 NVP: Waiting for prebuffer.. 3 
AAAAAAAUAALAAAAAAAAUAAAAuAAAAUA
2007-08-16 21:05:25.360 NVP: Waiting for prebuffer.. 4 
UAAAAUAUAAUuuLAUAAAUAAUAUAAAAUA
'video_output' mean = '39532.00', std. dev. = '61728.56', fps = '25.30'
2007-08-16 21:05:26.678 NVP: prebuffering pause
2007-08-16 21:05:26.679 NVP: Waiting for prebuffer.. 0 
AAAAAAAAAAuLAAAAAAAAAAAAAAAAAAA
2007-08-16 21:05:26.828 NVP: Waiting for prebuffer.. 1 
AAAAAAAAAAUUUuAALAUAAUAAAAAUAAA
2007-08-16 21:05:27.963 NVP: prebuffering pause
2007-08-16 21:05:27.963 NVP: Waiting for prebuffer.. 0 
AAAAAAAAAALAuAAAAAAAAAAAaAAAAAA
2007-08-16 21:05:28.112 NVP: Waiting for prebuffer.. 1 
AAAAAAAAAALAuAAAAAAAAAAAaAAAAAA
2007-08-16 21:05:28.262 NVP: Waiting for prebuffer.. 2 
AAAAAAAAAALAuAAAAAAAAAAAaAAAAAA
2007-08-16 21:05:28.412 NVP: Waiting for prebuffer.. 3 
AAAAAAAAAAUUUuUALAUAAUAAAAAUAAU
'video_output' mean = '40537.91', std. dev. = '58227.90', fps = '24.67'
2007-08-16 21:05:33.035 NVP: 6800 interlaced frames seen.
'video_output' mean = '33365.43', std. dev. = '687.80', fps = '29.97'
'video_output' mean = '33362.04', std. dev. = '131.73', fps = '29.97'
'video_output' mean = '33363.60', std. dev. = '177.48', fps = '29.97'
2007-08-16 21:05:41.542 NVP: prebuffering pause
2007-08-16 21:05:41.543 NVP: Waiting for prebuffer.. 0 
AAAAAAAaAAAAAAAAAAAAAAAALAAuAAA
2007-08-16 21:05:41.692 NVP: Waiting for prebuffer.. 1 
AAAAAAAaAAAAAAAAAAAAAAAALAAuAAA
2007-08-16 21:05:41.842 NVP: Waiting for prebuffer.. 2 
AAAAAAAaAAAAAAAAAAAAAAAALAAuAAA
2007-08-16 21:05:41.992 NVP: Waiting for prebuffer.. 3 
AAAAAAAaAAAAAAAAAAAAAAAALAAuAAA
2007-08-16 21:05:42.142 NVP: Waiting for prebuffer.. 4 
AAAAAAAaAAAAAAAAAAAAAAAALAAuAAA
2007-08-16 21:05:42.292 NVP: Waiting for prebuffer.. 5 
AAAAAAAaAAAAAAAAAAAAAuAALAAUAAA
2007-08-16 21:05:42.443 NVP: Waiting for prebuffer.. 6 
AAUAAAAAAAUuALUAAUAAAUAuUAAUAAU
2007-08-16 21:05:42.593 NVP: Waiting for prebuffer.. 7 
AAUuAAAAAAUuALUAAUAAAUAUUAAUAAU
'video_output' mean = '44706.68', std. dev. = '113489.12', fps = '22.37'
2007-08-16 21:05:47.498 NVP: 7200 interlaced frames seen.
'video_output' mean = '33197.68', std. dev. = '1846.86', fps = '30.12'
'video_output' mean = '33361.09', std. dev. = '983.20', fps = '29.98'
'video_output' mean = '33362.99', std. dev. = '477.75', fps = '29.97'
'video_output' mean = '33363.75', std. dev. = '482.05', fps = '29.97'
2007-08-16 21:05:59.209 NVP: prebuffering pause
2007-08-16 21:05:59.210 NVP: Waiting for prebuffer.. 0 
AAAAAAAaAuAAAAAAAAAAAAAALAAAAAA
2007-08-16 21:05:59.359 NVP: Waiting for prebuffer.. 1 
AAAAAAAaAuAAAAAAAAAAAAAALAAAAAA
2007-08-16 21:05:59.509 NVP: Waiting for prebuffer.. 2 
UAAAAUAAAUUUuuAULAAUAAUAUAAAAUA
2007-08-16 21:06:01.129 NVP: 7600 interlaced frames seen.
'video_output' mean = '36197.60', std. dev. = '35333.61', fps = '27.63'
'video_output' mean = '33361.67', std. dev. = '390.52', fps = '29.97'
'video_output' mean = '33364.27', std. dev. = '458.22', fps = '29.97'
'video_output' mean = '33372.38', std. dev. = '860.82', fps = '29.96'
2007-08-16 21:06:14.474 NVP: 8000 interlaced frames seen.
'video_output' mean = '33355.04', std. dev. = '958.40', fps = '29.98'
'video_output' mean = '33362.39', std. dev. = '656.61', fps = '29.97'
'video_output' mean = '33364.09', std. dev. = '348.75', fps = '29.97'
'video_output' mean = '33358.27', std. dev. = '828.68', fps = '29.98'
2007-08-16 21:06:27.820 NVP: 8400 interlaced frames seen.
'video_output' mean = '33366.18', std. dev. = '818.34', fps = '29.97'
'video_output' mean = '33360.57', std. dev. = '550.18', fps = '29.98'
'video_output' mean = '33365.26', std. dev. = '457.76', fps = '29.97'
'video_output' mean = '33363.16', std. dev. = '871.52', fps = '29.97'
2007-08-16 21:06:41.167 NVP: 8800 interlaced frames seen.
'video_output' mean = '33362.97', std. dev. = '162.95', fps = '29.97'
'video_output' mean = '33362.80', std. dev. = '151.59', fps = '29.97'
'video_output' mean = '33361.43', std. dev. = '698.79', fps = '29.97'
'video_output' mean = '33363.96', std. dev. = '813.50', fps = '29.97'
2007-08-16 21:06:54.513 NVP: 9200 interlaced frames seen.
'video_output' mean = '33362.83', std. dev. = '950.70', fps = '29.97'
'video_output' mean = '33366.89', std. dev. = '470.34', fps = '29.97'
'video_output' mean = '33358.27', std. dev. = '285.97', fps = '29.98'
'video_output' mean = '33360.11', std. dev. = '449.21', fps = '29.98'
2007-08-16 21:07:07.859 NVP: 9600 interlaced frames seen.
'video_output' mean = '33366.45', std. dev. = '588.83', fps = '29.97'
'video_output' mean = '33387.49', std. dev. = '714.81', fps = '29.95'
'video_output' mean = '33336.02', std. dev. = '1491.70', fps = '30.00'
'video_output' mean = '33363.79', std. dev. = '1444.81', fps = '29.97'
2007-08-16 21:07:21.205 NVP: 10000 interlaced frames seen.
'video_output' mean = '33362.22', std. dev. = '167.81', fps = '29.97'
2007-08-16 21:07:23.677 Received a remote 'Clear Cache' request
'video_output' mean = '33364.92', std. dev. = '127.38', fps = '29.97'
'video_output' mean = '33362.87', std. dev. = '850.13', fps = '29.97'
'video_output' mean = '33362.15', std. dev. = '174.72', fps = '29.97'
2007-08-16 21:07:34.551 NVP: 10400 interlaced frames seen.

I am also seeing these:

2007-08-16 21:04:04.593 
RingBuf(myth://10.10.20.170:6543/4017_20070812143000.mpg): Taking too 
long to be allowed to read..
2007-08-16 21:04:05.595 
RingBuf(myth://10.10.20.170:6543/4017_20070812143000.mpg): Taking too 
long to be allowed to read..
2007-08-16 21:04:06.597 
RingBuf(myth://10.10.20.170:6543/4017_20070812143000.mpg): Taking too 
long to be allowed to read..

I have 'stream always' set on the frontend, and gigabit ethernet all around.

Tom