[mythtv-users] DVD skip forward/rewind hangs mythfrontend - Trunk 0.22

Thu May 28 15:22:50 UTC 2009

2009/5/28 Mark Kendall <mark.kendall at gmail.com>:
> 2009/5/28 Rafael Moslin <rafmoslin at gmail.com>:
>> Eeek, I was too busy sulking to check that out, and your right, shows
>> in my myth users gmail folder and sent items but nothing on Gossamer,
>> maybe it was too big perhaps? I did attach the gdb back trace... I'll
>> best check, and thanks for pointing it out to me, faith restored! >:o)
>
> More than likely the back trace was too large and your post is waiting
> to get out of jail somewhere...
>
> But on the subject of dvd navigation (and general playback), for me at
> least it's a know issue with trunk. I've been spending some time
> trying to debug with little success to date. If you'd like to help
> out, try and get some useful back traces (obviously I've not seen the
> first one!) and either contact me directly off list or create a ticket
> on trac.
>
> regards
>
> Mark

Brian, yes indeed but as I've said I did fire that post off with a
moody sulk on, not really a proper excuse but sadly past experience of
open source mailing lists and forums have somewhat tainted me. Mostly
due to things getting embroiled in correct posting techniques and
perceived etiquette with a certain amount of flaming rather than being
helpful. I always try and help others myself when looking through the
mythtv list, and on boards, admittedly mostly when I see that there
has been no response. So I apologise for being somewhat sensitive
previously, I'm always willing to be proved wrong for all the right
reasons.

Mark, yep, found an overlooked email saying that my post it was in
jail waiting to be released by a mod, whoops, thanks for pointing it
out chap. The original email less the GDB backtrace attachment and
configure options below, I normally manage to debug issues myself but
this was has me this time. I am using trunk, updated frequently and as
you say there has been a few issues with navigating menus, and indeed
I quite often get problems with the background of a setup screen not
being displayed but the buttons do. I hate to raise tickets unless I
am sure that it's not me being an idiot or some driver issue but if on
seeing this you think there is merit in it then I'm happy to do so.

Looking for some pointers to help in working out why seeking either
forward or reverse during DVD playback with either a physical or iso
causes the front end to freeze (or hang, lock up, stop responding
etc!) with all DVD's causing me to have to kill mythfrontend.

Done some searching and it appears no one recently has had this
problem so it must be down to something on my system however the
problem only affects Mythtv, all other media programs work fine, ie.
xbmc, Kaffeine, Mplayer. There is nothing that means anything to me in
the logs below, or the backtrace that is attached (hope that's
allowed) so any little pointers from a helpful person would be most
appreciated.

This is all with trunk rev 20619 compiled in debug running on 32bit
fully updated PCLinuxOS.

A bit of -v playback log shows:

'video_output' mean = '33332.21', std. dev. = '65.37', fps = '30.00'
2009-05-25 10:20:05.852 AFD: HandleGopStart: Key frame distance
changed from 4 to 12.
2009-05-25 10:20:05.985 AFD: HandleGopStart: Key frame distance
changed from 12 to 3.
2009-05-25 10:20:06.253 AFD: HandleGopStart: Key frame distance
changed from 3 to 9.
2009-05-25 10:20:06.518 AFD: HandleGopStart: Key frame distance
changed from 9 to 6.
2009-05-25 10:20:06.718 TV: DoNVPSeek() -- begin
2009-05-25 10:20:06.718 TV: DoNVPSeek() -- ff
2009-05-25 10:20:06.718 TV: DoNVPSeek() -- end
2009-05-25 10:20:06.718 UpdateOSDSeekMessage(Skip Ahead, 2)
2009-05-25 10:20:06.785 VideoOutputXv: UpdatePauseFrame() DUUUUUUUDuUDAuUUL
2009-05-25 10:20:06.785 AFD: DoFastForward(1168 (269), do discard frames)
2009-05-25 10:20:06.785 Dec: DoFastForward(1168 (269), do discard frames)
2009-05-25 10:20:06.786 AFD: SeekReset(1169, 0, do flush, do discard)
2009-05-25 10:20:06.786 AFD: SeekReset() flushing
2009-05-25 10:20:06.786 VideoOutputXv: DiscardFrames(1)
2009-05-25 10:20:06.786 VideoBuffers::DiscardFrames(1): DUUUUUUUDUUDAUUUA
2009-05-25 10:20:06.786 VideoBuffers::DiscardFrames():
DAAAAAAADAADAAAAA -- done()
2009-05-25 10:20:06.786 VideoBuffers::DiscardFrames(1):
DAAAAAAADAADAAAAA -- done
2009-05-25 10:20:06.786 VideoOutputXv: DiscardFrames() 3:
DAAAAAAADAADAAAAA -- done()
2009-05-25 10:20:06.786 NVP(0): ClearAfterSeek(0)
2009-05-25 10:20:06.802 DVDNAV_HOP_CHANNEL happened.
2009-05-25 10:20:06.802 NVP(0): Waiting for prebuffer..  0 AAAAAAAAAAAAAAAAA
2009-05-25 10:20:06.802 DVDNAV_CELL_CHANGE: pg_length == 65700000,
pgc_length == 639516000, cell_start == 0, pg_start == 0, title == 2,
part == 1 titleParts 10
2009-05-25 10:20:06.802 DVDNAV_SPU_CLUT_CHANGE happened.
2009-05-25 10:20:06.802 DVDNAV_SPU_STREAM_CHANGE: physical_wide==-1,
physical_letterbox==-1, physical_pan_scan==-1, current_track==-1
2009-05-25 10:20:06.802 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0
2009-05-25 10:20:06.802 DVDNAV_HOP_CHANNEL happened.
2009-05-25 10:20:06.802 DVDNAV_CELL_CHANGE: pg_length == 65700000,
pgc_length == 639516000, cell_start == 0, pg_start == 0, title == 2,
part == 1 titleParts 10
2009-05-25 10:20:06.802 DVDNAV_SPU_CLUT_CHANGE happened.
2009-05-25 10:20:06.802 DVDNAV_SPU_STREAM_CHANGE: physical_wide==-1,
physical_letterbox==-1, physical_pan_scan==-1, current_track==-1
2009-05-25 10:20:06.802 DVDNAV_AUDIO_STREAM_CHANGE: Current Active Stream 0
2009-05-25 10:20:06.802 DVDNAV_HOP_CHANNEL happened.
2009-05-25 10:20:06.802 DVDNAV_CELL_CHANGE: pg_length == 65700000,
pgc_length == 639516000, cell_start == 0, pg_start == 0, title == 2,
part == 1 titleParts 10
<Repeats>

Plain log output:

2009-05-25 10:49:42.864 Opened DVD device at /films/Billy.iso
2009-05-25 10:49:42.867 There are 6 titles on the disk
2009-05-25 10:49:42.867 Title 0 has 0 parts.
2009-05-25 10:49:42.867 Title 1 has 3 parts.
2009-05-25 10:49:42.867 Title 2 has 10 parts.
2009-05-25 10:49:42.868 Title 3 has 7 parts.
2009-05-25 10:49:42.868 Title 4 has 9 parts.
2009-05-25 10:49:42.868 Title 5 has 2 parts.
2009-05-25 10:49:42.869 TV: StartPlayer(0, Watching DVD, main) -- begin

libdvdread: Attempting to retrieve all CSS keys
libdvdread: This can take a _long_ time, please be patient

libdvdread: Get key for /VIDEO_TS/VTS_01_0.VOB at 0x0000014d
libdvdread: Elapsed time 1
libdvdread: Get key for /VIDEO_TS/VTS_01_1.VOB at 0x000044af
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_0.VOB at 0x0000562f
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_02_1.VOB at 0x0000563a
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_0.VOB at 0x0029c042
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_03_1.VOB at 0x0029c04d
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_0.VOB at 0x002b02e9
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_04_1.VOB at 0x002b02f4
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_0.VOB at 0x002c69fe
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_05_1.VOB at 0x002c6a09
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_0.VOB at 0x002dd12a
libdvdread: Elapsed time 0
libdvdread: Get key for /VIDEO_TS/VTS_06_1.VOB at 0x002dd135
libdvdread: Elapsed time 0
libdvdread: Found 6 VTS's
libdvdread: Elapsed time 1
2009-05-25 10:49:44.191 AFD: Opened codec 0x9375c80,
id(MPEGVIDEO_VDPAU) type(Video)
2009-05-25 10:49:44.191 NVP(0): Disabling Audio, params(-1,-1,-1)
2009-05-25 10:49:45.511 OSD Theme Dimensions W: 1280 H: 720
2009-05-25 10:49:45.765 Container: settings already exists
2009-05-25 10:49:46.466 TV: StartPlayer(0, Watching DVD, main) -- end ok
2009-05-25 10:49:46.466 TV: Changing from None to Watching DVD
2009-05-25 10:49:46.468 OpenGLVideoSync()
2009-05-25 10:49:46.496 Video timing method: SGI OpenGL
2009-05-25 10:49:47.270 AFD: Warning, video codec 0x9375c80
id(MPEGVIDEO_VDPAU) type (Video) already open.
2009-05-25 10:49:47.537 AFD: codec AC3 has 0 channels
2009-05-25 10:49:47.537 AFD: Opened codec 0x9569b30, id(AC3) type(Audio)
2009-05-25 10:49:47.540 Opening audio device 'default'. ch 2(2) sr 48000
2009-05-25 10:49:47.540 Opening ALSA audio device 'default'.
2009-05-25 10:49:47.603 mixer unable to find control Master 1
2009-05-25 10:49:47.604 NVP(0): Enabling Audio
2009-05-25 10:49:47.607 Opening audio device 'default'. ch 2(2) sr 48000
2009-05-25 10:49:47.607 Opening ALSA audio device 'default'.
2009-05-25 10:49:47.625 mixer unable to find control Master 1
2009-05-25 10:49:47.625 AFD: Warning, audio codec 0x9569b30 id(AC3)
type (Audio) already open, leaving it alone.
2009-05-25 10:49:47.625 AFD: codec AC3 has 2 channels
2009-05-25 10:50:14.330 WriteAudio: buffer underrun
2009-05-25 10:50:14.888 AFD: Warning, video codec 0x9375c80
id(MPEGVIDEO_VDPAU) type (Video) already open.
2009-05-25 10:50:14.888 AFD: Opened codec 0xa016fd60, id(DVD_SUBTITLE)
type(Subtitle)
2009-05-25 10:50:14.888 NVP(0): Disabling Audio, params(-1,-1,-1)
2009-05-25 10:50:15.456 AFD: Warning, video codec 0x9375c80
id(MPEGVIDEO_VDPAU) type (Video) already open.
2009-05-25 10:50:15.456 AFD: codec AC3 has 0 channels
2009-05-25 10:50:15.456 AFD: Opened codec 0xb0c5a050, id(AC3) type(Audio)
2009-05-25 10:50:15.460 Opening audio device 'default'. ch 2(2) sr 48000
2009-05-25 10:50:15.460 Opening ALSA audio device 'default'.
2009-05-25 10:50:15.477 mixer unable to find control Master 1
2009-05-25 10:50:15.477 NVP(0): Enabling Audio
2009-05-25 10:50:15.479 Opening audio device 'default'. ch 2(2) sr 48000
2009-05-25 10:50:15.480 Opening ALSA audio device 'default'.
2009-05-25 10:50:15.496 mixer unable to find control Master 1
2009-05-25 10:50:15.497 AFD: Warning, audio codec 0xb0c5a050 id(AC3)
type (Audio) already open, leaving it alone.
2009-05-25 10:50:15.497 AFD: codec AC3 has 2 channels
2009-05-25 10:50:16.108 GetNextFreeFrame() served a busy frame C.
Dropping. AAUUUUUUUUUUUUUuL
2009-05-25 10:50:17.704 NVP(0): Disabling Audio, params(-1,-1,-1)
2009-05-25 10:50:18.784 AFD: Warning, video codec 0x9375c80
id(MPEGVIDEO_VDPAU) type (Video) already open.
2009-05-25 10:50:18.784 AFD: codec AC3 has 0 channels
2009-05-25 10:50:18.784 AFD: Opened codec 0xa01f9cc0, id(AC3) type(Audio)
2009-05-25 10:50:18.787 Opening audio device 'default'. ch 2(2) sr 48000
2009-05-25 10:50:18.787 Opening ALSA audio device 'default'.
2009-05-25 10:50:18.804 mixer unable to find control Master 1
2009-05-25 10:50:18.805 NVP(0): Enabling Audio
2009-05-25 10:50:18.807 Opening audio device 'default'. ch 2(2) sr 48000
2009-05-25 10:50:18.807 Opening ALSA audio device 'default'.
2009-05-25 10:50:18.824 mixer unable to find control Master 1
2009-05-25 10:50:18.845 GetNextFreeFrame() served a busy frame A.
Dropping. UUUUUUULUUUUUUAUU
2009-05-25 10:50:28.645 NVP(0): Prebuffer wait timed out 10 times.
2009-05-25 10:50:30.145 NVP(0): Prebuffer wait timed out 20 times.
<Repeats - with increment>