[asterisk-bugs] [Asterisk 0018511]: [patch] Asterisk hangs with no calls being able to go in or out, and the console does not respond to commands

Asterisk Bug Tracker noreply at bugs.digium.com
Thu Feb 3 11:41:41 CST 2011


A NOTE has been added to this issue. 
====================================================================== 
https://issues.asterisk.org/view.php?id=18511 
====================================================================== 
Reported By:                jonascph
Assigned To:                
====================================================================== 
Project:                    Asterisk
Issue ID:                   18511
Category:                   Channels/chan_iax2
Reproducibility:            random
Severity:                   block
Priority:                   normal
Status:                     acknowledged
Asterisk Version:           SVN 
JIRA:                       SWP-2804 
Regression:                 No 
Reviewboard Link:            
SVN Branch (only for SVN checkouts, not tarball releases):  trunk 
SVN Revision (number only!):  
Request Review:              
====================================================================== 
Date Submitted:             2010-12-21 09:42 CST
Last Modified:              2011-02-03 11:41 CST
====================================================================== 
Summary:                    [patch] Asterisk hangs with no calls being able to
go in or out, and the console does not respond to commands
Description: 
I'm not sure if we are seeing one or two different issues.

We've had stability issues with all versions of 1.8 we've tried. Those
include 1.8rc2, 1.8 final,a trunk checkout some weeks after 1.8 final and
latest 1.8.2rc1 all had the same issue or similar issues (They all
froze/crashed)

So far i do not have any type of debug information to show, but im
counting on being able to get some next time it happens. It's so far
happened about once every 6-9 days, and strangely enough it typically
doesnt happen for over a week and then happens 2-3 times on the same day.
======================================================================
Relationships       ID      Summary
----------------------------------------------------------------------
related to          0017521 Brief lagginess on IAX2 channels is fatal
related to          0018619 Asterisk deadlocks with no errors
====================================================================== 

---------------------------------------------------------------------- 
 (0131461) jcovert (reporter) - 2011-02-03 11:41
 https://issues.asterisk.org/view.php?id=18511#c131461 
---------------------------------------------------------------------- 
Four more, two in close succession.

Before the first one:

    -- Executing [77 at dectalk-orig:6]
Hangup("Local/dtk at dectalk-orig-fc34;1", "") in new stack
  == Spawn extension (dectalk-orig, 77, 6) exited non-zero on
'Local/dtk at dectalk-orig-fc34;1'
  == Spawn extension (dectalk-orig, dtk, 3) exited non-zero on
'Local/dtk at dectalk-orig-fc34;2'
[Feb  1 06:45:14] NOTICE[22651]: pbx_spool.c:366 attempt_thread: Call
completed to local/dtk at dectalk-orig/n
[Feb  1 07:05:04] NOTICE[22651]: chan_sip.c:19588
handle_response_peerpoke: Peer 'acat_x23' is now Lagged. (3235ms / 2000ms)
[Feb  1 07:05:14] NOTICE[22651]: chan_sip.c:19588
handle_response_peerpoke: Peer 'acat_x23' is now Reachable. (53ms /
2000ms)
    -- Registered SIP 'acat_x23' at 173.162.202.218:2072
[Feb  1 07:05:49] NOTICE[22651]: chan_sip.c:19588
handle_response_peerpoke: Peer 'acat_x23' is now Reachable. (55ms /
2000ms)
[Feb  1 08:05:12] WARNING[22651]: chan_sip.c:3386 retrans_pkt:
Retransmission timeout reached on transmission
5ac49bb2575455913980c57f44ab2822 at sip.broadvoice.com for seqno 2394
(Critical Request) -- See doc/sip-retransmit.txt.
Packet timed out after 37065ms with no response
[Feb  1 08:05:12] WARNING[22651]: chan_sip.c:3386 retrans_pkt:
Retransmission timeout reached on transmission
2da601e57fd3093d35cd20992e81812d at voipuser.org for seqno 802 (Critical
Request) -- See doc/sip-retransmit.txt.
Packet timed out after 34825ms with no response
[Feb  1 08:05:12] WARNING[22651]: chan_sip.c:3386 retrans_pkt:
Retransmission timeout reached on transmission
026e145206fa0f175fb333094207b60b at cgate.covert.org for seqno 3545 (Critical
Request) -- See doc/sip-retransmit.txt.
Packet timed out after 34303ms with no response
[Feb  1 08:05:12] WARNING[22651]: chan_sip.c:3386 retrans_pkt:
Retransmission timeout reached on transmission
5e22924664d1a94b756e8d17312560e1 at cgate.covert.org for seqno 1153 (Critical
Request) -- See doc/sip-retransmit.txt.
Packet timed out after 33828ms with no response
[Feb  1 08:05:12] NOTICE[22651]: chan_sip.c:24809 sip_poke_noanswer: Peer
'acat_x23' is now UNREACHABLE!  Last qualify: 51
[Feb  1 08:05:12] WARNING[22651]: chan_sip.c:3386 retrans_pkt:
Retransmission timeout reached on transmission
13d51bf33b63affd0c92d3062f1a3569 at sipgate.com for seqno 802 (Critical
Request) -- See doc/sip-retransmit.txt.
Packet timed out after 32649ms with no response
[Feb  1 08:05:12] WARNING[22651]: chan_sip.c:3386 retrans_pkt:
Retransmission timeout reached on transmission
62c050b05661730f5f2a71ff0bed8a38 at freeswitch.covert.org for seqno 1154
(Critical Request) -- See doc/sip-retransmit.txt.
Packet timed out after 32591ms with no response
[Feb  1 08:05:12] WARNING[22651]: chan_sip.c:3386 retrans_pkt:
Retransmission timeout reached on transmission
3c2dd69a320aaf0936dcbc6d751cea86 at sipgate.de for seqno 802 (Critical
Request) -- See doc/sip-retransmit.txt.
Packet timed out after 32127ms with no response
[Feb  1 08:05:12] WARNING[22651]: chan_sip.c:3386 retrans_pkt:
Retransmission timeout reached on transmission
58ccc3e4445500e9181700be3771b6ca at sipgate.de for seqno 803 (Critical
Request) -- See doc/sip-retransmit.txt.
Packet timed out after 32036ms with no response
[Feb  1 08:05:12] NOTICE[22651]: chan_sip.c:24809 sip_poke_noanswer: Peer
'acat_x24' is now UNREACHABLE!  Last qualify: 51
[Feb  1 08:05:35] NOTICE[22651]: chan_sip.c:12237 sip_reg_timeout:    --
Registration for '9784290097 at broadvoice' timed out, trying again (Attempt
https://issues.asterisk.org/view.php?id=2)
[Feb  1 08:05:35] NOTICE[22651]: chan_sip.c:24809 sip_poke_noanswer: Peer
'x37' is now UNREACHABLE!  Last qualify: 9
[Feb  1 08:05:38] NOTICE[22651]: chan_sip.c:12237 sip_reg_timeout:    --
Registration for 'jcovert at voipuseruk' timed out, trying again (Attempt
https://issues.asterisk.org/view.php?id=2)
[Feb  1 08:05:39] NOTICE[22651]: chan_sip.c:12237 sip_reg_timeout:    --
Registration for '16789357030 at sphone.vopr.vonage.net' timed out, trying
again (Attempt https://issues.asterisk.org/view.php?id=2)
[Feb  1 08:05:39] NOTICE[22651]: chan_sip.c:12237 sip_reg_timeout:    --
Registration for 'salmat399 at jrc-salmat399' timed out, trying again (Attempt
https://issues.asterisk.org/view.php?id=2)

Then -- asterisk-restart-2011-02-01-0806.txt

Before the next one:

[Feb  2 14:17:13] ERROR[26881]: chan_sip.c:27996 setup_srtp: No SRTP
module loaded, can't setup SRTP session.
    -- Stopped music on hold on SIP/88.87.32.27-000000e8
    -- Stopped music on hold on SIP/pstn5433-000000ef


Then -- asterisk-restart-2011-02-02-1424.txt

Next one: Nothing before, but at close to the same time
[Feb  3 12:20:35] ERROR[4959]: netsock2.c:249 ast_sockaddr_resolve:
getaddrinfo("free1.voipgateway.org", "(null)", ...): No address associated
with nodename
[Feb  3 12:20:36] WARNING[4959]: acl.c:569 resolve_first: Unable to lookup
'free1.voipgateway.org'

asterisk-restart-2011-02-03-1220.txt

and continuing afterwards:

[Feb  3 12:21:27] WARNING[10480]: chan_sip.c:3115 __sip_xmit: sip_xmit of
0x19f3400 (len 437) to (null) returned -1: Invalid argument
[Feb  3 12:21:28] WARNING[10480]: chan_sip.c:3115 __sip_xmit: sip_xmit of
0x19f3400 (len 437) to (null) returned -1: Invalid argument
[Feb  3 12:21:30] WARNING[10480]: chan_sip.c:3115 __sip_xmit: sip_xmit of
0x19f3400 (len 437) to (null) returned -1: Invalid argument
[Feb  3 12:21:34] WARNING[10480]: chan_sip.c:3115 __sip_xmit: sip_xmit of
0x19f3400 (len 437) to (null) returned -1: Invalid argument
[Feb  3 12:21:38] WARNING[10480]: chan_sip.c:3115 __sip_xmit: sip_xmit of
0x19f3400 (len 437) to (null) returned -1: Invalid argument
[Feb  3 12:21:42] WARNING[10480]: chan_sip.c:3115 __sip_xmit: sip_xmit of
0x19f3400 (len 437) to (null) returned -1: Invalid argument
[Feb  3 12:21:46] WARNING[10480]: chan_sip.c:3115 __sip_xmit: sip_xmit of
0x19f3400 (len 437) to (null) returned -1: Invalid argument
[Feb  3 12:21:48] ERROR[10480]: netsock2.c:249 ast_sockaddr_resolve:
getaddrinfo("free1.voipgateway.org", "(null)", ...): No address associated
with nodename
[Feb  3 12:21:48] WARNING[10480]: acl.c:569 resolve_first: Unable to
lookup 'free1.voipgateway.org'
[Feb  3 12:21:48] WARNING[10480]: acl.c:698 ast_ouraddrfor: Cannot
connect
[Feb  3 12:21:48] WARNING[10480]: chan_sip.c:3115 __sip_xmit: sip_xmit of
0x19bb000 (len 437) to (null) returned -1: Invalid argument
[Feb  3 12:21:48] NOTICE[10480]: chan_sip.c:12237 sip_reg_timeout:    --
Registration for '41325114995 at free1.voipgateway.org' timed out, trying
again (Attempt https://issues.asterisk.org/view.php?id=3)
[Feb  3 12:21:48] WARNING[10480]: chan_sip.c:3115 __sip_xmit: sip_xmit of
0x19bb000 (len 437) to (null) returned -1: Invalid argument
[Feb  3 12:21:49] WARNING[10480]: chan_sip.c:3115 __sip_xmit: sip_xmit of
0x19bb000 (len 437) to (null) returned -1: Invalid argument
[Feb  3 12:21:51] WARNING[10480]: chan_sip.c:3115 __sip_xmit: sip_xmit of
0x19bb000 (len 437) to (null) returned -1: Invalid argument

until the DNS problem was resolved.

Just a few minutes later:

[Feb  3 12:33:21] ERROR[10480]: chan_sip.c:27996 setup_srtp: No SRTP
module loaded, can't setup SRTP session.
    -- Stopped music on hold on SIP/von-g-00000001
  == Spawn extension (inbound-ringall, 990, 20) exited non-zero on
'SIP/von-g-00000001'
[Feb  3 12:33:48] WARNING[10480]: chan_sip.c:3386 retrans_pkt:
Retransmission timeout reached on transmission
2a12c99c3d3b25c00c3943a6328e5853 at cgate.covert.org for seqno 1 (Critical
Response) -- See doc/sip-retransmit.txt.
Packet timed out after 32001ms with no response
[Feb  3 12:33:51] WARNING[10480]: chan_sip.c:3386 retrans_pkt:
Retransmission timeout reached on transmission
2a12c99c3d3b25c00c3943a6328e5853 at cgate.covert.org for seqno 2 (Critical
Response) -- See doc/sip-retransmit.txt.
Packet timed out after 32000ms with no response
[Feb  3 12:33:52] WARNING[10480]: chan_sip.c:3386 retrans_pkt:
Retransmission timeout reached on transmission
2a12c99c3d3b25c00c3943a6328e5853 at cgate.covert.org for seqno 2 (Critical
Response) -- See doc/sip-retransmit.txt.
Packet timed out after 32000ms with no response
asterisk*CLI> 
asterisk*CLI> 
asterisk*CLI> 
[Feb  3 12:39:11] ERROR[10480]: chan_sip.c:27996 setup_srtp: No SRTP
module loaded, can't setup SRTP session.

and then -- asterisk-restart-2011-02-03-1240.txt 

Issue History 
Date Modified    Username       Field                    Change               
====================================================================== 
2011-02-03 11:41 jcovert        Note Added: 0131461                          
======================================================================




More information about the asterisk-bugs mailing list