Outbound analog calls automatically disconnect - but only when router connected to ISP


(Leodoggone) #1

I have this weird problem - when FreePBX is connected via a router to ISP1, all outbound calls on analog get disconnected. The destination phone still rings but the line has already been disconnected from FreePBX.

Here’s the weird part. When I plug the WAN port of my router into another ISP, everything works. When I DISCONNECTED the WAN port of my router (FreePBX is not connected to internet), it works. When I plug the router’s WAN port back into ISP1, outbound calls are disconnected.

How can the router’s WAN port cause an outbound ANALOG call to disconnect? It should have no relationship to analog calls. I need to fix this as ISP1 is a fast connection and my previous ISP is too slow. I am by no means an expert and would appreciate some help.


(Itzik) #2

A call trace should tell you what’s up.

Please post a pastebin link, see instructions: https://wiki.freepbx.org/display/SUP/Providing+Great+Debug#ProvidingGreatDebug-AsteriskLogs-PartII


(Leodoggone) #3

Here is the log file.

[2020-10-14 18:21:13] VERBOSE[7457] pbx_variables.c: Setting global variable ‘SIPDOMAIN’ to ‘10.0.51.5’
[2020-10-14 18:21:13] VERBOSE[7457] netsock2.c: Using SIP RTP Audio TOS bits 184
[2020-10-14 18:21:13] VERBOSE[7457] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2020-10-14 18:21:13] VERBOSE[7457] netsock2.c: Using SIP RTP Audio CoS mark 5
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [0422000338@from-internal:1] Macro(“PJSIP/803-0000003a”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/803-0000003a”, “TOUCH_MONITOR=1602660073.148”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/803-0000003a”, “AMPUSER=803”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:3] Set(“PJSIP/803-0000003a”, “HOTDESCKCHAN=803-0000003a”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:4] Set(“PJSIP/803-0000003a”, “HOTDESKEXTEN=803”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/803-0000003a”, “HOTDESKCALL=0”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:6] ExecIf(“PJSIP/803-0000003a”, “0?Set(HOTDESKCALL=1)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:7] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERID(name)=)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“PJSIP/803-0000003a”, “0?report”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:9] ExecIf(“PJSIP/803-0000003a”, “1?Set(REALCALLERIDNUM=803)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/803-0000003a”, “AMPUSER=803”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:11] GotoIf(“PJSIP/803-0000003a”, “0?limit”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:12] Set(“PJSIP/803-0000003a”, “AMPUSERCIDNAME=803 Kuan”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“PJSIP/803-0000003a”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:14] GotoIf(“PJSIP/803-0000003a”, “0?report”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:15] Set(“PJSIP/803-0000003a”, “AMPUSERCID=803”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:16] Set(“PJSIP/803-0000003a”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:17] Set(“PJSIP/803-0000003a”, “CALLERID(all)=“803 Kuan” <803>”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:18] Set(“PJSIP/803-0000003a”, “HOTDESCKCHAN=803-0000003a”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:19] Set(“PJSIP/803-0000003a”, “HOTDESKEXTEN=803”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:20] Set(“PJSIP/803-0000003a”, “HOTDESKCALL=0”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:21] ExecIf(“PJSIP/803-0000003a”, “0?Set(HOTDESKCALL=1)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:22] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERID(name)=)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:23] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERID(all)=”" < >)") in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:24] ExecIf(“PJSIP/803-0000003a”, “0?Set(CUSDIAL=)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:25] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERID(all)=“803 Kuan” <803>)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:26] GotoIf(“PJSIP/803-0000003a”, “0?limit”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:27] ExecIf(“PJSIP/803-0000003a”, “1?Set(GROUP(concurrency_limit)=803)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:28] ExecIf(“PJSIP/803-0000003a”, “0?Set(CHANNEL(language)=)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:29] NoOp(“PJSIP/803-0000003a”, “Macro Depth is 1”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:30] GotoIf(“PJSIP/803-0000003a”, “1?report2:macroerror”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx_builtins.c: Goto (macro-user-callerid,s,31)
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:31] GotoIf(“PJSIP/803-0000003a”, “1?continue”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx_builtins.c: Goto (macro-user-callerid,s,50)
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:50] Set(“PJSIP/803-0000003a”, “CALLERID(number)=803”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:51] Set(“PJSIP/803-0000003a”, “CALLERID(name)=803 Kuan”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:52] GotoIf(“PJSIP/803-0000003a”, “0?cnum”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:53] Set(“PJSIP/803-0000003a”, “CDR(cnam)=803 Kuan”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:54] Set(“PJSIP/803-0000003a”, “CDR(cnum)=803”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-user-callerid:55] Set(“PJSIP/803-0000003a”, “CHANNEL(language)=en_AU”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [0422000338@from-internal:2] Gosub(“PJSIP/803-0000003a”, “sub-record-check,s,1(out,0422000338,dontcare)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/803-0000003a”, “0?initialized”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/803-0000003a”, “__REC_STATUS=INITIALIZED”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/803-0000003a”, “NOW=1602660073”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/803-0000003a”, “__DAY=14”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/803-0000003a”, “__MONTH=10”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/803-0000003a”, “__YEAR=2020”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/803-0000003a”, “__TIMESTR=20201014-182113”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/803-0000003a”, “__FROMEXTEN=803”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/803-0000003a”, “__MON_FMT=wav”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/803-0000003a”, “Recordings initialized”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/803-0000003a”, “0?Set(ARG3=dontcare)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/803-0000003a”, “REC_POLICY_MODE_SAVE=”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/803-0000003a”, “0?Set(REC_STATUS=NO)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/803-0000003a”, “3?checkaction”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx_builtins.c: Goto (sub-record-check,s,17)
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/803-0000003a”, “1?sub-record-check,out,1”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx_builtins.c: Goto (sub-record-check,out,1)
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [out@sub-record-check:1] NoOp(“PJSIP/803-0000003a”, “Outbound Recording Check from 803 to 0422000338”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [out@sub-record-check:2] Set(“PJSIP/803-0000003a”, “RECMODE=dontcare”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [out@sub-record-check:3] ExecIf(“PJSIP/803-0000003a”, “1?Goto(routewins)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx_builtins.c: Goto (sub-record-check,out,7)
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [out@sub-record-check:7] Gosub(“PJSIP/803-0000003a”, “recordcheck,1(dontcare,out,0422000338)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/803-0000003a”, “Starting recording check against dontcare”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/803-0000003a”, “dontcare”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“PJSIP/803-0000003a”, “”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [out@sub-record-check:8] Return(“PJSIP/803-0000003a”, “”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [0422000338@from-internal:3] ExecIf(“PJSIP/803-0000003a”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [0422000338@from-internal:4] Set(“PJSIP/803-0000003a”, “_ROUTEID=6”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [0422000338@from-internal:5] Set(“PJSIP/803-0000003a”, “_ROUTENAME=Call STD & Mobile”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [0422000338@from-internal:6] Set(“PJSIP/803-0000003a”, “MOHCLASS=default”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [0422000338@from-internal:7] Set(“PJSIP/803-0000003a”, “_CALLERIDNAMEINTERNAL=803 Kuan”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [0422000338@from-internal:8] Set(“PJSIP/803-0000003a”, “_CALLERIDNUMINTERNAL=803”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [0422000338@from-internal:9] Set(“PJSIP/803-0000003a”, “_EMAILNOTIFICATION=FALSE”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [0422000338@from-internal:10] Set(“PJSIP/803-0000003a”, “_NODEST=”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [0422000338@from-internal:11] Macro(“PJSIP/803-0000003a”, “dialout-trunk,3,0422000338,on”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“PJSIP/803-0000003a”, “DIAL_TRUNK=3”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf(“PJSIP/803-0000003a”, “0?Set(DIAL_OPTIONS=Hhtr)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf(“PJSIP/803-0000003a”, “0?sub-pincheck,s,1()”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERID(num)=803)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf(“PJSIP/803-0000003a”, “0?disabletrunk,1”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“PJSIP/803-0000003a”, “DIAL_NUMBER=0422000338”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:7] Set(“PJSIP/803-0000003a”, “DIAL_TRUNK_OPTIONS=HhTtr”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:8] Set(“PJSIP/803-0000003a”, “OUTBOUND_GROUP=OUT_3”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:9] Set(“PJSIP/803-0000003a”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf(“PJSIP/803-0000003a”, “0?nomax”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf(“PJSIP/803-0000003a”, “0?chanfull”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf(“PJSIP/803-0000003a”, “0?skipoutcid”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:13] Macro(“PJSIP/803-0000003a”, “outbound-callerid,3”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp(“PJSIP/803-0000003a”, “803”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp(“PJSIP/803-0000003a”, “”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp(“PJSIP/803-0000003a”, “off”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:6] Set(“PJSIP/803-0000003a”, “HOTDESCKCHAN=803-0000003a”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:7] Set(“PJSIP/803-0000003a”, “HOTDESKEXTEN=803”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:8] Set(“PJSIP/803-0000003a”, “HOTDESKCALL=0”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf(“PJSIP/803-0000003a”, “0?Set(HOTDESKCALL=1)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERID(name)=)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:11] Set(“PJSIP/803-0000003a”, “ALLOWTHISROUTE=NO”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf(“PJSIP/803-0000003a”, “0?Set(ALLOWTHISROUTE=YES)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf(“PJSIP/803-0000003a”, “0?Hangup()”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf(“PJSIP/803-0000003a”, “0?Set(REALCALLERIDNUM=803)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf(“PJSIP/803-0000003a”, “0?Set(AMPUSER=803)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf(“PJSIP/803-0000003a”, “1?normcid”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:20] Set(“PJSIP/803-0000003a”, “USEROUTCID=”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:21] Set(“PJSIP/803-0000003a”, “EMERGENCYCID=”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf(“PJSIP/803-0000003a”, “0?Set(EMERGENCYCID=)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:23] Set(“PJSIP/803-0000003a”, “TRUNKOUTCID=61361283088”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf(“PJSIP/803-0000003a”, “1?trunkcid”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf(“PJSIP/803-0000003a”, “1?Set(CALLERID(all)=61361283088)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERID(all)=)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERID(all)=)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERID(all)=803)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:34] Set(“PJSIP/803-0000003a”, “TIOHIDE=no”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf(“PJSIP/803-0000003a”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:39] Set(“PJSIP/803-0000003a”, “CDR(outbound_cnum)=61361283088”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-outbound-callerid:40] Set(“PJSIP/803-0000003a”, “CDR(outbound_cnam)=”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf(“PJSIP/803-0000003a”, “0?sub-flp-3,s,1()”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:15] Set(“PJSIP/803-0000003a”, “OUTNUM=0422000338”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:16] Set(“PJSIP/803-0000003a”, “custom=DAHDI/2”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf(“PJSIP/803-0000003a”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf(“PJSIP/803-0000003a”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:19] Macro(“PJSIP/803-0000003a”, “dialout-trunk-predial-hook,”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“PJSIP/803-0000003a”, “”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf(“PJSIP/803-0000003a”, “0?skipcrm”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:21] Set(“PJSIP/803-0000003a”, “__CRM_DIRECTION=OUTBOUND”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:22] Set(“PJSIP/803-0000003a”, “__CRM_DESTINATION=0422000338”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:23] Set(“PJSIP/803-0000003a”, “__CRM_SOURCE=803”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:24] AGI(“PJSIP/803-0000003a”, “sangomacrm.agi”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] res_agi.c: <PJSIP/803-0000003a>AGI Script sangomacrm.agi completed, returning 0
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:25] Set(“PJSIP/803-0000003a”, “CHANNEL(hangup_handler_push)=crm-hangup,s,1”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp(“PJSIP/803-0000003a”, “CRM Finished”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf(“PJSIP/803-0000003a”, “0?bypass,1”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf(“PJSIP/803-0000003a”, “1?Set(CONNECTEDLINE(num,i)=0422000338)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf(“PJSIP/803-0000003a”, “1?Set(CONNECTEDLINE(name,i)=CID:61361283088)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf(“PJSIP/803-0000003a”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)61361283088)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf(“PJSIP/803-0000003a”, “0?customtrunk”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf(“PJSIP/803-0000003a”, “0?Set(DIAL_TRUNK_OPTIONS=)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:33] Set(“PJSIP/803-0000003a”, “HASH(__SIPHEADERS,Alert-Info)=unset”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@macro-dialout-trunk:34] Dial(“PJSIP/803-0000003a”, “DAHDI/2/0422000338,300,Tb(func-apply-sipheaders^s^1,(3))U(sub-send-obroute-email^0422000338^0422000338^3^1602660073^^61361283088)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] app_stack.c: DAHDI/2-1 Internal Gosub(func-apply-sipheaders,s,1(3)) start
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“DAHDI/2-1”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“DAHDI/2-1”, “Applying SIP Headers to channel DAHDI/2-1”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“DAHDI/2-1”, “TECH=DAHDI”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“DAHDI/2-1”, “SIPHEADERKEYS=Alert-Info”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:5] While(“DAHDI/2-1”, “1”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“DAHDI/2-1”, “sipheader=unset”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf(“DAHDI/2-1”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“DAHDI/2-1”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“DAHDI/2-1”, “0?Set(sipheader=http://127.0.0.1;info=unset)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“DAHDI/2-1”, “0?Set(sipheader=http://127.0.0.1unset)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“DAHDI/2-1”, “0?SIPAddHeader(Alert-Info:unset)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf(“DAHDI/2-1”, “0?Set(PJSIP_HEADER(add,Alert-Info)=unset)”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile(“DAHDI/2-1”, “”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:5] While(“DAHDI/2-1”, “0”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] pbx.c: Executing [s@func-apply-sipheaders:14] Return(“DAHDI/2-1”, “”) in new stack
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] app_stack.c: Spawn extension (from-analog, 0422000338, 1) exited non-zero on ‘DAHDI/2-1’
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] app_stack.c: DAHDI/2-1 Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL=
[2020-10-14 18:21:13] VERBOSE[31706][C-00000032] app_dial.c: Called DAHDI/2/0422000338
[2020-10-14 18:21:17] VERBOSE[31706][C-00000032] app_dial.c: DAHDI/2-1 answered PJSIP/803-0000003a
[2020-10-14 18:21:17] VERBOSE[31706][C-00000032] app_stack.c: DAHDI/2-1 Internal Gosub(sub-send-obroute-email,s,1(0422000338,0422000338,3,1602660073,61361283088)) start
[2020-10-14 18:21:17] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-send-obroute-email:1] GotoIf(“DAHDI/2-1”, “0?sendEmail”) in new stack
[2020-10-14 18:21:17] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-send-obroute-email:2] NoOp(“DAHDI/2-1”, “email notifications disabled…exiting.”) in new stack
[2020-10-14 18:21:17] VERBOSE[31706][C-00000032] pbx.c: Executing [s@sub-send-obroute-email:3] Return(“DAHDI/2-1”, “”) in new stack
[2020-10-14 18:21:17] VERBOSE[31706][C-00000032] app_stack.c: Spawn extension (from-analog, , 1) exited non-zero on ‘DAHDI/2-1’
[2020-10-14 18:21:17] VERBOSE[31706][C-00000032] app_stack.c: DAHDI/2-1 Internal Gosub(sub-send-obroute-email,s,1(0422000338,0422000338,3,1602660073,61361283088)) complete GOSUB_RETVAL=
[2020-10-14 18:21:17] VERBOSE[31718][C-00000032] bridge_channel.c: Channel DAHDI/2-1 joined ‘simple_bridge’ basic-bridge
[2020-10-14 18:21:17] VERBOSE[31706][C-00000032] bridge_channel.c: Channel PJSIP/803-0000003a joined ‘simple_bridge’ basic-bridge


(Itzik) #4

This is not the complete call trace. As mentioned above, please post post a pastebin link (see instructions how, in that same link) the forum has a limit of how many characters you can post in a single post, also, it’s hard to read it.


(Leodoggone) #5

Hi PitzKey,

Thanks for replying. Here is the pastebin. I have included 2 call logs for your perusal. Thanking you in advance.

https://pastebin.freepbx.org/view/e3d867c7

https://pastebin.freepbx.org/view/cd3b82bd


(Itzik) #6

The first log shows that the disconnect came from Dahdi 22 seconds after the call was connected.
Why it was disconnected? I don’t know, I didn’t have any experience with Dahdi. Maybe someone else will be able to point you how to troubleshoot that.

The second call, 803 is the one who disconnected the call.


(Leodoggone) #7

Hi PitzKey,

Thanks for the reply. In the second call, 803 is the extension that I used to dial out. I certainly didn’t disconnect which is the strange thing. The phone just disconnects on its own.

If I unplug the router from ISP1 and plug in ISP2, outbound calls complete. Any ideas?


(system) closed #8

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