So called is not delivered to the endpoint 1001 eventhough the device is registered. It looks like this tries to roll to voicemail which results in playing the prompts:
[2013-03-22 11:37:55] VERBOSE[24705] file.c: – <SIP/OakM3K-00000008> Playing ‘im-sorry.ulaw’ (language ‘en’)
[2013-03-22 11:37:56] VERBOSE[24705] file.c: – <SIP/OakM3K-00000008> Playing ‘an-error-has-occured.ulaw’ (language ‘en’)
[2013-03-22 11:37:51] VERBOSE[27050] netsock2.c: == Using SIP VIDEO TOS bits 136
[2013-03-22 11:37:51] VERBOSE[27050] netsock2.c: == Using SIP VIDEO CoS mark 6
[2013-03-22 11:37:51] VERBOSE[27050] netsock2.c: == Using SIP RTP TOS bits 184
[2013-03-22 11:37:51] VERBOSE[27050] netsock2.c: == Using SIP RTP CoS mark 5
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [8773554816@from-trunk:1] Set(“SIP/OakM3K-00000008”, “__FROM_DID=8773554816”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [8773554816@from-trunk:2] Gosub(“SIP/OakM3K-00000008”, “app-blacklist-check,s,1()”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [s@app-blacklist-check:1] GotoIf(“SIP/OakM3K-00000008”, “0?blacklisted”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [s@app-blacklist-check:2] Set(“SIP/OakM3K-00000008”, “CALLED_BLACKLIST=1”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [s@app-blacklist-check:3] Return(“SIP/OakM3K-00000008”, “”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [8773554816@from-trunk:3] Set(“SIP/OakM3K-00000008”, “CDR(did)=8773554816”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [8773554816@from-trunk:4] ExecIf(“SIP/OakM3K-00000008”, “1 ?Set(CALLERID(name)=2142283499)”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [8773554816@from-trunk:5] Set(“SIP/OakM3K-00000008”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [8773554816@from-trunk:6] Set(“SIP/OakM3K-00000008”, “CALLERPRES()=allowed_not_screened”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [8773554816@from-trunk:7] Goto(“SIP/OakM3K-00000008”, “ivr-2,s,1”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Goto (ivr-2,s,1)
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [s@ivr-2:1] Set(“SIP/OakM3K-00000008”, “TIMEOUT_LOOPCOUNT=0”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [s@ivr-2:2] Set(“SIP/OakM3K-00000008”, “INVALID_LOOPCOUNT=0”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [s@ivr-2:3] Set(“SIP/OakM3K-00000008”, “_IVR_CONTEXT_ivr-2=”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [s@ivr-2:4] Set(“SIP/OakM3K-00000008”, “_IVR_CONTEXT=ivr-2”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [s@ivr-2:5] Set(“SIP/OakM3K-00000008”, “__IVR_RETVM=”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [s@ivr-2:6] GotoIf(“SIP/OakM3K-00000008”, “0?skip”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [s@ivr-2:7] Answer(“SIP/OakM3K-00000008”, “”) in new stack
[2013-03-22 11:37:51] VERBOSE[24705] pbx.c: – Executing [s@ivr-2:8] Wait(“SIP/OakM3K-00000008”, “1”) in new stack
[2013-03-22 11:37:52] VERBOSE[24705] pbx.c: – Executing [s@ivr-2:9] Set(“SIP/OakM3K-00000008”, “IVR_MSG=en/hello-world”) in new stack
[2013-03-22 11:37:52] VERBOSE[24705] pbx.c: – Executing [s@ivr-2:10] Set(“SIP/OakM3K-00000008”, “TIMEOUT(digit)=3”) in new stack
[2013-03-22 11:37:52] VERBOSE[24705] func_timeout.c: – Digit timeout set to 3.000
[2013-03-22 11:37:52] VERBOSE[24705] pbx.c: – Executing [s@ivr-2:11] ExecIf(“SIP/OakM3K-00000008”, “1?Background(en/hello-world)”) in new stack
[2013-03-22 11:37:52] VERBOSE[24705] file.c: – <SIP/OakM3K-00000008> Playing ‘en/hello-world.ulaw’ (language ‘en’)
[2013-03-22 11:37:54] VERBOSE[24705] pbx.c: – Executing [s@ivr-2:12] WaitExten(“SIP/OakM3K-00000008”, “10,”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: == CDR updated on SIP/OakM3K-00000008
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [1@ivr-2:1] Goto(“SIP/OakM3K-00000008”, “from-did-direct,1001,1”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (from-did-direct,1001,1)
[2013-03-22 11:37:55] WARNING[24705] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected ‘>’, expecting $end; Input:
0
^
[2013-03-22 11:37:55] WARNING[24705] ast_expr2.fl: If you have questions, please refer to wiki.asterisk.org/wiki/display/ … +Variables
[2013-03-22 11:37:55] WARNING[24705] func_logic.c: Syntax IF(?[][:]) (expr must be non-null, and either or must be non-null)
[2013-03-22 11:37:55] WARNING[24705] func_logic.c: In this case, =‘’, =‘’, and =‘15’
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [1001@from-did-direct:1] Set(“SIP/OakM3K-00000008”, “__RINGTIMER=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [1001@from-did-direct:2] Macro(“SIP/OakM3K-00000008”, “exten-vm,1001,1001,0,0,0”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:1] Macro(“SIP/OakM3K-00000008”, “user-callerid,”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/OakM3K-00000008”, “AMPUSER=2142283499”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/OakM3K-00000008”, “0?report”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/OakM3K-00000008”, “1?Set(REALCALLERIDNUM=2142283499)”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/OakM3K-00000008”, “AMPUSER=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/OakM3K-00000008”, “AMPUSERCIDNAME=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/OakM3K-00000008”, “1?report”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-user-callerid,s,13)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:13] GotoIf(“SIP/OakM3K-00000008”, “0?continue”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:14] Set(“SIP/OakM3K-00000008”, “__TTL=64”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:15] GotoIf(“SIP/OakM3K-00000008”, “1?continue”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-user-callerid,s,26)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:26] Set(“SIP/OakM3K-00000008”, “CALLERID(number)=2142283499”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:27] Set(“SIP/OakM3K-00000008”, “CALLERID(name)=2142283499”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:28] Set(“SIP/OakM3K-00000008”, “CHANNEL(language)=en”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:2] Set(“SIP/OakM3K-00000008”, “RingGroupMethod=none”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:3] Set(“SIP/OakM3K-00000008”, “__EXTTOCALL=1001”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:4] Set(“SIP/OakM3K-00000008”, “__PICKUPMARK=1001”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:5] Set(“SIP/OakM3K-00000008”, “RT=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:6] Gosub(“SIP/OakM3K-00000008”, “sub-record-check,s,1(exten,1001,)”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“SIP/OakM3K-00000008”, “1?check”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (sub-record-check,s,6)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:6] Set(“SIP/OakM3K-00000008”, “__MON_FMT=wav”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:7] GotoIf(“SIP/OakM3K-00000008”, “1?next”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (sub-record-check,s,10)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:10] ExecIf(“SIP/OakM3K-00000008”, “0?Return()”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:11] GotoIf(“SIP/OakM3K-00000008”, “0?exten,1”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:12] Set(“SIP/OakM3K-00000008”, “__REC_STATUS=INITIALIZED”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:13] ExecIf(“SIP/OakM3K-00000008”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:14] Set(“SIP/OakM3K-00000008”, “NOW=1363970275”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:15] Set(“SIP/OakM3K-00000008”, “__DAY=22”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:16] Set(“SIP/OakM3K-00000008”, “__MONTH=03”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:17] Set(“SIP/OakM3K-00000008”, “__YEAR=2013”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:18] Set(“SIP/OakM3K-00000008”, “__TIMESTR=20130322-113755”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:19] Set(“SIP/OakM3K-00000008”, “__FROMEXTEN=2142283499”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:20] Set(“SIP/OakM3K-00000008”, “__CALLFILENAME=exten-1001-2142283499-20130322-113755-1363970271.8”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@sub-record-check:21] Goto(“SIP/OakM3K-00000008”, “exten,1”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (sub-record-check,exten,1)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [exten@sub-record-check:1] GotoIf(“SIP/OakM3K-00000008”, “0?callee”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [exten@sub-record-check:2] Set(“SIP/OakM3K-00000008”, “__REC_POLICY_MODE=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [exten@sub-record-check:3] GotoIf(“SIP/OakM3K-00000008”, “0?caller”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [exten@sub-record-check:4] GotoIf(“SIP/OakM3K-00000008”, “1?callee”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (sub-record-check,exten,8)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [exten@sub-record-check:8] GosubIf(“SIP/OakM3K-00000008”, “0?record,1(exten,1001,2142283499)”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [exten@sub-record-check:9] Return(“SIP/OakM3K-00000008”, “”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:7] GotoIf(“SIP/OakM3K-00000008”, “1?macrodial”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-exten-vm,s,13)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:13] GosubIf(“SIP/OakM3K-00000008”, “0?clrheader,1()”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:14] Macro(“SIP/OakM3K-00000008”, “dial-one,tr,1001”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:1] Set(“SIP/OakM3K-00000008”, “DEXTEN=1001”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:2] Set(“SIP/OakM3K-00000008”, “DIALSTATUS_CW=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:3] GosubIf(“SIP/OakM3K-00000008”, “0?screen,1()”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:4] GosubIf(“SIP/OakM3K-00000008”, “0?cf,1()”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:5] GotoIf(“SIP/OakM3K-00000008”, “1?skip1”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-dial-one,s,8)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:8] GotoIf(“SIP/OakM3K-00000008”, “0?nodial”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:9] GotoIf(“SIP/OakM3K-00000008”, “0?continue”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:10] Set(“SIP/OakM3K-00000008”, “EXTHASCW=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:11] GotoIf(“SIP/OakM3K-00000008”, “1?next1:cwinusebusy”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-dial-one,s,12)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:12] GotoIf(“SIP/OakM3K-00000008”, “0?docfu:skip3”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-dial-one,s,16)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:16] GotoIf(“SIP/OakM3K-00000008”, “1?next2:continue”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-dial-one,s,17)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:17] GotoIf(“SIP/OakM3K-00000008”, “1?continue”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-dial-one,s,25)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:25] GotoIf(“SIP/OakM3K-00000008”, “0?nodial”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:26] GosubIf(“SIP/OakM3K-00000008”, “1?dstring,1():dlocal,1()”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [dstring@macro-dial-one:1] Set(“SIP/OakM3K-00000008”, “DSTRING=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [dstring@macro-dial-one:2] Set(“SIP/OakM3K-00000008”, “DEVICES=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [dstring@macro-dial-one:3] ExecIf(“SIP/OakM3K-00000008”, “1?Return()”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:27] GotoIf(“SIP/OakM3K-00000008”, “1?nodial”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-dial-one,s,46)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:46] ExecIf(“SIP/OakM3K-00000008”, “1?Set(DIALSTATUS=NOANSWER)”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:47] NoOp(“SIP/OakM3K-00000008”, “Returned from dial-one with nothing to call and DIALSTATUS: NOANSWER”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-dial-one:48] MacroExit(“SIP/OakM3K-00000008”, “”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:15] Set(“SIP/OakM3K-00000008”, “SV_DIALSTATUS=NOANSWER”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:16] GosubIf(“SIP/OakM3K-00000008”, “0?docfu,1()”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:17] GosubIf(“SIP/OakM3K-00000008”, “0?docfb,1()”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:18] Set(“SIP/OakM3K-00000008”, “DIALSTATUS=NOANSWER”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:19] ExecIf(“SIP/OakM3K-00000008”, “0?MacroExit()”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:20] GotoIf(“SIP/OakM3K-00000008”, “0?s-NOANSWER,1”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-exten-vm:21] Macro(“SIP/OakM3K-00000008”, “vm,1001,NOANSWER,”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-vm:1] Macro(“SIP/OakM3K-00000008”, “user-callerid,SKIPTTL”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/OakM3K-00000008”, “AMPUSER=2142283499”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/OakM3K-00000008”, “0?report”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/OakM3K-00000008”, “0?Set(REALCALLERIDNUM=2142283499)”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/OakM3K-00000008”, “AMPUSER=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/OakM3K-00000008”, “AMPUSERCIDNAME=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/OakM3K-00000008”, “1?report”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-user-callerid,s,13)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:13] GotoIf(“SIP/OakM3K-00000008”, “1?continue”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-user-callerid,s,26)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:26] Set(“SIP/OakM3K-00000008”, “CALLERID(number)=2142283499”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:27] Set(“SIP/OakM3K-00000008”, “CALLERID(name)=2142283499”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-user-callerid:28] Set(“SIP/OakM3K-00000008”, “CHANNEL(language)=en”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-vm:2] Set(“SIP/OakM3K-00000008”, “VMGAIN=”“”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-vm:3] Macro(“SIP/OakM3K-00000008”, “blkvm-check,”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-blkvm-check:1] Set(“SIP/OakM3K-00000008”, “GOSUB_RETVAL=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-blkvm-check:2] ExecIf(“SIP/OakM3K-00000008”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-blkvm-check:3] MacroExit(“SIP/OakM3K-00000008”, “”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-vm:4] GotoIf(“SIP/OakM3K-00000008”, “1?vmx,1”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-vm,vmx,1)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [vmx@macro-vm:1] Set(“SIP/OakM3K-00000008”, “MEXTEN=1001”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [vmx@macro-vm:2] Set(“SIP/OakM3K-00000008”, “MMODE=NOANSWER”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [vmx@macro-vm:3] Set(“SIP/OakM3K-00000008”, “RETVM=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [vmx@macro-vm:4] Set(“SIP/OakM3K-00000008”, “MODE=unavail”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [vmx@macro-vm:5] GotoIf(“SIP/OakM3K-00000008”, “1?chknomsg”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-vm,vmx,8)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [vmx@macro-vm:8] GotoIf(“SIP/OakM3K-00000008”, “0?s-NOANSWER,1”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [vmx@macro-vm:9] GotoIf(“SIP/OakM3K-00000008”, “1?notdirect”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-vm,vmx,11)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [vmx@macro-vm:11] NoOp(“SIP/OakM3K-00000008”, “Checking if ext 1001 is enabled: “) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [vmx@macro-vm:12] GotoIf(“SIP/OakM3K-00000008”, “1?s-NOANSWER,1”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-vm,s-NOANSWER,1)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s-NOANSWER@macro-vm:1] Macro(“SIP/OakM3K-00000008”, “get-vmcontext,1001”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-get-vmcontext:1] Set(“SIP/OakM3K-00000008”, “VMCONTEXT=”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/OakM3K-00000008”, “1?200:300”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-get-vmcontext,s,200)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s@macro-get-vmcontext:200] Set(“SIP/OakM3K-00000008”, “VMCONTEXT=default”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s-NOANSWER@macro-vm:2] VoiceMail(“SIP/OakM3K-00000008”, “1001@default,u”””) in new stack
[2013-03-22 11:37:55] WARNING[24705] app_voicemail.c: No entry in voicemail config file for ‘1001’
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [s-NOANSWER@macro-vm:3] Goto(“SIP/OakM3K-00000008”, “exit-FAILED,1”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Goto (macro-vm,exit-FAILED,1)
[2013-03-22 11:37:55] VERBOSE[24705] pbx.c: – Executing [exit-FAILED@macro-vm:1] Playback(“SIP/OakM3K-00000008”, “im-sorry&an-error-has-occured”) in new stack
[2013-03-22 11:37:55] VERBOSE[24705] file.c: – <SIP/OakM3K-00000008> Playing ‘im-sorry.ulaw’ (language ‘en’)
[2013-03-22 11:37:56] VERBOSE[24705] file.c: – <SIP/OakM3K-00000008> Playing ‘an-error-has-occured.ulaw’ (language ‘en’)
[2013-03-22 11:37:58] VERBOSE[24705] pbx.c: – Executing [exit-FAILED@macro-vm:2] GotoIf(“SIP/OakM3K-00000008”, “0?exit-RETURN,1”) in new stack
[2013-03-22 11:37:58] VERBOSE[24705] pbx.c: – Executing [exit-FAILED@macro-vm:3] Hangup(“SIP/OakM3K-00000008”, “”) in new stack
[2013-03-22 11:37:58] VERBOSE[24705] app_macro.c: == Spawn extension (macro-vm, exit-FAILED, 3) exited non-zero on ‘SIP/OakM3K-00000008’ in macro ‘vm’
[2013-03-22 11:37:58] VERBOSE[24705] app_macro.c: == Spawn extension (macro-exten-vm, s, 21) exited non-zero on ‘SIP/OakM3K-00000008’ in macro ‘exten-vm’
[2013-03-22 11:37:58] VERBOSE[24705] pbx.c: == Spawn extension (from-did-direct, 1001, 2) exited non-zero on ‘SIP/OakM3K-00000008’
[2013-03-22 11:37:58] VERBOSE[24705] pbx.c: – Executing [h@from-did-direct:1] Macro(“SIP/OakM3K-00000008”, “hangupcall,”) in new stack
[2013-03-22 11:37:58] VERBOSE[24705] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/OakM3K-00000008”, “1?theend”) in new stack
[2013-03-22 11:37:58] VERBOSE[24705] pbx.c: – Goto (macro-hangupcall,s,3)
[2013-03-22 11:37:58] VERBOSE[24705] pbx.c: – Executing [s@macro-hangupcall:3] ExecIf(“SIP/OakM3K-00000008”, “0?Set(CDR(recordingfile)=)”) in new stack
[2013-03-22 11:37:58] VERBOSE[24705] pbx.c: – Executing [s@macro-hangupcall:4] Hangup(“SIP/OakM3K-00000008”, “”) in new stack
[2013-03-22 11:37:58] VERBOSE[24705] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/OakM3K-00000008’ in macro ‘hangupcall’
[2013-03-22 11:37:58] VERBOSE[24705] pbx.c: == Spawn extension (from-did-direct, h, 1) exited non-zero on ‘SIP/OakM3K-00000008’