[mythtv-commits] Ticket #11064: LiveTV with HDHR Prime crashes mythfrontend

MythTV noreply at mythtv.org
Mon Sep 3 07:44:45 UTC 2012 

#11064: LiveTV with HDHR Prime crashes mythfrontend
--------------------------------------+-----------------------------
 Reporter:  Monkey Pet <monkeypet@…>  |          Owner:
     Type:  Bug Report - Crash        |         Status:  new
 Priority:  minor                     |      Milestone:  unknown
Component:  MythTV - General          |        Version:  Master Head
 Severity:  low                       |     Resolution:
 Keywords:                            |  Ticket locked:  0
--------------------------------------+-----------------------------
Changes (by kenni):

 * priority:  critical => minor
 * severity:  high => low
 * milestone:  0.26 => unknown


Old description:

> Watching LiveTV with HDHR Prime is consistently crashing mythfrontend.
> The dialog i get refers to max buffering exceeded.  I can't exit from it
> and sometimes it just crashes there.
>
> With 0.25 and 0.26 (Master Head), I tried a few more experiments:
> 1. LiveTV - Remote frontend, Tune to a TV Channel within a few mins, it
> aborts almost immediately with "max buffering exceeded".  Frontend will
> crash and needs to be restarted.
> 2. Recording - Now schedule the same channel, same show and start the
> recording.  No remote frontend are viewing the stream. Recording proceeds
> with no problems.
> 3. Recording/then streaming to remote frontend - This works also.  I tell
> myth to record the show, then watch the recording immediately after
> starting.  I am guessing I have to use this as the workaround until the
> LiveTV streaming is more robust.  It sucks that I can't channel surf
> anymore, but now I can watch almostLiveTV again.
>
> I am really doubting that this is a signal issue.  If it was a signal
> issue, #2 would behavior exactly like #1 and would be aborted also.  Also
> a majority of my recordings are finishing without any issues.  However,
> LiveTV would consistently fail and most of the time fail immediately
> within the first 5 mins.  Also #3 works, so it can't be a signal issue.
>
> I will attach the full logs next.
>
> (gdb) bt
> #0  0xb77a9424 in __kernel_vsyscall ()
> #1  0xb51f91ef in __GI_raise (sig=6) at
> ../nptl/sysdeps/unix/sysv/linux/raise.c:64
> #2  0xb51fc835 in __GI_abort () at abort.c:91
> #3  0xb52342fa in __libc_message (do_abort=2, fmt=0xb532c3bc "*** glibc
> detected *** %s: %s: 0x%s ***\n")
>     at ../sysdeps/unix/sysv/linux/libc_fatal.c:201
> #4  0xb523ee42 in malloc_printerr (action=<optimized out>, str=<optimized
> out>, ptr=0x95626f8) at malloc.c:5007
> #5  0xb5240fbf in _int_malloc (av=0xb536c440, bytes=40) at malloc.c:3470
> #6  0xb5242d3c in __GI___libc_malloc (bytes=40) at malloc.c:2924
> #7  0xb54d313b in qMalloc(unsigned int) () from /usr/lib/i386-linux-
> gnu/libQtCore.so.4
> #8  0xb552ebef in QString::realloc(int) () from /usr/lib/i386-linux-
> gnu/libQtCore.so.4
> #9  0xb552f23d in QString::append(QString const&) () from /usr/lib/i386
> -linux-gnu/libQtCore.so.4
> #10 0xb5534bfe in QString::section(QString const&, int, int,
> QFlags<QString::SectionFlag>) const ()
>    from /usr/lib/i386-linux-gnu/libQtCore.so.4
> #11 0xb6cda1d1 in section (aflags=..., aend=<optimized out>,
> astart=<optimized out>, asep=..., this=<optimized out>)
>     at /usr/include/qt4/QtCore/qstring.h:782
> #12 SSDP::ProcessData (this=0x90bb330, pSocket=0x90b2bd8) at ssdp.cpp:356
> #13 0xb6cdadad in SSDP::run (this=0x90bb330) at ssdp.cpp:301
> #14 0xb6b09932 in MThreadInternal::run (this=0x91224e0) at mthread.cpp:79
> #15 0xb54dade0 in ?? () from /usr/lib/i386-linux-gnu/libQtCore.so.4
> #16 0xb3a32d4c in start_thread (arg=0xa8fe3b40) at pthread_create.c:308
> #17 0xb52b5ace in clone () at ../sysdeps/unix/sysv/linux/i386/clone.S:130
>

>
> Backend log:
>
> 2012-09-02 17:16:20.798928 I [6287/28999] ProcessRequest
> ringbuffer.cpp:1098 (WaitForAvail) - RingBuf(/export/data/myth
> tv/4766_20120903001527.mpg): Waited 0.5 seconds for data
>                         to become available... 225236 < 327680
> 2012-09-02 17:16:21.299115 I [6287/28999] ProcessRequest
> ringbuffer.cpp:1098 (WaitForAvail) -
> RingBuf(/export/data/mythtv/4766_20120903001527.mpg): Waited 1.0 seconds
> for data
>                         to become available... 225236 < 3276802012-09-02
> 17:16:22.299456 I [6287/28999] ProcessRequest ringbuffer.cpp:1098
> (WaitForAvail) - RingBuf(/export/data/myth
> tv/4766_20120903001527.mpg): Waited 2.0 seconds for data
> to become available... 225236 < 327680
> 2012-09-02 17:16:27.569263 I [6287/6351] HouseKeeping housekeeper.cpp:221
> (RunHouseKeeping) - Running housekeeping thread
> 2012-09-02 17:17:04.086174 I [6287/6340] TVRecEvent tv_rec.cpp:1043
> (HandleStateChange) - TVRec(5): Changing from WatchingLiveTV to None
> 2012-09-02 17:17:04.105938 E [6287/29068] HDHRStreamHandler
> hdhrstreamhandler.cpp:211 (UpdateFilters) - HDHRSH(1313E021-0):
> UpdateFilters called in wrong tune mode
> 2012-09-02 17:17:05.079236 I [6287/6340] TVRecEvent tv_rec.cpp:830
> (FinishedRecording) - TVRec(5):
> FinishedRecording(4766_2012-09-03T00:15:27Z) damaged
> recq:<RecordingQuality overall_score="0" key="4766_2012-09-03T00:15:27Z"
> countinuity_e
> rror_count="0" packet_count="365988">    <Gap
> start="2012-09-03T00:00:00Z" end="2012-09-03T00:15:27Z" duration="927" />
>     <Gap start="2012-09-03T00:16:14Z" end="2012-09-03T01:00:00Z"
> duration="2625" /></RecordingQuality>
>

> Frontend log:
>
> Sep  2 17:16:53  mythlogserver: last message repeated 10 timesSep  2
> 17:16:53 fa-Macmini mythlogserver: mythfrontend[2949]: N CoreContext myth
> player.cpp:2095 (PrebufferEnoughFrames) Player(0): Waited 19896ms for
> video buffers AAAAAUUUAAUUAAULAuAUUAUAAUAAAAAP
> Sep  2 17:16:53 fa-Macmini mythlogserver: mythfrontend[2949]: E Decoder
> avformatdecoder.cpp:4493 (GetFrame) decoding error#012#011#011#011eno:
> Unknown error 541478725 (541478725)
> Sep  2 17:16:53  mythlogserver: last message repeated 11 timesSep  2
> 17:16:53 fa-Macmini mythlogserver: mythfrontend[2949]: N CoreContext myth
> player.cpp:2095 (PrebufferEnoughFrames) Player(0): Waited 20012ms for
> video buffers AAAAAUUUAAUUAAULAuAUUAUAAUAAAAAP
> Sep  2 17:16:53 fa-Macmini mythlogserver: mythfrontend[2949]: E
> CoreContext mythplayer.cpp:2118 (PrebufferEnoughFrames) Player(0): Waited
> too long for decoder t
> o fill video buffers. Exiting..Sep  2 17:16:53 fa-Macmini mythlogserver:
> mythfrontend[2949]: E Decoder avformatdecoder.cpp:4493 (GetFrame)
> decoding error#012#011#011#011eno: Unknown error 541
> 478725 (541478725)Sep  2 17:16:54 fa-Macmini mythlogserver:
> mythfrontend[2949]: I CoreContext tv_p
> lay.cpp:2155 (HandleStateChange) TV: Attempting to change from
> WatchingLiveTV to None

New description:

 Watching LiveTV with HDHR Prime is consistently crashing mythfrontend.
 The dialog i get refers to max buffering exceeded.  I can't exit from it
 and sometimes it just crashes there.

 With 0.25 and 0.26 (Master Head), I tried a few more experiments:
 1. LiveTV - Remote frontend, Tune to a TV Channel within a few mins, it
 aborts almost immediately with "max buffering exceeded".  Frontend will
 crash and needs to be restarted.
 2. Recording - Now schedule the same channel, same show and start the
 recording.  No remote frontend are viewing the stream. Recording proceeds
 with no problems.
 3. Recording/then streaming to remote frontend - This works also.  I tell
 myth to record the show, then watch the recording immediately after
 starting.  I am guessing I have to use this as the workaround until the
 LiveTV streaming is more robust.  It sucks that I can't channel surf
 anymore, but now I can watch almostLiveTV again.

 I am really doubting that this is a signal issue.  If it was a signal
 issue, #2 would behavior exactly like #1 and would be aborted also.  Also
 a majority of my recordings are finishing without any issues.  However,
 LiveTV would consistently fail and most of the time fail immediately
 within the first 5 mins.  Also #3 works, so it can't be a signal issue.

 I will attach the full logs next.


 {{{
 (gdb) bt
 #0  0xb77a9424 in __kernel_vsyscall ()
 #1  0xb51f91ef in __GI_raise (sig=6) at
 ../nptl/sysdeps/unix/sysv/linux/raise.c:64
 #2  0xb51fc835 in __GI_abort () at abort.c:91
 #3  0xb52342fa in __libc_message (do_abort=2, fmt=0xb532c3bc "*** glibc
 detected *** %s: %s: 0x%s ***\n")
     at ../sysdeps/unix/sysv/linux/libc_fatal.c:201
 #4  0xb523ee42 in malloc_printerr (action=<optimized out>, str=<optimized
 out>, ptr=0x95626f8) at malloc.c:5007
 #5  0xb5240fbf in _int_malloc (av=0xb536c440, bytes=40) at malloc.c:3470
 #6  0xb5242d3c in __GI___libc_malloc (bytes=40) at malloc.c:2924
 #7  0xb54d313b in qMalloc(unsigned int) () from /usr/lib/i386-linux-
 gnu/libQtCore.so.4
 #8  0xb552ebef in QString::realloc(int) () from /usr/lib/i386-linux-
 gnu/libQtCore.so.4
 #9  0xb552f23d in QString::append(QString const&) () from /usr/lib/i386
 -linux-gnu/libQtCore.so.4
 #10 0xb5534bfe in QString::section(QString const&, int, int,
 QFlags<QString::SectionFlag>) const ()
    from /usr/lib/i386-linux-gnu/libQtCore.so.4
 #11 0xb6cda1d1 in section (aflags=..., aend=<optimized out>,
 astart=<optimized out>, asep=..., this=<optimized out>)
     at /usr/include/qt4/QtCore/qstring.h:782
 #12 SSDP::ProcessData (this=0x90bb330, pSocket=0x90b2bd8) at ssdp.cpp:356
 #13 0xb6cdadad in SSDP::run (this=0x90bb330) at ssdp.cpp:301
 #14 0xb6b09932 in MThreadInternal::run (this=0x91224e0) at mthread.cpp:79
 #15 0xb54dade0 in ?? () from /usr/lib/i386-linux-gnu/libQtCore.so.4
 #16 0xb3a32d4c in start_thread (arg=0xa8fe3b40) at pthread_create.c:308
 #17 0xb52b5ace in clone () at ../sysdeps/unix/sysv/linux/i386/clone.S:130

 }}}



 Backend log:


 {{{
 2012-09-02 17:16:20.798928 I [6287/28999] ProcessRequest
 ringbuffer.cpp:1098 (WaitForAvail) - RingBuf(/export/data/myth
 tv/4766_20120903001527.mpg): Waited 0.5 seconds for data
                         to become available... 225236 < 327680
 2012-09-02 17:16:21.299115 I [6287/28999] ProcessRequest
 ringbuffer.cpp:1098 (WaitForAvail) -
 RingBuf(/export/data/mythtv/4766_20120903001527.mpg): Waited 1.0 seconds
 for data
                         to become available... 225236 < 3276802012-09-02
 17:16:22.299456 I [6287/28999] ProcessRequest ringbuffer.cpp:1098
 (WaitForAvail) - RingBuf(/export/data/myth
 tv/4766_20120903001527.mpg): Waited 2.0 seconds for data
 to become available... 225236 < 327680
 2012-09-02 17:16:27.569263 I [6287/6351] HouseKeeping housekeeper.cpp:221
 (RunHouseKeeping) - Running housekeeping thread
 2012-09-02 17:17:04.086174 I [6287/6340] TVRecEvent tv_rec.cpp:1043
 (HandleStateChange) - TVRec(5): Changing from WatchingLiveTV to None
 2012-09-02 17:17:04.105938 E [6287/29068] HDHRStreamHandler
 hdhrstreamhandler.cpp:211 (UpdateFilters) - HDHRSH(1313E021-0):
 UpdateFilters called in wrong tune mode
 2012-09-02 17:17:05.079236 I [6287/6340] TVRecEvent tv_rec.cpp:830
 (FinishedRecording) - TVRec(5):
 FinishedRecording(4766_2012-09-03T00:15:27Z) damaged
 recq:<RecordingQuality overall_score="0" key="4766_2012-09-03T00:15:27Z"
 countinuity_e
 rror_count="0" packet_count="365988">    <Gap start="2012-09-03T00:00:00Z"
 end="2012-09-03T00:15:27Z" duration="927" />
     <Gap start="2012-09-03T00:16:14Z" end="2012-09-03T01:00:00Z"
 duration="2625" /></RecordingQuality>


 }}}

 Frontend log:


 {{{
 Sep  2 17:16:53  mythlogserver: last message repeated 10 timesSep  2
 17:16:53 fa-Macmini mythlogserver: mythfrontend[2949]: N CoreContext myth
 player.cpp:2095 (PrebufferEnoughFrames) Player(0): Waited 19896ms for
 video buffers AAAAAUUUAAUUAAULAuAUUAUAAUAAAAAP
 Sep  2 17:16:53 fa-Macmini mythlogserver: mythfrontend[2949]: E Decoder
 avformatdecoder.cpp:4493 (GetFrame) decoding error#012#011#011#011eno:
 Unknown error 541478725 (541478725)
 Sep  2 17:16:53  mythlogserver: last message repeated 11 timesSep  2
 17:16:53 fa-Macmini mythlogserver: mythfrontend[2949]: N CoreContext myth
 player.cpp:2095 (PrebufferEnoughFrames) Player(0): Waited 20012ms for
 video buffers AAAAAUUUAAUUAAULAuAUUAUAAUAAAAAP
 Sep  2 17:16:53 fa-Macmini mythlogserver: mythfrontend[2949]: E
 CoreContext mythplayer.cpp:2118 (PrebufferEnoughFrames) Player(0): Waited
 too long for decoder t
 o fill video buffers. Exiting..Sep  2 17:16:53 fa-Macmini mythlogserver:
 mythfrontend[2949]: E Decoder avformatdecoder.cpp:4493 (GetFrame) decoding
 error#012#011#011#011eno: Unknown error 541
 478725 (541478725)Sep  2 17:16:54 fa-Macmini mythlogserver:
 mythfrontend[2949]: I CoreContext tv_p
 lay.cpp:2155 (HandleStateChange) TV: Attempting to change from
 WatchingLiveTV to None

 }}}

--

-- 
Ticket URL: <http://code.mythtv.org/trac/ticket/11064#comment:2>
MythTV <http://code.mythtv.org/trac>
MythTV Media Center

More information about the mythtv-commits mailing list