Can't leave outbound Voicemail

Hi,

I’ve got a FreePBX 14.0.1.36 box running Asterisk 15.2.2. The users report they can’t leave voicemail when they call out from the system. The call hangs up just about when the voicemail makes the beep. To clarify this is not the voicemail on the Freepbx box. The call originates from my box and tries to leave a message on external phone system.

[2018-04-23 07:50:03] VERBOSE[25141][C-00001169] netsock2.c: Using SIP RTP TOS bits 184
[2018-04-23 07:50:03] VERBOSE[25141][C-00001169] netsock2.c: Using SIP RTP CoS mark 5
[2018-04-23 07:50:03] VERBOSE[25110] chan_sip.c: Extension Changed 200[ext-local] new state InUse for Notify User 203
[2018-04-23 07:50:03] VERBOSE[25110] chan_sip.c: Extension Changed 200[ext-local] new state InUse for Notify User 201
[2018-04-23 07:50:03] VERBOSE[25110] chan_sip.c: Extension Changed 200[ext-local] new state InUse for Notify User 204
[2018-04-23 07:50:03] VERBOSE[25110] chan_sip.c: Extension Changed 200[ext-local] new state InUse for Notify User 205
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:1] Macro(“SIP/200-0000125e”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:1] Set(“SIP/200-0000125e”, “TOUCH_MONITOR=1524495003.4708”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:2] Set(“SIP/200-0000125e”, “AMPUSER=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:3] GotoIf(“SIP/200-0000125e”, “0?report”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:4] ExecIf(“SIP/200-0000125e”, “1?Set(REALCALLERIDNUM=200)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:5] Set(“SIP/200-0000125e”, “AMPUSER=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:6] GotoIf(“SIP/200-0000125e”, “0?limit”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:7] Set(“SIP/200-0000125e”, “AMPUSERCIDNAME=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:8] ExecIf(“SIP/200-0000125e”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:9] GotoIf(“SIP/200-0000125e”, “0?report”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:10] Set(“SIP/200-0000125e”, “AMPUSERCID=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:11] Set(“SIP/200-0000125e”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:12] Set(“SIP/200-0000125e”, “CALLERID(all)=“200” <200>”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:13] GotoIf(“SIP/200-0000125e”, “0?limit”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:14] ExecIf(“SIP/200-0000125e”, “1?Set(GROUP(concurrency_limit)=200)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:15] ExecIf(“SIP/200-0000125e”, “0?Set(CHANNEL(language)=)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:16] NoOp(“SIP/200-0000125e”, “Macro Depth is 1”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:17] GotoIf(“SIP/200-0000125e”, “1?report2:macroerror”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx_builtins.c: Goto (macro-user-callerid,s,19)
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:19] GotoIf(“SIP/200-0000125e”, “1?continue”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:37] Set(“SIP/200-0000125e”, “CALLERID(number)=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:38] Set(“SIP/200-0000125e”, “CALLERID(name)=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:39] GotoIf(“SIP/200-0000125e”, “0?cnum”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:40] Set(“SIP/200-0000125e”, “CDR(cnam)=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:41] Set(“SIP/200-0000125e”, “CDR(cnum)=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:42] Set(“SIP/200-0000125e”, “CHANNEL(language)=en”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:2] Gosub(“SIP/200-0000125e”, “sub-record-check,s,1(out,5414346788,dontcare)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:1] GotoIf(“SIP/200-0000125e”, “0?initialized”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:2] Set(“SIP/200-0000125e”, “__REC_STATUS=INITIALIZED”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:3] Set(“SIP/200-0000125e”, “NOW=1524495003”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:4] Set(“SIP/200-0000125e”, “__DAY=23”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:5] Set(“SIP/200-0000125e”, “__MONTH=04”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:6] Set(“SIP/200-0000125e”, “__YEAR=2018”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:7] Set(“SIP/200-0000125e”, “__TIMESTR=20180423-075003”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:8] Set(“SIP/200-0000125e”, “__FROMEXTEN=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:9] Set(“SIP/200-0000125e”, “__MON_FMT=wav”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:10] NoOp(“SIP/200-0000125e”, “Recordings initialized”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:11] ExecIf(“SIP/200-0000125e”, “0?Set(ARG3=dontcare)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:12] Set(“SIP/200-0000125e”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:13] ExecIf(“SIP/200-0000125e”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:14] GotoIf(“SIP/200-0000125e”, “3?checkaction”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:17] GotoIf(“SIP/200-0000125e”, “1?sub-record-check,out,1”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx_builtins.c: Goto (sub-record-check,out,1)
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:1] NoOp(“SIP/200-0000125e”, “Outbound Recording Check from 200 to 5414346788”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:2] Set(“SIP/200-0000125e”, “RECMODE=dontcare”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/200-0000125e”, “1?Goto(routewins)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx_builtins.c: Goto (sub-record-check,out,7)
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:7] Gosub(“SIP/200-0000125e”, “recordcheck,1(dontcare,out,5414346788)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:1] NoOp(“SIP/200-0000125e”, “Starting recording check against dontcare”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:2] Goto(“SIP/200-0000125e”, “dontcare”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:3] Return(“SIP/200-0000125e”, “”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:8] Return(“SIP/200-0000125e”, “”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/200-0000125e”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:4] Set(“SIP/200-0000125e”, “MOHCLASS=default”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:5] ExecIf(“SIP/200-0000125e”, “1?Set(TRUNKCIDOVERRIDE=5413931037)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:6] Set(“SIP/200-0000125e”, “_NODEST=”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:7] Macro(“SIP/200-0000125e”, “dialout-trunk,3,5414346788,off”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:1] Set(“SIP/200-0000125e”, “DIAL_TRUNK=3”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:2] GosubIf(“SIP/200-0000125e”, “0?sub-pincheck,s,1()”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/200-0000125e”, “0?Set(CALLERID(num)=200)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:4] GotoIf(“SIP/200-0000125e”, “0?disabletrunk,1”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:5] Set(“SIP/200-0000125e”, “DIAL_NUMBER=5414346788”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:6] Set(“SIP/200-0000125e”, “DIAL_TRUNK_OPTIONS=HhTtr”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:7] Set(“SIP/200-0000125e”, “OUTBOUND_GROUP=OUT_3”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:8] Set(“SIP/200-0000125e”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:9] GotoIf(“SIP/200-0000125e”, “0?nomax”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:10] GotoIf(“SIP/200-0000125e”, “0?chanfull”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:11] GotoIf(“SIP/200-0000125e”, “0?skipoutcid”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:12] Macro(“SIP/200-0000125e”, “outbound-callerid,3”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:1] NoOp(“SIP/200-0000125e”, “200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:2] NoOp(“SIP/200-0000125e”, “”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:3] NoOp(“SIP/200-0000125e”, “off”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:4] ExecIf(“SIP/200-0000125e”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:5] ExecIf(“SIP/200-0000125e”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:6] ExecIf(“SIP/200-0000125e”, “0?Set(REALCALLERIDNUM=200)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:7] GotoIf(“SIP/200-0000125e”, “1?normcid”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx_builtins.c: Goto (macro-outbound-callerid,s,11)
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:11] Set(“SIP/200-0000125e”, “USEROUTCID=”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:12] Set(“SIP/200-0000125e”, “EMERGENCYCID=”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:13] Set(“SIP/200-0000125e”, “TRUNKOUTCID=5413931037”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:14] GotoIf(“SIP/200-0000125e”, “1?trunkcid”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx_builtins.c: Goto (macro-outbound-callerid,s,19)
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:19] ExecIf(“SIP/200-0000125e”, “1?Set(CALLERID(all)=5413931037)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:20] ExecIf(“SIP/200-0000125e”, “0?Set(CALLERID(all)=)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:21] ExecIf(“SIP/200-0000125e”, “1?Set(CALLERID(all)=5413931037)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:22] ExecIf(“SIP/200-0000125e”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:23] ExecIf(“SIP/200-0000125e”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:24] Set(“SIP/200-0000125e”, “CDR(outbound_cnum)=5413931037”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:25] Set(“SIP/200-0000125e”, “CDR(outbound_cnam)=”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:13] GosubIf(“SIP/200-0000125e”, “0?sub-flp-3,s,1()”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:14] Set(“SIP/200-0000125e”, “OUTNUM=5414346788”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:15] Set(“SIP/200-0000125e”, “custom=SIP/vitel-outbound”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:16] ExecIf(“SIP/200-0000125e”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:17] ExecIf(“SIP/200-0000125e”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:18] Macro(“SIP/200-0000125e”, “dialout-trunk-predial-hook,”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:1] MacroExit(“SIP/200-0000125e”, “”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:19] GotoIf(“SIP/200-0000125e”, “0?skipcrm”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:20] Set(“SIP/200-0000125e”, “__CRM_DIRECTION=OUTBOUND”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:21] Set(“SIP/200-0000125e”, “__CRM_DESTINATION=5414346788”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:22] Set(“SIP/200-0000125e”, “__CRM_SOURCE=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:23] AGI(“SIP/200-0000125e”, “sangomacrm.agi”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] res_agi.c: <SIP/200-0000125e>AGI Script sangomacrm.agi completed, returning 0
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:24] Set(“SIP/200-0000125e”, “CHANNEL(hangup_handler_push)=crm-hangup,s,1”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:25] NoOp(“SIP/200-0000125e”, “CRM Finished”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:26] GotoIf(“SIP/200-0000125e”, “0?bypass,1”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:27] ExecIf(“SIP/200-0000125e”, “1?Set(CONNECTEDLINE(num,i)=5414346788)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:28] ExecIf(“SIP/200-0000125e”, “1?Set(CONNECTEDLINE(name,i)=CID:5413931037)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:29] ExecIf(“SIP/200-0000125e”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)5413931037)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:30] GotoIf(“SIP/200-0000125e”, “0?customtrunk”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:31] Dial(“SIP/200-0000125e”, “SIP/vitel-outbound/5414346788,300,Tb(func-apply-sipheaders^s^1)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] netsock2.c: Using SIP RTP TOS bits 184
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] netsock2.c: Using SIP RTP CoS mark 5
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] app_stack.c: SIP/vitel-outbound-0000125f Internal Gosub(func-apply-sipheaders,s,1) start
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:1] ExecIf(“SIP/vitel-outbound-0000125f”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:2] NoOp(“SIP/vitel-outbound-0000125f”, “Applying SIP Headers to channel”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:3] Set(“SIP/vitel-outbound-0000125f”, “SIPHEADERKEYS=”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:4] While(“SIP/vitel-outbound-0000125f”, “0”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] app_while.c: Jumping to priority 7
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:8] Return(“SIP/vitel-outbound-0000125f”, “”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] app_stack.c: Spawn extension (from-trunk, 5414346788, 1) exited non-zero on ‘SIP/vitel-outbound-0000125f’
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] app_stack.c: SIP/vitel-outbound-0000125f Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] app_dial.c: Called SIP/vitel-outbound/5414346788
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] app_dial.c: SIP/vitel-outbound-0000125f redirecting info has changed, passing it to SIP/200-0000125e
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] app_dial.c: SIP/vitel-outbound-0000125f is busy
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] app_dial.c: Everyone is busy/congested at this time (1:1/0/0)
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:32] NoOp(“SIP/200-0000125e”, “Dial failed for some reason with DIALSTATUS = BUSY and HANGUPCAUSE = 19”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:33] GotoIf(“SIP/200-0000125e”, “0?continue,1:s-BUSY,1”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx_builtins.c: Goto (macro-dialout-trunk,s-BUSY,1)
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:1] NoOp(“SIP/200-0000125e”, “Dial failed due to trunk reporting BUSY - giving up”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:2] PlayTones(“SIP/200-0000125e”, “busy”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:3] Busy(“SIP/200-0000125e”, “20”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] app_macro.c: Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on ‘SIP/200-0000125e’ in macro ‘dialout-trunk’
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Spawn extension (from-internal, 5414346788, 7) exited non-zero on ‘SIP/200-0000125e’
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:1] Macro(“SIP/200-0000125e”, “hangupcall”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:1] GotoIf(“SIP/200-0000125e”, “1?theend”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/200-0000125e”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:4] NoOp(“SIP/200-0000125e”, " monior file= ") in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:5] AGI(“SIP/200-0000125e”, “attendedtransfer-rec-restart.php,”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] res_agi.c: <SIP/200-0000125e>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:6] Hangup(“SIP/200-0000125e”, “”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘SIP/200-0000125e’ in macro ‘hangupcall’
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/200-0000125e’
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] app_stack.c: SIP/200-0000125e Internal Gosub(crm-hangup,s,1) start
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:1] NoOp(“SIP/200-0000125e”, “Sending Hangup to CRM”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:2] NoOp(“SIP/200-0000125e”, “HANGUP CAUSE: 17”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/200-0000125e”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:4] NoOp(“SIP/200-0000125e”, “MASTER CHANNEL: 1524495003.4708 = 1524495003.4708”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:5] GotoIf(“SIP/200-0000125e”, “0?return”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:6] Set(“SIP/200-0000125e”, “__CRM_HANGUP=1”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:7] AGI(“SIP/200-0000125e”, “sangomacrm.agi”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] res_agi.c: <SIP/200-0000125e>AGI Script sangomacrm.agi completed, returning 0
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [[email protected]:8] Return(“SIP/200-0000125e”, “”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/200-0000125e’
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] app_stack.c: SIP/200-0000125e Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-04-23 07:50:06] VERBOSE[25110] chan_sip.c: Extension Changed 200[ext-local] new state Idle for Notify User 203
[2018-04-23 07:50:06] VERBOSE[25110] chan_sip.c: Extension Changed 200[ext-local] new state Idle for Notify User 201
[2018-04-23 07:50:06] VERBOSE[25110] chan_sip.c: Extension Changed 200[ext-local] new state Idle for Notify User 204
[2018-04-23 07:50:06] VERBOSE[25110] chan_sip.c: Extension Changed 200[ext-local] new state Idle for Notify User 205
[2018-04-23 07:51:12] VERBOSE[25141][C-0000116a] netsock2.c: Using SIP RTP TOS bits 184
[2018-04-23 07:51:12] VERBOSE[25141][C-0000116a] netsock2.c: Using SIP RTP CoS mark 5

Had this issue recently on Asterisk 11. Upgraded to 13, rebuilt the NAT settings, reboot. No peeps from them since.

Yeah, its on 14, but I’ll run the updates.

So I ran updates. It is now the most current 14 but still intermittent voicemail hangups

This topic was automatically closed 365 days after the last reply. New replies are no longer allowed.