Call trace

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