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

[noreply at bugs.digium.com]

A NOTE has been added to this issue. 
====================================================================== 
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:              12-14-2007 13:24 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 - 12-14-07 13:24  
---------------------------------------------------------------------- 
some console output from today's 'proding channel...' issue, it's repeating
many times.
yes, I'm using ztdummy as timming source and have in asterisk.conf
[options]
internal_timing = yes
it's pure voip gateway, without real isdn hardware.


[Dec 14 10:02:19]     -- Executing [555 at from-pj-test:2]
MusicOnHold("SIP/777-082ae2f8", "") in new stack
[Dec 14 10:02:19] WARNING[4145]: channel.c:2723 ast_prod: Prodding channel
'SIP/777-082ae2f8' failed
[Dec 14 10:02:19]     -- Started music on hold, class 'default', on
SIP/777-082ae2f8
[Dec 14 10:02:19]     -- Stopped music on hold on SIP/777-082ae2f8
[Dec 14 10:02:19]   == Spawn extension (from-pj-test, 555, 2) exited
non-zero on 'SIP/777-082ae2f8'
[Dec 14 10:02:19]     -- Executing [555 at from-pj-test:2]
MusicOnHold("SIP/777-082ae2f8", "") in new stack
[Dec 14 10:02:19] WARNING[4145]: channel.c:2723 ast_prod: Prodding channel
'SIP/777-082ae2f8' failed
[Dec 14 10:02:19]     -- Started music on hold, class 'default', on
SIP/777-082ae2f8
[Dec 14 10:02:19]     -- Stopped music on hold on SIP/777-082ae2f8
[Dec 14 10:02:19]   == Spawn extension (from-pj-test, 555, 2) exited
non-zero on 'SIP/777-082ae2f8'
[Dec 14 10:02:19]     -- Executing [555 at from-pj-test:2]
MusicOnHold("SIP/777-082ae2f8", "") in new stack 

Issue History 
Date Modified   Username       Field                    Change               
====================================================================== 
12-14-07 13:24  pj             Note Added: 0075411                          
======================================================================