[asterisk-users] call not routed

[Balu Raman]

After a power interruption, asterisk doesn't seem to be routing calls and
there seems to be a premature timeout and hangups occurring. I am clueless
where to look. Can someone in the know, look at the following log and
enlighten me if there's a problem, or if it looks normal. From the calling
phone, it keeps ringing as if never picked up.
Thanks soo much.
-braman
================================================== xxxxxxxxxx=calling number
x'ed out for confidentiality prupose======
Mar 25 09:48:13 VERBOSE[3685] logger.c: -- Starting simple switch on
'Zap/4-1'
Mar 25 09:48:14 DEBUG[3685] pbx.c: Function result is '"DEWOLFE ENG"
<xxxxxxxxxx>'
Mar 25 09:48:14 VERBOSE[3685] logger.c: -- Executing NoOp("Zap/4-1",
">>>>>>>>> CallerID="DEWOLFE ENG" <xxxxxxxxxx>") in new stack
Mar 25 09:48:14 VERBOSE[3685] logger.c: -- Executing Answer("Zap/4-1", "")
in new stack
Mar 25 09:48:14 DEBUG[3685] chan_zap.c: Took Zap/4-1 off hook
Mar 25 09:48:14 DEBUG[3685] chan_zap.c: Enabled echo cancellation on channel
4
Mar 25 09:48:14 DEBUG[3685] chan_zap.c: Engaged echo training on channel 4
Mar 25 09:48:14 VERBOSE[3685] logger.c: -- Executing PlayTones("Zap/4-1",
"ring") in new stack
Mar 25 09:48:14 DEBUG[3685] channel.c: Scheduling timer at 160 sample
intervals
Mar 25 09:48:14 VERBOSE[3685] logger.c: -- Executing NVFaxDetect("Zap/4-1",
"6") in new stack
Mar 25 09:48:14 DEBUG[3685] app_nv_faxdetect.c: Preparing detect of fax
(waitdur=6ms, sildur=1000ms, mindur=100ms, maxdur=-1ms)
Mar 25 09:48:14 DEBUG[3685] channel.c: Generator got voice, switching to
phase locked mode
Mar 25 09:48:14 DEBUG[3685] channel.c: Scheduling timer at 0 sample
intervals
Mar 25 09:48:14 DEBUG[3685] app_nv_faxdetect.c: Start of voice token!
Mar 25 09:48:15 DEBUG[3685] app_nv_faxdetect.c: Found unqualified token of 0
ms
Mar 25 09:48:15 DEBUG[3685] app_nv_faxdetect.c: Start of voice token!
Mar 25 09:48:21 VERBOSE[3685] logger.c: -- Executing Goto("Zap/4-1",
"timeconditions|1|1") in new stack
Mar 25 09:48:21 VERBOSE[3685] logger.c: -- Goto (timeconditions,1,1)
Mar 25 09:48:21 VERBOSE[3685] logger.c: -- Executing GotoIfTime("Zap/4-1",
"08:00-12:00|mon-fri|*|*?ivr-4|s|1") in new stack
Mar 25 09:48:21 VERBOSE[3685] logger.c: -- Goto (ivr-4,s,1)
Mar 25 09:48:21 VERBOSE[3685] logger.c: -- Executing Set("Zap/4-1",
"LOOPCOUNT=0") in new stack
Mar 25 09:48:21 VERBOSE[3685] logger.c: -- Executing Answer("Zap/4-1", "")
in new stack
Mar 25 09:48:21 VERBOSE[3685] logger.c: -- Executing Wait("Zap/4-1", "1") in
new stack
Mar 25 09:48:22 VERBOSE[3685] logger.c: -- Executing Set("Zap/4-1",
"TIMEOUT(digit)=10") in new stack
Mar 25 09:48:22 VERBOSE[3685] logger.c: -- Digit timeout set to 10
Mar 25 09:48:22 VERBOSE[3685] logger.c: -- Executing Set("Zap/4-1",
"TIMEOUT(response)=10") in new stack
Mar 25 09:48:22 VERBOSE[3685] logger.c: -- Response timeout set to 10
Mar 25 09:48:22 VERBOSE[3685] logger.c: -- Executing BackGround("Zap/4-1",
"custom/RyderBrookWelcome") in new stack
Mar 25 09:48:22 DEBUG[3685] channel.c: Scheduling timer at 0 sample
intervals
Mar 25 09:48:22 DEBUG[3685] channel.c: Scheduling timer at 160 sample
intervals
Mar 25 09:48:22 VERBOSE[3685] logger.c: -- Playing
'custom/RyderBrookWelcome' (language 'en')
Mar 25 09:48:42 DEBUG[3685] channel.c: Scheduling timer at 58 sample
intervals
Mar 25 09:48:42 DEBUG[3685] channel.c: Scheduling timer at 0 sample
intervals
Mar 25 09:48:42 DEBUG[3685] channel.c: Scheduling timer at 0 sample
intervals
Mar 25 09:48:53 VERBOSE[3685] logger.c: -- Timeout on Zap/4-1
Mar 25 09:48:53 VERBOSE[3685] logger.c: == CDR updated on Zap/4-1
Mar 25 09:48:53 VERBOSE[3685] logger.c: -- Executing Goto("Zap/4-1",
"loop|1") in new stack
Mar 25 09:48:53 VERBOSE[3685] logger.c: -- Goto (ivr-4,loop,1)
Mar 25 09:48:53 DEBUG[3685] pbx.c: Expression result is '1'
Mar 25 09:48:53 VERBOSE[3685] logger.c: -- Executing Set("Zap/4-1",
"LOOPCOUNT=1") in new stack
Mar 25 09:48:53 DEBUG[3685] pbx.c: Expression result is '0'
Mar 25 09:48:53 VERBOSE[3685] logger.c: -- Executing GotoIf("Zap/4-1",
"0?hang|1") in new stack
Mar 25 09:48:53 DEBUG[3685] pbx.c: Not taking any branch
Mar 25 09:48:53 VERBOSE[3685] logger.c: -- Executing Goto("Zap/4-1",
"ivr-4|s|begin") in new stack
Mar 25 09:48:53 VERBOSE[3685] logger.c: -- Goto (ivr-4,s,4)
Mar 25 09:48:53 VERBOSE[3685] logger.c: -- Executing Set("Zap/4-1",
"TIMEOUT(digit)=10") in new stack
Mar 25 09:48:53 VERBOSE[3685] logger.c: -- Digit timeout set to 10
Mar 25 09:48:53 VERBOSE[3685] logger.c: -- Executing Set("Zap/4-1",
"TIMEOUT(response)=10") in new stack
Mar 25 09:48:53 VERBOSE[3685] logger.c: -- Response timeout set to 10
Mar 25 09:48:53 VERBOSE[3685] logger.c: -- Executing BackGround("Zap/4-1",
"custom/RyderBrookWelcome") in new stack
Mar 25 09:48:53 DEBUG[3685] channel.c: Scheduling timer at 160 sample
intervals
Mar 25 09:48:53 VERBOSE[3685] logger.c: -- Playing
'custom/RyderBrookWelcome' (language 'en')
Mar 25 09:48:59 DEBUG[3685] chan_zap.c: Exception on 13, channel 4
Mar 25 09:48:59 DEBUG[3685] chan_zap.c: Got event On hook(1) on channel 4
(index 0)
Mar 25 09:48:59 DEBUG[3685] chan_zap.c: disabled echo cancellation on
channel 4
Mar 25 09:48:59 DEBUG[3685] channel.c: Scheduling timer at 0 sample
intervals
Mar 25 09:48:59 VERBOSE[3685] logger.c: == Spawn extension (ivr-4, s, 6)
exited non-zero on 'Zap/4-1'
Mar 25 09:48:59 VERBOSE[3685] logger.c: -- Executing Hangup("Zap/4-1", "")
in new stack
Mar 25 09:48:59 VERBOSE[3685] logger.c: == Spawn extension (ivr-4, h, 1)
exited non-zero on 'Zap/4-1'
Mar 25 09:48:59 DEBUG[3685] cdr_addon_mysql.c: cdr_mysql: inserting a CDR
record.
Mar 25 09:48:59 DEBUG[3685] cdr_addon_mysql.c: cdr_mysql: SQL command as
follows: INSERT INTO cdr
(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid)
VALUES ('2010-03-25 09:48:14','\"DEWOLFE ENG\"
<xxxxxxxxxx>','xxxxxxxxxx','s','ivr-4',
'Zap/4-1','','Hangup','',45,45,'ANSWERED',3,'','1269524893.14')
Mar 25 09:48:59 DEBUG[3685] chan_zap.c: Hangup: channel: 4 index = 0, normal
= 13, callwait = -1, thirdcall = -1
Mar 25 09:48:59 DEBUG[3685] chan_zap.c: disabled echo cancellation on
channel 4
Mar 25 09:48:59 DEBUG[3685] chan_zap.c: Set option TDD MODE, value: OFF(0)
on Zap/4-1
Mar 25 09:48:59 DEBUG[3685] chan_zap.c: Updated conferencing on 4, with 0
conference users
Mar 25 09:48:59 VERBOSE[3685] logger.c: -- Hungup 'Zap/4-1'
==============================================================
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20100325/badae081/attachment.htm