All circuits are busy


(Thomas) #1

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

(Thomas) #2

Here is some more of the log, as it was not possible to post everything in the first post.

[2020-11-01 12:13:24] VERBOSE[30899] pbx_variables.c: Setting global variable 'SIPDOMAIN' to '***IP-ADDRESS FREEPBX***'
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-sip-external:1] NoOp("PJSIP/anonymous-0000007f", "Received incoming SIP connection from unknown peer to ***OWN_NUMBER***") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-sip-external:2] Set("PJSIP/anonymous-0000007f", "DID=***OWN_NUMBER***") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-sip-external:3] Goto("PJSIP/anonymous-0000007f", "s,1") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (from-sip-external,s,1)
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@from-sip-external:1] GotoIf("PJSIP/anonymous-0000007f", "1?setlanguage:checkanon") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (from-sip-external,s,2)
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@from-sip-external:2] Set("PJSIP/anonymous-0000007f", "CHANNEL(language)=de_pforzheim_v2.0") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@from-sip-external:3] GotoIf("PJSIP/anonymous-0000007f", "0?noanonymous") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@from-sip-external:4] Goto("PJSIP/anonymous-0000007f", "from-trunk,***OWN_NUMBER***,1") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (from-trunk,***OWN_NUMBER***,1)
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:1] Set("PJSIP/anonymous-0000007f", "__DIRECTION=INBOUND") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:2] Gosub("PJSIP/anonymous-0000007f", "sub-record-check,s,1(in,***OWN_NUMBER***,dontcare)") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/anonymous-0000007f", "0?initialized") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/anonymous-0000007f", "__REC_STATUS=INITIALIZED") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/anonymous-0000007f", "NOW=1604229204") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/anonymous-0000007f", "__DAY=01") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/anonymous-0000007f", "__MONTH=11") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/anonymous-0000007f", "__YEAR=2020") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/anonymous-0000007f", "__TIMESTR=20201101-121324") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/anonymous-0000007f", "__FROMEXTEN=unknown") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/anonymous-0000007f", "__MON_FMT=wav") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/anonymous-0000007f", "Recordings initialized") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/anonymous-0000007f", "0?Set(ARG3=dontcare)") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/anonymous-0000007f", "REC_POLICY_MODE_SAVE=") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/anonymous-0000007f", "0?Set(REC_STATUS=NO)") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/anonymous-0000007f", "2?checkaction") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (sub-record-check,s,17)
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/anonymous-0000007f", "1?sub-record-check,in,1") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (sub-record-check,in,1)
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [in@sub-record-check:1] NoOp("PJSIP/anonymous-0000007f", "Inbound Recording Check to ***OWN_NUMBER***") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [in@sub-record-check:2] Set("PJSIP/anonymous-0000007f", "FROMEXTEN=unknown") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [in@sub-record-check:3] ExecIf("PJSIP/anonymous-0000007f", "11?Set(FROMEXTEN=+49***EXTERNAL NUMBER***)") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [in@sub-record-check:4] Gosub("PJSIP/anonymous-0000007f", "recordcheck,1(dontcare,in,***OWN_NUMBER***)") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/anonymous-0000007f", "Starting recording check against dontcare") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/anonymous-0000007f", "dontcare") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [in@sub-record-check:5] Return("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:3] Set("PJSIP/anonymous-0000007f", "CHANNEL(tonezone)=de") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:4] Set("PJSIP/anonymous-0000007f", "__FROM_DID=***OWN_NUMBER***") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:5] Set("PJSIP/anonymous-0000007f", "returnhere=1") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:6] Gosub("PJSIP/anonymous-0000007f", "app-blacklist-check,s,1()") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("PJSIP/anonymous-0000007f", "0?blacklisted") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@app-blacklist-check:2] Set("PJSIP/anonymous-0000007f", "CALLED_BLACKLIST=1") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [s@app-blacklist-check:3] Return("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:7] Set("PJSIP/anonymous-0000007f", "CDR(did)=***OWN_NUMBER***") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:8] GotoIf("PJSIP/anonymous-0000007f", "0?") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:9] ExecIf("PJSIP/anonymous-0000007f", "1 ?Set(CALLERID(name)=+49***EXTERNAL NUMBER***)") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:10] Set("PJSIP/anonymous-0000007f", "CHANNEL(musicclass)=OwnMusic") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:11] Set("PJSIP/anonymous-0000007f", "__MOHCLASS=OwnMusic") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:12] Set("PJSIP/anonymous-0000007f", "__REVERSAL_REJECT=FALSE") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:13] GotoIf("PJSIP/anonymous-0000007f", "1?post-reverse-charge") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (from-trunk,***OWN_NUMBER***,15)
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:15] NoOp("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:24] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:16] Wait("PJSIP/anonymous-0000007f", "3") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:17] Set("PJSIP/anonymous-0000007f", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:18] Set("PJSIP/anonymous-0000007f", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:19] Set("PJSIP/anonymous-0000007f", "CALLERID(name-pres)=allowed_not_screened") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:20] Set("PJSIP/anonymous-0000007f", "CALLERID(num-pres)=allowed_not_screened") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:21] NoOp("PJSIP/anonymous-0000007f", "CallerID Entry Point") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:22] Set("PJSIP/anonymous-0000007f", "__CRM_DIRECTION=INBOUND") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:23] Set("PJSIP/anonymous-0000007f", "__CRM_SOURCE=+49***EXTERNAL NUMBER***") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:24] Set("PJSIP/anonymous-0000007f", "__CRM_LINKEDID=1604229204.134") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:25] AGI("PJSIP/anonymous-0000007f", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: <PJSIP/anonymous-0000007f>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:26] ExecIf("PJSIP/anonymous-0000007f", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [***OWN_NUMBER***@from-trunk:27] Goto("PJSIP/anonymous-0000007f", "ext-group,4001,1") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (ext-group,4001,1)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:1] GotoIf("PJSIP/anonymous-0000007f", "0?cid") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:2] PlayTones("PJSIP/anonymous-0000007f", "ring") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:3] Progress("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:4] Macro("PJSIP/anonymous-0000007f", "user-callerid,") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/anonymous-0000007f", "TOUCH_MONITOR=1604229204.134") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/anonymous-0000007f", "AMPUSER=+49***EXTERNAL NUMBER***") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/anonymous-0000007f", "HOTDESCKCHAN=anonymous-0000007f") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/anonymous-0000007f", "HOTDESKEXTEN=anonymous") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/anonymous-0000007f", "HOTDESKCALL=0") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("PJSIP/anonymous-0000007f", "0?Set(HOTDESKCALL=1)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("PJSIP/anonymous-0000007f", "0?Set(CALLERID(name)=)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/anonymous-0000007f", "0?report") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("PJSIP/anonymous-0000007f", "1?Set(REALCALLERIDNUM=+49***EXTERNAL NUMBER***)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/anonymous-0000007f", "AMPUSER=") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("PJSIP/anonymous-0000007f", "0?limit") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:12] Set("PJSIP/anonymous-0000007f", "AMPUSERCIDNAME=") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/anonymous-0000007f", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("PJSIP/anonymous-0000007f", "1?report") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-user-callerid,s,23)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:23] NoOp("PJSIP/anonymous-0000007f", "Macro Depth is 1") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("PJSIP/anonymous-0000007f", "1?report2:macroerror") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-user-callerid,s,25)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/anonymous-0000007f", "0?continue") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("PJSIP/anonymous-0000007f", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:27] Set("PJSIP/anonymous-0000007f", "__TTL=64") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:28] GotoIf("PJSIP/anonymous-0000007f", "1?continue") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-user-callerid,s,44)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:44] Set("PJSIP/anonymous-0000007f", "CALLERID(number)=+49***EXTERNAL NUMBER***") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:45] Set("PJSIP/anonymous-0000007f", "CALLERID(name)=+49***EXTERNAL NUMBER***") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("PJSIP/anonymous-0000007f", "0?cnum") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:47] Set("PJSIP/anonymous-0000007f", "CDR(cnam)=+49***EXTERNAL NUMBER***") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:48] Set("PJSIP/anonymous-0000007f", "CDR(cnum)=+49***EXTERNAL NUMBER***") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/anonymous-0000007f", "CHANNEL(language)=de_pforzheim_v2.0") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:5] Macro("PJSIP/anonymous-0000007f", "blkvm-setifempty,") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("PJSIP/anonymous-0000007f", "1?init") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set("PJSIP/anonymous-0000007f", "__BLKVM_CHANNEL=PJSIP/anonymous-0000007f") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set("PJSIP/anonymous-0000007f", "SHARED(BLKVM,PJSIP/anonymous-0000007f)=TRUE") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set("PJSIP/anonymous-0000007f", "GOSUB_RETVAL=TRUE") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-blkvm-setifempty:7] MacroExit("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:6] GotoIf("PJSIP/anonymous-0000007f", "1?skipov") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (ext-group,4001,9)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:9] Set("PJSIP/anonymous-0000007f", "RRNODEST=") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:10] Set("PJSIP/anonymous-0000007f", "__NODEST=4001") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:11] GosubIf("PJSIP/anonymous-0000007f", "0?sub-rgsetcid,s,1()") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:12] Gosub("PJSIP/anonymous-0000007f", "sub-record-check,s,1(rg,4001,dontcare)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/anonymous-0000007f", "11?initialized") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (sub-record-check,s,10)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/anonymous-0000007f", "Recordings initialized") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/anonymous-0000007f", "0?Set(ARG3=dontcare)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/anonymous-0000007f", "REC_POLICY_MODE_SAVE=") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/anonymous-0000007f", "0?Set(REC_STATUS=NO)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/anonymous-0000007f", "2?checkaction") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (sub-record-check,s,17)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/anonymous-0000007f", "0?sub-record-check,rg,1") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:18] NoOp("PJSIP/anonymous-0000007f", "Generic rg Recording Check - +49***EXTERNAL NUMBER*** 4001") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:19] Gosub("PJSIP/anonymous-0000007f", "recordcheck,1(dontcare,rg,4001)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/anonymous-0000007f", "Starting recording check against dontcare") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/anonymous-0000007f", "dontcare") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-check:20] Return("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:13] Set("PJSIP/anonymous-0000007f", "RingGroupMethod=ringall") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:14] Set("PJSIP/anonymous-0000007f", "__ALT_CONFIRM_MSG=") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:15] GotoIf("PJSIP/anonymous-0000007f", "0?RGVQANNOUNCE:NORGVQANNOUNCE") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (ext-group,4001,18)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:18] Macro("PJSIP/anonymous-0000007f", "dial,15,HhTtrQ(NO_ANSWER),2001-2101-2102") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:1] NoOp("PJSIP/anonymous-0000007f", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:2] Set("PJSIP/anonymous-0000007f", "__CRM_SOURCE=+49***EXTERNAL NUMBER***") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:3] ExecIf("PJSIP/anonymous-0000007f", "0?Set(ALERT_INFO=)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:4] ExecIf("PJSIP/anonymous-0000007f", "0?Set(ALERT_INFO=)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:5] ExecIf("PJSIP/anonymous-0000007f", "0?Set(ALERT_INFO=)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:6] ExecIf("PJSIP/anonymous-0000007f", "1?Set(CHANNEL(musicclass)=OwnMusic)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:7] AGI("PJSIP/anonymous-0000007f", "agi://127.0.0.1/dialparties.agi") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Starting New Dialparties.agi
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Caller ID name is '+49***EXTERNAL NUMBER***' number is '+49***EXTERNAL NUMBER***'
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: CW Ignore is: 
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: CF Ignore is: 
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: CW IN_USE/BUSY is: 1
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Methodology of ring is  'ringall'
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 2001 to extension map
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 2101 to extension map
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 2102 to extension map
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 2001 cf is disabled
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 2101 cf is disabled
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 2102 cf is disabled
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 2001 do not disturb is disabled
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 2101 do not disturb is disabled
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 2102 do not disturb is disabled
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/2001
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Ended up with real PJSIP Dial string PJSIP/2001/sip:2001@***IP-ADDRESS SIP-PHONE***:5060&PJSIP/2001/sip:2001@***IP-ADDRESS CISCO ATA***:5060;x-ast-orig-host=***OWN WAN IP-ADDRESS***:43972
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/2001 to +49***EXTERNAL NUMBER***
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/2101
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Ended up with no PJSIP contacts
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/2101 to +49***EXTERNAL NUMBER***
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/2102
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Ended up with real PJSIP Dial string PJSIP/2102/sip:2102@***IP-ADDRESS SIP-PHONE***:37018
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/2102 to +49***EXTERNAL NUMBER***
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: Filtered ARG3: 2001-2101-2102
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: RVOL_MODE '' 
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: RVOL is: 
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: agi://127.0.0.1/dialparties.agi: RVOLPARENT is: 
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] res_agi.c: <PJSIP/anonymous-0000007f>AGI Script agi://127.0.0.1/dialparties.agi completed, returning 0
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:8] GotoIf("PJSIP/anonymous-0000007f", "1?normdial") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-dial,s,11)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:11] NoOp("PJSIP/anonymous-0000007f", "Returned from dialparties with groups to dial") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:12] NoOp("PJSIP/anonymous-0000007f", "ringall array  ") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:13] Set("PJSIP/anonymous-0000007f", "__FMGL_DIAL=") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:14] Set("PJSIP/anonymous-0000007f", "LOOPCNT=3") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:15] Set("PJSIP/anonymous-0000007f", "ITER=1") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:16] Set("PJSIP/anonymous-0000007f", "__EXTTOCALL=2001") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:17] NoOp("PJSIP/anonymous-0000007f", "Working with 2001") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:18] ExecIf("PJSIP/anonymous-0000007f", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:19] ExecIf("PJSIP/anonymous-0000007f", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:20] Set("PJSIP/anonymous-0000007f", "ITER=2") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:21] GotoIf("PJSIP/anonymous-0000007f", "1?ndloopbegin") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-dial,s,16)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:16] Set("PJSIP/anonymous-0000007f", "__EXTTOCALL=2101") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:17] NoOp("PJSIP/anonymous-0000007f", "Working with 2101") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:18] ExecIf("PJSIP/anonymous-0000007f", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:19] ExecIf("PJSIP/anonymous-0000007f", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:20] Set("PJSIP/anonymous-0000007f", "ITER=3") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:21] GotoIf("PJSIP/anonymous-0000007f", "1?ndloopbegin") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-dial,s,16)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:16] Set("PJSIP/anonymous-0000007f", "__EXTTOCALL=2102") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:17] NoOp("PJSIP/anonymous-0000007f", "Working with 2102") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:18] ExecIf("PJSIP/anonymous-0000007f", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:19] ExecIf("PJSIP/anonymous-0000007f", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:20] Set("PJSIP/anonymous-0000007f", "ITER=4") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:21] GotoIf("PJSIP/anonymous-0000007f", "0?ndloopbegin") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:22] Macro("PJSIP/anonymous-0000007f", "dial-ringall-predial-hook,") in new stack

(Thomas) #3

And the last part:

[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:23] ExecIf("PJSIP/anonymous-0000007f", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:24] ExecIf("PJSIP/anonymous-0000007f", "0?Set(ds=PJSIP/2001/sip:2001@***IP-ADDRESS SIP-PHONE***:5060&PJSIP/2001/sip:2001@***IP-ADDRESS CISCO ATA***:5060;x-ast-orig-host=***OWN WAN IP-ADDRESS***:43972&PJSIP/2102/sip:2102@***IP-ADDRESS SIP-PHONE***:37018,15,HhtrQ(NO_ANSWER)M(auto-blkvm)g)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:25] Dial("PJSIP/anonymous-0000007f", "PJSIP/2001/sip:2001@***IP-ADDRESS SIP-PHONE***:5060&PJSIP/2001/sip:2001@***IP-ADDRESS CISCO ATA***:5060;x-ast-orig-host=***OWN WAN IP-ADDRESS***:43972&PJSIP/2102/sip:2102@***IP-ADDRESS SIP-PHONE***:37018,15,HhtrQ(NO_ANSWER)M(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/2001-00000080 Internal Gosub(func-apply-sipheaders,s,1) start
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/2001-00000080", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/2001-00000080", "Applying SIP Headers to channel PJSIP/2001-00000080") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/2001-00000080", "TECH=PJSIP") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/2001-00000080", "SIPHEADERKEYS=") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:5] While("PJSIP/2001-00000080", "0") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_while.c: Jumping to priority 13
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:14] Return("PJSIP/2001-00000080", "") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_stack.c: Spawn extension (from-internal, 4001, 1) exited non-zero on 'PJSIP/2001-00000080'
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/2001-00000080 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/2001-00000081 Internal Gosub(func-apply-sipheaders,s,1) start
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/2001-00000081", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/2001-00000081", "Applying SIP Headers to channel PJSIP/2001-00000081") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/2001-00000081", "TECH=PJSIP") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/2001-00000081", "SIPHEADERKEYS=") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:5] While("PJSIP/2001-00000081", "0") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_while.c: Jumping to priority 13
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:14] Return("PJSIP/2001-00000081", "") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_stack.c: Spawn extension (from-internal, 4001, 1) exited non-zero on 'PJSIP/2001-00000081'
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/2001-00000081 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/2102-00000082 Internal Gosub(func-apply-sipheaders,s,1) start
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/2102-00000082", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/2102-00000082", "Applying SIP Headers to channel PJSIP/2102-00000082") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/2102-00000082", "TECH=PJSIP") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/2102-00000082", "SIPHEADERKEYS=") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:5] While("PJSIP/2102-00000082", "0") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_while.c: Jumping to priority 13
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@func-apply-sipheaders:14] Return("PJSIP/2102-00000082", "") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_stack.c: Spawn extension (from-internal, 4001, 1) exited non-zero on 'PJSIP/2102-00000082'
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/2102-00000082 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_dial.c: Called PJSIP/2001/sip:2001@***IP-ADDRESS SIP-PHONE***:5060
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_dial.c: Called PJSIP/2001/sip:2001@***IP-ADDRESS CISCO ATA***:5060;x-ast-orig-host=***OWN WAN IP-ADDRESS***:43972
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_dial.c: Called PJSIP/2102/sip:2102@***IP-ADDRESS SIP-PHONE***:37018
[2020-11-01 12:13:27] VERBOSE[29379] netsock2.c: Using SIP RTP Audio TOS bits 184
[2020-11-01 12:13:27] VERBOSE[29379] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2020-11-01 12:13:27] VERBOSE[29379] netsock2.c: Using SIP RTP Audio CoS mark 5
[2020-11-01 12:13:27] VERBOSE[30899] netsock2.c: Using SIP RTP Audio TOS bits 184
[2020-11-01 12:13:27] VERBOSE[30899] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2020-11-01 12:13:27] VERBOSE[30899] netsock2.c: Using SIP RTP Audio CoS mark 5
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_dial.c: PJSIP/2001-00000081 connected line has changed. Saving it until answer for PJSIP/anonymous-0000007f
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_dial.c: PJSIP/2001-00000080 connected line has changed. Saving it until answer for PJSIP/anonymous-0000007f
[2020-11-01 12:13:27] VERBOSE[29835] netsock2.c: Using SIP RTP Audio TOS bits 184
[2020-11-01 12:13:27] VERBOSE[29835] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2020-11-01 12:13:27] VERBOSE[29835] netsock2.c: Using SIP RTP Audio CoS mark 5
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_dial.c: PJSIP/2102-00000082 connected line has changed. Saving it until answer for PJSIP/anonymous-0000007f
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/2001-00000081 Internal Gosub(crm-hangup,s,1) start
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/2001-00000081", "Sending Hangup to CRM") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/2001-00000081", "HANGUP CAUSE: 17") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/2001-00000081", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/2001-00000081", "MASTER CHANNEL: 1604229207.136 = 1604229204.134") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/2001-00000081", "1?return") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (crm-hangup,s,8)
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/2001-00000081", "") in new stack
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_stack.c: Spawn extension (from-internal, 4001, 1) exited non-zero on 'PJSIP/2001-00000081'
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/2001-00000081 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_dial.c: PJSIP/2001-00000080 is ringing
[2020-11-01 12:13:27] VERBOSE[5052][C-00000030] app_dial.c: PJSIP/2001-00000080 is ringing
[2020-11-01 12:13:29] VERBOSE[5052][C-00000030] app_dial.c: PJSIP/2102-00000082 is ringing
[2020-11-01 12:13:29] VERBOSE[5052][C-00000030] app_dial.c: PJSIP/2102-00000082 is ringing
[2020-11-01 12:13:29] VERBOSE[5052][C-00000030] app_dial.c: PJSIP/2102-00000082 is ringing
[2020-11-01 12:13:29] VERBOSE[5052][C-00000030] app_dial.c: PJSIP/2102-00000082 is ringing
[2020-11-01 12:13:29] VERBOSE[5052][C-00000030] app_dial.c: PJSIP/2102-00000082 is ringing
[2020-11-01 12:13:29] VERBOSE[5052][C-00000030] app_dial.c: PJSIP/2102-00000082 is ringing
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] app_dial.c: Nobody picked up in 15000 ms
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/2001-00000080 Internal Gosub(crm-hangup,s,1) start
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/2001-00000080", "Sending Hangup to CRM") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/2001-00000080", "HANGUP CAUSE: 0") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/2001-00000080", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/2001-00000080", "MASTER CHANNEL: 1604229207.135 = 1604229204.134") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/2001-00000080", "1?return") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (crm-hangup,s,8)
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/2001-00000080", "") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] app_stack.c: Spawn extension (from-internal, 4001, 1) exited non-zero on 'PJSIP/2001-00000080'
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/2001-00000080 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/2102-00000082 Internal Gosub(crm-hangup,s,1) start
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/2102-00000082", "Sending Hangup to CRM") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/2102-00000082", "HANGUP CAUSE: 0") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/2102-00000082", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/2102-00000082", "MASTER CHANNEL: 1604229207.137 = 1604229204.134") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/2102-00000082", "1?return") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (crm-hangup,s,8)
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/2102-00000082", "") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] app_stack.c: Spawn extension (from-internal, 4001, 1) exited non-zero on 'PJSIP/2102-00000082'
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/2102-00000082 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:26] Set("PJSIP/anonymous-0000007f", "DIALSTATUS=NOANSWER") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:27] GosubIf("PJSIP/anonymous-0000007f", "0?NOANSWER,1()") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:28] NoOp("PJSIP/anonymous-0000007f", "Returning since nobody answered") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-dial:29] MacroExit("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:19] Gosub("PJSIP/anonymous-0000007f", "sub-record-cancel,s,1()") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@sub-record-cancel:1] Return("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:20] Set("PJSIP/anonymous-0000007f", "RingGroupMethod=") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:21] GotoIf("PJSIP/anonymous-0000007f", "0?nodest") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:22] Set("PJSIP/anonymous-0000007f", "__NODEST=") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:23] Macro("PJSIP/anonymous-0000007f", "blkvm-clr,") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-blkvm-clr:1] Set("PJSIP/anonymous-0000007f", "SHARED(BLKVM,PJSIP/anonymous-0000007f)=") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-blkvm-clr:2] Set("PJSIP/anonymous-0000007f", "GOSUB_RETVAL=") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [4001@ext-group:24] Goto("PJSIP/anonymous-0000007f", "ext-local,vmu6000,1") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (ext-local,vmu6000,1)
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmu6000@ext-local:1] Macro("PJSIP/anonymous-0000007f", "vm,6000,NOANSWER,") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-vm:1] Macro("PJSIP/anonymous-0000007f", "user-callerid,SKIPTTL") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/anonymous-0000007f", "TOUCH_MONITOR=1604229204.134") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/anonymous-0000007f", "AMPUSER=+49***EXTERNAL NUMBER***") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/anonymous-0000007f", "HOTDESCKCHAN=anonymous-0000007f") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/anonymous-0000007f", "HOTDESKEXTEN=anonymous") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/anonymous-0000007f", "HOTDESKCALL=0") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("PJSIP/anonymous-0000007f", "0?Set(HOTDESKCALL=1)") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("PJSIP/anonymous-0000007f", "0?Set(CALLERID(name)=)") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("PJSIP/anonymous-0000007f", "0?report") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("PJSIP/anonymous-0000007f", "0?Set(REALCALLERIDNUM=+49***EXTERNAL NUMBER***)") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/anonymous-0000007f", "AMPUSER=") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("PJSIP/anonymous-0000007f", "0?limit") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:12] Set("PJSIP/anonymous-0000007f", "AMPUSERCIDNAME=") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/anonymous-0000007f", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("PJSIP/anonymous-0000007f", "1?report") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-user-callerid,s,23)
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:23] NoOp("PJSIP/anonymous-0000007f", "Macro Depth is 2") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("PJSIP/anonymous-0000007f", "1?report2:macroerror") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-user-callerid,s,25)
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/anonymous-0000007f", "1?continue") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-user-callerid,s,44)
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:44] Set("PJSIP/anonymous-0000007f", "CALLERID(number)=+49***EXTERNAL NUMBER***") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:45] Set("PJSIP/anonymous-0000007f", "CALLERID(name)=+49***EXTERNAL NUMBER***") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("PJSIP/anonymous-0000007f", "0?cnum") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:47] Set("PJSIP/anonymous-0000007f", "CDR(cnam)=+49***EXTERNAL NUMBER***") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:48] Set("PJSIP/anonymous-0000007f", "CDR(cnum)=+49***EXTERNAL NUMBER***") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/anonymous-0000007f", "CHANNEL(language)=de_pforzheim_v2.0") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-vm:2] Set("PJSIP/anonymous-0000007f", "VMGAIN=g(6)") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-vm:3] Macro("PJSIP/anonymous-0000007f", "blkvm-check,") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-blkvm-check:1] Set("PJSIP/anonymous-0000007f", "GOSUB_RETVAL=") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-blkvm-check:2] ExecIf("PJSIP/anonymous-0000007f", "0?Set(GOSUB_RETVAL=TRUE)") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-blkvm-check:3] MacroExit("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-vm:4] GotoIf("PJSIP/anonymous-0000007f", "1?vmx,1") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-vm,vmx,1)
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:1] Set("PJSIP/anonymous-0000007f", "__EXTTOCALL=6000") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:2] Set("PJSIP/anonymous-0000007f", "__CRM_VOICEMAIL=6000") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:3] Set("PJSIP/anonymous-0000007f", "MEXTEN=6000") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:4] Set("PJSIP/anonymous-0000007f", "MMODE=NOANSWER") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:5] Set("PJSIP/anonymous-0000007f", "RETVM=") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:6] Set("PJSIP/anonymous-0000007f", "MODE=unavail") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:7] Macro("PJSIP/anonymous-0000007f", "get-vmcontext,6000") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-get-vmcontext:1] Set("PJSIP/anonymous-0000007f", "VMCONTEXT=default") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("PJSIP/anonymous-0000007f", "0?200:300") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:8] Set("PJSIP/anonymous-0000007f", "MODE=unavail") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:9] NoOp("PJSIP/anonymous-0000007f", "MODE IS: unavail") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:10] GotoIf("PJSIP/anonymous-0000007f", "1?chknomsg") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-vm,vmx,12)
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:12] GotoIf("PJSIP/anonymous-0000007f", "0?s-NOANSWER,1") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:13] GotoIf("PJSIP/anonymous-0000007f", "1?notdirect") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-vm,vmx,15)
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:15] NoOp("PJSIP/anonymous-0000007f", "Checking if ext 6000 is enabled: ") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [vmx@macro-vm:16] GotoIf("PJSIP/anonymous-0000007f", "1?s-NOANSWER,1") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-vm,s-NOANSWER,1)
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s-NOANSWER@macro-vm:1] Macro("PJSIP/anonymous-0000007f", "get-vmcontext,6000") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-get-vmcontext:1] Set("PJSIP/anonymous-0000007f", "VMCONTEXT=default") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("PJSIP/anonymous-0000007f", "0?200:300") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] pbx.c: Executing [s-NOANSWER@macro-vm:2] VoiceMail("PJSIP/anonymous-0000007f", "6000@default,ug(6)") in new stack
[2020-11-01 12:13:42] VERBOSE[5052][C-00000030] file.c: <PJSIP/anonymous-0000007f> Playing '/var/spool/asterisk/voicemail/default/6000/unavail.slin' (language 'de_pforzheim_v2.0')
[2020-11-01 12:13:45] VERBOSE[5052][C-00000030] file.c: <PJSIP/anonymous-0000007f> Playing 'vm-intro.gsm' (language 'de_pforzheim_v2.0')
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] app_macro.c: Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on 'PJSIP/anonymous-0000007f' in macro 'vm'
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Spawn extension (ext-local, vmu6000, 1) exited non-zero on 'PJSIP/anonymous-0000007f'
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [h@ext-local:1] Macro("PJSIP/anonymous-0000007f", "hangupcall,") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/anonymous-0000007f", "1?theend") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/anonymous-0000007f", "0?Set(CDR(recordingfile)=)") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-hangupcall:4] NoOp("PJSIP/anonymous-0000007f", " montior file= ") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("PJSIP/anonymous-0000007f", "1?skipagi") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [s@macro-hangupcall:7] Hangup("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/anonymous-0000007f' in macro 'hangupcall'
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'PJSIP/anonymous-0000007f'
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/anonymous-0000007f Internal Gosub(crm-hangup,s,1) start
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/anonymous-0000007f", "Sending Hangup to CRM") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/anonymous-0000007f", "HANGUP CAUSE: 17") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/anonymous-0000007f", "1?Set(__CRM_VOICEMAIL=FAILED)") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/anonymous-0000007f", "MASTER CHANNEL: 1604229204.134 = 1604229204.134") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/anonymous-0000007f", "0?return") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/anonymous-0000007f", "__CRM_HANGUP=1") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:7] AGI("PJSIP/anonymous-0000007f", "agi://127.0.0.1/sangomacrm.agi") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] res_agi.c: <PJSIP/anonymous-0000007f>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/anonymous-0000007f", "") in new stack
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on 'PJSIP/anonymous-0000007f'
[2020-11-01 12:13:47] VERBOSE[5052][C-00000030] app_stack.c: PJSIP/anonymous-0000007f Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=

(Ricardo) #4

When problem happens, how about to check SPA status??

As PSTN line status and others parameters (on status screen).