[mythtv-users] DVD playback works for a bit and then stops (at the same point each time)

Rodd Clarkson rodd at clarkson.id.au
Sun Aug 22 07:47:13 UTC 2010 

I've got a bunch of dvd's ripped as ISO using local storage on my mythbox
(Fedora 12/RpmFusion)

We get a lot of the following problem:

You play a DVD and it works fine for a while and then just stops playing and
goes back to the menu.  If you bookmark just before it quits, then you'll
quickly discover that it fails at the same point ever time.

Anyone got any ideas of what's going wrong and/or how to fix it.

I've run mythfrontend with '-v playback' and I've attached the error log,
but I think this is the key to the problem:

010-08-22 17:36:21.272 AFD: HandleGopStart: Key frame distance changed from
3 to 9.
2010-08-22 17:36:21.419 AFD: HandleGopStart: Key frame distance changed from
9 to 3.
2010-08-22 17:36:21.788 AFD: HandleGopStart: Key frame distance changed from
3 to 10.
libdvdnav: demux error! 00 00 00 (should be 0x000001)
2010-08-22 17:36:21.790 Error reading block from DVD: Expected NAV packet
but none found.
2010-08-22 17:36:21.790 [ac3 @ 0x365a84cc40]incomplete frame
libdvdnav: demux error! 00 00 00 (should be 0x000001)
2010-08-22 17:36:21.791 Error reading block from DVD: Expected NAV packet
but none found.
libdvdnav: demux error! 00 00 00 (should be 0x000001)
2010-08-22 17:36:21.791 Error reading block from DVD: Expected NAV packet
but none found.
2010-08-22 17:36:21.792 NVP(0): Exited decoder loop.
2010-08-22 17:36:21.826 VideoOutputXv: dtor
2010-08-22 17:36:21.826 VideoOutputXv: DiscardFrames(1)
2010-08-22 17:36:21.826 VideoBuffers::DiscardFrames(1):
UUUUUUUUUUUUUAUUUUUUUUUuUULUUUU
2010-08-22 17:36:21.826 VideoBuffers::DiscardFrames():
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-08-22 17:36:21.826 VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-08-22 17:36:21.826 VideoOutputXv: DiscardFrames() 3:
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-08-22 17:36:21.826 VideoOutputXv: DiscardFrames(1)
2010-08-22 17:36:21.826 VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-08-22 17:36:21.826 VideoBuffers::DiscardFrames():
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-08-22 17:36:21.826 VideoBuffers::DiscardFrames(1):
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-08-22 17:36:21.826 VideoOutputXv: DiscardFrames() 3:
AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-08-22 17:36:21.829 VideoOutputXv: Closing XVideo port 93
2010-08-22 17:36:22.046 TV: HandleStateChange(0) -- begin
2010-08-22 17:36:22.046 TV: Attempting to change from WatchingDVD to None
2010-08-22 17:36:22.066 TV: StopStuff() for player ctx 0 -- begin
2010-08-22 17:36:22.066 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2010-08-22 17:36:22.316 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2010-08-22 17:36:22.317 TV:  StopStuff() -- get dvd player out of still
frame or wait status
2010-08-22 17:36:22.317 TV: StopStuff(): stopping ring buffer
2010-08-22 17:36:22.317 TV: StopStuff(): stopping player
2010-08-22 17:36:22.317 TV: StopStuff() -- end
2010-08-22 17:36:22.317 TV: Changing from WatchingDVD to None
2010-08-22 17:36:22.317 TV: HandleStateChange(0) -- end
2010-08-22 17:36:22.337 ScreenSaverX11Private: DPMS Reactivated 1
2010-08-22 17:36:22.337 ScreenSaverX11Private: StopTimer
2010-08-22 17:36:22.337 TV: HandleStateChange(0) -- begin
2010-08-22 17:36:22.337 TV: Attempting to change from None to None
2010-08-22 17:36:22.337 TV: HandleStateChange(0) -- end
2010-08-22 17:36:22.337 TV: StartTV -- process events end
2010-08-22 17:36:22.337 TV: StartTV -- process events 2 begin
2010-08-22 17:36:22.337 TV: StartTV -- process events 2 end
2010-08-22 17:36:22.469 TV::~TV() -- begin
2010-08-22 17:36:22.613 TV::~TV() -- lock
2010-08-22 17:36:22.748 TV::~TV() -- end
2010-08-22 17:36:22.748 TV: StartTV -- end
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://mythtv.org/pipermail/mythtv-users/attachments/20100822/ed7caa9a/attachment-0001.htm>
-------------- next part --------------
2010-08-22 17:36:11.667 AFD: DVD Audio Track Map Stream id #1, MPEG stream 129
2010-08-22 17:36:11.667 AFD: DVD Subtitle Track Map Stream id #32 
2010-08-22 17:36:11.667 AFD: DVD Subtitle Track Map Stream id #33 
2010-08-22 17:36:11.689 Dec: Trying to reselect track
2010-08-22 17:36:11.689 Dec: Selected track #1 in the Undetermined language(7695972)
2010-08-22 17:36:11.689 Dec: Trying to reselect track
2010-08-22 17:36:11.689 Dec: Selected track #1 in the Unknown language(0)
2010-08-22 17:36:11.974 AFD: HandleGopStart: Key frame distance changed from 3 to 9.
2010-08-22 17:36:12.122 AFD: HandleGopStart: Key frame distance changed from 9 to 3.
2010-08-22 17:36:12.407 AFD: HandleGopStart: Key frame distance changed from 3 to 8.
2010-08-22 17:36:12.604 AFD: HandleGopStart: Key frame distance changed from 8 to 4.
'video_output' mean = '40934.47', std. dev. = '15187.02', fps = '24.43'
2010-08-22 17:36:12.889 AFD: HandleGopStart: Key frame distance changed from 4 to 8.
2010-08-22 17:36:13.086 AFD: HandleGopStart: Key frame distance changed from 8 to 4.
2010-08-22 17:36:13.404 AFD: HandleGopStart: Key frame distance changed from 4 to 9.
2010-08-22 17:36:13.569 AFD: HandleGopStart: Key frame distance changed from 9 to 3.
2010-08-22 17:36:13.854 AFD: HandleGopStart: Key frame distance changed from 3 to 8.
2010-08-22 17:36:14.051 AFD: HandleGopStart: Key frame distance changed from 8 to 4.
2010-08-22 17:36:14.336 AFD: HandleGopStart: Key frame distance changed from 4 to 8.
2010-08-22 17:36:14.533 AFD: HandleGopStart: Key frame distance changed from 8 to 4.
2010-08-22 17:36:14.852 AFD: HandleGopStart: Key frame distance changed from 4 to 9.
2010-08-22 17:36:15.016 AFD: HandleGopStart: Key frame distance changed from 9 to 3.
2010-08-22 17:36:15.285 AFD: HandleGopStart: Key frame distance changed from 3 to 8.
2010-08-22 17:36:15.482 AFD: HandleGopStart: Key frame distance changed from 8 to 4.
2010-08-22 17:36:15.766 AFD: HandleGopStart: Key frame distance changed from 4 to 8.
2010-08-22 17:36:15.964 AFD: HandleGopStart: Key frame distance changed from 8 to 4.
2010-08-22 17:36:16.282 AFD: HandleGopStart: Key frame distance changed from 4 to 9.
2010-08-22 17:36:16.446 AFD: HandleGopStart: Key frame distance changed from 9 to 3.
2010-08-22 17:36:16.731 AFD: HandleGopStart: Key frame distance changed from 3 to 8.
'video_output' mean = '40084.15', std. dev. = '8035.95', fps = '24.95'
2010-08-22 17:36:16.929 AFD: HandleGopStart: Key frame distance changed from 8 to 4.
2010-08-22 17:36:18.393 NVP(0): Video is 3.86165 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.393 NVP(0): Video is 7.10873 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 9.29403 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 10.683 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 11.4747 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 11.8186 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 11.8264 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 11.5823 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 11.1492 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 10.5744 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 9.8933 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 9.13247 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 8.3181 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 7.45732 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 6.56173 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 5.64003 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): Video is 4.69875 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:18.394 NVP(0): prebuffering pause
2010-08-22 17:36:18.394 NVP(0): Waiting for prebuffer..  0 AALAAAAAuAALAAAAAAAAAAAAAAAAAAA
2010-08-22 17:36:18.558 NVP(0): Waiting for prebuffer..  1 AALAAAAAuAALAAAAAAAAAAAAAAAAAAA
2010-08-22 17:36:18.725 NVP(0): Waiting for prebuffer..  2 AALAAAAAuAALAAAAAAAAAAAAAAAAAAA
2010-08-22 17:36:18.891 NVP(0): Waiting for prebuffer..  3 AALAAAAAuAALAAAAAAAAAAAAAAAAAAA
2010-08-22 17:36:19.057 NVP(0): Waiting for prebuffer..  4 AALAAAAAuAALAAAAAAAAAAAAAAAAAAA
2010-08-22 17:36:19.224 NVP(0): Waiting for prebuffer..  5 AALAAAAAuAALAAAAAAAAAAAAAAAAAAA
2010-08-22 17:36:19.390 NVP(0): Waiting for prebuffer..  6 AALAAAAAuAALAAAAAAAAAAAAAAAAAAA
2010-08-22 17:36:19.437 AFD: HandleGopStart: Key frame distance changed from 4 to 8.
2010-08-22 17:36:19.556 NVP(0): Waiting for prebuffer..  7 AAUAUAULUAAuAALAAAAAAAAAAAAAAAA
2010-08-22 17:36:19.566 AFD: HandleGopStart: Key frame distance changed from 8 to 4.
2010-08-22 17:36:19.723 NVP(0): Waiting for prebuffer..  8 AAUAUAUUULAUAAuAALAAAAAAAAAAAAA
2010-08-22 17:36:19.737 AFD: HandleGopStart: Key frame distance changed from 4 to 3.
2010-08-22 17:36:19.839 NVP(0): Video is 3.7428 frames behind audio (too slow), dropping frame to catch up.
2010-08-22 17:36:19.863 AFD: HandleGopStart: Key frame distance changed from 3 to 6.
2010-08-22 17:36:20.009 AFD: HandleGopStart: Key frame distance changed from 6 to 3.
2010-08-22 17:36:20.047 AFD: HandleGopStart: Key frame distance changed from 3 to 9.
2010-08-22 17:36:20.072 AFD: HandleGopStart: Key frame distance changed from 9 to 3.
2010-08-22 17:36:20.340 AFD: HandleGopStart: Key frame distance changed from 3 to 9.
2010-08-22 17:36:20.505 AFD: HandleGopStart: Key frame distance changed from 9 to 3.
2010-08-22 17:36:20.790 AFD: HandleGopStart: Key frame distance changed from 3 to 8.
2010-08-22 17:36:20.937 AFD: HandleGopStart: Key frame distance changed from 8 to 3.
2010-08-22 17:36:21.272 AFD: HandleGopStart: Key frame distance changed from 3 to 9.
2010-08-22 17:36:21.419 AFD: HandleGopStart: Key frame distance changed from 9 to 3.
2010-08-22 17:36:21.788 AFD: HandleGopStart: Key frame distance changed from 3 to 10.
libdvdnav: demux error! 00 00 00 (should be 0x000001) 
2010-08-22 17:36:21.790 Error reading block from DVD: Expected NAV packet but none found.
2010-08-22 17:36:21.790 [ac3 @ 0x365a84cc40]incomplete frame
libdvdnav: demux error! 00 00 00 (should be 0x000001) 
2010-08-22 17:36:21.791 Error reading block from DVD: Expected NAV packet but none found.
libdvdnav: demux error! 00 00 00 (should be 0x000001) 
2010-08-22 17:36:21.791 Error reading block from DVD: Expected NAV packet but none found.
2010-08-22 17:36:21.792 NVP(0): Exited decoder loop.
2010-08-22 17:36:21.826 VideoOutputXv: dtor
2010-08-22 17:36:21.826 VideoOutputXv: DiscardFrames(1)
2010-08-22 17:36:21.826 VideoBuffers::DiscardFrames(1): UUUUUUUUUUUUUAUUUUUUUUUuUULUUUU
2010-08-22 17:36:21.826 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-08-22 17:36:21.826 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-08-22 17:36:21.826 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-08-22 17:36:21.826 VideoOutputXv: DiscardFrames(1)
2010-08-22 17:36:21.826 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA
2010-08-22 17:36:21.826 VideoBuffers::DiscardFrames(): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-08-22 17:36:21.826 VideoBuffers::DiscardFrames(1): AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
2010-08-22 17:36:21.826 VideoOutputXv: DiscardFrames() 3: AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
2010-08-22 17:36:21.829 VideoOutputXv: Closing XVideo port 93
2010-08-22 17:36:22.046 TV: HandleStateChange(0) -- begin
2010-08-22 17:36:22.046 TV: Attempting to change from WatchingDVD to None
2010-08-22 17:36:22.066 TV: StopStuff() for player ctx 0 -- begin
2010-08-22 17:36:22.066 TV: SetActive(0,w/o OSD) 0 -> 0 -- begin
2010-08-22 17:36:22.316 TV: SetActive(0,w/o OSD) 0 -> 0 -- end
2010-08-22 17:36:22.317 TV:  StopStuff() -- get dvd player out of still frame or wait status
2010-08-22 17:36:22.317 TV: StopStuff(): stopping ring buffer
2010-08-22 17:36:22.317 TV: StopStuff(): stopping player
2010-08-22 17:36:22.317 TV: StopStuff() -- end
2010-08-22 17:36:22.317 TV: Changing from WatchingDVD to None
2010-08-22 17:36:22.317 TV: HandleStateChange(0) -- end
2010-08-22 17:36:22.337 ScreenSaverX11Private: DPMS Reactivated 1
2010-08-22 17:36:22.337 ScreenSaverX11Private: StopTimer
2010-08-22 17:36:22.337 TV: HandleStateChange(0) -- begin
2010-08-22 17:36:22.337 TV: Attempting to change from None to None
2010-08-22 17:36:22.337 TV: HandleStateChange(0) -- end
2010-08-22 17:36:22.337 TV: StartTV -- process events end
2010-08-22 17:36:22.337 TV: StartTV -- process events 2 begin
2010-08-22 17:36:22.337 TV: StartTV -- process events 2 end
2010-08-22 17:36:22.469 TV::~TV() -- begin
2010-08-22 17:36:22.613 TV::~TV() -- lock
2010-08-22 17:36:22.748 TV::~TV() -- end
2010-08-22 17:36:22.748 TV: StartTV -- end

More information about the mythtv-users mailing list