Trouble with Asterisk on incoming

Hi everyone…
I have a problem with Asterisk 11 on Centos 5.9 and I have perused the documentation but have not found anything pertaining to this issue:

I am able to call out through my voip provider, also able to receive calls just fine, calling from one extension to another is fine. The trouble is that if the line rings then goes to NA voicemail, the voicemail and/or IVR voice is heard about 1 in five calls, usually just silence (CLI says it is delivering the messages to the caller but nothing is heard at callers end) until the caller hangs up, then the VM closes the message with the MWI active but upon retrieving it it is 0 secs long - nothing recorded. Here is a snap of the CLI print out:Connected to Asterisk 11.13.0 currently running on Matarazzo (pid = 2660)
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [555*******@from-trunk-sip-VoipVoip:1] Set(“SIP/VoipVoip-00000000”, “GROUP()=OUT_1”) in new stack
– Executing [555*******@from-trunk-sip-VoipVoip:2] Goto(“SIP/VoipVoip-00000000”, “from-trunk,555*******,1”) in new stack
– Goto (from-trunk,555*******,1)
– Executing [555*******@from-trunk:1] Set(“SIP/VoipVoip-00000000”, “__FROM_DID=555*******”) in new stack
– Executing [555*******@from-trunk:2] Gosub(“SIP/VoipVoip-00000000”, “app-blacklist-check,s,1()”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“SIP/VoipVoip-00000000”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Set(“SIP/VoipVoip-00000000”, “CALLED_BLACKLIST=1”) in new stack
– Executing [s@app-blacklist-check:3] Return(“SIP/VoipVoip-00000000”, “”) in new stack
– Executing [555*******@from-trunk:3] Set(“SIP/VoipVoip-00000000”, “CDR(did)=555*******”) in new stack
– Executing [555*******@from-trunk:4] ExecIf(“SIP/VoipVoip-00000000”, “1 ?Set(CALLERID(name)=+16625******)”) in new stack
– Executing [555*******@from-trunk:5] Set(“SIP/VoipVoip-00000000”, “CHANNEL(musicclass)=default”) in new stack
– Executing [555*******@from-trunk:6] Set(“SIP/VoipVoip-00000000”, “__MOHCLASS=default”) in new stack
– Executing [555*******@from-trunk:7] Wait(“SIP/VoipVoip-00000000”, “5”) in new stack
[2015-02-16 09:27:46] WARNING[2934][C-00000000]: func_callerid.c:910 callerpres_read: CALLERPRES is deprecated. Use CALLERID(name-pres) or CALLERID(num-pres) instead.
– Executing [555*******@from-trunk:8] Set(“SIP/VoipVoip-00000000”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
– Executing [555*******@from-trunk:9] Set(“SIP/VoipVoip-00000000”, “CALLERPRES()=allowed_not_screened”) in new stack
– Executing [555*******@from-trunk:10] Goto(“SIP/VoipVoip-00000000”, “from-did-direct,1291,1”) in new stack
– Goto (from-did-direct,1291,1)
– Executing [1291@from-did-direct:1] Set(“SIP/VoipVoip-00000000”, “__RINGTIMER=15”) in new stack
– Executing [1291@from-did-direct:2] Macro(“SIP/VoipVoip-00000000”, “exten-vm,1291,1291,0,0,0”) in new stack
– Executing [s@macro-exten-vm:1] Macro(“SIP/VoipVoip-00000000”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/VoipVoip-00000000”, “TOUCH_MONITOR=1424096861.0”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/VoipVoip-00000000”, “AMPUSER=+16625******”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“SIP/VoipVoip-00000000”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“SIP/VoipVoip-00000000”, “1?Set(REALCALLERIDNUM=+16625******)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/VoipVoip-00000000”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/VoipVoip-00000000”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“SIP/VoipVoip-00000000”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“SIP/VoipVoip-00000000”, “1?report”) in new stack
– Goto (macro-user-callerid,s,15)
– Executing [s@macro-user-callerid:15] GotoIf(“SIP/VoipVoip-00000000”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:16] Set(“SIP/VoipVoip-00000000”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:17] GotoIf(“SIP/VoipVoip-00000000”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,28)
– Executing [s@macro-user-callerid:28] Set(“SIP/VoipVoip-00000000”, “CALLERID(number)=+16625******”) in new stack
– Executing [s@macro-user-callerid:29] Set(“SIP/VoipVoip-00000000”, “CALLERID(name)=+16625******”) in new stack
– Executing [s@macro-user-callerid:30] Set(“SIP/VoipVoip-00000000”, “CDR(cnum)=+16625******”) in new stack
– Executing [s@macro-user-callerid:31] Set(“SIP/VoipVoip-00000000”, “CDR(cnam)=+16625******”) in new stack
– Executing [s@macro-user-callerid:32] Set(“SIP/VoipVoip-00000000”, “CHANNEL(language)=en”) in new stack
– Executing [s@macro-exten-vm:2] Set(“SIP/VoipVoip-00000000”, “RingGroupMethod=none”) in new stack
– Executing [s@macro-exten-vm:3] Set(“SIP/VoipVoip-00000000”, “__EXTTOCALL=1291”) in new stack
– Executing [s@macro-exten-vm:4] Set(“SIP/VoipVoip-00000000”, “__PICKUPMARK=1291”) in new stack
– Executing [s@macro-exten-vm:5] Set(“SIP/VoipVoip-00000000”, “RT=15”) in new stack
– Executing [s@macro-exten-vm:6] Gosub(“SIP/VoipVoip-00000000”, “sub-record-check,s,1(exten,1291,)”) in new stack
– Executing [s@sub-record-check:1] Set(“SIP/VoipVoip-00000000”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:2] GotoIf(“SIP/VoipVoip-00000000”, “1?check”) in new stack
– Goto (sub-record-check,s,7)
– Executing [s@sub-record-check:7] Set(“SIP/VoipVoip-00000000”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:8] GotoIf(“SIP/VoipVoip-00000000”, “1?next”) in new stack
– Goto (sub-record-check,s,11)
– Executing [s@sub-record-check:11] ExecIf(“SIP/VoipVoip-00000000”, “0?Return()”) in new stack
– Executing [s@sub-record-check:12] ExecIf(“SIP/VoipVoip-00000000”, “0?Set(__REC_POLICY_MODE=)”) in new stack
– Executing [s@sub-record-check:13] GotoIf(“SIP/VoipVoip-00000000”, “0?exten,1”) in new stack
– Executing [s@sub-record-check:14] Set(“SIP/VoipVoip-00000000”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:15] Set(“SIP/VoipVoip-00000000”, “NOW=1424096866”) in new stack
– Executing [s@sub-record-check:16] Set(“SIP/VoipVoip-00000000”, “__DAY=16”) in new stack
– Executing [s@sub-record-check:17] Set(“SIP/VoipVoip-00000000”, “__MONTH=02”) in new stack
– Executing [s@sub-record-check:18] Set(“SIP/VoipVoip-00000000”, “__YEAR=2015”) in new stack
– Executing [s@sub-record-check:19] Set(“SIP/VoipVoip-00000000”, “__TIMESTR=20150216-092746”) in new stack
– Executing [s@sub-record-check:20] Set(“SIP/VoipVoip-00000000”, “__FROMEXTEN=+16625******”) in new stack
– Executing [s@sub-record-check:21] Set(“SIP/VoipVoip-00000000”, “__CALLFILENAME=exten-1291-+16625******-20150216-092746-1424096861.0”) in new stack
– Executing [s@sub-record-check:22] Goto(“SIP/VoipVoip-00000000”, “exten,1”) in new stack
– Goto (sub-record-check,exten,1)
– Executing [exten@sub-record-check:1] GotoIf(“SIP/VoipVoip-00000000”, “0?callee”) in new stack
– Executing [exten@sub-record-check:2] Set(“SIP/VoipVoip-00000000”, “__REC_POLICY_MODE=dontcare”) in new stack
– Executing [exten@sub-record-check:3] GotoIf(“SIP/VoipVoip-00000000”, “1?caller”) in new stack
– Goto (sub-record-check,exten,10)
– Executing [exten@sub-record-check:10] Set(“SIP/VoipVoip-00000000”, “__REC_POLICY_MODE=”) in new stack
– Executing [exten@sub-record-check:11] GosubIf(“SIP/VoipVoip-00000000”, “0?record,1(exten,1291,+16625******)”) in new stack
– Executing [exten@sub-record-check:12] Return(“SIP/VoipVoip-00000000”, “”) in new stack
– Executing [s@macro-exten-vm:7] GotoIf(“SIP/VoipVoip-00000000”, “1?macrodial”) in new stack
– Goto (macro-exten-vm,s,13)
– Executing [s@macro-exten-vm:13] GosubIf(“SIP/VoipVoip-00000000”, “0?clrheader,1()”) in new stack
– Executing [s@macro-exten-vm:14] Macro(“SIP/VoipVoip-00000000”, “dial-one,15,tr,1291”) in new stack
– Executing [s@macro-dial-one:1] Set(“SIP/VoipVoip-00000000”, “DEXTEN=1291”) in new stack
– Executing [s@macro-dial-one:2] Set(“SIP/VoipVoip-00000000”, “DIALSTATUS_CW=”) in new stack
– Executing [s@macro-dial-one:3] GosubIf(“SIP/VoipVoip-00000000”, “0?screen,1()”) in new stack
– Executing [s@macro-dial-one:4] GosubIf(“SIP/VoipVoip-00000000”, “0?cf,1()”) in new stack
– Executing [s@macro-dial-one:5] GotoIf(“SIP/VoipVoip-00000000”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,8)
– Executing [s@macro-dial-one:8] GotoIf(“SIP/VoipVoip-00000000”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:9] GotoIf(“SIP/VoipVoip-00000000”, “0?continue”) in new stack
– Executing [s@macro-dial-one:10] Set(“SIP/VoipVoip-00000000”, “EXTHASCW=”) in new stack
– Executing [s@macro-dial-one:11] GotoIf(“SIP/VoipVoip-00000000”, “1?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,12)
– Executing [s@macro-dial-one:12] GotoIf(“SIP/VoipVoip-00000000”, “0?docfu:skip3”) in new stack
– Goto (macro-dial-one,s,16)
– Executing [s@macro-dial-one:16] GotoIf(“SIP/VoipVoip-00000000”, “1?next2:continue”) in new stack
– Goto (macro-dial-one,s,17)
– Executing [s@macro-dial-one:17] GotoIf(“SIP/VoipVoip-00000000”, “1?continue”) in new stack
– Goto (macro-dial-one,s,25)
– Executing [s@macro-dial-one:25] GotoIf(“SIP/VoipVoip-00000000”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:26] GosubIf(“SIP/VoipVoip-00000000”, “1?dstring,1():dlocal,1()”) in new stack
– Executing [dstring@macro-dial-one:1] Set(“SIP/VoipVoip-00000000”, “DSTRING=”) in new stack
– Executing [dstring@macro-dial-one:2] Set(“SIP/VoipVoip-00000000”, “DEVICES=1291”) in new stack
– Executing [dstring@macro-dial-one:3] ExecIf(“SIP/VoipVoip-00000000”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:4] ExecIf(“SIP/VoipVoip-00000000”, “0?Set(DEVICES=291)”) in new stack
– Executing [dstring@macro-dial-one:5] Set(“SIP/VoipVoip-00000000”, “LOOPCNT=1”) in new stack
– Executing [dstring@macro-dial-one:6] Set(“SIP/VoipVoip-00000000”, “ITER=1”) in new stack
– Executing [dstring@macro-dial-one:7] Set(“SIP/VoipVoip-00000000”, “THISDIAL=SIP/1291”) in new stack
– Executing [dstring@macro-dial-one:8] GosubIf(“SIP/VoipVoip-00000000”, “1?zap2dahdi,1()”) in new stack
– Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/VoipVoip-00000000”, “0?Return()”) in new stack
– Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/VoipVoip-00000000”, “NEWDIAL=”) in new stack
– Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/VoipVoip-00000000”, “LOOPCNT2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/VoipVoip-00000000”, “ITER2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/VoipVoip-00000000”, “THISPART2=SIP/1291”) in new stack
– Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/VoipVoip-00000000”, “0?Set(THISPART2=DAHDI/1291)”) in new stack
– Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/VoipVoip-00000000”, “NEWDIAL=SIP/1291&”) in new stack
– Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/VoipVoip-00000000”, “ITER2=2”) in new stack
– Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/VoipVoip-00000000”, “0?begin2”) in new stack
– Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/VoipVoip-00000000”, “THISDIAL=SIP/1291”) in new stack
– Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/VoipVoip-00000000”, “”) in new stack
– Executing [dstring@macro-dial-one:9] Set(“SIP/VoipVoip-00000000”, “DSTRING=SIP/1291&”) in new stack
– Executing [dstring@macro-dial-one:10] Set(“SIP/VoipVoip-00000000”, “ITER=2”) in new stack
– Executing [dstring@macro-dial-one:11] GotoIf(“SIP/VoipVoip-00000000”, “0?begin”) in new stack
– Executing [dstring@macro-dial-one:12] Set(“SIP/VoipVoip-00000000”, “DSTRING=SIP/1291”) in new stack
– Executing [dstring@macro-dial-one:13] Return(“SIP/VoipVoip-00000000”, “”) in new stack
– Executing [s@macro-dial-one:27] GotoIf(“SIP/VoipVoip-00000000”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:28] GotoIf(“SIP/VoipVoip-00000000”, “0?skiptrace”) in new stack
– Executing [s@macro-dial-one:29] GosubIf(“SIP/VoipVoip-00000000”, “1?ctset,1():ctclear,1()”) in new stack
– Executing [ctset@macro-dial-one:1] Set(“SIP/VoipVoip-00000000”, “DB(CALLTRACE/1291)=+16625******”) in new stack
– Executing [ctset@macro-dial-one:2] Return(“SIP/VoipVoip-00000000”, “”) in new stack
– Executing [s@macro-dial-one:30] Set(“SIP/VoipVoip-00000000”, “D_OPTIONS=tr”) in new stack
– Executing [s@macro-dial-one:31] ExecIf(“SIP/VoipVoip-00000000”, “0?SIPAddHeader(Alert-Info: )”) in new stack
– Executing [s@macro-dial-one:32] ExecIf(“SIP/VoipVoip-00000000”, “0?SIPAddHeader()”) in new stack
– Executing [s@macro-dial-one:33] ExecIf(“SIP/VoipVoip-00000000”, “1?Set(CHANNEL(musicclass)=default)”) in new stack
– Executing [s@macro-dial-one:34] GosubIf(“SIP/VoipVoip-00000000”, “0?qwait,1()”) in new stack
– Executing [s@macro-dial-one:35] Set(“SIP/VoipVoip-00000000”, “__CWIGNORE=”) in new stack
– Executing [s@macro-dial-one:36] Set(“SIP/VoipVoip-00000000”, “__KEEPCID=TRUE”) in new stack
– Executing [s@macro-dial-one:37] GotoIf(“SIP/VoipVoip-00000000”, “0?usegoto,1”) in new stack
– Executing [s@macro-dial-one:38] GotoIf(“SIP/VoipVoip-00000000”, “1?godial”) in new stack
– Goto (macro-dial-one,s,43)
– Executing [s@macro-dial-one:43] Dial(“SIP/VoipVoip-00000000”, “SIP/1291,15,tr”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called SIP/1291
– SIP/1291-00000001 is ringing
– Nobody picked up in 15000 ms
– Executing [s@macro-dial-one:44] ExecIf(“SIP/VoipVoip-00000000”, “0?MacroExit()”) in new stack
– Executing [s@macro-dial-one:45] ExecIf(“SIP/VoipVoip-00000000”, “0?Set(DIALSTATUS=)”) in new stack
– Executing [s@macro-dial-one:46] GosubIf(“SIP/VoipVoip-00000000”, “0?s-NOANSWER,1()”) in new stack
– Executing [s@macro-dial-one:47] MacroExit(“SIP/VoipVoip-00000000”, “”) in new stack
– Executing [s@macro-exten-vm:15] Set(“SIP/VoipVoip-00000000”, “SV_DIALSTATUS=NOANSWER”) in new stack
– Executing [s@macro-exten-vm:16] GosubIf(“SIP/VoipVoip-00000000”, “0?docfu,1()”) in new stack
– Executing [s@macro-exten-vm:17] GosubIf(“SIP/VoipVoip-00000000”, “0?docfb,1()”) in new stack
– Executing [s@macro-exten-vm:18] Set(“SIP/VoipVoip-00000000”, “DIALSTATUS=NOANSWER”) in new stack
– Executing [s@macro-exten-vm:19] ExecIf(“SIP/VoipVoip-00000000”, “0?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:20] GotoIf(“SIP/VoipVoip-00000000”, “0?s-NOANSWER,1”) in new stack
– Executing [s@macro-exten-vm:21] Macro(“SIP/VoipVoip-00000000”, “vm,1291,NOANSWER,”) in new stack
– Executing [s@macro-vm:1] Macro(“SIP/VoipVoip-00000000”, “user-callerid,SKIPTTL”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/VoipVoip-00000000”, “TOUCH_MONITOR=1424096861.0”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/VoipVoip-00000000”, “AMPUSER=+16625******”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“SIP/VoipVoip-00000000”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“SIP/VoipVoip-00000000”, “0?Set(REALCALLERIDNUM=+16625******)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/VoipVoip-00000000”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/VoipVoip-00000000”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“SIP/VoipVoip-00000000”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“SIP/VoipVoip-00000000”, “1?report”) in new stack
– Goto (macro-user-callerid,s,15)
– Executing [s@macro-user-callerid:15] GotoIf(“SIP/VoipVoip-00000000”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,28)
– Executing [s@macro-user-callerid:28] Set(“SIP/VoipVoip-00000000”, “CALLERID(number)=+16625******”) in new stack
– Executing [s@macro-user-callerid:29] Set(“SIP/VoipVoip-00000000”, “CALLERID(name)=+16625******”) in new stack
– Executing [s@macro-user-callerid:30] Set(“SIP/VoipVoip-00000000”, “CDR(cnum)=+16625******”) in new stack
– Executing [s@macro-user-callerid:31] Set(“SIP/VoipVoip-00000000”, “CDR(cnam)=+16625******”) in new stack
– Executing [s@macro-user-callerid:32] Set(“SIP/VoipVoip-00000000”, “CHANNEL(language)=en”) in new stack
– Executing [s@macro-vm:2] Set(“SIP/VoipVoip-00000000”, “VMGAIN=”) in new stack
– Executing [s@macro-vm:3] Macro(“SIP/VoipVoip-00000000”, “blkvm-check,”) in new stack
– Executing [s@macro-blkvm-check:1] Set(“SIP/VoipVoip-00000000”, “GOSUB_RETVAL=”) in new stack
– Executing [s@macro-blkvm-check:2] ExecIf(“SIP/VoipVoip-00000000”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
– Executing [s@macro-blkvm-check:3] MacroExit(“SIP/VoipVoip-00000000”, “”) in new stack
– Executing [s@macro-vm:4] GotoIf(“SIP/VoipVoip-00000000”, “1?vmx,1”) in new stack
– Goto (macro-vm,vmx,1)
– Executing [vmx@macro-vm:1] Set(“SIP/VoipVoip-00000000”, “MEXTEN=1291”) in new stack
– Executing [vmx@macro-vm:2] Set(“SIP/VoipVoip-00000000”, “MMODE=NOANSWER”) in new stack
– Executing [vmx@macro-vm:3] Set(“SIP/VoipVoip-00000000”, “RETVM=”) in new stack
– Executing [vmx@macro-vm:4] Set(“SIP/VoipVoip-00000000”, “MODE=unavail”) in new stack
– Executing [vmx@macro-vm:5] GotoIf(“SIP/VoipVoip-00000000”, “1?chknomsg”) in new stack
– Goto (macro-vm,vmx,7)
– Executing [vmx@macro-vm:7] GotoIf(“SIP/VoipVoip-00000000”, “0?s-NOANSWER,1”) in new stack
– Executing [vmx@macro-vm:8] GotoIf(“SIP/VoipVoip-00000000”, “1?notdirect”) in new stack
– Goto (macro-vm,vmx,10)
– Executing [vmx@macro-vm:10] NoOp(“SIP/VoipVoip-00000000”, "Checking if ext 1291 is enabled: ") in new stack
– Executing [vmx@macro-vm:11] GotoIf(“SIP/VoipVoip-00000000”, “1?s-NOANSWER,1”) in new stack
– Goto (macro-vm,s-NOANSWER,1)
– Executing [s-NOANSWER@macro-vm:1] Macro(“SIP/VoipVoip-00000000”, “get-vmcontext,1291”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“SIP/VoipVoip-00000000”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/VoipVoip-00000000”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“SIP/VoipVoip-00000000”, “”) in new stack
– Executing [s-NOANSWER@macro-vm:2] VoiceMail(“SIP/VoipVoip-00000000”, “1291@default,u”) in new stack
– <SIP/VoipVoip-00000000> Playing ‘vm-theperson.gsm’ (language ‘en’)
– <SIP/VoipVoip-00000000> Playing ‘digits/1.gsm’ (language ‘en’)
– <SIP/VoipVoip-00000000> Playing ‘digits/2.gsm’ (language ‘en’)
– <SIP/VoipVoip-00000000> Playing ‘digits/9.gsm’ (language ‘en’)
– <SIP/VoipVoip-00000000> Playing ‘digits/1.gsm’ (language ‘en’)
– <SIP/VoipVoip-00000000> Playing ‘vm-isunavail.gsm’ (language ‘en’)
– <SIP/VoipVoip-00000000> Playing ‘vm-intro.gsm’ (language ‘en’)
– <SIP/VoipVoip-00000000> Playing ‘beep.gsm’ (language ‘en’)
– Recording the message
– x=0, open writing: /var/spool/asterisk/voicemail/default/1291/tmp/6RU2xm format: wav, 0xa2dcb74
– User hung up
== Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘SIP/VoipVoip-00000000’ in macro ‘vm’
== Spawn extension (macro-exten-vm, s, 21) exited non-zero on ‘SIP/VoipVoip-00000000’ in macro ‘exten-vm’
== Spawn extension (from-did-direct, 1291, 2) exited non-zero on ‘SIP/VoipVoip-00000000’
– Executing [h@from-did-direct:1] Macro(“SIP/VoipVoip-00000000”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“SIP/VoipVoip-00000000”, “1?endmixmoncheck”) in new stack
– Goto (macro-hangupcall,s,9)
– Executing [s@macro-hangupcall:9] NoOp(“SIP/VoipVoip-00000000”, “End of MIXMON check”) in new stack
– Executing [s@macro-hangupcall:10] GotoIf(“SIP/VoipVoip-00000000”, “1?nomeetmemon”) in new stack
– Goto (macro-hangupcall,s,28)
– Executing [s@macro-hangupcall:28] NoOp(“SIP/VoipVoip-00000000”, “End of MEETME check”) in new stack
– Executing [s@macro-hangupcall:29] GotoIf(“SIP/VoipVoip-00000000”, “1?noautomon”) in new stack
– Goto (macro-hangupcall,s,34)
– Executing [s@macro-hangupcall:34] NoOp(“SIP/VoipVoip-00000000”, “TOUCH_MONITOR_OUTPUT=”) in new stack
– Executing [s@macro-hangupcall:35] GotoIf(“SIP/VoipVoip-00000000”, “1?noautomon2”) in new stack
– Goto (macro-hangupcall,s,41)
– Executing [s@macro-hangupcall:41] NoOp(“SIP/VoipVoip-00000000”, “MONITOR_FILENAME=”) in new stack
– Executing [s@macro-hangupcall:42] GotoIf(“SIP/VoipVoip-00000000”, “1?skiprg”) in new stack
– Goto (macro-hangupcall,s,45)
– Executing [s@macro-hangupcall:45] GotoIf(“SIP/VoipVoip-00000000”, “1?skipblkvm”) in new stack
– Goto (macro-hangupcall,s,48)
– Executing [s@macro-hangupcall:48] GotoIf(“SIP/VoipVoip-00000000”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,50)
– Executing [s@macro-hangupcall:50] AGI(“SIP/VoipVoip-00000000”, “hangup.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi
– <SIP/VoipVoip-00000000>AGI Script hangup.agi completed, returning 0
– Executing [s@macro-hangupcall:51] Hangup(“SIP/VoipVoip-00000000”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 51) exited non-zero on ‘SIP/VoipVoip-00000000’ in macro ‘hangupcall’
== Spawn extension (from-did-direct, h, 1) exited non-zero on ‘SIP/VoipVoip-00000000’
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [*97@from-internal:1] Answer(“SIP/1291-00000002”, “”) in new stack
– Executing [*97@from-internal:2] Wait(“SIP/1291-00000002”, “1”) in new stack
[2015-02-16 09:28:39] NOTICE[2937][C-00000001]: channel.c:4301 __ast_read: Dropping incompatible voice frame on SIP/1291-00000002 of format alaw since our native format has changed to (ulaw)
– Executing [*97@from-internal:3] Macro(“SIP/1291-00000002”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/1291-00000002”, “TOUCH_MONITOR=1424096918.2”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/1291-00000002”, “AMPUSER=1291”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“SIP/1291-00000002”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“SIP/1291-00000002”, “1?Set(REALCALLERIDNUM=1291)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/1291-00000002”, “AMPUSER=1291”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/1291-00000002”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“SIP/1291-00000002”, “AMPUSERCIDNAME=Bill Willy”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“SIP/1291-00000002”, “0?report”) in new stack
– Executing [s@macro-user-callerid:9] Set(“SIP/1291-00000002”, “AMPUSERCID=1291”) in new stack
– Executing [s@macro-user-callerid:10] Set(“SIP/1291-00000002”, “__DIAL_OPTIONS=tr”) in new stack
– Executing [s@macro-user-callerid:11] Set(“SIP/1291-00000002”, “CALLERID(all)=“Bill Willy” <1291>”) in new stack
– Executing [s@macro-user-callerid:12] GotoIf(“SIP/1291-00000002”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:13] ExecIf(“SIP/1291-00000002”, “0?Set(GROUP(concurrency_limit)=1291)”) in new stack
– Executing [s@macro-user-callerid:14] ExecIf(“SIP/1291-00000002”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [s@macro-user-callerid:15] GotoIf(“SIP/1291-00000002”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:16] Set(“SIP/1291-00000002”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:17] GotoIf(“SIP/1291-00000002”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,28)
– Executing [s@macro-user-callerid:28] Set(“SIP/1291-00000002”, “CALLERID(number)=1291”) in new stack
– Executing [s@macro-user-callerid:29] Set(“SIP/1291-00000002”, “CALLERID(name)=Bill Willy”) in new stack
– Executing [s@macro-user-callerid:30] Set(“SIP/1291-00000002”, “CDR(cnum)=1291”) in new stack
– Executing [s@macro-user-callerid:31] Set(“SIP/1291-00000002”, “CDR(cnam)=Bill Willy”) in new stack
– Executing [s@macro-user-callerid:32] Set(“SIP/1291-00000002”, “CHANNEL(language)=en”) in new stack
– Executing [*97@from-internal:4] Macro(“SIP/1291-00000002”, “get-vmcontext,1291”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“SIP/1291-00000002”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/1291-00000002”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“SIP/1291-00000002”, “”) in new stack
– Executing [*97@from-internal:5] Set(“SIP/1291-00000002”, “VMBOXEXISTSSTATUS=SUCCESS”) in new stack
– Executing [*97@from-internal:6] GotoIf(“SIP/1291-00000002”, “1?mbexist”) in new stack
– Goto (from-internal,*97,106)
– Executing [*97@from-internal:106] VoiceMailMain(“SIP/1291-00000002”, “1291@default”) in new stack
– <SIP/1291-00000002> Playing ‘vm-youhave.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘digits/2.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘vm-INBOX.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘vm-messages.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘vm-onefor.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘vm-first.gsm’ (language ‘en’)
== Parsing ‘/var/spool/asterisk/voicemail/default/1291/INBOX/msg0000.txt’: Found
– <SIP/1291-00000002> Playing ‘vm-message.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘/var/spool/asterisk/voicemail/default/1291/INBOX/msg0000.slin’ (language ‘en’)
[2015-02-16 09:28:44] WARNING[2937][C-00000001]: app_voicemail.c:8577 play_message: Playback of message /var/spool/asterisk/voicemail/default/1291/INBOX/msg0000 failed
– <SIP/1291-00000002> Playing ‘vm-advopts.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘vm-repeat.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘vm-deleted.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘vm-advopts.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘vm-last.gsm’ (language ‘en’)
== Parsing ‘/var/spool/asterisk/voicemail/default/1291/INBOX/msg0001.txt’: Found
– <SIP/1291-00000002> Playing ‘vm-message.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘/var/spool/asterisk/voicemail/default/1291/INBOX/msg0001.slin’ (language ‘en’)
[2015-02-16 09:28:50] WARNING[2937][C-00000001]: app_voicemail.c:8577 play_message: Playback of message /var/spool/asterisk/voicemail/default/1291/INBOX/msg0001 failed
– <SIP/1291-00000002> Playing ‘vm-prev.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘vm-advopts.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘vm-deleted.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘vm-prev.gsm’ (language ‘en’)
– <SIP/1291-00000002> Playing ‘vm-goodbye.gsm’ (language ‘en’)
– Executing [*97@from-internal:107] GotoIf(“SIP/1291-00000002”, “0?playret”) in new stack
– Executing [*97@from-internal:108] Macro(“SIP/1291-00000002”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“SIP/1291-00000002”, “1?endmixmoncheck”) in new stack
– Goto (macro-hangupcall,s,9)
– Executing [s@macro-hangupcall:9] NoOp(“SIP/1291-00000002”, “End of MIXMON check”) in new stack
– Executing [s@macro-hangupcall:10] GotoIf(“SIP/1291-00000002”, “1?nomeetmemon”) in new stack
– Goto (macro-hangupcall,s,28)
– Executing [s@macro-hangupcall:28] NoOp(“SIP/1291-00000002”, “End of MEETME check”) in new stack
– Executing [s@macro-hangupcall:29] GotoIf(“SIP/1291-00000002”, “1?noautomon”) in new stack
– Goto (macro-hangupcall,s,34)
– Executing [s@macro-hangupcall:34] NoOp(“SIP/1291-00000002”, “TOUCH_MONITOR_OUTPUT=”) in new stack
– Executing [s@macro-hangupcall:35] GotoIf(“SIP/1291-00000002”, “1?noautomon2”) in new stack
– Goto (macro-hangupcall,s,41)
– Executing [s@macro-hangupcall:41] NoOp(“SIP/1291-00000002”, “MONITOR_FILENAME=”) in new stack
– Executing [s@macro-hangupcall:42] GotoIf(“SIP/1291-00000002”, “1?skiprg”) in new stack
– Goto (macro-hangupcall,s,45)
– Executing [s@macro-hangupcall:45] GotoIf(“SIP/1291-00000002”, “1?skipblkvm”) in new stack
– Goto (macro-hangupcall,s,48)
– Executing [s@macro-hangupcall:48] GotoIf(“SIP/1291-00000002”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,50)
– Executing [s@macro-hangupcall:50] AGI(“SIP/1291-00000002”, “hangup.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/hangup.agi
– <SIP/1291-00000002>AGI Script hangup.agi completed, returning 0
– Executing [s@macro-hangupcall:51] Hangup(“SIP/1291-00000002”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 51) exited non-zero on ‘SIP/1291-00000002’ in macro ‘hangupcall’
== Spawn extension (from-internal, 97, 108) exited non-zero on ‘SIP/1291-00000002’
– Executing [h@from-internal:1] Hangup(“SIP/1291-00000002”, “”) in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/1291-00000002’
– Remote UNIX connection
– Remote UNIX connection disconnected
Matarazzo
CLI>

Any help is appreciated. You guys are the best!

hwrdmltn

I think you need to get initial support from the people who wrote the dialplan.