Nov 10 19:24:00 DEBUG[2070]: Manager received command 'Command'
Nov 10 19:24:00 DEBUG[2070]: Manager received command 'Command'
Nov 10 19:24:28 VERBOSE[2070]: -- Starting simple switch on 'Zap/1-1'
Nov 10 19:24:34 DEBUG[2070]: DTMF digit: 9 on Zap/1-1
Nov 10 19:24:35 DEBUG[2070]: DTMF digit: 1 on Zap/1-1
Nov 10 19:24:35 DEBUG[2070]: DTMF digit: 6 on Zap/1-1
Nov 10 19:24:35 DEBUG[2070]: DTMF digit: 4 on Zap/1-1
Nov 10 19:24:36 DEBUG[2070]: DTMF digit: 6 on Zap/1-1
Nov 10 19:24:36 DEBUG[2070]: DTMF digit: 5 on Zap/1-1
Nov 10 19:24:36 DEBUG[2070]: DTMF digit: 0 on Zap/1-1
Nov 10 19:24:37 DEBUG[2070]: DTMF digit: 9 on Zap/1-1
Nov 10 19:24:37 DEBUG[2070]: DTMF digit: 4 on Zap/1-1
Nov 10 19:24:37 DEBUG[2070]: DTMF digit: 8 on Zap/1-1
Nov 10 19:24:38 DEBUG[2070]: DTMF digit: 3 on Zap/1-1
Nov 10 19:24:38 DEBUG[2070]: DTMF digit: 5 on Zap/1-1
Nov 10 19:24:41 DEBUG[2070]: No echocancellation requested
Nov 10 19:24:41 VERBOSE[2070]: -- Executing Macro("Zap/1-1", "dialout-trunk|1|16465094835|") in new stack
Nov 10 19:24:41 DEBUG[2070]: Expression is '1'
Nov 10 19:24:41 VERBOSE[2070]: -- Executing GotoIf("Zap/1-1", "1?3:2)") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Goto (macro-dialout-trunk,s,3)
Nov 10 19:24:41 VERBOSE[2070]: -- Executing Macro("Zap/1-1", "record-enable|200|OUT") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Executing GotoIf("Zap/1-1", "0 > 0?2:4") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Goto (macro-record-enable,s,4)
Nov 10 19:24:41 DEBUG[2070]: Expression is '1'
Nov 10 19:24:41 VERBOSE[2070]: -- Executing GotoIf("Zap/1-1", "1?5:8") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Goto (macro-record-enable,s,5)
Nov 10 19:24:41 VERBOSE[2070]: -- Executing DBget("Zap/1-1", "RecEnable=RECORD-OUT/200") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- DBget: varname=RecEnable, family=RECORD-OUT, key=200
Nov 10 19:24:41 DEBUG[2070]: Unable to find key '200' in family 'RECORD-OUT'
Nov 10 19:24:41 VERBOSE[2070]: -- DBget: Value not found in database.
Nov 10 19:24:41 VERBOSE[2070]: -- Executing SetVar("Zap/1-1", "CALLFILENAME=OUT200-20051110-192441-1131668668.9") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Executing Goto("Zap/1-1", "s|14") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Goto (macro-record-enable,s,14)
Nov 10 19:24:41 DEBUG[2070]: Expression is '0'
Nov 10 19:24:41 VERBOSE[2070]: -- Executing GotoIf("Zap/1-1", "0?15:99") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Goto (macro-record-enable,s,99)
Nov 10 19:24:41 VERBOSE[2070]: -- Executing NoOp("Zap/1-1", "NO RECORDING NEEDED") in new stack
Nov 10 19:24:41 DEBUG[2070]: Expression is '1'
Nov 10 19:24:41 VERBOSE[2070]: -- Executing GotoIf("Zap/1-1", "1?7") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Goto (macro-dialout-trunk,s,7)
Nov 10 19:24:41 DEBUG[2070]: Expression is '0'
Nov 10 19:24:41 VERBOSE[2070]: -- Executing GotoIf("Zap/1-1", "0?9") in new stack
Nov 10 19:24:41 DEBUG[2070]: Not taking any branch
Nov 10 19:24:41 VERBOSE[2070]: -- Executing SetCallerID("Zap/1-1", "12125685159") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Executing SetGroup("Zap/1-1", "OUT_1") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Executing CheckGroup("Zap/1-1", "1") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Executing SetVar("Zap/1-1", "DIAL_NUMBER=16465094835") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Executing SetVar("Zap/1-1", "DIAL_TRUNK=1") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Executing AGI("Zap/1-1", "fixlocalprefix") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Nov 10 19:24:41 VERBOSE[2070]: -- AGI Script fixlocalprefix completed, returning 0
Nov 10 19:24:41 VERBOSE[2070]: -- Executing SetVar("Zap/1-1", "OUTNUM=916465094835") in new stack
Nov 10 19:24:41 VERBOSE[2070]: -- Executing Cut("Zap/1-1", "custom=OUT_1|:|1") in new stack
Nov 10 19:24:41 DEBUG[2070]: Expression is '0'
Nov 10 19:24:41 VERBOSE[2070]: -- Executing GotoIf("Zap/1-1", "0?19") in new stack
Nov 10 19:24:41 DEBUG[2070]: Not taking any branch
Nov 10 19:24:41 VERBOSE[2070]: -- Executing Dial("Zap/1-1", "ZAP/g0/916465094835") in new stack
Nov 10 19:24:41 DEBUG[2070]: Dialing '916465094835'
Nov 10 19:24:41 DEBUG[2070]: Deferring dialing...
Nov 10 19:24:41 VERBOSE[2070]: -- Called g0/916465094835
Nov 10 19:24:42 DEBUG[2070]: Exception on 17, channel 4
Nov 10 19:24:42 DEBUG[2070]: Got event Hook Transition Complete(12) on channel 4 (index 0)
Nov 10 19:24:44 DEBUG[2070]: Exception on 17, channel 4
Nov 10 19:24:44 DEBUG[2070]: Got event Dial Complete(9) on channel 4 (index 0)
Nov 10 19:24:44 DEBUG[2070]: Enabled echo cancellation on channel 4
Nov 10 19:24:44 DEBUG[2070]: Engaged echo training on channel 4
Nov 10 19:24:46 DEBUG[2070]: Exception on 17, channel 4
Nov 10 19:24:46 DEBUG[2070]: Got event Dial Complete(9) on channel 4 (index 0)
Nov 10 19:24:46 DEBUG[2070]: Echo cancellation already on
Nov 10 19:24:46 DEBUG[2070]: Dropping duplicate answer!
Nov 10 19:24:46 VERBOSE[2070]: -- Zap/4-1 answered Zap/1-1
Nov 10 19:24:46 DEBUG[2070]: Took Zap/1-1 off hook
Nov 10 19:24:46 VERBOSE[2070]: -- Attempting native bridge of Zap/1-1 and Zap/4-1
Nov 10 19:24:46 DEBUG[2070]: master: 1, slave: 4, nothingok: 0
Nov 10 19:24:46 DEBUG[2070]: Stoping tones on 1/0 talking to 4/0
Nov 10 19:24:46 DEBUG[2070]: Stoping tones on 4/0 talking to 1/0
Nov 10 19:24:46 DEBUG[2070]: disabled echo cancellation on channel 4
Nov 10 19:24:46 DEBUG[2070]: Making 4 slave to master 1 at 0
Nov 10 19:24:46 DEBUG[2070]: Added 17 to conference 9/1
Nov 10 19:24:46 DEBUG[2070]: Added 15 to conference 9/4
Nov 10 19:24:46 DEBUG[2070]: Updated conferencing on 1, with 0 conference users
Nov 10 19:24:46 DEBUG[2070]: Updated conferencing on 4, with 0 conference users
Nov 10 19:24:46 DEBUG[2070]: Updated conferencing on 4, with 0 conference