Oct 23 16:41:14 DEBUG[26242] chan_zap.c: Monitor doohicky got event Ring Begin on channel 1
Oct 23 16:41:15 DEBUG[26242] chan_zap.c: Monitor doohicky got event Ring/Answered on channel 1
Oct 23 16:41:15 DEBUG[26242] dsp.c: dsp busy pattern set to 0,0
Oct 23 16:41:15 VERBOSE[12467] logger.c: -- Starting simple switch on 'Zap/1-1'
Oct 23 16:41:15 DEBUG[12468] app_queue.c: Device 'Zap/1' changed to state '2' (In use) but we don't care because they're not a member of any queue.
Oct 23 16:41:16 DEBUG[12467] chan_zap.c: CallerID number: XXXXXXXX, name: (null), flags=4
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'NoOp'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing NoOp("Zap/1-1", "Entering from-zaptel with DID == ") in new stack
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'Ringing'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing Ringing("Zap/1-1", "") in new stack
Oct 23 16:41:16 DEBUG[12467] chan_zap.c: Requested indication 3 on channel Zap/1-1
Oct 23 16:41:16 DEBUG[12467] pbx.c: Expression result is '1'
Oct 23 16:41:16 DEBUG[12467] pbx.c: Function result is 's'
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'Set'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing Set("Zap/1-1", "DID=s") in new stack
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'NoOp'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing NoOp("Zap/1-1", "DID is now s") in new stack
Oct 23 16:41:16 DEBUG[12467] pbx.c: Expression result is '1'
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'GotoIf'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing GotoIf("Zap/1-1", "1?zapok:notzap") in new stack
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Goto (from-zaptel,s,8)
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'NoOp'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing NoOp("Zap/1-1", "Is a Zaptel Channel") in new stack
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'Set'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing Set("Zap/1-1", "CHAN=1-1") in new stack
Oct 23 16:41:16 DEBUG[12467] pbx.c: Function result is '1'
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'Set'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing Set("Zap/1-1", "CHAN=1") in new stack
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'Macro'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing Macro("Zap/1-1", "from-zaptel-1|s|1") in new stack
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'NoOp'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing NoOp("Zap/1-1", "Entering macro-from-zaptel-1 with DID = s") in new stack
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'Set'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing Set("Zap/1-1", "FROM_DID=s") in new stack
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'Set'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing Set("Zap/1-1", "FAX_RX=disabled") in new stack
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'Goto'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing Goto("Zap/1-1", "timeconditions|1|1") in new stack
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Goto (timeconditions,1,1)
Oct 23 16:41:16 VERBOSE[12467] logger.c: == Channel 'Zap/1-1' jumping out of macro 'from-zaptel-1'
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'GotoIfTime'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing GotoIfTime("Zap/1-1", "10:00-20:00|sun-thu|*|*?ivr-5|s|1") in new stack
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Goto (ivr-5,s,1)
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'Set'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing Set("Zap/1-1", "LOOPCOUNT=0") in new stack
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'Set'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing Set("Zap/1-1", "__DIR-CONTEXT=default") in new stack
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'Answer'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing Answer("Zap/1-1", "") in new stack
Oct 23 16:41:16 DEBUG[12467] chan_zap.c: Took Zap/1-1 off hook
Oct 23 16:41:16 DEBUG[12467] chan_zap.c: Enabled echo cancellation on channel 1
Oct 23 16:41:16 DEBUG[12467] chan_zap.c: Engaged echo training on channel 1
Oct 23 16:41:16 DEBUG[12467] pbx.c: Launching 'Wait'
Oct 23 16:41:16 VERBOSE[12467] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Oct 23 16:41:16 DEBUG[12469] app_queue.c: Device 'Zap/1' changed to state '2' (In use) but we don't care because they're not a member of any queue.
Oct 23 16:41:17 DEBUG[12467] pbx.c: Launching 'Set'
Oct 23 16:41:17 VERBOSE[12467] logger.c: -- Executing Set("Zap/1-1", "TIMEOUT(digit)=3") in new stack
Oct 23 16:41:17 VERBOSE[12467] logger.c: -- Digit timeout set to 3
Oct 23 16:41:17 DEBUG[12467] pbx.c: Launching 'Set'
Oct 23 16:41:17 VERBOSE[12467] logger.c: -- Executing Set("Zap/1-1", "TIMEOUT(response)=8") in new stack
Oct 23 16:41:17 VERBOSE[12467] logger.c: -- Response timeout set to 8
Oct 23 16:41:17 DEBUG[12467] pbx.c: Launching 'BackGround'
Oct 23 16:41:17 VERBOSE[12467] logger.c: -- Executing BackGround("Zap/1-1", "custom/MainDay") in new stack
Oct 23 16:41:17 DEBUG[12467] channel.c: Set channel Zap/1-1 to write format slin
Oct 23 16:41:17 DEBUG[12467] channel.c: Scheduling timer at 160 sample intervals
Oct 23 16:41:17 VERBOSE[12467] logger.c: -- Playing 'custom/MainDay' (language 'he')
Oct 23 16:41:33 DEBUG[12467] dsp.c: ast_dsp_busydetect detected busy, avgtone: 500, avgsilence 500
Oct 23 16:41:33 DEBUG[12467] dsp.c: Requesting Hangup because the busy tone was detected on channel Zap/1-1
Oct 23 16:41:33 DEBUG[12467] channel.c: Scheduling timer at 0 sample intervals
Oct 23 16:41:33 DEBUG[12467] channel.c: Set channel Zap/1-1 to write format ulaw
Oct 23 16:41:33 DEBUG[12467] pbx.c: Spawn extension (ivr-5,s,7) exited non-zero on 'Zap/1-1'
Oct 23 16:41:33 DEBUG[12467] pbx.c: Launching 'Hangup'
Oct 23 16:41:33 VERBOSE[12467] logger.c: -- Executing Hangup("Zap/1-1", "") in new stack
Oct 23 16:41:33 DEBUG[12467] pbx.c: Spawn extension (ivr-5,h,1) exited non-zero on 'Zap/1-1'
Oct 23 16:41:33 DEBUG[12467] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Oct 23 16:41:33 DEBUG[12467] 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 ('2006-10-23 16:41:16','XXXXXXXX','XXXXXXXX','s','ivr-5', 'Zap/1-1','','Hangup','',17,17,'ANSWERED',3,'','1161614475.205')
Oct 23 16:41:33 DEBUG[12467] channel.c: Hanging up channel 'Zap/1-1'
Oct 23 16:41:33 DEBUG[12467] chan_zap.c: zt_hangup(Zap/1-1)
Oct 23 16:41:33 DEBUG[12467] chan_zap.c: Hangup: channel: 1 index = 0, normal = 15, callwait = -1, thirdcall = -1
Oct 23 16:41:33 DEBUG[12467] chan_zap.c: disabled echo cancellation on channel 1
Oct 23 16:41:33 DEBUG[12467] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
Oct 23 16:41:33 DEBUG[12467] chan_zap.c: Updated conferencing on 1, with 0 conference users
Oct 23 16:41:33 VERBOSE[12467] logger.c: -- Hungup 'Zap/1-1'
Oct 23 16:41:33 DEBUG[12494] app_queue.c: Device 'Zap/1' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.