[mythtv-commits] Ticket #9955: Waited 100ms for video buffers -- FE crash

MythTV noreply at mythtv.org
Fri Jul 29 21:45:48 UTC 2011


#9955: Waited 100ms for video buffers -- FE crash
-------------------------------------+-------------------------------------
 Reporter:  Roger <rtrevor@…>        |           Type:  Bug Report -
   Status:  new                      |  Hang/Deadlock
Milestone:  unknown                  |       Priority:  minor
  Version:  0.24-fixes               |      Component:  MythTV - General
 Keywords:  video buffer hang        |       Severity:  medium
                                     |  Ticket locked:  0
-------------------------------------+-------------------------------------
 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

-- 
Ticket URL: <http://code.mythtv.org/trac/ticket/9955>
MythTV <http://code.mythtv.org/trac>
MythTV Media Center


More information about the mythtv-commits mailing list