Outbound calls stopped, do not show in CDR but do in logfiles


(Mvogel4949) #1

This past Friday I had a system suddenly stop processing outbound calls via PRI. The calls are absent from the CDR but I do see the attempts in the logfiles. I don’t see a specific reason myself but I’m hoping someone else can tell why these calls were not successful. Thank you.

2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [16193847180@from-internal:1] Macro("SIP/149-00003d04", "user-callerid,LIMIT") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/149-00003d04", "TOUCH_MONITOR=1590777419.39025") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/149-00003d04", "AMPUSER=149") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/149-00003d04", "HOTDESCKCHAN=149-00003d04") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/149-00003d04", "HOTDESKEXTEN=149") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/149-00003d04", "HOTDESKCALL=0") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/149-00003d04", "0?Set(HOTDESKCALL=1)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/149-00003d04", "0?Set(CALLERID(name)=)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/149-00003d04", "0?report") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/149-00003d04", "1?Set(REALCALLERIDNUM=149)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/149-00003d04", "AMPUSER=149") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/149-00003d04", "0?limit") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/149-00003d04", "AMPUSERCIDNAME=Nicole Vivirito") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/149-00003d04", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/149-00003d04", "0?report") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:15] Set("SIP/149-00003d04", "AMPUSERCID=149") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:16] Set("SIP/149-00003d04", "__DIAL_OPTIONS=HhTtr") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/149-00003d04", "CALLERID(all)="Nicole Vivirito" <149>") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:18] Set("SIP/149-00003d04", "HOTDESCKCHAN=149-00003d04") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:19] Set("SIP/149-00003d04", "HOTDESKEXTEN=149") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:20] Set("SIP/149-00003d04", "HOTDESKCALL=0") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:21] ExecIf("SIP/149-00003d04", "0?Set(HOTDESKCALL=1)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:22] ExecIf("SIP/149-00003d04", "0?Set(CALLERID(name)=)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("SIP/149-00003d04", "0?Set(CALLERID(all)="" <  >)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("SIP/149-00003d04", "0?Set(CALLERID(all)=)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("SIP/149-00003d04", "0?limit") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("SIP/149-00003d04", "1?Set(GROUP(concurrency_limit)=149)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("SIP/149-00003d04", "0?Set(CHANNEL(language)=)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:28] NoOp("SIP/149-00003d04", "Macro Depth is 1") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("SIP/149-00003d04", "1?report2:macroerror") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx_builtins.c: Goto (macro-user-callerid,s,30)
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("SIP/149-00003d04", "1?continue") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/149-00003d04", "CALLERID(number)=149") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:50] Set("SIP/149-00003d04", "CALLERID(name)=Nicole Vivirito") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("SIP/149-00003d04", "0?cnum") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:52] Set("SIP/149-00003d04", "CDR(cnam)=Nicole Vivirito") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:53] Set("SIP/149-00003d04", "CDR(cnum)=149") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-user-callerid:54] Set("SIP/149-00003d04", "CHANNEL(language)=en") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [16193847180@from-internal:2] Set("SIP/149-00003d04", "ROUTEUSER=149") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [16193847180@from-internal:3] Set("SIP/149-00003d04", "ROUTEUSER=149") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [16193847180@from-internal:4] GotoIf("SIP/149-00003d04", "1?notblind") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx_builtins.c: Goto (from-internal,16193847180,7)
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [16193847180@from-internal:7] GotoIf("SIP/149-00003d04", "1?restrictedroute-c4ca4238a0b923820dcc509a6f75849b,16193847180,2:outbound-allroutes,16193847180,2") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx_builtins.c: Goto (restrictedroute-c4ca4238a0b923820dcc509a6f75849b,16193847180,2)
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [16193847180@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:2] Gosub("SIP/149-00003d04", "sub-record-check,s,1(out,16193847180,dontcare)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/149-00003d04", "0?initialized") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:2] Set("SIP/149-00003d04", "__REC_STATUS=INITIALIZED") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:3] Set("SIP/149-00003d04", "NOW=1590777419") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:4] Set("SIP/149-00003d04", "__DAY=29") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:5] Set("SIP/149-00003d04", "__MONTH=05") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:6] Set("SIP/149-00003d04", "__YEAR=2020") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:7] Set("SIP/149-00003d04", "__TIMESTR=20200529-133659") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:8] Set("SIP/149-00003d04", "__FROMEXTEN=149") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:9] Set("SIP/149-00003d04", "__MON_FMT=wav") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/149-00003d04", "Recordings initialized") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/149-00003d04", "0?Set(ARG3=dontcare)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:12] Set("SIP/149-00003d04", "REC_POLICY_MODE_SAVE=") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/149-00003d04", "0?Set(REC_STATUS=NO)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/149-00003d04", "3?checkaction") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx_builtins.c: Goto (sub-record-check,s,17)
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/149-00003d04", "1?sub-record-check,out,1") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx_builtins.c: Goto (sub-record-check,out,1)
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/149-00003d04", "Outbound Recording Check from 149 to 16193847180") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [out@sub-record-check:2] Set("SIP/149-00003d04", "RECMODE=dontcare") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/149-00003d04", "1?Goto(routewins)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx_builtins.c: Goto (sub-record-check,out,7)
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [out@sub-record-check:7] Gosub("SIP/149-00003d04", "recordcheck,1(dontcare,out,16193847180)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/149-00003d04", "Starting recording check against dontcare") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/149-00003d04", "dontcare") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/149-00003d04", "") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [out@sub-record-check:8] Return("SIP/149-00003d04", "") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [16193847180@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:3] ExecIf("SIP/149-00003d04", "0 ?Set(CDR(accountcode)=)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [16193847180@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:4] Set("SIP/149-00003d04", "MOHCLASS=default") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [16193847180@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:5] ExecIf("SIP/149-00003d04", "0?Set(TRUNKCIDOVERRIDE=2628913656)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [16193847180@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:6] Set("SIP/149-00003d04", "_NODEST=") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [16193847180@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:7] Macro("SIP/149-00003d04", "dialout-trunk,2,16193847180,,off") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/149-00003d04", "DIAL_TRUNK=2") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("SIP/149-00003d04", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("SIP/149-00003d04", "0?sub-pincheck,s,1()") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("SIP/149-00003d04", "0?Set(CALLERID(num)=149)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("SIP/149-00003d04", "0?disabletrunk,1") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/149-00003d04", "DIAL_NUMBER=16193847180") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:7] Set("SIP/149-00003d04", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:8] Set("SIP/149-00003d04", "OUTBOUND_GROUP=OUT_2") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:9] Set("SIP/149-00003d04", "DIAL_TRUNK_OPTIONS=T") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("SIP/149-00003d04", "1?nomax") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx_builtins.c: Goto (macro-dialout-trunk,s,12)
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("SIP/149-00003d04", "0?skipoutcid") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("SIP/149-00003d04", "outbound-callerid,2") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("SIP/149-00003d04", "149") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("SIP/149-00003d04", "") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("SIP/149-00003d04", "off") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("SIP/149-00003d04", "0?Set(CALLERPRES(name-pres)=)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("SIP/149-00003d04", "0?Set(CALLERPRES(num-pres)=)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:6] Set("SIP/149-00003d04", "HOTDESCKCHAN=149-00003d04") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:7] Set("SIP/149-00003d04", "HOTDESKEXTEN=149") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:8] Set("SIP/149-00003d04", "HOTDESKCALL=0") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("SIP/149-00003d04", "0?Set(HOTDESKCALL=1)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("SIP/149-00003d04", "0?Set(CALLERID(name)=)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:11] Set("SIP/149-00003d04", "ALLOWTHISROUTE=NO") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("SIP/149-00003d04", "0?Set(ALLOWTHISROUTE=YES)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("SIP/149-00003d04", "0?Hangup()") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("SIP/149-00003d04", "0?Set(REALCALLERIDNUM=149)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("SIP/149-00003d04", "0?Set(AMPUSER=149)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("SIP/149-00003d04", "1?normcid") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:20] Set("SIP/149-00003d04", "USEROUTCID=2622886155") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:21] Set("SIP/149-00003d04", "EMERGENCYCID=") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("SIP/149-00003d04", "0?Set(EMERGENCYCID=)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:23] Set("SIP/149-00003d04", "TRUNKOUTCID=2628913656") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("SIP/149-00003d04", "1?trunkcid") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("SIP/149-00003d04", "1?Set(CALLERID(all)=2628913656)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("SIP/149-00003d04", "1?Set(CALLERID(all)=2622886155)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("SIP/149-00003d04", "0?Set(CALLERID(all)=)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("SIP/149-00003d04", "0?Set(CALLERID(all)=149)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:34] Set("SIP/149-00003d04", "TIOHIDE=no") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf("SIP/149-00003d04", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("SIP/149-00003d04", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("SIP/149-00003d04", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("SIP/149-00003d04", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:39] Set("SIP/149-00003d04", "CDR(outbound_cnum)=2622886155") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-outbound-callerid:40] Set("SIP/149-00003d04", "CDR(outbound_cnam)=") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("SIP/149-00003d04", "0?sub-flp-2,s,1()") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:15] Set("SIP/149-00003d04", "OUTNUM=16193847180") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:16] Set("SIP/149-00003d04", "custom=DAHDI/g0") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("SIP/149-00003d04", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("SIP/149-00003d04", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("SIP/149-00003d04", "dialout-trunk-predial-hook,") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/149-00003d04", "") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("SIP/149-00003d04", "0?bypass,1") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("SIP/149-00003d04", "1?Set(CONNECTEDLINE(num,i)=16193847180)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf("SIP/149-00003d04", "1?Set(CONNECTEDLINE(name,i)=CID:2622886155)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:23] ExecIf("SIP/149-00003d04", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)2622886155)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:24] GotoIf("SIP/149-00003d04", "0?customtrunk") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:25] ExecIf("SIP/149-00003d04", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:26] Dial("SIP/149-00003d04", "DAHDI/g0/16193847180,300,Tb(func-apply-sipheaders^s^1,(2))") in new stack

(Dave Burgess) #2

That’s the line before the call fails, and everything looks good to that point. Whatever happens next is likely to be the issue.

If it doesn’t do anything after that, check your PRI/FXS line and make sure it’s up/working. There are lots of dahdi tools that can help you troubleshoot that part.


(Mvogel4949) #3

Interesting. I appreciate your help. There are no more lines for the call after that last line. Looking at all the outgoing calls that failed during that time they all end with that step, followed by the next call attempt. Maybe the PRI box had a hiccup


(Mvogel4949) #4

Probably a dumb question but are there any specific dahdi logs in the system?


(Dave Burgess) #5

If there are, they’d be in /var/log/asterisk/ - but remember that dahdi is its own “thing”. Have you tried ‘service restart dahdi’ and checked the output of that yet?

Having said that, however, my guy says “No”. Dahdi output might be in the messages file (in the log dir) or in the full file. A reboot might work, as might an “fwconsole restart” (which should restart DAHDI as well.


(Mvogel4949) #6

A reboot does seem to bring the PRI back online. During the outage they can call extension to extension but no inbound calls reach the logfiles and outbound calls just die after this:

[2020-05-29 13:36:59] VERBOSE[7648][C-00003019] pbx.c: Executing [s@macro-dialout-trunk:26] Dial(“SIP/149-00003d04”, “DAHDI/g0/16193847180,300,Tb(func-apply-sipheaders^s^1,(2))”) in new stack


(Dave Burgess) #7

Since dahdi is it’s own process and Asterisk just talks to it and FreePBX talks to Asterisk, it might be a good experiment to “hand” stop and start DAHDI, then to an “fwconsore restart”.

It’s possible that the dahdi process has locked itself up or that the card and dahdi are “arguing” somehow.


(Mvogel4949) #8

I requested any logfiles from the PRI adtran to see if we might glean any info there. The issues certainly seem to reside between the system and the PRI.


(Mvogel4949) #9

THe inbound and outbound calls on the PRI locked up again today. Going into DAHDI config I did a restart DAHDI and asterisk and it came back to life. Does this indicate an issue with my PRI card perhaps?


(Dave Burgess) #10

If I was a betting man, I’d say that’s where you’re going to end up. Before that, call your provider and see if they have anything in their logs. Also, check the messages and asterisk/full logs in /var/log/ to see if an error was captured.

It could be a card thing at either end, a line thing, or a software thing in the DAHDI interface. Timing on PRIs is a pretty big deal, so don’t disregard things like punch down blocks and wire terminations.


(Mvogel4949) #11

Would an update to the DAHDI module be of use or perhaps to asterisk?


(Dave Burgess) #12

I wouldn’t start updating things until I was sure it wasn’t hardware. Grab your punchdown tool and bop your 66 block, then start looking at the server. Check with the provider and see if the card is losing clock (PRI Timing can mess with all sorts of things). Check your dahdi_* software and see if anything is going Red (or Yellow) and look for common causes. In spite of what we tell the customers, software doesn’t actually rot, so assuming it’s a DAHDI or Asterisk thing would be “when everything else is ruled out” sort of activity.


(Mvogel4949) #13

I’m on it. Thank you for your help!