[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:24] ExecIf("SIP/706-00000012", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/706-00000012", "NEWDIAL=") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/706-00000012", "LOOPCNT2=1") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/706-00000012", "ITER2=1") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/706-00000012", "THISPART2=SIP/701") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/706-00000012", "0?Set(THISPART2=DAHDI/701)") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/706-00000012", "NEWDIAL=SIP/701&") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/706-00000012", "ITER2=2") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/706-00000012", "0?begin2") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/706-00000012", "THISDIAL=SIP/701") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/706-00000012", "") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [dstring@macro-dial-one:9] Set("SIP/706-00000012", "DSTRING=SIP/701&") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [dstring@macro-dial-one:10] Set("SIP/706-00000012", "ITER=2") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [dstring@macro-dial-one:11] GotoIf("SIP/706-00000012", "0?begin") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [dstring@macro-dial-one:12] Set("SIP/706-00000012", "DSTRING=SIP/701") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [dstring@macro-dial-one:13] Return("SIP/706-00000012", "") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:27] GotoIf("SIP/706-00000012", "0?nodial") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:28] GotoIf("SIP/706-00000012", "1?skiptrace") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:30] Set("SIP/706-00000012", "D_OPTIONS=tr") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:31] ExecIf("SIP/706-00000012", "0?SIPAddHeader(Alert-Info: )") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:32] ExecIf("SIP/706-00000012", "0?SIPAddHeader()") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:33] ExecIf("SIP/706-00000012", "0?Set(CHANNEL(musicclass)=)") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:34] GosubIf("SIP/706-00000012", "0?qwait,1") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:35] Set("SIP/706-00000012", "__CWIGNORE=") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:36] Set("SIP/706-00000012", "__KEEPCID=TRUE") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:37] GotoIf("SIP/706-00000012", "0?usegoto,1") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:38] GotoIf("SIP/706-00000012", "0?godial") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:39] Set("SIP/706-00000012", "CONNECTEDLINE(name,i)=701") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:40] Set("SIP/706-00000012", "CONNECTEDLINE(num)=701") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:41] Set("SIP/706-00000012", "D_OPTIONS=trI") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:42] Dial("SIP/706-00000012", "SIP/701,20,trI") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:43] ExecIf("SIP/706-00000012", "0?Set(DIALSTATUS=)") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:44] GosubIf("SIP/706-00000012", "0?s-CHANUNAVAIL,1") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-dial-one:45] MacroExit("SIP/706-00000012", "") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-exten-vm:15] GotoIf("SIP/706-00000012", "0?exit") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-exten-vm:16] Set("SIP/706-00000012", "SV_DIALSTATUS=CHANUNAVAIL") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-exten-vm:17] GosubIf("SIP/706-00000012", "0?docfu,1") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-exten-vm:18] GosubIf("SIP/706-00000012", "0?docfb,1") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-exten-vm:19] Set("SIP/706-00000012", "DIALSTATUS=CHANUNAVAIL") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-exten-vm:20] ExecIf("SIP/706-00000012", "0?MacroExit()") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-exten-vm:21] GotoIf("SIP/706-00000012", "0?s-CHANUNAVAIL,1") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-exten-vm:22] Macro("SIP/706-00000012", "vm,701,CHANUNAVAIL,") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-vm:1] Macro("SIP/706-00000012", "user-callerid,SKIPTTL") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/706-00000012", "AMPUSER=706") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/706-00000012", "13?report") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/706-00000012", "1?continue") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-user-callerid:25] Set("SIP/706-00000012", "CALLERID(number)=706") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/706-00000012", "CALLERID(name)=Jason Desktop") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/706-00000012", "CHANNEL(language)=en") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-vm:2] Set("SIP/706-00000012", "VMGAIN=""") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-vm:3] Macro("SIP/706-00000012", "blkvm-check,") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-blkvm-check:1] Set("SIP/706-00000012", "GOSUB_RETVAL=") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-blkvm-check:2] MacroExit("SIP/706-00000012", "") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-vm:4] GotoIf("SIP/706-00000012", "1?vmx,1") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [vmx@macro-vm:1] Set("SIP/706-00000012", "MEXTEN=701") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [vmx@macro-vm:2] Set("SIP/706-00000012", "MMODE=CHANUNAVAIL") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [vmx@macro-vm:3] Set("SIP/706-00000012", "RETVM=") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [vmx@macro-vm:4] Set("SIP/706-00000012", "MODE=unavail") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [vmx@macro-vm:5] GotoIf("SIP/706-00000012", "1?chknomsg") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [vmx@macro-vm:8] GotoIf("SIP/706-00000012", "0?s-CHANUNAVAIL,1") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [vmx@macro-vm:9] GotoIf("SIP/706-00000012", "1?notdirect") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [vmx@macro-vm:11] NoOp("SIP/706-00000012", "Checking if ext 701 is enabled: ") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [vmx@macro-vm:12] GotoIf("SIP/706-00000012", "1?s-CHANUNAVAIL,1") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s-CHANUNAVAIL@macro-vm:1] Macro("SIP/706-00000012", "get-vmcontext,701") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-get-vmcontext:1] Set("SIP/706-00000012", "VMCONTEXT=default") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/706-00000012", "0?200:300") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/706-00000012", "") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] pbx.c: -- Executing [s-CHANUNAVAIL@macro-vm:2] VoiceMail("SIP/706-00000012", "701@default,u""") in new stack
[2012-02-18 15:12:46] VERBOSE[2533] app_macro.c: == Spawn extension (macro-vm, s-CHANUNAVAIL, 2) exited non-zero on 'SIP/706-00000012' in macro 'vm'