Call Forward simply gives Busy on incoming call

Lo folks,

This is a simple system, no custom contexts just the normal from-internal, an outbound route and a SIP trunk. When we set a Call Forward on an extension (either to another extension or an external number) the person calling the extension simply gets Busy on their screen and doesn’t actually get forwarded… here is an example scenario…

From an external source were dialling 01611113888 which is pointing straight to extension 3888 which has been set to call forward unconditional (using *72) to 3887.

Entry in database after using *72…

/CF/3888 : 3887

Logs…

– Executing [01611113888@from-trunk:1] Set(“SIP/844265900-0000009e”, “__FROM_DID=01611113888”) in new stack
– Executing [01611113888@from-trunk:2] Gosub(“SIP/844265900-0000009e”, “app-blacklist-check,s,1”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“SIP/844265900-0000009e”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Set(“SIP/844265900-0000009e”, “CALLED_BLACKLIST=1”) in new stack
– Executing [s@app-blacklist-check:3] Return(“SIP/844265900-0000009e”, “”) in new stack
– Executing [01611113888@from-trunk:3] ExecIf(“SIP/844265900-0000009e”, “0 ?Set(CALLERID(name)=08451233322)”) in new stack
– Executing [01611113888@from-trunk:4] Set(“SIP/844265900-0000009e”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
– Executing [01611113888@from-trunk:5] Set(“SIP/844265900-0000009e”, “CALLERPRES()=allowed_not_screened”) in new stack
– Executing [01611113888@from-trunk:6] Goto(“SIP/844265900-0000009e”, “from-did-direct,3888,1”) in new stack
– Goto (from-did-direct,3888,1)
– Executing [3888@from-did-direct:1] ExecIf(“SIP/844265900-0000009e”, “0?Set(__RINGTIMER=0)”) in new stack
– Executing [3888@from-did-direct:2] Macro(“SIP/844265900-0000009e”, “exten-vm,novm,3888,0,0,0”) in new stack
– Executing [s@macro-exten-vm:1] Macro(“SIP/844265900-0000009e”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/844265900-0000009e”, “AMPUSER=08451233322”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“SIP/844265900-0000009e”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“SIP/844265900-0000009e”, “1?Set(REALCALLERIDNUM=08451233322)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“SIP/844265900-0000009e”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/844265900-0000009e”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/844265900-0000009e”, “1?report”) in new stack
– Goto (macro-user-callerid,s,11)
– Executing [s@macro-user-callerid:11] GotoIf(“SIP/844265900-0000009e”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:12] Set(“SIP/844265900-0000009e”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:13] GotoIf(“SIP/844265900-0000009e”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,24)
– Executing [s@macro-user-callerid:24] Set(“SIP/844265900-0000009e”, “CALLERID(number)=08451233322”) in new stack
– Executing [s@macro-user-callerid:25] Set(“SIP/844265900-0000009e”, “CALLERID(name)=08451233322”) in new stack
– Executing [s@macro-user-callerid:26] Set(“SIP/844265900-0000009e”, “CHANNEL(language)=en”) in new stack
– Executing [s@macro-exten-vm:2] Set(“SIP/844265900-0000009e”, “RingGroupMethod=none”) in new stack
– Executing [s@macro-exten-vm:3] Set(“SIP/844265900-0000009e”, “__EXTTOCALL=3888”) in new stack
– Executing [s@macro-exten-vm:4] Set(“SIP/844265900-0000009e”, “__PICKUPMARK=3888”) in new stack
– Executing [s@macro-exten-vm:5] Set(“SIP/844265900-0000009e”, “RT=”"") in new stack
– Executing [s@macro-exten-vm:6] Macro(“SIP/844265900-0000009e”, “record-enable,3888,IN”) in new stack
– Executing [s@macro-record-enable:1] GotoIf(“SIP/844265900-0000009e”, “1?check”) in new stack
– Goto (macro-record-enable,s,4)
– Executing [s@macro-record-enable:4] ExecIf(“SIP/844265900-0000009e”, “0?MacroExit()”) in new stack
– Executing [s@macro-record-enable:5] GotoIf(“SIP/844265900-0000009e”, “0?Group:OUT”) in new stack
– Goto (macro-record-enable,s,14)
– Executing [s@macro-record-enable:14] GotoIf(“SIP/844265900-0000009e”, “1?IN”) in new stack
– Goto (macro-record-enable,s,18)
– Executing [s@macro-record-enable:18] ExecIf(“SIP/844265900-0000009e”, “1?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:7] Macro(“SIP/844265900-0000009e”, “dial-one,”",tr,3888") in new stack
– Executing [s@macro-dial-one:1] Set(“SIP/844265900-0000009e”, “DEXTEN=3888”) in new stack
– Executing [s@macro-dial-one:2] Set(“SIP/844265900-0000009e”, “DIALSTATUS_CW=”) in new stack
– Executing [s@macro-dial-one:3] GosubIf(“SIP/844265900-0000009e”, “0?screen,1”) in new stack
– Executing [s@macro-dial-one:4] GosubIf(“SIP/844265900-0000009e”, “1?cf,1”) in new stack
– Executing [cf@macro-dial-one:1] Set(“SIP/844265900-0000009e”, “CFAMPUSER=08451233322”) in new stack
– Executing [cf@macro-dial-one:2] ExecIf(“SIP/844265900-0000009e”, “0?Return()”) in new stack
– Executing [cf@macro-dial-one:3] ExecIf(“SIP/844265900-0000009e”, “0?Set(ARG1=0)”) in new stack
– Executing [cf@macro-dial-one:4] Set(“SIP/844265900-0000009e”, “DEXTEN=3887#”) in new stack
– Executing [cf@macro-dial-one:5] Set(“SIP/844265900-0000009e”, “__DIVERSION_REASON=unconditional”) in new stack
– Executing [cf@macro-dial-one:6] ExecIf(“SIP/844265900-0000009e”, “1?Return()”) in new stack
– Executing [s@macro-dial-one:5] GotoIf(“SIP/844265900-0000009e”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,8)
– Executing [s@macro-dial-one:8] GotoIf(“SIP/844265900-0000009e”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:9] GotoIf(“SIP/844265900-0000009e”, “1?continue”) in new stack
– Goto (macro-dial-one,s,25)
– Executing [s@macro-dial-one:25] GotoIf(“SIP/844265900-0000009e”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:26] GosubIf(“SIP/844265900-0000009e”, “0?dstring,1:dlocal,1”) in new stack
– Executing [dlocal@macro-dial-one:1] Set(“SIP/844265900-0000009e”, “DSTRING=”) in new stack
– Executing [dlocal@macro-dial-one:2] Set(“SIP/844265900-0000009e”, “USEGOTO=”) in new stack
– Executing [dlocal@macro-dial-one:3] Return(“SIP/844265900-0000009e”, “”) in new stack
– Executing [s@macro-dial-one:27] GotoIf(“SIP/844265900-0000009e”, “1?nodial”) in new stack
– Goto (macro-dial-one,s,46)
– Executing [s@macro-dial-one:46] ExecIf(“SIP/844265900-0000009e”, “1?Set(DIALSTATUS=NOANSWER)”) in new stack
– Executing [s@macro-dial-one:47] NoOp(“SIP/844265900-0000009e”, “Returned from dial-one with nothing to call and DIALSTATUS: NOANSWER”) in new stack
– Executing [s@macro-dial-one:48] MacroExit(“SIP/844265900-0000009e”, “”) in new stack
– Executing [s@macro-exten-vm:8] GotoIf(“SIP/844265900-0000009e”, “0?exit”) in new stack
– Executing [s@macro-exten-vm:9] Set(“SIP/844265900-0000009e”, “SV_DIALSTATUS=NOANSWER”) in new stack
– Executing [s@macro-exten-vm:10] GosubIf(“SIP/844265900-0000009e”, “0?docfu,1”) in new stack
– Executing [s@macro-exten-vm:11] GosubIf(“SIP/844265900-0000009e”, “0?docfb,1”) in new stack
– Executing [s@macro-exten-vm:12] Set(“SIP/844265900-0000009e”, “DIALSTATUS=NOANSWER”) in new stack
– Executing [s@macro-exten-vm:13] ExecIf(“SIP/844265900-0000009e”, “0?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:14] GotoIf(“SIP/844265900-0000009e”, “1?s-NOANSWER,1”) in new stack
– Goto (macro-exten-vm,s-NOANSWER,1)
– Executing [s-NOANSWER@macro-exten-vm:1] GotoIf(“SIP/844265900-0000009e”, “0?exit,1”) in new stack
– Executing [s-NOANSWER@macro-exten-vm:2] PlayTones(“SIP/844265900-0000009e”, “congestion”) in new stack
– Executing [s-NOANSWER@macro-exten-vm:3] Congestion(“SIP/844265900-0000009e”, “10”) in new stack
== Spawn extension (macro-exten-vm, s-NOANSWER, 3) exited non-zero on ‘SIP/844265900-0000009e’ in macro ‘exten-vm’
== Spawn extension (from-did-direct, 3888, 2) exited non-zero on ‘SIP/844265900-0000009e’
– Executing [h@from-did-direct:1] Macro(“SIP/844265900-0000009e”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“SIP/844265900-0000009e”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [s@macro-hangupcall:3] Hangup(“SIP/844265900-0000009e”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 3) exited non-zero on ‘SIP/844265900-0000009e’ in macro ‘hangupcall’
== Spawn extension (from-did-direct, h, 1) exited non-zero on ‘SIP/844265900-0000009e’

Now someone has mentioned that on this line…
– Executing [cf@macro-dial-one:4] Set(“SIP/844265900-0000009e”, “DEXTEN=3887#”) in new stack
There shouldn’t be a hash, but I certainly haven’t dialled a hash when using *72 and as you can see it is not in the database.

Any other ideas most appreciated as I can’t spot what is causing this.

Steven