Outbound calls drop @ 6 seconds


(Mike) #1

Happened two weeks ago also but I restarted the system and it has been fine until today? I had updated some modules at that time & figured that had caused the issue. The incoming calls work properly. I restarted both FreePBX & my PFSesnse firewall / router but the problem persists? I will provide my Asterisk log file if it helps?

[2019-08-26 16:19:31] VERBOSE[10764][C-0000001f] netsock2.c: Using SIP RTP TOS bits 184
[2019-08-26 16:19:31] VERBOSE[10764][C-0000001f] netsock2.c: Using SIP RTP CoS mark 5
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [XXXXXXX@from-internal:1] Macro(“SIP/100-00000049”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/100-00000049”, “TOUCH_MONITOR=1566850771.111”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/100-00000049”, “AMPUSER=100”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/100-00000049”, “0?report”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/100-00000049”, “1?Set(REALCALLERIDNUM=100)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/100-00000049”, “AMPUSER=100”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/100-00000049”, “0?limit”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/100-00000049”, “AMPUSERCIDNAME= Plumbing”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“SIP/100-00000049”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“SIP/100-00000049”, “0?report”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/100-00000049”, “AMPUSERCID=100”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:11] Set(“SIP/100-00000049”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:12] Set(“SIP/100-00000049”, “CALLERID(all)=” Plumbing" <100>") in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“SIP/100-00000049”, “0?limit”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“SIP/100-00000049”, “1?Set(GROUP(concurrency_limit)=100)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:15] ExecIf(“SIP/100-00000049”, “1?Set(CHANNEL(language)=en)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“SIP/100-00000049”, “Macro Depth is 1”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“SIP/100-00000049”, “1?report2:macroerror”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“SIP/100-00000049”, “1?continue”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:37] Set(“SIP/100-00000049”, “CALLERID(number)=100”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:38] Set(“SIP/100-00000049”, “CALLERID(name)= Plumbing”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:39] GotoIf(“SIP/100-00000049”, “0?cnum”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:40] Set(“SIP/100-00000049”, “CDR(cnam)= Plumbing”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:41] Set(“SIP/100-00000049”, “CDR(cnum)=100”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-user-callerid:42] Set(“SIP/100-00000049”, “CHANNEL(language)=en”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [XXXXXXX@from-internal:2] Gosub(“SIP/100-00000049”, “sub-record-check,s,1(out,770,dontcare)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/100-00000049”, “0?initialized”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/100-00000049”, “__REC_STATUS=INITIALIZED”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/100-00000049”, “NOW=1566850771”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/100-00000049”, “__DAY=26”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/100-00000049”, “__MONTH=08”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/100-00000049”, “__YEAR=2019”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/100-00000049”, “__TIMESTR=20190826-161931”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/100-00000049”, “__FROMEXTEN=100”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/100-00000049”, “__MON_FMT=wav”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/100-00000049”, “Recordings initialized”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/100-00000049”, “0?Set(ARG3=dontcare)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/100-00000049”, “REC_POLICY_MODE_SAVE=”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/100-00000049”, “0?Set(REC_STATUS=NO)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/100-00000049”, “3?checkaction”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/100-00000049”, “1?sub-record-check,out,1”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx_builtins.c: Goto (sub-record-check,out,1)
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [out@sub-record-check:1] NoOp(“SIP/100-00000049”, “Outbound Recording Check from 100 to 7703288283”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [out@sub-record-check:2] Set(“SIP/100-00000049”, “RECMODE=dontcare”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [out@sub-record-check:3] ExecIf(“SIP/100-00000049”, “1?Goto(routewins)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx_builtins.c: Goto (sub-record-check,out,7)
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [out@sub-record-check:7] Gosub(“SIP/100-00000049”, “recordcheck,1(dontcare,out,XXXXXXX)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/100-00000049”, “Starting recording check against dontcare”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/100-00000049”, “dontcare”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/100-00000049”, “”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [out@sub-record-check:8] Return(“SIP/100-00000049”, “”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [770@from-internal:3] ExecIf(“SIP/100-00000049”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [770@from-internal:4] Set(“SIP/100-00000049”, “MOHCLASS=default”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [770@from-internal:5] ExecIf(“SIP/100-00000049”, “1?Set(TRUNKCIDOVERRIDE=770)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [770@from-internal:6] Set(“SIP/100-00000049”, “_NODEST=”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [770@from-internal:7] Macro(“SIP/100-00000049”, “dialout-trunk,1,1770,off”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“SIP/100-00000049”, “DIAL_TRUNK=1”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf(“SIP/100-00000049”, “0?Set(DIAL_OPTIONS=Hhtr)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf(“SIP/100-00000049”, “0?sub-pincheck,s,1()”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf(“SIP/100-00000049”, “0?Set(CALLERID(num)=100)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf(“SIP/100-00000049”, “0?disabletrunk,1”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:6] Set(“SIP/100-00000049”, “DIAL_NUMBER=17703288283”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:7] Set(“SIP/100-00000049”, “DIAL_TRUNK_OPTIONS=HhTtr”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:8] Set(“SIP/100-00000049”, “OUTBOUND_GROUP=OUT_1”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:9] Set(“SIP/100-00000049”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf(“SIP/100-00000049”, “0?nomax”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf(“SIP/100-00000049”, “0?chanfull”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf(“SIP/100-00000049”, “0?skipoutcid”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:13] Macro(“SIP/100-00000049”, “outbound-callerid,1”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp(“SIP/100-00000049”, “100”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp(“SIP/100-00000049”, “”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp(“SIP/100-00000049”, “off”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf(“SIP/100-00000049”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf(“SIP/100-00000049”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:6] ExecIf(“SIP/100-00000049”, “0?Set(REALCALLERIDNUM=100)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:7] ExecIf(“SIP/100-00000049”, “0?Set(AMPUSER=100)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:8] GotoIf(“SIP/100-00000049”, “1?normcid”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx_builtins.c: Goto (macro-outbound-callerid,s,12)
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:12] Set(“SIP/100-00000049”, “USEROUTCID=”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:13] Set(“SIP/100-00000049”, “EMERGENCYCID=”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:14] Set(“SIP/100-00000049”, “TRUNKOUTCID=770”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:15] GotoIf(“SIP/100-00000049”, “1?trunkcid”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx_builtins.c: Goto (macro-outbound-callerid,s,21)
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:21] ExecIf(“SIP/100-00000049”, “1?Set(CALLERID(all)=770)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf(“SIP/100-00000049”, “0?Set(CALLERID(all)=)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf(“SIP/100-00000049”, “1?Set(CALLERID(all)=770)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:24] ExecIf(“SIP/100-00000049”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:25] ExecIf(“SIP/100-00000049”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:26] Set(“SIP/100-00000049”, “CDR(outbound_cnum)=770”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:27] Set(“SIP/100-00000049”, “CDR(outbound_cnam)=”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf(“SIP/100-00000049”, “1?sub-flp-1,s,1()”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-flp-1:1] ExecIf(“SIP/100-00000049”, “0?Set(TARGET_FLP_1=1770)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-flp-1:2] GotoIf(“SIP/100-00000049”, “0?match”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-flp-1:3] ExecIf(“SIP/100-00000049”, “0?Set(TARGET_FLP_1=11770)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-flp-1:4] GotoIf(“SIP/100-00000049”, “0?match”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@sub-flp-1:5] ExecIf(“SIP/100-00000049”, “1?Return()”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:15] Set(“SIP/100-00000049”, “OUTNUM=17703288283”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:16] Set(“SIP/100-00000049”, “custom=SIP/plumbing”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf(“SIP/100-00000049”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf(“SIP/100-00000049”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:19] Macro(“SIP/100-00000049”, “dialout-trunk-predial-hook,”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/100-00000049”, “”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf(“SIP/100-00000049”, “0?bypass,1”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf(“SIP/100-00000049”, “1?Set(CONNECTEDLINE(num,i)=1770)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf(“SIP/100-00000049”, “1?Set(CONNECTEDLINE(name,i)=CID:770)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:23] ExecIf(“SIP/100-00000049”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)770)”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:24] GotoIf(“SIP/100-00000049”, “0?customtrunk”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:25] Dial(“SIP/100-00000049”, “SIP/plumbing/1770,300,Tb(func-apply-sipheaders^s^1,(1))”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] netsock2.c: Using SIP RTP TOS bits 184
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] netsock2.c: Using SIP RTP CoS mark 5
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] app_stack.c: SIP/plumbing-0000004a Internal Gosub(func-apply-sipheaders,s,1(1)) start
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“SIP/plumbing-0000004a”, “Applying SIP Headers to channel SIP/plumbing-0000004a”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“SIP/plumbing-0000004a”, “TECH=SIP”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“SIP/plumbing-0000004a”, “SIPHEADERKEYS=”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:4] While(“SIP/plumbing-0000004a”, “0”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] app_while.c: Jumping to priority 11
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:12] Return(“SIP/plumbing-0000004a”, “”) in new stack
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] app_stack.c: Spawn extension (from-trunk, 7703288283, 1) exited non-zero on ‘SIP/plumbing-0000004a’
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] app_stack.c: SIP/plumbing-0000004a Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL=
[2019-08-26 16:19:31] VERBOSE[25350][C-0000001f] app_dial.c: Called SIP/plumbing/1770
[2019-08-26 16:19:34] VERBOSE[25350][C-0000001f] app_dial.c: SIP/plumbing-0000004a is making progress passing it to SIP/100-00000049
[2019-08-26 16:19:38] VERBOSE[25350][C-0000001f] app_dial.c: SIP/plumbing-0000004a answered SIP/100-00000049
[2019-08-26 16:19:38] VERBOSE[25376][C-0000001f] bridge_channel.c: Channel SIP/plumbing-0000004a joined ‘simple_bridge’ basic-bridge <91aed4e7-bd8e-4162-b64e-9db00ec095b2>
[2019-08-26 16:19:38] VERBOSE[25350][C-0000001f] bridge_channel.c: Channel SIP/100-00000049 joined ‘simple_bridge’ basic-bridge <91aed4e7-bd8e-4162-b64e-9db00ec095b2>
[2019-08-26 16:19:44] WARNING[10764] chan_sip.c: Retransmission timeout reached on transmission 167e2035c70c952@192.168.20.114 for seqno 21 (Critical Response) – See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 6399ms with no response
[2019-08-26 16:19:44] WARNING[10764] chan_sip.c: Hanging up call 167e2035c70c952@192.168.20.114 - no reply to our critical packet (see https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions).
[2019-08-26 16:19:44] VERBOSE[25350][C-0000001f] bridge_channel.c: Channel SIP/100-00000049 left ‘simple_bridge’ basic-bridge <91aed4e7-bd8e-4162-b64e-9db00ec095b2>
[2019-08-26 16:19:44] VERBOSE[25350][C-0000001f] app_macro.c: Spawn extension (macro-dialout-trunk, s, 25) exited non-zero on ‘SIP/100-00000049’ in macro ‘dialout-trunk’
[2019-08-26 16:19:44] VERBOSE[25350][C-0000001f] pbx.c: Spawn extension (from-internal, 7703288283, 7) exited non-zero on ‘SIP/100-00000049’
[2019-08-26 16:19:44] VERBOSE[25350][C-0000001f] pbx.c: Executing [h@from-internal:1] Macro(“SIP/100-00000049”, “hangupcall”) in new stack
[2019-08-26 16:19:44] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/100-00000049”, “1?theend”) in new stack
[2019-08-26 16:19:44] VERBOSE[25350][C-0000001f] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-08-26 16:19:44] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/100-00000049”, “0?Set(CDR(recordingfile)=)”) in new stack
[2019-08-26 16:19:44] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“SIP/100-00000049”, "SIP/plumbing-0000004a montior file= ") in new stack
[2019-08-26 16:19:44] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“SIP/100-00000049”, “1?skipagi”) in new stack
[2019-08-26 16:19:44] VERBOSE[25350][C-0000001f] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2019-08-26 16:19:44] VERBOSE[25350][C-0000001f] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“SIP/100-00000049”, “”) in new stack
[2019-08-26 16:19:44] VERBOSE[25350][C-0000001f] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘SIP/100-00000049’ in macro ‘hangupcall’
[2019-08-26 16:19:44] VERBOSE[25350][C-0000001f] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/100-00000049’
[2019-08-26 16:19:44] VERBOSE[25376][C-0000001f] bridge_channel.c: Channel SIP/plumbing-0000004a left ‘simple_bridge’ basic-bridge <91aed4e7-bd8e-4162-b64e-9db00ec095b2>
[2019-08-26 16:20:03] VERBOSE[10609] chan_sip.c: Extension Changed 9922*100[restapps-device-hints] new state Idle for Notify User 100


(Dave Burgess) #2

It’s kind of right there in the post. Did you look at the Wiki article.


(Mike) #3

I read it but I have no clue what needs to be done to find the problem. Sorry for my ignorance in this matter, I am a plumber trying to run my own system.


(Dave Burgess) #4

It means there is/are at least one place where you NAT settings are messed up.

Here’s the deal with NAT - if you say “NAT = YES” in the right places (on your trunks through Advanced SIP settings and extensions, primarily) then when calls go out, they tell the system at the other end of the call “Hey, don’t use the actual non-routable address I know about, use my routable address and I’ll handle the rest.”

Unfortunately, there’s no way for us to know where the right places are.

Let’s start at the top.
Under Advanced Settings, look for SIP settings. You’ll need to configure this correctly. You need to identify your local networks and your external (routable) address.

After that, you may need to do the same thing on your extensions. In general, assume NAT = ‘Comedia’ or ‘Yes’. on extensions. It may require a little tongue holding to get it right. Remember, though, unless you tell us how your network is set up, there are just too many variables for us to be able to guess right.

Once that’s done, you need to look at your external firewall/router settings to make sure the RTP packet the error messages were talking about are getting to the server correctly.


(Mike) #5

Thanks for your help! Just so we are on the same page this system has been running unchanged for over a year now. All of my settings that you advised to check remain as they were from the beginning. My Nat Mode in the extensions page is set to (No) “Set to No if the device will always be internal.[nat]” Is that correct?


(Mike) #6

Found the problem. Sangoma S500 had connected to IP address that wasn’t right. Works now!


(system) closed #7

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