== Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
  == Using SIP VRTP TOS bits 136
  == Using SIP VRTP CoS mark 6
    -- Executing [101@from-internal:1] Macro("SIP/103-00000002", "exten-vm,101,101") in new stack
    -- Executing [s@macro-exten-vm:1] Macro("SIP/103-00000002", "user-callerid,") in new stack
    -- Executing [s@macro-user-callerid:1] Set("SIP/103-00000002", "AMPUSER=103") in new stack
    -- Executing [s@macro-user-callerid:2] GotoIf("SIP/103-00000002", "0?report") in new stack
    -- Executing [s@macro-user-callerid:3] ExecIf("SIP/103-00000002", "1?Set(REALCALLERIDNUM=103)") in new stack
    -- Executing [s@macro-user-callerid:4] Set("SIP/103-00000002", "AMPUSER=103") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/103-00000002", "AMPUSERCIDNAME=Gabriel") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("SIP/103-00000002", "0?report") in new stack
    -- Executing [s@macro-user-callerid:7] Set("SIP/103-00000002", "AMPUSERCID=103") in new stack
    -- Executing [s@macro-user-callerid:8] Set("SIP/103-00000002", "CALLERID(all)="Gabriel" <103>") in new stack
    -- Executing [s@macro-user-callerid:9] GotoIf("SIP/103-00000002", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:10] Set("SIP/103-00000002", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:11] GotoIf("SIP/103-00000002", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,18)
    -- Executing [s@macro-user-callerid:18] Set("SIP/103-00000002", "CALLERID(number)=103") in new stack
    -- Executing [s@macro-user-callerid:19] Set("SIP/103-00000002", "CALLERID(name)=Gabriel") in new stack
    -- Executing [s@macro-user-callerid:20] NoOp("SIP/103-00000002", "Using CallerID "Gabriel" <103>") in new stack
    -- Executing [s@macro-exten-vm:2] Set("SIP/103-00000002", "RingGroupMethod=none") in new stack
    -- Executing [s@macro-exten-vm:3] Set("SIP/103-00000002", "VMBOX=101") in new stack
    -- Executing [s@macro-exten-vm:4] Set("SIP/103-00000002", "__EXTTOCALL=101") in new stack
    -- Executing [s@macro-exten-vm:5] Set("SIP/103-00000002", "CFUEXT=") in new stack
    -- Executing [s@macro-exten-vm:6] Set("SIP/103-00000002", "CFBEXT=") in new stack
    -- Executing [s@macro-exten-vm:7] Set("SIP/103-00000002", "RT=15") in new stack
    -- Executing [s@macro-exten-vm:8] Macro("SIP/103-00000002", "record-enable,101,IN") in new stack
    -- Executing [s@macro-record-enable:1] GotoIf("SIP/103-00000002", "1?check") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing [s@macro-record-enable:4] ExecIf("SIP/103-00000002", "0?MacroExit()") in new stack
    -- Executing [s@macro-record-enable:5] GotoIf("SIP/103-00000002", "0?Group:OUT") in new stack
    -- Goto (macro-record-enable,s,15)
    -- Executing [s@macro-record-enable:15] GotoIf("SIP/103-00000002", "1?IN") in new stack
    -- Goto (macro-record-enable,s,20)
    -- Executing [s@macro-record-enable:20] ExecIf("SIP/103-00000002", "1?MacroExit()") in new stack
    -- Executing [s@macro-exten-vm:9] Macro("SIP/103-00000002", "dial-one,15,WwTtr,101") in new stack
    -- Executing [s@macro-dial-one:1] Set("SIP/103-00000002", "DEXTEN=101") in new stack
    -- Executing [s@macro-dial-one:2] Set("SIP/103-00000002", "DIALSTATUS_CW=") in new stack
    -- Executing [s@macro-dial-one:3] GosubIf("SIP/103-00000002", "0?screen,1") in new stack
    -- Executing [s@macro-dial-one:4] GosubIf("SIP/103-00000002", "0?cf,1") in new stack
    -- Executing [s@macro-dial-one:5] GotoIf("SIP/103-00000002", "1?skip1") in new stack
    -- Goto (macro-dial-one,s,8)
    -- Executing [s@macro-dial-one:8] GotoIf("SIP/103-00000002", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:9] GotoIf("SIP/103-00000002", "0?continue") in new stack
    -- Executing [s@macro-dial-one:10] Set("SIP/103-00000002", "EXTHASCW=ENABLED") in new stack
    -- Executing [s@macro-dial-one:11] GotoIf("SIP/103-00000002", "0?next1:cwinusebusy") in new stack
    -- Goto (macro-dial-one,s,23)
    -- Executing [s@macro-dial-one:23] GotoIf("SIP/103-00000002", "1?next3:continue") in new stack
    -- Goto (macro-dial-one,s,24)
    -- Executing [s@macro-dial-one:24] ExecIf("SIP/103-00000002", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
    -- Executing [s@macro-dial-one:25] GotoIf("SIP/103-00000002", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:26] GosubIf("SIP/103-00000002", "1?dstring,1:dlocal,1") in new stack
    -- Executing [dstring@macro-dial-one:1] Set("SIP/103-00000002", "DSTRING=") in new stack
    -- Executing [dstring@macro-dial-one:2] Set("SIP/103-00000002", "DEVICES=101") in new stack
    -- Executing [dstring@macro-dial-one:3] ExecIf("SIP/103-00000002", "0?Return()") in new stack
    -- Executing [dstring@macro-dial-one:4] ExecIf("SIP/103-00000002", "0?Set(DEVICES=01)") in new stack
    -- Executing [dstring@macro-dial-one:5] Set("SIP/103-00000002", "LOOPCNT=1") in new stack
    -- Executing [dstring@macro-dial-one:6] Set("SIP/103-00000002", "ITER=1") in new stack
    -- Executing [dstring@macro-dial-one:7] Set("SIP/103-00000002", "THISDIAL=DAHDI/1") in new stack
    -- Executing [dstring@macro-dial-one:8] GosubIf("SIP/103-00000002", "1?zap2dahdi,1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/103-00000002", "0?Return()") in new stack
    -- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/103-00000002", "NEWDIAL=") in new stack
    -- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/103-00000002", "LOOPCNT2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/103-00000002", "ITER2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/103-00000002", "THISPART2=DAHDI/1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/103-00000002", "0?Set(THISPART2=DAHDIDI/1)") in new stack
    -- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/103-00000002", "NEWDIAL=DAHDI/1&") in new stack
    -- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/103-00000002", "ITER2=2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/103-00000002", "0?begin2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/103-00000002", "THISDIAL=DAHDI/1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/103-00000002", "") in new stack
    -- Executing [dstring@macro-dial-one:9] Set("SIP/103-00000002", "DSTRING=DAHDI/1&") in new stack
    -- Executing [dstring@macro-dial-one:10] Set("SIP/103-00000002", "ITER=2") in new stack
    -- Executing [dstring@macro-dial-one:11] GotoIf("SIP/103-00000002", "0?begin") in new stack
    -- Executing [dstring@macro-dial-one:12] Set("SIP/103-00000002", "DSTRING=DAHDI/1") in new stack
    -- Executing [dstring@macro-dial-one:13] Return("SIP/103-00000002", "") in new stack
    -- Executing [s@macro-dial-one:27] GotoIf("SIP/103-00000002", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:28] GotoIf("SIP/103-00000002", "1?skiptrace") in new stack
    -- Goto (macro-dial-one,s,30)
    -- Executing [s@macro-dial-one:30] Set("SIP/103-00000002", "D_OPTIONS=WwTtr") in new stack
    -- Executing [s@macro-dial-one:31] ExecIf("SIP/103-00000002", "0?SIPAddHeader(Alert-Info: )") in new stack
    -- Executing [s@macro-dial-one:32] ExecIf("SIP/103-00000002", "0?SIPAddHeader()") in new stack
    -- Executing [s@macro-dial-one:33] ExecIf("SIP/103-00000002", "0?Set(CHANNEL(musicclass)=)") in new stack
    -- Executing [s@macro-dial-one:34] GosubIf("SIP/103-00000002", "0?qwait,1") in new stack
    -- Executing [s@macro-dial-one:35] Set("SIP/103-00000002", "__CWIGNORE=") in new stack
    -- Executing [s@macro-dial-one:36] Set("SIP/103-00000002", "__KEEPCID=TRUE") in new stack
    -- Executing [s@macro-dial-one:37] Dial("SIP/103-00000002", "DAHDI/1,15,WwTtr") in new stack
[Jan 22 17:04:18] WARNING[17305]: app_dial.c:1759 dial_exec_full: Unable to create channel of type 'DAHDI' (cause 17 - User busy)
  == Everyone is busy/congested at this time (1:1/0/0)
    -- Executing [s@macro-dial-one:38] ExecIf("SIP/103-00000002", "0?Set(DIALSTATUS=)") in new stack
    -- Executing [s@macro-dial-one:39] GosubIf("SIP/103-00000002", "0?s-BUSY,1") in new stack
    -- Executing [s@macro-dial-one:40] MacroExit("SIP/103-00000002", "") in new stack
    -- Executing [s@macro-exten-vm:10] GotoIf("SIP/103-00000002", "0?exit") in new stack
    -- Executing [s@macro-exten-vm:11] Set("SIP/103-00000002", "SV_DIALSTATUS=BUSY") in new stack
    -- Executing [s@macro-exten-vm:12] GosubIf("SIP/103-00000002", "0?docfu,1") in new stack
    -- Executing [s@macro-exten-vm:13] GosubIf("SIP/103-00000002", "0?docfb,1") in new stack
    -- Executing [s@macro-exten-vm:14] Set("SIP/103-00000002", "DIALSTATUS=BUSY") in new stack
    -- Executing [s@macro-exten-vm:15] NoOp("SIP/103-00000002", "Voicemail is '101'") in new stack
    -- Executing [s@macro-exten-vm:16] GotoIf("SIP/103-00000002", "0?s-BUSY,1") in new stack
    -- Executing [s@macro-exten-vm:17] NoOp("SIP/103-00000002", "Sending to Voicemail box 101") in new stack
    -- Executing [s@macro-exten-vm:18] Macro("SIP/103-00000002", "vm,101,BUSY,") in new stack
    -- Executing [s@macro-vm:1] Macro("SIP/103-00000002", "user-callerid,SKIPTTL") in new stack
    -- Executing [s@macro-user-callerid:1] Set("SIP/103-00000002", "AMPUSER=103") in new stack
    -- Executing [s@macro-user-callerid:2] GotoIf("SIP/103-00000002", "0?report") in new stack
    -- Executing [s@macro-user-callerid:3] ExecIf("SIP/103-00000002", "0?Set(REALCALLERIDNUM=103)") in new stack
    -- Executing [s@macro-user-callerid:4] Set("SIP/103-00000002", "AMPUSER=103") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/103-00000002", "AMPUSERCIDNAME=Gabriel") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("SIP/103-00000002", "0?report") in new stack
    -- Executing [s@macro-user-callerid:7] Set("SIP/103-00000002", "AMPUSERCID=103") in new stack
    -- Executing [s@macro-user-callerid:8] Set("SIP/103-00000002", "CALLERID(all)="Gabriel" <103>") in new stack
    -- Executing [s@macro-user-callerid:9] GotoIf("SIP/103-00000002", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,18)
    -- Executing [s@macro-user-callerid:18] Set("SIP/103-00000002", "CALLERID(number)=103") in new stack
    -- Executing [s@macro-user-callerid:19] Set("SIP/103-00000002", "CALLERID(name)=Gabriel") in new stack
    -- Executing [s@macro-user-callerid:20] NoOp("SIP/103-00000002", "Using CallerID "Gabriel" <103>") in new stack
    -- Executing [s@macro-vm:2] Set("SIP/103-00000002", "VMGAIN=""") in new stack
[Jan 22 17:04:18] WARNING[17305]: func_db.c:116 function_db_read: DB requires an argument, DB(<family>/<key>)
    -- Executing [s@macro-vm:3] GotoIf("SIP/103-00000002", "1?vmx,1") in new stack
    -- Goto (macro-vm,vmx,1)
    -- Executing [vmx@macro-vm:1] Set("SIP/103-00000002", "MEXTEN=101") in new stack
    -- Executing [vmx@macro-vm:2] Set("SIP/103-00000002", "MMODE=BUSY") in new stack
    -- Executing [vmx@macro-vm:3] Set("SIP/103-00000002", "RETVM=") in new stack
    -- Executing [vmx@macro-vm:4] Set("SIP/103-00000002", "MODE=busy") in new stack
    -- Executing [vmx@macro-vm:5] GotoIf("SIP/103-00000002", "0?chknomsg") in new stack
    -- Executing [vmx@macro-vm:6] Set("SIP/103-00000002", "VM_OPTS=") in new stack
    -- Executing [vmx@macro-vm:7] GotoIf("SIP/103-00000002", "0?s-BUSY,1") in new stack
    -- Executing [vmx@macro-vm:8] GotoIf("SIP/103-00000002", "1?notdirect") in new stack
    -- Goto (macro-vm,vmx,10)
    -- Executing [vmx@macro-vm:10] NoOp("SIP/103-00000002", "Checking if ext 101 is enabled: enabled") in new stack
    -- Executing [vmx@macro-vm:11] GotoIf("SIP/103-00000002", "0?s-BUSY,1") in new stack
    -- Executing [vmx@macro-vm:12] Macro("SIP/103-00000002", "get-vmcontext,101") in new stack
    -- Executing [s@macro-get-vmcontext:1] Set("SIP/103-00000002", "VMCONTEXT=default") in new stack
    -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/103-00000002", "0?200:300") in new stack
    -- Goto (macro-get-vmcontext,s,300)
    -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/103-00000002", "") in new stack
    -- Executing [vmx@macro-vm:13] GotoIf("SIP/103-00000002", "0?tmpgreet") in new stack
    -- Executing [vmx@macro-vm:14] GotoIf("SIP/103-00000002", "0?nofile") in new stack
    -- Executing [vmx@macro-vm:15] Set("SIP/103-00000002", "LOOPCOUNT=0") in new stack
    -- Executing [vmx@macro-vm:16] GotoIf("SIP/103-00000002", "1?vmxtime") in new stack
    -- Goto (macro-vm,vmx,18)
    -- Executing [vmx@macro-vm:18] GotoIf("SIP/103-00000002", "1?vmxloops") in new stack
    -- Goto (macro-vm,vmx,20)
    -- Executing [vmx@macro-vm:20] GotoIf("SIP/103-00000002", "1?vmxanswer") in new stack
    -- Goto (macro-vm,vmx,22)
    -- Executing [vmx@macro-vm:22] Answer("SIP/103-00000002", "") in new stack
    -- Executing [vmx@macro-vm:23] Read("SIP/103-00000002", "ACTION,/var/spool/asterisk/voicemail/default/101/busy,1,skip,1,2") in new stack
    -- Accepting a maximum of 1 digits.
    -- <SIP/103-00000002> Playing '/var/spool/asterisk/voicemail/default/101/busy.gsm' (language 'es')
    -- User disconnected
    -- Executing [h@from-internal:1] Macro("SIP/103-00000002", "hangupcall") in new stack
    -- Executing [s@macro-hangupcall:1] GotoIf("SIP/103-00000002", "1?skiprg") in new stack
    -- Goto (macro-hangupcall,s,4)
    -- Executing [s@macro-hangupcall:4] GotoIf("SIP/103-00000002", "1?skipblkvm") in new stack
    -- Goto (macro-hangupcall,s,7)
    -- Executing [s@macro-hangupcall:7] GotoIf("SIP/103-00000002", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,9)
    -- Executing [s@macro-hangupcall:9] Hangup("SIP/103-00000002", "") in new stack
  == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/103-00000002' in macro 'hangupcall'
  == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/103-00000002'
       > doing dnsmgr_lookup for 'sip09.us.overvoip.net'