Nov 13 10:55:13 DEBUG[2630] chan_sip.c: Setting NAT on RTP to 0
Nov 13 10:55:13 DEBUG[2630] chan_sip.c: Checking SIP call limits for device oceanetworks-15812553
Nov 13 10:55:13 DEBUG[2630] chan_sip.c: build_route: Contact hop:
Nov 13 10:55:13 VERBOSE[3706] logger.c: -- Executing NoOp("SIP/oceanetworks-15812553-08857db0", "Catch-All DID Match - Found 9492816656 - You probably want a DID for this.") in new stack
Nov 13 10:55:13 VERBOSE[3706] logger.c: -- Executing Goto("SIP/oceanetworks-15812553-08857db0", "ext-did|s|1") in new stack
Nov 13 10:55:13 VERBOSE[3706] logger.c: -- Goto (ext-did,s,1)
Nov 13 10:55:13 VERBOSE[3706] logger.c: -- Executing Set("SIP/oceanetworks-15812553-08857db0", "FROM_DID=s") in new stack
Nov 13 10:55:13 VERBOSE[3706] logger.c: -- Executing Gosub("SIP/oceanetworks-15812553-08857db0", "app-blacklist-check|s|1") in new stack
Nov 13 10:55:13 VERBOSE[3706] logger.c: -- Executing LookupBlacklist("SIP/oceanetworks-15812553-08857db0", "") in new stack
Nov 13 10:55:13 DEBUG[3706] db.c: Unable to find key '+16264040945' in family 'blacklist'
Nov 13 10:55:13 DEBUG[3706] db.c: Unable to find key '+16264040945' in family 'blacklist'
Nov 13 10:55:13 DEBUG[3706] pbx.c: Expression result is '0'
Nov 13 10:55:13 VERBOSE[3706] logger.c: -- Executing GotoIf("SIP/oceanetworks-15812553-08857db0", "0?blacklisted") in new stack
Nov 13 10:55:13 DEBUG[3706] pbx.c: Not taking any branch
Nov 13 10:55:13 VERBOSE[3706] logger.c: -- Executing Return("SIP/oceanetworks-15812553-08857db0", "") in new stack
Nov 13 10:55:13 VERBOSE[3706] logger.c: -- Executing Answer("SIP/oceanetworks-15812553-08857db0", "") in new stack
Nov 13 10:55:13 VERBOSE[3706] logger.c: -- Executing PlayTones("SIP/oceanetworks-15812553-08857db0", "ring") in new stack
Nov 13 10:55:13 DEBUG[3706] channel.c: Scheduling timer at 160 sample intervals
Nov 13 10:55:13 VERBOSE[3706] logger.c: -- Executing NVFaxDetect("SIP/oceanetworks-15812553-08857db0", "3") in new stack
Nov 13 10:55:13 DEBUG[3706] app_nv_faxdetect.c: Preparing detect of fax (waitdur=3ms, sildur=1000ms, mindur=100ms, maxdur=-1ms)
Nov 13 10:55:13 DEBUG[2630] chan_sip.c: Stopping retransmission on '4c7f9f291090a67e2e5e0f9f534f7910@64.136.174.24' of Response 102: Match Found
Nov 13 10:55:14 DEBUG[2630] chan_sip.c: Stopping retransmission on '1248b19d78d2144809feebcf75bf023b@192.168.1.200' of Request 102: Match Found
Nov 13 10:55:14 DEBUG[3706] channel.c: Generator got voice, switching to phase locked mode
Nov 13 10:55:14 DEBUG[3706] channel.c: Scheduling timer at 0 sample intervals
Nov 13 10:55:14 DEBUG[3706] app_nv_faxdetect.c: Start of voice token!
Nov 13 10:55:14 DEBUG[3706] app_nv_faxdetect.c: Found unqualified token of 0 ms
Nov 13 10:55:16 DEBUG[2630] chan_sip.c: Auto destroying call '73c428264ce119f578be6ae1275347fa@127.0.0.1'
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Executing Goto("SIP/oceanetworks-15812553-08857db0", "timeconditions|1|1") in new stack
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Goto (timeconditions,1,1)
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Executing GotoIfTime("SIP/oceanetworks-15812553-08857db0", "08:00-17:00|mon-fri|*|*?ivr-3|s|1") in new stack
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Goto (ivr-3,s,1)
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Executing Set("SIP/oceanetworks-15812553-08857db0", "LOOPCOUNT=0") in new stack
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Executing Set("SIP/oceanetworks-15812553-08857db0", "__DIR-CONTEXT=default") in new stack
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Executing Set("SIP/oceanetworks-15812553-08857db0", "_IVR_CONTEXT_ivr-3=") in new stack
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Executing Set("SIP/oceanetworks-15812553-08857db0", "_IVR_CONTEXT=ivr-3") in new stack
Nov 13 10:55:17 DEBUG[3706] pbx.c: Function result is 'ANSWERED'
Nov 13 10:55:17 DEBUG[3706] pbx.c: Expression result is '1'
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Executing GotoIf("SIP/oceanetworks-15812553-08857db0", "1?begin") in new stack
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Goto (ivr-3,s,8)
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Executing Set("SIP/oceanetworks-15812553-08857db0", "TIMEOUT(digit)=3") in new stack
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Digit timeout set to 3
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Executing Set("SIP/oceanetworks-15812553-08857db0", "TIMEOUT(response)=10") in new stack
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Response timeout set to 10
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Executing BackGround("SIP/oceanetworks-15812553-08857db0", "custom/Main") in new stack
Nov 13 10:55:17 DEBUG[3706] channel.c: Scheduling timer at 0 sample intervals
Nov 13 10:55:17 DEBUG[3706] channel.c: Scheduling timer at 160 sample intervals
Nov 13 10:55:17 VERBOSE[3706] logger.c: -- Playing 'custom/Main' (language 'en')
Nov 13 10:55:20 DEBUG[3645] manager.c: Manager received command 'Command'
Nov 13 10:55:20 DEBUG[3645] manager.c: Manager received command 'Command'
Nov 13 10:55:20 DEBUG[3645] manager.c: Manager received command 'Command'
Nov 13 10:55:37 DEBUG[3706] channel.c: Scheduling timer at 44 sample intervals
Nov 13 10:55:37 DEBUG[3706] channel.c: Scheduling timer at 0 sample intervals
Nov 13 10:55:37 DEBUG[3706] channel.c: Scheduling timer at 0 sample intervals
Nov 13 10:55:40 VERBOSE[3706] logger.c: -- Executing Hangup("SIP/oceanetworks-15812553-08857db0", "") in new stack
Nov 13 10:55:40 VERBOSE[3706] logger.c: == Spawn extension (ivr-3, h, 1) exited non-zero on 'SIP/oceanetworks-15812553-08857db0'
Nov 13 10:55:40 DEBUG[3706] chan_sip.c: update_call_counter(oceanetworks-15812553) - decrement call limit counter
Nov 13 10:55:40 DEBUG[3706] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Nov 13 10:55:40 DEBUG[3706] 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 ('2007-11-13 10:55:13','\"+16264040945\" <+16264040945>','+16264040945','s','ivr-3', 'SIP/oceanetworks-15812553-08857db0','','BackGround','custom/Main',27,27,'ANSWERED',3,'','1194980113.12')
Nov 13 10:55:40 DEBUG[3706] 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 ('2007-11-13 10:55:13','\"+16264040945\" <+16264040945>','+16264040945','s','ivr-3', 'SIP/oceanetworks-15812553-08857db0','','BackGround','custom/Main',27,27,'ANSWERED',3,'','1194980113.12')