Outbound Calls Disconnect or Go To Inbound Route (IVR)

When an office user dials a number to call someone using an outgoing truck, sometimes instead of hearing the expected ringing of the other party, the incoming IVR greeting is played, sometimes, the call is simply terminated.

It doesn’t happen every time, albeit it happens almost every day now (maybe 50% or more lately) What to do?

Here’s my log from the last attempted call that simply terminated:

[2018-12-28 16:19:22] VERBOSE[16445] pbx_variables.c: Setting global variable 'SIPDOMAIN' to '192.168.1.115'
[2018-12-28 16:19:22] VERBOSE[16445] netsock2.c: Using SIP RTP Audio TOS bits 184
[2018-12-28 16:19:22] VERBOSE[16445] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2018-12-28 16:19:22] VERBOSE[16445] netsock2.c: Using SIP RTP Audio CoS mark 5
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:1] Macro("PJSIP/2210-000002a3", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:1] Set("PJSIP/2210-000002a3", "TOUCH_MONITOR=1546013962.759") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:2] Set("PJSIP/2210-000002a3", "AMPUSER=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:3] GotoIf("PJSIP/2210-000002a3", "0?report") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:4] ExecIf("PJSIP/2210-000002a3", "1?Set(REALCALLERIDNUM=2210)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:5] Set("PJSIP/2210-000002a3", "AMPUSER=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:6] GotoIf("PJSIP/2210-000002a3", "0?limit") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:7] Set("PJSIP/2210-000002a3", "AMPUSERCIDNAME=Robert Golf Outgoing") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:8] ExecIf("PJSIP/2210-000002a3", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:9] GotoIf("PJSIP/2210-000002a3", "0?report") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:10] Set("PJSIP/2210-000002a3", "AMPUSERCID=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:11] Set("PJSIP/2210-000002a3", "__DIAL_OPTIONS=HhTtr") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:12] Set("PJSIP/2210-000002a3", "CALLERID(all)="Robert Golf Outgoing" <2210>") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:13] GotoIf("PJSIP/2210-000002a3", "0?limit") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:14] ExecIf("PJSIP/2210-000002a3", "1?Set(GROUP(concurrency_limit)=2210)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:15] ExecIf("PJSIP/2210-000002a3", "0?Set(CHANNEL(language)=)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:16] NoOp("PJSIP/2210-000002a3", "Macro Depth is 1") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:17] GotoIf("PJSIP/2210-000002a3", "1?report2:macroerror") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:18] GotoIf("PJSIP/2210-000002a3", "1?continue") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:37] Set("PJSIP/2210-000002a3", "CALLERID(number)=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:38] Set("PJSIP/2210-000002a3", "CALLERID(name)=Robert Golf Outgoing") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:39] GotoIf("PJSIP/2210-000002a3", "0?cnum") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:40] Set("PJSIP/2210-000002a3", "CDR(cnam)=Robert Golf Outgoing") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:41] Set("PJSIP/2210-000002a3", "CDR(cnum)=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:42] Set("PJSIP/2210-000002a3", "CHANNEL(language)=en") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:2] Gosub("PJSIP/2210-000002a3", "sub-record-check,s,1(out,828XXXX,force)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:1] GotoIf("PJSIP/2210-000002a3", "0?initialized") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:2] Set("PJSIP/2210-000002a3", "__REC_STATUS=INITIALIZED") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:3] Set("PJSIP/2210-000002a3", "NOW=1546013962") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:4] Set("PJSIP/2210-000002a3", "__DAY=28") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:5] Set("PJSIP/2210-000002a3", "__MONTH=12") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:6] Set("PJSIP/2210-000002a3", "__YEAR=2018") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:7] Set("PJSIP/2210-000002a3", "__TIMESTR=20181228-161922") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:8] Set("PJSIP/2210-000002a3", "__FROMEXTEN=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:9] Set("PJSIP/2210-000002a3", "__MON_FMT=WAV") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:10] NoOp("PJSIP/2210-000002a3", "Recordings initialized") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:11] ExecIf("PJSIP/2210-000002a3", "0?Set(ARG3=dontcare)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:12] Set("PJSIP/2210-000002a3", "REC_POLICY_MODE_SAVE=") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:13] ExecIf("PJSIP/2210-000002a3", "0?Set(REC_STATUS=NO)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:14] GotoIf("PJSIP/2210-000002a3", "3?checkaction") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:17] GotoIf("PJSIP/2210-000002a3", "1?sub-record-check,out,1") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx_builtins.c: Goto (sub-record-check,out,1)
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:1] NoOp("PJSIP/2210-000002a3", "Outbound Recording Check from 2210 to 828XXXX") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:2] Set("PJSIP/2210-000002a3", "RECMODE=dontcare") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:3] ExecIf("PJSIP/2210-000002a3", "1?Goto(routewins)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx_builtins.c: Goto (sub-record-check,out,7)
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:7] Gosub("PJSIP/2210-000002a3", "recordcheck,1(force,out,828XXXX)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:1] NoOp("PJSIP/2210-000002a3", "Starting recording check against force") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:2] Goto("PJSIP/2210-000002a3", "force") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx_builtins.c: Goto (sub-record-check,recordcheck,5)
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:5] Set("PJSIP/2210-000002a3", "__REC_POLICY_MODE=FORCE") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:6] GotoIf("PJSIP/2210-000002a3", "1?startrec") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx_builtins.c: Goto (sub-record-check,recordcheck,16)
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:16] NoOp("PJSIP/2210-000002a3", "Starting recording: out, 828XXXX") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:17] Set("PJSIP/2210-000002a3", "__CALLFILENAME=out-828XXXX-2210-20181228-161922-1546013962.759") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:18] MixMonitor("PJSIP/2210-000002a3", "2018/12/28/out-828XXXX-2210-20181228-161922-1546013962.759.WAV,abi(LOCAL_MIXMON_ID),") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:19] Set("PJSIP/2210-000002a3", "__MIXMON_ID=0x7f5bf002ce50") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:20] Set("PJSIP/2210-000002a3", "__RECORD_ID=PJSIP/2210-000002a3") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:21] Set("PJSIP/2210-000002a3", "__REC_STATUS=RECORDING") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:22] Set("PJSIP/2210-000002a3", "CDR(recordingfile)=out-828XXXX-2210-20181228-161922-1546013962.759.WAV") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:23] Return("PJSIP/2210-000002a3", "") in new stack
[2018-12-28 16:19:22] VERBOSE[29831][C-00000110] app_mixmonitor.c: Begin MixMonitor Recording PJSIP/2210-000002a3
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:8] Return("PJSIP/2210-000002a3", "") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:3] ExecIf("PJSIP/2210-000002a3", "0 ?Set(CDR(accountcode)=)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:4] Set("PJSIP/2210-000002a3", "ROUTE_CIDSAVE="Robert Golf Outgoing" <2210>") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:5] Set("PJSIP/2210-000002a3", "MOHCLASS=default") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:6] ExecIf("PJSIP/2210-000002a3", "1?Set(TRUNKCIDOVERRIDE=<7155989924>)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:7] Set("PJSIP/2210-000002a3", "_NODEST=") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:8] Macro("PJSIP/2210-000002a3", "dialout-trunk,2,1715828XXXX,,on") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:1] Set("PJSIP/2210-000002a3", "DIAL_TRUNK=2") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:2] UserEvent("PJSIP/2210-000002a3", "zulu-outbound-call,from:2210,to:1715828XXXX") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:3] ExecIf("PJSIP/2210-000002a3", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:4] GosubIf("PJSIP/2210-000002a3", "0?sub-pincheck,s,1()") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:5] ExecIf("PJSIP/2210-000002a3", "0?Set(CALLERID(num)=2210)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:6] GotoIf("PJSIP/2210-000002a3", "0?disabletrunk,1") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:7] Set("PJSIP/2210-000002a3", "DIAL_NUMBER=1715828XXXX") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:8] Set("PJSIP/2210-000002a3", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:9] Set("PJSIP/2210-000002a3", "OUTBOUND_GROUP=OUT_2") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:10] Set("PJSIP/2210-000002a3", "DIAL_TRUNK_OPTIONS=T") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:11] GotoIf("PJSIP/2210-000002a3", "0?nomax") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:12] GotoIf("PJSIP/2210-000002a3", "0?chanfull") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]t-trunk:13] GotoIf("PJSIP/2210-000002a3", "0?skipoutcid") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:14] Macro("PJSIP/2210-000002a3", "outbound-callerid,2") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:1] NoOp("PJSIP/2210-000002a3", "2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:2] NoOp("PJSIP/2210-000002a3", "") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:3] NoOp("PJSIP/2210-000002a3", "off") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:4] ExecIf("PJSIP/2210-000002a3", "0?Set(CALLERPRES(name-pres)=)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:5] ExecIf("PJSIP/2210-000002a3", "0?Set(CALLERPRES(num-pres)=)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:6] ExecIf("PJSIP/2210-000002a3", "0?Set(REALCALLERIDNUM=2210)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:7] ExecIf("PJSIP/2210-000002a3", "0?Set(AMPUSER=2210)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:8] GotoIf("PJSIP/2210-000002a3", "1?normcid") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx_builtins.c: Goto (macro-outbound-callerid,s,12)
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:12] Set("PJSIP/2210-000002a3", "USEROUTCID=") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:13] Set("PJSIP/2210-000002a3", "EMERGENCYCID=") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:14] Set("PJSIP/2210-000002a3", "TRUNKOUTCID=7155989924") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:15] GotoIf("PJSIP/2210-000002a3", "1?trunkcid") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:20] ExecIf("PJSIP/2210-000002a3", "1?Set(CALLERID(all)=7155989924)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:21] ExecIf("PJSIP/2210-000002a3", "0?Set(CALLERID(all)=)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:22] ExecIf("PJSIP/2210-000002a3", "1?Set(CALLERID(all)=<7155989924>)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:23] ExecIf("PJSIP/2210-000002a3", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:24] ExecIf("PJSIP/2210-000002a3", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:25] Set("PJSIP/2210-000002a3", "CDR(outbound_cnum)=7155989924") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:26] Set("PJSIP/2210-000002a3", "CDR(outbound_cnam)=") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:15] GosubIf("PJSIP/2210-000002a3", "0?sub-flp-2,s,1()") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:16] Set("PJSIP/2210-000002a3", "OUTNUM=1715828XXXX") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:17] Set("PJSIP/2210-000002a3", "custom=PJSIP") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]unk:18] ExecIf("PJSIP/2210-000002a3", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:19] ExecIf("PJSIP/2210-000002a3", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:20] Macro("PJSIP/2210-000002a3", "dialout-trunk-predial-hook,") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:1] MacroExit("PJSIP/2210-000002a3", "") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:21] GotoIf("PJSIP/2210-000002a3", "0?skipcrm") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:22] Set("PJSIP/2210-000002a3", "__CRM_DIRECTION=OUTBOUND") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:23] Set("PJSIP/2210-000002a3", "__CRM_DESTINATION=1715828XXXX") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:24] Set("PJSIP/2210-000002a3", "__CRM_SOURCE=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:25] AGI("PJSIP/2210-000002a3", "sangomacrm.agi") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: LINKEDID: 1546013962.759
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: SOURCE: 2210
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: DESTINATION: 1715828XXXX
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: DIRECTION: OUTBOUND
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: EXTTOCALL:
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: START
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTU0NjAxMzk2Mi43NTkiLCJzb3VyY2UiOiIyMjEwIiwiZGVzdGluYXRpb24iOiIxNzE1ODI4MTAxMCIsImRpcmVjdGlvbiI6Ik9VVEJPVU5EIiwidHlwZSI6IlNUQVJUIiwienVsdV9yYXdfdHlwZSI6IiIsInp1bHVfdHlwZSI6IiIsInp1bHVfdXJsIjoiIiwiZXh0dG9jYWxsIjoiIiwiY251bSI6IjcxNTU5ODk5MjQiLCJjbmFtIjoiIiwiY2FsbHBvcCI6ZmFsc2UsInZvaWNlbWFpbCI6IiJ9' > /dev/null 2>&1 &
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] res_agi.c: <PJSIP/2210-000002a3>AGI Script sangomacrm.agi completed, returning 0
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:26] Set("PJSIP/2210-000002a3", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:27] NoOp("PJSIP/2210-000002a3", "CRM Finished") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:28] GotoIf("PJSIP/2210-000002a3", "0?bypass,1") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:29] ExecIf("PJSIP/2210-000002a3", "1?Set(CONNECTEDLINE(num,i)=1715828XXXX)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:30] ExecIf("PJSIP/2210-000002a3", "1?Set(CONNECTEDLINE(name,i)=CID:7155989924)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:31] ExecIf("PJSIP/2210-000002a3", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)7155989924)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:32] GotoIf("PJSIP/2210-000002a3", "0?customtrunk") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:33] Dial("PJSIP/2210-000002a3", "PJSIP/[email protected]_Networks_outbound,300,Tb(func-apply-sipheaders^s^1,(2))") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] app_stack.c: PJSIP/Alcazar_Networks_outbound-000002a4 Internal Gosub(func-apply-sipheaders,s,1(2)) start
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:1] ExecIf("PJSIP/Alcazar_Networks_outbound-000002a4", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:2] UserEvent("PJSIP/Alcazar_Networks_outbound-000002a4", "zulu-call-b,type:func-apply-sipheaders,to:,from:2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:3] NoOp("PJSIP/Alcazar_Networks_outbound-000002a4", "Applying SIP Headers to channel 2") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:4] Set("PJSIP/Alcazar_Networks_outbound-000002a4", "SIPHEADERKEYS=") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:5] ExecIf("PJSIP/Alcazar_Networks_outbound-000002a4", "0?Set(Rheader=1)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:6] While("PJSIP/Alcazar_Networks_outbound-000002a4", "0") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] app_while.c: Jumping to priority 10
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:11] ExecIf("PJSIP/Alcazar_Networks_outbound-000002a4", "0?SIPRemoveHeader(Alert-Info:)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:12] ExecIf("PJSIP/Alcazar_Networks_outbound-000002a4", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:13] Return("PJSIP/Alcazar_Networks_outbound-000002a4", "") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] app_stack.c: Spawn extension (outbound, 828XXXX, 1) exited non-zero on 'PJSIP/Alcazar_Networks_outbound-000002a4'
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] app_stack.c: PJSIP/Alcazar_Networks_outbound-000002a4 Internal Gosub(func-apply-sipheaders,s,1(2)) complete GOSUB_RETVAL=
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] app_dial.c: Called PJSIP/[email protected]_Networks_outbound
[2018-12-28 16:19:25] VERBOSE[29830][C-00000110] app_dial.c: PJSIP/Alcazar_Networks_outbound-000002a4 is making progress passing it to PJSIP/2210-000002a3
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] app_macro.c: Spawn extension (macro-dialout-trunk, s, 33) exited non-zero on 'PJSIP/2210-000002a3' in macro 'dialout-trunk'
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Spawn extension (from-internal, 828XXXX, 8) exited non-zero on 'PJSIP/2210-000002a3'
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:1] Macro("PJSIP/2210-000002a3", "hangupcall") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:1] GotoIf("PJSIP/2210-000002a3", "1?theend") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:3] ExecIf("PJSIP/2210-000002a3", "0?Set(CDR(recordingfile)=)") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:4] NoOp("PJSIP/2210-000002a3", "PJSIP/Alcazar_Networks_outbound-000002a4 monior file= /var/spool/asterisk/monitor/2018/12/28/out-828XXXX-2210-20181228-161922-1546013962.759.WAV") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:5] AGI("PJSIP/2210-000002a3", "attendedtransfer-rec-restart.php,PJSIP/Alcazar_Networks_outbound-000002a4,/var/spool/asterisk/monitor/2018/12/28/out-828XXXX-2210-20181228-161922-1546013962.759.WAV") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] res_agi.c: <PJSIP/2210-000002a3>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:6] Hangup("PJSIP/2210-000002a3", "") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'PJSIP/2210-000002a3' in macro 'hangupcall'
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/2210-000002a3'
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] app_stack.c: PJSIP/2210-000002a3 Internal Gosub(crm-hangup,s,1) start
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:1] NoOp("PJSIP/2210-000002a3", "Sending Hangup to CRM") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:2] NoOp("PJSIP/2210-000002a3", "HANGUP CAUSE: 127") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:3] ExecIf("PJSIP/2210-000002a3", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:4] NoOp("PJSIP/2210-000002a3", "MASTER CHANNEL: 1546013962.759 = 1546013962.759") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:5] GotoIf("PJSIP/2210-000002a3", "0?return") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:6] Set("PJSIP/2210-000002a3", "__CRM_HANGUP=1") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:7] AGI("PJSIP/2210-000002a3", "sangomacrm.agi") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: LINKEDID: 1546013962.759
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: SOURCE: 2210
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: DESTINATION: 1715828XXXX
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: DIRECTION: OUTBOUND
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: EXTTOCALL:
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: START
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] res_agi.c: sangomacrm.agi: SCRIPT: php /var/www/html/admin/modules/sangomacrm/importOne.php 'eyJ1dWlkIjoiMTU0NjAxMzk2Mi43NTkiLCJzb3VyY2UiOiIyMjEwIiwiZGVzdGluYXRpb24iOiIxNzE1ODI4MTAxMCIsImRpcmVjdGlvbiI6Ik9VVEJPVU5EIiwidHlwZSI6IkVORCIsInp1bHVfcmF3X3R5cGUiOiIiLCJ6dWx1X3R5cGUiOiIiLCJ6dWx1X3VybCI6IiIsImV4dHRvY2FsbCI6IiIsImNudW0iOiI3MTU1OTg5OTI0IiwiY25hbSI6IiIsImNhbGxwb3AiOmZhbHNlLCJ2b2ljZW1haWwiOiIifQ==' > /dev/null 2>&1 &
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] res_agi.c: <PJSIP/2210-000002a3>AGI Script sangomacrm.agi completed, returning 0
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [[email protected]:8] Return("PJSIP/2210-000002a3", "") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/2210-000002a3'
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] app_stack.c: PJSIP/2210-000002a3 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-12-28 16:19:41] VERBOSE[29831][C-00000110] app_mixmonitor.c: MixMonitor close filestream (mixed)
[2018-12-28 16:19:41] VERBOSE[29831][C-00000110] app_mixmonitor.c: End MixMonitor Recording PJSIP/2210-000002a3
[2018-12-28 16:19:47] WARNING[16445] res_pjsip_registrar.c: Endpoint 'anonymous' has no configured AORs

Any ideas?

Maybe a misconfigured outbound route?
My guess without a log from a failing call.

I think based on the repeated logs that it’s maybe the CRM module that’s causing the hangups.

Seems to be happening a lot, with most calls disconnecting at the time the person answers.

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