[Feb 14 09:09:05] VERBOSE[20816] logger.c:     -- Executing [s@macro-dialout-trunk:22] Macro("SIP/3340-0825f060", "dialout-trunk-predial-hook") in new stack
[Feb 14 09:09:05] WARNING[20816] app_macro.c: Context 'macro-dialout-trunk-predial-hook' for macro 'dialout-trunk-predial-hook' lacks 's' extension, priority 1
[Feb 14 09:09:05] DEBUG[20816] app_macro.c: Executed application: Macro
[Feb 14 09:09:05] VERBOSE[20816] logger.c:     -- Executing [s@macro-dialout-trunk:23] GotoIf("SIP/3340-0825f060", "0?bypass|1") in new stack
[Feb 14 09:09:05] DEBUG[20816] app_macro.c: Executed application: GotoIf
[Feb 14 09:09:05] VERBOSE[20816] logger.c:     -- Executing [s@macro-dialout-trunk:24] GotoIf("SIP/3340-0825f060", "0?customtrunk") in new stack
[Feb 14 09:09:05] DEBUG[20816] app_macro.c: Executed application: GotoIf
[Feb 14 09:09:05] VERBOSE[20816] logger.c:     -- Executing [s@macro-dialout-trunk:25] Dial("SIP/3340-0825f060", "ZAP/g60/840809080080|300|tT") in new stack
[Feb 14 09:09:05] VERBOSE[20816] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
[Feb 14 09:09:05] VERBOSE[20816] logger.c:     -- Called g60/840809080080
[Feb 14 09:09:05] DEBUG[3272] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1
[Feb 14 09:09:05] VERBOSE[20816] logger.c:     -- Zap/1-1 is proceeding passing it to SIP/3340-0825f060
[Feb 14 09:09:06] VERBOSE[20816] logger.c:     -- Zap/1-1 is ringing
[Feb 14 09:09:06] DEBUG[3272] chan_zap.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/1 span 1
[Feb 14 09:09:06] VERBOSE[20816] logger.c:     -- Zap/1-1 is making progress passing it to SIP/3340-0825f060
[Feb 14 09:09:08] DEBUG[3272] chan_zap.c: Echo cancellation already on
[Feb 14 09:09:08] VERBOSE[20816] logger.c:     -- Zap/1-1 answered SIP/3340-0825f060
[Feb 14 09:09:08] VERBOSE[20816] logger.c:     -- fixed jitterbuffer created on channel Zap/1-1
[Feb 14 09:09:14] DTMF[20816] channel.c: DTMF end '1' received on SIP/3340-0825f060, duration 74617280 ms
[Feb 14 09:09:14] DTMF[20816] channel.c: DTMF begin emulation of '1' with duration 74617280 queued on SIP/3340-0825f060
[Feb 14 09:09:16] DTMF[20816] channel.c: DTMF end '2' received on SIP/3340-0825f060, duration 160 ms
[Feb 14 09:09:16] DTMF[20816] channel.c: DTMF end '2' put into dtmf queue on SIP/3340-0825f060
[Feb 14 09:09:30] DEBUG[20816] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/1-1
[Feb 14 09:09:30] DEBUG[20816] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Feb 14 09:09:30] DEBUG[20816] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/1-1
[Feb 14 09:09:30] VERBOSE[20816] logger.c:     -- Hungup 'Zap/1-1'
[Feb 14 09:09:30] VERBOSE[20816] logger.c:     -- fixed jitterbuffer destroyed on channel Zap/1-1
[Feb 14 09:09:30] VERBOSE[20816] logger.c:   == Spawn extension (macro-dialout-trunk, s, 25) exited non-zero on 'SIP/3340-0825f060' in macro 'dialout-trunk'
[Feb 14 09:09:30] VERBOSE[20816] logger.c:   == Spawn extension (macro-dialout-trunk, s, 25) exited non-zero on 'SIP/3340-0825f060'
[Feb 14 09:09:30] VERBOSE[20816] logger.c:     -- Executing [h@macro-dialout-trunk:1] Macro("SIP/3340-0825f060", "hangupcall") in new stack
[Feb 14 09:09:30] VERBOSE[20816] logger.c:     -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/3340-0825f060", "w") in new stack
[Feb 14 09:09:30] DEBUG[20816] app_macro.c: Executed application: ResetCDR
[Feb 14 09:09:30] VERBOSE[20816] logger.c:     -- Executing [s@macro-hangupcall:2] NoCDR("SIP/3340-0825f060", "") in new stack
[Feb 14 09:09:30] DEBUG[20816] app_macro.c: Executed application: NoCDR
[Feb 14 09:09:30] VERBOSE[20816] logger.c:     -- Executing [s@macro-hangupcall:3] GotoIf("SIP/3340-0825f060", "1?skiprg") in new stack
[Feb 14 09:09:30] VERBOSE[20816] logger.c:     -- Goto (macro-hangupcall,s,6)
[Feb 14 09:09:30] DEBUG[20816] app_macro.c: Executed application: GotoIf
[Feb 14 09:09:30] VERBOSE[20816] logger.c:     -- Executing [s@macro-hangupcall:6] GotoIf("SIP/3340-0825f060", "1?skipblkvm") in new stack
[Feb 14 09:09:30] VERBOSE[20816] logger.c:     -- Goto (macro-hangupcall,s,9)
[Feb 14 09:09:30] DEBUG[20816] app_macro.c: Executed application: GotoIf
[Feb 14 09:09:30] VERBOSE[20816] logger.c:     -- Executing [s@macro-hangupcall:9] GotoIf("SIP/3340-0825f060", "1?theend") in new stack
[Feb 14 09:09:30] VERBOSE[20816] logger.c:     -- Goto (macro-hangupcall,s,11)
[Feb 14 09:09:30] DEBUG[20816] app_macro.c: Executed application: GotoIf
[Feb 14 09:09:30] VERBOSE[20816] logger.c:     -- Executing [s@macro-hangupcall:11] Hangup("SIP/3340-0825f060", "") in new stack
[Feb 14 09:09:30] VERBOSE[20816] logger.c:   == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/3340-0825f060' in macro 'hangupcall'
[Feb 14 09:09:30] VERBOSE[20816] logger.c:   == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/3340-0825f060'