Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Allocating new SIP dialog for call-804FD691-2BC2-D311-0016-9A@192.168.16.101 - INVITE (With RTP) Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Setting NAT on RTP to 0 Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Checking SIP call limits for device 192.168.16.100 Aug 1 11:40:24 DEBUG[2732] chan_sip.c: build_route: Contact hop: Aug 1 11:40:24 DEBUG[2682] channel.c: Avoiding initial deadlock for 'SIP/192.168.16.100-c2e4' Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Set' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Set("SIP/192.168.16.100-c2e4", "FROM_DID=s") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Goto' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Goto("SIP/192.168.16.100-c2e4", "ext-group|1|1") in new stack Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Goto (ext-group,1,1) Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Macro' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Macro("SIP/192.168.16.100-c2e4", "user-callerid|") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Expression result is '0' Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'GotoIf' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing GotoIf("SIP/192.168.16.100-c2e4", "0?report") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Not taking any branch Aug 1 11:40:24 DEBUG[8525] pbx.c: Expression result is '0' Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'GotoIf' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing GotoIf("SIP/192.168.16.100-c2e4", "0?start") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Not taking any branch Aug 1 11:40:24 DEBUG[8525] pbx.c: Function result is '' Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Set' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Set("SIP/192.168.16.100-c2e4", "REALCALLERIDNUM=") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'NoOp' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing NoOp("SIP/192.168.16.100-c2e4", "REALCALLERIDNUM is ") in new stack Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '/user' in family 'DEVICE' Aug 1 11:40:24 DEBUG[8525] func_db.c: DB: DEVICE//user not found in database. Aug 1 11:40:24 DEBUG[8525] pbx.c: Function result is '' Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Set' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Set("SIP/192.168.16.100-c2e4", "AMPUSER=") in new stack Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '/cidname' in family 'AMPUSER' Aug 1 11:40:24 DEBUG[8525] func_db.c: DB: AMPUSER//cidname not found in database. Aug 1 11:40:24 DEBUG[8525] pbx.c: Function result is '' Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Set' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Set("SIP/192.168.16.100-c2e4", "AMPUSERCIDNAME=") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Expression result is '1' Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'GotoIf' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing GotoIf("SIP/192.168.16.100-c2e4", "1?report") in new stack Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Goto (macro-user-callerid,s,9) Aug 1 11:40:24 DEBUG[8525] pbx.c: Function result is '"" <>' Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'NoOp' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing NoOp("SIP/192.168.16.100-c2e4", "Using CallerID "" <>") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Function result is '0' Aug 1 11:40:24 DEBUG[8525] pbx.c: Function result is '' Aug 1 11:40:24 DEBUG[8525] pbx.c: Expression result is '0' Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'GotoIf' 40% Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing GotoIf("SIP/192.168.16.100-c2e4", "0?NEWPREFIX") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Not taking any branch Aug 1 11:40:24 DEBUG[8525] pbx.c: Function result is '0' Aug 1 11:40:24 DEBUG[8525] pbx.c: Function result is '' Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Set' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Set("SIP/192.168.16.100-c2e4", "CALLERID(name)=") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Set' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Set("SIP/192.168.16.100-c2e4", "RGPREFIX=") in new stack Aug 1 11:40:24 DEBUG[8526] app_queue.c: Device 'SIP/192.168.16.100' changed to state '2' (In use) but we don't care because they're not a member of any queu e. Aug 1 11:40:24 DEBUG[8525] pbx.c: Function result is '' Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Set' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Set("SIP/192.168.16.100-c2e4", "CALLERID(name)=") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Set' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Set("SIP/192.168.16.100-c2e4", "RecordMethod=Group") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Macro' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Macro("SIP/192.168.16.100-c2e4", "record-enable|130-131-132-133-134-135-136|Group") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Function result is '0' Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'GotoIf' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing GotoIf("SIP/192.168.16.100-c2e4", "0 > 0?2:4") in new stack Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Goto (macro-record-enable,s,4) Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'AGI' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing AGI("SIP/192.168.16.100-c2e4", "recordingcheck|20060801-114024|1154403624.1838") in new stack Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck Aug 1 11:40:24 VERBOSE[8525] logger.c: -- AGI Script recordingcheck completed, returning 0 Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'NoOp' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing NoOp("SIP/192.168.16.100-c2e4", "No recording needed") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Set' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Set("SIP/192.168.16.100-c2e4", "RingGroupMethod=ringall") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Macro' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Macro("SIP/192.168.16.100-c2e4", "dial|60|tr|130-131-132-133-134-135-136") in new stack Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'AGI' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing AGI("SIP/192.168.16.100-c2e4", "dialparties.agi") in new stack Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi Aug 1 11:40:24 VERBOSE[8525] logger.c: dialparties.agi: Starting New Dialparties.agi Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: priority is 1 Aug 1 11:40:24 VERBOSE[8525] logger.c: dialparties.agi: Caller ID name is 'unknown' number is 'unknown' Aug 1 11:40:24 VERBOSE[8525] logger.c: dialparties.agi: Methodology of ring is 'ringall' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Added extension 130 to extension map Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Added extension 131 to extension map Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Added extension 132 to extension map Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Added extension 133 to extension map Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Added extension 134 to extension map Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Added extension 135 to extension map Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Added extension 136 to extension map Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '130' in family 'CF' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 130 cf is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '131' in family 'CF' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 131 cf is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '132' in family 'CF' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 132 cf is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '133' in family 'CF' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 133 cf is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '134' in family 'CF' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 134 cf is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '135' in family 'CF' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 135 cf is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '136' in family 'CF' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 136 cf is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '130' in family 'DND' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 130 do not disturb is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '131' in family 'DND' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 131 do not disturb is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '132' in family 'DND' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 132 do not disturb is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '133' in family 'DND' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 133 do not disturb is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '134' in family 'DND' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 134 do not disturb is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '135' in family 'DND' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 135 do not disturb is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '136' in family 'DND' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Extension 136 do not disturb is disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '130' in family 'CFB' Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '130' in family 'CFU' Aug 1 11:40:24 DEBUG[8518] rtp.c: Got RTCP report of 68 bytes Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: DbSet CALLTRACE/130 to unknown Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '131' in family 'CFB' Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '131' in family 'CFU' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: DbSet CALLTRACE/131 to unknown Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '132' in family 'CW' Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '132' in family 'CFB' Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '132' in family 'CFU' Aug 1 11:40:24 DEBUG[8529] manager.c: Manager received command 'login' Aug 1 11:40:24 DEBUG[8529] config.c: Parsing /etc/asterisk/manager.conf Aug 1 11:40:24 DEBUG[8529] config.c: Parsing /etc/asterisk/manager_custom.conf Aug 1 11:40:24 WARNING[8529] config.c: Unknown directive 'permit=192.168.1.0/255.255.255.0' at line 18 of manager_custom.conf Aug 1 11:40:24 DEBUG[8529] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer Aug 1 11:40:24 DEBUG[8529] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer Aug 1 11:40:24 DEBUG[8529] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0 Aug 1 11:40:24 DEBUG[8529] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0 Aug 1 11:40:24 VERBOSE[8529] logger.c: == Manager 'admin' logged on from 127.0.0.1 Aug 1 11:40:24 DEBUG[8529] manager.c: Manager received command 'ExtensionState' Aug 1 11:40:24 DEBUG[8529] manager.c: Manager received command 'Logoff' Aug 1 11:40:24 VERBOSE[8529] logger.c: == Manager 'admin' logged off from 127.0.0.1 Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 132 Aug 1 11:40:24 VERBOSE[8525] logger.c: dialparties.agi: Extension 132 is not available to be called Aug 1 11:40:24 VERBOSE[8525] logger.c: dialparties.agi: Extension 132 has call waiting disabled Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '133' in family 'CW' Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '133' in family 'CFB' Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '133' in family 'CFU' Aug 1 11:40:24 DEBUG[8530] manager.c: Manager received command 'login' Aug 1 11:40:24 DEBUG[8530] config.c: Parsing /etc/asterisk/manager.conf Aug 1 11:40:24 DEBUG[8530] config.c: Parsing /etc/asterisk/manager_custom.conf Aug 1 11:40:24 WARNING[8530] config.c: Unknown directive 'permit=192.168.1.0/255.255.255.0' at line 18 of manager_custom.conf Aug 1 11:40:24 DEBUG[8530] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer Aug 1 11:40:24 DEBUG[8530] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer Aug 1 11:40:24 DEBUG[8530] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0 Aug 1 11:40:24 DEBUG[8530] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0 Aug 1 11:40:24 VERBOSE[8530] logger.c: == Manager 'admin' logged on from 127.0.0.1 Aug 1 11:40:24 DEBUG[8530] manager.c: Manager received command 'ExtensionState' Aug 1 11:40:24 DEBUG[8530] manager.c: Manager received command 'Logoff' Aug 1 11:40:24 VERBOSE[8530] logger.c: == Manager 'admin' logged off from 127.0.0.1 Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 133 Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: DbSet CALLTRACE/133 to unknown Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '134' in family 'CFB' Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '134' in family 'CFU' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: DbSet CALLTRACE/134 to unknown Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '135' in family 'CFB' Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '135' in family 'CFU' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: DbSet CALLTRACE/135 to unknown Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '136' in family 'CFB' Aug 1 11:40:24 DEBUG[8525] db.c: Unable to find key '136' in family 'CFU' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- dialparties.agi: DbSet CALLTRACE/136 to unknown Aug 1 11:40:24 VERBOSE[8525] logger.c: -- AGI Script dialparties.agi completed, returning 0 Aug 1 11:40:24 DEBUG[8525] pbx.c: Launching 'Dial' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Executing Dial("SIP/192.168.16.100-c2e4", "SIP/130&SIP/131&SIP/133&SIP/134&SIP/135&SIP/136|60|tr") in new stac k Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Setting NAT on RTP to 0 Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-10. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_DEPTH. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ds. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable DIALSTATUS. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_PRIORITY. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_CONTEXT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_EXTEN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RingGroupMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-8. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecEnable. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecordMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RGPREFIX. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSERCIDNAME. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSER. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable REALCALLERIDNUM. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable FROM_DID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPCALLID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPUSERAGENT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPDOMAIN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPURI. Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Outgoing Call for 130 Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Called 130 Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Setting NAT on RTP to 0 Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-10. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_DEPTH. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ds. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable DIALSTATUS. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_PRIORITY. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_CONTEXT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_EXTEN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RingGroupMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-8. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecEnable. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecordMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RGPREFIX. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSERCIDNAME. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSER. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable REALCALLERIDNUM. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable FROM_DID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPCALLID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPUSERAGENT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPDOMAIN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPURI. Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Outgoing Call for 131 Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Called 131 Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Setting NAT on RTP to 0 Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-10. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_DEPTH. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ds. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable DIALSTATUS. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_PRIORITY. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_CONTEXT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_EXTEN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RingGroupMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-8. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecEnable. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecordMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RGPREFIX. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSERCIDNAME. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSER. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable REALCALLERIDNUM. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable FROM_DID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPCALLID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPUSERAGENT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPDOMAIN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPURI. Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Outgoing Call for 133 Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Called 133 Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Setting NAT on RTP to 0 Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-10. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_DEPTH. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ds. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable DIALSTATUS. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_PRIORITY. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_CONTEXT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_EXTEN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RingGroupMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-8. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecEnable. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecordMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RGPREFIX. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSERCIDNAME. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSER. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable REALCALLERIDNUM. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable FROM_DID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPCALLID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPUSERAGENT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPDOMAIN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPURI. Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Outgoing Call for 134 Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Called 134 Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Setting NAT on RTP to 0 Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-10. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_DEPTH. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ds. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable DIALSTATUS. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_PRIORITY. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_CONTEXT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_EXTEN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RingGroupMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-8. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecEnable. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecordMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RGPREFIX. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSERCIDNAME. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSER. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable REALCALLERIDNUM. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable FROM_DID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPCALLID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPUSERAGENT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPDOMAIN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPURI. Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Outgoing Call for 135 Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Called 135 Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP) Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Setting NAT on RTP to 0 Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-10. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_DEPTH. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ds. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable DIALSTATUS. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_PRIORITY. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_CONTEXT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_EXTEN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RingGroupMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-8. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecEnable. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecordMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RGPREFIX. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSERCIDNAME. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSER. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable REALCALLERIDNUM. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable FROM_DID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPCALLID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPUSERAGENT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPDOMAIN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPURI. Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Outgoing Call for 136 Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Called 136 Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Acked pending invite 102 Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Stopping retransmission on '775fb1ae4a0a4f4722d35801127fc24a@192.168.16.100' of Request 102: Match Found Aug 1 11:40:24 VERBOSE[2732] logger.c: -- Got SIP response 302 "Moved Temporarily" back from 192.168.16.30 Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Found 302 Redirect to extension '*98130' Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Now forwarding SIP/192.168.16.100-c2e4 to 'Local/*98130@from-internal' (thanks to SIP/130-4f91) Aug 1 11:40:24 DEBUG[8531] app_queue.c: Device 'Local/*98130@from-internal' changed to state '2' (In use) but we don't care because they're not a member of any queue. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-10. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_DEPTH. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ds. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable DIALSTATUS. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-dial-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable ARG1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_PRIORITY. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_CONTEXT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable MACRO_EXTEN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RingGroupMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-8. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecEnable. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-record-enable-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RecordMethod. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable RGPREFIX. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-9. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-7. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSERCIDNAME. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-6. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable AMPUSER. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-5. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-4. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable REALCALLERIDNUM. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-3. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-macro-user-callerid-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-ext-group-1-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-2. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable FROM_DID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable STACK-from-trunk-s-1. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPCALLID. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPUSERAGENT. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPDOMAIN. Aug 1 11:40:24 DEBUG[8525] channel.c: Not copying variable SIPURI. Aug 1 11:40:24 DEBUG[8525] channel.c: Hanging up channel 'SIP/130-4f91' Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Hangup call SIP/130-4f91, SIP callid 775fb1ae4a0a4f4722d35801127fc24a@192.168.16.100) Aug 1 11:40:24 DEBUG[8525] chan_sip.c: update_call_counter(130) - decrement call limit counter Aug 1 11:40:24 DEBUG[8533] app_queue.c: Device 'SIP/130' changed to state '2' (In use) but we don't care because they're not a member of any queue. Aug 1 11:40:24 DEBUG[8532] pbx.c: Launching 'Answer' Aug 1 11:40:24 VERBOSE[8532] logger.c: -- Executing Answer("Local/*98130@from-internal-c732,2", "") in new stack Aug 1 11:40:24 VERBOSE[8525] logger.c: -- Local/*98130@from-internal-c732,1 answered SIP/192.168.16.100-c2e4 Aug 1 11:40:24 DEBUG[8525] channel.c: Hanging up channel 'SIP/136-8820' Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Hangup call SIP/136-8820, SIP callid 4a3c3c0f030202b84f04a67c493591c0@192.168.16.100) Aug 1 11:40:24 DEBUG[8525] chan_sip.c: update_call_counter(136) - decrement call limit counter Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Acked pending invite 102 Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Stopping retransmission on '4a3c3c0f030202b84f04a67c493591c0@192.168.16.100' of Request 102: Match Found Aug 1 11:40:24 DEBUG[8532] pbx.c: Launching 'Wait' Aug 1 11:40:24 VERBOSE[8532] logger.c: -- Executing Wait("Local/*98130@from-internal-c732,2", "1") in new stack Aug 1 11:40:24 DEBUG[8536] app_queue.c: Device 'SIP/136' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. Aug 1 11:40:24 DEBUG[8535] app_queue.c: Device 'Local/*98130@from-internal' changed to state '2' (In use) but we don't care because they're not a member of any queue. Aug 1 11:40:24 DEBUG[8525] channel.c: Hanging up channel 'SIP/135-fe5e' Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Hangup call SIP/135-fe5e, SIP callid 2cc4881c151a0e613843fd682fa3af17@192.168.16.100) Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Failed to grab lock, trying again... Aug 1 11:40:24 DEBUG[8534] app_queue.c: Device 'Local/*98130@from-internal' changed to state '2' (In use) but we don't care because they're not a member of any queue. Aug 1 11:40:24 DEBUG[8525] chan_sip.c: update_call_counter(135) - decrement call limit counter Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Acked pending invite 102 Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Stopping retransmission on '2cc4881c151a0e613843fd682fa3af17@192.168.16.100' of Request 102: Match Found Aug 1 11:40:24 DEBUG[8525] channel.c: Hanging up channel 'SIP/134-e942' Aug 1 11:40:24 DEBUG[8537] app_queue.c: Device 'SIP/135' changed to state '2' (In use) but we don't care because they're not a member of any queue. Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Hangup call SIP/134-e942, SIP callid 4b0d44a0080514845cc4e063231fd867@192.168.16.100) Aug 1 11:40:24 DEBUG[8525] chan_sip.c: update_call_counter(134) - decrement call limit counter Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Acked pending invite 102 Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Stopping retransmission on '4b0d44a0080514845cc4e063231fd867@192.168.16.100' of Request 102: Match Found Aug 1 11:40:24 DEBUG[8525] channel.c: Hanging up channel 'SIP/133-8006' Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Hangup call SIP/133-8006, SIP callid 4434cb91098ad881402548e82d679b70@192.168.16.100) Aug 1 11:40:24 DEBUG[8525] chan_sip.c: update_call_counter(133) - decrement call limit counter Aug 1 11:40:24 DEBUG[8538] app_queue.c: Device 'SIP/134' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Acked pending invite 102 Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Stopping retransmission on '4434cb91098ad881402548e82d679b70@192.168.16.100' of Request 102: Match Found Aug 1 11:40:24 DEBUG[8525] channel.c: Hanging up channel 'SIP/131-0fde' Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Hangup call SIP/131-0fde, SIP callid 7a30eb446ab2f65f354d195b2e1b25b7@192.168.16.100) Aug 1 11:40:24 DEBUG[8525] chan_sip.c: update_call_counter(131) - decrement call limit counter Aug 1 11:40:24 DEBUG[8539] app_queue.c: Device 'SIP/133' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Acked pending invite 102 Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Stopping retransmission on '7a30eb446ab2f65f354d195b2e1b25b7@192.168.16.100' of Request 102: Match Found Aug 1 11:40:24 DEBUG[8525] channel.c: Set channel SIP/192.168.16.100-c2e4 to read format ulaw Aug 1 11:40:24 DEBUG[8525] channel.c: Set channel Local/*98130@from-internal-c732,1 to write format ulaw Aug 1 11:40:24 DEBUG[8525] channel.c: Set channel Local/*98130@from-internal-c732,1 to read format ulaw Aug 1 11:40:24 DEBUG[8525] channel.c: Set channel SIP/192.168.16.100-c2e4 to write format ulaw Aug 1 11:40:24 DEBUG[8540] app_queue.c: Device 'SIP/131' changed to state '1' (Not in use) but we don't care because they're not a member of any queue. Aug 1 11:40:24 DEBUG[8525] chan_sip.c: sip_answer(SIP/192.168.16.100-c2e4) Aug 1 11:40:24 DEBUG[2682] channel.c: Avoiding initial deadlock for 'SIP/192.168.16.100-c2e4' Aug 1 11:40:24 DEBUG[2732] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2cc4881c151a0e613843fd682fa3af17@192.168.16.100' Req uest 102: Not Found Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Allocating new SIP dialog for 4434cb91098ad881402548e82d679b70@192.168.16.100 - SIP/2.0 (No RTP) Aug 1 11:40:24 DEBUG[2732] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Allocating new SIP dialog for 7a30eb446ab2f65f354d195b2e1b25b7@192.168.16.100 - SIP/2.0 (No RTP) Aug 1 11:40:24 DEBUG[2732] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Allocating new SIP dialog for 4a3c3c0f030202b84f04a67c493591c0@192.168.16.100 - SIP/2.0 (No RTP) Aug 1 11:40:24 DEBUG[2732] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 Aug 1 11:40:24 DEBUG[8541] app_queue.c: Device 'SIP/192.168.16.100' changed to state '2' (In use) but we don't care because they're not a member of any queu e. Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Allocating new SIP dialog for 4b0d44a0080514845cc4e063231fd867@192.168.16.100 - SIP/2.0 (No RTP) Aug 1 11:40:24 DEBUG[2732] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Allocating new SIP dialog for 4434cb91098ad881402548e82d679b70@192.168.16.100 - SIP/2.0 (No RTP) Aug 1 11:40:24 DEBUG[2732] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Allocating new SIP dialog for 7a30eb446ab2f65f354d195b2e1b25b7@192.168.16.100 - SIP/2.0 (No RTP) Aug 1 11:40:24 DEBUG[2732] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Allocating new SIP dialog for 4a3c3c0f030202b84f04a67c493591c0@192.168.16.100 - SIP/2.0 (No RTP) Aug 1 11:40:24 DEBUG[2732] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Allocating new SIP dialog for 4b0d44a0080514845cc4e063231fd867@192.168.16.100 - SIP/2.0 (No RTP) Aug 1 11:40:24 DEBUG[2732] chan_sip.c: That's odd... Got a response on a call we dont know about. Cseq 102 Cmd SIP/2.0 Aug 1 11:40:24 DEBUG[2732] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2cc4881c151a0e613843fd682fa3af17@192.168.16.100' Req uest 102: Not Found Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Stopping retransmission on '2cc4881c151a0e613843fd682fa3af17@192.168.16.100' of Request 102: Match Found Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Stopping retransmission on 'call-804FD691-2BC2-D311-0016-9A@192.168.16.101' of Response 1: Match Found Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Stopping retransmission on '2cc4881c151a0e613843fd682fa3af17@192.168.16.100' of Request 102: Match Not Found Aug 1 11:40:24 DEBUG[2732] chan_sip.c: Stopping retransmission on '2cc4881c151a0e613843fd682fa3af17@192.168.16.100' of Request 103: Match Found Aug 1 11:40:24 DEBUG[8525] chan_sip.c: Oooh, format changed to 8 Aug 1 11:40:24 DEBUG[8525] channel.c: Set channel SIP/192.168.16.100-c2e4 to read format ulaw Aug 1 11:40:24 DEBUG[8525] channel.c: Set channel SIP/192.168.16.100-c2e4 to write format ulaw Aug 1 11:40:24 DEBUG[8525] channel.c: Set channel SIP/192.168.16.100-c2e4 to read format slin Aug 1 11:40:24 DEBUG[8525] channel.c: Set channel Local/*98130@from-internal-c732,1 to write format slin Aug 1 11:40:24 DEBUG[8525] channel.c: Set channel Local/*98130@from-internal-c732,1 to read format slin Aug 1 11:40:24 DEBUG[8525] channel.c: Set channel SIP/192.168.16.100-c2e4 to write format slin