[asterisk-bugs] [Asterisk 0011550]: Prodding channel... causes 100% utilization

[noreply at bugs.digium.com]

The following issue has been REOPENED. 
====================================================================== 
http://bugs.digium.com/view.php?id=11550 
====================================================================== 
Reported By:                pj
Assigned To:                
====================================================================== 
Project:                    Asterisk
Issue ID:                   11550
Category:                   Channels/chan_sip/General
Reproducibility:            sometimes
Severity:                   block
Priority:                   normal
Status:                     new
Asterisk Version:            SVN 
SVN Branch (only for SVN checkouts, not tarball releases):  trunk 
SVN Revision (number only!): 92697 
Disclaimer on File?:        N/A 
Request Review:              
====================================================================== 
Date Submitted:             12-14-2007 08:37 CST
Last Modified:              03-01-2008 05:14 CST
====================================================================== 
Summary:                    Prodding channel... causes 100% utilization
Description: 
from time to time, asterisk is locked (100% cpu utilization, but not crash)
with "Prodding channel..." messages...
grep "SIP/777-082ae2f8" /var/log/asterisk/messages | wc -l
856979

in my case it happened in entering simple diaplan, calling MoH...
 555 => { Set(TIMEOUT(absolute)=60); MusicOnHold(); }
user is connected over wifi, so maybe some packet was lost in some
critical call stage, that causes sip channel starts looping?

unfortunately, I was not able to do some debugging, because asterisk pc
was completely locked, due to realtime priority of asterisk process...
====================================================================== 

---------------------------------------------------------------------- 
 pj - 03-01-08 05:14  
---------------------------------------------------------------------- 
I would like to reopen this issue, 
I found source of the 'prodding channel..." issue, it can be easy
reproduced:
I'm using softphone to call this simple dialplan:
  '555' =>          1. Set(TIMEOUT(absolute)=60)                 
[pbx_ael]
                    2. MusicOnHold()                             
[pbx_ael]

after call is established, I kill softphone to simulate broken connection,
after absolute timeout expires asterisk starts looping in cycle below...

when I remove 'Set(TIMEOUT(absolute)=60)' and repeat, channel remains
open, even if I have 'rtptimeout=15' in sip.conf, this is related to 
# 0011562: "rtptimeout" doesn't terminate channel if RTP is lost during
"Echo()" 




[Mar  1 12:00:47] WARNING[27792]: channel.c:2883 ast_prod: Prodding
channel 'SIP/324z-082731b8' failed
[Mar  1 12:00:47]     -- Started music on hold, class 'default', on
SIP/324z-082731b8
[Mar  1 12:00:47]     -- Stopped music on hold on SIP/324z-082731b8
[Mar  1 12:00:47]   == Spawn extension (from-pj-test, 555, 2) exited
non-zero on 'SIP/324z-082731b8'
[Mar  1 12:00:47]     -- Executing [555 at from-pj-test:2]
MusicOnHold("SIP/324z-082731b8", "") in new stack
[Mar  1 12:00:47] WARNING[27792]: channel.c:2883 ast_prod: Prodding
channel 'SIP/324z-082731b8' failed
[Mar  1 12:00:47]     -- Started music on hold, class 'default', on
SIP/324z-082731b8
[Mar  1 12:00:47]     -- Stopped music on hold on SIP/324z-082731b8
[Mar  1 12:00:47]   == Spawn extension (from-pj-test, 555, 2) exited
non-zero on 'SIP/324z-082731b8' 

Issue History 
Date Modified   Username       Field                    Change               
====================================================================== 
03-01-08 05:14  pj             Note Added: 0083223                          
======================================================================