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: <sip:192.168.16.101>
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