Opened 9 years ago

Closed 9 years ago

#9955 closed Bug Report - Hang/Deadlock (Invalid)

Waited 100ms for video buffers -- FE crash

Reported by: Roger <rtrevor@…> Owned by:
Priority: minor Milestone: unknown
Component: MythTV - General Version: 0.24-fixes
Severity: medium Keywords: video buffer hang
Cc: Ticket locked: yes

Description

Hi

Having similar problems as reported in other tickets e.g. http://code.mythtv.org/trac/ticket/9769.

Basically when playing videos in MythVideo? occasionally playback will freeze and the frontend just completely hangs and does not recover (have waited several minutes for it to recover, nothing happens - the mythfrontend process needs to be killed and restarted).

The difference from other tickets and posts I've seen is that this is for local video files on an external HDD in MythVideo? (not live or recorded TV).

This issue started occurring for me a week or so ago, when I changed all my MythVideo? files to use Storage Groups as opposed to using direct paths configured on the FE. Never had this problem before using Storage Groups for the playback of MythVideo? files. Haven't had any issues with TV, live or recorded, which were always using Storage Groups. The difference may be that I've only seen this issue with HD 720p content in MythVideo?; I only get standard definition TV.

FE and BE are on the same server, running latest version of 0.24-fixes from Mythbuntu PPA, 11.04. Pentium 4 @ 3.2GHz w/ 2GB RAM.

Error in mythfrontend.log when playback freezes below (slightly truncated to avoid large number of mostly identical lines).

2011-07-29 21:07:46.811 VDPAU: Added 2 output surfaces (total 4, max 4)
2011-07-29 21:07:48.805 Player(0): Waited 100ms for video buffers AAAAAAAAAAAAAuLLL
<lots of similar messages>
2011-07-29 21:34:40.318 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:40.420 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 1.0 seconds for data 
2011-07-29 21:34:40.421 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:41.342 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:41.420 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 2.0 seconds for data 
2011-07-29 21:34:41.446 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:43.391 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:43.421 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 4.0 seconds for data 
2011-07-29 21:34:43.495 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:47.349 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:47.427 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 8.0 seconds for data 
2011-07-29 21:34:47.470 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:48.365 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:48.428 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 9.0 seconds for data 
2011-07-29 21:34:48.484 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:48.637 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:48.678 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 9.2 seconds for data 
2011-07-29 21:34:48.742 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:48.886 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:48.929 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 9.5 seconds for data 
2011-07-29 21:34:48.998 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:49.151 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:49.179 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 9.8 seconds for data 
2011-07-29 21:34:49.261 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:49.407 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:49.430 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 10.0 seconds for data 
2011-07-29 21:34:49.509 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:49.660 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:49.680 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 10.2 seconds for data 
2011-07-29 21:34:49.766 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:49.916 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:49.930 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 10.5 seconds for data 
2011-07-29 21:34:50.029 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:50.167 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:50.180 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 10.8 seconds for data 
2011-07-29 21:34:50.278 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:50.429 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:50.431 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 11.0 seconds for data 
2011-07-29 21:34:50.542 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:50.676 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:50.681 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 11.2 seconds for data 
2011-07-29 21:34:50.687 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:50.917 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:50.931 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 11.5 seconds for data 
2011-07-29 21:34:50.935 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:51.181 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:51.182 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 11.8 seconds for data 
2011-07-29 21:34:51.197 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:51.429 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:51.432 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 12.0 seconds for data 
2011-07-29 21:34:51.446 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:51.584 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:51.682 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 12.2 seconds for data 
2011-07-29 21:34:51.685 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:51.836 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:51.932 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 12.5 seconds for data 
2011-07-29 21:34:51.942 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:52.095 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:52.183 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 12.8 seconds for data 
2011-07-29 21:34:52.206 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:52.348 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:52.433 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 13.0 seconds for data 
2011-07-29 21:34:52.453 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:52.605 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:52.683 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 13.2 seconds for data 
2011-07-29 21:34:52.708 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:52.860 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:52.933 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 13.5 seconds for data 
2011-07-29 21:34:52.964 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:53.116 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:53.184 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 13.8 seconds for data 
2011-07-29 21:34:53.220 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:53.223 MythSocket(9a01140:73): readStringList: Error, timed out after 7000 ms.
2011-07-29 21:34:53.223 RemoteFile::Read(): No response from control socket.
2011-07-29 21:34:53.223 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv) Error: RingBuffer::safe_read(RemoteFile* ...): read failed
2011-07-29 21:34:53.230 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:53.372 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:53.434 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 14.0 seconds for data 
2011-07-29 21:34:53.476 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:53.628 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:53.684 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 14.2 seconds for data 
2011-07-29 21:34:53.733 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:53.885 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:53.934 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 14.5 seconds for data 
2011-07-29 21:34:53.989 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:54.141 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:54.185 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 14.8 seconds for data 
2011-07-29 21:34:54.245 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:54.397 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:54.435 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 15.0 seconds for data 
2011-07-29 21:34:54.501 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:54.653 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:54.685 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 15.2 seconds for data 
2011-07-29 21:34:54.757 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:54.902 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:54.935 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 15.5 seconds for data 
2011-07-29 21:34:55.021 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:55.166 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:55.186 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 15.8 seconds for data 
2011-07-29 21:34:55.270 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:55.421 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:55.436 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv): Waited 16.0 seconds for data 
2011-07-29 21:34:55.436 RingBuf(myth://Videos@127.0.0.1:6543/TV/<tvshow>/Season 2/<tvshow>.S02E01.720p.HDTV.x264.mkv) Error: Waited 16 seconds for data, aborting.
2011-07-29 21:34:55.534 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
<lots of similar messages>
2011-07-29 21:34:59.903 Player(0): Waited 100ms for video buffers AALLAAAAfAAfAAfAA
2011-07-29 21:34:59.939 Player(0), Error: Waited too long for decoder to fill video buffers. Exiting..
2011-07-29 21:35:00.034 TV: Attempting to change from WatchingVideo to None
2011-07-29 21:35:05.171 Player(0), Error: Failed to stop decoder loop.
2011-07-29 21:35:05.207 VDPAU Painter: Clearing VDPAU painter cache.
2011-07-29 21:35:05.216 MythPainter: 43 images not yet de-allocated.

Checked the mythbackend.log, nothing too exciting although it did seem fairly busy at the time (had started a commercial detection job and was rescheduling a recording just as the video buffer errors started on the frontend). Not sure if relevant.

Let me know if I can provide any other information which may help.

Thanks

Change History (3)

comment:1 Changed 9 years ago by Raymond Wagner

Old hardware such as P4s and AthlonXPs often had both USB1.1 and USB2.0 ports. Are you sure that external hard drive is actually connected to port with sufficient bandwidth for HD video?

Older hardware also typically ran AGP rather than PCIe. If that's an AGP system, then you are running a PCI graphics card. Is there anything else connected that may be using a significant amount of the PCI bus and not allowing sufficient communication to the graphics card?

VDPAU is implemented in a custom ASIC, and as such is inherently limited. It only supports a small subset of what the h264 codec is capable of, and the x264 encoder offers many options that can break playback with VDPAU. What options did you use when encoding these videos?

comment:2 Changed 9 years ago by rtrevor@…

Hi

It's a PCIe 2.0 graphics card (9500GT) and the external HDD is connected via USB2. I've used this system as a combined BE/FE for several years and never had any issues with HD content before, including full fat 1080p h264, 5GB for 1 hour.

Not sure what encoding options were used I'm afraid, they were downloaded. They are 720p, approx 1.5GB for 1 hour.

This only started happening when I switched from using direct paths on the FE config to using a Videos storage group configured on the BE, and set up MythWeb about a week ago which is why I suspect it may be something to do with MythTV storage groups...

Thanks

Roger

comment:3 in reply to:  2 Changed 9 years ago by Raymond Wagner

Resolution: Invalid
Status: newclosed
Ticket locked: set

Replying to rtrevor@…:

Not sure what encoding options were used I'm afraid, they were downloaded. They are 720p, approx 1.5GB for 1 hour.

Whoops!

Note: See TracTickets for help on using tickets.