Miscellany

May 8 10:53:19 DEBUG[2719] chan_sip.c: Auto destroying call '395d7c0c72ec5e1751a0a91478df7f5c@127.0.0.1'
May 8 10:53:29 DEBUG[2719] chan_sip.c: Auto destroying call '656fc1092dd93c9361b2be725d6bf654@127.0.0.1'
May 8 10:53:29 DEBUG[2719] chan_sip.c: Auto destroying call '59170b1600232e4613d94fe566d9614b@127.0.0.1'
May 8 10:53:32 VERBOSE[6048] logger.c: -- Starting simple switch on 'Zap/3-1'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing NoOp("Zap/3-1", "Entering from-zaptel with DID == ") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Ringing("Zap/3-1", "") in new stack
May 8 10:53:33 DEBUG[6048] chan_zap.c: Requested indication 3 on channel Zap/3-1
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '1'
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is 's'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "DID=s") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing NoOp("Zap/3-1", "DID is now s") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '1'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "1?zapok:notzap") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Goto (from-zaptel,s,8)
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing NoOp("Zap/3-1", "Is a Zaptel Channel") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "CHAN=3-1") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '3'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "CHAN=3") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Macro("Zap/3-1", "from-zaptel-3|s|1") in new stack
May 8 10:53:33 WARNING[6048] app_macro.c: No such context 'macro-from-zaptel-3' for macro 'from-zaptel-3'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing NoOp("Zap/3-1", "Returned from Macro from-zaptel-3") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Goto("Zap/3-1", "from-pstn|s|1") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Goto (from-pstn,s,1)
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "__FROM_DID=s") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Gosub("Zap/3-1", "app-blacklist-check|s|1") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing LookupBlacklist("Zap/3-1", "") in new stack
May 8 10:53:33 DEBUG[6048] db.c: Unable to find key '9712394147' in family 'blacklist'
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '0'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "0?blacklisted") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Not taking any branch
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Return("Zap/3-1", "") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is ''
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '0'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "0 ?cidok") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Not taking any branch
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '9712394147'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "CALLERID(name)=9712394147") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '"9712394147" <9712394147>'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing NoOp("Zap/3-1", "CallerID is "9712394147" <9712394147>") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Goto("Zap/3-1", "ext-findmefollow|FM201|1") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Goto (ext-findmefollow,FM201,1)
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Goto("Zap/3-1", "201|FM201") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Goto (ext-findmefollow,201,2)
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Macro("Zap/3-1", "user-callerid|") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '9712394147'
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '9712394147'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing NoOp("Zap/3-1", "user-callerid: 9712394147 9712394147") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '0'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "0?report") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Not taking any branch
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '0'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "0?start") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Not taking any branch
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '9712394147'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "REALCALLERIDNUM=9712394147") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing NoOp("Zap/3-1", "REALCALLERIDNUM is 9712394147") in new stack
May 8 10:53:33 DEBUG[6048] db.c: Unable to find key '9712394147/user' in family 'DEVICE'
May 8 10:53:33 DEBUG[6048] func_db.c: DB: DEVICE/9712394147/user not found in database.
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is ''
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "AMPUSER=") in new stack
May 8 10:53:33 DEBUG[6048] db.c: Unable to find key '/cidname' in family 'AMPUSER'
May 8 10:53:33 DEBUG[6048] func_db.c: DB: AMPUSER//cidname not found in database.
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is ''
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "AMPUSERCIDNAME=") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '1'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "1?report") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Goto (macro-user-callerid,s,12)
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing NoOp("Zap/3-1", "TTL: ARG1: ") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '0'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "0?continue") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Not taking any branch
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '1'
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '-1'
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '64'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "__TTL=64") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '1'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "1?continue") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Goto (macro-user-callerid,s,22)
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '"9712394147" <9712394147>'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing NoOp("Zap/3-1", "Using CallerID "9712394147" <9712394147>") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '1'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "1?skipdb") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Goto (ext-findmefollow,201,5)
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "__NODEST=") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "__BLKVM_OVERRIDE=BLKVM/201/Zap/3-1") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "__BLKVM_BASE=201") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "DB(BLKVM/201/Zap/3-1)=TRUE") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "RRNODEST=") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "__NODEST=201") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '1'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "1?REPCID") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Goto (ext-findmefollow,201,15)
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '9712394147'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing NoOp("Zap/3-1", "CALLERID(name) is 9712394147") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "RecordMethod=Group") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '201-5038510262#'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Macro("Zap/3-1", "record-enable|201-5038510262#|Group") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '0'
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '0'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "0?2:4") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Goto (macro-record-enable,s,4)
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing DeadAGI("Zap/3-1", "recordingcheck|20070508-105333|1178646812.656") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 8 10:53:33 DEBUG[6048] db.c: Unable to find key '5038510262#/recording' in family 'AMPUSER'
May 8 10:53:33 VERBOSE[6048] logger.c: recordingcheck|20070508-105333|1178646812.656: No DB Entry AMPUSER/5038510262#/recording - Not recording
May 8 10:53:33 VERBOSE[6048] logger.c: -- AGI Script recordingcheck completed, returning 0
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing NoOp("Zap/3-1", "No recording needed") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "RingGroupMethod=ringallv2-prim") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "_FMGRP=201") in new stack
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is 'ENABLED'
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '1'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "1?doconfirm") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Goto (ext-findmefollow,201,23)
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '30'
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '6'
May 8 10:53:33 DEBUG[6048] pbx.c: Expression result is '36'
May 8 10:53:33 DEBUG[6048] pbx.c: Function result is '201-5038510262#'
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Macro("Zap/3-1", "dial-confirm|36|tr|201-5038510262#|201") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "DB(RG/201/Zap/3-1)=RINGING") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "__UNIQCHAN=Zap/3-1") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "USE_CONFIRMATION=TRUE") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "RINGGROUP_INDEX=201") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Set("Zap/3-1", "ARG4=") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing Macro("Zap/3-1", "dial|36|tr|201-5038510262#") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Executing DeadAGI("Zap/3-1", "dialparties.agi") in new stack
May 8 10:53:33 VERBOSE[6048] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
May 8 10:53:34 VERBOSE[6048] logger.c: dialparties.agi: Starting New Dialparties.agi
May 8 10:53:34 DEBUG[6052] manager.c: Manager received command 'login'
May 8 10:53:34 VERBOSE[6052] logger.c: == Parsing '/etc/asterisk/manager.conf': May 8 10:53:34 VERBOSE[6052] logger.c: == Parsing '/etc/asterisk/manager.conf': Found
May 8 10:53:34 VERBOSE[6052] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': May 8 10:53:34 VERBOSE[6052] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': Found
May 8 10:53:34 VERBOSE[6052] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': May 8 10:53:34 VERBOSE[6052] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found
May 8 10:53:34 DEBUG[6052] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
May 8 10:53:34 DEBUG[6052] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 8 10:53:34 DEBUG[6052] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
May 8 10:53:34 DEBUG[6052] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 8 10:53:34 VERBOSE[6052] logger.c: == Manager 'admin' logged on from 127.0.0.1
May 8 10:53:34 VERBOSE[6048] logger.c: dialparties.agi: priority is 1
May 8 10:53:34 VERBOSE[6048] logger.c: dialparties.agi: Caller ID name is '9712394147' number is '9712394147'
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: USE_CONFIRMATION: 'TRUE'
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: RINGGROUP_INDEX: '201'
May 8 10:53:34 VERBOSE[6048] logger.c: dialparties.agi: Methodology of ring is 'ringallv2-prim'
May 8 10:53:34 VERBOSE[6048] logger.c: -- dialparties.agi: Added extension 201 to extension map
May 8 10:53:34 VERBOSE[6048] logger.c: -- dialparties.agi: Added extension 5038510262# to extension map
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: got fmgrp_prering: 6, fmgrp_grptime: 30
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: fmgrp_totalprering: 36
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: found extension in pre-ring and array
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: ringallv2 ring times: REALPRERING: 36, PRERING: 6
May 8 10:53:34 DEBUG[6048] db.c: Unable to find key '201' in family 'CF'
May 8 10:53:34 VERBOSE[6048] logger.c: -- dialparties.agi: Extension 201 cf is disabled
May 8 10:53:34 DEBUG[6048] db.c: Unable to find key '5038510262#' in family 'CF'
May 8 10:53:34 VERBOSE[6048] logger.c: -- dialparties.agi: Extension 5038510262# cf is disabled
May 8 10:53:34 DEBUG[6048] db.c: Unable to find key '201' in family 'DND'
May 8 10:53:34 VERBOSE[6048] logger.c: -- dialparties.agi: Extension 201 do not disturb is disabled
May 8 10:53:34 DEBUG[6048] db.c: Unable to find key '201' in family 'CFB'
May 8 10:53:34 DEBUG[6048] db.c: Unable to find key '201' in family 'CFU'
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: extnum: 201
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: exthascw: 1
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: exthascfb: 0
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: extcfb:
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: exthascfu: 0
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: extcfu:
May 8 10:53:34 DEBUG[6052] manager.c: Manager received command 'ExtensionState'
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: ExtensionState: 0
May 8 10:53:34 VERBOSE[6048] logger.c: dialparties.agi: Extension 201 has ExtensionState: 0
May 8 10:53:34 VERBOSE[6048] logger.c: -- dialparties.agi: dbset CALLTRACE/201 to 9712394147
May 8 10:53:34 DEBUG[6048] db.c: Unable to find key '5038510262#' in family 'CW'
May 8 10:53:34 DEBUG[6048] db.c: Unable to find key '5038510262#' in family 'CFB'
May 8 10:53:34 DEBUG[6048] db.c: Unable to find key '5038510262#' in family 'CFU'
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: extnum: 5038510262#
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: exthascw: 0
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: exthascfb: 0
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: extcfb:
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: exthascfu: 0
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: extcfu:
May 8 10:53:34 VERBOSE[6048] logger.c: > dialparties.agi: Built External dialstring component for 5038510262: Local/RG-201-5038510262#@from-internal
May 8 10:53:34 DEBUG[6052] manager.c: Manager received command 'Logoff'
May 8 10:53:34 VERBOSE[6052] logger.c: == Manager 'admin' logged off from 127.0.0.1
May 8 10:53:34 VERBOSE[6048] logger.c: -- AGI Script dialparties.agi completed, returning 0
May 8 10:53:34 VERBOSE[6048] logger.c: -- Executing Dial("Zap/3-1", "Local/LC-201@from-internal&Local/RG-201-5038510262#@from-internal|36|tr") in new stack
May 8 10:53:34 VERBOSE[6048] logger.c: -- Called LC-201@from-internal
May 8 10:53:34 VERBOSE[6048] logger.c: -- Called RG-201-5038510262#@from-internal
May 8 10:53:34 DEBUG[6048] chan_zap.c: Requested indication 3 on channel Zap/3-1
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Macro("Local/LC-201@from-internal-28ad,2", "dialout-trunk|4|LC-201||") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "DIAL_TRUNK=4") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "DIAL_NUMBER=LC-201") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "ROUTE_PASSWD=") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?noauth") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Goto (macro-dialout-trunk,s,6)
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?disabletrunk|1") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "_NODEST=") in new stack
May 8 10:53:34 DEBUG[6054] pbx.c: Function result is '30'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "DIAL_TRUNK_OPTIONS=tr") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "GROUP()=OUT_4") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Macro("Local/LC-201@from-internal-28ad,2", "user-callerid|SKIPTTL") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Function result is '9712394147'
May 8 10:53:34 DEBUG[6053] pbx.c: Function result is '9712394147'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "user-callerid: 9712394147 9712394147") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?report") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Goto (macro-user-callerid,s,12)
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "TTL: 64 ARG1: SKIPTTL") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?continue") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Goto (macro-user-callerid,s,22)
May 8 10:53:34 DEBUG[6053] pbx.c: Function result is '"9712394147" <9712394147>'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "Using CallerID "9712394147" <9712394147>") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Function result is '9712394147'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Macro("Local/LC-201@from-internal-28ad,2", "record-enable|9712394147|OUT") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Function result is '0'
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?2:4") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Goto (macro-record-enable,s,4)
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing DeadAGI("Local/LC-201@from-internal-28ad,2", "recordingcheck|20070508-105334|1178646814.658") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 8 10:53:34 VERBOSE[6054] logger.c: -- Executing Macro("Local/RG-201-5038510262#@from-internal-ce3e,2", "dial|30|M(confirm^^^201)tr|5038510262#") in new stack
May 8 10:53:34 VERBOSE[6054] logger.c: -- Executing DeadAGI("Local/RG-201-5038510262#@from-internal-ce3e,2", "dialparties.agi") in new stack
May 8 10:53:34 VERBOSE[6054] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
May 8 10:53:34 DEBUG[6053] db.c: Unable to find key '9712394147/recording' in family 'AMPUSER'
May 8 10:53:34 VERBOSE[6053] logger.c: recordingcheck|20070508-105334|1178646814.658: No AMPUSER db entry for 9712394147. Not recording
May 8 10:53:34 VERBOSE[6053] logger.c: -- AGI Script recordingcheck completed, returning 0
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "No recording needed") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?skipoutcid") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "DIAL_TRUNK_OPTIONS=t") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Macro("Local/LC-201@from-internal-28ad,2", "outbound-callerid|4") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?start") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:34 DEBUG[6053] pbx.c: Function result is '9712394147'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "REALCALLERIDNUM=9712394147") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "REALCALLERIDNUM is 9712394147") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?normcid") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?normcid") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?normcid") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "USEROUTCID=9712394147") in new stack
May 8 10:53:34 DEBUG[6053] db.c: Unable to find key '9712394147/device' in family 'AMPUSER'
May 8 10:53:34 DEBUG[6053] func_db.c: DB: AMPUSER/9712394147/device not found in database.
May 8 10:53:34 DEBUG[6053] pbx.c: Function result is ''
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?bypass:normcid") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Goto (macro-outbound-callerid,s,10)
May 8 10:53:34 DEBUG[6053] db.c: Unable to find key '9712394147/emergency_cid' in family 'DEVICE'
May 8 10:53:34 DEBUG[6053] func_db.c: DB: DEVICE/9712394147/emergency_cid not found in database.
May 8 10:53:34 DEBUG[6053] pbx.c: Function result is ''
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "EMERGENCYCID=") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "TRUNKOUTCID=ANSI <5034850808>") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?trunkcid") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Goto (macro-outbound-callerid,s,16)
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?usercid") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "CALLERID(all)=ANSI <5034850808>") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?report") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "CALLERID(all)=9712394147") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Function result is ''
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?report:hidecid") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Goto (macro-outbound-callerid,s,22)
May 8 10:53:34 DEBUG[6053] pbx.c: Function result is '"" <9712394147>'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "CallerID set to "" <9712394147>") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?nomax") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:34 DEBUG[6053] pbx.c: Function result is '1'
May 8 10:53:34 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?chanfull") in new stack
May 8 10:53:34 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:34 VERBOSE[6053] logger.c: -- Executing DeadAGI("Local/LC-201@from-internal-28ad,2", "fixlocalprefix") in new stack
May 8 10:53:34 VERBOSE[6053] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
May 8 10:53:34 VERBOSE[6054] logger.c: dialparties.agi: Starting New Dialparties.agi
May 8 10:53:34 DEBUG[6060] manager.c: Manager received command 'login'
May 8 10:53:34 VERBOSE[6060] logger.c: == Parsing '/etc/asterisk/manager.conf': May 8 10:53:34 VERBOSE[6060] logger.c: == Parsing '/etc/asterisk/manager.conf': Found
May 8 10:53:34 VERBOSE[6060] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': May 8 10:53:34 VERBOSE[6060] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': Found
May 8 10:53:34 VERBOSE[6060] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': May 8 10:53:34 VERBOSE[6060] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found
May 8 10:53:34 DEBUG[6060] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
May 8 10:53:34 DEBUG[6060] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 8 10:53:34 DEBUG[6060] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
May 8 10:53:34 DEBUG[6060] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 8 10:53:34 VERBOSE[6060] logger.c: == Manager 'admin' logged on from 127.0.0.1
May 8 10:53:34 VERBOSE[6054] logger.c: dialparties.agi: priority is 1
May 8 10:53:34 VERBOSE[6054] logger.c: dialparties.agi: Caller ID name is '9712394147' number is '9712394147'
May 8 10:53:34 VERBOSE[6054] logger.c: > dialparties.agi: USE_CONFIRMATION: 'FALSE'
May 8 10:53:34 VERBOSE[6054] logger.c: > dialparties.agi: RINGGROUP_INDEX: ''
May 8 10:53:34 VERBOSE[6054] logger.c: dialparties.agi: Methodology of ring is 'none'
May 8 10:53:34 VERBOSE[6054] logger.c: -- dialparties.agi: Added extension 5038510262# to extension map
May 8 10:53:34 DEBUG[6054] db.c: Unable to find key '5038510262#' in family 'CF'
May 8 10:53:34 VERBOSE[6054] logger.c: -- dialparties.agi: Extension 5038510262# cf is disabled
May 8 10:53:34 DEBUG[6054] db.c: Unable to find key '5038510262#' in family 'CW'
May 8 10:53:34 DEBUG[6054] db.c: Unable to find key '5038510262#' in family 'CFB'
May 8 10:53:34 DEBUG[6054] db.c: Unable to find key '5038510262#' in family 'CFU'
May 8 10:53:34 VERBOSE[6054] logger.c: > dialparties.agi: extnum: 5038510262#
May 8 10:53:34 VERBOSE[6054] logger.c: > dialparties.agi: exthascw: 0
May 8 10:53:34 VERBOSE[6054] logger.c: > dialparties.agi: exthascfb: 0
May 8 10:53:34 VERBOSE[6054] logger.c: > dialparties.agi: extcfb:
May 8 10:53:34 VERBOSE[6054] logger.c: > dialparties.agi: exthascfu: 0
May 8 10:53:34 VERBOSE[6054] logger.c: > dialparties.agi: extcfu:
May 8 10:53:34 VERBOSE[6054] logger.c: > dialparties.agi: Built External dialstring component for 5038510262: Local/5038510262@from-internal/n
May 8 10:53:34 VERBOSE[6054] logger.c: > dialparties.agi: NODEST: 201 blkvm enabled macro already in dialopts: M(confirm^^^201)tr
May 8 10:53:34 DEBUG[6060] manager.c: Manager received command 'Logoff'
May 8 10:53:34 VERBOSE[6060] logger.c: == Manager 'admin' logged off from 127.0.0.1
May 8 10:53:34 VERBOSE[6054] logger.c: -- AGI Script dialparties.agi completed, returning 0
May 8 10:53:34 VERBOSE[6054] logger.c: -- Executing Dial("Local/RG-201-5038510262#@from-internal-ce3e,2", "Local/5038510262@from-internal/n|30|M(confirm^^^201)tr") in new stack
May 8 10:53:34 VERBOSE[6054] logger.c: -- Called 5038510262@from-internal/n
May 8 10:53:34 VERBOSE[6048] logger.c: -- Local/RG-201-5038510262#@from-internal-ce3e,1 is ringing
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing Macro("Local/5038510262@from-internal-1165,2", "dialout-trunk|4|5038510262||") in new stack
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "DIAL_TRUNK=4") in new stack
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "DIAL_NUMBER=5038510262") in new stack
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "ROUTE_PASSWD=") in new stack
May 8 10:53:34 DEBUG[6061] pbx.c: Expression result is '1'
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "1?noauth") in new stack
May 8 10:53:34 VERBOSE[6061] logger.c: -- Goto (macro-dialout-trunk,s,6)
May 8 10:53:34 DEBUG[6061] pbx.c: Expression result is '0'
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "0?disabletrunk|1") in new stack
May 8 10:53:34 DEBUG[6061] pbx.c: Not taking any branch
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "_NODEST=") in new stack
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "DIAL_TRUNK_OPTIONS=tr") in new stack
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "GROUP()=OUT_4") in new stack
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing Macro("Local/5038510262@from-internal-1165,2", "user-callerid|SKIPTTL") in new stack
May 8 10:53:34 DEBUG[6061] pbx.c: Function result is '9712394147'
May 8 10:53:34 DEBUG[6061] pbx.c: Function result is '9712394147'
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing NoOp("Local/5038510262@from-internal-1165,2", "user-callerid: 9712394147 9712394147") in new stack
May 8 10:53:34 DEBUG[6061] pbx.c: Expression result is '1'
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "1?report") in new stack
May 8 10:53:34 VERBOSE[6061] logger.c: -- Goto (macro-user-callerid,s,12)
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing NoOp("Local/5038510262@from-internal-1165,2", "TTL: 64 ARG1: SKIPTTL") in new stack
May 8 10:53:34 DEBUG[6061] pbx.c: Expression result is '1'
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "1?continue") in new stack
May 8 10:53:34 VERBOSE[6061] logger.c: -- Goto (macro-user-callerid,s,22)
May 8 10:53:34 DEBUG[6061] pbx.c: Function result is '"9712394147" <9712394147>'
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing NoOp("Local/5038510262@from-internal-1165,2", "Using CallerID "9712394147" <9712394147>") in new stack
May 8 10:53:34 DEBUG[6061] pbx.c: Function result is '9712394147'
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing Macro("Local/5038510262@from-internal-1165,2", "record-enable|9712394147|OUT") in new stack
May 8 10:53:34 DEBUG[6061] pbx.c: Function result is '0'
May 8 10:53:34 DEBUG[6061] pbx.c: Expression result is '0'
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "0?2:4") in new stack
May 8 10:53:34 VERBOSE[6061] logger.c: -- Goto (macro-record-enable,s,4)
May 8 10:53:34 VERBOSE[6061] logger.c: -- Executing DeadAGI("Local/5038510262@from-internal-1165,2", "recordingcheck|20070508-105334|1178646814.662") in new stack
May 8 10:53:34 VERBOSE[6061] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 8 10:53:35 VERBOSE[6053] logger.c: -- AGI Script fixlocalprefix completed, returning 0
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "OUTNUM=LC-201") in new stack
May 8 10:53:35 DEBUG[6053] pbx.c: Function result is 'SIP/Vitelity-outbound'
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "custom=SIP/Vitelity-outbound") in new stack
May 8 10:53:35 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:35 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:35 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?gocall") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Goto (macro-dialout-trunk,s,22)
May 8 10:53:35 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?customtrunk") in new stack
May 8 10:53:35 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing Dial("Local/LC-201@from-internal-28ad,2", "SIP/Vitelity-outbound/LC-201|300|t") in new stack
May 8 10:53:35 DEBUG[6053] chan_sip.c: Setting NAT on RTP to 0
May 8 10:53:35 DEBUG[6053] acl.c: ##### Testing 64.2.142.22 with 192.168.2.0
May 8 10:53:35 DEBUG[6053] chan_sip.c: Target address 64.2.142.22 is not local, substituting externip
May 8 10:53:35 DEBUG[6053] chan_sip.c: Outgoing Call for LC-201
May 8 10:53:35 VERBOSE[6053] logger.c: -- Called Vitelity-outbound/LC-201
May 8 10:53:35 DEBUG[2719] chan_sip.c: Acked pending invite 102
May 8 10:53:35 DEBUG[2719] chan_sip.c: Stopping retransmission on '37e050522ee6283a601af9637c4c9555@71.237.146.239' of Request 102: Match Found
May 8 10:53:35 DEBUG[6061] db.c: Unable to find key '9712394147/recording' in family 'AMPUSER'
May 8 10:53:35 VERBOSE[6061] logger.c: recordingcheck|20070508-105334|1178646814.662: No AMPUSER db entry for 9712394147. Not recording
May 8 10:53:35 VERBOSE[6061] logger.c: -- AGI Script recordingcheck completed, returning 0
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing NoOp("Local/5038510262@from-internal-1165,2", "No recording needed") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Expression result is '0'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "0?skipoutcid") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Not taking any branch
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "DIAL_TRUNK_OPTIONS=t") in new stack
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing Macro("Local/5038510262@from-internal-1165,2", "outbound-callerid|4") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Expression result is '0'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "0?start") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Not taking any branch
May 8 10:53:35 DEBUG[6061] pbx.c: Function result is '9712394147'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "REALCALLERIDNUM=9712394147") in new stack
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing NoOp("Local/5038510262@from-internal-1165,2", "REALCALLERIDNUM is 9712394147") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Expression result is '0'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "0?normcid") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Not taking any branch
May 8 10:53:35 DEBUG[6061] pbx.c: Expression result is '0'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "0?normcid") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Not taking any branch
May 8 10:53:35 DEBUG[6061] pbx.c: Expression result is '0'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "0?normcid") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Not taking any branch
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "USEROUTCID=9712394147") in new stack
May 8 10:53:35 DEBUG[6061] db.c: Unable to find key '9712394147/device' in family 'AMPUSER'
May 8 10:53:35 DEBUG[6061] func_db.c: DB: AMPUSER/9712394147/device not found in database.
May 8 10:53:35 DEBUG[6061] pbx.c: Function result is ''
May 8 10:53:35 DEBUG[6061] pbx.c: Expression result is '1'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "1?bypass:normcid") in new stack
May 8 10:53:35 VERBOSE[6061] logger.c: -- Goto (macro-outbound-callerid,s,10)
May 8 10:53:35 DEBUG[6061] db.c: Unable to find key '9712394147/emergency_cid' in family 'DEVICE'
May 8 10:53:35 DEBUG[6061] func_db.c: DB: DEVICE/9712394147/emergency_cid not found in database.
May 8 10:53:35 DEBUG[6061] pbx.c: Function result is ''
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "EMERGENCYCID=") in new stack
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "TRUNKOUTCID=ANSI <5034850808>") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Expression result is '1'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "1?trunkcid") in new stack
May 8 10:53:35 VERBOSE[6061] logger.c: -- Goto (macro-outbound-callerid,s,16)
May 8 10:53:35 DEBUG[6061] pbx.c: Expression result is '0'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "0?usercid") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Not taking any branch
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "CALLERID(all)=ANSI <5034850808>") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Expression result is '0'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "0?report") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Not taking any branch
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "CALLERID(all)=9712394147") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Function result is ''
May 8 10:53:35 DEBUG[6061] pbx.c: Expression result is '1'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "1?report:hidecid") in new stack
May 8 10:53:35 VERBOSE[6061] logger.c: -- Goto (macro-outbound-callerid,s,22)
May 8 10:53:35 DEBUG[6061] pbx.c: Function result is '"" <9712394147>'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing NoOp("Local/5038510262@from-internal-1165,2", "CallerID set to "" <9712394147>") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Expression result is '0'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "0?nomax") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Not taking any branch
May 8 10:53:35 DEBUG[6061] pbx.c: Function result is '2'
May 8 10:53:35 DEBUG[6061] pbx.c: Expression result is '0'
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "0?chanfull") in new stack
May 8 10:53:35 DEBUG[6061] pbx.c: Not taking any branch
May 8 10:53:35 VERBOSE[6061] logger.c: -- Executing DeadAGI("Local/5038510262@from-internal-1165,2", "fixlocalprefix") in new stack
May 8 10:53:35 VERBOSE[6061] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
May 8 10:53:35 DEBUG[2719] chan_sip.c: Acked pending invite 103
May 8 10:53:35 DEBUG[2719] chan_sip.c: Stopping retransmission on '37e050522ee6283a601af9637c4c9555@71.237.146.239' of Request 103: Match Found
May 8 10:53:35 VERBOSE[6053] logger.c: -- SIP/Vitelity-outbound-084aeb88 is circuit-busy
May 8 10:53:35 DEBUG[6053] chan_sip.c: update_call_counter(LC-201) - decrement call limit counter
May 8 10:53:35 VERBOSE[6053] logger.c: == Everyone is busy/congested at this time (1:0/1/0)
May 8 10:53:35 DEBUG[6053] app_dial.c: Exiting with DIALSTATUS=CONGESTION.
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing Goto("Local/LC-201@from-internal-28ad,2", "s-CONGESTION|1") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,1)
May 8 10:53:35 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?noreport") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,3)
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "TRUNK Dial failed due to CONGESTION - failing through to other trunks") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing Macro("Local/LC-201@from-internal-28ad,2", "dialout-trunk|2|LC-201||") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "DIAL_TRUNK=2") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "DIAL_NUMBER=LC-201") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "ROUTE_PASSWD=") in new stack
May 8 10:53:35 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?noauth") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Goto (macro-dialout-trunk,s,6)
May 8 10:53:35 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?disabletrunk|1") in new stack
May 8 10:53:35 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "_NODEST=") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "DIAL_TRUNK_OPTIONS=tr") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "GROUP()=OUT_2") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing Macro("Local/LC-201@from-internal-28ad,2", "user-callerid|SKIPTTL") in new stack
May 8 10:53:35 DEBUG[6053] pbx.c: Function result is ''
May 8 10:53:35 DEBUG[6053] pbx.c: Function result is '9712394147'
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "user-callerid: 9712394147") in new stack
May 8 10:53:35 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?report") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Goto (macro-user-callerid,s,12)
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "TTL: 64 ARG1: SKIPTTL") in new stack
May 8 10:53:35 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?continue") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Goto (macro-user-callerid,s,22)
May 8 10:53:35 DEBUG[6053] pbx.c: Function result is '"" <9712394147>'
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "Using CallerID "" <9712394147>") in new stack
May 8 10:53:35 DEBUG[6053] pbx.c: Function result is '9712394147'
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing Macro("Local/LC-201@from-internal-28ad,2", "record-enable|9712394147|OUT") in new stack
May 8 10:53:35 DEBUG[6053] pbx.c: Function result is '0'
May 8 10:53:35 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?2:4") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Goto (macro-record-enable,s,4)
May 8 10:53:35 VERBOSE[6053] logger.c: -- Executing DeadAGI("Local/LC-201@from-internal-28ad,2", "recordingcheck|20070508-105335|1178646814.658") in new stack
May 8 10:53:35 VERBOSE[6053] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 8 10:53:36 VERBOSE[6061] logger.c: -- AGI Script fixlocalprefix completed, returning 0
May 8 10:53:36 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "OUTNUM=5038510262") in new stack
May 8 10:53:36 DEBUG[6061] pbx.c: Function result is 'SIP/Vitelity-outbound'
May 8 10:53:36 VERBOSE[6061] logger.c: -- Executing Set("Local/5038510262@from-internal-1165,2", "custom=SIP/Vitelity-outbound") in new stack
May 8 10:53:36 DEBUG[6061] pbx.c: Expression result is '0'
May 8 10:53:36 DEBUG[6061] pbx.c: Expression result is '1'
May 8 10:53:36 DEBUG[6061] pbx.c: Expression result is '1'
May 8 10:53:36 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "1?gocall") in new stack
May 8 10:53:36 VERBOSE[6061] logger.c: -- Goto (macro-dialout-trunk,s,22)
May 8 10:53:36 DEBUG[6061] pbx.c: Expression result is '0'
May 8 10:53:36 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "0?customtrunk") in new stack
May 8 10:53:36 DEBUG[6061] pbx.c: Not taking any branch
May 8 10:53:36 VERBOSE[6061] logger.c: -- Executing Dial("Local/5038510262@from-internal-1165,2", "SIP/Vitelity-outbound/5038510262|300|t") in new stack
May 8 10:53:36 DEBUG[6061] chan_sip.c: Setting NAT on RTP to 0
May 8 10:53:36 DEBUG[6061] acl.c: ##### Testing 64.2.142.22 with 192.168.2.0
May 8 10:53:36 DEBUG[6061] chan_sip.c: Target address 64.2.142.22 is not local, substituting externip
May 8 10:53:36 DEBUG[6061] chan_sip.c: Outgoing Call for 5038510262
May 8 10:53:36 VERBOSE[6061] logger.c: -- Called Vitelity-outbound/5038510262
May 8 10:53:36 DEBUG[6053] db.c: Unable to find key '9712394147/recording' in family 'AMPUSER'
May 8 10:53:36 VERBOSE[6053] logger.c: recordingcheck|20070508-105335|1178646814.658: No AMPUSER db entry for 9712394147. Not recording
May 8 10:53:36 VERBOSE[6053] logger.c: -- AGI Script recordingcheck completed, returning 0
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "No recording needed") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?skipoutcid") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "DIAL_TRUNK_OPTIONS=t") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Macro("Local/LC-201@from-internal-28ad,2", "outbound-callerid|2") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?start") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Goto (macro-outbound-callerid,s,3)
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "REALCALLERIDNUM is 9712394147") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?normcid") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?normcid") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?normcid") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "USEROUTCID=9712394147") in new stack
May 8 10:53:36 DEBUG[6053] db.c: Unable to find key '9712394147/device' in family 'AMPUSER'
May 8 10:53:36 DEBUG[6053] func_db.c: DB: AMPUSER/9712394147/device not found in database.
May 8 10:53:36 DEBUG[6053] pbx.c: Function result is ''
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?bypass:normcid") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Goto (macro-outbound-callerid,s,10)
May 8 10:53:36 DEBUG[6053] db.c: Unable to find key '9712394147/emergency_cid' in family 'DEVICE'
May 8 10:53:36 DEBUG[6053] func_db.c: DB: DEVICE/9712394147/emergency_cid not found in database.
May 8 10:53:36 DEBUG[6053] pbx.c: Function result is ''
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "EMERGENCYCID=") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "TRUNKOUTCID=<5034850808>") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?trunkcid") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Goto (macro-outbound-callerid,s,16)
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?usercid") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "CALLERID(all)=<5034850808>") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?report") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "CALLERID(all)=9712394147") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Function result is ''
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?report:hidecid") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Goto (macro-outbound-callerid,s,22)
May 8 10:53:36 DEBUG[6053] pbx.c: Function result is '"" <9712394147>'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "CallerID set to "" <9712394147>") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?nomax") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:36 DEBUG[6053] pbx.c: Function result is '1'
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?chanfull") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing DeadAGI("Local/LC-201@from-internal-28ad,2", "fixlocalprefix") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
May 8 10:53:36 DEBUG[2719] chan_sip.c: Acked pending invite 102
May 8 10:53:36 DEBUG[2719] chan_sip.c: Stopping retransmission on '245440d00b2da21b0b0014664a4867f7@71.237.146.239' of Request 102: Match Found
May 8 10:53:36 DEBUG[6048] chan_zap.c: Exception on 20, channel 3
May 8 10:53:36 DEBUG[6048] chan_zap.c: Got event Ring Begin(18) on channel 3 (index 0)
May 8 10:53:36 DEBUG[2719] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '245440d00b2da21b0b0014664a4867f7@71.237.146.239' Request 103: Found
May 8 10:53:36 VERBOSE[6053] logger.c: -- AGI Script fixlocalprefix completed, returning 0
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "OUTNUM=LC-201") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Function result is 'SIP/telasip-gw'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "custom=SIP/telasip-gw") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?gocall") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Goto (macro-dialout-trunk,s,22)
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?customtrunk") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Dial("Local/LC-201@from-internal-28ad,2", "SIP/telasip-gw/LC-201|300|t") in new stack
May 8 10:53:36 DEBUG[6053] chan_sip.c: Setting NAT on RTP to 0
May 8 10:53:36 DEBUG[6053] acl.c: ##### Testing 4.79.19.56 with 192.168.2.0
May 8 10:53:36 DEBUG[6053] chan_sip.c: Target address 4.79.19.56 is not local, substituting externip
May 8 10:53:36 DEBUG[6053] chan_sip.c: Outgoing Call for LC-201
May 8 10:53:36 VERBOSE[6053] logger.c: -- Called telasip-gw/LC-201
May 8 10:53:36 DEBUG[2719] chan_sip.c: Acked pending invite 102
May 8 10:53:36 DEBUG[2719] chan_sip.c: Stopping retransmission on '7554b44b5380b6715347c2bc49e35e7b@gw4.sip.telasip.com' of Request 102: Match Found
May 8 10:53:36 DEBUG[2719] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '7554b44b5380b6715347c2bc49e35e7b@gw4.sip.telasip.com' Request 103: Found
May 8 10:53:36 DEBUG[2719] chan_sip.c: Acked pending invite 103
May 8 10:53:36 DEBUG[2719] chan_sip.c: Stopping retransmission on '7554b44b5380b6715347c2bc49e35e7b@gw4.sip.telasip.com' of Request 103: Match Found
May 8 10:53:36 VERBOSE[6053] logger.c: -- SIP/telasip-gw-084aeb88 is circuit-busy
May 8 10:53:36 DEBUG[6053] chan_sip.c: update_call_counter(LC-201) - decrement call limit counter
May 8 10:53:36 VERBOSE[6053] logger.c: == Everyone is busy/congested at this time (1:0/1/0)
May 8 10:53:36 DEBUG[6053] app_dial.c: Exiting with DIALSTATUS=CONGESTION.
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Goto("Local/LC-201@from-internal-28ad,2", "s-CONGESTION|1") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,1)
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?noreport") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,3)
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "TRUNK Dial failed due to CONGESTION - failing through to other trunks") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Macro("Local/LC-201@from-internal-28ad,2", "dialout-trunk|1|LC-201||") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "DIAL_TRUNK=1") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "DIAL_NUMBER=LC-201") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "ROUTE_PASSWD=") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?noauth") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Goto (macro-dialout-trunk,s,6)
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?disabletrunk|1") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "_NODEST=") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "DIAL_TRUNK_OPTIONS=tr") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "GROUP()=OUT_1") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Macro("Local/LC-201@from-internal-28ad,2", "user-callerid|SKIPTTL") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Function result is ''
May 8 10:53:36 DEBUG[6053] pbx.c: Function result is '9712394147'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "user-callerid: 9712394147") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?report") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Goto (macro-user-callerid,s,12)
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "TTL: 64 ARG1: SKIPTTL") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?continue") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Goto (macro-user-callerid,s,22)
May 8 10:53:36 DEBUG[6053] pbx.c: Function result is '"" <9712394147>'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "Using CallerID "" <9712394147>") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Function result is '9712394147'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing Macro("Local/LC-201@from-internal-28ad,2", "record-enable|9712394147|OUT") in new stack
May 8 10:53:36 DEBUG[6053] pbx.c: Function result is '0'
May 8 10:53:36 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?2:4") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Goto (macro-record-enable,s,4)
May 8 10:53:36 VERBOSE[6053] logger.c: -- Executing DeadAGI("Local/LC-201@from-internal-28ad,2", "recordingcheck|20070508-105336|1178646814.658") in new stack
May 8 10:53:36 VERBOSE[6053] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 8 10:53:37 DEBUG[2719] chan_sip.c: Stopping retransmission on '7554b44b5380b6715347c2bc49e35e7b@gw4.sip.telasip.com' of Request 103: Match Not Found
May 8 10:53:37 DEBUG[2719] chan_sip.c: Got response on call that is already terminated: 7554b44b5380b6715347c2bc49e35e7b@gw4.sip.telasip.com (ignoring)
May 8 10:53:37 DEBUG[6053] db.c: Unable to find key '9712394147/recording' in family 'AMPUSER'
May 8 10:53:37 VERBOSE[6053] logger.c: recordingcheck|20070508-105336|1178646814.658: No AMPUSER db entry for 9712394147. Not recording
May 8 10:53:37 VERBOSE[6053] logger.c: -- AGI Script recordingcheck completed, returning 0
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "No recording needed") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?skipoutcid") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "DIAL_TRUNK_OPTIONS=t") in new stack
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing Macro("Local/LC-201@from-internal-28ad,2", "outbound-callerid|1") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?start") in new stack
May 8 10:53:37 VERBOSE[6053] logger.c: -- Goto (macro-outbound-callerid,s,3)
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "REALCALLERIDNUM is 9712394147") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?normcid") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?normcid") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?normcid") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "USEROUTCID=9712394147") in new stack
May 8 10:53:37 DEBUG[6053] db.c: Unable to find key '9712394147/device' in family 'AMPUSER'
May 8 10:53:37 DEBUG[6053] func_db.c: DB: AMPUSER/9712394147/device not found in database.
May 8 10:53:37 DEBUG[6053] pbx.c: Function result is ''
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?bypass:normcid") in new stack
May 8 10:53:37 VERBOSE[6053] logger.c: -- Goto (macro-outbound-callerid,s,10)
May 8 10:53:37 DEBUG[6053] db.c: Unable to find key '9712394147/emergency_cid' in family 'DEVICE'
May 8 10:53:37 DEBUG[6053] func_db.c: DB: DEVICE/9712394147/emergency_cid not found in database.
May 8 10:53:37 DEBUG[6053] pbx.c: Function result is ''
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "EMERGENCYCID=") in new stack
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "TRUNKOUTCID=") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?trunkcid") in new stack
May 8 10:53:37 VERBOSE[6053] logger.c: -- Goto (macro-outbound-callerid,s,16)
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?usercid") in new stack
May 8 10:53:37 VERBOSE[6053] logger.c: -- Goto (macro-outbound-callerid,s,18)
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?report") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "CALLERID(all)=9712394147") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Function result is ''
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?report:hidecid") in new stack
May 8 10:53:37 VERBOSE[6053] logger.c: -- Goto (macro-outbound-callerid,s,22)
May 8 10:53:37 DEBUG[6053] pbx.c: Function result is '"" <9712394147>'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing NoOp("Local/LC-201@from-internal-28ad,2", "CallerID set to "" <9712394147>") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?nomax") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:37 DEBUG[6053] pbx.c: Function result is '1'
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?chanfull") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing DeadAGI("Local/LC-201@from-internal-28ad,2", "fixlocalprefix") in new stack
May 8 10:53:37 VERBOSE[6053] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
May 8 10:53:37 VERBOSE[6053] logger.c: -- AGI Script fixlocalprefix completed, returning 0
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "OUTNUM=LC-201") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Function result is 'ZAP/g0'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing Set("Local/LC-201@from-internal-28ad,2", "custom=ZAP/g0") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?gocall") in new stack
May 8 10:53:37 VERBOSE[6053] logger.c: -- Goto (macro-dialout-trunk,s,22)
May 8 10:53:37 DEBUG[6053] pbx.c: Expression result is '0'
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "0?customtrunk") in new stack
May 8 10:53:37 DEBUG[6053] pbx.c: Not taking any branch
May 8 10:53:37 VERBOSE[6053] logger.c: -- Executing Dial("Local/LC-201@from-internal-28ad,2", "ZAP/g0/LC-201|300|t") in new stack
May 8 10:53:37 DEBUG[6053] chan_zap.c: Dialing 'LC-201'
May 8 10:53:37 DEBUG[6053] chan_zap.c: Deferring dialing...
May 8 10:53:37 VERBOSE[6053] logger.c: -- Called g0/LC-201
May 8 10:53:38 DEBUG[6053] chan_zap.c: Exception on 19, channel 2
May 8 10:53:38 DEBUG[6053] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
May 8 10:53:38 DEBUG[6048] chan_zap.c: Exception on 20, channel 3
May 8 10:53:38 DEBUG[6048] chan_zap.c: Got event Ring/Answered(2) on channel 3 (index 0)
May 8 10:53:38 DEBUG[6048] chan_zap.c: Setting IDLE polarity due to ring. Old polarity was 0
May 8 10:53:38 DEBUG[6053] chan_zap.c: Exception on 19, channel 2
May 8 10:53:38 DEBUG[6053] chan_zap.c: Got event Dial Complete(9) on channel 2 (index 0)
May 8 10:53:38 DEBUG[6053] chan_zap.c: Enabled echo cancellation on channel 2
May 8 10:53:38 DEBUG[6053] chan_zap.c: Engaged echo training on channel 2
May 8 10:53:40 DEBUG[6053] chan_zap.c: Exception on 19, channel 2
May 8 10:53:40 DEBUG[6053] chan_zap.c: Got event Dial Complete(9) on channel 2 (index 0)
May 8 10:53:40 DEBUG[6053] chan_zap.c: Echo cancellation already on
May 8 10:53:40 VERBOSE[6053] logger.c: -- Zap/2-1 answered Local/LC-201@from-internal-28ad,2
May 8 10:53:40 VERBOSE[6048] logger.c: -- Local/LC-201@from-internal-28ad,1 answered Zap/3-1
May 8 10:53:40 DEBUG[6048] chan_zap.c: Requested indication -1 on channel Zap/3-1
May 8 10:53:40 DEBUG[6048] chan_zap.c: Took Zap/3-1 off hook
May 8 10:53:40 DEBUG[6048] chan_zap.c: Enabled echo cancellation on channel 3
May 8 10:53:40 DEBUG[6048] chan_zap.c: Engaged echo training on channel 3
May 8 10:53:40 DEBUG[6054] app_dial.c: Exiting with DIALSTATUS=CANCEL.
May 8 10:53:40 VERBOSE[6054] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'Local/RG-201-5038510262#@from-internal-ce3e,2' in macro 'dial'
May 8 10:53:40 VERBOSE[6054] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'Local/RG-201-5038510262#@from-internal-ce3e,2'
May 8 10:53:40 VERBOSE[6054] logger.c: -- Executing Macro("Local/RG-201-5038510262#@from-internal-ce3e,2", "hangupcall") in new stack
May 8 10:53:40 VERBOSE[6054] logger.c: -- Executing ResetCDR("Local/RG-201-5038510262#@from-internal-ce3e,2", "w") in new stack
May 8 10:53:40 DEBUG[6061] chan_sip.c: update_call_counter(5038510262) - decrement call limit counter
May 8 10:53:40 DEBUG[6061] chan_sip.c: Acked pending invite 103
May 8 10:53:40 DEBUG[6061] chan_sip.c: Stopping retransmission on '245440d00b2da21b0b0014664a4867f7@71.237.146.239' of Request 103: Match Found
May 8 10:53:40 DEBUG[6061] app_dial.c: Exiting with DIALSTATUS=CANCEL.
May 8 10:53:40 VERBOSE[6061] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'Local/5038510262@from-internal-1165,2' in macro 'dialout-trunk'
May 8 10:53:40 VERBOSE[6061] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'Local/5038510262@from-internal-1165,2'
May 8 10:53:40 VERBOSE[6061] logger.c: -- Executing Macro("Local/5038510262@from-internal-1165,2", "hangupcall") in new stack
May 8 10:53:40 VERBOSE[6061] logger.c: -- Executing ResetCDR("Local/5038510262@from-internal-1165,2", "w") in new stack
May 8 10:53:40 DEBUG[6053] channel.c: Planning to masquerade channel Zap/2-1 into the structure of Local/LC-201@from-internal-28ad,1
May 8 10:53:40 DEBUG[6053] channel.c: Done planning to masquerade channel Zap/2-1 into the structure of Local/LC-201@from-internal-28ad,1
May 8 10:53:40 DEBUG[6053] chan_local.c: Not posting to queue since already masked on 'Local/LC-201@from-internal-28ad,2'
May 8 10:53:40 DEBUG[6048] channel.c: Got clone lock for masquerade on 'Zap/2-1' at 0x84a96fc
May 8 10:53:40 DEBUG[6048] channel.c: Putting channel Zap/2-1 in 64/64 formats
May 8 10:53:40 DEBUG[6048] chan_zap.c: New owner for channel 2 is Zap/2-1
May 8 10:53:40 DEBUG[6048] chan_zap.c: Updated conferencing on 2, with 0 conference users
May 8 10:53:40 DEBUG[6048] chan_zap.c: Updated conferencing on 2, with 0 conference users
May 8 10:53:40 DEBUG[6048] channel.c: Released clone lock on 'Local/LC-201@from-internal-28ad,1'
May 8 10:53:40 DEBUG[6048] channel.c: Done Masquerading Zap/2-1 (6)
May 8 10:53:40 DEBUG[6053] channel.c: Didn't get a frame from channel: Local/LC-201@from-internal-28ad,2
May 8 10:53:40 DEBUG[6053] channel.c: Bridge stops bridging channels Local/LC-201@from-internal-28ad,2 and Local/LC-201@from-internal-28ad,1
May 8 10:53:40 DEBUG[6053] app_dial.c: Exiting with DIALSTATUS=ANSWER.
May 8 10:53:40 VERBOSE[6053] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'Local/LC-201@from-internal-28ad,2' in macro 'dialout-trunk'
May 8 10:53:40 VERBOSE[6053] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'Local/LC-201@from-internal-28ad,2'
May 8 10:53:40 VERBOSE[6053] logger.c: -- Executing Macro("Local/LC-201@from-internal-28ad,2", "hangupcall") in new stack
May 8 10:53:40 VERBOSE[6053] logger.c: -- Executing ResetCDR("Local/LC-201@from-internal-28ad,2", "w") in new stack
May 8 10:53:40 DEBUG[6054] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
May 8 10:53:40 DEBUG[6054] 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 ('2007-05-08 10:53:34','\"9712394147\" <9712394147>','9712394147','RG-201-5038510262#','from-internal', 'Local/RG-201-5038510262#@from-internal-ce3e,2','Local/5038510262@from-internal-1165,1','ResetCDR','w',6,0,'NO ANSWER',3,'')
May 8 10:53:40 VERBOSE[6054] logger.c: -- Executing NoCDR("Local/RG-201-5038510262#@from-internal-ce3e,2", "") in new stack
May 8 10:53:40 NOTICE[6054] cdr.c: CDR on channel 'Local/RG-201-5038510262#@from-internal-ce3e,2' not posted
May 8 10:53:40 NOTICE[6054] cdr.c: CDR on channel 'Local/RG-201-5038510262#@from-internal-ce3e,2' lacks end
May 8 10:53:40 DEBUG[6054] pbx.c: Expression result is '1'
May 8 10:53:40 VERBOSE[6054] logger.c: -- Executing GotoIf("Local/RG-201-5038510262#@from-internal-ce3e,2", "1?skiprg") in new stack
May 8 10:53:40 VERBOSE[6054] logger.c: -- Goto (macro-hangupcall,s,6)
May 8 10:53:40 DEBUG[6054] pbx.c: Expression result is '1'
May 8 10:53:40 VERBOSE[6054] logger.c: -- Executing GotoIf("Local/RG-201-5038510262#@from-internal-ce3e,2", "1?skipblkvm") in new stack
May 8 10:53:40 VERBOSE[6054] logger.c: -- Goto (macro-hangupcall,s,9)
May 8 10:53:40 DEBUG[6054] pbx.c: Expression result is '1'
May 8 10:53:40 VERBOSE[6054] logger.c: -- Executing GotoIf("Local/RG-201-5038510262#@from-internal-ce3e,2", "1?theend") in new stack
May 8 10:53:40 VERBOSE[6054] logger.c: -- Goto (macro-hangupcall,s,11)
May 8 10:53:40 VERBOSE[6054] logger.c: -- Executing Hangup("Local/RG-201-5038510262#@from-internal-ce3e,2", "") in new stack
May 8 10:53:40 VERBOSE[6054] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Local/RG-201-5038510262#@from-internal-ce3e,2' in macro 'hangupcall'
May 8 10:53:40 VERBOSE[6054] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Local/RG-201-5038510262#@from-internal-ce3e,2'
May 8 10:53:40 DEBUG[6061] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
May 8 10:53:40 DEBUG[6061] 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 ('2007-05-08 10:53:34','9712394147','9712394147','5038510262','from-internal', 'Local/5038510262@from-internal-1165,2','SIP/Vitelity-outbound-08549b90','ResetCDR','w',6,0,'NO ANSWER',3,'')
May 8 10:53:40 VERBOSE[6061] logger.c: -- Executing NoCDR("Local/5038510262@from-internal-1165,2", "") in new stack
May 8 10:53:40 NOTICE[6061] cdr.c: CDR on channel 'Local/5038510262@from-internal-1165,2' not posted
May 8 10:53:40 NOTICE[6061] cdr.c: CDR on channel 'Local/5038510262@from-internal-1165,2' lacks end
May 8 10:53:40 DEBUG[6061] pbx.c: Expression result is '1'
May 8 10:53:40 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "1?skiprg") in new stack
May 8 10:53:40 VERBOSE[6061] logger.c: -- Goto (macro-hangupcall,s,6)
May 8 10:53:40 DEBUG[6061] pbx.c: Expression result is '1'
May 8 10:53:40 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "1?skipblkvm") in new stack
May 8 10:53:40 VERBOSE[6061] logger.c: -- Goto (macro-hangupcall,s,9)
May 8 10:53:40 DEBUG[6061] pbx.c: Expression result is '1'
May 8 10:53:40 VERBOSE[6061] logger.c: -- Executing GotoIf("Local/5038510262@from-internal-1165,2", "1?theend") in new stack
May 8 10:53:40 VERBOSE[6061] logger.c: -- Goto (macro-hangupcall,s,11)
May 8 10:53:40 VERBOSE[6061] logger.c: -- Executing Hangup("Local/5038510262@from-internal-1165,2", "") in new stack
May 8 10:53:40 VERBOSE[6061] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Local/5038510262@from-internal-1165,2' in macro 'hangupcall'
May 8 10:53:40 VERBOSE[6061] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Local/5038510262@from-internal-1165,2'
May 8 10:53:40 DEBUG[6053] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
May 8 10:53:40 DEBUG[6053] 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 ('2007-05-08 10:53:34','9712394147','9712394147','LC-201','from-internal', 'Local/LC-201@from-internal-28ad,2','Zap/2-1','ResetCDR','w',6,0,'ANSWERED',3,'')
May 8 10:53:40 VERBOSE[6053] logger.c: -- Executing NoCDR("Local/LC-201@from-internal-28ad,2", "") in new stack
May 8 10:53:40 NOTICE[6053] cdr.c: CDR on channel 'Local/LC-201@from-internal-28ad,2' not posted
May 8 10:53:40 NOTICE[6053] cdr.c: CDR on channel 'Local/LC-201@from-internal-28ad,2' lacks end
May 8 10:53:40 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:40 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?skiprg") in new stack
May 8 10:53:40 VERBOSE[6053] logger.c: -- Goto (macro-hangupcall,s,6)
May 8 10:53:40 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:40 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?skipblkvm") in new stack
May 8 10:53:40 VERBOSE[6053] logger.c: -- Goto (macro-hangupcall,s,9)
May 8 10:53:40 DEBUG[6053] pbx.c: Expression result is '1'
May 8 10:53:40 VERBOSE[6053] logger.c: -- Executing GotoIf("Local/LC-201@from-internal-28ad,2", "1?theend") in new stack
May 8 10:53:40 VERBOSE[6053] logger.c: -- Goto (macro-hangupcall,s,11)
May 8 10:53:40 VERBOSE[6053] logger.c: -- Executing Hangup("Local/LC-201@from-internal-28ad,2", "") in new stack
May 8 10:53:40 VERBOSE[6053] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Local/LC-201@from-internal-28ad,2' in macro 'hangupcall'
May 8 10:53:40 VERBOSE[6053] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Local/LC-201@from-internal-28ad,2'
May 8 10:53:40 DEBUG[2719] chan_sip.c: Stopping retransmission on '245440d00b2da21b0b0014664a4867f7@71.237.146.239' of Request 103: Match Not Found
May 8 10:53:40 DEBUG[2719] chan_sip.c: Stopping retransmission on '245440d00b2da21b0b0014664a4867f7@71.237.146.239' of Request 103: Match Found
May 8 10:53:42 DEBUG[2719] acl.c: ##### Testing 64.2.142.26 with 192.168.2.0
May 8 10:53:42 DEBUG[2719] chan_sip.c: Target address 64.2.142.26 is not local, substituting externip
May 8 10:53:42 DEBUG[2719] chan_sip.c: Scheduled a registration timeout for inbound1.vitelity.net id #550451
May 8 10:53:42 DEBUG[2719] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '656fc1092dd93c9361b2be725d6bf654@127.0.0.1' Request 104: Found
May 8 10:53:42 DEBUG[2719] chan_sip.c: Stopping retransmission on '656fc1092dd93c9361b2be725d6bf654@127.0.0.1' of Request 104: Match Found
May 8 10:53:42 DEBUG[2719] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '656fc1092dd93c9361b2be725d6bf654@127.0.0.1' Request 105: Found
May 8 10:53:42 DEBUG[2719] chan_sip.c: Stopping retransmission on '656fc1092dd93c9361b2be725d6bf654@127.0.0.1' of Request 105: Match Found
May 8 10:53:42 DEBUG[2719] chan_sip.c: Registration successful
May 8 10:53:42 DEBUG[2719] chan_sip.c: Cancelling timeout 550451
May 8 10:53:56 DEBUG[2719] acl.c: ##### Testing 192.168.2.92 with 192.168.2.0
May 8 10:53:56 DEBUG[2719] chan_sip.c: Stopping retransmission on '7899907b526894520f53268011e45531@192.168.2.12' of Request 102: Match Found
May 8 10:53:56 DEBUG[2719] acl.c: ##### Testing 192.168.2.59 with 192.168.2.0
May 8 10:53:56 DEBUG[2719] chan_sip.c: Stopping retransmission on '55dec9dd5e056d29525a3b81445cde78@192.168.2.12' of Request 102: Match Found
May 8 10:53:56 DEBUG[2719] acl.c: ##### Testing 4.79.19.56 with 192.168.2.0
May 8 10:53:56 DEBUG[2719] chan_sip.c: Target address 4.79.19.56 is not local, substituting externip
May 8 10:53:56 DEBUG[2719] chan_sip.c: Stopping retransmission on '46b3b060570b7305678663f10bdaa4c1@71.237.146.239' of Request 102: Match Found
May 8 10:53:59 DEBUG[6048] chan_zap.c: Exception on 20, channel 3
May 8 10:53:59 DEBUG[6048] chan_zap.c: Got event On hook(1) on channel 3 (index 0)
May 8 10:53:59 DEBUG[6048] chan_zap.c: disabled echo cancellation on channel 3
May 8 10:53:59 DEBUG[6048] channel.c: Didn't get a frame from channel: Zap/3-1
May 8 10:53:59 DEBUG[6048] channel.c: Bridge stops bridging channels Zap/3-1 and Zap/2-1
May 8 10:53:59 DEBUG[6048] chan_zap.c: Hangup: channel: 2 index = 0, normal = 19, callwait = -1, thirdcall = -1
May 8 10:53:59 DEBUG[6048] chan_zap.c: disabled echo cancellation on channel 2
May 8 10:53:59 DEBUG[6048] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/2-1
May 8 10:53:59 DEBUG[6048] chan_zap.c: Updated conferencing on 2, with 0 conference users
May 8 10:53:59 VERBOSE[6048] logger.c: -- Hungup 'Zap/2-1'
May 8 10:53:59 DEBUG[6048] app_dial.c: Exiting with DIALSTATUS=ANSWER.
May 8 10:53:59 VERBOSE[6048] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'Zap/3-1' in macro 'dial'
May 8 10:53:59 VERBOSE[6048] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'Zap/3-1' in macro 'dial-confirm'
May 8 10:53:59 VERBOSE[6048] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'Zap/3-1'
May 8 10:53:59 VERBOSE[6048] logger.c: -- Executing Macro("Zap/3-1", "hangupcall") in new stack
May 8 10:53:59 VERBOSE[6048] logger.c: -- Executing ResetCDR("Zap/3-1", "w") in new stack
May 8 10:53:59 DEBUG[6048] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
May 8 10:53:59 DEBUG[6048] 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 ('2007-05-08 10:53:33','\"9712394147\" <9712394147>','9712394147','201','ext-findmefollow', 'Zap/3-1','Local/LC-201@from-internal-28ad,1','ResetCDR','w',26,19,'ANSWERED',3,'')
May 8 10:53:59 VERBOSE[6048] logger.c: -- Executing NoCDR("Zap/3-1", "") in new stack
May 8 10:53:59 NOTICE[6048] cdr.c: CDR on channel 'Zap/3-1' not posted
May 8 10:53:59 NOTICE[6048] cdr.c: CDR on channel 'Zap/3-1' lacks end
May 8 10:53:59 DEBUG[6048] pbx.c: Expression result is '0'
May 8 10:53:59 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "0?skiprg") in new stack
May 8 10:53:59 DEBUG[6048] pbx.c: Not taking any branch
May 8 10:53:59 VERBOSE[6048] logger.c: -- Executing NoOp("Zap/3-1", "Cleaning Up Confirmation Flag: RG/201/Zap/3-1") in new stack
May 8 10:53:59 VERBOSE[6048] logger.c: -- Executing DBdel("Zap/3-1", "RG/201/Zap/3-1") in new stack
May 8 10:53:59 VERBOSE[6048] logger.c: -- DBdel: family=RG, key=201/Zap/3-1
May 8 10:53:59 DEBUG[6048] pbx.c: Expression result is '0'
May 8 10:53:59 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "0?skipblkvm") in new stack
May 8 10:53:59 DEBUG[6048] pbx.c: Not taking any branch
May 8 10:53:59 VERBOSE[6048] logger.c: -- Executing NoOp("Zap/3-1", "Cleaning Up Block VM Flag: BLKVM/201/Zap/3-1") in new stack
May 8 10:53:59 VERBOSE[6048] logger.c: -- Executing DBdel("Zap/3-1", "BLKVM/201/Zap/3-1") in new stack
May 8 10:53:59 VERBOSE[6048] logger.c: -- DBdel: family=BLKVM, key=201/Zap/3-1
May 8 10:53:59 DEBUG[6048] pbx.c: Expression result is '1'
May 8 10:53:59 VERBOSE[6048] logger.c: -- Executing GotoIf("Zap/3-1", "1?theend") in new stack
May 8 10:53:59 VERBOSE[6048] logger.c: -- Goto (macro-hangupcall,s,11)
May 8 10:53:59 VERBOSE[6048] logger.c: -- Executing Hangup("Zap/3-1", "") in new stack
May 8 10:53:59 VERBOSE[6048] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Zap/3-1' in macro 'hangupcall'
May 8 10:53:59 VERBOSE[6048] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Zap/3-1'
May 8 10:53:59 DEBUG[6048] chan_zap.c: Hangup: channel: 3 index = 0, normal = 20, callwait = -1, thirdcall = -1
May 8 10:53:59 DEBUG[6048] chan_zap.c: disabled echo cancellation on channel 3
May 8 10:53:59 DEBUG[6048] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/3-1
May 8 10:53:59 DEBUG[6048] chan_zap.c: Updated conferencing on 3, with 0 conference users
May 8 10:53:59 VERBOSE[6048] logger.c: -- Hungup 'Zap/3-1'
May 8 10:53:59 VERBOSE[6048] logger.c: -- Hungup 'Zap/3-1'