Mine

Dial 1:
[2007-11-09 10:29:11] DEBUG[10833]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:29:12] DEBUG[10833]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:29:12] DEBUG[10833]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:29:13] DEBUG[10833]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:29:13] DEBUG[10833]: DTMF digit: 8 on Zap/17-1
[2007-11-09 10:29:15] DEBUG[10833]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:29:15] DEBUG[10833]: DTMF digit: 4 on Zap/17-1
[2007-11-09 10:29:15] DEBUG[10833]: DTMF digit: 2 on Zap/17-1
[2007-11-09 10:29:18] DEBUG[10833]: DTMF digit: 8 on Zap/17-1
[2007-11-09 10:29:19] DEBUG[10833]: DTMF digit: 4 on Zap/17-1
[2007-11-09 10:29:19] DEBUG[10833]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:29:20] DEBUG[10833]: DTMF digit: 8 on Zap/17-1

Connect 1:
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing NoOp("Zap/17-1", "Processing LD call dialed as 603366185428438") in new stack
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing Set("Zap/17-1", "accountcode=60336") in new stack
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing NoOp("Zap/17-1", "Account code extracted as 60336") in new stack
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing Set("Zap/17-1", "mynum=6185428438") in new stack
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing NoOp("Zap/17-1", "LD number extracted as 6185428438") in new stack
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(num): ") in new stack
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(name): ") in new stack
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing AGI("Zap/17-1", "callerid-test.agi") in new stack
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(num): 7216") in new stack
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(name): Brian Mondoni") in new stack
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing NoOp("Zap/17-1", "Dialing LD call") in new stack
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing Macro("Zap/17-1", "lddial|60336|6185428438") in new stack
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing Set("Zap/17-1", "CDR(accountcode)=60336") in new stack
[2007-11-09 10:29:20] VERBOSE[10833]: [2007-11-09 10:29:20]     -- Executing Dial("Zap/17-1", "Zap/g2/wwww16185428438") in new stack
[2007-11-09 10:29:26] VERBOSE[10833]: [2007-11-09 10:29:26]     -- Zap/27-1 answered Zap/17-1
[2007-11-09 10:29:26] DEBUG[10833]: Took Zap/17-1 off hook
[2007-11-09 10:29:26] VERBOSE[10833]: [2007-11-09 10:29:26]     -- Attempting native bridge of Zap/17-1 and Zap/27-1
[2007-11-09 10:30:47] DEBUG[10833]: Returning from native bridge, channels: Zap/17-1, Zap/27-1
[2007-11-09 10:30:47] VERBOSE[10833]: [2007-11-09 10:30:47]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1' in macro 'lddial'
[2007-11-09 10:30:47] VERBOSE[10833]: [2007-11-09 10:30:47]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:30:47] VERBOSE[10833]: [2007-11-09 10:30:47]     -- Executing Set("Zap/17-1", "CDR(userfield)= Hangupcause:16") in new stack
[2007-11-09 10:30:47] VERBOSE[10833]: [2007-11-09 10:30:47]     -- Executing Hangup("Zap/17-1", "") in new stack
[2007-11-09 10:30:47] VERBOSE[10833]: [2007-11-09 10:30:47]   == Spawn extension (macro-lddial, h, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:30:47] DEBUG[10833]: Set option TDD MODE, value: OFF(0) on Zap/17-1
[2007-11-09 10:30:47] VERBOSE[10833]: [2007-11-09 10:30:47]     -- Hungup 'Zap/17-1'
[2007-11-09 10:30:47] DEBUG[10833]: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2007-11-09 10:29:20','\"Brian Mondoni\" <7216>','7216','603366185428438','from-internal', 'Zap/17-1','Zap/27-1','Dial','Zap/g2/wwww16185428438',87,81,'ANSWERED',3,'60336',' Hangupcause:16')

Dial 2:
[2007-11-09 10:30:54] VERBOSE[11025]: [2007-11-09 10:30:54]     -- Starting simple switch on 'Zap/17-1'
[2007-11-09 10:30:55] DEBUG[11025]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:30:56] DEBUG[11025]: DTMF digit: 0 on Zap/17-1
[2007-11-09 10:30:57] DEBUG[11025]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:30:57] DEBUG[11025]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:30:58] DEBUG[11025]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:30:59] DEBUG[11025]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:31:00] DEBUG[11025]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:31:00] DEBUG[11025]: DTMF digit: 8 on Zap/17-1
[2007-11-09 10:31:02] DEBUG[11025]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:31:02] DEBUG[11025]: DTMF digit: 4 on Zap/17-1
[2007-11-09 10:31:02] DEBUG[11025]: DTMF digit: 2 on Zap/17-1
[2007-11-09 10:31:04] DEBUG[11025]: DTMF digit: 4 on Zap/17-1
[2007-11-09 10:31:05] DEBUG[11025]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:31:06] DEBUG[11025]: DTMF digit: 9 on Zap/17-1
[2007-11-09 10:31:06] DEBUG[11025]: DTMF digit: 2 on Zap/17-1

Connect 2:
[2007-11-09 10:31:06] VERBOSE[11025]: [2007-11-09 10:31:06]     -- Executing NoOp("Zap/17-1", "Processing LD call dialed as 603366185424592") in new stack
[2007-11-09 10:31:06] VERBOSE[11025]: [2007-11-09 10:31:06]     -- Executing Set("Zap/17-1", "accountcode=60336") in new stack
[2007-11-09 10:31:06] VERBOSE[11025]: [2007-11-09 10:31:06]     -- Executing NoOp("Zap/17-1", "Account code extracted as 60336") in new stack
[2007-11-09 10:31:06] VERBOSE[11025]: [2007-11-09 10:31:06]     -- Executing Set("Zap/17-1", "mynum=6185424592") in new stack
[2007-11-09 10:31:06] VERBOSE[11025]: [2007-11-09 10:31:06]     -- Executing NoOp("Zap/17-1", "LD number extracted as 6185424592") in new stack
[2007-11-09 10:31:06] VERBOSE[11025]: [2007-11-09 10:31:06]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(num): ") in new stack
[2007-11-09 10:31:06] VERBOSE[11025]: [2007-11-09 10:31:06]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(name): ") in new stack
[2007-11-09 10:31:06] VERBOSE[11025]: [2007-11-09 10:31:06]     -- Executing AGI("Zap/17-1", "callerid-test.agi") in new stack
[2007-11-09 10:31:07] VERBOSE[11025]: [2007-11-09 10:31:07]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(num): 7216") in new stack
[2007-11-09 10:31:07] VERBOSE[11025]: [2007-11-09 10:31:07]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(name): Brian Mondoni") in new stack
[2007-11-09 10:31:07] VERBOSE[11025]: [2007-11-09 10:31:07]     -- Executing NoOp("Zap/17-1", "Dialing LD call") in new stack
[2007-11-09 10:31:07] VERBOSE[11025]: [2007-11-09 10:31:07]     -- Executing Macro("Zap/17-1", "lddial|60336|6185424592") in new stack
[2007-11-09 10:31:07] VERBOSE[11025]: [2007-11-09 10:31:07]     -- Executing Set("Zap/17-1", "CDR(accountcode)=60336") in new stack
[2007-11-09 10:31:07] VERBOSE[11025]: [2007-11-09 10:31:07]     -- Executing Dial("Zap/17-1", "Zap/g2/wwww16185424592") in new stack
[2007-11-09 10:31:11] VERBOSE[11025]: [2007-11-09 10:31:11]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1' in macro 'lddial'
[2007-11-09 10:31:11] VERBOSE[11025]: [2007-11-09 10:31:11]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:31:11] VERBOSE[11025]: [2007-11-09 10:31:11]     -- Executing Set("Zap/17-1", "CDR(userfield)= Hangupcause:0") in new stack
[2007-11-09 10:31:11] VERBOSE[11025]: [2007-11-09 10:31:11]     -- Executing Hangup("Zap/17-1", "") in new stack
[2007-11-09 10:31:11] VERBOSE[11025]: [2007-11-09 10:31:11]   == Spawn extension (macro-lddial, h, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:31:11] DEBUG[11025]: Set option TDD MODE, value: OFF(0) on Zap/17-1
[2007-11-09 10:31:11] VERBOSE[11025]: [2007-11-09 10:31:11]     -- Hungup 'Zap/17-1'
[2007-11-09 10:31:11] DEBUG[11025]: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2007-11-09 10:31:06','\"Brian Mondoni\" <7216>','7216','603366185424592','from-internal', 'Zap/17-1','Zap/27-1','Dial','Zap/g2/wwww16185424592',5,0,'NO ANSWER',3,'60336',' Hangupcause:0')

Dial 3:
[2007-11-09 10:31:14] VERBOSE[11089]: [2007-11-09 10:31:14]     -- Starting simple switch on 'Zap/17-1'
[2007-11-09 10:31:15] DEBUG[11089]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:31:16] DEBUG[11089]: DTMF digit: 0 on Zap/17-1
[2007-11-09 10:31:17] DEBUG[11089]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:31:17] DEBUG[11089]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:31:18] DEBUG[11089]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:31:18] DEBUG[11089]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:31:18] DEBUG[11089]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:31:19] DEBUG[11089]: DTMF digit: 8 on Zap/17-1
[2007-11-09 10:31:21] DEBUG[11089]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:31:21] DEBUG[11089]: DTMF digit: 4 on Zap/17-1
[2007-11-09 10:31:21] DEBUG[11089]: DTMF digit: 2 on Zap/17-1
[2007-11-09 10:31:23] DEBUG[11089]: DTMF digit: 8 on Zap/17-1
[2007-11-09 10:31:24] DEBUG[11089]: DTMF digit: 4 on Zap/17-1
[2007-11-09 10:31:24] DEBUG[11089]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:31:25] DEBUG[11089]: DTMF digit: 8 on Zap/17-1

Connect 3:
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing NoOp("Zap/17-1", "Processing LD call dialed as 603366185428468") in new stack
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing Set("Zap/17-1", "accountcode=60336") in new stack
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing NoOp("Zap/17-1", "Account code extracted as 60336") in new stack
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing Set("Zap/17-1", "mynum=6185428468") in new stack
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing NoOp("Zap/17-1", "LD number extracted as 6185428468") in new stack
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(num): ") in new stack
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(name): ") in new stack
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing AGI("Zap/17-1", "callerid-test.agi") in new stack
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(num): 7216") in new stack
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(name): Brian Mondoni") in new stack
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing NoOp("Zap/17-1", "Dialing LD call") in new stack
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing Macro("Zap/17-1", "lddial|60336|6185428468") in new stack
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing Set("Zap/17-1", "CDR(accountcode)=60336") in new stack
[2007-11-09 10:31:25] VERBOSE[11089]: [2007-11-09 10:31:25]     -- Executing Dial("Zap/17-1", "Zap/g2/wwww16185428468") in new stack
[2007-11-09 10:31:30] VERBOSE[11089]: [2007-11-09 10:31:30]     -- Zap/27-1 answered Zap/17-1
[2007-11-09 10:31:30] DEBUG[11089]: Took Zap/17-1 off hook
[2007-11-09 10:31:30] DEBUG[8263]: Avoiding initial deadlock for 'Zap/17-1'
[2007-11-09 10:31:30] VERBOSE[11089]: [2007-11-09 10:31:30]     -- Attempting native bridge of Zap/17-1 and Zap/27-1
[2007-11-09 10:31:38] DEBUG[11089]: Returning from native bridge, channels: Zap/17-1, Zap/27-1
[2007-11-09 10:31:38] VERBOSE[11089]: [2007-11-09 10:31:38]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1' in macro 'lddial'
[2007-11-09 10:31:38] VERBOSE[11089]: [2007-11-09 10:31:38]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:31:38] VERBOSE[11089]: [2007-11-09 10:31:38]     -- Executing Set("Zap/17-1", "CDR(userfield)= Hangupcause:16") in new stack
[2007-11-09 10:31:38] VERBOSE[11089]: [2007-11-09 10:31:38]     -- Executing Hangup("Zap/17-1", "") in new stack
[2007-11-09 10:31:38] VERBOSE[11089]: [2007-11-09 10:31:38]   == Spawn extension (macro-lddial, h, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:31:38] DEBUG[11089]: Set option TDD MODE, value: OFF(0) on Zap/17-1
[2007-11-09 10:31:38] VERBOSE[11089]: [2007-11-09 10:31:38]     -- Hungup 'Zap/17-1'
[2007-11-09 10:31:38] DEBUG[11089]: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2007-11-09 10:31:25','\"Brian Mondoni\" <7216>','7216','603366185428468','from-internal', 'Zap/17-1','Zap/27-1','Dial','Zap/g2/wwww16185428468',13,8,'ANSWERED',3,'60336',' Hangupcause:16')

Dial 4:
[2007-11-09 10:35:43] VERBOSE[11521]: [2007-11-09 10:35:43]     -- Starting simple switch on 'Zap/17-1'
[2007-11-09 10:35:44] DEBUG[11521]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:35:46] DEBUG[11521]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:35:46] DEBUG[11521]: DTMF digit: 9 on Zap/17-1
[2007-11-09 10:35:47] DEBUG[11521]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:35:47] DEBUG[11521]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:35:48] DEBUG[11521]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:35:49] DEBUG[11521]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:35:49] DEBUG[11521]: DTMF digit: 8 on Zap/17-1
[2007-11-09 10:35:51] DEBUG[11521]: DTMF digit: 2 on Zap/17-1
[2007-11-09 10:35:51] DEBUG[11521]: DTMF digit: 7 on Zap/17-1
[2007-11-09 10:35:52] DEBUG[11521]: DTMF digit: 2 on Zap/17-1
[2007-11-09 10:35:54] DEBUG[11521]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:35:55] DEBUG[11521]: DTMF digit: 2 on Zap/17-1
[2007-11-09 10:35:55] DEBUG[11521]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:35:56] DEBUG[11521]: DTMF digit: 1 on Zap/17-1

Connect 4:
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing NoOp("Zap/17-1", "Processing LD call dialed as 639166182725251") in new stack
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing Set("Zap/17-1", "accountcode=63916") in new stack
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing NoOp("Zap/17-1", "Account code extracted as 63916") in new stack
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing Set("Zap/17-1", "mynum=6182725251") in new stack
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing NoOp("Zap/17-1", "LD number extracted as 6182725251") in new stack
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(num): ") in new stack
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(name): ") in new stack
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing AGI("Zap/17-1", "callerid-test.agi") in new stack
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(num): 7216") in new stack
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(name): Brian Mondoni") in new stack
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing NoOp("Zap/17-1", "Dialing LD call") in new stack
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing Macro("Zap/17-1", "lddial|63916|6182725251") in new stack
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing Set("Zap/17-1", "CDR(accountcode)=63916") in new stack
[2007-11-09 10:35:56] VERBOSE[11521]: [2007-11-09 10:35:56]     -- Executing Dial("Zap/17-1", "Zap/g2/wwww16182725251") in new stack
[2007-11-09 10:36:01] VERBOSE[11521]: [2007-11-09 10:36:01]     -- Zap/25-1 answered Zap/17-1
[2007-11-09 10:36:01] DEBUG[11521]: Took Zap/17-1 off hook
[2007-11-09 10:36:01] DEBUG[8263]: Avoiding initial deadlock for 'Zap/17-1'
[2007-11-09 10:36:01] VERBOSE[11521]: [2007-11-09 10:36:01]     -- Attempting native bridge of Zap/17-1 and Zap/25-1
[2007-11-09 10:36:25] DEBUG[11521]: Returning from native bridge, channels: Zap/17-1, Zap/25-1
[2007-11-09 10:36:25] VERBOSE[11521]: [2007-11-09 10:36:25]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1' in macro 'lddial'
[2007-11-09 10:36:25] VERBOSE[11521]: [2007-11-09 10:36:25]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:36:25] VERBOSE[11521]: [2007-11-09 10:36:25]     -- Executing Set("Zap/17-1", "CDR(userfield)= Hangupcause:16") in new stack
[2007-11-09 10:36:25] VERBOSE[11521]: [2007-11-09 10:36:25]     -- Executing Hangup("Zap/17-1", "") in new stack
[2007-11-09 10:36:25] VERBOSE[11521]: [2007-11-09 10:36:25]   == Spawn extension (macro-lddial, h, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:36:25] DEBUG[11521]: Set option TDD MODE, value: OFF(0) on Zap/17-1
[2007-11-09 10:36:25] VERBOSE[11521]: [2007-11-09 10:36:25]     -- Hungup 'Zap/17-1'
[2007-11-09 10:36:25] DEBUG[11521]: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2007-11-09 10:35:56','\"Brian Mondoni\" <7216>','7216','639166182725251','from-internal', 'Zap/17-1','Zap/25-1','Dial','Zap/g2/wwww16182725251',29,24,'ANSWERED',3,'63916',' Hangupcause:16')

Dial 5:
[2007-11-09 10:37:10] VERBOSE[11670]: [2007-11-09 10:37:10]     -- Starting simple switch on 'Zap/17-1'
[2007-11-09 10:37:11] DEBUG[11670]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:37:11] DEBUG[11670]: DTMF digit: 0 on Zap/17-1
[2007-11-09 10:37:11] DEBUG[11670]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:37:12] DEBUG[11670]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:37:12] DEBUG[11670]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:37:13] DEBUG[11670]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:37:13] DEBUG[11670]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:37:14] DEBUG[11670]: DTMF digit: 8 on Zap/17-1
[2007-11-09 10:37:15] DEBUG[11670]: DTMF digit: 2 on Zap/17-1
[2007-11-09 10:37:15] DEBUG[11670]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:37:16] DEBUG[11670]: DTMF digit: 9 on Zap/17-1
[2007-11-09 10:37:17] DEBUG[11670]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:37:17] DEBUG[11670]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:37:18] DEBUG[11670]: DTMF digit: 2 on Zap/17-1
[2007-11-09 10:37:18] DEBUG[11670]: DTMF digit: 1 on Zap/17-1

Connect 5:
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing NoOp("Zap/17-1", "Processing LD call dialed as 603366182693321") in new stack
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing Set("Zap/17-1", "accountcode=60336") in new stack
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing NoOp("Zap/17-1", "Account code extracted as 60336") in new stack
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing Set("Zap/17-1", "mynum=6182693321") in new stack
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing NoOp("Zap/17-1", "LD number extracted as 6182693321") in new stack
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(num): ") in new stack
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(name): ") in new stack
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing AGI("Zap/17-1", "callerid-test.agi") in new stack
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(num): 7216") in new stack
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(name): Brian Mondoni") in new stack
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing NoOp("Zap/17-1", "Dialing LD call") in new stack
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing Macro("Zap/17-1", "lddial|60336|6182693321") in new stack
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing Set("Zap/17-1", "CDR(accountcode)=60336") in new stack
[2007-11-09 10:37:18] VERBOSE[11670]: [2007-11-09 10:37:18]     -- Executing Dial("Zap/17-1", "Zap/g2/wwww16182693321") in new stack
[2007-11-09 10:37:24] VERBOSE[11670]: [2007-11-09 10:37:24]     -- Zap/27-1 answered Zap/17-1
[2007-11-09 10:37:24] DEBUG[11670]: Took Zap/17-1 off hook
[2007-11-09 10:37:24] DEBUG[8263]: Avoiding initial deadlock for 'Zap/17-1'
[2007-11-09 10:37:24] VERBOSE[11670]: [2007-11-09 10:37:24]     -- Attempting native bridge of Zap/17-1 and Zap/27-1
[2007-11-09 10:37:57] DEBUG[11670]: Returning from native bridge, channels: Zap/17-1, Zap/27-1
[2007-11-09 10:37:57] VERBOSE[11670]: [2007-11-09 10:37:57]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1' in macro 'lddial'
[2007-11-09 10:37:57] VERBOSE[11670]: [2007-11-09 10:37:57]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:37:57] VERBOSE[11670]: [2007-11-09 10:37:57]     -- Executing Set("Zap/17-1", "CDR(userfield)= Hangupcause:16") in new stack
[2007-11-09 10:37:57] VERBOSE[11670]: [2007-11-09 10:37:57]     -- Executing Hangup("Zap/17-1", "") in new stack
[2007-11-09 10:37:57] VERBOSE[11670]: [2007-11-09 10:37:57]   == Spawn extension (macro-lddial, h, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:37:57] DEBUG[11670]: Set option TDD MODE, value: OFF(0) on Zap/17-1
[2007-11-09 10:37:57] VERBOSE[11670]: [2007-11-09 10:37:57]     -- Hungup 'Zap/17-1'
[2007-11-09 10:37:57] DEBUG[11670]: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2007-11-09 10:37:18','\"Brian Mondoni\" <7216>','7216','603366182693321','from-internal', 'Zap/17-1','Zap/27-1','Dial','Zap/g2/wwww16182693321',39,33,'ANSWERED',3,'60336',' Hangupcause:16')

Dial 6:
[2007-11-09 10:41:34] VERBOSE[12055]: [2007-11-09 10:41:34]     -- Starting simple switch on 'Zap/17-1'
[2007-11-09 10:41:35] DEBUG[12055]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:41:36] DEBUG[12055]: DTMF digit: 0 on Zap/17-1
[2007-11-09 10:41:36] DEBUG[12055]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:41:36] DEBUG[12055]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:41:38] DEBUG[12055]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:41:39] DEBUG[12055]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:41:39] DEBUG[12055]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:41:40] DEBUG[12055]: DTMF digit: 8 on Zap/17-1
[2007-11-09 10:41:41] DEBUG[12055]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:41:42] DEBUG[12055]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:41:42] DEBUG[12055]: DTMF digit: 7 on Zap/17-1
[2007-11-09 10:41:44] DEBUG[12055]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:41:46] DEBUG[12055]: DTMF digit: 2 on Zap/17-1
[2007-11-09 10:41:46] DEBUG[12055]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:41:47] DEBUG[12055]: DTMF digit: 5 on Zap/17-1

Connect 6:
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing NoOp("Zap/17-1", "Processing LD call dialed as 603366183576215") in new stack
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing Set("Zap/17-1", "accountcode=60336") in new stack
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing NoOp("Zap/17-1", "Account code extracted as 60336") in new stack
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing Set("Zap/17-1", "mynum=6183576215") in new stack
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing NoOp("Zap/17-1", "LD number extracted as 6183576215") in new stack
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(num): ") in new stack
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(name): ") in new stack
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing AGI("Zap/17-1", "callerid-test.agi") in new stack
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(num): 7216") in new stack
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(name): Brian Mondoni") in new stack
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing NoOp("Zap/17-1", "Dialing LD call") in new stack
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing Macro("Zap/17-1", "lddial|60336|6183576215") in new stack
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing Set("Zap/17-1", "CDR(accountcode)=60336") in new stack
[2007-11-09 10:41:47] VERBOSE[12055]: [2007-11-09 10:41:47]     -- Executing Dial("Zap/17-1", "Zap/g2/wwww16183576215") in new stack
[2007-11-09 10:41:53] VERBOSE[12055]: [2007-11-09 10:41:53]     -- Zap/29-1 answered Zap/17-1
[2007-11-09 10:41:53] DEBUG[12055]: Took Zap/17-1 off hook
[2007-11-09 10:41:53] DEBUG[8263]: Avoiding initial deadlock for 'Zap/17-1'
[2007-11-09 10:41:53] VERBOSE[12055]: [2007-11-09 10:41:53]     -- Attempting native bridge of Zap/17-1 and Zap/29-1
[2007-11-09 10:42:33] DEBUG[12055]: Returning from native bridge, channels: Zap/17-1, Zap/29-1
[2007-11-09 10:42:33] VERBOSE[12055]: [2007-11-09 10:42:33]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1' in macro 'lddial'
[2007-11-09 10:42:33] VERBOSE[12055]: [2007-11-09 10:42:33]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:42:33] VERBOSE[12055]: [2007-11-09 10:42:33]     -- Executing Set("Zap/17-1", "CDR(userfield)= Hangupcause:16") in new stack
[2007-11-09 10:42:33] VERBOSE[12055]: [2007-11-09 10:42:33]     -- Executing Hangup("Zap/17-1", "") in new stack
[2007-11-09 10:42:33] VERBOSE[12055]: [2007-11-09 10:42:33]   == Spawn extension (macro-lddial, h, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:42:33] DEBUG[12055]: Set option TDD MODE, value: OFF(0) on Zap/17-1
[2007-11-09 10:42:33] VERBOSE[12055]: [2007-11-09 10:42:33]     -- Hungup 'Zap/17-1'
[2007-11-09 10:42:33] DEBUG[12055]: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2007-11-09 10:41:47','\"Brian Mondoni\" <7216>','7216','603366183576215','from-internal', 'Zap/17-1','Zap/29-1','Dial','Zap/g2/wwww16183576215',46,40,'ANSWERED',3,'60336',' Hangupcause:16')

Dial 7:
[2007-11-09 10:42:56] VERBOSE[12170]: [2007-11-09 10:42:56]     -- Starting simple switch on 'Zap/17-1'
[2007-11-09 10:42:57] DEBUG[12170]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:42:57] DEBUG[12170]: DTMF digit: 0 on Zap/17-1
[2007-11-09 10:42:58] DEBUG[12170]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:42:58] DEBUG[12170]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:42:59] DEBUG[12170]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:43:00] DEBUG[12170]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:43:00] DEBUG[12170]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:43:01] DEBUG[12170]: DTMF digit: 8 on Zap/17-1
[2007-11-09 10:43:02] DEBUG[12170]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:43:02] DEBUG[12170]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:43:03] DEBUG[12170]: DTMF digit: 7 on Zap/17-1
[2007-11-09 10:43:04] DEBUG[12170]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:43:05] DEBUG[12170]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:43:05] DEBUG[12170]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:43:05] DEBUG[12170]: DTMF digit: 6 on Zap/17-1

Connect 7:
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing NoOp("Zap/17-1", "Processing LD call dialed as 603366183576636") in new stack
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing Set("Zap/17-1", "accountcode=60336") in new stack
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing NoOp("Zap/17-1", "Account code extracted as 60336") in new stack
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing Set("Zap/17-1", "mynum=6183576636") in new stack
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing NoOp("Zap/17-1", "LD number extracted as 6183576636") in new stack
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(num): ") in new stack
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(name): ") in new stack
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing AGI("Zap/17-1", "callerid-test.agi") in new stack
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(num): 7216") in new stack
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(name): Brian Mondoni") in new stack
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing NoOp("Zap/17-1", "Dialing LD call") in new stack
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing Macro("Zap/17-1", "lddial|60336|6183576636") in new stack
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing Set("Zap/17-1", "CDR(accountcode)=60336") in new stack
[2007-11-09 10:43:05] VERBOSE[12170]: [2007-11-09 10:43:05]     -- Executing Dial("Zap/17-1", "Zap/g2/wwww16183576636") in new stack
[2007-11-09 10:43:11] VERBOSE[12170]: [2007-11-09 10:43:11]     -- Zap/25-1 answered Zap/17-1
[2007-11-09 10:43:11] DEBUG[12170]: Took Zap/17-1 off hook
[2007-11-09 10:43:11] VERBOSE[12170]: [2007-11-09 10:43:11]     -- Attempting native bridge of Zap/17-1 and Zap/25-1
[2007-11-09 10:43:20] DEBUG[12170]: Returning from native bridge, channels: Zap/17-1, Zap/25-1
[2007-11-09 10:43:20] VERBOSE[12170]: [2007-11-09 10:43:20]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1' in macro 'lddial'
[2007-11-09 10:43:20] VERBOSE[12170]: [2007-11-09 10:43:20]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:43:20] VERBOSE[12170]: [2007-11-09 10:43:20]     -- Executing Set("Zap/17-1", "CDR(userfield)= Hangupcause:16") in new stack
[2007-11-09 10:43:20] VERBOSE[12170]: [2007-11-09 10:43:20]     -- Executing Hangup("Zap/17-1", "") in new stack
[2007-11-09 10:43:20] VERBOSE[12170]: [2007-11-09 10:43:20]   == Spawn extension (macro-lddial, h, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:43:20] DEBUG[12170]: Set option TDD MODE, value: OFF(0) on Zap/17-1
[2007-11-09 10:43:20] VERBOSE[12170]: [2007-11-09 10:43:20]     -- Hungup 'Zap/17-1'
[2007-11-09 10:43:20] DEBUG[12170]: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2007-11-09 10:43:05','\"Brian Mondoni\" <7216>','7216','603366183576636','from-internal', 'Zap/17-1','Zap/25-1','Dial','Zap/g2/wwww16183576636',15,9,'ANSWERED',3,'60336',' Hangupcause:16')

Dial 8:
[2007-11-09 10:44:20] VERBOSE[12296]: [2007-11-09 10:44:20]     -- Starting simple switch on 'Zap/17-1'
[2007-11-09 10:44:22] DEBUG[12296]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:44:22] DEBUG[12296]: DTMF digit: 0 on Zap/17-1
[2007-11-09 10:44:23] DEBUG[12296]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:44:23] DEBUG[12296]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:44:24] DEBUG[12296]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:44:26] DEBUG[12296]: DTMF digit: 7 on Zap/17-1
[2007-11-09 10:44:26] DEBUG[12296]: DTMF digit: 7 on Zap/17-1
[2007-11-09 10:44:26] DEBUG[12296]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:44:28] DEBUG[12296]: DTMF digit: 7 on Zap/17-1
[2007-11-09 10:44:28] DEBUG[12296]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:44:29] DEBUG[12296]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:44:31] DEBUG[12296]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:44:32] DEBUG[12296]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:44:33] DEBUG[12296]: DTMF digit: 9 on Zap/17-1
[2007-11-09 10:44:34] DEBUG[12296]: DTMF digit: 7 on Zap/17-1

Connect 8:
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing NoOp("Zap/17-1", "Processing LD call dialed as 603367737155197") in new stack
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing Set("Zap/17-1", "accountcode=60336") in new stack
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing NoOp("Zap/17-1", "Account code extracted as 60336") in new stack
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing Set("Zap/17-1", "mynum=7737155197") in new stack
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing NoOp("Zap/17-1", "LD number extracted as 7737155197") in new stack
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(num): ") in new stack
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(name): ") in new stack
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing AGI("Zap/17-1", "callerid-test.agi") in new stack
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(num): 7216") in new stack
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(name): Brian Mondoni") in new stack
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing NoOp("Zap/17-1", "Dialing LD call") in new stack
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing Macro("Zap/17-1", "lddial|60336|7737155197") in new stack
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing Set("Zap/17-1", "CDR(accountcode)=60336") in new stack
[2007-11-09 10:44:34] VERBOSE[12296]: [2007-11-09 10:44:34]     -- Executing Dial("Zap/17-1", "Zap/g2/wwww17737155197") in new stack
[2007-11-09 10:44:39] VERBOSE[12296]: [2007-11-09 10:44:39]     -- Zap/27-1 answered Zap/17-1
[2007-11-09 10:44:39] DEBUG[12296]: Took Zap/17-1 off hook
[2007-11-09 10:44:39] DEBUG[8263]: Avoiding initial deadlock for 'Zap/17-1'
[2007-11-09 10:44:39] VERBOSE[12296]: [2007-11-09 10:44:39]     -- Attempting native bridge of Zap/17-1 and Zap/27-1
[2007-11-09 10:45:45] DEBUG[12296]: Returning from native bridge, channels: Zap/17-1, Zap/27-1
[2007-11-09 10:45:45] VERBOSE[12296]: [2007-11-09 10:45:45]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1' in macro 'lddial'
[2007-11-09 10:45:45] VERBOSE[12296]: [2007-11-09 10:45:45]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:45:45] VERBOSE[12296]: [2007-11-09 10:45:45]     -- Executing Set("Zap/17-1", "CDR(userfield)= Hangupcause:16") in new stack
[2007-11-09 10:45:45] VERBOSE[12296]: [2007-11-09 10:45:45]     -- Executing Hangup("Zap/17-1", "") in new stack
[2007-11-09 10:45:45] VERBOSE[12296]: [2007-11-09 10:45:45]   == Spawn extension (macro-lddial, h, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:45:45] DEBUG[12296]: Set option TDD MODE, value: OFF(0) on Zap/17-1
[2007-11-09 10:45:45] VERBOSE[12296]: [2007-11-09 10:45:45]     -- Hungup 'Zap/17-1'
[2007-11-09 10:45:45] DEBUG[12296]: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2007-11-09 10:44:34','\"Brian Mondoni\" <7216>','7216','603367737155197','from-internal', 'Zap/17-1','Zap/27-1','Dial','Zap/g2/wwww17737155197',71,66,'ANSWERED',3,'60336',' Hangupcause:16')

Dial 9:
[2007-11-09 10:47:02] VERBOSE[12504]: [2007-11-09 10:47:02]     -- Starting simple switch on 'Zap/17-1'
[2007-11-09 10:47:04] DEBUG[12504]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:47:04] DEBUG[12504]: DTMF digit: 0 on Zap/17-1
[2007-11-09 10:47:05] DEBUG[12504]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:47:05] DEBUG[12504]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:47:06] DEBUG[12504]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:47:07] DEBUG[12504]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:47:07] DEBUG[12504]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:47:08] DEBUG[12504]: DTMF digit: 8 on Zap/17-1
[2007-11-09 10:47:10] DEBUG[12504]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:47:10] DEBUG[12504]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:47:10] DEBUG[12504]: DTMF digit: 7 on Zap/17-1
[2007-11-09 10:47:13] DEBUG[12504]: DTMF digit: 9 on Zap/17-1
[2007-11-09 10:47:13] DEBUG[12504]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:47:14] DEBUG[12504]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:47:14] DEBUG[12504]: DTMF digit: 3 on Zap/17-1

Connect 9:
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing NoOp("Zap/17-1", "Processing LD call dialed as 603366183579633") in new stack
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing Set("Zap/17-1", "accountcode=60336") in new stack
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing NoOp("Zap/17-1", "Account code extracted as 60336") in new stack
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing Set("Zap/17-1", "mynum=6183579633") in new stack
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing NoOp("Zap/17-1", "LD number extracted as 6183579633") in new stack
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(num): ") in new stack
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(name): ") in new stack
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing AGI("Zap/17-1", "callerid-test.agi") in new stack
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(num): 7216") in new stack
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(name): Brian Mondoni") in new stack
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing NoOp("Zap/17-1", "Dialing LD call") in new stack
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing Macro("Zap/17-1", "lddial|60336|6183579633") in new stack
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing Set("Zap/17-1", "CDR(accountcode)=60336") in new stack
[2007-11-09 10:47:14] VERBOSE[12504]: [2007-11-09 10:47:14]     -- Executing Dial("Zap/17-1", "Zap/g2/wwww16183579633") in new stack
[2007-11-09 10:47:18] VERBOSE[12504]: [2007-11-09 10:47:18]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1' in macro 'lddial'
[2007-11-09 10:47:18] VERBOSE[12504]: [2007-11-09 10:47:18]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:47:18] VERBOSE[12504]: [2007-11-09 10:47:18]     -- Executing Set("Zap/17-1", "CDR(userfield)= Hangupcause:0") in new stack
[2007-11-09 10:47:18] VERBOSE[12504]: [2007-11-09 10:47:18]     -- Executing Hangup("Zap/17-1", "") in new stack
[2007-11-09 10:47:18] VERBOSE[12504]: [2007-11-09 10:47:18]   == Spawn extension (macro-lddial, h, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:47:18] DEBUG[12504]: Set option TDD MODE, value: OFF(0) on Zap/17-1
[2007-11-09 10:47:18] VERBOSE[12504]: [2007-11-09 10:47:18]     -- Hungup 'Zap/17-1'
[2007-11-09 10:47:18] DEBUG[12504]: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2007-11-09 10:47:14','\"Brian Mondoni\" <7216>','7216','603366183579633','from-internal', 'Zap/17-1','Zap/26-1','Dial','Zap/g2/wwww16183579633',4,0,'NO ANSWER',3,'60336',' Hangupcause:0')

Dial 10:
[2007-11-09 10:47:30] VERBOSE[12543]: [2007-11-09 10:47:30]     -- Starting simple switch on 'Zap/17-1'
[2007-11-09 10:47:31] DEBUG[12543]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:47:32] DEBUG[12543]: DTMF digit: 0 on Zap/17-1
[2007-11-09 10:47:32] DEBUG[12543]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:47:32] DEBUG[12543]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:47:33] DEBUG[12543]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:47:34] DEBUG[12543]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:47:35] DEBUG[12543]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:47:35] DEBUG[12543]: DTMF digit: 8 on Zap/17-1
[2007-11-09 10:47:37] DEBUG[12543]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:47:37] DEBUG[12543]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:47:38] DEBUG[12543]: DTMF digit: 7 on Zap/17-1
[2007-11-09 10:47:39] DEBUG[12543]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:47:40] DEBUG[12543]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:47:40] DEBUG[12543]: DTMF digit: 0 on Zap/17-1
[2007-11-09 10:47:41] DEBUG[12543]: DTMF digit: 4 on Zap/17-1

Connect 10:
[2007-11-09 10:47:41] VERBOSE[12543]: [2007-11-09 10:47:41]     -- Executing NoOp("Zap/17-1", "Processing LD call dialed as 603366183575304") in new stack
[2007-11-09 10:47:41] VERBOSE[12543]: [2007-11-09 10:47:41]     -- Executing Set("Zap/17-1", "accountcode=60336") in new stack
[2007-11-09 10:47:41] VERBOSE[12543]: [2007-11-09 10:47:41]     -- Executing NoOp("Zap/17-1", "Account code extracted as 60336") in new stack
[2007-11-09 10:47:41] VERBOSE[12543]: [2007-11-09 10:47:41]     -- Executing Set("Zap/17-1", "mynum=6183575304") in new stack
[2007-11-09 10:47:41] VERBOSE[12543]: [2007-11-09 10:47:41]     -- Executing NoOp("Zap/17-1", "LD number extracted as 6183575304") in new stack
[2007-11-09 10:47:41] VERBOSE[12543]: [2007-11-09 10:47:41]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(num): ") in new stack
[2007-11-09 10:47:41] VERBOSE[12543]: [2007-11-09 10:47:41]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(name): ") in new stack
[2007-11-09 10:47:41] VERBOSE[12543]: [2007-11-09 10:47:41]     -- Executing AGI("Zap/17-1", "callerid-test.agi") in new stack
[2007-11-09 10:47:42] VERBOSE[12543]: [2007-11-09 10:47:42]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(num): 7216") in new stack
[2007-11-09 10:47:42] VERBOSE[12543]: [2007-11-09 10:47:42]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(name): Brian Mondoni") in new stack
[2007-11-09 10:47:42] VERBOSE[12543]: [2007-11-09 10:47:42]     -- Executing NoOp("Zap/17-1", "Dialing LD call") in new stack
[2007-11-09 10:47:42] VERBOSE[12543]: [2007-11-09 10:47:42]     -- Executing Macro("Zap/17-1", "lddial|60336|6183575304") in new stack
[2007-11-09 10:47:42] VERBOSE[12543]: [2007-11-09 10:47:42]     -- Executing Set("Zap/17-1", "CDR(accountcode)=60336") in new stack
[2007-11-09 10:47:42] VERBOSE[12543]: [2007-11-09 10:47:42]     -- Executing Dial("Zap/17-1", "Zap/g2/wwww16183575304") in new stack
[2007-11-09 10:47:47] VERBOSE[12543]: [2007-11-09 10:47:47]     -- Zap/27-1 answered Zap/17-1
[2007-11-09 10:47:47] DEBUG[12543]: Took Zap/17-1 off hook
[2007-11-09 10:47:47] DEBUG[8263]: Avoiding initial deadlock for 'Zap/17-1'
[2007-11-09 10:47:47] VERBOSE[12543]: [2007-11-09 10:47:47]     -- Attempting native bridge of Zap/17-1 and Zap/27-1
[2007-11-09 10:48:25] DEBUG[12543]: Returning from native bridge, channels: Zap/17-1, Zap/27-1
[2007-11-09 10:48:25] VERBOSE[12543]: [2007-11-09 10:48:25]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1' in macro 'lddial'
[2007-11-09 10:48:25] VERBOSE[12543]: [2007-11-09 10:48:25]   == Spawn extension (macro-lddial, s, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:48:25] VERBOSE[12543]: [2007-11-09 10:48:25]     -- Executing Set("Zap/17-1", "CDR(userfield)= Hangupcause:16") in new stack
[2007-11-09 10:48:25] VERBOSE[12543]: [2007-11-09 10:48:25]     -- Executing Hangup("Zap/17-1", "") in new stack
[2007-11-09 10:48:25] VERBOSE[12543]: [2007-11-09 10:48:25]   == Spawn extension (macro-lddial, h, 2) exited non-zero on 'Zap/17-1'
[2007-11-09 10:48:25] DEBUG[12543]: Set option TDD MODE, value: OFF(0) on Zap/17-1
[2007-11-09 10:48:25] VERBOSE[12543]: [2007-11-09 10:48:25]     -- Hungup 'Zap/17-1'
[2007-11-09 10:48:25] DEBUG[12543]: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2007-11-09 10:47:41','\"Brian Mondoni\" <7216>','7216','603366183575304','from-internal', 'Zap/17-1','Zap/27-1','Dial','Zap/g2/wwww16183575304',44,38,'ANSWERED',3,'60336',' Hangupcause:16')

Dial 11:
[2007-11-09 10:48:43] VERBOSE[12660]: [2007-11-09 10:48:43]     -- Starting simple switch on 'Zap/17-1'
[2007-11-09 10:48:44] DEBUG[12660]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:48:44] DEBUG[12660]: DTMF digit: 0 on Zap/17-1
[2007-11-09 10:48:44] DEBUG[12660]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:48:45] DEBUG[12660]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:48:46] DEBUG[12660]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:48:46] DEBUG[12660]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:48:47] DEBUG[12660]: DTMF digit: 1 on Zap/17-1
[2007-11-09 10:48:48] DEBUG[12660]: DTMF digit: 8 on Zap/17-1
[2007-11-09 10:48:49] DEBUG[12660]: DTMF digit: 3 on Zap/17-1
[2007-11-09 10:48:49] DEBUG[12660]: DTMF digit: 5 on Zap/17-1
[2007-11-09 10:48:50] DEBUG[12660]: DTMF digit: 7 on Zap/17-1
[2007-11-09 10:48:52] DEBUG[12660]: DTMF digit: 2 on Zap/17-1
[2007-11-09 10:48:52] DEBUG[12660]: DTMF digit: 4 on Zap/17-1
[2007-11-09 10:48:53] DEBUG[12660]: DTMF digit: 6 on Zap/17-1
[2007-11-09 10:48:54] DEBUG[12660]: DTMF digit: 5 on Zap/17-1

Connect 11:
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing NoOp("Zap/17-1", "Processing LD call dialed as 603366183572465") in new stack
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing Set("Zap/17-1", "accountcode=60336") in new stack
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing NoOp("Zap/17-1", "Account code extracted as 60336") in new stack
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing Set("Zap/17-1", "mynum=6183572465") in new stack
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing NoOp("Zap/17-1", "LD number extracted as 6183572465") in new stack
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(num): ") in new stack
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing NoOp("Zap/17-1", "Pre-AGI CallerID(name): ") in new stack
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing AGI("Zap/17-1", "callerid-test.agi") in new stack
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(num): 7216") in new stack
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing NoOp("Zap/17-1", "Post-AGI CallerID(name): Brian Mondoni") in new stack
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing NoOp("Zap/17-1", "Dialing LD call") in new stack
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing Macro("Zap/17-1", "lddial|60336|6183572465") in new stack
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing Set("Zap/17-1", "CDR(accountcode)=60336") in new stack
[2007-11-09 10:48:54] VERBOSE[12660]: [2007-11-09 10:48:54]     -- Executing Dial("Zap/17-1", "Zap/g2/wwww16183572465") in new stack
[2007-11-09 10:48:59] VERBOSE[12660]: [2007-11-09 10:48:59]     -- Zap/26-1 answered Zap/17-1
[2007-11-09 10:48:59] DEBUG[12660]: Took Zap/17-1 off hook
[2007-11-09 10:48:59] DEBUG[8263]: Avoiding initial deadlock for 'Zap/17-1'
[2007-11-09 10:48:59] VERBOSE[12660]: [2007-11-09 10:48:59]     -- Attempting native bridge of Zap/17-1 and Zap/26-1