Dec 21 15:03:53 DEBUG[20281] chan_sip.c: Setting NAT on RTP to 524288
Dec 21 15:03:53 DEBUG[20281] chan_sip.c: Stopping retransmission on 'eebc1a5e-ae9381b0-ddbe5d1@69.17.161.38' of Response 1: Match Found
Dec 21 15:03:53 DEBUG[20281] chan_sip.c: Setting NAT on RTP to 524288
Dec 21 15:03:53 DEBUG[20281] chan_sip.c: Checking SIP call limits for device 201
Dec 21 15:03:53 DEBUG[20281] chan_sip.c: build_route: Contact hop: <sip:201@69.17.161.38:5070>
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing Set("SIP/201-b7901d98", "_FORCE_PAGE=1") in new stack
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing Macro("SIP/201-b7901d98", "user-callerid|") in new stack
Dec 21 15:03:53 DEBUG[30870] pbx.c: Expression result is '0'
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing GotoIf("SIP/201-b7901d98", "0?report") in new stack
Dec 21 15:03:53 DEBUG[30870] pbx.c: Not taking any branch
Dec 21 15:03:53 DEBUG[30870] pbx.c: Expression result is '0'
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing GotoIf("SIP/201-b7901d98", "0?start") in new stack
Dec 21 15:03:53 DEBUG[30870] pbx.c: Not taking any branch
Dec 21 15:03:53 DEBUG[30870] pbx.c: Function result is '201'
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing Set("SIP/201-b7901d98", "REALCALLERIDNUM=201") in new stack
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing NoOp("SIP/201-b7901d98", "REALCALLERIDNUM is 201") in new stack
Dec 21 15:03:53 DEBUG[30870] pbx.c: Function result is '201'
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing Set("SIP/201-b7901d98", "AMPUSER=201") in new stack
Dec 21 15:03:53 DEBUG[30870] pbx.c: Function result is 'Base Test'
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing Set("SIP/201-b7901d98", "AMPUSERCIDNAME=Base Test") in new stack
Dec 21 15:03:53 DEBUG[30870] pbx.c: Expression result is '0'
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing GotoIf("SIP/201-b7901d98", "0?report") in new stack
Dec 21 15:03:53 DEBUG[30870] pbx.c: Not taking any branch
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing Set("SIP/201-b7901d98", "CALLERID(all)=Base Test <201>") in new stack
Dec 21 15:03:53 DEBUG[30870] pbx.c: Function result is '201'
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing Set("SIP/201-b7901d98", "REALCALLERIDNUM=201") in new stack
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing NoOp("SIP/201-b7901d98", "TTL:  ARG1: ") in new stack
Dec 21 15:03:53 DEBUG[30870] pbx.c: Expression result is '0'
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing GotoIf("SIP/201-b7901d98", "0?continue") in new stack
Dec 21 15:03:53 DEBUG[30870] pbx.c: Not taking any branch
Dec 21 15:03:53 DEBUG[30870] pbx.c: Expression result is '1'
Dec 21 15:03:53 DEBUG[30870] pbx.c: Expression result is '-1'
Dec 21 15:03:53 DEBUG[30870] pbx.c: Function result is '64'
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing Set("SIP/201-b7901d98", "_TTL=64") in new stack
Dec 21 15:03:53 DEBUG[30870] pbx.c: Expression result is '1'
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing GotoIf("SIP/201-b7901d98", "1?continue") in new stack
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Goto (macro-user-callerid,s,20)
Dec 21 15:03:53 DEBUG[30870] pbx.c: Function result is '"Base Test" <201>'
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing NoOp("SIP/201-b7901d98", "Using CallerID "Base Test" <201>") in new stack
Dec 21 15:03:53 WARNING[30870] pbx.c: No application 'Page' for extension (from-internal, 610, 3)
Dec 21 15:03:53 VERBOSE[30870] logger.c:   == Spawn extension (from-internal, 610, 3) exited non-zero on 'SIP/201-b7901d98'
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing Macro("SIP/201-b7901d98", "hangupcall") in new stack
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing ResetCDR("SIP/201-b7901d98", "w") in new stack
Dec 21 15:03:53 DEBUG[30870] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Dec 21 15:03:53 DEBUG[30870] 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) VALUES ('2006-12-21 15:03:53','\"Base Test\" <201>','201','610','from-internal', 'SIP/201-b7901d98','','ResetCDR','w',0,0,'NO ANSWER',3,'aci')
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing NoCDR("SIP/201-b7901d98", "") in new stack
Dec 21 15:03:53 NOTICE[30870] cdr.c: CDR on channel 'SIP/201-b7901d98' not posted
Dec 21 15:03:53 NOTICE[30870] cdr.c: CDR on channel 'SIP/201-b7901d98' lacks end
Dec 21 15:03:53 DEBUG[30870] pbx.c: Expression result is '1'
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing GotoIf("SIP/201-b7901d98", "1?theend") in new stack
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Goto (macro-hangupcall,s,6)
Dec 21 15:03:53 VERBOSE[30870] logger.c:     -- Executing Wait("SIP/201-b7901d98", "5") in new stack
Dec 21 15:03:58 VERBOSE[30870] logger.c:     -- Executing Hangup("SIP/201-b7901d98", "") in new stack
Dec 21 15:03:58 VERBOSE[30870] logger.c:   == Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/201-b7901d98' in macro 'hangupcall'
Dec 21 15:03:58 VERBOSE[30870] logger.c:   == Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/201-b7901d98'
Dec 21 15:03:58 DEBUG[30870] chan_sip.c: update_call_counter(201) - decrement call limit counter
Dec 21 15:03:58 DEBUG[30870] chan_sip.c: AST hangup cause 16 (no match found in SIP)
Dec 21 15:03:58 DEBUG[20281] chan_sip.c: Stopping retransmission on 'eebc1a5e-ae9381b0-ddbe5d1@69.17.161.38' of Response 2: Match Found