Hello,
I’m using FreePBX 15.0.16.76 connected to Deutsche Telekom as SIP provider and with some Yealink SIP phones connected as well as a Cisco SPA232D ATA. Basically, everything seems to works fine, but from time to time I run into the problem that no inbound and outbound calls are available anymore. Everytime this problem happens, I only hear “All circuits are busy” when I try to make a call. After a simple restart of FreePBX everything is back to normal, until the problem occurs again.
Sometimes it takes only a few hours between the problems, other times it runs for days before my system runs into the problem.
The system is used in a private non-commercial environment and, therefore, I can tell you that there are not too much simultaneous calls which cause the problem.
When the problem occurred yesterday, I copied the log. The only error I can see in the log is at 12:08:08 and tells “res_pjsip_header_funcs.c: No headers had been previously added to this session.”
Do you have any ideas what could cause the “All circuits are busy” problem and the PJSIP header error?
Thanks,
Thomas
Here is the log:
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [***CALLED_NUMBER***@from-internal:1] Macro("PJSIP/2001-0000007d", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/2001-0000007d", "TOUCH_MONITOR=1604228888.132") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/2001-0000007d", "AMPUSER=2001") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/2001-0000007d", "HOTDESCKCHAN=2001-0000007d") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/2001-0000007d", "HOTDESKEXTEN=2001") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/2001-0000007d", "HOTDESKCALL=0") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("PJSIP/2001-0000007d", "0?Set(HOTDESKCALL=1)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("PJSIP/2001-0000007d", "0?Set(CALLERID(name)=)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/2001-0000007d", "0?report") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("PJSIP/2001-0000007d", "1?Set(REALCALLERIDNUM=2001)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/2001-0000007d", "AMPUSER=2001") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("PJSIP/2001-0000007d", "0?limit") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:12] Set("PJSIP/2001-0000007d", "AMPUSERCIDNAME=General Phones") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/2001-0000007d", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("PJSIP/2001-0000007d", "0?report") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/2001-0000007d", "AMPUSERCID=2001") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:16] Set("PJSIP/2001-0000007d", "__DIAL_OPTIONS=HhTtr") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/2001-0000007d", "CALLERID(all)="General Phones" <2001>") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("PJSIP/2001-0000007d", "0?Set(CUSDIAL=)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("PJSIP/2001-0000007d", "0?Set(CALLERID(all)="General Phones" <2001>)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:20] GotoIf("PJSIP/2001-0000007d", "0?limit") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:21] ExecIf("PJSIP/2001-0000007d", "1?Set(GROUP(concurrency_limit)=2001)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:22] ExecIf("PJSIP/2001-0000007d", "0?Set(CHANNEL(language)=)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:23] NoOp("PJSIP/2001-0000007d", "Macro Depth is 1") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("PJSIP/2001-0000007d", "1?report2:macroerror") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx_builtins.c: Goto (macro-user-callerid,s,25)
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/2001-0000007d", "1?continue") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx_builtins.c: Goto (macro-user-callerid,s,44)
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:44] Set("PJSIP/2001-0000007d", "CALLERID(number)=2001") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:45] Set("PJSIP/2001-0000007d", "CALLERID(name)=General Phones") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("PJSIP/2001-0000007d", "0?cnum") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:47] Set("PJSIP/2001-0000007d", "CDR(cnam)=General Phones") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:48] Set("PJSIP/2001-0000007d", "CDR(cnum)=2001") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/2001-0000007d", "CHANNEL(language)=de_pforzheim_v2.0") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [***CALLED_NUMBER***@from-internal:2] Gosub("PJSIP/2001-0000007d", "sub-record-check,s,1(out,***CALLED_NUMBER***,dontcare)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/2001-0000007d", "0?initialized") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/2001-0000007d", "__REC_STATUS=INITIALIZED") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/2001-0000007d", "NOW=1604228888") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/2001-0000007d", "__DAY=01") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/2001-0000007d", "__MONTH=11") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/2001-0000007d", "__YEAR=2020") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/2001-0000007d", "__TIMESTR=20201101-120808") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/2001-0000007d", "__FROMEXTEN=2001") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/2001-0000007d", "__MON_FMT=wav") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/2001-0000007d", "Recordings initialized") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/2001-0000007d", "0?Set(ARG3=dontcare)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/2001-0000007d", "REC_POLICY_MODE_SAVE=") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/2001-0000007d", "0?Set(REC_STATUS=NO)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/2001-0000007d", "3?checkaction") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx_builtins.c: Goto (sub-record-check,s,17)
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/2001-0000007d", "1?sub-record-check,out,1") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx_builtins.c: Goto (sub-record-check,out,1)
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/2001-0000007d", "Outbound Recording Check from 2001 to ***CALLED_NUMBER***") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/2001-0000007d", "RECMODE=dontcare") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/2001-0000007d", "1?Goto(routewins)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx_builtins.c: Goto (sub-record-check,out,7)
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/2001-0000007d", "recordcheck,1(dontcare,out,***CALLED_NUMBER***)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/2001-0000007d", "Starting recording check against dontcare") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/2001-0000007d", "dontcare") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/2001-0000007d", "") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/2001-0000007d", "") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [***CALLED_NUMBER***@from-internal:3] ExecIf("PJSIP/2001-0000007d", "0 ?Set(CDR(accountcode)=)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [***CALLED_NUMBER***@from-internal:4] Set("PJSIP/2001-0000007d", "_ROUTEID=1") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [***CALLED_NUMBER***@from-internal:5] Set("PJSIP/2001-0000007d", "_ROUTENAME=OUTBOUND_***OWN_NUMBER***") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [***CALLED_NUMBER***@from-internal:6] Set("PJSIP/2001-0000007d", "MOHCLASS=default") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [***CALLED_NUMBER***@from-internal:7] ExecIf("PJSIP/2001-0000007d", "1?Set(TRUNKCIDOVERRIDE=***OWN_NUMBER***)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [***CALLED_NUMBER***@from-internal:8] Set("PJSIP/2001-0000007d", "_CALLERIDNAMEINTERNAL=General Phones") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [***CALLED_NUMBER***@from-internal:9] Set("PJSIP/2001-0000007d", "_CALLERIDNUMINTERNAL=2001") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [***CALLED_NUMBER***@from-internal:10] Set("PJSIP/2001-0000007d", "_EMAILNOTIFICATION=FALSE") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [***CALLED_NUMBER***@from-internal:11] Set("PJSIP/2001-0000007d", "_NODEST=") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [***CALLED_NUMBER***@from-internal:12] Macro("PJSIP/2001-0000007d", "dialout-trunk,3,***CALLED_NUMBER***,,off") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/2001-0000007d", "DIAL_TRUNK=3") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("PJSIP/2001-0000007d", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("PJSIP/2001-0000007d", "0?sub-pincheck,s,1()") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("PJSIP/2001-0000007d", "0?Set(CALLERID(num)=2001)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("PJSIP/2001-0000007d", "0?disabletrunk,1") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:6] Set("PJSIP/2001-0000007d", "DIAL_NUMBER=***CALLED_NUMBER***") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:7] Set("PJSIP/2001-0000007d", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:8] Set("PJSIP/2001-0000007d", "OUTBOUND_GROUP=OUT_3") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:9] Set("PJSIP/2001-0000007d", "DIAL_TRUNK_OPTIONS=T") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("PJSIP/2001-0000007d", "0?nomax") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("PJSIP/2001-0000007d", "0?chanfull") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("PJSIP/2001-0000007d", "0?skipoutcid") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("PJSIP/2001-0000007d", "outbound-callerid,3") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("PJSIP/2001-0000007d", "2001") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("PJSIP/2001-0000007d", "") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("PJSIP/2001-0000007d", "all") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/2001-0000007d", "0?Set(CALLERPRES(name-pres)=)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("PJSIP/2001-0000007d", "0?Set(CALLERPRES(num-pres)=)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:6] Set("PJSIP/2001-0000007d", "HOTDESCKCHAN=2001-0000007d") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:7] Set("PJSIP/2001-0000007d", "HOTDESKEXTEN=2001") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:8] Set("PJSIP/2001-0000007d", "HOTDESKCALL=0") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("PJSIP/2001-0000007d", "0?Set(HOTDESKCALL=1)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("PJSIP/2001-0000007d", "0?Set(CALLERID(name)=)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:11] Set("PJSIP/2001-0000007d", "ALLOWTHISROUTE=NO") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("PJSIP/2001-0000007d", "0?Set(ALLOWTHISROUTE=YES)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("PJSIP/2001-0000007d", "0?Hangup()") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("PJSIP/2001-0000007d", "0?Set(REALCALLERIDNUM=2001)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("PJSIP/2001-0000007d", "0?Set(AMPUSER=2001)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("PJSIP/2001-0000007d", "1?normcid") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:20] Set("PJSIP/2001-0000007d", "USEROUTCID=") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:21] Set("PJSIP/2001-0000007d", "EMERGENCYCID=") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("PJSIP/2001-0000007d", "0?Set(EMERGENCYCID=)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:23] Set("PJSIP/2001-0000007d", "TRUNKOUTCID=***OWN_NUMBER***") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("PJSIP/2001-0000007d", "1?trunkcid") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("PJSIP/2001-0000007d", "1?Set(CALLERID(all)=***OWN_NUMBER***)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("PJSIP/2001-0000007d", "0?Set(CALLERID(all)=)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("PJSIP/2001-0000007d", "1?Set(CALLERID(all)=***OWN_NUMBER***)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("PJSIP/2001-0000007d", "0?Set(CALLERID(all)=2001)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:34] Set("PJSIP/2001-0000007d", "TIOHIDE=no") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf("PJSIP/2001-0000007d", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("PJSIP/2001-0000007d", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("PJSIP/2001-0000007d", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("PJSIP/2001-0000007d", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:39] Set("PJSIP/2001-0000007d", "CDR(outbound_cnum)=***OWN_NUMBER***") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-outbound-callerid:40] Set("PJSIP/2001-0000007d", "CDR(outbound_cnam)=") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("PJSIP/2001-0000007d", "0?sub-flp-3,s,1()") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:15] Set("PJSIP/2001-0000007d", "OUTNUM=***CALLED_NUMBER***") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:16] Set("PJSIP/2001-0000007d", "custom=PJSIP") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("PJSIP/2001-0000007d", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("PJSIP/2001-0000007d", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("PJSIP/2001-0000007d", "dialout-trunk-predial-hook,") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/2001-0000007d", "") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("PJSIP/2001-0000007d", "0?skipcrm") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:21] Set("PJSIP/2001-0000007d", "__CRM_DIRECTION=OUTBOUND") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:22] Set("PJSIP/2001-0000007d", "__CRM_DESTINATION=***CALLED_NUMBER***") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:23] Set("PJSIP/2001-0000007d", "__CRM_SOURCE=2001") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:24] AGI("PJSIP/2001-0000007d", "agi://127.0.0.1/sangomacrm.agi") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] res_agi.c: <PJSIP/2001-0000007d>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:25] Set("PJSIP/2001-0000007d", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("PJSIP/2001-0000007d", "CRM Finished") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("PJSIP/2001-0000007d", "0?bypass,1") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("PJSIP/2001-0000007d", "1?Set(CONNECTEDLINE(num,i)=***CALLED_NUMBER***)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("PJSIP/2001-0000007d", "1?Set(CONNECTEDLINE(name,i)=CID:***OWN_NUMBER***)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("PJSIP/2001-0000007d", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)***OWN_NUMBER***)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("PJSIP/2001-0000007d", "0?customtrunk") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("PJSIP/2001-0000007d", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:33] Set("PJSIP/2001-0000007d", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@macro-dialout-trunk:34] Dial("PJSIP/2001-0000007d", "PJSIP/***CALLED_NUMBER***@T-Online_***OWN_NUMBER***,300,Tb(func-apply-sipheaders^s^1,(3))U(sub-send-obroute-email^***CALLED_NUMBER***^***CALLED_NUMBER***^3^1604228888^^***OWN_NUMBER***)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] app_stack.c: PJSIP/T-Online_***OWN_NUMBER***-0000007e Internal Gosub(func-apply-sipheaders,s,1(3)) start
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "Applying SIP Headers to channel PJSIP/T-Online_***OWN_NUMBER***-0000007e") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "TECH=PJSIP") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "SIPHEADERKEYS=Alert-Info") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:5] While("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "1") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "sipheader=unset") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "0?SIPRemoveHeader(Alert-Info:)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "1?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
[2020-11-01 12:08:08] ERROR[29835] res_pjsip_header_funcs.c: No headers had been previously added to this session.
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "0?SIPAddHeader(Alert-Info:unset)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:5] While("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "0") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@func-apply-sipheaders:14] Return("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "") in new stack
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] app_stack.c: Spawn extension (from-pstn, ***CALLED_NUMBER***, 1) exited non-zero on 'PJSIP/T-Online_***OWN_NUMBER***-0000007e'
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] app_stack.c: PJSIP/T-Online_***OWN_NUMBER***-0000007e Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL=
[2020-11-01 12:08:08] VERBOSE[4329][C-0000002f] app_dial.c: Called PJSIP/***CALLED_NUMBER***@T-Online_***OWN_NUMBER***
[2020-11-01 12:08:09] VERBOSE[4329][C-0000002f] app_dial.c: PJSIP/T-Online_***OWN_NUMBER***-0000007e is ringing
[2020-11-01 12:08:09] VERBOSE[4329][C-0000002f] app_dial.c: PJSIP/T-Online_***OWN_NUMBER***-0000007e is ringing
[2020-11-01 12:08:26] VERBOSE[4329][C-0000002f] app_dial.c: PJSIP/T-Online_***OWN_NUMBER***-0000007e answered PJSIP/2001-0000007d
[2020-11-01 12:08:26] VERBOSE[4329][C-0000002f] app_stack.c: PJSIP/T-Online_***OWN_NUMBER***-0000007e Internal Gosub(sub-send-obroute-email,s,1(***CALLED_NUMBER***,***CALLED_NUMBER***,3,1604228888,,***OWN_NUMBER***)) start
[2020-11-01 12:08:26] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-send-obroute-email:1] GotoIf("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "0?sendEmail") in new stack
[2020-11-01 12:08:26] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-send-obroute-email:2] NoOp("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "email notifications disabled..exiting.") in new stack
[2020-11-01 12:08:26] VERBOSE[4329][C-0000002f] pbx.c: Executing [s@sub-send-obroute-email:3] Return("PJSIP/T-Online_***OWN_NUMBER***-0000007e", "") in new stack
[2020-11-01 12:08:26] VERBOSE[4329][C-0000002f] app_stack.c: Spawn extension (from-pstn, , 1) exited non-zero on 'PJSIP/T-Online_***OWN_NUMBER***-0000007e'
[2020-11-01 12:08:26] VERBOSE[4329][C-0000002f] app_stack.c: PJSIP/T-Online_***OWN_NUMBER***-0000007e Internal Gosub(sub-send-obroute-email,s,1(***CALLED_NUMBER***,***CALLED_NUMBER***,3,1604228888,,***OWN_NUMBER***)) complete GOSUB_RETVAL=
[2020-11-01 12:08:26] VERBOSE[4341][C-0000002f] bridge_channel.c: Channel PJSIP/T-Online_***OWN_NUMBER***-0000007e joined 'simple_bridge' basic-bridge <d2e22de9-8a5d-4055-8c90-15b37b3be2ad>
[2020-11-01 12:08:26] VERBOSE[4329][C-0000002f] bridge_channel.c: Channel PJSIP/2001-0000007d joined 'simple_bridge' basic-bridge <d2e22de9-8a5d-4055-8c90-15b37b3be2ad>
[2020-11-01 12:11:09] VERBOSE[29379] res_pjsip_registrar.c: Added contact 'sip:2001@***IP-ADDRESS CISCO ATA***:5060;x-ast-orig-host=***OWN WAN IP-ADDRESS***:43972' to AOR '2001' with expiration of 600 seconds
[2020-11-01 12:11:10] VERBOSE[30899] res_pjsip/pjsip_options.c: Contact 2001/sip:2001@***IP-ADDRESS CISCO ATA***:5060;x-ast-orig-host=***OWN WAN IP-ADDRESS***:43972 is now Reachable. RTT: 51.876 msec
[2020-11-01 12:11:27] VERBOSE[30899] res_pjsip/pjsip_options.c: Contact 2001/sip:2001@***IP-ADDRESS CISCO ATA***:5060;x-ast-orig-host=***OWN WAN IP-ADDRESS***:31726 has been deleted