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 [5414346788@from-internal: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 [s@macro-user-callerid: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 [s@macro-user-callerid:2] Set(“SIP/200-0000125e”, “AMPUSER=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/200-0000125e”, “0?report”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid: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 [s@macro-user-callerid:5] Set(“SIP/200-0000125e”, “AMPUSER=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/200-0000125e”, “0?limit”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/200-0000125e”, “AMPUSERCIDNAME=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid: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 [s@macro-user-callerid:9] GotoIf(“SIP/200-0000125e”, “0?report”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/200-0000125e”, “AMPUSERCID=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid:11] Set(“SIP/200-0000125e”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid: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 [s@macro-user-callerid:13] GotoIf(“SIP/200-0000125e”, “0?limit”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid: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 [s@macro-user-callerid: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 [s@macro-user-callerid: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 [s@macro-user-callerid: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 [s@macro-user-callerid: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 [s@macro-user-callerid:37] Set(“SIP/200-0000125e”, “CALLERID(number)=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid:38] Set(“SIP/200-0000125e”, “CALLERID(name)=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“SIP/200-0000125e”, “0?cnum”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid:40] Set(“SIP/200-0000125e”, “CDR(cnam)=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid:41] Set(“SIP/200-0000125e”, “CDR(cnum)=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-user-callerid:42] Set(“SIP/200-0000125e”, “CHANNEL(language)=en”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [5414346788@from-internal: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 [s@sub-record-check:1] GotoIf(“SIP/200-0000125e”, “0?initialized”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/200-0000125e”, “__REC_STATUS=INITIALIZED”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/200-0000125e”, “NOW=1524495003”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/200-0000125e”, “__DAY=23”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/200-0000125e”, “__MONTH=04”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/200-0000125e”, “__YEAR=2018”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/200-0000125e”, “__TIMESTR=20180423-075003”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/200-0000125e”, “__FROMEXTEN=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/200-0000125e”, “__MON_FMT=wav”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/200-0000125e”, “Recordings initialized”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@sub-record-check: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 [s@sub-record-check: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 [s@sub-record-check: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 [s@sub-record-check: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 [s@sub-record-check: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 [out@sub-record-check: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 [out@sub-record-check:2] Set(“SIP/200-0000125e”, “RECMODE=dontcare”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [out@sub-record-check: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 [out@sub-record-check: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 [recordcheck@sub-record-check: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 [recordcheck@sub-record-check: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 [recordcheck@sub-record-check:3] Return(“SIP/200-0000125e”, “”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [out@sub-record-check:8] Return(“SIP/200-0000125e”, “”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [5414346788@from-internal: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 [5414346788@from-internal:4] Set(“SIP/200-0000125e”, “MOHCLASS=default”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [5414346788@from-internal: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 [5414346788@from-internal:6] Set(“SIP/200-0000125e”, “_NODEST=”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [5414346788@from-internal: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 [s@macro-dialout-trunk:1] Set(“SIP/200-0000125e”, “DIAL_TRUNK=3”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk:4] GotoIf(“SIP/200-0000125e”, “0?disabletrunk,1”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk:5] Set(“SIP/200-0000125e”, “DIAL_NUMBER=5414346788”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk:9] GotoIf(“SIP/200-0000125e”, “0?nomax”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf(“SIP/200-0000125e”, “0?chanfull”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf(“SIP/200-0000125e”, “0?skipoutcid”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk:12] Macro(“SIP/200-0000125e”, “outbound-callerid,3”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp(“SIP/200-0000125e”, “200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp(“SIP/200-0000125e”, “”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp(“SIP/200-0000125e”, “off”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid:11] Set(“SIP/200-0000125e”, “USEROUTCID=”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-outbound-callerid:12] Set(“SIP/200-0000125e”, “EMERGENCYCID=”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-outbound-callerid:13] Set(“SIP/200-0000125e”, “TRUNKOUTCID=5413931037”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid:25] Set(“SIP/200-0000125e”, “CDR(outbound_cnam)=”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk:14] Set(“SIP/200-0000125e”, “OUTNUM=5414346788”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/200-0000125e”, “”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk:19] GotoIf(“SIP/200-0000125e”, “0?skipcrm”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk:20] Set(“SIP/200-0000125e”, “__CRM_DIRECTION=OUTBOUND”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk:21] Set(“SIP/200-0000125e”, “__CRM_DESTINATION=5414346788”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk:22] Set(“SIP/200-0000125e”, “__CRM_SOURCE=200”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk:25] NoOp(“SIP/200-0000125e”, “CRM Finished”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk:26] GotoIf(“SIP/200-0000125e”, “0?bypass,1”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk:30] GotoIf(“SIP/200-0000125e”, “0?customtrunk”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-dialout-trunk: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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders:3] Set(“SIP/vitel-outbound-0000125f”, “SIPHEADERKEYS=”) in new stack
[2018-04-23 07:50:03] VERBOSE[24266][C-00001169] pbx.c: Executing [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s-BUSY@macro-dialout-trunk: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 [s-BUSY@macro-dialout-trunk:2] PlayTones(“SIP/200-0000125e”, “busy”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [s-BUSY@macro-dialout-trunk: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 [h@from-internal:1] Macro(“SIP/200-0000125e”, “hangupcall”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-hangupcall: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 [s@macro-hangupcall: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 [s@macro-hangupcall:4] NoOp(“SIP/200-0000125e”, " monior file= ") in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [s@macro-hangupcall: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 [s@macro-hangupcall: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 [s@crm-hangup: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 [s@crm-hangup:2] NoOp(“SIP/200-0000125e”, “HANGUP CAUSE: 17”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [s@crm-hangup: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 [s@crm-hangup: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 [s@crm-hangup:5] GotoIf(“SIP/200-0000125e”, “0?return”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [s@crm-hangup:6] Set(“SIP/200-0000125e”, “__CRM_HANGUP=1”) in new stack
[2018-04-23 07:50:06] VERBOSE[24266][C-00001169] pbx.c: Executing [s@crm-hangup: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 [s@crm-hangup: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