Hangup instead of voicemail when using DID

[system]

Hello all,

I have set up Elastix 2.40 using asterisk Asterisk 1.8.20.0 and have it more-or-less configured. I have created 4 extensions, each with a DID inbound route. The route matching is working fine. However, the call is dropped instead of voicemail picking up as expected.

Can someone please help me

Here is the debug output:

sip_additional.conf for my extension 201:

[201]
deny=0.0.0.0/0.0.0.0
secret=11223344
dtmfmode=rfc2833
canreinvite=no
context=from-internal
host=dynamic
type=friend
nat=yes
port=5060
qualify=yes
callgroup=
pickupgroup=
dial=SIP/201
mailbox=201@default
permit=0.0.0.0/0.0.0.0
callerid=device <201>
callcounter=yes
faxdetect=no

call trace…

== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [12814884351@from-trunk:1] Set(“SIP/InboundSIP Trunk-00000087”, “__FROM_DID=12814884351”) in new stack
– Executing [12814884351@from-trunk:2] Gosub(“SIP/InboundSIP Trunk-00000087”, “app-blacklist-check,s,1”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Set(“SIP/InboundSIP Trunk-00000087”, “CALLED_BLACKLIST=1”) in new stack
– Executing [s@app-blacklist-check:3] Return(“SIP/InboundSIP Trunk-00000087”, “”) in new stack
– Executing [12814884351@from-trunk:3] ExecIf(“SIP/InboundSIP Trunk-00000087”, “1 ?Set(CALLERID(name)=+17134381122)”) in new stack
– Executing [12814884351@from-trunk:4] Set(“SIP/InboundSIP Trunk-00000087”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
– Executing [12814884351@from-trunk:5] Set(“SIP/InboundSIP Trunk-00000087”, “CALLERPRES()=allowed_not_screened”) in new stack
– Executing [12814884351@from-trunk:6] Goto(“SIP/InboundSIP Trunk-00000087”, “from-did-direct,201,1”) in new stack
– Goto (from-did-direct,201,1)
– Executing [201@from-did-direct:1] Macro(“SIP/InboundSIP Trunk-00000087”, “exten-vm,201,201”) in new stack
– Executing [s@macro-exten-vm:1] Macro(“SIP/InboundSIP Trunk-00000087”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/InboundSIP Trunk-00000087”, “AMPUSER=+17134381122”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“SIP/InboundSIP Trunk-00000087”, “1?Set(REALCALLERIDNUM=+17134381122)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“SIP/InboundSIP Trunk-00000087”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/InboundSIP Trunk-00000087”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?report”) in new stack
– Goto (macro-user-callerid,s,10)
– Executing [s@macro-user-callerid:10] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:11] Set(“SIP/InboundSIP Trunk-00000087”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:12] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,19)
– Executing [s@macro-user-callerid:19] Set(“SIP/InboundSIP Trunk-00000087”, “CALLERID(number)=+17134381122”) in new stack
– Executing [s@macro-user-callerid:20] Set(“SIP/InboundSIP Trunk-00000087”, “CALLERID(name)=+17134381122”) in new stack
– Executing [s@macro-user-callerid:21] NoOp(“SIP/InboundSIP Trunk-00000087”, “Using CallerID “+17134381122” <+17134381122>”) in new stack
– Executing [s@macro-exten-vm:2] Set(“SIP/InboundSIP Trunk-00000087”, “RingGroupMethod=none”) in new stack
– Executing [s@macro-exten-vm:3] Set(“SIP/InboundSIP Trunk-00000087”, “VMBOX=201”) in new stack
– Executing [s@macro-exten-vm:4] Set(“SIP/InboundSIP Trunk-00000087”, “__EXTTOCALL=201”) in new stack
– Executing [s@macro-exten-vm:5] Set(“SIP/InboundSIP Trunk-00000087”, “CFUEXT=”) in new stack
– Executing [s@macro-exten-vm:6] Set(“SIP/InboundSIP Trunk-00000087”, “CFBEXT=”) in new stack
– Executing [s@macro-exten-vm:7] Set(“SIP/InboundSIP Trunk-00000087”, “RT=15”) in new stack
– Executing [s@macro-exten-vm:8] Macro(“SIP/InboundSIP Trunk-00000087”, “record-enable,201,IN”) in new stack
– Executing [s@macro-record-enable:1] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?check”) in new stack
– Goto (macro-record-enable,s,4)
– Executing [s@macro-record-enable:4] ExecIf(“SIP/InboundSIP Trunk-00000087”, “0?MacroExit()”) in new stack
– Executing [s@macro-record-enable:5] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?Group:OUT”) in new stack
– Goto (macro-record-enable,s,15)
– Executing [s@macro-record-enable:15] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?IN”) in new stack
– Goto (macro-record-enable,s,20)
– Executing [s@macro-record-enable:20] ExecIf(“SIP/InboundSIP Trunk-00000087”, “1?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:9] Macro(“SIP/InboundSIP Trunk-00000087”, “dial-one,15,tr,201”) in new stack
– Executing [s@macro-dial-one:1] Set(“SIP/InboundSIP Trunk-00000087”, “DEXTEN=201”) in new stack
– Executing [s@macro-dial-one:2] Set(“SIP/InboundSIP Trunk-00000087”, “DIALSTATUS_CW=”) in new stack
– Executing [s@macro-dial-one:3] GosubIf(“SIP/InboundSIP Trunk-00000087”, “0?screen,1”) in new stack
– Executing [s@macro-dial-one:4] GosubIf(“SIP/InboundSIP Trunk-00000087”, “0?cf,1”) in new stack
– Executing [s@macro-dial-one:5] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,8)
– Executing [s@macro-dial-one:8] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:9] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?continue”) in new stack
– Executing [s@macro-dial-one:10] Set(“SIP/InboundSIP Trunk-00000087”, “EXTHASCW=”) in new stack
– Executing [s@macro-dial-one:11] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,12)
– Executing [s@macro-dial-one:12] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?docfu:skip3”) in new stack
– Goto (macro-dial-one,s,16)
– Executing [s@macro-dial-one:16] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?next2:continue”) in new stack
– Goto (macro-dial-one,s,17)
– Executing [s@macro-dial-one:17] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?continue”) in new stack
– Goto (macro-dial-one,s,25)
– Executing [s@macro-dial-one:25] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:26] GosubIf(“SIP/InboundSIP Trunk-00000087”, “1?dstring,1:dlocal,1”) in new stack
– Executing [dstring@macro-dial-one:1] Set(“SIP/InboundSIP Trunk-00000087”, “DSTRING=”) in new stack
– Executing [dstring@macro-dial-one:2] Set(“SIP/InboundSIP Trunk-00000087”, “DEVICES=201”) in new stack
– Executing [dstring@macro-dial-one:3] ExecIf(“SIP/InboundSIP Trunk-00000087”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:4] ExecIf(“SIP/InboundSIP Trunk-00000087”, “0?Set(DEVICES=01)”) in new stack
– Executing [dstring@macro-dial-one:5] Set(“SIP/InboundSIP Trunk-00000087”, “LOOPCNT=1”) in new stack
– Executing [dstring@macro-dial-one:6] Set(“SIP/InboundSIP Trunk-00000087”, “ITER=1”) in new stack
– Executing [dstring@macro-dial-one:7] Set(“SIP/InboundSIP Trunk-00000087”, “THISDIAL=SIP/201”) in new stack
– Executing [dstring@macro-dial-one:8] GosubIf(“SIP/InboundSIP Trunk-00000087”, “1?zap2dahdi,1”) in new stack
– Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/InboundSIP Trunk-00000087”, “0?Return()”) in new stack
– Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/InboundSIP Trunk-00000087”, “NEWDIAL=”) in new stack
– Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/InboundSIP Trunk-00000087”, “LOOPCNT2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/InboundSIP Trunk-00000087”, “ITER2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/InboundSIP Trunk-00000087”, “THISPART2=SIP/201”) in new stack
– Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/InboundSIP Trunk-00000087”, “0?Set(THISPART2=DAHDI/201)”) in new stack
– Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/InboundSIP Trunk-00000087”, “NEWDIAL=SIP/201&”) in new stack
– Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/InboundSIP Trunk-00000087”, “ITER2=2”) in new stack
– Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?begin2”) in new stack
– Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/InboundSIP Trunk-00000087”, “THISDIAL=SIP/201”) in new stack
– Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/InboundSIP Trunk-00000087”, “”) in new stack
– Executing [dstring@macro-dial-one:9] Set(“SIP/InboundSIP Trunk-00000087”, “DSTRING=SIP/201&”) in new stack
– Executing [dstring@macro-dial-one:10] Set(“SIP/InboundSIP Trunk-00000087”, “ITER=2”) in new stack
– Executing [dstring@macro-dial-one:11] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?begin”) in new stack
– Executing [dstring@macro-dial-one:12] Set(“SIP/InboundSIP Trunk-00000087”, “DSTRING=SIP/201”) in new stack
– Executing [dstring@macro-dial-one:13] Return(“SIP/InboundSIP Trunk-00000087”, “”) in new stack
– Executing [s@macro-dial-one:27] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:28] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?skiptrace”) in new stack
– Executing [s@macro-dial-one:29] GosubIf(“SIP/InboundSIP Trunk-00000087”, “1?ctset,1:ctclear,1”) in new stack
– Executing [ctset@macro-dial-one:1] Set(“SIP/InboundSIP Trunk-00000087”, “DB(CALLTRACE/201)=+17134381122”) in new stack
– Executing [ctset@macro-dial-one:2] Return(“SIP/InboundSIP Trunk-00000087”, “”) in new stack
– Executing [s@macro-dial-one:30] Set(“SIP/InboundSIP Trunk-00000087”, “D_OPTIONS=tr”) in new stack
– Executing [s@macro-dial-one:31] ExecIf(“SIP/InboundSIP Trunk-00000087”, “0?SIPAddHeader(Alert-Info: )”) in new stack
– Executing [s@macro-dial-one:32] ExecIf(“SIP/InboundSIP Trunk-00000087”, “0?SIPAddHeader()”) in new stack
– Executing [s@macro-dial-one:33] ExecIf(“SIP/InboundSIP Trunk-00000087”, “0?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [s@macro-dial-one:34] GosubIf(“SIP/InboundSIP Trunk-00000087”, “0?qwait,1”) in new stack
– Executing [s@macro-dial-one:35] Set(“SIP/InboundSIP Trunk-00000087”, “__CWIGNORE=”) in new stack
– Executing [s@macro-dial-one:36] Set(“SIP/InboundSIP Trunk-00000087”, “__KEEPCID=TRUE”) in new stack
– Executing [s@macro-dial-one:37] Dial(“SIP/InboundSIP Trunk-00000087”, “SIP/201,15,tr”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called SIP/201
– SIP/201-00000088 is ringing
– Nobody picked up in 15000 ms
– Executing [s@macro-dial-one:38] ExecIf(“SIP/InboundSIP Trunk-00000087”, “0?Set(DIALSTATUS=)”) in new stack
– Executing [s@macro-dial-one:39] GosubIf(“SIP/InboundSIP Trunk-00000087”, “0?s-NOANSWER,1”) in new stack
– Executing [s@macro-dial-one:40] MacroExit(“SIP/InboundSIP Trunk-00000087”, “”) in new stack
– Executing [s@macro-exten-vm:10] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?exit”) in new stack
– Executing [s@macro-exten-vm:11] Set(“SIP/InboundSIP Trunk-00000087”, “SV_DIALSTATUS=NOANSWER”) in new stack
– Executing [s@macro-exten-vm:12] GosubIf(“SIP/InboundSIP Trunk-00000087”, “0?docfu,1”) in new stack
– Executing [s@macro-exten-vm:13] GosubIf(“SIP/InboundSIP Trunk-00000087”, “0?docfb,1”) in new stack
– Executing [s@macro-exten-vm:14] Set(“SIP/InboundSIP Trunk-00000087”, “DIALSTATUS=NOANSWER”) in new stack
– Executing [s@macro-exten-vm:15] NoOp(“SIP/InboundSIP Trunk-00000087”, “Voicemail is ‘201’”) in new stack
– Executing [s@macro-exten-vm:16] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?s-NOANSWER,1”) in new stack
– Executing [s@macro-exten-vm:17] NoOp(“SIP/InboundSIP Trunk-00000087”, “Sending to Voicemail box 201”) in new stack
– Executing [s@macro-exten-vm:18] Macro(“SIP/InboundSIP Trunk-00000087”, “vm,201,NOANSWER,”) in new stack
– Executing [s@macro-vm:1] Macro(“SIP/InboundSIP Trunk-00000087”, “user-callerid,SKIPTTL”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/InboundSIP Trunk-00000087”, “AMPUSER=+17134381122”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“SIP/InboundSIP Trunk-00000087”, “0?Set(REALCALLERIDNUM=+17134381122)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“SIP/InboundSIP Trunk-00000087”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/InboundSIP Trunk-00000087”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?report”) in new stack
– Goto (macro-user-callerid,s,10)
– Executing [s@macro-user-callerid:10] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,19)
– Executing [s@macro-user-callerid:19] Set(“SIP/InboundSIP Trunk-00000087”, “CALLERID(number)=+17134381122”) in new stack
– Executing [s@macro-user-callerid:20] Set(“SIP/InboundSIP Trunk-00000087”, “CALLERID(name)=+17134381122”) in new stack
– Executing [s@macro-user-callerid:21] NoOp(“SIP/InboundSIP Trunk-00000087”, “Using CallerID “+17134381122” <+17134381122>”) in new stack
– Executing [s@macro-vm:2] Set(“SIP/InboundSIP Trunk-00000087”, “VMGAIN=”“”) in new stack
– Executing [s@macro-vm:3] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?vmx,1”) in new stack
– Goto (macro-vm,vmx,1)
– Executing [vmx@macro-vm:1] Set(“SIP/InboundSIP Trunk-00000087”, “MEXTEN=201”) in new stack
– Executing [vmx@macro-vm:2] Set(“SIP/InboundSIP Trunk-00000087”, “MMODE=NOANSWER”) in new stack
– Executing [vmx@macro-vm:3] Set(“SIP/InboundSIP Trunk-00000087”, “RETVM=”) in new stack
– Executing [vmx@macro-vm:4] Set(“SIP/InboundSIP Trunk-00000087”, “MODE=unavail”) in new stack
– Executing [vmx@macro-vm:5] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?chknomsg”) in new stack
– Executing [vmx@macro-vm:6] Set(“SIP/InboundSIP Trunk-00000087”, “VM_OPTS=”) in new stack
– Executing [vmx@macro-vm:7] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?s-NOANSWER,1”) in new stack
– Executing [vmx@macro-vm:8] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?notdirect”) in new stack
– Goto (macro-vm,vmx,10)
– Executing [vmx@macro-vm:10] NoOp(“SIP/InboundSIP Trunk-00000087”, “Checking if ext 201 is enabled: blocked”) in new stack
– Executing [vmx@macro-vm:11] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?s-NOANSWER,1”) in new stack
– Goto (macro-vm,s-NOANSWER,1)
– Executing [s-NOANSWER@macro-vm:1] Macro(“SIP/InboundSIP Trunk-00000087”, “get-vmcontext,201”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“SIP/InboundSIP Trunk-00000087”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/InboundSIP Trunk-00000087”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“SIP/InboundSIP Trunk-00000087”, “”) in new stack
– Executing [s-NOANSWER@macro-vm:2] VoiceMail(“SIP/InboundSIP Trunk-00000087”, “201@default,u”“”) in new stack
== Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘SIP/InboundSIP Trunk-00000087’ in macro ‘vm’
== Spawn extension (macro-exten-vm, s, 18) exited non-zero on ‘SIP/InboundSIP Trunk-00000087’ in macro ‘exten-vm’
== Spawn extension (from-did-direct, 201, 1) exited non-zero on ‘SIP/InboundSIP Trunk-00000087’
– Executing [h@from-did-direct:1] Macro(“SIP/InboundSIP Trunk-00000087”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?endmixmoncheck”) in new stack
– Goto (macro-hangupcall,s,9)
– Executing [s@macro-hangupcall:9] NoOp(“SIP/InboundSIP Trunk-00000087”, “End of MIXMON check”) in new stack
– Executing [s@macro-hangupcall:10] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?nomeetmemon”) in new stack
– Goto (macro-hangupcall,s,28)
– Executing [s@macro-hangupcall:28] NoOp(“SIP/InboundSIP Trunk-00000087”, “End of MEETME check”) in new stack
– Executing [s@macro-hangupcall:29] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?noautomon”) in new stack
– Goto (macro-hangupcall,s,34)
– Executing [s@macro-hangupcall:34] NoOp(“SIP/InboundSIP Trunk-00000087”, “TOUCH_MONITOR_OUTPUT=”) in new stack
– Executing [s@macro-hangupcall:35] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?noautomon2”) in new stack
– Goto (macro-hangupcall,s,41)
– Executing [s@macro-hangupcall:41] NoOp(“SIP/InboundSIP Trunk-00000087”, “MONITOR_FILENAME=”) in new stack
– Executing [s@macro-hangupcall:42] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?skiprg”) in new stack
– Goto (macro-hangupcall,s,45)
– Executing [s@macro-hangupcall:45] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?skipblkvm”) in new stack
– Goto (macro-hangupcall,s,48)
– Executing [s@macro-hangupcall:48] GotoIf(“SIP/InboundSIP Trunk-00000087”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,50)
– Executing [s@macro-hangupcall:50] AGI(“SIP/InboundSIP Trunk-00000087”, “hangup.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi
– <SIP/InboundSIP Trunk-00000087>AGI Script hangup.agi completed, returning 0
– Executing [s@macro-hangupcall:51] Hangup(“SIP/InboundSIP Trunk-00000087”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 51) exited non-zero on ‘SIP/InboundSIP Trunk-00000087’ in macro ‘hangupcall’
== Spawn extension (from-did-direct, h, 1) exited non-zero on 'SIP/InboundSIP Trunk-00000087

[david55]

Your problem relates to Elastix, not to Asterisk. You need to take it up with the Elastix people. They are the ones who know how their dialplans and AGI scripts are supposed to work.