[mythtv] [mythtv-commits] Ticket #1340: playback halts during LiveTV at end of show from remote frontends

Tom Lichti tom at redpepperracing.com
Mon Feb 20 00:22:13 UTC 2006 

MythTV wrote:
> #1340: playback halts during LiveTV at end of show from remote frontends
> -----------------------------+----------------------------------------------
>  Reporter:  admin at prnet.org  |        Owner:  ijr      
>      Type:  defect           |       Status:  closed   
>  Priority:  blocker          |    Milestone:  unknown  
> Component:  mythtv           |      Version:  0.19     
>  Severity:  high             |   Resolution:  duplicate
> -----------------------------+----------------------------------------------
> Changes (by ijr):
>
>   * resolution:  => duplicate
>   * status:  reopened => closed
>
> Comment:
>
>  Pretty sure this duplicates #1309, which should be fixed in current svn.
>
>   
What if it's not? I thought it had, but I had the same thing happen 
tonight with current svn:

Library API version: 0.19.20060121-2
Source code version: 9053
Options compiled in:
 linux debug using_xvmcw using_v4l using_oss using_alsa using_ivtv 
using_lirc using_x11 using_xv using_xrandr using_xvmc using_xvmc_vld 
using_opengl_vsync using_frontend using_backend

The relevant frontend log (-v playback, general, important) portion is 
here. This is a remote frontend, talking to the master, and streaming 
the file from a slave backend, all SDTV from an Avermedia M179.

2006-02-19 17:59:59.211 AFD: positionMap[ 3553 ] == 815454310.
2006-02-19 17:59:59.711 AFD: positionMap[ 3554 ] == 815671398.
2006-02-19 18:00:00.212 AFD: positionMap[ 3555 ] == 815888486.
'video_output' mean = '33359.84', std. dev. = '189.57', fps = '29.98'
2006-02-19 18:00:00.712 AFD: positionMap[ 3556 ] == 816111718.
2006-02-19 18:00:01.212 AFD: positionMap[ 3557 ] == 816380006.
2006-02-19 18:00:01.713 AFD: positionMap[ 3558 ] == 816658534.
2006-02-19 18:00:02.213 AFD: positionMap[ 3559 ] == 816910438.
2006-02-19 18:00:02.714 AFD: positionMap[ 3560 ] == 817182822.
2006-02-19 18:00:03.214 AFD: positionMap[ 3561 ] == 817438822.
'video_output' mean = '33359.91', std. dev. = '166.57', fps = '29.98'
2006-02-19 18:00:03.715 AFD: positionMap[ 3562 ] == 817778790.
2006-02-19 18:00:04.215 AFD: positionMap[ 3563 ] == 817936486.
2006-02-19 18:00:04.716 AFD: positionMap[ 3564 ] == 818143334.
2006-02-19 18:00:05.216 AFD: positionMap[ 3565 ] == 818430054.
2006-02-19 18:00:05.716 AFD: positionMap[ 3566 ] == 818720870.
2006-02-19 18:00:06.217 AFD: positionMap[ 3567 ] == 818935910.
2006-02-19 18:00:06.718 AFD: positionMap[ 3568 ] == 819206246.
'video_output' mean = '33360.35', std. dev. = '111.82', fps = '29.98'
2006-02-19 18:00:07.218 AFD: positionMap[ 3569 ] == 819458150.
2006-02-19 18:00:08.551 NVP: prebuffering pause
2006-02-19 18:00:08.552 NVP: Waiting for prebuffer.. 0 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:08.603 WriteAudio: buffer underrun
2006-02-19 18:00:08.686 NVP: Waiting for prebuffer.. 1 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:08.822 NVP: Waiting for prebuffer.. 2 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:08.958 NVP: Waiting for prebuffer.. 3 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:09.094 NVP: Waiting for prebuffer.. 4 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:09.230 NVP: Waiting for prebuffer.. 5 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:09.366 NVP: Waiting for prebuffer.. 6 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:09.459 rebuffering (30721 32768)
2006-02-19 18:00:09.502 NVP: Waiting for prebuffer.. 7 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:09.608 
RingBuf(myth://10.10.20.169:6543/1006_20060219173013.mpg): Waited 2 
seconds for data to b
ecome available...
2006-02-19 18:00:09.638 NVP: Waiting for prebuffer.. 8 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:09.774 NVP: Waiting for prebuffer.. 9 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:09.910 NVP: Prebuffer wait timed out 10 times.
2006-02-19 18:00:09.910 NVP: Waiting for prebuffer.. 0 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:10.046 NVP: Waiting for prebuffer.. 1 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:10.182 NVP: Waiting for prebuffer.. 2 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:10.318 NVP: Waiting for prebuffer.. 3 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:10.454 NVP: Waiting for prebuffer.. 4 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:10.590 NVP: Waiting for prebuffer.. 5 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:10.726 NVP: Waiting for prebuffer.. 6 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:10.862 NVP: Waiting for prebuffer.. 7 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:10.998 NVP: Waiting for prebuffer.. 8 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:11.134 NVP: Waiting for prebuffer.. 9 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:11.270 NVP: Prebuffer wait timed out 10 times.
2006-02-19 18:00:11.270 NVP: Waiting for prebuffer.. 0 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:11.406 NVP: Waiting for prebuffer.. 1 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:11.542 NVP: Waiting for prebuffer.. 2 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:11.542 NVP: Waiting for prebuffer.. 2 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:11.625 
RingBuf(myth://10.10.20.169:6543/1006_20060219173013.mpg): Waited 4 
seconds for data to b
ecome available...
2006-02-19 18:00:11.678 NVP: Waiting for prebuffer.. 3 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:11.814 NVP: Waiting for prebuffer.. 4 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:11.950 NVP: Waiting for prebuffer.. 5 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:12.086 NVP: Waiting for prebuffer.. 6 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:12.222 NVP: Waiting for prebuffer.. 7 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:12.358 NVP: Waiting for prebuffer.. 8 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:12.494 NVP: Waiting for prebuffer.. 9 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:12.629 NVP: Prebuffer wait timed out 10 times.
2006-02-19 18:00:12.630 NVP: Waiting for prebuffer.. 0 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:12.765 NVP: Waiting for prebuffer.. 1 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:12.901 NVP: Waiting for prebuffer.. 2 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:13.037 NVP: Waiting for prebuffer.. 3 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:13.173 NVP: Waiting for prebuffer.. 4 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:13.309 NVP: Waiting for prebuffer.. 5 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:13.445 NVP: Waiting for prebuffer.. 6 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:13.581 NVP: Waiting for prebuffer.. 7 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:13.717 NVP: Waiting for prebuffer.. 8 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:13.853 NVP: Waiting for prebuffer.. 9 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:13.989 NVP: Prebuffer wait timed out 10 times.
2006-02-19 18:00:13.989 NVP: Waiting for prebuffer.. 0 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:14.125 NVP: Waiting for prebuffer.. 1 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:14.261 NVP: Waiting for prebuffer.. 2 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:14.397 NVP: Waiting for prebuffer.. 3 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:14.533 NVP: Waiting for prebuffer.. 4 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:14.669 NVP: Waiting for prebuffer.. 5 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:14.805 NVP: Waiting for prebuffer.. 6 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:14.941 NVP: Waiting for prebuffer.. 7 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:15.077 NVP: Waiting for prebuffer.. 8 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:15.213 NVP: Waiting for prebuffer.. 9 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:15.349 NVP: Prebuffer wait timed out 10 times.
2006-02-19 18:00:15.349 NVP: Waiting for prebuffer.. 0 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:15.485 NVP: Waiting for prebuffer.. 1 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:15.621 NVP: Waiting for prebuffer.. 2 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:15.657 
RingBuf(myth://10.10.20.169:6543/1006_20060219173013.mpg): Waited 8 
seconds for data to b
ecome available...
2006-02-19 18:00:15.757 NVP: Waiting for prebuffer.. 3 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:15.893 NVP: Waiting for prebuffer.. 4 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:16.029 NVP: Waiting for prebuffer.. 5 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:16.164 NVP: Waiting for prebuffer.. 6 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:16.299 NVP: Waiting for prebuffer.. 7 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:16.434 NVP: Waiting for prebuffer.. 8 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:16.569 NVP: Waiting for prebuffer.. 9 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:16.704 NVP: Prebuffer wait timed out 10 times.

That stuff repeats for a few seconds and then:

2006-02-19 18:00:23.455 NVP: Waiting for prebuffer.. 0 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:23.589 NVP: Waiting for prebuffer.. 1 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:23.719 
RingBuf(myth://10.10.20.169:6543/1006_20060219173013.mpg) Error: Waited 
16 seconds for da
ta, aborting.
2006-02-19 18:00:23.720 Ignoring livetv eof in decoder loop
2006-02-19 18:00:23.724 NVP: Waiting for prebuffer.. 2 
AAAAAAAAAAAAAALAAAAAAAAuaAAAAAA
2006-02-19 18:00:23.775 Ignoring livetv eof in decoder loop
2006-02-19 18:00:23.832 Ignoring livetv eof in decoder loop
2006-02-19 18:00:23.859 NVP: Waiting for prebuffer.. 3 
uALAAAAAAAAAAAuAAAAAAAAUAAAAAAA
2006-02-19 18:00:23.887 Ignoring livetv eof in decoder loop
2006-02-19 18:00:23.939 AFD: positionMap[ 3570 ] == 819724390.
2006-02-19 18:00:23.943 Ignoring livetv eof in decoder loop
2006-02-19 18:00:23.994 NVP: Waiting for prebuffer.. 4 
UAuAAAAAAAAAAAUAAAAAAAAUAAUAAAL
2006-02-19 18:00:23.998 Ignoring livetv eof in decoder loop
2006-02-19 18:00:24.053 Ignoring livetv eof in decoder loop
2006-02-19 18:00:24.109 Ignoring livetv eof in decoder loop
2006-02-19 18:00:24.129 NVP: Waiting for prebuffer.. 5 
UUULUAAAAAAAAAUAAAAAAAAUAAUAAAu
2006-02-19 18:00:24.164 Ignoring livetv eof in decoder loop
2006-02-19 18:00:24.220 Ignoring livetv eof in decoder loop
2006-02-19 18:00:24.264 NVP: Waiting for prebuffer.. 6 
UUULUuAUAAAAAAUAAAAAAAAUAAUAAAu
2006-02-19 18:00:24.276 Ignoring livetv eof in decoder loop
2006-02-19 18:00:24.333 Ignoring livetv eof in decoder loop
2006-02-19 18:00:24.388 Ignoring livetv eof in decoder loop
2006-02-19 18:00:24.399 NVP: Waiting for prebuffer.. 7 
UUUuUUAULuUAAAUAAAAAAAAUAAUAAAU
2006-02-19 18:00:24.444 Ignoring livetv eof in decoder loop
2006-02-19 18:00:24.500 Ignoring livetv eof in decoder loop
2006-02-19 18:00:24.534 NVP: Waiting for prebuffer.. 8 
UUUUUUAUuUUAAuUAAAAAAAAUAAUAALU
2006-02-19 18:00:24.555 Ignoring livetv eof in decoder loop

and the previous repeats until I exited:

2006-02-19 18:16:10.626 Ignoring livetv eof in decoder loop
2006-02-19 18:16:10.678 Ignoring livetv eof in decoder loop
2006-02-19 18:16:10.711 TV: Attempting to change from WatchingLiveTV to None
2006-02-19 18:16:10.711 TV: StopStuff() -- begin
2006-02-19 18:16:10.711 TV: StopStuff(): stopping ring buffer[s]
2006-02-19 18:16:10.728 NVP: Waiting for prebuffer.. 1 
UUUUUUAUUUUALUUAUAAAAAAUAAUAAuU
2006-02-19 18:16:10.730 Ignoring livetv eof in decoder loop
2006-02-19 18:16:10.782 Ignoring livetv eof in decoder loop
2006-02-19 18:16:10.834 Ignoring livetv eof in decoder loop
2006-02-19 18:16:10.862 NVP: Waiting for prebuffer.. 2 
UUUUUUAUUUUALUUAUAAAAAAUAAUAAuU
2006-02-19 18:16:10.886 Ignoring livetv eof in decoder loop
2006-02-19 18:16:10.938 Ignoring livetv eof in decoder loop
2006-02-19 18:16:10.990 Ignoring livetv eof in decoder loop
2006-02-19 18:16:10.997 NVP: Waiting for prebuffer.. 3 
UUUUUUAUUUUALUUAUAAAAAAUAAUAAuU
2006-02-19 18:16:11.042 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.094 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.132 NVP: Waiting for prebuffer.. 4 
UUUUUUAUUUUALUUAUAAAAAAUAAUAAuU
2006-02-19 18:16:11.145 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.197 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.249 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.267 NVP: Waiting for prebuffer.. 5 
UUUUUUAUUUUALUUAUAAAAAAUAAUAAuU
2006-02-19 18:16:11.301 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.353 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.402 NVP: Waiting for prebuffer.. 6 
UUUUUUAUUUUALUUAUAAAAAAUAAUAAuU
2006-02-19 18:16:11.405 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.457 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.509 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.537 NVP: Waiting for prebuffer.. 7 
UUUUUUAUUUUALUUAUAAAAAAUAAUAAuU
2006-02-19 18:16:11.561 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.613 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.665 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.672 NVP: Waiting for prebuffer.. 8 
UUUUUUAUUUUALUUAUAAAAAAUAAUAAuU
2006-02-19 18:16:11.713 
RingBuf(myth://10.10.20.169:6543/1006_20060219173013.mpg): Waited too 
long for ringbuffer
 pause..
2006-02-19 18:16:11.717 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.769 Ignoring livetv eof in decoder loop
2006-02-19 18:16:11.800 TV: StopStuff(): stopping player[s] (1/2)
2006-02-19 18:16:11.801 TV: StopStuff(): stopping recorder[s]
2006-02-19 18:16:11.807 NVP: Waiting for prebuffer.. 9 
UUUUUUAUUUUALUUAUAAAAAAUAAUAAuU
2006-02-19 18:16:11.821 NVP: Exited decoder loop.
2006-02-19 18:16:11.834 LiveTVChain(live-htpc-2006-02-19T14:26:02): 
ReloadAll(): Added new recording
2006-02-19 18:16:11.834 Resyncing position map. posmapStarted = 0 
livetv(1) watchingRec(0)
2006-02-19 18:16:11.891 Position map filled from DB to: 3573
2006-02-19 18:16:11.891 SyncPositionMap watchingrecording, from DB: 3573 
entries
2006-02-19 18:16:11.892 SyncPositionMap watchingrecording no entries 
from encoder, try DB
2006-02-19 18:16:11.942 NVP: Prebuffer wait timed out 10 times.
2006-02-19 18:16:11.943 VideoOutputXv: dtor
2006-02-19 18:16:11.943 VideoBuffers::DiscardFrames(1): 
UUUUUUAUUUUALUUAUAAAAAAUAAUAAuU
2006-02-19 18:16:11.943 VideoBuffers::DiscardFrames(): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2006-02-19 18:16:11.943 VideoBuffers::DiscardFrames(1): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2006-02-19 18:16:11.944 VideoBuffers::DiscardFrames(1): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2006-02-19 18:16:11.944 VideoBuffers::DiscardFrames(): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2006-02-19 18:16:11.944 VideoBuffers::DiscardFrames(1): 
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2006-02-19 18:16:11.950 VideoOutputXv: Closing XVideo port 270
2006-02-19 18:16:11.957 TV: StopStuff(): stopping player[s] (2/2)
2006-02-19 18:16:11.961 Position map filled from DB to: 3573
2006-02-19 18:16:11.962 SyncPositionMap watchingrecording total: 3573 
entries
2006-02-19 18:16:11.963 SyncPositionMap, new totframes: 53595, new 
length: 1788, posMap size: 3573
2006-02-19 18:16:11.995 TV: StopStuff() -- end
2006-02-19 18:16:11.996 TV: Changing from WatchingLiveTV to None

Tom

More information about the mythtv-dev mailing list