May 23 17:33:36 VERBOSE[2410] logger.c: -- Remote UNIX connection
May 23 17:33:40 DEBUG[2422] chan_sip.c: Setting NAT on RTP to 0
May 23 17:33:40 DEBUG[2422] chan_sip.c: Stopping retransmission on '3E160D84-4B21-471A-8979-0F7C5AD6FCCA@192.168.1.3' of Response 16968: Match Found
May 23 17:33:40 DEBUG[2422] chan_sip.c: Setting NAT on RTP to 0
May 23 17:33:40 DEBUG[2422] chan_sip.c: Checking SIP call limits for device 1000
May 23 17:33:40 DEBUG[2422] chan_sip.c: build_route: Contact hop:
May 23 17:33:40 VERBOSE[25848] logger.c: -- Executing Macro("SIP/1000-bfb1", "dialout-trunk|1|9042482006||") in new stack
May 23 17:33:40 DEBUG[25848] pbx.c: Expression result is '1'
May 23 17:33:40 VERBOSE[25848] logger.c: -- Executing GotoIf("SIP/1000-bfb1", "1?3:2") in new stack
May 23 17:33:40 VERBOSE[25848] logger.c: -- Goto (macro-dialout-trunk,s,3)
May 23 17:33:40 VERBOSE[25848] logger.c: -- Executing Macro("SIP/1000-bfb1", "user-callerid") in new stack
May 23 17:33:40 DEBUG[25848] pbx.c: Function result is '1000'
May 23 17:33:40 DEBUG[25848] pbx.c: Function result is '1000'
May 23 17:33:40 VERBOSE[25848] logger.c: -- Executing Set("SIP/1000-bfb1", "AMPUSER=1000") in new stack
May 23 17:33:40 DEBUG[25848] pbx.c: Function result is '1000'
May 23 17:33:40 DEBUG[25848] db.c: Unable to find key '1000/emergency_cid' in family 'DEVICE'
May 23 17:33:40 DEBUG[25848] func_db.c: DB: DEVICE/1000/emergency_cid not found in database.
May 23 17:33:40 DEBUG[25848] pbx.c: Function result is ''
May 23 17:33:40 VERBOSE[25848] logger.c: -- Executing Set("SIP/1000-bfb1", "EMERGENCYCID=") in new stack
May 23 17:33:40 DEBUG[25848] pbx.c: Function result is 'Eric Sineath'
May 23 17:33:40 VERBOSE[25848] logger.c: -- Executing Set("SIP/1000-bfb1", "AMPUSERCIDNAME=Eric Sineath") in new stack
May 23 17:33:40 DEBUG[25848] pbx.c: Expression result is '0'
May 23 17:33:40 VERBOSE[25848] logger.c: -- Executing GotoIf("SIP/1000-bfb1", "0?6") in new stack
May 23 17:33:40 DEBUG[25848] pbx.c: Not taking any branch
May 23 17:33:40 VERBOSE[25848] logger.c: -- Executing Set("SIP/1000-bfb1", "CALLERID(all)="Eric Sineath" <1000>") in new stack
May 23 17:33:40 DEBUG[25848] pbx.c: Function result is '"Eric Sineath" <1000>'
May 23 17:33:40 VERBOSE[25848] logger.c: -- Executing NoOp("SIP/1000-bfb1", "Using CallerID "Eric Sineath" <1000>") in new stack
May 23 17:33:40 DEBUG[25848] pbx.c: Function result is '1000'
May 23 17:33:40 VERBOSE[25848] logger.c: -- Executing Macro("SIP/1000-bfb1", "record-enable|1000|OUT") in new stack
May 23 17:33:40 DEBUG[25848] pbx.c: Function result is '0'
May 23 17:33:40 VERBOSE[25848] logger.c: -- Executing GotoIf("SIP/1000-bfb1", "0 > 0?2:4") in new stack
May 23 17:33:40 VERBOSE[25848] logger.c: -- Goto (macro-record-enable,s,4)
May 23 17:33:40 VERBOSE[25848] logger.c: -- Executing AGI("SIP/1000-bfb1", "recordingcheck|20060523-173340|1148420020.25") in new stack
May 23 17:33:40 VERBOSE[25848] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 23 17:33:42 VERBOSE[25848] logger.c: recordingcheck|20060523-173340|1148420020.25: Outbound recording not enabled
May 23 17:33:42 VERBOSE[25848] logger.c: -- AGI Script recordingcheck completed, returning 0
May 23 17:33:42 VERBOSE[25848] logger.c: -- Executing NoOp("SIP/1000-bfb1", "No recording needed") in new stack
May 23 17:33:42 VERBOSE[25848] logger.c: -- Executing Macro("SIP/1000-bfb1", "outbound-callerid|1") in new stack
May 23 17:33:42 DEBUG[25848] pbx.c: Function result is '1000'
May 23 17:33:42 DEBUG[25848] pbx.c: Function result is ''
May 23 17:33:42 VERBOSE[25848] logger.c: -- Executing Set("SIP/1000-bfb1", "USEROUTCID=") in new stack
May 23 17:33:42 DEBUG[25848] pbx.c: Expression result is '0'
May 23 17:33:42 VERBOSE[25848] logger.c: -- Executing GotoIf("SIP/1000-bfb1", "0?4") in new stack
May 23 17:33:42 DEBUG[25848] pbx.c: Not taking any branch
May 23 17:33:42 VERBOSE[25848] logger.c: -- Executing Set("SIP/1000-bfb1", "CALLERID(all)=9042138001") in new stack
May 23 17:33:42 DEBUG[25848] pbx.c: Expression result is '1'
May 23 17:33:42 VERBOSE[25848] logger.c: -- Executing GotoIf("SIP/1000-bfb1", "1?6") in new stack
May 23 17:33:42 VERBOSE[25848] logger.c: -- Goto (macro-outbound-callerid,s,6)
May 23 17:33:42 DEBUG[25848] pbx.c: Expression result is '1'
May 23 17:33:42 VERBOSE[25848] logger.c: -- Executing GotoIf("SIP/1000-bfb1", "1?8") in new stack
May 23 17:33:42 VERBOSE[25848] logger.c: -- Goto (macro-outbound-callerid,s,8)
May 23 17:33:42 DEBUG[25848] pbx.c: Function result is '"" <9042138001>'
May 23 17:33:42 VERBOSE[25848] logger.c: -- Executing NoOp("SIP/1000-bfb1", "CallerID set to "" <9042138001>") in new stack
May 23 17:33:42 VERBOSE[25848] logger.c: -- Executing Set("SIP/1000-bfb1", "GROUP()=OUT_1") in new stack
May 23 17:33:42 DEBUG[25848] pbx.c: Function result is '1'
May 23 17:33:42 DEBUG[25848] pbx.c: Expression result is '0'
May 23 17:33:42 VERBOSE[25848] logger.c: -- Executing GotoIf("SIP/1000-bfb1", "0?108") in new stack
May 23 17:33:42 DEBUG[25848] pbx.c: Not taking any branch
May 23 17:33:42 VERBOSE[25848] logger.c: -- Executing Set("SIP/1000-bfb1", "DIAL_NUMBER=9042482006") in new stack
May 23 17:33:42 VERBOSE[25848] logger.c: -- Executing Set("SIP/1000-bfb1", "DIAL_TRUNK=1") in new stack
May 23 17:33:42 VERBOSE[25848] logger.c: -- Executing AGI("SIP/1000-bfb1", "fixlocalprefix") in new stack
May 23 17:33:42 VERBOSE[25848] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
May 23 17:33:44 VERBOSE[25848] logger.c: fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf
May 23 17:33:44 VERBOSE[25848] logger.c: -- AGI Script fixlocalprefix completed, returning 0
May 23 17:33:44 VERBOSE[25848] logger.c: -- Executing Set("SIP/1000-bfb1", "OUTNUM=9042482006") in new stack
May 23 17:33:44 DEBUG[25848] pbx.c: Function result is 'ZAP/1'
May 23 17:33:44 VERBOSE[25848] logger.c: -- Executing Set("SIP/1000-bfb1", "custom=ZAP/1") in new stack
May 23 17:33:44 DEBUG[25848] pbx.c: Expression result is '0'
May 23 17:33:44 VERBOSE[25848] logger.c: -- Executing GotoIf("SIP/1000-bfb1", "0?16") in new stack
May 23 17:33:44 DEBUG[25848] pbx.c: Not taking any branch
May 23 17:33:44 VERBOSE[25848] logger.c: -- Executing Dial("SIP/1000-bfb1", "ZAP/1/9042482006|120|W") in new stack
May 23 17:33:44 DEBUG[25848] chan_zap.c: Dialing '9042482006'
May 23 17:33:44 DEBUG[25848] chan_zap.c: Deferring dialing...
May 23 17:33:44 VERBOSE[25848] logger.c: -- Called 1/9042482006
May 23 17:33:45 DEBUG[25848] chan_zap.c: Exception on 14, channel 1
May 23 17:33:45 DEBUG[25848] chan_zap.c: Got event Hook Transition Complete(12) on channel 1 (index 0)
May 23 17:33:47 DEBUG[25848] chan_zap.c: Exception on 14, channel 1
May 23 17:33:47 DEBUG[25848] chan_zap.c: Got event Dial Complete(9) on channel 1 (index 0)
May 23 17:33:47 DEBUG[25848] chan_zap.c: Enabled echo cancellation on channel 1
May 23 17:33:47 DEBUG[25848] chan_zap.c: Engaged echo training on channel 1
May 23 17:33:50 DEBUG[25848] chan_zap.c: Exception on 14, channel 1
May 23 17:33:50 DEBUG[25848] chan_zap.c: Got event Dial Complete(9) on channel 1 (index 0)
May 23 17:33:50 DEBUG[25848] chan_zap.c: Echo cancellation already on
May 23 17:33:50 VERBOSE[25848] logger.c: -- Zap/1-1 answered SIP/1000-bfb1
May 23 17:33:50 DEBUG[2422] chan_sip.c: Stopping retransmission on '3E160D84-4B21-471A-8979-0F7C5AD6FCCA@192.168.1.3' of Response 16969: Match Found
May 23 17:33:58 DEBUG[25848] channel.c: Didn't get a frame from channel: SIP/1000-bfb1
May 23 17:33:58 DEBUG[25848] channel.c: Bridge stops bridging channels SIP/1000-bfb1 and Zap/1-1
May 23 17:33:58 DEBUG[25848] chan_zap.c: Hangup: channel: 1 index = 0, normal = 14, callwait = -1, thirdcall = -1
May 23 17:33:58 DEBUG[25848] chan_zap.c: disabled echo cancellation on channel 1
May 23 17:33:58 DEBUG[25848] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
May 23 17:33:58 DEBUG[25848] chan_zap.c: Updated conferencing on 1, with 0 conference users
May 23 17:33:58 VERBOSE[25848] logger.c: -- Hungup 'Zap/1-1'
May 23 17:33:58 DEBUG[25848] app_dial.c: Exiting with DIALSTATUS=ANSWER.
May 23 17:33:58 VERBOSE[25848] logger.c: == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on 'SIP/1000-bfb1' in macro 'dialout-trunk'
May 23 17:33:58 VERBOSE[25848] logger.c: == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on 'SIP/1000-bfb1'
May 23 17:33:58 DEBUG[25848] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
May 23 17:33:58 DEBUG[25848] 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-05-23 17:33:40','9042138001','9042138001','9042482006','from-internal', 'SIP/1000-bfb1','Zap/1-1','Dial','ZAP/1/9042482006|120|W',18,8,'ANSWERED',3,'','1148420020.25')
May 23 17:33:58 DEBUG[25848] chan_sip.c: update_call_counter(1000) - decrement call limit counter
May 23 17:34:24 VERBOSE[25847] logger.c: -- Remote UNIX connection disconnected
May 23 17:34:49 DEBUG[25780] manager.c: Manager received command 'Command'
May 23 17:34:49 DEBUG[25780] manager.c: Manager received command 'Command'
May 23 17:36:49 DEBUG[25780] manager.c: Manager received command 'Command'
May 23 17:36:49 DEBUG[25780] manager.c: Manager received command 'Command'
May 23 17:36:49 DEBUG[25780] manager.c: Manager received command 'Command'