Sep 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: 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