[mythtv-users] frontends starved to death

Wed Aug 31 17:07:49 UTC 2011

On Tue, Aug 30, 2011 at 9:54 PM, Tom Bongiorno <two.bits.11 at gmail.com>wrote:

> Hello All,
>
> I am not sure what is going on with my setup, but I am having problems
> where my frontends get starved for frames.  This almost always happens when
> watching live TV, but I do see small recoverable starvations when watching
> recorded content.  My setup is a fresh install on Mythbuntu 11.04 with Ron
> Fraziers Ceton patch.  I have a gigabit network with a modern triple core
> AMD backend and five Atom/ION frontends.  Below is an example output of my
> logs.  For the record, I have no idea what my kids were watching, "My Wife
> and Kids "Of Breasts and Basketball"", but channel 2033 is Nickelodeon so
> keep it clean guys!  ;)
>
> -Tom
>
>
> Backend version:
> --------------------------
> MythTV Version   : v0.24-243-g9ba3ece
> MythTV Branch    : fixes/0.24
> Network Protocol : 63
> Library API      : 0.24.20101129-1
> QT Version       : 4.7.2
> Options compiled in:
> linux debug using_alsa using_oss using_pulse using_pulseoutput
> using_backend using_bindings_perl using_bindings_python using_dvb
> using_firewire using_frontend using_hdhomerun using_ceton using_hdpvr
> using_iptv using_ivtv using_joystick_menu using_lirc using_mheg
> using_opengl_video using_opengl_vsync using_qtdbus using_qtwebkit using_v4l2
> using_x11 using_xrandr using_xv using_bindings_perl using_bindings_python
> using_mythtranscode using_opengl using_vdpau using_ffmpeg_threads using_live
> using_mheg
>
> Frontend version:
> --------------------------
> MythTV Version   : v0.24.1-69-g515171d
> MythTV Branch    : fixes/0.24
> Network Protocol : 63
> Library API      : 0.24.20110505-1
> QT Version       : 4.7.2
> Options compiled in:
> linux debug using_alsa using_oss using_pulse using_pulseoutput
> using_backend using_bindings_perl using_bindings_python using_dvb
> using_firewire using_frontend using_hdhomerun using_hdpvr using_iptv
> using_ivtv using_joystick_menu using_lirc using_mheg using_opengl_video
> using_opengl_vsync using_qtdbus using_qtwebkit using_v4l using_x11
> using_xrandr using_xv using_bindings_perl using_bindings_python
> using_mythtranscode using_opengl using_vdpau using_ffmpeg_threads using_live
> using_mheg
>
>
> Backend log:
> --------------------------
> 2011-08-30 20:31:29.486 TVRec(8): HW Tuner: 8->8
> 2011-08-30 20:31:29.543 LoadFromScheduler(): Error, called from backend.
> 2011-08-30 20:31:29.556 AutoExpire: CalcParams(): Max required Free Space:
> 3.0 GB w/freq: 7 min
> 2011-08-30 20:31:29.729 Finished recording My Wife and Kids "Of Breasts and
> Basketball": channel 2033
> 2011-08-30 20:31:29.931 TVRec(8): RingBufferChanged()
> 2011-08-30 20:31:29.959 MainServer::ANN Monitor
> 2011-08-30 20:31:30.055 adding: mythtvbe1 as a client (events: 0)
> 2011-08-30 20:31:30.063 MainServer::ANN Playback
> 2011-08-30 20:31:30.098 MainServer::ANN Monitor
> 2011-08-30 20:31:30.147 adding: mythtvfe4 as a client (events: 0)
> 2011-08-30 20:31:30.206 adding: mythtvbe1 as a client (events: 1)
> 2011-08-30 20:31:30.257 MainServer::HandleAnnounce FileTransfer
>
> Frontend log:
> --------------------------
> 2011-08-30 20:31:31.659 AFD: Resetting byte context eof (livetv 1 was eof
> 0)
> 2011-08-30 20:31:31.659 AFD: Resetting byte context eof (livetv 1 was eof
> 0)
> 2011-08-30 20:31:31.666 msg: On known multiplex...
> 2011-08-30 20:31:31.763 Player(0): Waited 100ms for video buffers
> AAAAAAAAAAAAAAAAA
> ...
> 2011-08-30 20:31:32.427 Player(0): Waited 100ms for video buffers
> AAAAAAAAAAAAAAAAA
> 2011-08-30 20:31:32.465 VDPAU Painter: Clearing VDPAU painter cache.
> 2011-08-30 20:31:32.468 Player(0): Timed out waiting for frames, and
>                        there are not enough free frames. Discarding
> buffered frames.
> 2011-08-30 20:31:32.491 MythPainter: 11 images not yet de-allocated.
> 2011-08-30 20:31:32.574 Clearing OpenGL painter cache.
> 2011-08-30 20:31:32.632 VDPAU: Created 2 output surfaces.
> 2011-08-30 20:31:32.633 VDPAU: Created VDPAU render device 1280x720
> 2011-08-30 20:31:32.752 Player(0): Forcing decode extra audio option on
> (Video method requires it).
> 2011-08-30 20:31:32.752 AFD: Opened codec 0x95e4e10, id(MPEG2VIDEO)
> type(Video)
> 2011-08-30 20:31:32.752 AFD: codec AC3 has 0 channels
> 2011-08-30 20:31:32.754 AFD: Opened codec 0x9700560, id(AC3) type(Audio)
> 2011-08-30 20:31:32.755 [mpegvideo_vdpau @ 0x5caeae0]mpeg_decode_postinit()
> failure
> 2011-08-30 20:31:32.755 AFD Error: Unknown decoding error
> 2011-08-30 20:31:32.755 [mpegvideo_vdpau @ 0x5caeae0]mpeg_decode_postinit()
> failure
> 2011-08-30 20:31:32.755 AFD Error: Unknown decoding error
> 2011-08-30 20:31:32.756 [mpegvideo_vdpau @ 0x5caeae0]mpeg_decode_postinit()
> failure
> 2011-08-30 20:31:32.756 AFD Error: Unknown decoding error
> 2011-08-30 20:31:32.784 Player(0): Waited 100ms for video buffers
> AAAAAAAAAAAAAAAAA
> ...
> 2011-08-30 20:31:32.808 Player(0): Waited 100ms for video buffers
> AAAAAAAAAAAAAAAAA
> 2011-08-30 20:31:32.812 VDPAU Painter: Clearing VDPAU painter cache.
> 2011-08-30 20:31:32.813 Player(0): Timed out waiting for frames, and
>                        there are not enough free frames. Discarding
> buffered frames.
> 2011-08-30 20:31:32.839 Clearing OpenGL painter cache.
> 2011-08-30 20:31:32.917 VDPAU: Created 2 output surfaces.
> 2011-08-30 20:31:32.918 VDPAU: Created VDPAU render device 1280x720
> 2011-08-30 20:31:33.026 Player(0): Forcing decode extra audio option on
> (Video method requires it).
> 2011-08-30 20:31:33.040 Player(0): Waited 100ms for video buffers
> AAAAAAAAAAALAAAAA
> ...
> 2011-08-30 20:31:33.058 Player(0): Waited 100ms for video buffers
> AAAAAAAAAAALAAAAA
> 2011-08-30 20:31:33.096 VDPAU: Added 2 output surfaces (total 4, max 4)
> 2011-08-30 20:31:33.300 Player(0): Waited 100ms for video buffers
> AAAAfAAALAAfAAAAA
> ...
> 2011-08-30 20:31:34.125 Player(0): Waited 100ms for video buffers
> AfAAAAAAfAAALAAAA
>

I forgot the following line at the end of the backend log:

2011-08-30 20:31:30.356 adding: mythtvfe4 as a remote file transfer

I am going to do some more testing tonight, but I have a gut feeling it is
related to the events below.  There may be something bad happening when
transitioning from one temporary live TV recording buffer to the next.  This
may explain why I only see it on live TV.

-Tom

Backend log:
--------------------------
2011-08-30 20:31:29.486 TVRec(8): HW Tuner: 8->8
2011-08-30 20:31:29.556 AutoExpire: CalcParams(): Max required Free Space:
3.0 GB w/freq: 7 min
2011-08-30 20:31:29.729 Finished recording My Wife and Kids "Of Breasts and
Basketball": channel 2033
2011-08-30 20:31:29.931 TVRec(8): RingBufferChanged()
2011-08-30 20:31:30.257 MainServer::HandleAnnounce FileTransfer
2011-08-30 20:31:30.356 adding: mythtvfe4 as a remote file transfer

Frontend log:
--------------------------
2011-08-30 20:31:31.659 AFD: Resetting byte context eof (livetv 1 was eof 0)
2011-08-30 20:31:32.468 Player(0): Timed out waiting for frames, and
                       there are not enough free frames. Discarding buffered
frames.
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://www.mythtv.org/pipermail/mythtv-users/attachments/20110831/cc07d2fb/attachment.html 