[asterisk-bugs] [LibPRI 0014335]: Crash on pri_schedule_event - t200_expire

Tue Jun 16 06:13:07 CDT 2009

A NOTE has been added to this issue. 
====================================================================== 
https://issues.asterisk.org/view.php?id=14335 
====================================================================== 
Reported By:                ricvil
Assigned To:                mattf
====================================================================== 
Project:                    LibPRI
Issue ID:                   14335
Category:                   General
Reproducibility:            random
Severity:                   crash
Priority:                   normal
Status:                     acknowledged
Asterisk Version:           1.4.22 
SVN Branch (only for SVN checkouts, not tarball releases): N/A 
SVN Revision (number only!):  
Disclaimer on File?:        N/A 
Request Review:              
====================================================================== 
Date Submitted:             2009-01-26 10:56 CST
Last Modified:              2009-06-16 06:13 CDT
====================================================================== 
Summary:                    Crash on pri_schedule_event - t200_expire
Description: 
I have had multiple crashes using libpri 1.4.7 (see
http://bugs.digium.com/view.php?id=14243).

I now upgraded to libpri 1.4.9 (same Asterisk 1.4.22) and the issue still
happens.  Compiled with DONT_OPTIMIZE, DEBUG_CHANNEL_LOCK, and
DEBUG_THREADS

Here is the latest crash from today:
Program terminated with signal 11, Segmentation fault.
https://issues.asterisk.org/view.php?id=0  0x009d6ce2 in pri_schedule_event
(pri=0x12, ms=0, function=0x9d43a4
<t200_expire>, data=0xb7a502e0) at prisched.c:44
44              while (pri->master)

(gdb) bt
https://issues.asterisk.org/view.php?id=0  0x009d6ce2 in pri_schedule_event
(pri=0x12, ms=0, function=0x9d43a4
<t200_expire>, data=0xb7a502e0) at prisched.c:44
https://issues.asterisk.org/view.php?id=1  0x009d3c0f in reschedule_t200
(pri=0xb7a502e0) at q921.c:259
https://issues.asterisk.org/view.php?id=2  0x009d4ba7 in q921_transmit_iframe
(pri=0xb7a502e0, buf=0x5725b50,
len=9, cr=1) at q921.c:537
https://issues.asterisk.org/view.php?id=3  0x009dd489 in q931_xmit
(pri=0xb7a502e0, h=0x5725b50, len=9, cr=1) at
q931.c:2611
https://issues.asterisk.org/view.php?id=4  0x009dd682 in send_message
(pri=0xb7d73540, c=0xb7a383a0, msgtype=69,
ies=0x9f501c) at q931.c:2654
https://issues.asterisk.org/view.php?id=5  0x009de935 in q931_disconnect
(pri=0xb7d73540, c=0xb7a383a0, cause=16)
at q931.c:3020
https://issues.asterisk.org/view.php?id=6  0x009df2a3 in q931_hangup
(pri=0xb7d73540, c=0xb7a383a0, cause=16) at
q931.c:3230
https://issues.asterisk.org/view.php?id=7  0x009d2693 in pri_hangup
(pri=0xb7d73540, call=0xb7a383a0, cause=16)
at pri.c:623
https://issues.asterisk.org/view.php?id=8  0x012e9851 in dahdi_hangup
(ast=0xb7a0b9a8) at chan_dahdi.c:2718
https://issues.asterisk.org/view.php?id=9  0x08087ae3 in ast_hangup
(chan=0xb7a0b9a8) at channel.c:1507
https://issues.asterisk.org/view.php?id=10 0x080d6245 in __ast_pbx_run
(c=0xb7a0b9a8) at pbx.c:2561
https://issues.asterisk.org/view.php?id=11 0x080d6495 in pbx_thread
(data=0xb7a0b9a8) at pbx.c:2621
https://issues.asterisk.org/view.php?id=12 0x08119e13 in dummy_start
(data=0xb7a6d360) at utils.c:912
https://issues.asterisk.org/view.php?id=13 0x0067946b in start_thread () from
/lib/libpthread.so.0
https://issues.asterisk.org/view.php?id=14 0x005d0dbe in clone () from
/lib/libc.so.6
(gdb) 

I will attach the full backtrace on file backtrace1.txt
======================================================================
Relationships       ID      Summary
----------------------------------------------------------------------
related to          0014243 core dump on pri_schedule_event
====================================================================== 

---------------------------------------------------------------------- 
 (0106449) AC (reporter) - 2009-06-16 06:13
 https://issues.asterisk.org/view.php?id=14335#c106449 
---------------------------------------------------------------------- 
It looks like I may have the same fault.

I've noticed this previously in asterisk 1.6.0.3-rc1 and libpri 1.4.9. The
errors occurred when I had more than 200 incoming PRI calls. I've also had
the D-channels failing and coming up after 2-3 seconds. Established calls
remain up but new calls are not possible from the PRI once the problem
starts.

I've since upgraded asterisk to SVN-branch-1.6.0-r199301, and below are
some of the errors. When the errors are active, if I dial into the asterisk
box my call gets routed to the wrong extension. I've also found that the
channels were not released.

[Jun 16 09:00:14] WARNING[5991]: chan_dahdi.c:10361 pri_fixup_principle:
Can't fix up channel from 103 to 97 because 97 is already in use
[Jun 16 09:00:14] WARNING[5991]: chan_dahdi.c:11542 pri_dchannel: Hangup
on bad channel 0/4 on span 4
[Jun 16 09:00:14] WARNING[5990]: chan_dahdi.c:10361 pri_fixup_principle:
Can't fix up channel from 72 to 75 because 75 is already in use
[Jun 16 09:00:14] WARNING[5990]: chan_dahdi.c:11608 pri_dchannel: Hangup
REQ on bad channel 0/13 on span 3
[Jun 16 09:00:18] WARNING[5991]: chan_dahdi.c:10361 pri_fixup_principle:
Can't fix up channel from 103 to 97 because 97 is already in use
[Jun 16 09:00:18] WARNING[5991]: chan_dahdi.c:11542 pri_dchannel: Hangup
on bad channel 0/4 on span 4
[Jun 16 09:00:19] WARNING[5990]: chan_dahdi.c:10361 pri_fixup_principle:
Can't fix up channel from 75 to 73 because 73 is already in use
[Jun 16 09:00:19] WARNING[5990]: chan_dahdi.c:11608 pri_dchannel: Hangup
REQ on bad channel 0/11 on span 3
[Jun 16 09:00:20] WARNING[5990]: chan_dahdi.c:10361 pri_fixup_principle:
Can't fix up channel from 88 to 69 because 69 is already in use
[Jun 16 09:00:20] WARNING[5990]: chan_dahdi.c:11542 pri_dchannel: Hangup
on bad channel 0/7 on span 3
[Jun 16 09:00:20] WARNING[5990]: chan_dahdi.c:10361 pri_fixup_principle:
Can't fix up channel from 82 to 87 because 87 is already in use
[Jun 16 09:00:20] WARNING[5990]: chan_dahdi.c:11608 pri_dchannel: Hangup
REQ on bad channel 0/25 on span 3 

Issue History 
Date Modified    Username       Field                    Change               
====================================================================== 
2009-06-16 06:13 AC             Note Added: 0106449                          
======================================================================