[Asterisk-bugs] [Asterisk 0009724]: SIP Transfers To Parking Lot From Grandstream GXP-2000 Locks Up SIP Channel

[noreply at bugs.digium.com]

A NOTE has been added to this issue. 
====================================================================== 
http://bugs.digium.com/view.php?id=9724 
====================================================================== 
Reported By:                kenw
Assigned To:                
====================================================================== 
Project:                    Asterisk
Issue ID:                   9724
Category:                   Channels/chan_sip/Transfers
Reproducibility:            random
Severity:                   major
Priority:                   normal
Status:                     new
Asterisk Version:           1.4.4 
SVN Branch (only for SVN checkouts, not tarball releases):  1.4  
SVN Revision (number only!): 63519 
Disclaimer on File?:        No 
Request Review:              
====================================================================== 
Date Submitted:             05-14-2007 14:49 CDT
Last Modified:              07-20-2007 13:19 CDT
====================================================================== 
Summary:                    SIP Transfers To Parking Lot From Grandstream
GXP-2000 Locks Up SIP Channel
Description: 
Starting a couple of months ago about once a week Asterisk would stop
responding to our SIP phones.  This has increased slowly to 3-5 times a
day.  It's taken me a long time to (hopefully) narrow the problem down to
something I could report.  I've looked everywhere online for help and I
hope I didn't miss this being reported already.

I've been able to reproduce the problem with 1.4.1, 1.4.2, 1.4.4 & the
latest SVN as of last week (63519), the version doesn't seem to affect the
timing of the problem one way or another.

I've attached logs below including SIP Debug enabled, verbose & debug set
to 4.  I've also included a console log of the state of things when it
happens.  

We are using the TRNF button on the GXP-2000 phones to transfer to a
parking lot.  It seems to be a transfer to the parking lot that causes SIP
to stop responding, though I may be mistaken.  Asterisk appears fine but
all phones get 'no response from server' when the lock up occurs.

We've got 3 boxes at 3 locations, the other 2 are working fine, this one
is having fits, however, this box is also used 10x more then the other 2. 
Last week I removed all asterisk folders and reinstalled 1.4.4 but the
problem still occurs.

When the lockup occurs if I try to reload chan_sip I get no errors, if I
try to unload it I get the following:
[May 14 11:40:47] WARNING[6314]: loader.c:458 ast_unload_resource: Soft
unload failed, 'chan_sip.so' has use count 32

The server is running CentOS 4.4, has two TDM-400p cards with 6 FXS & 2
FXO cards.


====================================================================== 

---------------------------------------------------------------------- 
 kenw - 07-20-07 13:19  
---------------------------------------------------------------------- 
Okay, so I am attaching once more a console & full log file of the event. 
Beings we're on 1.4.6, these are a bit different then previous logs
attached and I think (and hope) they offer more insight as to what's going
on.  Couple notes of interest:

1.  When system SIP channel locks up I can do a "SIP SHOW CHANNELS"
followed by "SIP SHOW HISTORY xxxxxxxx" and get details
2.  Once locked up, if I do SHOW CHANNELS I get a LOT of spam that says
the following repeated for hundreds of lines:

asterisk-price*CLI> SIPPeer/SIP/717-0973 s at from-internal:1    Down   
(None)                        
asterisk-price*CLI> Parking/Zap/3-1      SIP/717 at park-dial:1  Down   
(None)                        

3. Once locked up and I've done SHOW CHANNELS, any command repeats the
spam from above

4.  The log file shows hundreds of lines repeating:

[Jul 20 12:19:44] DEBUG[10870] channel.c: Avoiding deadlock for channel
'0x9768088'
[Jul 20 12:19:44] DEBUG[10870] channel.c: Avoiding deadlock for channel
'0x9768088'
[Jul 20 12:19:44] DEBUG[10870] channel.c: Avoiding deadlock for channel
'0x9768088'
[Jul 20 12:19:44] DEBUG[10870] channel.c: Avoiding deadlock for channel
'0x9768088'

Hope this helps!!! 

Issue History 
Date Modified   Username       Field                    Change               
====================================================================== 
07-20-07 13:19  kenw           Note Added: 0067648                          
======================================================================