[Asterisk-bugs] [Asterisk 0009549]: Asterisk crashes with error "Unable to masquerade"

[noreply at bugs.digium.com]

The following issue has been REOPENED. 
====================================================================== 
http://bugs.digium.com/view.php?id=9549 
====================================================================== 
Reported By:                amorsen
Assigned To:                file
====================================================================== 
Project:                    Asterisk
Issue ID:                   9549
Category:                   Core/General
Reproducibility:            unable to reproduce
Severity:                   crash
Priority:                   normal
Status:                     feedback
Asterisk Version:            1.2.16  
SVN Branch (only for SVN checkouts, not tarball releases): N/A  
SVN Revision (number only!):  
Disclaimer on File?:        No 
Request Review:              
====================================================================== 
Date Submitted:             04-17-2007 03:58 CDT
Last Modified:              07-04-2007 05:54 CDT
====================================================================== 
Summary:                    Asterisk crashes with error "Unable to masquerade"
Description: 
A masquerade which fails with "Unable to answer" results in a second
masquerade. The second masquerade fails with "Unable to masquerade". A
moment later, channel.c says "Unable to find channel in list" and asterisk
crashes.
====================================================================== 

---------------------------------------------------------------------- 
 amorsen - 07-04-07 05:54  
---------------------------------------------------------------------- 
I'm sorry to report this, but the issue reoccurred in asterisk 1.2.19.
Notice that the problem I reported most likely differs from what Callmewind
reported. We do not use the Local channel, all calls involved were using
plain SIP.

Messages in the log:

Jul  4 12:20:26 DEBUG[492] res_config_mysql.c: MySQL RealTime: Everything
is fine.
Jul  4 12:20:26 DEBUG[492] res_config_mysql.c: MySQL RealTime: Retrieve
SQL: SELECT * FROM skanlog_sip WHERE name = '172.31.0.8'
Jul  4 12:20:26 DEBUG[579] chan_sip.c: Stopping retransmission on
'593272e77deb85d86667be2b6b9dcdd0 at 10.10.9.164' of Request 102: Match Found
Jul  4 12:20:27 DEBUG[579] chan_sip.c: Setting NAT on RTP to 0
Jul  4 12:20:27 DEBUG[579] chan_sip.c: Auto destroying call
'3c2671603345-idm41mcl1mk4 at snom320-00041327139E'
Jul  4 12:20:27 DEBUG[579] chan_sip.c: Stopping retransmission on
'3c44bc90ea60-s1j7qpc07r80 at snom320-0004132713A4' of Response 1: Match
Found
Jul  4 12:20:28 DEBUG[579] chan_sip.c: Auto destroying call
'3c26700cecd1-zi9arbj9x7b8 at snom320-00041327139F'
Jul  4 12:20:28 DEBUG[579] chan_sip.c: Auto destroying call
'3c26700c35b6-nyxxjoaax0ht at snom320-0004132713A2'
Jul  4 12:20:28 DEBUG[579] chan_sip.c: Setting NAT on RTP to 0
Jul  4 12:20:28 DEBUG[579] chan_sip.c: Checking SIP call limits for device
300
Jul  4 12:20:28 DEBUG[579] chan_sip.c: build_route: Contact hop:
<sip:300 at 10.0.136.185:2051;line=dovd7jxk>;flow-id=1
Jul  4 12:20:28 DEBUG[492] channel.c: Avoiding initial deadlock for
'SIP/300-083ff5d0'
Jul  4 12:20:28 DEBUG[579] channel.c: Planning to masquerade channel
SIP/300-083ff5d0 into the structure of SIP/303-084ac760
Jul  4 12:20:28 DEBUG[579] channel.c: Done planning to masquerade channel
SIP/300-083ff5d0 into the structure of SIP/303-084ac760
Jul  4 12:20:28 WARNING[579] res_features.c: Unable to answer
'SIP/300-083ff5d0'
Jul  4 12:20:28 DEBUG[579] channel.c: Planning to masquerade channel
SIP/300-083ff5d0 into the structure of SIP/303-084ac760
Jul  4 12:20:28 WARNING[579] channel.c: SIP/300-083ff5d0 is already going
to masquerade as SIP/303-084ac760
Jul  4 12:20:28 WARNING[579] res_features.c: Unable to masquerade
'SIP/300-083ff5d0' into 'SIP/303-084ac760'
Jul  4 12:20:28 NOTICE[579] chan_sip.c: Nothing to pick up
Jul  4 12:20:28 DEBUG[21137] channel.c: Got clone lock for masquerade on
'SIP/300-083ff5d0' at 0x842d114
Jul  4 12:20:28 DEBUG[21137] chan_sip.c: update_call_counter(303) -
decrement call limit counter
Jul  4 12:20:28 DEBUG[21137] chan_sip.c: Acked pending invite 102
Jul  4 12:20:28 DEBUG[21137] chan_sip.c: Stopping retransmission on
'0a7506076ae30e0a506555151574f0a6 at 10.10.9.164' of Request 102: Match Found
Jul  4 12:20:28 DEBUG[21137] channel.c: Putting channel SIP/300-083ff5d0
in 4/4 formats
Jul  4 12:20:28 DEBUG[21137] channel.c: Destroying channel clone
'SIP/303-084ac760<ZOMBIE>'
Jul  4 12:20:28 DEBUG[21137] channel.c: Done Masquerading SIP/300-083ff5d0
(4)
Jul  4 12:20:28 DEBUG[22283] sched.c: Request to schedule in the past?!?!

After that, no more messages until asterisk was restarted. 

Issue History 
Date Modified   Username       Field                    Change               
====================================================================== 
07-04-07 05:54  amorsen        Status                   closed => feedback  
07-04-07 05:54  amorsen        Resolution               suspended => reopened
07-04-07 05:54  amorsen        Note Added: 0066482                          
======================================================================