rendered paste bodySep 7 15:46:58 DEBUG[11609] acl.c: ##### Testing 69.47.46.168 with 10.0.1.0
Sep 7 15:46:58 DEBUG[11609] chan_sip.c: Target address 69.47.46.168 is not local, substituting externip
Sep 7 15:46:58 DEBUG[11609] chan_sip.c: Setting NAT on RTP to 524288
Sep 7 15:46:58 DEBUG[11609] chan_sip.c: Checking SIP call limits for device 1000
Sep 7 15:46:58 DEBUG[11609] chan_sip.c: build_route: Contact hop: <sip:1000@192.168.1.99>
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Macro("SIP/1000-090d7008", "exten-vm|206|206") in new stack
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Macro("SIP/1000-090d7008", "user-callerid") in new stack
Sep 7 15:46:58 DEBUG[11856] pbx.c: Expression result is '0'
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing GotoIf("SIP/1000-090d7008", "0?report") in new stack
Sep 7 15:46:58 DEBUG[11856] pbx.c: Not taking any branch
Sep 7 15:46:58 DEBUG[11856] pbx.c: Expression result is '0'
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing GotoIf("SIP/1000-090d7008", "0?start") in new stack
Sep 7 15:46:58 DEBUG[11856] pbx.c: Not taking any branch
Sep 7 15:46:58 DEBUG[11856] pbx.c: Function result is '1000'
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Set("SIP/1000-090d7008", "REALCALLERIDNUM=1000") in new stack
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing NoOp("SIP/1000-090d7008", "REALCALLERIDNUM is 1000") in new stack
Sep 7 15:46:58 DEBUG[11856] pbx.c: Function result is '1000'
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Set("SIP/1000-090d7008", "AMPUSER=1000") in new stack
Sep 7 15:46:58 DEBUG[11856] pbx.c: Function result is 'Richard'
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Set("SIP/1000-090d7008", "AMPUSERCIDNAME=Richard") in new stack
Sep 7 15:46:58 DEBUG[11856] pbx.c: Expression result is '0'
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing GotoIf("SIP/1000-090d7008", "0?report") in new stack
Sep 7 15:46:58 DEBUG[11856] pbx.c: Not taking any branch
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Set("SIP/1000-090d7008", "CALLERID(all)=Richard <1000>") in new stack
Sep 7 15:46:58 DEBUG[11856] pbx.c: Function result is '"Richard" <1000>'
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing NoOp("SIP/1000-090d7008", "Using CallerID "Richard" <1000>") in new stack
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Set("SIP/1000-090d7008", "FROMCONTEXT=exten-vm") in new stack
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Set("SIP/1000-090d7008", "VMBOX=206") in new stack
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Set("SIP/1000-090d7008", "EXTTOCALL=206") in new stack
Sep 7 15:46:58 DEBUG[11856] db.c: Unable to find key '206' in family 'CFU'
Sep 7 15:46:58 DEBUG[11856] func_db.c: DB: CFU/206 not found in database.
Sep 7 15:46:58 DEBUG[11856] pbx.c: Function result is ''
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Set("SIP/1000-090d7008", "CFUEXT=") in new stack
Sep 7 15:46:58 DEBUG[11856] pbx.c: Expression result is '1'
Sep 7 15:46:58 DEBUG[11856] pbx.c: Expression result is '0'
Sep 7 15:46:58 DEBUG[11856] pbx.c: Expression result is '1'
Sep 7 15:46:58 DEBUG[11856] pbx.c: Function result is '25'
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Set("SIP/1000-090d7008", "RT=25") in new stack
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Macro("SIP/1000-090d7008", "record-enable|206|IN") in new stack
Sep 7 15:46:58 DEBUG[11856] pbx.c: Function result is '0'
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing GotoIf("SIP/1000-090d7008", "0 > 0?2:4") in new stack
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Goto (macro-record-enable,s,4)
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing DeadAGI("SIP/1000-090d7008", "recordingcheck|20060907-154658|1157662018.17") in new stack
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Sep 7 15:46:58 VERBOSE[11856] logger.c: recordingcheck|20060907-154658|1157662018.17: Inbound recording not enabled
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- AGI Script recordingcheck completed, returning 0
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing NoOp("SIP/1000-090d7008", "No recording needed") in new stack
Sep 7 15:46:58 DEBUG[11856] pbx.c: Expression result is '0'
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing GotoIf("SIP/1000-090d7008", "0?dolocaldial|1") in new stack
Sep 7 15:46:58 DEBUG[11856] pbx.c: Not taking any branch
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Macro("SIP/1000-090d7008", "dial|25|tr|206") in new stack
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing AGI("SIP/1000-090d7008", "dialparties.agi") in new stack
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
Sep 7 15:46:58 VERBOSE[11856] logger.c: dialparties.agi: Starting New Dialparties.agi
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- dialparties.agi: priority is 1
Sep 7 15:46:58 VERBOSE[11856] logger.c: dialparties.agi: Caller ID name is 'Richard' number is '1000'
Sep 7 15:46:58 VERBOSE[11856] logger.c: dialparties.agi: Methodology of ring is 'none'
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- dialparties.agi: Added extension 206 to extension map
Sep 7 15:46:58 DEBUG[11856] db.c: Unable to find key '206' in family 'CF'
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- dialparties.agi: Extension 206 cf is disabled
Sep 7 15:46:58 DEBUG[11856] db.c: Unable to find key '206' in family 'DND'
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- dialparties.agi: Extension 206 do not disturb is disabled
Sep 7 15:46:58 DEBUG[11856] db.c: Unable to find key '206' in family 'CW'
Sep 7 15:46:58 DEBUG[11856] db.c: Unable to find key '206' in family 'CFB'
Sep 7 15:46:58 DEBUG[11856] db.c: Unable to find key '206' in family 'CFU'
Sep 7 15:46:58 VERBOSE[11856] logger.c: > dialparties.agi: extnum: 206
Sep 7 15:46:58 VERBOSE[11856] logger.c: > dialparties.agi: exthascw: 0
Sep 7 15:46:58 VERBOSE[11856] logger.c: > dialparties.agi: exthascfb: 0
Sep 7 15:46:58 VERBOSE[11856] logger.c: > dialparties.agi: extcfb:
Sep 7 15:46:58 VERBOSE[11856] logger.c: > dialparties.agi: exthascfu: 0
Sep 7 15:46:58 VERBOSE[11856] logger.c: > dialparties.agi: extcfu:
Sep 7 15:46:58 DEBUG[11860] manager.c: Manager received command 'login'
Sep 7 15:46:58 VERBOSE[11860] logger.c: == Parsing '/etc/asterisk/manager.conf': Sep 7 15:46:58 VERBOSE[11860] logger.c: == Parsing '/etc/asterisk/manager.conf': Found
Sep 7 15:46:58 VERBOSE[11860] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': Sep 7 15:46:58 VERBOSE[11860] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': Found
Sep 7 15:46:58 VERBOSE[11860] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Sep 7 15:46:58 VERBOSE[11860] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found
Sep 7 15:46:58 WARNING[11860] config.c: Unknown directive 'permit=192.168.1.0/255.255.255.0' at line 18 of manager_custom.conf
Sep 7 15:46:58 DEBUG[11860] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Sep 7 15:46:58 DEBUG[11860] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Sep 7 15:46:58 DEBUG[11860] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Sep 7 15:46:58 DEBUG[11860] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Sep 7 15:46:58 VERBOSE[11860] logger.c: == Manager 'admin' logged on from 127.0.0.1
Sep 7 15:46:58 DEBUG[11860] manager.c: Manager received command 'ExtensionState'
Sep 7 15:46:58 DEBUG[11860] manager.c: Manager received command 'Logoff'
Sep 7 15:46:58 VERBOSE[11860] logger.c: == Manager 'admin' logged off from 127.0.0.1
Sep 7 15:46:58 VERBOSE[11856] logger.c: > dialparties.agi: ExtensionState: 0
Sep 7 15:46:58 VERBOSE[11856] logger.c: dialparties.agi: Extension 206 has ExtensionState: 0
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 206
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- dialparties.agi: DbSet CALLTRACE/206 to 1000
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- AGI Script dialparties.agi completed, returning 0
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Executing Dial("SIP/1000-090d7008", "SIP/206|25|tr") in new stack
Sep 7 15:46:58 DEBUG[11856] chan_sip.c: Setting NAT on RTP to 524288
Sep 7 15:46:58 DEBUG[11856] acl.c: ##### Testing 70.135.19.241 with 10.0.1.0
Sep 7 15:46:58 DEBUG[11856] chan_sip.c: Target address 70.135.19.241 is not local, substituting externip
Sep 7 15:46:58 DEBUG[11856] chan_sip.c: Outgoing Call for 206
Sep 7 15:46:58 VERBOSE[11856] logger.c: -- Called 206
Sep 7 15:46:58 DEBUG[11609] acl.c: ##### Testing 70.135.19.241 with 10.0.1.0
Sep 7 15:46:58 DEBUG[11609] chan_sip.c: Target address 70.135.19.241 is not local, substituting externip
Sep 7 15:46:58 DEBUG[11609] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Sep 7 15:46:58 DEBUG[11609] acl.c: ##### Testing 70.135.19.241 with 10.0.1.0
Sep 7 15:46:58 DEBUG[11609] chan_sip.c: Target address 70.135.19.241 is not local, substituting externip
Sep 7 15:46:58 DEBUG[11609] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Sep 7 15:46:59 DEBUG[11609] acl.c: ##### Testing 70.135.19.241 with 10.0.1.0
Sep 7 15:46:59 DEBUG[11609] chan_sip.c: Target address 70.135.19.241 is not local, substituting externip
Sep 7 15:46:59 DEBUG[11609] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Sep 7 15:47:00 DEBUG[11609] acl.c: ##### Testing 70.135.19.241 with 10.0.1.0
Sep 7 15:47:00 DEBUG[11609] chan_sip.c: Target address 70.135.19.241 is not local, substituting externip
Sep 7 15:47:00 DEBUG[11609] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Sep 7 15:47:00 DEBUG[11609] acl.c: ##### Testing 70.135.19.241 with 10.0.1.0
Sep 7 15:47:00 DEBUG[11609] chan_sip.c: Target address 70.135.19.241 is not local, substituting externip
Sep 7 15:47:00 DEBUG[11609] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Sep 7 15:47:02 DEBUG[11609] acl.c: ##### Testing 70.135.19.241 with 10.0.1.0
Sep 7 15:47:02 DEBUG[11609] chan_sip.c: Target address 70.135.19.241 is not local, substituting externip
Sep 7 15:47:02 DEBUG[11609] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Sep 7 15:47:04 DEBUG[11609] acl.c: ##### Testing 70.135.19.241 with 10.0.1.0
Sep 7 15:47:04 DEBUG[11609] chan_sip.c: Target address 70.135.19.241 is not local, substituting externip
Sep 7 15:47:04 DEBUG[11609] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Sep 7 15:47:06 DEBUG[11609] acl.c: ##### Testing 70.135.19.241 with 10.0.1.0
Sep 7 15:47:06 DEBUG[11609] chan_sip.c: Target address 70.135.19.241 is not local, substituting externip
Sep 7 15:47:06 DEBUG[11609] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0
Sep 7 15:47:07 DEBUG[11856] chan_sip.c: update_call_counter(206) - decrement call limit counter
Sep 7 15:47:07 DEBUG[11856] chan_sip.c: Acked pending invite 102
Sep 7 15:47:07 DEBUG[11856] chan_sip.c: Stopping retransmission on '0984e4e53d0ab51c0fc2764d20f77b6d@66.128.119.107' of Request 102: Match Found
Sep 7 15:47:07 DEBUG[11856] app_dial.c: Exiting with DIALSTATUS=CANCEL.
Sep 7 15:47:07 VERBOSE[11856] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/1000-090d7008' in macro 'dial'
Sep 7 15:47:07 VERBOSE[11856] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/1000-090d7008' in macro 'exten-vm'
Sep 7 15:47:07 VERBOSE[11856] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/1000-090d7008'
Sep 7 15:47:07 DEBUG[11856] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Sep 7 15:47:07 DEBUG[11856] 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-09-07 15:46:58','\"Richard\" <1000>','1000','206','from-internal', 'SIP/1000-090d7008','SIP/206-090ef7d0','Dial','SIP/206|25|tr',9,0,'NO ANSWER',3,'')
Sep 7 15:47:07 DEBUG[11856] chan_sip.c: update_call_counter(1000) - decrement call limit counter
Sep 7 15:47:07 DEBUG[11609] chan_sip.c: Stopping retransmission on 'ef4cc914-327bab1a-8ca031a9@192.168.1.99' of Response 1: Match Found
Sep 7 15:47:08 DEBUG[11609] acl.c: ##### Testing 70.135.19.241 with 10.0.1.0
Sep 7 15:47:08 DEBUG[11609] chan_sip.c: Target address 70.135.19.241 is not local, substituting externip
Sep 7 15:47:08 DEBUG[11609] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0