IVR Calls failing

General Help
1

I’m getting a dial plan syntax error and ultimately a call failure when routing a call to an IVR then trying to transfer to an extension. Does anyone have any insight into the attached debug?

[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: == CDR updated on SIP/OakM3K-00000005
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [2@ivr-2:1] Goto("SIP/OakM3K-00000005 ", “from-did-direct,1002,1”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (from-did-direct,1002,1)
[2013-03-22 05:08:11] WARNING[25980] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, une xpected ‘>’, expecting $end; Input:

0
^
[2013-03-22 05:08:11] WARNING[25980] ast_expr2.fl: If you have questions, please refer to https:// Home - Asterisk Documentation
[2013-03-22 05:08:11] WARNING[25980] func_logic.c: Syntax IF(?[:]) (expr mus t be non-null, and either or must be non-null)
[2013-03-22 05:08:11] WARNING[25980] func_logic.c: In this case, =‘’, =‘’, and < false>=‘15’
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [1002@from-did-direct:1] Set(“SIP/Oak M3K-00000005”, “__RINGTIMER=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [1002@from-did-direct:2] Macro(“SIP/O akM3K-00000005”, “exten-vm,1002,1002,0,0,0”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:1] Macro(“SIP/OakM3 K-00000005”, “user-callerid,”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/Oa kM3K-00000005”, “AMPUSER=2142283499”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP /OakM3K-00000005”, “0?report”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP /OakM3K-00000005”, “1?Set(REALCALLERIDNUM=2142283499)”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/Oa kM3K-00000005”, “AMPUSER=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/Oa kM3K-00000005”, “AMPUSERCIDNAME=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP /OakM3K-00000005”, “1?report”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-user-callerid,s,13)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:13] GotoIf(“SI P/OakM3K-00000005”, “0?continue”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:14] Set(“SIP/O akM3K-00000005”, “__TTL=64”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:15] GotoIf(“SI P/OakM3K-00000005”, “1?continue”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-user-callerid,s,26)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:26] Set(“SIP/O akM3K-00000005”, “CALLERID(number)=2142283499”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:27] Set(“SIP/O akM3K-00000005”, “CALLERID(name)=2142283499”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:28] Set(“SIP/O akM3K-00000005”, “CHANNEL(language)=en”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:2] Set(“SIP/OakM3K- 00000005”, “RingGroupMethod=none”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:3] Set(“SIP/OakM3K- 00000005”, “__EXTTOCALL=1002”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:4] Set(“SIP/OakM3K- 00000005”, “__PICKUPMARK=1002”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:5] Set(“SIP/OakM3K- 00000005”, “RT=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:6] Gosub(“SIP/OakM3 K-00000005”, “sub-record-check,s,1(exten,1002,)”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“SIP/Oa kM3K-00000005”, “1?check”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (sub-record-check,s,6)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:6] Set(“SIP/OakM3 K-00000005”, “__MON_FMT=wav”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:7] GotoIf(“SIP/Oa kM3K-00000005”, “1?next”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (sub-record-check,s,10)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:10] ExecIf(“SIP/O akM3K-00000005”, “0?Return()”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:11] GotoIf(“SIP/O akM3K-00000005”, “0?exten,1”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:12] Set(“SIP/OakM 3K-00000005”, “__REC_STATUS=INITIALIZED”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:13] ExecIf(“SIP/O akM3K-00000005”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:14] Set(“SIP/OakM 3K-00000005”, “NOW=1363946891”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:15] Set(“SIP/OakM 3K-00000005”, “__DAY=22”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:16] Set(“SIP/OakM 3K-00000005”, “__MONTH=03”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:17] Set(“SIP/OakM 3K-00000005”, “__YEAR=2013”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:18] Set(“SIP/OakM 3K-00000005”, “__TIMESTR=20130322-050811”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:19] Set(“SIP/OakM 3K-00000005”, “__FROMEXTEN=2142283499”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:20] Set(“SIP/OakM 3K-00000005”, “__CALLFILENAME=exten-1002-2142283499-20130322-050811-1363946885.5”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@sub-record-check:21] Goto(“SIP/Oak M3K-00000005”, “exten,1”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (sub-record-check,exten,1)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [exten@sub-record-check:1] GotoIf(“SI P/OakM3K-00000005”, “0?callee”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [exten@sub-record-check:2] Set(“SIP/O akM3K-00000005”, “__REC_POLICY_MODE=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [exten@sub-record-check:3] GotoIf(“SI P/OakM3K-00000005”, “0?caller”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [exten@sub-record-check:4] GotoIf(“SI P/OakM3K-00000005”, “1?callee”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (sub-record-check,exten,8)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [exten@sub-record-check:8] GosubIf(“S IP/OakM3K-00000005”, “0?record,1(exten,1002,2142283499)”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [exten@sub-record-check:9] Return(“SI P/OakM3K-00000005”, “”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:7] GotoIf(“SIP/OakM 3K-00000005”, “1?macrodial”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-exten-vm,s,13)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:13] GosubIf(“SIP/Oa kM3K-00000005”, “0?clrheader,1()”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:14] Macro(“SIP/OakM 3K-00000005”, “dial-one,tr,1002”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:1] Set(“SIP/OakM3K- 00000005”, “DEXTEN=1002”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:2] Set(“SIP/OakM3K- 00000005”, “DIALSTATUS_CW=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:3] GosubIf(“SIP/Oak M3K-00000005”, “0?screen,1()”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:4] GosubIf(“SIP/Oak M3K-00000005”, “0?cf,1()”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:5] GotoIf(“SIP/OakM 3K-00000005”, “1?skip1”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-dial-one,s,8)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:8] GotoIf(“SIP/OakM 3K-00000005”, “0?nodial”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:9] GotoIf(“SIP/OakM 3K-00000005”, “0?continue”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:10] Set(“SIP/OakM3K -00000005”, “EXTHASCW=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:11] GotoIf(“SIP/Oak M3K-00000005”, “1?next1:cwinusebusy”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-dial-one,s,12)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:12] GotoIf(“SIP/Oak M3K-00000005”, “0?docfu:skip3”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-dial-one,s,16)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:16] GotoIf(“SIP/Oak M3K-00000005”, “1?next2:continue”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-dial-one,s,17)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:17] GotoIf(“SIP/Oak M3K-00000005”, “1?continue”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-dial-one,s,25)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:25] GotoIf(“SIP/Oak M3K-00000005”, “0?nodial”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:26] GosubIf(“SIP/Oa kM3K-00000005”, “1?dstring,1():dlocal,1()”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [dstring@macro-dial-one:1] Set(“SIP/O akM3K-00000005”, “DSTRING=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [dstring@macro-dial-one:2] Set(“SIP/O akM3K-00000005”, “DEVICES=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [dstring@macro-dial-one:3] ExecIf(“SI P/OakM3K-00000005”, “1?Return()”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:27] GotoIf(“SIP/Oak M3K-00000005”, “1?nodial”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-dial-one,s,46)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:46] ExecIf(“SIP/Oak M3K-00000005”, “1?Set(DIALSTATUS=NOANSWER)”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:47] NoOp(“SIP/OakM3 K-00000005”, “Returned from dial-one with nothing to call and DIALSTATUS: NOANSWER”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-dial-one:48] MacroExit(“SIP/ OakM3K-00000005”, “”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:15] Set(“SIP/OakM3K -00000005”, “SV_DIALSTATUS=NOANSWER”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:16] GosubIf(“SIP/Oa kM3K-00000005”, “0?docfu,1()”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:17] GosubIf(“SIP/Oa kM3K-00000005”, “0?docfb,1()”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:18] Set(“SIP/OakM3K -00000005”, “DIALSTATUS=NOANSWER”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:19] ExecIf(“SIP/Oak M3K-00000005”, “0?MacroExit()”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:20] GotoIf(“SIP/Oak M3K-00000005”, “0?s-NOANSWER,1”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-exten-vm:21] Macro(“SIP/OakM 3K-00000005”, “vm,1002,NOANSWER,”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-vm:1] Macro(“SIP/OakM3K-0000 0005”, “user-callerid,SKIPTTL”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/Oa kM3K-00000005”, “AMPUSER=2142283499”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP /OakM3K-00000005”, “0?report”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP /OakM3K-00000005”, “0?Set(REALCALLERIDNUM=2142283499)”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/Oa kM3K-00000005”, “AMPUSER=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/Oa kM3K-00000005”, “AMPUSERCIDNAME=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP /OakM3K-00000005”, “1?report”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-user-callerid,s,13)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:13] GotoIf(“SI P/OakM3K-00000005”, “1?continue”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-user-callerid,s,26)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:26] Set(“SIP/O akM3K-00000005”, “CALLERID(number)=2142283499”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:27] Set(“SIP/O akM3K-00000005”, “CALLERID(name)=2142283499”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-user-callerid:28] Set(“SIP/O akM3K-00000005”, “CHANNEL(language)=en”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-vm:2] Set(“SIP/OakM3K-000000 05”, “VMGAIN=”“”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-vm:3] Macro(“SIP/OakM3K-0000 0005”, “blkvm-check,”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-blkvm-check:1] Set(“SIP/OakM 3K-00000005”, “GOSUB_RETVAL=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-blkvm-check:2] ExecIf(“SIP/O akM3K-00000005”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-blkvm-check:3] MacroExit(“SI P/OakM3K-00000005”, “”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-vm:4] GotoIf(“SIP/OakM3K-000 00005”, “1?vmx,1”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-vm,vmx,1)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [vmx@macro-vm:1] Set(“SIP/OakM3K-0000 0005”, “MEXTEN=1002”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [vmx@macro-vm:2] Set(“SIP/OakM3K-0000 0005”, “MMODE=NOANSWER”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [vmx@macro-vm:3] Set(“SIP/OakM3K-0000 0005”, “RETVM=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [vmx@macro-vm:4] Set(“SIP/OakM3K-0000 0005”, “MODE=unavail”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [vmx@macro-vm:5] GotoIf(“SIP/OakM3K-0 0000005”, “1?chknomsg”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-vm,vmx,8)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [vmx@macro-vm:8] GotoIf(“SIP/OakM3K-0 0000005”, “0?s-NOANSWER,1”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [vmx@macro-vm:9] GotoIf(“SIP/OakM3K-0 0000005”, “1?notdirect”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-vm,vmx,11)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [vmx@macro-vm:11] NoOp(“SIP/OakM3K-00 000005”, “Checking if ext 1002 is enabled: “) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [vmx@macro-vm:12] GotoIf(“SIP/OakM3K- 00000005”, “1?s-NOANSWER,1”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-vm,s-NOANSWER,1)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s-NOANSWER@macro-vm:1] Macro(“SIP/Oa kM3K-00000005”, “get-vmcontext,1002”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-get-vmcontext:1] Set(“SIP/Oa kM3K-00000005”, “VMCONTEXT=”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-get-vmcontext:2] GotoIf(“SIP /OakM3K-00000005”, “1?200:300”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-get-vmcontext,s,200)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s@macro-get-vmcontext:200] Set(“SIP/ OakM3K-00000005”, “VMCONTEXT=default”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s-NOANSWER@macro-vm:2] VoiceMail(“SI P/OakM3K-00000005”, “1002@default,u”””) in new stack
[2013-03-22 05:08:11] WARNING[25980] app_voicemail.c: No entry in voicemail config file for ‘1002’
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [s-NOANSWER@macro-vm:3] Goto(“SIP/Oak M3K-00000005”, “exit-FAILED,1”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Goto (macro-vm,exit-FAILED,1)
[2013-03-22 05:08:11] VERBOSE[25980] pbx.c: – Executing [exit-FAILED@macro-vm:1] Playback(“SI P/OakM3K-00000005”, “im-sorry&an-error-has-occured”) in new stack
[2013-03-22 05:08:11] VERBOSE[25980] file.c: – Playing ‘im-sorry.ulaw’ (language ‘en’)
[2013-03-22 05:08:12] VERBOSE[25980] file.c: – Playing ‘an-error-has-oc cured.ulaw’ (language ‘en’)

2

Could you share with us the dialplan code for extension 2@ivr that FreePBX generated?

Also, please don’t double-post (03/22/2013 - Topic “ast_expr2.fl: ast_yyerror()”.
If no one answers, it usually means no one understood the question or knows the answer.

3

Would that be located in extensions_additional.conf?

4

Yes, that’s where it should be by default.

5

[ivr-4] ; Main
include => ivr-4-custom
exten => s,1,Set(TIMEOUT_LOOPCOUNT=0)
exten => s,n,Set(INVALID_LOOPCOUNT=0)
exten => s,n,Set(IVR_CONTEXT${CONTEXT}=${IVR_CONTEXT})
exten => s,n,Set(_IVR_CONTEXT=${CONTEXT})
exten => s,n,Set(__IVR_RETVM=)
exten => s,n,GotoIf($["${CDR(disposition)}" = “ANSWERED”]?skip)
exten => s,n,Answer
exten => s,n,Wait(1)
exten => s,n(skip),Set(IVR_MSG=en/hello-world)
exten => s,n(start),Set(TIMEOUT(digit)=3)
exten => s,n,ExecIf($["${IVR_MSG}" != “”]?Background(${IVR_MSG}))
exten => s,n,WaitExten(10,)

exten => 0,1,Goto(from-did-direct,1000,1)

exten => 1,1,Goto(from-did-direct,1001,1)

exten => 2,1,Goto(from-did-direct,1002,1)

exten => i,1,Set(INVALID_LOOPCOUNT=$[${INVALID_LOOPCOUNT}+1])
exten => i,n,GotoIf($[${INVALID_LOOPCOUNT} > 3]?final)
exten => i,n,Set(IVR_MSG=no-valid-responce-pls-try-again)
exten => i,n,Goto(s,start)
exten => i,n(final),Playback(no-valid-responce-transfering)
exten => i,n,Goto(from-did-direct,1002,1)

exten => t,1,Set(TIMEOUT_LOOPCOUNT=$[${TIMEOUT_LOOPCOUNT}+1])
exten => t,n,GotoIf($[${TIMEOUT_LOOPCOUNT} > 3]?final)
exten => t,n,Set(IVR_MSG=no-valid-responce-pls-try-again)
exten => t,n,Goto(s,start)
exten => t,n(final),Playback(no-valid-responce-transfering)
exten => t,n,Goto(from-did-direct,1002,1)

exten => return,1,Set(IVR_CONTEXT=${CONTEXT})
exten => return,n,Set(IVR_CONTEXT${CONTEXT}=${IVR_CONTEXT${CONTEXT}})
exten => return,n,Set(IVR_MSG=en/hello-world)
exten => return,n,Goto(s,start)

exten => h,1,Hangup

exten => hang,1,Playback(vm-goodbye)
exten => hang,n,Hangup

;–== end of [ivr-4] ==–;

6

[from-did-direct-ivr]
include => from-did-direct-ivr-custom
exten => 1000,1,Macro(blkvm-clr,)
exten => 1000,n,Set(__NODEST=)
exten => 1000,n,Goto(from-did-direct,1000,1)

exten => ${VM_PREFIX}1000,1,Macro(blkvm-clr,)
exten => ${VM_PREFIX}1000,n,Set(__NODEST=)
exten => ${VM_PREFIX}1000,n,Macro(vm,1000,DIRECTDIAL,${IVR_RETVM})
exten => ${VM_PREFIX}1000,n,GotoIf($["${IVR_RETVM}" = “RETURN” & “${IVR_CONTEXT}” != “”]?ext-local,vmret,playret)

exten => 1001,1,Macro(blkvm-clr,)
exten => 1001,n,Set(__NODEST=)
exten => 1001,n,Goto(from-did-direct,1001,1)

exten => ${VM_PREFIX}1001,1,Macro(blkvm-clr,)
exten => ${VM_PREFIX}1001,n,Set(__NODEST=)
exten => ${VM_PREFIX}1001,n,Macro(vm,1001,DIRECTDIAL,${IVR_RETVM})
exten => ${VM_PREFIX}1001,n,GotoIf($["${IVR_RETVM}" = “RETURN” & “${IVR_CONTEXT}” != “”]?ext-local,vmret,playret)

exten => 1002,1,Macro(blkvm-clr,)
exten => 1002,n,Set(__NODEST=)
exten => 1002,n,Goto(from-did-direct,1002,1)

exten => ${VM_PREFIX}1002,1,Macro(blkvm-clr,)
exten => ${VM_PREFIX}1002,n,Set(__NODEST=)
exten => ${VM_PREFIX}1002,n,Macro(vm,1002,DIRECTDIAL,${IVR_RETVM})
exten => ${VM_PREFIX}1002,n,GotoIf($["${IVR_RETVM}" = “RETURN” & “${IVR_CONTEXT}” != “”]?ext-local,vmret,playret)

7

You go to from-did-direct, but the second context you posted is from-did-direct-ivr. Shouldn’t it go into the from-did-direct context? Could you post that?
It appears as though this problem may occur in the Macro call to blkvm-clr. This sounds like maybe you’re running into an issue I’ve ran into with something else.

Could you check this ticket?
http://www.freepbx.org/trac/ticket/6273

I posted this a while ago (I thought it was auto-confirm, but dpilon corrected me and said it was auto-blkvm).

Maybe you could try my fix if you think your problem may be related. Or maybe blkvm-clr generates similar code elsewhere in functions.inc.

Unfortunately, it hasn’t been integrated yet because it seems like no one can replicate it so far :[

8

I just searched through all of extensions_additional.conf, there is no from-did-direct context. Could it be in some other location?

9

;*******************************************************************************
; INTERNAL MACROS & SPECIAL CONTEXTS *
;*******************************************************************************
;

;-------------------------------------------------------------------------------
; from-did-direct:
;
; forces ext-findmefollow to take precedence over ext-local. Also exposed to
; the public side to allow an extension number to be used as an external DID
; without requiring inbound routes to be created, common in many PRI installations
; where the last 4 digits are used as the extnension and DIDs are delivered in
; 4 digit formats.
;
[from-did-direct]
include => ext-findmefollow
include => ext-local
;-------------------------------------------------------------------------------

10

that I’m getting this on extension to extension calls as well.

[2013-03-28 10:18:17] VERBOSE[3670] netsock2.c: == Using SIP VIDEO TOS bits 136
[2013-03-28 10:18:17] VERBOSE[3670] netsock2.c: == Using SIP VIDEO CoS mark 6
[2013-03-28 10:18:17] VERBOSE[3670] netsock2.c: == Using SIP RTP TOS bits 184
[2013-03-28 10:18:17] VERBOSE[3670] netsock2.c: == Using SIP RTP CoS mark 5
[2013-03-28 10:18:17] WARNING[8435] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected ‘>’, expecting $end; Input:

0
^
[2013-03-28 10:18:17] WARNING[8435] ast_expr2.fl: If you have questions, please refer to https://wiki.asterisk.org/wiki/display/AST/Channel+Variables
[2013-03-28 10:18:17] WARNING[8435] func_logic.c: Syntax IF(?[][:]) (expr must be non-null, and either or must be non-null)
[2013-03-28 10:18:17] WARNING[8435] func_logic.c: In this case, =‘’, =‘’, and =‘15’
[2013-03-28 10:18:17] VERBOSE[8435] pbx.c: – Executing [1003@from-internal:1] Set(“SIP/1000-00000009”, “__RINGTIMER=”) in new stack
[2013-03-28 10:18:17] VERBOSE[8435] pbx.c: – Executing [1003@from-internal:2] Macro(“SIP/1000-00000009”, “exten-vm,1003,1003,0,0,0”) in new stack
[2013-03-28 10:18:17] VERBOSE[8435] pbx.c: – Executing [s@macro-exten-vm:1] Macro(“SIP/1000-00000009”, “user-callerid,”) in new stack
[2013-03-28 10:18:17] VERBOSE[8435] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/1000-00000009”, “AMPUSER=1000”) in new stack
[2013-03-28 10:18:17] VERBOSE[8435] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/1000-00000009”, “0?report”) in new stack
[2013-03-28 10:18:17] VERBOSE[8435] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/1000-00000009”, “1?Set(REALCALLERIDNUM=1000)”) in new stack
[2013-03-28 10:18:17] VERBOSE[8435] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/1000-00000009”, “AMPUSER=”) in new stack
[2013-03-28 10:18:17] VERBOSE[8435] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/1000-00000009”, “AMPUSERCIDNAME=”) in new stack
[2013-03-28 10:18:17] VERBOSE[8435] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/1000-00000009”, “1?report”) in new stack

11

Ok, so the from-did-direct-ivr context doesn’t seem to be used at all (unless it’s somehow included ext-findmefollow or ext-local.
I’m a little out of ideas right now, maybe dicko or tm100 or someone who’s really in-depth on FreePBX would know where to look right away…

Have you looked at the ticket?
Does the extension you’re transferring to ring and the call gets dropped when the extension picks up?

12

For ext to ext the call never makes it to the terminating ext. The syntax error occurs just as in the IVR to ext scenario.

I have looked at the ticket and will try modifying the syntax for the dial plan. Should I try this under the [macro-auto-confirm] context or some other context? I’m not 100% sure where to make the modifications.

13

There is nothing wrong and no code needs to be fixed. Either the mailbox of that extension is messed up in /etc/asterisk/voicemail.conf or you have permissions/ sym link errors in your voicemail.

14

Basically it can’t find 1002…so it goes to voicemail and… like SkykingOH says…you do not have voicemail configured, etc.

15

I knew it was time for the super experts to march in on this one and see the problem right away :]
Let me ask though, should having no VM configured result in a syntax error?

16

What are you calling a syntax error? Maybe I am missing that. It is not part of your problem though.

I was kinda surprised that someone was digging through code and telling you to modify it.

You have to consider who you are getting advice from, sometimes it is not all good.

Glad you got it fixed.

17

SkykingOH,

I don’t think his problem is fixed.

cveazey…
But you should show a call coming in and also show that the actual peer(phone/extension) is actually online at the same time). Maybe pastebin the entire output. (http://pastebin.com/)

18

I was talking about this here:

[2013-03-28 10:18:17] WARNING[8435] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected '>', expecting $end; Input: > 0 ^

Is this error always generated when VM is disabled as in the OP’s case?

19

I wasn’t trying to hijack this post, but since blkvm-clr was involved it seemed relevant.

20

Call hits IVR then choose Opt3(Should xfer to 1003). 1003 has a peer entry. Call fails with same syntax/VM error etc as in previous post. Call log from
/var/log/asterisk/full is located at http://pastebin.com/0PLBEvJq

Name/username Host Dyn Forcerport ACL Port Status Description
1000/1000 192.168.12.5 D A 5060 OK (241 ms)
1001/1001 (Unspecified) D A 0 UNKNOWN
1002/1002 (Unspecified) D A 0 UNKNOWN
1003/1003 192.168.12.6 D A 5060 OK (243 ms)
1004 (Unspecified) D A 0 UNKNOWN
OakM3K 172.27.50.10 N 5060 Unmonitored
SIPServer_172_25_1_186 172.25.1.186 N 5060 Unmonitored
7 sip peers [Monitored: 2 online, 3 offline Unmonitored: 2 online, 0 offline]