Outbound Calls Disconnect or Go To Inbound Route (IVR)

1reason · December 28, 2018, 4:51pm

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 [828XXXX@from-internal: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 [s@macro-user-callerid: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 [s@macro-user-callerid:2] Set("PJSIP/2210-000002a3", "AMPUSER=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("PJSIP/2210-000002a3", "0?report") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-user-callerid: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 [s@macro-user-callerid:5] Set("PJSIP/2210-000002a3", "AMPUSER=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("PJSIP/2210-000002a3", "0?limit") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-user-callerid: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 [s@macro-user-callerid: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 [s@macro-user-callerid:9] GotoIf("PJSIP/2210-000002a3", "0?report") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/2210-000002a3", "AMPUSERCID=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-user-callerid:11] Set("PJSIP/2210-000002a3", "__DIAL_OPTIONS=HhTtr") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-user-callerid: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 [s@macro-user-callerid:13] GotoIf("PJSIP/2210-000002a3", "0?limit") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-user-callerid: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 [s@macro-user-callerid: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 [s@macro-user-callerid: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 [s@macro-user-callerid: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 [s@macro-user-callerid: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 [s@macro-user-callerid:37] Set("PJSIP/2210-000002a3", "CALLERID(number)=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-user-callerid: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 [s@macro-user-callerid:39] GotoIf("PJSIP/2210-000002a3", "0?cnum") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-user-callerid: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 [s@macro-user-callerid:41] Set("PJSIP/2210-000002a3", "CDR(cnum)=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-user-callerid:42] Set("PJSIP/2210-000002a3", "CHANNEL(language)=en") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [828XXXX@from-internal: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 [s@sub-record-check:1] GotoIf("PJSIP/2210-000002a3", "0?initialized") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/2210-000002a3", "__REC_STATUS=INITIALIZED") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/2210-000002a3", "NOW=1546013962") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/2210-000002a3", "__DAY=28") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/2210-000002a3", "__MONTH=12") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/2210-000002a3", "__YEAR=2018") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/2210-000002a3", "__TIMESTR=20181228-161922") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/2210-000002a3", "__FROMEXTEN=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/2210-000002a3", "__MON_FMT=WAV") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/2210-000002a3", "Recordings initialized") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@sub-record-check: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 [s@sub-record-check: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 [s@sub-record-check: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 [s@sub-record-check: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 [s@sub-record-check: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 [out@sub-record-check: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 [out@sub-record-check:2] Set("PJSIP/2210-000002a3", "RECMODE=dontcare") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [out@sub-record-check: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 [out@sub-record-check: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 [recordcheck@sub-record-check: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 [recordcheck@sub-record-check: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 [recordcheck@sub-record-check: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 [recordcheck@sub-record-check: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 [recordcheck@sub-record-check: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 [recordcheck@sub-record-check: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 [recordcheck@sub-record-check: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 [recordcheck@sub-record-check:19] Set("PJSIP/2210-000002a3", "__MIXMON_ID=0x7f5bf002ce50") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [recordcheck@sub-record-check: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 [recordcheck@sub-record-check:21] Set("PJSIP/2210-000002a3", "__REC_STATUS=RECORDING") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [recordcheck@sub-record-check: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 [recordcheck@sub-record-check: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 [out@sub-record-check:8] Return("PJSIP/2210-000002a3", "") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [828XXXX@from-internal: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 [828XXXX@from-internal: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 [828XXXX@from-internal:5] Set("PJSIP/2210-000002a3", "MOHCLASS=default") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [828XXXX@from-internal: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 [828XXXX@from-internal:7] Set("PJSIP/2210-000002a3", "_NODEST=") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [828XXXX@from-internal: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 [s@macro-dialout-trunk:1] Set("PJSIP/2210-000002a3", "DIAL_TRUNK=2") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk:6] GotoIf("PJSIP/2210-000002a3", "0?disabletrunk,1") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk:7] Set("PJSIP/2210-000002a3", "DIAL_NUMBER=1715828XXXX") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk:11] GotoIf("PJSIP/2210-000002a3", "0?nomax") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("PJSIP/2210-000002a3", "0?chanfull") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk:13] GotoIf("PJSIP/2210-000002a3", "0?skipoutcid") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk:14] Macro("PJSIP/2210-000002a3", "outbound-callerid,2") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("PJSIP/2210-000002a3", "2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("PJSIP/2210-000002a3", "") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("PJSIP/2210-000002a3", "off") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid:12] Set("PJSIP/2210-000002a3", "USEROUTCID=") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-outbound-callerid:13] Set("PJSIP/2210-000002a3", "EMERGENCYCID=") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-outbound-callerid:14] Set("PJSIP/2210-000002a3", "TRUNKOUTCID=7155989924") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid: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 [s@macro-outbound-callerid:26] Set("PJSIP/2210-000002a3", "CDR(outbound_cnam)=") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk:16] Set("PJSIP/2210-000002a3", "OUTNUM=1715828XXXX") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk:17] Set("PJSIP/2210-000002a3", "custom=PJSIP") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/2210-000002a3", "") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk:21] GotoIf("PJSIP/2210-000002a3", "0?skipcrm") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk:22] Set("PJSIP/2210-000002a3", "__CRM_DIRECTION=OUTBOUND") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk:23] Set("PJSIP/2210-000002a3", "__CRM_DESTINATION=1715828XXXX") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk:24] Set("PJSIP/2210-000002a3", "__CRM_SOURCE=2210") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk:27] NoOp("PJSIP/2210-000002a3", "CRM Finished") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk:28] GotoIf("PJSIP/2210-000002a3", "0?bypass,1") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk: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 [s@macro-dialout-trunk:32] GotoIf("PJSIP/2210-000002a3", "0?customtrunk") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-dialout-trunk:33] Dial("PJSIP/2210-000002a3", "PJSIP/1715828XXXX@Alcazar_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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders:4] Set("PJSIP/Alcazar_Networks_outbound-000002a4", "SIPHEADERKEYS=") in new stack
[2018-12-28 16:19:22] VERBOSE[29830][C-00000110] pbx.c: Executing [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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/1715828XXXX@Alcazar_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 [h@from-internal:1] Macro("PJSIP/2210-000002a3", "hangupcall") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [s@macro-hangupcall: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 [s@macro-hangupcall: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 [s@macro-hangupcall: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 [s@macro-hangupcall: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 [s@macro-hangupcall: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 [s@crm-hangup: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 [s@crm-hangup:2] NoOp("PJSIP/2210-000002a3", "HANGUP CAUSE: 127") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [s@crm-hangup: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 [s@crm-hangup: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 [s@crm-hangup:5] GotoIf("PJSIP/2210-000002a3", "0?return") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/2210-000002a3", "__CRM_HANGUP=1") in new stack
[2018-12-28 16:19:41] VERBOSE[29830][C-00000110] pbx.c: Executing [s@crm-hangup: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 [s@crm-hangup: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

1reason · January 1, 2019, 7:08pm

Any ideas?

arielgrin · January 1, 2019, 7:16pm

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

1reason · January 4, 2019, 11:13pm

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.

system · January 11, 2019, 11:13pm

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