Hey, I’m really getting desperate:
Since I reinstalled FreePBX, I wanted to use Asterisk 18 directly. Unfortunately I have the problem that I receive incoming calls via a telephone number from Fonial, but I only get the message for outgoing calls: “The number does not answer”.
What can be the reason?
I already had this with Asterisk 16, but it helped me to set up a SIP and no PJSIP connection for outgoing calls.
Info: FreePBX 16.0.14 - Asterisk 18.6.0 - Core 16.0.56.28
[2022-02-04 23:52:29] VERBOSE[52480] netsock2.c: Using SIP RTP Audio TOS bits 184
[2022-02-04 23:52:29] VERBOSE[52480] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2022-02-04 23:52:29] VERBOSE[52480] netsock2.c: Using SIP RTP Audio CoS mark 5
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [0151...@from-internal:1] Macro("PJSIP/1-00000017", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/1-00000017", "TOUCH_MONITOR=1644015149.24") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/1-00000017", "CHANCONTEXT=") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/1-00000017", "CHANCONTEXT=") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/1-00000017", "CHANEXTENCONTEXT=1-00000017") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/1-00000017", "CHANEXTEN=1-00000017") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/1-00000017", "CALLERID(number)=1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/1-00000017", "AMPUSER=1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/1-00000017", "HOTDESCKCHAN=1-00000017") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/1-00000017", "HOTDESKEXTEN=1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/1-00000017", "HOTDESKCALL=0") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("PJSIP/1-00000017", "0?Set(HOTDESKCALL=1)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/1-00000017", "0?Set(CALLERID(name)=)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/1-00000017", "0?report") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/1-00000017", "1?Set(REALCALLERIDNUM=1)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/1-00000017", "AMPUSER=1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/1-00000017", "0?limit") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/1-00000017", "AMPUSERCIDNAME=web4free") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("PJSIP/1-00000017", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/1-00000017", "0?report") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/1-00000017", "AMPUSERCID=1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:21] Set("PJSIP/1-00000017", "__DIAL_OPTIONS=HhTtr") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:22] Set("PJSIP/1-00000017", "CALLERID(all)="web4free" <1>") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("PJSIP/1-00000017", "0?Set(CUSDIAL=)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/1-00000017", "0?Set(CALLERID(all)="web4free" <1>)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/1-00000017", "0?limit") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("PJSIP/1-00000017", "1?Set(GROUP(concurrency_limit)=1)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("PJSIP/1-00000017", "0?Set(CHANNEL(language)=)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:28] NoOp("PJSIP/1-00000017", "Macro Depth is 1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("PJSIP/1-00000017", "1?report2:macroerror") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx_builtins.c: Goto (macro-user-callerid,s,30)
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("PJSIP/1-00000017", "1?continue") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/1-00000017", "CALLERID(number)=1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/1-00000017", "CALLERID(name)=web4free") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("PJSIP/1-00000017", "0?cnum") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/1-00000017", "CDR(cnam)=web4free") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/1-00000017", "CDR(cnum)=1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/1-00000017", "CHANNEL(language)=de_DE") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [0151....@from-internal:2] Gosub("PJSIP/1-00000017", "sub-record-check,s,1(out,0151....,dontcare)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/1-00000017", "0?initialized") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/1-00000017", "__REC_STATUS=INITIALIZED") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/1-00000017", "NOW=1644015149") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/1-00000017", "__DAY=04") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/1-00000017", "__MONTH=02") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/1-00000017", "__YEAR=2022") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/1-00000017", "__TIMESTR=20220204-235229") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/1-00000017", "__FROMEXTEN=1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/1-00000017", "__MON_FMT=wav") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/1-00000017", "Recordings initialized") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/1-00000017", "0?Set(ARG3=dontcare)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/1-00000017", "REC_POLICY_MODE_SAVE=") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/1-00000017", "0?Set(REC_STATUS=NO)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/1-00000017", "3?checkaction") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx_builtins.c: Goto (sub-record-check,s,17)
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/1-00000017", "1?sub-record-check,out,1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx_builtins.c: Goto (sub-record-check,out,1)
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/1-00000017", "Outbound Recording Check from 1 to 0151...") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/1-00000017", "RECMODE=dontcare") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/1-00000017", "1?Goto(routewins)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx_builtins.c: Goto (sub-record-check,out,7)
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/1-00000017", "recordcheck,1(dontcare,out,0151...)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/1-00000017", "Starting recording check against dontcare") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/1-00000017", "dontcare") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/1-00000017", "") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/1-00000017", "") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [0151....@from-internal:3] ExecIf("PJSIP/1-00000017", "0 ?Set(CDR(accountcode)=)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [0151....@from-internal:4] Set("PJSIP/1-00000017", "_ROUTEID=1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [0151....@from-internal:5] Set("PJSIP/1-00000017", "_ROUTENAME=web4free") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [0151....@from-internal:6] Set("PJSIP/1-00000017", "MOHCLASS=default") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [0151....@from-internal:7] ExecIf("PJSIP/1-00000017", "1?Set(TRUNKCIDOVERRIDE=<4941313....>)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [0151....@from-internal:8] Set("PJSIP/1-00000017", "_CALLERIDNAMEINTERNAL=web4free") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [0151....@from-internal:9] Set("PJSIP/1-00000017", "_CALLERIDNUMINTERNAL=1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [0151...@from-internal:10] Set("PJSIP/1-00000017", "_EMAILNOTIFICATION=TRUE") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [0151....@from-internal:11] Set("PJSIP/1-00000017", "_NODEST=") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [0151...@from-internal:12] Macro("PJSIP/1-00000017", "dialout-trunk,1,0151....,,off") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/1-00000017", "DIAL_TRUNK=1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("PJSIP/1-00000017", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("PJSIP/1-00000017", "0?sub-pincheck,s,1()") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("PJSIP/1-00000017", "0?Set(CALLERID(num)=1)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("PJSIP/1-00000017", "0?disabletrunk,1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:6] Set("PJSIP/1-00000017", "DIAL_NUMBER=0151...") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:7] Set("PJSIP/1-00000017", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:8] Set("PJSIP/1-00000017", "OUTBOUND_GROUP=OUT_1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:9] Set("PJSIP/1-00000017", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("PJSIP/1-00000017", "1?nomax") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx_builtins.c: Goto (macro-dialout-trunk,s,12)
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("PJSIP/1-00000017", "0?skipoutcid") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("PJSIP/1-00000017", "outbound-callerid,1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("PJSIP/1-00000017", "1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("PJSIP/1-00000017", "") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("PJSIP/1-00000017", "off") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/1-00000017", "0?Set(CALLERID(name-pres)=)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("PJSIP/1-00000017", "0?Set(CALLERID(num-pres)=)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:6] Set("PJSIP/1-00000017", "HOTDESCKCHAN=1-00000017") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:7] Set("PJSIP/1-00000017", "HOTDESKEXTEN=1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:8] Set("PJSIP/1-00000017", "HOTDESKCALL=0") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("PJSIP/1-00000017", "0?Set(HOTDESKCALL=1)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("PJSIP/1-00000017", "0?Set(CALLERID(name)=)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:11] Set("PJSIP/1-00000017", "ALLOWTHISROUTE=NO") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("PJSIP/1-00000017", "0?Set(ALLOWTHISROUTE=YES)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("PJSIP/1-00000017", "0?Hangup()") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("PJSIP/1-00000017", "1?Set(REALCALLERIDNUM=1)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("PJSIP/1-00000017", "0?Set(AMPUSER=1)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("PJSIP/1-00000017", "1?normcid") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:20] Set("PJSIP/1-00000017", "USEROUTCID=") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:21] Set("PJSIP/1-00000017", "EMERGENCYCID=") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("PJSIP/1-00000017", "0?Set(EMERGENCYCID=)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:23] Set("PJSIP/1-00000017", "TRUNKOUTCID=<4941313....>") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("PJSIP/1-00000017", "1?trunkcid") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("PJSIP/1-00000017", "1?Set(CALLERID(all)=<4941313....>)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("PJSIP/1-00000017", "0?Set(CALLERID(all)=)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("PJSIP/1-00000017", "1?Set(CALLERID(all)=<4941313...>)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("PJSIP/1-00000017", "0?Set(CALLERID(all)=1)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:34] Set("PJSIP/1-00000017", "TIOHIDE=no") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:35] ExecIf("PJSIP/1-00000017", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("PJSIP/1-00000017", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("PJSIP/1-00000017", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("PJSIP/1-00000017", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:39] Set("PJSIP/1-00000017", "CDR(outbound_cnum)=4941313...") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-outbound-callerid:40] Set("PJSIP/1-00000017", "CDR(outbound_cnam)=") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("PJSIP/1-00000017", "1?sub-flp-1,s,1()") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@sub-flp-1:1] ExecIf("PJSIP/1-00000017", "1?Return()") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:15] Set("PJSIP/1-00000017", "OUTNUM=0151....") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:16] Set("PJSIP/1-00000017", "custom=PJSIP") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("PJSIP/1-00000017", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Ttr)") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("PJSIP/1-00000017", "0?Set(DIAL_TRUNK_OPTIONS=TtrM(confirm))") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:19] GotoIf("PJSIP/1-00000017", "1?gocall") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx_builtins.c: Goto (macro-dialout-trunk,s,21)
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:21] Macro("PJSIP/1-00000017", "dialout-trunk-predial-hook,") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("PJSIP/1-00000017", "") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf("PJSIP/1-00000017", "0?skipcrm") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:23] Set("PJSIP/1-00000017", "__CRM_DIRECTION=OUTBOUND") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:24] Set("PJSIP/1-00000017", "__CRM_DESTINATION=0151...") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:25] Set("PJSIP/1-00000017", "__CRM_SOURCE=1") in new stack
[2022-02-04 23:52:29] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:26] AGI("PJSIP/1-00000017", "agi://127.0.0.1/sangomacrm.agi") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] res_agi.c: <PJSIP/1-00000017>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:27] Set("PJSIP/1-00000017", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:28] NoOp("PJSIP/1-00000017", "CRM Finished") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:29] GotoIf("PJSIP/1-00000017", "0?bypass,1") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("PJSIP/1-00000017", "1?Set(CONNECTEDLINE(num,i)=0151...)") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:31] ExecIf("PJSIP/1-00000017", "1?Set(CONNECTEDLINE(name,i)=CID:4941313....)") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("PJSIP/1-00000017", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)4941313....)") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:33] GotoIf("PJSIP/1-00000017", "0?customtrunk") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:34] ExecIf("PJSIP/1-00000017", "0?Set(DIAL_TRUNK_OPTIONS=tr)") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:35] Set("PJSIP/1-00000017", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:36] Dial("PJSIP/1-00000017", "PJSIP/0151....@web4free,300,Ttrb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^0151....^0151....^1^1644015149^^4941313....)") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] app_stack.c: PJSIP/web4free-00000018 Internal Gosub(func-apply-sipheaders,s,1(1)) start
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/web4free-00000018", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/web4free-00000018", "Applying SIP Headers to channel PJSIP/web4free-00000018") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/web4free-00000018", "TECH=PJSIP") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/web4free-00000018", "SIPHEADERKEYS=Alert-Info") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@func-apply-sipheaders:5] While("PJSIP/web4free-00000018", "1") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/web4free-00000018", "sipheader=unset") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("PJSIP/web4free-00000018", "1?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
[2022-02-04 23:52:30] ERROR[52480] res_pjsip_header_funcs.c: No headers had been previously added to this session.
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("PJSIP/web4free-00000018", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("PJSIP/web4free-00000018", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("PJSIP/web4free-00000018", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@func-apply-sipheaders:11] EndWhile("PJSIP/web4free-00000018", "") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@func-apply-sipheaders:5] While("PJSIP/web4free-00000018", "0") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@func-apply-sipheaders:12] Return("PJSIP/web4free-00000018", "") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] app_stack.c: Spawn extension (from-pstn, 0151...., 1) exited non-zero on 'PJSIP/web4free-00000018'
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] app_stack.c: PJSIP/web4free-00000018 Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL=
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] app_dial.c: Called PJSIP/0151...@web4free
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] app_dial.c: No one is available to answer at this time (1:0/0/0)
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:37] NoOp("PJSIP/1-00000017", "Dial failed for some reason with DIALSTATUS = NOANSWER and HANGUPCAUSE = 19") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-dialout-trunk:38] GotoIf("PJSIP/1-00000017", "0?continue,1:s-NOANSWER,1") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx_builtins.c: Goto (macro-dialout-trunk,s-NOANSWER,1)
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s-NOANSWER@macro-dialout-trunk:1] NoOp("PJSIP/1-00000017", "Dial failed due to trunk reporting NOANSWER - giving up") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s-NOANSWER@macro-dialout-trunk:2] Progress("PJSIP/1-00000017", "") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] pbx.c: Executing [s-NOANSWER@macro-dialout-trunk:3] Playback("PJSIP/1-00000017", "number-not-answering,noanswer") in new stack
[2022-02-04 23:52:30] VERBOSE[7857][C-0000000f] file.c: <PJSIP/1-00000017> Playing 'number-not-answering.g722' (language 'de_DE')
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s-NOANSWER@macro-dialout-trunk:4] Congestion("PJSIP/1-00000017", "20") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] app_macro.c: Spawn extension (macro-dialout-trunk, s-NOANSWER, 4) exited non-zero on 'PJSIP/1-00000017' in macro 'dialout-trunk'
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Spawn extension (from-internal, 0151..., 12) exited non-zero on 'PJSIP/1-00000017'
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [h@from-internal:1] Macro("PJSIP/1-00000017", "hangupcall") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("PJSIP/1-00000017", "1?theend") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("PJSIP/1-00000017", "0?Set(CDR(recordingfile)=)") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-hangupcall:4] NoOp("PJSIP/1-00000017", " montior file= ") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("PJSIP/1-00000017", "1?skipagi") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx_builtins.c: Goto (macro-hangupcall,s,7)
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@macro-hangupcall:7] Hangup("PJSIP/1-00000017", "") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/1-00000017' in macro 'hangupcall'
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/1-00000017'
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] app_stack.c: PJSIP/1-00000017 Internal Gosub(crm-hangup,s,1) start
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/1-00000017", "Sending Hangup to CRM") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/1-00000017", "HANGUP CAUSE: 34") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/1-00000017", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/1-00000017", "MASTER CHANNEL: 1644015149.24 = 1644015149.24") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/1-00000017", "0?return") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/1-00000017", "__CRM_HANGUP=1") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@crm-hangup:7] AGI("PJSIP/1-00000017", "agi://127.0.0.1/sangomacrm.agi") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] res_agi.c: <PJSIP/1-00000017>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/1-00000017", "") in new stack
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'PJSIP/1-00000017'
[2022-02-04 23:52:32] VERBOSE[7857][C-0000000f] app_stack.c: PJSIP/1-00000017 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=