Outbound call issues with authorization?

buzz11 · January 4, 2025, 9:36am

We are switching from outdated 3CX to FreePBX(IncrediblePBX) and so far I managed to get all working except outbound calls. Suspect what could be a problem but do not know how to manage. I have two nics: local LAN 192.168.0.0/22 and dedicated SIP line 10.x.x.113. SIP server address is 10.X.X.228.
Added routes 10.x.x.0/21 via 10.x.x.1 and 10.x.0.0/16 via 10.x.x.1. No NAT.
Inbound calls are working fine. With outbound calls I got till here.
This is SIP message sent out from working 3CX instance

INVITE sip:[email protected] SIP/2.0
Via: SIP/2.0/ ;branch=z9hG4bK-524287-1—8b6f973b2e51645d;rport
Max-Forwards: 70
Contact: sip:[email protected]:5060
To: sip:[email protected]
From: "+38733xxxxxx"sip:[email protected];tag=5603bd05
Call-ID: 1FgFKuAxAnnClNSj7GlDqQ…
CSeq: 1 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE, UPDATE
Content-Type: application/sdp
Supported: replaces, timer
User-Agent: 3CXPhoneSystem 16.0.8.16 (16)
Remote-Party-ID: "+38733xxxxxx"sip:[email protected];party=calling
Content-Length: 303

v=0
o=3cxPS 377319587840 234813915137 IN IP4 10.x.x.113
s=3cxPS Audio call
c=IN IP4 10.x.x.113
t=0 0
m=audio 7852 RTP/AVP 0 8 18 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=sendrecv

and this one from FreePBX(IncrediblePBX)

INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 10.113.x.x:5060;rport;branch=z9hG4bKPjfc3bed25-c1f1-411f-a718-2883a3f9ca2e
From: “+38733xxxxxx” sip:[email protected];tag=a606c774-e4e1-46e3-b278-d10b3c1c8936
To: sip:[email protected]
Contact: sip:[email protected]:5060
Call-ID: d8c5cf7e-0168-4fd0-bbc3-43ebac85fef8
CSeq: 5495 INVITE
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, INFO, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub, histinfo
Session-Expires: 1800
Min-SE: 90
Remote-Party-ID: “+38733xxxxxx” sip:[email protected];party=calling;privacy=off;screen=no
Max-Forwards: 70
User-Agent: IncrediblePBX-17.0.19.21(19.0)
Content-Type: application/sdp
Content-Length: 329

v=0
o=- 81954688 81954688 IN IP4 10.x.x.113
s=Asterisk
c=IN IP4 10.x.x.113
t=0 0
m=audio 11840 RTP/AVP 0 8 3 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:140
a=sendrecv

They looks pretty much the same but there are some differences I suspect are show stoppers. So, in FreePBX message there are some options not included into 3CX message and probably most important in FreePBX message From and Remote-Party-ID fields there is a space between " < part what is not a case in 3CX message. If the space is a problem how to remove?

Thanks

jcolp · January 4, 2025, 9:37am

I would suggest showing the actual complete SIP exchange, and not just the INVITE. You also haven’t really stated what happens or what the problem is.

buzz11 · January 4, 2025, 10:08am

Sorry. Forgot on that. It rings for 30s and then just ends. Nothing else. Before I figured out, how to inject “xxxxxxx” into the From and Remote-Party-ID fields, it was pretty much the same. Difference was that after the call ends there was a voice message call not answered.
Also forgot to mention SIP trunk is IP authorized.

Cannot insert exchange message. Getting error
An error occurred: Sorry, new users can only put 2 links in a post.
Also have to split into multiple parts as it is 730 lines long

david55 · January 4, 2025, 11:42am

That’s because the angle brackets are being treated as forum markup; you need to mark up the whole log as pre-formatted text. (</> in the buttons, or direct markup with three back ticks and each end).

buzz11 · January 4, 2025, 12:33pm

@david55 Thanks for the tip

Here we go. Part1

INVITE sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.8.0.2:55733;branch=z9hG4bK-d8754z-27417f0ba7270321-1---d8754z-;rport	
Max-Forwards: 70	
Contact: <sip:[email protected]:55733;rinstance=c77dc377dbb3597d>	
To: <sip:[email protected]:5060>	
From: "+38733xxxxxx"<sip:[email protected]:5060>;tag=f77d2977	
Call-ID: Y2E2MmZjNzQ0YzgyNTc3MTk3MjU4MmYyZmNhYzZmZmQ.	
CSeq: 1 INVITE	
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE	
Content-Type: application/sdp	
Supported: replaces	
User-Agent: 3CXPhone 6.0.26523.0	
Content-Length: 389	
	
v=0	
o=3cxVCE 6579885 63305160 IN IP4 10.8.0.2	
s=3cxVCE Audio Call	
c=IN IP4 10.8.0.2	
t=0 0	
m=audio 40030 RTP/AVP 0 8 3 101	
a=rtpmap:0 PCMU/8000	
a=rtpmap:8 PCMA/8000	
a=rtpmap:3 GSM/8000	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-15	
a=ptime:20	
a=sendrecv	
m=video 40010 RTP/AVP 34	
c=IN IP4 10.8.0.2	
a=rtpmap:34 H263/90000	
a=fmtp:34 QCIF=1;CIF=1;SQCIF=1;CIF4=1	
a=sendrecv	
	
[2025-01-04 09:01:04] VERBOSE[45560] res_pjsip_logger.c: <--- Transmitting SIP response (564 bytes) to UDP:192.168.1.111:55733 --->	
SIP/2.0 401 Unauthorized	
Via: SIP/2.0/UDP 10.8.0.2:55733;rport=55733;received=192.168.1.111;branch=z9hG4bK-d8754z-27417f0ba7270321-1---d8754z-	
Call-ID: Y2E2MmZjNzQ0YzgyNTc3MTk3MjU4MmYyZmNhYzZmZmQ.	
From: "+38733xxxxxx" <sip:[email protected]>;tag=f77d2977	
To: <sip:[email protected]>;tag=z9hG4bK-d8754z-27417f0ba7270321-1---d8754z-	
CSeq: 1 INVITE	
WWW-Authenticate: Digest realm="asterisk",nonce="1735981264/1a80cfa1a61bb7252a044c7370197310",opaque="27f7f69464235a06",algorithm=MD5,qop="auth"	
Server: IncrediblePBX-17.0.19.21(19.0)	
Content-Length: 0	
	
	
[2025-01-04 09:01:04] VERBOSE[2021] res_pjsip_logger.c: <--- Received SIP request (386 bytes) from UDP:192.168.1.111:55733 --->	
ACK sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.8.0.2:55733;branch=z9hG4bK-d8754z-27417f0ba7270321-1---d8754z-;rport	
Max-Forwards: 70	
To: <sip:[email protected]>;tag=z9hG4bK-d8754z-27417f0ba7270321-1---d8754z-	
From: "+38733xxxxxx"<sip:[email protected]:5060>;tag=f77d2977	
Call-ID: Y2E2MmZjNzQ0YzgyNTc3MTk3MjU4MmYyZmNhYzZmZmQ.	
CSeq: 1 ACK	
Content-Length: 0	
	
	
[2025-01-04 09:01:04] VERBOSE[2021] res_pjsip_logger.c: <--- Received SIP request (1273 bytes) from UDP:192.168.1.111:55733 --->	
INVITE sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.8.0.2:55733;branch=z9hG4bK-d8754z-501d02672e49ba12-1---d8754z-;rport	
Max-Forwards: 70	
Contact: <sip:[email protected]:55733;rinstance=c77dc377dbb3597d>	
To: <sip:[email protected]:5060>	
From: "+38733xxxxxx"<sip:[email protected]:5060>;tag=f77d2977	
Call-ID: Y2E2MmZjNzQ0YzgyNTc3MTk3MjU4MmYyZmNhYzZmZmQ.	
CSeq: 2 INVITE	
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE	
Content-Type: application/sdp	
Supported: replaces	
User-Agent: 3CXPhone 6.0.26523.0	
Authorization: Digest username="200",realm="asterisk",nonce="1735981264/1a80cfa1a61bb7252a044c7370197310",uri="sip:[email protected]:5060",response="a4b6411d4da07e4848feec654fce3b4d",cnonce="a4261e5a9b6edffc77a0ee30da1dbb7c",nc=00000001,qop=auth,algorithm=MD5,opaque="27f7f69464235a06"	
Content-Length: 389	
	
v=0	
o=3cxVCE 6579885 63305160 IN IP4 10.8.0.2	
s=3cxVCE Audio Call	
c=IN IP4 10.8.0.2	
t=0 0	
m=audio 40030 RTP/AVP 0 8 3 101	
a=rtpmap:0 PCMU/8000	
a=rtpmap:8 PCMA/8000	
a=rtpmap:3 GSM/8000	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-15	
a=ptime:20	
a=sendrecv	
m=video 40010 RTP/AVP 34	
c=IN IP4 10.8.0.2	
a=rtpmap:34 H263/90000	
a=fmtp:34 QCIF=1;CIF=1;SQCIF=1;CIF4=1	
a=sendrecv	
	
[2025-01-04 09:01:04] VERBOSE[45560] res_pjsip_logger.c: <--- Transmitting SIP response (364 bytes) to UDP:192.168.1.111:55733 --->	
SIP/2.0 100 Trying	
Via: SIP/2.0/UDP 10.8.0.2:55733;rport=55733;received=192.168.1.111;branch=z9hG4bK-d8754z-501d02672e49ba12-1---d8754z-	
Call-ID: Y2E2MmZjNzQ0YzgyNTc3MTk3MjU4MmYyZmNhYzZmZmQ.	
From: "+38733xxxxxx" <sip:[email protected]>;tag=f77d2977	
To: <sip:[email protected]>	
CSeq: 2 INVITE	
Server: IncrediblePBX-17.0.19.21(19.0)	
Content-Length: 0	
	
	
[2025-01-04 09:01:04] VERBOSE[45560] netsock2.c: Using SIP RTP Audio TOS bits 184	
[2025-01-04 09:01:04] VERBOSE[45560] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.	
[2025-01-04 09:01:04] VERBOSE[45560] netsock2.c: Using SIP RTP Audio CoS mark 5	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [065xxxxx@from-internal:1] Gosub("PJSIP/200-00000007", "macro-user-callerid,s,1(LIMIT,EXTERNAL)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/200-00000007", "TOUCH_MONITOR=1735981264.7") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/200-00000007", "CHANCONTEXT=") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/200-00000007", "CHANCONTEXT=") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/200-00000007", "CHANEXTENCONTEXT=200-00000007") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/200-00000007", "CHANEXTEN=200-00000007") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/200-00000007", "CALLERID(number)=200") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/200-00000007", "AMPUSER=200") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/200-00000007", "HOTDESCKCHAN=200-00000007") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/200-00000007", "HOTDESKEXTEN=200") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/200-00000007", "HOTDESKCALL=0") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("PJSIP/200-00000007", "0?Set(HOTDESKCALL=1)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(name)=)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/200-00000007", "0?report") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/200-00000007", "1?Set(REALCALLERIDNUM=200)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/200-00000007", "AMPUSER=200") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/200-00000007", "0?limit") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/200-00000007", "AMPUSERCIDNAME=Recepcija") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("PJSIP/200-00000007", "0?Set(__CIDMASQUERADING=TRUE)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/200-00000007", "0?report") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/200-00000007", "AMPUSERCID=200") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:21] Set("PJSIP/200-00000007", "__DIAL_OPTIONS=HhTtr") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:22] Set("PJSIP/200-00000007", "CALLERID(all)="Recepcija" <200>") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("PJSIP/200-00000007", "0?Set(CUSDIAL=)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(all)="Recepcija" <200>)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/200-00000007", "0?limit") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("PJSIP/200-00000007", "1?Set(GROUP(concurrency_limit)=200)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:27] NoOp("PJSIP/200-00000007", "Macro depricated!! To keep the same line numbers") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:28] NoOp("PJSIP/200-00000007", "Macro depricated !! To keep the same line numbers") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("PJSIP/200-00000007", "1?continue") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx_builtins.c: Goto (macro-user-callerid,s,47)	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:47] Set("PJSIP/200-00000007", "CALLERID(number)=200") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:48] Set("PJSIP/200-00000007", "CALLERID(name)=Recepcija") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:49] GotoIf("PJSIP/200-00000007", "0?cnum") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/200-00000007", "__MCNUM=200") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:51] Set("PJSIP/200-00000007", "__MCNAME=Recepcija") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/200-00000007", "__MCEXTEN=200") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/200-00000007", "__MCORGCHAN=PJSIP/200-00000007") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/200-00000007", "CDR(cnam)=Recepcija") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:55] Set("PJSIP/200-00000007", "CDR(cnum)=200") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-user-callerid:56] Return("PJSIP/200-00000007", "") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [065xxxxx@from-internal:2] Gosub("PJSIP/200-00000007", "sub-record-check,s,1(out,065xxxxx,dontcare)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/200-00000007", "0?initialized") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/200-00000007", "__REC_STATUS=INITIALIZED") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/200-00000007", "NOW=1735981264") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/200-00000007", "__DAY=04") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/200-00000007", "__MONTH=01") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/200-00000007", "__YEAR=2025") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/200-00000007", "__TIMESTR=20250104-090104") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/200-00000007", "__FROMEXTEN=200") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/200-00000007", "__MON_FMT=wav") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/200-00000007", "Recordings initialized") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/200-00000007", "0?Set(ARG3=dontcare)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/200-00000007", "REC_POLICY_MODE_SAVE=") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/200-00000007", "0?Set(REC_STATUS=NO)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/200-00000007", "3?checkaction") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx_builtins.c: Goto (sub-record-check,s,17)	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/200-00000007", "1?sub-record-check,out,1") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx_builtins.c: Goto (sub-record-check,out,1)	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/200-00000007", "Outbound Recording Check from 200 to 065xxxxx") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/200-00000007", "RECMODE=dontcare") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/200-00000007", "1?Goto(routewins)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx_builtins.c: Goto (sub-record-check,out,7)	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/200-00000007", "recordcheck,1(dontcare,out,065xxxxx)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/200-00000007", "Starting recording check against dontcare") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/200-00000007", "dontcare") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/200-00000007", "") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/200-00000007", "") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [065xxxxx@from-internal:3] Set("PJSIP/200-00000007", "_ROUTEID=2") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [065xxxxx@from-internal:4] Set("PJSIP/200-00000007", "_ROUTENAME=FKKSS") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [065xxxxx@from-internal:5] Set("PJSIP/200-00000007", "MOHCLASS=default") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [065xxxxx@from-internal:6] Set("PJSIP/200-00000007", "_CALLERIDNAMEINTERNAL=Recepcija") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [065xxxxx@from-internal:7] Set("PJSIP/200-00000007", "_CALLERIDNUMINTERNAL=200") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [065xxxxx@from-internal:8] Set("PJSIP/200-00000007", "_EMAILNOTIFICATION=FALSE") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [065xxxxx@from-internal:9] Set("PJSIP/200-00000007", "_NODEST=") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [065xxxxx@from-internal:10] Gosub("PJSIP/200-00000007", "macro-dialout-trunk,s,1(2,065xxxxx,,off)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/200-00000007", "DIAL_TRUNK=2") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("PJSIP/200-00000007", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:3] ExecIf("PJSIP/200-00000007", "0?Set(DIAL_OPTIONS=HhTr)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("PJSIP/200-00000007", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:5] GosubIf("PJSIP/200-00000007", "0?sub-pincheck,s,1()") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:6] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(num)=200)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf("PJSIP/200-00000007", "0?disabletrunk,1") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:8] Set("PJSIP/200-00000007", "DIAL_NUMBER=06xxxxxx") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:9] Set("PJSIP/200-00000007", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:10] Set("PJSIP/200-00000007", "OUTBOUND_GROUP=OUT_2") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:11] Set("PJSIP/200-00000007", "DIAL_TRUNK_OPTIONS=T") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:12] ExecIf("PJSIP/200-00000007", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:13] GotoIf("PJSIP/200-00000007", "0?nomax") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:14] GotoIf("PJSIP/200-00000007", "0?chanfull") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:15] GotoIf("PJSIP/200-00000007", "0?skipoutcid") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:16] Gosub("PJSIP/200-00000007", "macro-outbound-callerid,s,1(2)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("PJSIP/200-00000007", "200") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("PJSIP/200-00000007", "") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("PJSIP/200-00000007", "off") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(name-pres)=)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(num-pres)=)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:6] Set("PJSIP/200-00000007", "HOTDESCKCHAN=200-00000007") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:7] Set("PJSIP/200-00000007", "HOTDESKEXTEN=200") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:8] Set("PJSIP/200-00000007", "HOTDESKCALL=0") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("PJSIP/200-00000007", "0?Set(HOTDESKCALL=1)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(name)=)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:11] Set("PJSIP/200-00000007", "ALLOWTHISROUTE=NO") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("PJSIP/200-00000007", "0?Set(ALLOWTHISROUTE=YES)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("PJSIP/200-00000007", "0?Hangup()") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("PJSIP/200-00000007", "0?Set(REALCALLERIDNUM=200)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("PJSIP/200-00000007", "0?Set(AMPUSER=200)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("PJSIP/200-00000007", "1?normcid") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:20] Set("PJSIP/200-00000007", "USEROUTCID="+38733xxxxxx"<+38733xxxxxx>") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:21] Set("PJSIP/200-00000007", "EMERGENCYCID=") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("PJSIP/200-00000007", "0?Set(EMERGENCYCID=)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:23] Set("PJSIP/200-00000007", "TRUNKOUTCID=") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("PJSIP/200-00000007", "1?trunkcid") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(all)=)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("PJSIP/200-00000007", "1?Set(CALLERID(all)="+38733xxxxxx"<+38733xxxxxx>)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(all)=)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(all)=200)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:34] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(all)=200)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:35] Set("PJSIP/200-00000007", "TIOHIDE=no") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:39] ExecIf("PJSIP/200-00000007", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:40] Set("PJSIP/200-00000007", "CDR(outbound_cnum)=+38733xxxxxx") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:41] Set("PJSIP/200-00000007", "CDR(outbound_cnam)=+38733xxxxxx") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-outbound-callerid:42] Return("PJSIP/200-00000007", "") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:17] GosubIf("PJSIP/200-00000007", "0?sub-flp-2,s,1()") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:18] Set("PJSIP/200-00000007", "OUTNUM=06xxxxxx") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:19] Set("PJSIP/200-00000007", "custom=PJSIP") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("PJSIP/200-00000007", "0?Set(DIAL_TRUNK_MOH=default)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("PJSIP/200-00000007", "0?Set(DIAL_TRUNK_OPTIONS=TU(macro-confirm))") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf("PJSIP/200-00000007", "0?AGI(allowlist-autoadd.agi,)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:23] Gosub("PJSIP/200-00000007", "macro-dialout-trunk-predial-hook,s,1()") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] Return("PJSIP/200-00000007", "") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:24] GotoIf("PJSIP/200-00000007", "0?bypass,1") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:25] ExecIf("PJSIP/200-00000007", "1?Set(CONNECTEDLINE(num,i)=065xxxxx)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:26] ExecIf("PJSIP/200-00000007", "1?Set(CONNECTEDLINE(name,i)=CID:+38733xxxxxx)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:27] ExecIf("PJSIP/200-00000007", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)+38733xxxxxx)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:28] GotoIf("PJSIP/200-00000007", "0?customtrunk") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("PJSIP/200-00000007", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:30] Set("PJSIP/200-00000007", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@macro-dialout-trunk:31] Gosub("PJSIP/200-00000007", "trunk-dial-with-exten,065xxxxx,1()") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [065xxxxx@trunk-dial-with-exten:1] Dial("PJSIP/200-00000007", "PJSIP/06xxxxxx@+38733xxxxxx,300,Tb(func-apply-sipheaders^s^1,(2))U(sub-send-obroute-email^06xxxxxx^^2^1735981264^+38733xxxxxx^+38733xxxxxx,^)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] app_stack.c: PJSIP/+38733xxxxxx-00000008 Internal Gosub(func-apply-sipheaders,s,1(2)) start	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("PJSIP/+38733xxxxxx-00000008", "Applying SIP Headers to channel PJSIP/+38733xxxxxx-00000008") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:2] Set("PJSIP/+38733xxxxxx-00000008", "localchan=+38733xxxxxx-00000008") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/+38733xxxxxx-00000008", "DialMCEXT=+38733xxxxxx") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/+38733xxxxxx-00000008", "CHANNEL(hangup_handler_push)=app-missedcall-hangup,+38733xxxxxx,1") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:5] Set("PJSIP/+38733xxxxxx-00000008", "Dchan=+38733xxxxxx-00000008") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/+38733xxxxxx-00000008", "TECH=PJSIP") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:7] Set("PJSIP/+38733xxxxxx-00000008", "SIPHEADERKEYS=Alert-Info") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:8] While("PJSIP/+38733xxxxxx-00000008", "1") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:9] Set("PJSIP/+38733xxxxxx-00000008", "sipheader=unset") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("PJSIP/+38733xxxxxx-00000008", "1?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack	
[2025-01-04 09:01:04] ERROR[45560] res_pjsip_header_funcs.c: No headers had been previously added to this session.	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("PJSIP/+38733xxxxxx-00000008", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("PJSIP/+38733xxxxxx-00000008", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:13] ExecIf("PJSIP/+38733xxxxxx-00000008", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:14] EndWhile("PJSIP/+38733xxxxxx-00000008", "") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:8] While("PJSIP/+38733xxxxxx-00000008", "0") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] pbx.c: Executing [s@func-apply-sipheaders:15] Return("PJSIP/+38733xxxxxx-00000008", "") in new stack	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] app_stack.c: Spawn extension (from-pstn, 06xxxxxx, 1) exited non-zero on 'PJSIP/+38733xxxxxx-00000008'	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] app_stack.c: PJSIP/+38733xxxxxx-00000008 Internal Gosub(func-apply-sipheaders,s,1(2)) complete GOSUB_RETVAL=	
[2025-01-04 09:01:04] VERBOSE[47110][C-00000006] app_dial.c: Called PJSIP/06xxxxxx@+38733xxxxxx	
[2025-01-04 09:01:04] VERBOSE[45560] res_pjsip_logger.c: <--- Transmitting SIP request (1154 bytes) to UDP:10.x.x.1:5060 --->

buzz11 · January 4, 2025, 12:34pm

Part2

INVITE sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.x.x.113:5060;rport;branch=z9hG4bKPjb27c22ec-2e5a-424d-ae4a-f48428322646	
From: "+38733xxxxxx" <sip:[email protected]>;tag=311a0c23-8cca-4878-9fe0-90a36532d7d6	
To: <sip:[email protected]>	
Contact: <sip:[email protected]:5060>	
Call-ID: 6c2c9df7-3512-4e95-a9f2-181c452b7b6b	
CSeq: 464 INVITE	
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, INFO, MESSAGE, REFER	
Supported: 100rel, timer, replaces, norefersub, histinfo	
Session-Expires: 1800	
Min-SE: 90	
Remote-Party-ID: "+38733xxxxxx" <sip:[email protected]>;party=calling;privacy=off;screen=no	
Max-Forwards: 70	
User-Agent: IncrediblePBX-17.0.19.21(19.0)	
Content-Type: application/sdp	
Content-Length: 331	
	
v=0	
o=- 832080848 832080848 IN IP4 10.x.x.113	
s=Asterisk	
c=IN IP4 10.x.x.113	
t=0 0	
m=audio 15600 RTP/AVP 0 8 3 18 101	
a=rtpmap:0 PCMU/8000	
a=rtpmap:8 PCMA/8000	
a=rtpmap:3 GSM/8000	
a=rtpmap:18 G729/8000	
a=fmtp:18 annexb=no	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-16	
a=ptime:20	
a=maxptime:140	
a=sendrecv	
	
[2025-01-04 09:01:04] VERBOSE[2021] res_pjsip_logger.c: <--- Transmitting SIP request (1154 bytes) to UDP:10.x.x.1:5060 --->	
INVITE sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.x.x.113:5060;rport;branch=z9hG4bKPjb27c22ec-2e5a-424d-ae4a-f48428322646	
From: "+38733xxxxxx" <sip:[email protected]>;tag=311a0c23-8cca-4878-9fe0-90a36532d7d6	
To: <sip:[email protected]>	
Contact: <sip:[email protected]:5060>	
Call-ID: 6c2c9df7-3512-4e95-a9f2-181c452b7b6b	
CSeq: 464 INVITE	
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, INFO, MESSAGE, REFER	
Supported: 100rel, timer, replaces, norefersub, histinfo	
Session-Expires: 1800	
Min-SE: 90	
Remote-Party-ID: "+38733xxxxxx" <sip:[email protected]>;party=calling;privacy=off;screen=no	
Max-Forwards: 70	
User-Agent: IncrediblePBX-17.0.19.21(19.0)	
Content-Type: application/sdp	
Content-Length: 331	
	
v=0	
o=- 832080848 832080848 IN IP4 10.x.x.113	
s=Asterisk	
c=IN IP4 10.x.x.113	
t=0 0	
m=audio 15600 RTP/AVP 0 8 3 18 101	
a=rtpmap:0 PCMU/8000	
a=rtpmap:8 PCMA/8000	
a=rtpmap:3 GSM/8000	
a=rtpmap:18 G729/8000	
a=fmtp:18 annexb=no	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-16	
a=ptime:20	
a=maxptime:140	
a=sendrecv	
	
[2025-01-04 09:01:05] VERBOSE[2021] res_pjsip_logger.c: <--- Transmitting SIP request (1154 bytes) to UDP:10.x.x.1:5060 --->	
INVITE sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.x.x.113:5060;rport;branch=z9hG4bKPjb27c22ec-2e5a-424d-ae4a-f48428322646	
From: "+38733xxxxxx" <sip:[email protected]>;tag=311a0c23-8cca-4878-9fe0-90a36532d7d6	
To: <sip:[email protected]>	
Contact: <sip:[email protected]:5060>	
Call-ID: 6c2c9df7-3512-4e95-a9f2-181c452b7b6b	
CSeq: 464 INVITE	
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, INFO, MESSAGE, REFER	
Supported: 100rel, timer, replaces, norefersub, histinfo	
Session-Expires: 1800	
Min-SE: 90	
Remote-Party-ID: "+38733xxxxxx" <sip:[email protected]>;party=calling;privacy=off;screen=no	
Max-Forwards: 70	
User-Agent: IncrediblePBX-17.0.19.21(19.0)	
Content-Type: application/sdp	
Content-Length: 331	
	
v=0	
o=- 832080848 832080848 IN IP4 10.x.x.113	
s=Asterisk	
c=IN IP4 10.x.x.113	
t=0 0	
m=audio 15600 RTP/AVP 0 8 3 18 101	
a=rtpmap:0 PCMU/8000	
a=rtpmap:8 PCMA/8000	
a=rtpmap:3 GSM/8000	
a=rtpmap:18 G729/8000	
a=fmtp:18 annexb=no	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-16	
a=ptime:20	
a=maxptime:140	
a=sendrecv	
	
[2025-01-04 09:01:07] VERBOSE[2021] res_pjsip_logger.c: <--- Transmitting SIP request (1154 bytes) to UDP:10.x.x.1:5060 --->	
INVITE sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.x.x.113:5060;rport;branch=z9hG4bKPjb27c22ec-2e5a-424d-ae4a-f48428322646	
From: "+38733xxxxxx" <sip:[email protected]>;tag=311a0c23-8cca-4878-9fe0-90a36532d7d6	
To: <sip:[email protected]>	
Contact: <sip:[email protected]:5060>	
Call-ID: 6c2c9df7-3512-4e95-a9f2-181c452b7b6b	
CSeq: 464 INVITE	
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, INFO, MESSAGE, REFER	
Supported: 100rel, timer, replaces, norefersub, histinfo	
Session-Expires: 1800	
Min-SE: 90	
Remote-Party-ID: "+38733xxxxxx" <sip:[email protected]>;party=calling;privacy=off;screen=no	
Max-Forwards: 70	
User-Agent: IncrediblePBX-17.0.19.21(19.0)	
Content-Type: application/sdp	
Content-Length: 331	
	
v=0	
o=- 832080848 832080848 IN IP4 10.x.x.113	
s=Asterisk	
c=IN IP4 10.x.x.113	
t=0 0	
m=audio 15600 RTP/AVP 0 8 3 18 101	
a=rtpmap:0 PCMU/8000	
a=rtpmap:8 PCMA/8000	
a=rtpmap:3 GSM/8000	
a=rtpmap:18 G729/8000	
a=fmtp:18 annexb=no	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-16	
a=ptime:20	
a=maxptime:140	
a=sendrecv	
	
[2025-01-04 09:01:07] VERBOSE[2021] res_pjsip_logger.c: <--- Received SIP request (801 bytes) from UDP:192.168.1.111:55733 --->	
SUBSCRIBE sip:192.168.1.44:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.8.0.2:55733;branch=z9hG4bK-d8754z-ae5de4406e040c68-1---d8754z-;rport	
Max-Forwards: 70	
Contact: <sip:[email protected]:55733;rinstance=c77dc377dbb3597d>	
To: "+38733xxxxxx"<sip:[email protected]>;tag=143ddebb-8c0d-4779-a622-b077f28501b8	
From: "+38733xxxxxx"<sip:[email protected]>;tag=68071e07	
Call-ID: NjQwNWQwZTYxN2FiOWNjYjNhZjY5YjM5OTdmNjIyN2E.	
CSeq: 22 SUBSCRIBE	
Expires: 119	
User-Agent: 3CXPhone 6.0.26523.0	
Authorization: Digest username="200",realm="asterisk",nonce="1735979234/00ad6577369f8c0f2f45d9dc73a0d639",uri="sip:192.168.1.44:5060",response="a36e371498eee3f9b717fa41c2318ae8",cnonce="aa2e839269b8b48956d3e70852f1ad90",nc=00000014,qop=auth,algorithm=MD5,opaque="5d0485ac683f6b56"	
Event: message-summary	
Content-Length: 0	
	
	
[2025-01-04 09:01:07] VERBOSE[45560] res_pjsip_logger.c: <--- Transmitting SIP response (627 bytes) to UDP:192.168.1.111:55733 --->	
SIP/2.0 200 OK	
Via: SIP/2.0/UDP 10.8.0.2:55733;rport=55733;received=192.168.1.111;branch=z9hG4bK-d8754z-ae5de4406e040c68-1---d8754z-	
Call-ID: NjQwNWQwZTYxN2FiOWNjYjNhZjY5YjM5OTdmNjIyN2E.	
From: "+38733xxxxxx" <sip:[email protected]>;tag=68071e07	
To: "+38733xxxxxx" <sip:[email protected]>;tag=143ddebb-8c0d-4779-a622-b077f28501b8	
CSeq: 22 SUBSCRIBE	
Expires: 119	
Contact: <sip:192.168.1.44:5060>	
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, INFO, MESSAGE, REFER	
Supported: 100rel, timer, replaces, norefersub	
Server: IncrediblePBX-17.0.19.21(19.0)	
Content-Length: 0	
	
	
[2025-01-04 09:01:07] VERBOSE[45560] res_pjsip_logger.c: <--- Transmitting SIP request (723 bytes) to UDP:192.168.1.111:55733 --->	
NOTIFY sip:[email protected]:55733;rinstance=c77dc377dbb3597d SIP/2.0	
Via: SIP/2.0/UDP 192.168.1.44:5060;rport;branch=z9hG4bKPj12209a05-8d35-4dbc-b9b4-7819ca7a1a0f	
From: "+38733xxxxxx" <sip:[email protected]>;tag=143ddebb-8c0d-4779-a622-b077f28501b8	
To: "+38733xxxxxx" <sip:[email protected]>;tag=68071e07	
Contact: <sip:192.168.1.44:5060>	
Call-ID: NjQwNWQwZTYxN2FiOWNjYjNhZjY5YjM5OTdmNjIyN2E.	
CSeq: 28226 NOTIFY	
Event: message-summary	
Subscription-State: active;expires=119	
Allow-Events: presence, dialog, message-summary, refer	
Max-Forwards: 70	
User-Agent: IncrediblePBX-17.0.19.21(19.0)	
Content-Type: application/simple-message-summary	
Content-Length: 49	
	
Messages-Waiting: yes	
Voice-Message: 2/0 (0/0)	
	
[2025-01-04 09:01:08] VERBOSE[2021] res_pjsip_logger.c: <--- Received SIP response (448 bytes) from UDP:192.168.1.111:55733 --->	
SIP/2.0 200 OK	
Via: SIP/2.0/UDP 192.168.1.44:5060;rport=5060;branch=z9hG4bKPj12209a05-8d35-4dbc-b9b4-7819ca7a1a0f	
Contact: <sip:[email protected]:55733;rinstance=c77dc377dbb3597d>	
To: "+38733xxxxxx"<sip:[email protected]>;tag=68071e07	
From: "+38733xxxxxx"<sip:[email protected]>;tag=143ddebb-8c0d-4779-a622-b077f28501b8	
Call-ID: NjQwNWQwZTYxN2FiOWNjYjNhZjY5YjM5OTdmNjIyN2E.	
CSeq: 28226 NOTIFY	
User-Agent: 3CXPhone 6.0.26523.0	
Content-Length: 0	
	
	
[2025-01-04 09:01:11] VERBOSE[2021] res_pjsip_logger.c: <--- Transmitting SIP request (1154 bytes) to UDP:10.x.x.1:5060 --->	
INVITE sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.x.x.113:5060;rport;branch=z9hG4bKPjb27c22ec-2e5a-424d-ae4a-f48428322646	
From: "+38733xxxxxx" <sip:[email protected]>;tag=311a0c23-8cca-4878-9fe0-90a36532d7d6	
To: <sip:[email protected]>	
Contact: <sip:[email protected]:5060>	
Call-ID: 6c2c9df7-3512-4e95-a9f2-181c452b7b6b	
CSeq: 464 INVITE	
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, INFO, MESSAGE, REFER	
Supported: 100rel, timer, replaces, norefersub, histinfo	
Session-Expires: 1800	
Min-SE: 90	
Remote-Party-ID: "+38733xxxxxx" <sip:[email protected]>;party=calling;privacy=off;screen=no	
Max-Forwards: 70	
User-Agent: IncrediblePBX-17.0.19.21(19.0)	
Content-Type: application/sdp	
Content-Length: 331	
	
v=0	
o=- 832080848 832080848 IN IP4 10.x.x.113	
s=Asterisk	
c=IN IP4 10.x.x.113	
t=0 0	
m=audio 15600 RTP/AVP 0 8 3 18 101	
a=rtpmap:0 PCMU/8000	
a=rtpmap:8 PCMA/8000	
a=rtpmap:3 GSM/8000	
a=rtpmap:18 G729/8000	
a=fmtp:18 annexb=no	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-16	
a=ptime:20	
a=maxptime:140	
a=sendrecv	
	
[2025-01-04 09:01:13] VERBOSE[2021] res_pjsip_logger.c: <--- Received SIP request (858 bytes) from UDP:192.168.1.111:55733 --->	
REGISTER sip:192.168.1.44:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.8.0.2:55733;branch=z9hG4bK-d8754z-8c33c223524d4f19-1---d8754z-;rport	
Max-Forwards: 70	
Contact: <sip:[email protected]:55733;rinstance=c77dc377dbb3597d>	
To: "+38733xxxxxx"<sip:[email protected]:5060>	
From: "+38733xxxxxx"<sip:[email protected]:5060>;tag=290fdc7c	
Call-ID: NDJlY2VmZDJmMjNhZWM1YmY2MzRmMzQwMTM4MmNhNzQ.	
CSeq: 39 REGISTER	
Expires: 120	
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE	
Supported: replaces	
User-Agent: 3CXPhone 6.0.26523.0	
Authorization: Digest username="200",realm="asterisk",nonce="1735981166/9ab6e4bbc41081c00fac3551942bfd30",uri="sip:192.168.1.44:5060",response="baa3024cb7bd23dc1d3c6015138a7382",cnonce="21a5a10e23a01e908f5716f44861a144",nc=00000002,qop=auth,algorithm=MD5,opaque="4d72327f5f7ac868"	
Content-Length: 0	
	
	
[2025-01-04 09:01:13] VERBOSE[45560] res_pjsip_logger.c: <--- Transmitting SIP response (587 bytes) to UDP:192.168.1.111:55733 --->	
SIP/2.0 401 Unauthorized	
Via: SIP/2.0/UDP 10.8.0.2:55733;rport=55733;received=192.168.1.111;branch=z9hG4bK-d8754z-8c33c223524d4f19-1---d8754z-	
Call-ID: NDJlY2VmZDJmMjNhZWM1YmY2MzRmMzQwMTM4MmNhNzQ.	
From: "+38733xxxxxx" <sip:[email protected]>;tag=290fdc7c	
To: "+38733xxxxxx" <sip:[email protected]>;tag=z9hG4bK-d8754z-8c33c223524d4f19-1---d8754z-	
CSeq: 39 REGISTER	
WWW-Authenticate: Digest realm="asterisk",nonce="1735981273/b6bf82e565a762ccc8ea81e9614d6f02",opaque="50f001376791240d",stale=true,algorithm=MD5,qop="auth"	
Server: IncrediblePBX-17.0.19.21(19.0)	
Content-Length: 0	
	
	
[2025-01-04 09:01:14] VERBOSE[2021] res_pjsip_logger.c: <--- Received SIP request (858 bytes) from UDP:192.168.1.111:55733 --->	
REGISTER sip:192.168.1.44:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.8.0.2:55733;branch=z9hG4bK-d8754z-522557534422b464-1---d8754z-;rport	
Max-Forwards: 70	
Contact: <sip:[email protected]:55733;rinstance=c77dc377dbb3597d>	
To: "+38733xxxxxx"<sip:[email protected]:5060>	
From: "+38733xxxxxx"<sip:[email protected]:5060>;tag=290fdc7c	
Call-ID: NDJlY2VmZDJmMjNhZWM1YmY2MzRmMzQwMTM4MmNhNzQ.	
CSeq: 40 REGISTER	
Expires: 120	
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE	
Supported: replaces	
User-Agent: 3CXPhone 6.0.26523.0	
Authorization: Digest username="200",realm="asterisk",nonce="1735981273/b6bf82e565a762ccc8ea81e9614d6f02",uri="sip:192.168.1.44:5060",response="8665b7a638b0f0a9990f7cfaa48a647a",cnonce="9bda9ff1b62a6d3f6da95a95ce15e994",nc=00000001,qop=auth,algorithm=MD5,opaque="50f001376791240d"	
Content-Length: 0	
	
	
[2025-01-04 09:01:14] VERBOSE[45560] res_pjsip_logger.c: <--- Transmitting SIP response (545 bytes) to UDP:192.168.1.111:55733 --->	
SIP/2.0 200 OK	
Via: SIP/2.0/UDP 10.8.0.2:55733;rport=55733;received=192.168.1.111;branch=z9hG4bK-d8754z-522557534422b464-1---d8754z-	
Call-ID: NDJlY2VmZDJmMjNhZWM1YmY2MzRmMzQwMTM4MmNhNzQ.	
From: "+38733xxxxxx" <sip:[email protected]>;tag=290fdc7c	
To: "+38733xxxxxx" <sip:[email protected]>;tag=z9hG4bK-d8754z-522557534422b464-1---d8754z-	
CSeq: 40 REGISTER	
Date: Sat, 04 Jan 2025 09:01:14 GMT	
Contact: <sip:[email protected]:55733;rinstance=c77dc377dbb3597d>;expires=119	
Expires: 120	
Server: IncrediblePBX-17.0.19.21(19.0)	
Content-Length: 0	
	
	
[2025-01-04 09:01:14] VERBOSE[45560] res_pjsip_logger.c: <--- Transmitting SIP request (484 bytes) to UDP:192.168.1.111:55733 --->	
OPTIONS sip:[email protected]:55733;rinstance=c77dc377dbb3597d SIP/2.0	
Via: SIP/2.0/UDP 192.168.1.44:5060;rport;branch=z9hG4bKPj823533b9-6427-4d65-9f46-9642b90c6cab	
From: <sip:[email protected]>;tag=e3ccfb39-e917-4664-9460-dc5d24271df8	
To: <sip:[email protected];rinstance=c77dc377dbb3597d>	
Contact: <sip:[email protected]:5060>	
Call-ID: c01b296f-e518-42c1-82f3-b189c5eb9357	
CSeq: 37914 OPTIONS	
Max-Forwards: 70	
User-Agent: IncrediblePBX-17.0.19.21(19.0)	
Content-Length: 0	
	
	
[2025-01-04 09:01:14] VERBOSE[2021] res_pjsip_logger.c: <--- Received SIP response (590 bytes) from UDP:192.168.1.111:55733 --->	
SIP/2.0 200 OK	
Via: SIP/2.0/UDP 192.168.1.44:5060;rport=5060;branch=z9hG4bKPj823533b9-6427-4d65-9f46-9642b90c6cab	
Contact: <sip:10.8.0.2:55733>	
To: <sip:[email protected];rinstance=c77dc377dbb3597d>;tag=c03eaf4a	
From: <sip:[email protected]>;tag=e3ccfb39-e917-4664-9460-dc5d24271df8	
Call-ID: c01b296f-e518-42c1-82f3-b189c5eb9357	
CSeq: 37914 OPTIONS	
Accept: application/sdp	
Accept-Language: en	
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REGISTER, SUBSCRIBE, NOTIFY, REFER, INFO, MESSAGE	
Supported: replaces	
Allow-Events: presence, message-summary, tunnel-info	
Content-Length: 0	
	
	
[2025-01-04 09:01:19] VERBOSE[2021] res_pjsip_logger.c: <--- Transmitting SIP request (1154 bytes) to UDP:10.x.x.1:5060 --->	
INVITE sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.x.x.113:5060;rport;branch=z9hG4bKPjb27c22ec-2e5a-424d-ae4a-f48428322646	
From: "+38733xxxxxx" <sip:[email protected]>;tag=311a0c23-8cca-4878-9fe0-90a36532d7d6	
To: <sip:[email protected]>	
Contact: <sip:[email protected]:5060>	
Call-ID: 6c2c9df7-3512-4e95-a9f2-181c452b7b6b	
CSeq: 464 INVITE	
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, INFO, MESSAGE, REFER	
Supported: 100rel, timer, replaces, norefersub, histinfo	
Session-Expires: 1800	
Min-SE: 90	
Remote-Party-ID: "+38733xxxxxx" <sip:[email protected]>;party=calling;privacy=off;screen=no	
Max-Forwards: 70	
User-Agent: IncrediblePBX-17.0.19.21(19.0)	
Content-Type: application/sdp	
Content-Length: 331	
	
v=0	
o=- 832080848 832080848 IN IP4 10.x.x.113	
s=Asterisk	
c=IN IP4 10.x.x.113	
t=0 0	
m=audio 15600 RTP/AVP 0 8 3 18 101	
a=rtpmap:0 PCMU/8000	
a=rtpmap:8 PCMA/8000	
a=rtpmap:3 GSM/8000	
a=rtpmap:18 G729/8000	
a=fmtp:18 annexb=no	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-16	
a=ptime:20	
a=maxptime:140	
a=sendrecv	
	
[2025-01-04 09:01:35] VERBOSE[2021] res_pjsip_logger.c: <--- Transmitting SIP request (1154 bytes) to UDP:10.x.x.1:5060 --->	
INVITE sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.x.x.113:5060;rport;branch=z9hG4bKPjb27c22ec-2e5a-424d-ae4a-f48428322646	
From: "+38733xxxxxx" <sip:[email protected]>;tag=311a0c23-8cca-4878-9fe0-90a36532d7d6	
To: <sip:[email protected]>	
Contact: <sip:[email protected]:5060>	
Call-ID: 6c2c9df7-3512-4e95-a9f2-181c452b7b6b	
CSeq: 464 INVITE	
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, INFO, MESSAGE, REFER	
Supported: 100rel, timer, replaces, norefersub, histinfo	
Session-Expires: 1800	
Min-SE: 90	
Remote-Party-ID: "+38733xxxxxx" <sip:[email protected]>;party=calling;privacy=off;screen=no	
Max-Forwards: 70	
User-Agent: IncrediblePBX-17.0.19.21(19.0)	
Content-Type: application/sdp	
Content-Length: 331	
	
v=0	
o=- 832080848 832080848 IN IP4 10.x.x.113	
s=Asterisk	
c=IN IP4 10.x.x.113	
t=0 0	
m=audio 15600 RTP/AVP 0 8 3 18 101	
a=rtpmap:0 PCMU/8000	
a=rtpmap:8 PCMA/8000	
a=rtpmap:3 GSM/8000	
a=rtpmap:18 G729/8000	
a=fmtp:18 annexb=no	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-16	
a=ptime:20	
a=maxptime:140	
a=sendrecv	
	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] app_stack.c: PJSIP/+38733xxxxxx-00000008 Internal Gosub(app-missedcall-hangup,+38733xxxxxx,1) start	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:1] NoOp("PJSIP/+38733xxxxxx-00000008", "Dialed: +38733xxxxxx") in new stack	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:2] NoOp("PJSIP/+38733xxxxxx-00000008", "Caller: 200") in new stack	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:3] GotoIf("PJSIP/+38733xxxxxx-00000008", "0?exit") in new stack	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:4] Set("PJSIP/+38733xxxxxx-00000008", "EXTENNUM=+38733xxxxxx") in new stack	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:5] Set("PJSIP/+38733xxxxxx-00000008", "FEXTENNUM=+38733xxxxxx") in new stack	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:6] GotoIf("PJSIP/+38733xxxxxx-00000008", "0?exit") in new stack	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:7] AGI("PJSIP/+38733xxxxxx-00000008", "missedcallnotify.php,+38733xxxxxx,,+38733xxxxxx,0,,PJSIP/+38733xxxxxx-00000008,,,,") in new stack	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/missedcallnotify.php	
[2025-01-04 09:01:36] VERBOSE[2021] res_pjsip_logger.c: <--- Received SIP request (675 bytes) from UDP:192.168.1.111:55733 --->	
CANCEL sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.8.0.2:55733;branch=z9hG4bK-d8754z-501d02672e49ba12-1---d8754z-;rport	
Max-Forwards: 70	
To: <sip:[email protected]:5060>	
From: "+38733xxxxxx"<sip:[email protected]:5060>;tag=f77d2977	
Call-ID: Y2E2MmZjNzQ0YzgyNTc3MTk3MjU4MmYyZmNhYzZmZmQ.	
CSeq: 2 CANCEL	
User-Agent: 3CXPhone 6.0.26523.0	
Authorization: Digest username="200",realm="asterisk",nonce="1735981264/1a80cfa1a61bb7252a044c7370197310",uri="sip:[email protected]:5060",response="0e132aab4fa36e3cbcac34a152d859a1",cnonce="6f5dfa0dcca08099f5f02ac4dcb7c254",nc=00000002,qop=auth,algorithm=MD5,opaque="27f7f69464235a06"	
Content-Length: 0	
	
	
[2025-01-04 09:01:36] VERBOSE[45560] res_pjsip_logger.c: <--- Transmitting SIP response (401 bytes) to UDP:192.168.1.111:55733 --->	
SIP/2.0 200 OK	
Via: SIP/2.0/UDP 10.8.0.2:55733;rport=55733;received=192.168.1.111;branch=z9hG4bK-d8754z-501d02672e49ba12-1---d8754z-	
Call-ID: Y2E2MmZjNzQ0YzgyNTc3MTk3MjU4MmYyZmNhYzZmZmQ.	
From: "+38733xxxxxx" <sip:[email protected]>;tag=f77d2977	
To: <sip:[email protected]>;tag=375e7cf4-8c3e-41ad-8aa6-5cd2be0b53ec	
CSeq: 2 CANCEL	
Server: IncrediblePBX-17.0.19.21(19.0)	
Content-Length: 0	
	
	
[2025-01-04 09:01:36] VERBOSE[45560] res_pjsip_logger.c: <--- Transmitting SIP response (542 bytes) to UDP:192.168.1.111:55733 --->	
SIP/2.0 487 Request Terminated	
Via: SIP/2.0/UDP 10.8.0.2:55733;rport=55733;received=192.168.1.111;branch=z9hG4bK-d8754z-501d02672e49ba12-1---d8754z-	
Call-ID: Y2E2MmZjNzQ0YzgyNTc3MTk3MjU4MmYyZmNhYzZmZmQ.	
From: "+38733xxxxxx" <sip:[email protected]>;tag=f77d2977	
To: <sip:[email protected]>;tag=375e7cf4-8c3e-41ad-8aa6-5cd2be0b53ec	
CSeq: 2 INVITE	
Server: IncrediblePBX-17.0.19.21(19.0)	
Reason: Q.850;cause=18	
Remote-Party-ID: "CID:+38733xxxxxx" <sip:[email protected]>;party=called;privacy=off;screen=no	
Content-Length: 0	
	
	
[2025-01-04 09:01:36] VERBOSE[2021] res_pjsip_logger.c: <--- Received SIP request (379 bytes) from UDP:192.168.1.111:55733 --->	
ACK sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.8.0.2:55733;branch=z9hG4bK-d8754z-501d02672e49ba12-1---d8754z-;rport	
Max-Forwards: 70	
To: <sip:[email protected]>;tag=375e7cf4-8c3e-41ad-8aa6-5cd2be0b53ec	
From: "+38733xxxxxx"<sip:[email protected]:5060>;tag=f77d2977	
Call-ID: Y2E2MmZjNzQ0YzgyNTc3MTk3MjU4MmYyZmNhYzZmZmQ.	
CSeq: 2 ACK	
Content-Length: 0	
	
	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] res_agi.c: <PJSIP/+38733xxxxxx-00000008>AGI Script missedcallnotify.php completed, returning 0	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:8] Return("PJSIP/+38733xxxxxx-00000008", "") in new stack	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] app_stack.c: Spawn extension (from-pstn, 06xxxxxx, 1) exited non-zero on 'PJSIP/+38733xxxxxx-00000008'	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] app_stack.c: PJSIP/+38733xxxxxx-00000008 Internal Gosub(app-missedcall-hangup,+38733xxxxxx,1) complete GOSUB_RETVAL=	
[2025-01-04 09:01:36] VERBOSE[47110][C-00000006] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)

jcolp · January 4, 2025, 12:52pm

Asterisk is not getting any response to the SIP INVITE. This usually means networking related outside of Asterisk.

david55 · January 4, 2025, 12:55pm

10/24 is a private network, so I don’t understand why you are redacting its addresses.

You’ve sent the request to 10.0.0.1, which I think is your router, but the request URI says it is destined for 10.x.x.228. The 3CX log doesn’t show to what address the request was actually sent. My guess is that you have configured the router as an outbound proxy, when it isn’t. What does the provider say about this?

I don’t see an authorisation issue. What I see is mutiple transmissions with no responses.

buzz11 · January 4, 2025, 1:05pm

Unfortunately not sure what do you mean by that.

You’re right. 10.0.0.1 is router/gateway and yes I have configured it as outbound proxy. If proxy is set to 10.0.0.228 or empty then call immediately drops with voice message all circuits are busy now.
Unforunately 3CX logs even verbose are less informative than FreePBX logs.
Had no chance to get in touch with provider yet.

jcolp · January 4, 2025, 1:20pm

Is 10.0.0.1 actually a SIP outbound proxy? If not, then it would get no response and the log would show exactly what it is showing. I’d suggest removing it as an outbound proxy and showing a trace without it.

buzz11 · January 4, 2025, 1:20pm

Found few more lines in 3CX logs if that tell you anything

01/03/2025 10:03:37 PM - Provisional(183) from sip:[email protected];tag=311228548-1735938217590 to "+38733xxxxxx"sip:[email protected];tag=5603bd05
01/03/2025 10:03:37 PM - [CM505003]: Provider:[+38733561200] Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: PBX contact: [sip:[email protected]:5060]
01/03/2025 10:03:37 PM - [CM503002]: Call(C:202): Alerting Line:10000>>065xxxxxx by contact sip:[email protected]:0/UDP
01/03/2025 10:03:37 PM - Answer from sip:[email protected];tag=311228548-1735938217590 to "+38733xxxxxx"sip:[email protected];tag=5603bd05
01/03/2025 10:03:36 PM - Dev(1663653447):[sip:[email protected]:5130;transport=tcp;user=phone / 245]: Reliable transport is used on existing connection, PBX contact=sip:[email protected]:5060/TCP; local

buzz11 · January 4, 2025, 1:27pm

I guess it is not but I was trying different combinations.
To clarify:
10.x.x.1 is router/gateway (I guess because not provided by provider) i got this from routing table from 3CX server. Same two routes I have applied here.
10.x.x.113 is FreePBX IP address given from provider
10.x.x.66 and 10.x.x.67 are two DNS IP’s given from provider
10.x.x.228 is SIP server IP

I can remove outbound proxy and post SIP exchange again.

david55 · January 4, 2025, 1:36pm

buzz11 · January 4, 2025, 1:38pm

Without outbound proxy
Part1

[2025-01-04 13:29:11] VERBOSE[1932] res_pjsip_logger.c: <--- Received SIP request (984 bytes) from UDP:192.168.1.111:5060 --->	
INVITE sip:[email protected] SIP/2.0	
Via: SIP/2.0/UDP 192.168.1.111:5060;branch=z9hG4bK00d7748c0dc9ef11922c5d7750defeec;rport	
From: "PhonerLite" <sip:[email protected]>;tag=1364328288	
To: <sip:[email protected]>	
Call-ID: [email protected]	
CSeq: 1 INVITE	
Contact: <sip:[email protected]>	
Content-Type: application/sdp	
Allow: INVITE, ACK, BYE, CANCEL, INFO, MESSAGE, NOTIFY, OPTIONS, REFER, UPDATE, PRACK	
Max-Forwards: 70	
Supported: replaces, from-change, 100rel	
User-Agent: PhonerLite/3.27	
P-Preferred-Identity: <sip:[email protected]>	
Content-Length: 370	
	
v=0	
o=- 1129055411 1 IN IP4 192.168.1.111	
s=PhonerLite/3.27	
c=IN IP4 192.168.1.111	
t=0 0	
m=audio 5080 RTP/AVP 8 0 3 9 18 101	
a=rtpmap:8 PCMA/8000	
a=rtpmap:0 PCMU/8000	
a=rtpmap:3 GSM/8000	
a=rtpmap:9 G722/8000	
a=rtpmap:18 G729/8000	
a=fmtp:18 annexb=yes	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-16	
a=ssrc:3474057018 cname:[email protected]	
a=sendrecv	
	
[2025-01-04 13:29:11] VERBOSE[15651] res_pjsip_logger.c: <--- Transmitting SIP response (565 bytes) to UDP:192.168.1.111:5060 --->	
SIP/2.0 401 Unauthorized	
Via: SIP/2.0/UDP 192.168.1.111:5060;rport=5060;received=192.168.1.111;branch=z9hG4bK00d7748c0dc9ef11922c5d7750defeec	
Call-ID: [email protected]	
From: "PhonerLite" <sip:[email protected]>;tag=1364328288	
To: <sip:[email protected]>;tag=z9hG4bK00d7748c0dc9ef11922c5d7750defeec	
CSeq: 1 INVITE	
WWW-Authenticate: Digest realm="asterisk",nonce="1735997351/46e1249ddd198ffb8df2836bd00716d9",opaque="76768f8d6ecd06b7",algorithm=MD5,qop="auth"	
Server: IncrediblePBX-17.0.19.21(19.0)	
Content-Length: 0	
	
	
[2025-01-04 13:29:11] VERBOSE[1932] res_pjsip_logger.c: <--- Received SIP request (361 bytes) from UDP:192.168.1.111:5060 --->	
ACK sip:[email protected] SIP/2.0	
Via: SIP/2.0/UDP 192.168.1.111:5060;branch=z9hG4bK00d7748c0dc9ef11922c5d7750defeec;rport	
From: "PhonerLite" <sip:[email protected]>;tag=1364328288	
To: <sip:[email protected]>;tag=z9hG4bK00d7748c0dc9ef11922c5d7750defeec	
Call-ID: [email protected]	
CSeq: 1 ACK	
Content-Length: 0	
	
	
[2025-01-04 13:29:11] VERBOSE[1932] res_pjsip_logger.c: <--- Received SIP request (1280 bytes) from UDP:192.168.1.111:5060 --->	
INVITE sip:[email protected] SIP/2.0	
Via: SIP/2.0/UDP 192.168.1.111:5060;branch=z9hG4bK00d7748c0dc9ef11922e5d7750defeec;rport	
From: "PhonerLite" <sip:[email protected]>;tag=1364328288	
To: <sip:[email protected]>	
Call-ID: [email protected]	
CSeq: 2 INVITE	
Contact: <sip:[email protected]>	
Authorization: Digest username="200", realm="asterisk", nonce="1735997351/46e1249ddd198ffb8df2836bd00716d9", uri="sip:[email protected]", response="1d177d9180b4a99d9034f14da3ff791c", algorithm=MD5, cnonce="00d7748c0dc9ef11922d5d7750defeec", opaque="76768f8d6ecd06b7", qop=auth, nc=00000001	
Content-Type: application/sdp	
Allow: INVITE, ACK, BYE, CANCEL, INFO, MESSAGE, NOTIFY, OPTIONS, REFER, UPDATE, PRACK	
Max-Forwards: 70	
Supported: replaces, from-change, 100rel	
User-Agent: PhonerLite/3.27	
P-Preferred-Identity: <sip:[email protected]>	
Content-Length: 370	
	
v=0	
o=- 1129055411 1 IN IP4 192.168.1.111	
s=PhonerLite/3.27	
c=IN IP4 192.168.1.111	
t=0 0	
m=audio 5080 RTP/AVP 8 0 3 9 18 101	
a=rtpmap:8 PCMA/8000	
a=rtpmap:0 PCMU/8000	
a=rtpmap:3 GSM/8000	
a=rtpmap:9 G722/8000	
a=rtpmap:18 G729/8000	
a=fmtp:18 annexb=yes	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-16	
a=ssrc:3474057018 cname:[email protected]	
a=sendrecv	
	
[2025-01-04 13:29:11] VERBOSE[15651] res_pjsip_logger.c: <--- Transmitting SIP response (369 bytes) to UDP:192.168.1.111:5060 --->	
SIP/2.0 100 Trying	
Via: SIP/2.0/UDP 192.168.1.111:5060;rport=5060;received=192.168.1.111;branch=z9hG4bK00d7748c0dc9ef11922e5d7750defeec	
Call-ID: [email protected]	
From: "PhonerLite" <sip:[email protected]>;tag=1364328288	
To: <sip:[email protected]>	
CSeq: 2 INVITE	
Server: IncrediblePBX-17.0.19.21(19.0)	
Content-Length: 0	
	
	
[2025-01-04 13:29:11] VERBOSE[15651] netsock2.c: Using SIP RTP Audio TOS bits 184	
[2025-01-04 13:29:11] VERBOSE[15651] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.	
[2025-01-04 13:29:11] VERBOSE[15651] netsock2.c: Using SIP RTP Audio CoS mark 5	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [065xxxxxx@from-internal:1] Gosub("PJSIP/200-00000020", "macro-user-callerid,s,1(LIMIT,EXTERNAL)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/200-00000020", "TOUCH_MONITOR=1735997351.35") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/200-00000020", "CHANCONTEXT=") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/200-00000020", "CHANCONTEXT=") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/200-00000020", "CHANEXTENCONTEXT=200-00000020") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/200-00000020", "CHANEXTEN=200-00000020") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/200-00000020", "CALLERID(number)=200") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/200-00000020", "AMPUSER=200") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/200-00000020", "HOTDESCKCHAN=200-00000020") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/200-00000020", "HOTDESKEXTEN=200") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/200-00000020", "HOTDESKCALL=0") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("PJSIP/200-00000020", "0?Set(HOTDESKCALL=1)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(name)=)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/200-00000020", "0?report") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/200-00000020", "1?Set(REALCALLERIDNUM=200)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/200-00000020", "AMPUSER=200") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/200-00000020", "0?limit") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/200-00000020", "AMPUSERCIDNAME=Recepcija") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("PJSIP/200-00000020", "0?Set(__CIDMASQUERADING=TRUE)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/200-00000020", "0?report") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/200-00000020", "AMPUSERCID=200") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:21] Set("PJSIP/200-00000020", "__DIAL_OPTIONS=HhTtr") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:22] Set("PJSIP/200-00000020", "CALLERID(all)="Recepcija" <200>") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("PJSIP/200-00000020", "0?Set(CUSDIAL=)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(all)="Recepcija" <200>)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/200-00000020", "0?limit") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("PJSIP/200-00000020", "1?Set(GROUP(concurrency_limit)=200)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:27] NoOp("PJSIP/200-00000020", "Macro depricated!! To keep the same line numbers") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:28] NoOp("PJSIP/200-00000020", "Macro depricated !! To keep the same line numbers") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("PJSIP/200-00000020", "1?continue") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx_builtins.c: Goto (macro-user-callerid,s,47)	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:47] Set("PJSIP/200-00000020", "CALLERID(number)=200") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:48] Set("PJSIP/200-00000020", "CALLERID(name)=Recepcija") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:49] GotoIf("PJSIP/200-00000020", "0?cnum") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/200-00000020", "__MCNUM=200") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:51] Set("PJSIP/200-00000020", "__MCNAME=Recepcija") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/200-00000020", "__MCEXTEN=200") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/200-00000020", "__MCORGCHAN=PJSIP/200-00000020") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/200-00000020", "CDR(cnam)=Recepcija") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:55] Set("PJSIP/200-00000020", "CDR(cnum)=200") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-user-callerid:56] Return("PJSIP/200-00000020", "") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [065xxxxxx@from-internal:2] Gosub("PJSIP/200-00000020", "sub-record-check,s,1(out,065xxxxxx,dontcare)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/200-00000020", "0?initialized") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/200-00000020", "__REC_STATUS=INITIALIZED") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/200-00000020", "NOW=1735997351") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/200-00000020", "__DAY=04") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/200-00000020", "__MONTH=01") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/200-00000020", "__YEAR=2025") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/200-00000020", "__TIMESTR=20250104-132911") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/200-00000020", "__FROMEXTEN=200") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/200-00000020", "__MON_FMT=wav") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/200-00000020", "Recordings initialized") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/200-00000020", "0?Set(ARG3=dontcare)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/200-00000020", "REC_POLICY_MODE_SAVE=") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/200-00000020", "0?Set(REC_STATUS=NO)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/200-00000020", "3?checkaction") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx_builtins.c: Goto (sub-record-check,s,17)	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/200-00000020", "1?sub-record-check,out,1") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx_builtins.c: Goto (sub-record-check,out,1)	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/200-00000020", "Outbound Recording Check from 200 to 065xxxxxx") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/200-00000020", "RECMODE=dontcare") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/200-00000020", "1?Goto(routewins)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx_builtins.c: Goto (sub-record-check,out,7)	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/200-00000020", "recordcheck,1(dontcare,out,065xxxxxx)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/200-00000020", "Starting recording check against dontcare") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/200-00000020", "dontcare") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/200-00000020", "") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/200-00000020", "") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [065xxxxxx@from-internal:3] Set("PJSIP/200-00000020", "_ROUTEID=2") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [065xxxxxx@from-internal:4] Set("PJSIP/200-00000020", "_ROUTENAME=FKKSS") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [065xxxxxx@from-internal:5] Set("PJSIP/200-00000020", "MOHCLASS=default") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [065xxxxxx@from-internal:6] Set("PJSIP/200-00000020", "_CALLERIDNAMEINTERNAL=Recepcija") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [065xxxxxx@from-internal:7] Set("PJSIP/200-00000020", "_CALLERIDNUMINTERNAL=200") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [065xxxxxx@from-internal:8] Set("PJSIP/200-00000020", "_EMAILNOTIFICATION=FALSE") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [065xxxxxx@from-internal:9] Set("PJSIP/200-00000020", "_NODEST=") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [065xxxxxx@from-internal:10] Gosub("PJSIP/200-00000020", "macro-dialout-trunk,s,1(2,065xxxxxx,,off)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/200-00000020", "DIAL_TRUNK=2") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("PJSIP/200-00000020", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:3] ExecIf("PJSIP/200-00000020", "0?Set(DIAL_OPTIONS=HhTr)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("PJSIP/200-00000020", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:5] GosubIf("PJSIP/200-00000020", "0?sub-pincheck,s,1()") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:6] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(num)=200)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf("PJSIP/200-00000020", "0?disabletrunk,1") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:8] Set("PJSIP/200-00000020", "DIAL_NUMBER=065xxxxxx") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:9] Set("PJSIP/200-00000020", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:10] Set("PJSIP/200-00000020", "OUTBOUND_GROUP=OUT_2") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:11] Set("PJSIP/200-00000020", "DIAL_TRUNK_OPTIONS=T") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:12] ExecIf("PJSIP/200-00000020", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:13] GotoIf("PJSIP/200-00000020", "0?nomax") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:14] GotoIf("PJSIP/200-00000020", "0?chanfull") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:15] GotoIf("PJSIP/200-00000020", "0?skipoutcid") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:16] Gosub("PJSIP/200-00000020", "macro-outbound-callerid,s,1(2)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("PJSIP/200-00000020", "200") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("PJSIP/200-00000020", "") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("PJSIP/200-00000020", "off") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(name-pres)=)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(num-pres)=)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:6] Set("PJSIP/200-00000020", "HOTDESCKCHAN=200-00000020") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:7] Set("PJSIP/200-00000020", "HOTDESKEXTEN=200") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:8] Set("PJSIP/200-00000020", "HOTDESKCALL=0") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("PJSIP/200-00000020", "0?Set(HOTDESKCALL=1)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(name)=)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:11] Set("PJSIP/200-00000020", "ALLOWTHISROUTE=NO") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("PJSIP/200-00000020", "0?Set(ALLOWTHISROUTE=YES)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("PJSIP/200-00000020", "0?Hangup()") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("PJSIP/200-00000020", "0?Set(REALCALLERIDNUM=200)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("PJSIP/200-00000020", "0?Set(AMPUSER=200)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("PJSIP/200-00000020", "1?normcid") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:20] Set("PJSIP/200-00000020", "USEROUTCID="+38733xxxxxx"<+38733xxxxxx>") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:21] Set("PJSIP/200-00000020", "EMERGENCYCID=") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("PJSIP/200-00000020", "0?Set(EMERGENCYCID=)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:23] Set("PJSIP/200-00000020", "TRUNKOUTCID=") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("PJSIP/200-00000020", "1?trunkcid") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(all)=)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("PJSIP/200-00000020", "1?Set(CALLERID(all)="+38733xxxxxx"<+38733xxxxxx>)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(all)=)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(all)=200)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:34] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(all)=200)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:35] Set("PJSIP/200-00000020", "TIOHIDE=no") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:39] ExecIf("PJSIP/200-00000020", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:40] Set("PJSIP/200-00000020", "CDR(outbound_cnum)=+38733xxxxxx") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:41] Set("PJSIP/200-00000020", "CDR(outbound_cnam)=+38733xxxxxx") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outbound-callerid:42] Return("PJSIP/200-00000020", "") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:17] GosubIf("PJSIP/200-00000020", "0?sub-flp-2,s,1()") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:18] Set("PJSIP/200-00000020", "OUTNUM=065xxxxxx") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:19] Set("PJSIP/200-00000020", "custom=PJSIP") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("PJSIP/200-00000020", "0?Set(DIAL_TRUNK_MOH=default)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("PJSIP/200-00000020", "0?Set(DIAL_TRUNK_OPTIONS=TU(macro-confirm))") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf("PJSIP/200-00000020", "0?AGI(allowlist-autoadd.agi,)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:23] Gosub("PJSIP/200-00000020", "macro-dialout-trunk-predial-hook,s,1()") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] Return("PJSIP/200-00000020", "") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:24] GotoIf("PJSIP/200-00000020", "0?bypass,1") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:25] ExecIf("PJSIP/200-00000020", "1?Set(CONNECTEDLINE(num,i)=065xxxxxx)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:26] ExecIf("PJSIP/200-00000020", "1?Set(CONNECTEDLINE(name,i)=CID:+38733xxxxxx)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:27] ExecIf("PJSIP/200-00000020", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)+38733xxxxxx)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:28] GotoIf("PJSIP/200-00000020", "0?customtrunk") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("PJSIP/200-00000020", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:30] Set("PJSIP/200-00000020", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:31] Gosub("PJSIP/200-00000020", "trunk-dial-with-exten,065xxxxxx,1()") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [065xxxxxx@trunk-dial-with-exten:1] Dial("PJSIP/200-00000020", "PJSIP/065xxxxxx@+38733xxxxxx,300,Tb(func-apply-sipheaders^s^1,(2))U(sub-send-obroute-email^065xxxxxx^^2^1735997351^+38733xxxxxx^+38733xxxxxx,^)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] app_stack.c: PJSIP/+38733xxxxxx-00000021 Internal Gosub(func-apply-sipheaders,s,1(2)) start	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("PJSIP/+38733xxxxxx-00000021", "Applying SIP Headers to channel PJSIP/+38733xxxxxx-00000021") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:2] Set("PJSIP/+38733xxxxxx-00000021", "localchan=+38733xxxxxx-00000021") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/+38733xxxxxx-00000021", "DialMCEXT=+38733xxxxxx") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/+38733xxxxxx-00000021", "CHANNEL(hangup_handler_push)=app-missedcall-hangup,+38733xxxxxx,1") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:5] Set("PJSIP/+38733xxxxxx-00000021", "Dchan=+38733xxxxxx-00000021") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/+38733xxxxxx-00000021", "TECH=PJSIP") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:7] Set("PJSIP/+38733xxxxxx-00000021", "SIPHEADERKEYS=Alert-Info") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:8] While("PJSIP/+38733xxxxxx-00000021", "1") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:9] Set("PJSIP/+38733xxxxxx-00000021", "sipheader=unset") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("PJSIP/+38733xxxxxx-00000021", "1?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack	
[2025-01-04 13:29:11] ERROR[15651] res_pjsip_header_funcs.c: No headers had been previously added to this session.	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("PJSIP/+38733xxxxxx-00000021", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("PJSIP/+38733xxxxxx-00000021", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:13] ExecIf("PJSIP/+38733xxxxxx-00000021", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:14] EndWhile("PJSIP/+38733xxxxxx-00000021", "") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:8] While("PJSIP/+38733xxxxxx-00000021", "0") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] pbx.c: Executing [s@func-apply-sipheaders:15] Return("PJSIP/+38733xxxxxx-00000021", "") in new stack	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] app_stack.c: Spawn extension (from-pstn, 065xxxxxx, 1) exited non-zero on 'PJSIP/+38733xxxxxx-00000021'	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] app_stack.c: PJSIP/+38733xxxxxx-00000021 Internal Gosub(func-apply-sipheaders,s,1(2)) complete GOSUB_RETVAL=	
[2025-01-04 13:29:11] VERBOSE[16012][C-00000014] app_dial.c: Called PJSIP/065xxxxxx@+38733xxxxxx	
[2025-01-04 13:29:11] VERBOSE[15651] res_pjsip_logger.c: <--- Transmitting SIP request (1157 bytes) to UDP:10.x.x.228:5060 --->

buzz11 · January 4, 2025, 1:39pm

Part2

INVITE sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.x.x.113:5060;rport;branch=z9hG4bKPj8bbf5b40-55b5-4073-b4a8-c0a70a85b83c	
From: "+38733xxxxxx" <sip:[email protected]>;tag=aa5eef51-d08e-48b6-8ad8-f496447b5781	
To: <sip:[email protected]>	
Contact: <sip:[email protected]:5060>	
Call-ID: c20dd945-cc38-4cbf-b1df-a3174b75bbb7	
CSeq: 5179 INVITE	
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, INFO, MESSAGE, REFER	
Supported: 100rel, timer, replaces, norefersub, histinfo	
Session-Expires: 1800	
Min-SE: 90	
Remote-Party-ID: "+38733xxxxxx" <sip:[email protected]>;party=calling;privacy=off;screen=no	
Max-Forwards: 70	
User-Agent: IncrediblePBX-17.0.19.21(19.0)	
Content-Type: application/sdp	
Content-Length: 333	
	
v=0	
o=- 1095316256 1095316256 IN IP4 10.x.x.113	
s=Asterisk	
c=IN IP4 10.x.x.113	
t=0 0	
m=audio 15542 RTP/AVP 0 8 18 3 101	
a=rtpmap:0 PCMU/8000	
a=rtpmap:8 PCMA/8000	
a=rtpmap:18 G729/8000	
a=fmtp:18 annexb=no	
a=rtpmap:3 GSM/8000	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-16	
a=ptime:20	
a=maxptime:140	
a=sendrecv	
	
[2025-01-04 13:29:11] VERBOSE[1932] res_pjsip_logger.c: <--- Received SIP response (340 bytes) from UDP:10.x.x.228:5060 --->	
SIP/2.0 100 Trying	
Via: SIP/2.0/UDP 10.x.x.113:5060;branch=z9hG4bKPj8bbf5b40-55b5-4073-b4a8-c0a70a85b83c;rport=5060	
Call-ID: c20dd945-cc38-4cbf-b1df-a3174b75bbb7	
From: "+38733xxxxxx"<sip:[email protected]>;tag=aa5eef51-d08e-48b6-8ad8-f496447b5781	
To: <sip:[email protected]>	
CSeq: 5179 INVITE	
Content-Length: 0	
	
	
[2025-01-04 13:29:12] VERBOSE[1932] res_pjsip_logger.c: <--- Received SIP response (649 bytes) from UDP:10.x.x.228:5060 --->	
SIP/2.0 401 Unauthorized	
Via: SIP/2.0/UDP 10.x.x.113:5060;branch=z9hG4bKPj8bbf5b40-55b5-4073-b4a8-c0a70a85b83c;rport=5060	
Call-ID: c20dd945-cc38-4cbf-b1df-a3174b75bbb7	
From: "+38733xxxxxx"<sip:[email protected]>;tag=aa5eef51-d08e-48b6-8ad8-f496447b5781	
To: <sip:[email protected]>;tag=1463850291-1735997352114	
CSeq: 5179 INVITE	
WWW-Authenticate: Digest realm="ims.bhtelecom.ba",nonce="BroadWorksXm5i801puTy1hka9BW",algorithm=MD5,qop="auth"	
Reason: Q.850;cause=21,SIP;cause=401	
P-Charging-Vector: icid-value="5d15972a6bdc59ac1aa62436a82a7140.3944986151.7970668.3";orig-ioi=sascscf01.ims.bhtelecom.ba	
Content-Length: 0	
	
	
[2025-01-04 13:29:12] VERBOSE[15651] res_pjsip_logger.c: <--- Transmitting SIP request (452 bytes) to UDP:10.x.x.228:5060 --->	
ACK sip:[email protected]:5060 SIP/2.0	
Via: SIP/2.0/UDP 10.x.x.113:5060;rport;branch=z9hG4bKPj8bbf5b40-55b5-4073-b4a8-c0a70a85b83c	
From: "+38733xxxxxx" <sip:[email protected]>;tag=aa5eef51-d08e-48b6-8ad8-f496447b5781	
To: <sip:[email protected]>;tag=1463850291-1735997352114	
Call-ID: c20dd945-cc38-4cbf-b1df-a3174b75bbb7	
CSeq: 5179 ACK	
Max-Forwards: 70	
User-Agent: IncrediblePBX-17.0.19.21(19.0)	
Content-Length: 0	
	
	
[2025-01-04 13:29:12] ERROR[15651] res_pjsip_outbound_authenticator_digest.c: Endpoint: '+38733xxxxxx': There were no auth ids available	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] app_stack.c: PJSIP/+38733xxxxxx-00000021 Internal Gosub(app-missedcall-hangup,+38733xxxxxx,1) start	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:1] NoOp("PJSIP/+38733xxxxxx-00000021", "Dialed: +38733xxxxxx") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:2] NoOp("PJSIP/+38733xxxxxx-00000021", "Caller: 200") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:3] GotoIf("PJSIP/+38733xxxxxx-00000021", "0?exit") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:4] Set("PJSIP/+38733xxxxxx-00000021", "EXTENNUM=+38733xxxxxx") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:5] Set("PJSIP/+38733xxxxxx-00000021", "FEXTENNUM=+38733xxxxxx") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:6] GotoIf("PJSIP/+38733xxxxxx-00000021", "0?exit") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:7] AGI("PJSIP/+38733xxxxxx-00000021", "missedcallnotify.php,+38733xxxxxx,,+38733xxxxxx,0,,PJSIP/+38733xxxxxx-00000021,,,,") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/missedcallnotify.php	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] res_agi.c: <PJSIP/+38733xxxxxx-00000021>AGI Script missedcallnotify.php completed, returning 0	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [+38733xxxxxx@app-missedcall-hangup:8] Return("PJSIP/+38733xxxxxx-00000021", "") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] app_stack.c: Spawn extension (from-pstn, 065xxxxxx, 1) exited non-zero on 'PJSIP/+38733xxxxxx-00000021'	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] app_stack.c: PJSIP/+38733xxxxxx-00000021 Internal Gosub(app-missedcall-hangup,+38733xxxxxx,1) complete GOSUB_RETVAL=	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [065xxxxxx@trunk-dial-with-exten:2] Return("PJSIP/200-00000020", "") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:32] NoOp("PJSIP/200-00000020", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 21") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-dialout-trunk:33] GotoIf("PJSIP/200-00000020", "0?continue,1:s-CHANUNAVAIL,1") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("PJSIP/200-00000020", "RC=21") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("PJSIP/200-00000020", "21,1") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx_builtins.c: Goto (macro-dialout-trunk,21,1)	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [21@macro-dialout-trunk:1] Goto("PJSIP/200-00000020", "continue,1") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("PJSIP/200-00000020", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 21 - failing through to other trunks") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("PJSIP/200-00000020", "1?Set(CALLERID(number)=200)") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [continue@macro-dialout-trunk:3] Return("PJSIP/200-00000020", "") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [065xxxxxx@from-internal:11] Gosub("PJSIP/200-00000020", "macro-outisbusy,s,1()") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outisbusy:1] Progress("PJSIP/200-00000020", "") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("PJSIP/200-00000020", "0?emergency,1") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("PJSIP/200-00000020", "0?intracompany,1") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outisbusy:4] Playback("PJSIP/200-00000020", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack	
[2025-01-04 13:29:12] VERBOSE[16012][C-00000014] file.c: <PJSIP/200-00000020> Playing 'all-circuits-busy-now.ulaw' (language 'en_GB')	
[2025-01-04 13:29:12] VERBOSE[15651] res_pjsip_logger.c: <--- Transmitting SIP response (1028 bytes) to UDP:192.168.1.111:5060 --->	
SIP/2.0 183 Session Progress	
Via: SIP/2.0/UDP 192.168.1.111:5060;rport=5060;received=192.168.1.111;branch=z9hG4bK00d7748c0dc9ef11922e5d7750defeec	
Call-ID: [email protected]	
From: "PhonerLite" <sip:[email protected]>;tag=1364328288	
To: <sip:[email protected]>;tag=cc003d73-25bb-4809-b9b9-77a75dbda14f	
CSeq: 2 INVITE	
Server: IncrediblePBX-17.0.19.21(19.0)	
Contact: <sip:192.168.1.44:5060>	
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, INFO, MESSAGE, REFER	
Remote-Party-ID: "CID:+38733xxxxxx" <sip:[email protected]>;party=called;privacy=off;screen=no	
Content-Type: application/sdp	
Content-Length: 322	
	
v=0	
o=- 1129055411 3 IN IP4 192.168.1.44	
s=Asterisk	
c=IN IP4 192.168.1.44	
t=0 0	
m=audio 17868 RTP/AVP 0 8 18 3 101	
a=rtpmap:0 PCMU/8000	
a=rtpmap:8 PCMA/8000	
a=rtpmap:18 G729/8000	
a=fmtp:18 annexb=no	
a=rtpmap:3 GSM/8000	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-16	
a=ptime:20	
a=maxptime:140	
a=sendrecv	
	
[2025-01-04 13:29:13] VERBOSE[16012][C-00000014] file.c: <PJSIP/200-00000020> Playing 'please-try-call-later.ulaw' (language 'en_GB')	
[2025-01-04 13:29:15] VERBOSE[16012][C-00000014] pbx.c: Executing [s@macro-outisbusy:5] Congestion("PJSIP/200-00000020", "20") in new stack	
[2025-01-04 13:29:15] VERBOSE[15651] res_pjsip_logger.c: <--- Transmitting SIP response (665 bytes) to UDP:192.168.1.111:5060 --->	
SIP/2.0 503 Service Unavailable	
Via: SIP/2.0/UDP 192.168.1.111:5060;rport=5060;received=192.168.1.111;branch=z9hG4bK00d7748c0dc9ef11922e5d7750defeec	
Call-ID: [email protected]	
From: "PhonerLite" <sip:[email protected]>;tag=1364328288	
To: <sip:[email protected]>;tag=cc003d73-25bb-4809-b9b9-77a75dbda14f	
CSeq: 2 INVITE	
Server: IncrediblePBX-17.0.19.21(19.0)	
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, INFO, MESSAGE, REFER	
Remote-Party-ID: "CID:+38733xxxxxx" <sip:[email protected]>;party=called;privacy=off;screen=no	
Reason: Q.850;cause=34	
Content-Length: 0	
	
	
[2025-01-04 13:29:15] VERBOSE[16012][C-00000014] pbx.c: Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'PJSIP/200-00000020'	
[2025-01-04 13:29:15] VERBOSE[1932] res_pjsip_logger.c: <--- Received SIP request (654 bytes) from UDP:192.168.1.111:5060 --->	
ACK sip:[email protected] SIP/2.0	
Via: SIP/2.0/UDP 192.168.1.111:5060;branch=z9hG4bK00d7748c0dc9ef11922e5d7750defeec;rport	
From: "PhonerLite" <sip:[email protected]>;tag=1364328288	
To: <sip:[email protected]>;tag=cc003d73-25bb-4809-b9b9-77a75dbda14f	
Call-ID: [email protected]	
CSeq: 2 ACK	
Authorization: Digest username="200", realm="asterisk", nonce="1735997351/46e1249ddd198ffb8df2836bd00716d9", uri="sip:[email protected]", response="1d177d9180b4a99d9034f14da3ff791c", algorithm=MD5, cnonce="00d7748c0dc9ef11922d5d7750defeec", opaque="76768f8d6ecd06b7", qop=auth, nc=00000001	
Content-Length: 0

buzz11 · January 4, 2025, 1:42pm

I know what private network is I just thinked of 10/24 and figured out. Only one 10/24 is VPN I am connected through. Not redacting it. Other 10 networks I am redacting…I do not know why. Habit I guess.

jcolp · January 4, 2025, 2:11pm

The INVITE was challenged, but there is no outbound authentication configured so it could not authenticate.

buzz11 · January 4, 2025, 2:34pm

Added. Still failing.

Well…cannot reply anymore as new user.
Error following is:

NoOp("PJSIP/200-00000031", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 21")
NoOp("PJSIP/200-00000031", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 21 - failing through to other trunks")

Part1

[2025-01-04 14:21:17] VERBOSE[1932] res_pjsip_logger.c: <--- Received SIP request (982 bytes) from UDP:192.168.1.111:5060 --->	
INVITE sip:[email protected] SIP/2.0	
Via: SIP/2.0/UDP 192.168.1.111:5060;branch=z9hG4bK0096b2d314c9ef1192655d7750defeec;rport	
From: "PhonerLite" <sip:[email protected]>;tag=975337550	
To: <sip:[email protected]>	
Call-ID: [email protected]	
CSeq: 1 INVITE	
Contact: <sip:[email protected]>	
Content-Type: application/sdp	
Allow: INVITE, ACK, BYE, CANCEL, INFO, MESSAGE, NOTIFY, OPTIONS, REFER, UPDATE, PRACK	
Max-Forwards: 70	
Supported: replaces, from-change, 100rel	
User-Agent: PhonerLite/3.27	
P-Preferred-Identity: <sip:[email protected]>	
Content-Length: 369	
	
v=0	
o=- 1148823227 1 IN IP4 192.168.1.111	
s=PhonerLite/3.27	
c=IN IP4 192.168.1.111	
t=0 0	
m=audio 5102 RTP/AVP 8 0 3 9 18 101	
a=rtpmap:8 PCMA/8000	
a=rtpmap:0 PCMU/8000	
a=rtpmap:3 GSM/8000	
a=rtpmap:9 G722/8000	
a=rtpmap:18 G729/8000	
a=fmtp:18 annexb=yes	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-16	
a=ssrc:102315668 cname:[email protected]	
a=sendrecv	
	
[2025-01-04 14:21:17] VERBOSE[23364] res_pjsip_logger.c: <--- Transmitting SIP response (564 bytes) to UDP:192.168.1.111:5060 --->	
SIP/2.0 401 Unauthorized	
Via: SIP/2.0/UDP 192.168.1.111:5060;rport=5060;received=192.168.1.111;branch=z9hG4bK0096b2d314c9ef1192655d7750defeec	
Call-ID: [email protected]	
From: "PhonerLite" <sip:[email protected]>;tag=975337550	
To: <sip:[email protected]>;tag=z9hG4bK0096b2d314c9ef1192655d7750defeec	
CSeq: 1 INVITE	
WWW-Authenticate: Digest realm="asterisk",nonce="1736000477/656c15d3a62b0fe337fe679d32c942f9",opaque="1ae357650ce8c90a",algorithm=MD5,qop="auth"	
Server: IncrediblePBX-17.0.19.21(19.0)	
Content-Length: 0	
	
	
[2025-01-04 14:21:17] VERBOSE[1932] res_pjsip_logger.c: <--- Received SIP request (360 bytes) from UDP:192.168.1.111:5060 --->	
ACK sip:[email protected] SIP/2.0	
Via: SIP/2.0/UDP 192.168.1.111:5060;branch=z9hG4bK0096b2d314c9ef1192655d7750defeec;rport	
From: "PhonerLite" <sip:[email protected]>;tag=975337550	
To: <sip:[email protected]>;tag=z9hG4bK0096b2d314c9ef1192655d7750defeec	
Call-ID: [email protected]	
CSeq: 1 ACK	
Content-Length: 0	
	
	
[2025-01-04 14:21:17] VERBOSE[1932] res_pjsip_logger.c: <--- Received SIP request (1278 bytes) from UDP:192.168.1.111:5060 --->	
INVITE sip:[email protected] SIP/2.0	
Via: SIP/2.0/UDP 192.168.1.111:5060;branch=z9hG4bK0096b2d314c9ef1192675d7750defeec;rport	
From: "PhonerLite" <sip:[email protected]>;tag=975337550	
To: <sip:[email protected]>	
Call-ID: [email protected]	
CSeq: 2 INVITE	
Contact: <sip:[email protected]>	
Authorization: Digest username="200", realm="asterisk", nonce="1736000477/656c15d3a62b0fe337fe679d32c942f9", uri="sip:[email protected]", response="372f6d4caf79d973d2532cbb80c74932", algorithm=MD5, cnonce="0096b2d314c9ef1192665d7750defeec", opaque="1ae357650ce8c90a", qop=auth, nc=00000001	
Content-Type: application/sdp	
Allow: INVITE, ACK, BYE, CANCEL, INFO, MESSAGE, NOTIFY, OPTIONS, REFER, UPDATE, PRACK	
Max-Forwards: 70	
Supported: replaces, from-change, 100rel	
User-Agent: PhonerLite/3.27	
P-Preferred-Identity: <sip:[email protected]>	
Content-Length: 369	
	
v=0	
o=- 1148823227 1 IN IP4 192.168.1.111	
s=PhonerLite/3.27	
c=IN IP4 192.168.1.111	
t=0 0	
m=audio 5102 RTP/AVP 8 0 3 9 18 101	
a=rtpmap:8 PCMA/8000	
a=rtpmap:0 PCMU/8000	
a=rtpmap:3 GSM/8000	
a=rtpmap:9 G722/8000	
a=rtpmap:18 G729/8000	
a=fmtp:18 annexb=yes	
a=rtpmap:101 telephone-event/8000	
a=fmtp:101 0-16	
a=ssrc:102315668 cname:[email protected]	
a=sendrecv	
	
[2025-01-04 14:21:17] VERBOSE[23364] res_pjsip_logger.c: <--- Transmitting SIP response (368 bytes) to UDP:192.168.1.111:5060 --->	
SIP/2.0 100 Trying	
Via: SIP/2.0/UDP 192.168.1.111:5060;rport=5060;received=192.168.1.111;branch=z9hG4bK0096b2d314c9ef1192675d7750defeec	
Call-ID: [email protected]	
From: "PhonerLite" <sip:[email protected]>;tag=975337550	
To: <sip:[email protected]>	
CSeq: 2 INVITE	
Server: IncrediblePBX-17.0.19.21(19.0)	
Content-Length: 0	
		
[2025-01-04 14:21:17] VERBOSE[23364] netsock2.c: Using SIP RTP Audio TOS bits 184	
[2025-01-04 14:21:17] VERBOSE[23364] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.	
[2025-01-04 14:21:17] VERBOSE[23364] netsock2.c: Using SIP RTP Audio CoS mark 5	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [065xxxxxx@from-internal:1] Gosub("PJSIP/200-00000031", "macro-user-callerid,s,1(LIMIT,EXTERNAL)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/200-00000031", "TOUCH_MONITOR=1736000477.52") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/200-00000031", "CHANCONTEXT=") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/200-00000031", "CHANCONTEXT=") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/200-00000031", "CHANEXTENCONTEXT=200-00000031") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/200-00000031", "CHANEXTEN=200-00000031") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/200-00000031", "CALLERID(number)=200") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/200-00000031", "AMPUSER=200") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/200-00000031", "HOTDESCKCHAN=200-00000031") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/200-00000031", "HOTDESKEXTEN=200") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/200-00000031", "HOTDESKCALL=0") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("PJSIP/200-00000031", "0?Set(HOTDESKCALL=1)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(name)=)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/200-00000031", "0?report") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/200-00000031", "1?Set(REALCALLERIDNUM=200)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/200-00000031", "AMPUSER=200") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/200-00000031", "0?limit") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/200-00000031", "AMPUSERCIDNAME=Recepcija") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("PJSIP/200-00000031", "0?Set(__CIDMASQUERADING=TRUE)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/200-00000031", "0?report") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/200-00000031", "AMPUSERCID=200") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:21] Set("PJSIP/200-00000031", "__DIAL_OPTIONS=HhTtr") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:22] Set("PJSIP/200-00000031", "CALLERID(all)="Recepcija" <200>") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("PJSIP/200-00000031", "0?Set(CUSDIAL=)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(all)="Recepcija" <200>)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/200-00000031", "0?limit") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("PJSIP/200-00000031", "1?Set(GROUP(concurrency_limit)=200)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:27] NoOp("PJSIP/200-00000031", "Macro depricated!! To keep the same line numbers") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:28] NoOp("PJSIP/200-00000031", "Macro depricated !! To keep the same line numbers") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("PJSIP/200-00000031", "1?continue") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx_builtins.c: Goto (macro-user-callerid,s,47)	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:47] Set("PJSIP/200-00000031", "CALLERID(number)=200") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:48] Set("PJSIP/200-00000031", "CALLERID(name)=Recepcija") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:49] GotoIf("PJSIP/200-00000031", "0?cnum") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/200-00000031", "__MCNUM=200") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:51] Set("PJSIP/200-00000031", "__MCNAME=Recepcija") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/200-00000031", "__MCEXTEN=200") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/200-00000031", "__MCORGCHAN=PJSIP/200-00000031") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/200-00000031", "CDR(cnam)=Recepcija") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:55] Set("PJSIP/200-00000031", "CDR(cnum)=200") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-user-callerid:56] Return("PJSIP/200-00000031", "") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [065xxxxxx@from-internal:2] Gosub("PJSIP/200-00000031", "sub-record-check,s,1(out,065xxxxxx,dontcare)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/200-00000031", "0?initialized") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/200-00000031", "__REC_STATUS=INITIALIZED") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/200-00000031", "NOW=1736000477") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/200-00000031", "__DAY=04") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/200-00000031", "__MONTH=01") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/200-00000031", "__YEAR=2025") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/200-00000031", "__TIMESTR=20250104-142117") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/200-00000031", "__FROMEXTEN=200") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/200-00000031", "__MON_FMT=wav") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/200-00000031", "Recordings initialized") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/200-00000031", "0?Set(ARG3=dontcare)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/200-00000031", "REC_POLICY_MODE_SAVE=") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/200-00000031", "0?Set(REC_STATUS=NO)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/200-00000031", "3?checkaction") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx_builtins.c: Goto (sub-record-check,s,17)	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/200-00000031", "1?sub-record-check,out,1") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx_builtins.c: Goto (sub-record-check,out,1)	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [out@sub-record-check:1] NoOp("PJSIP/200-00000031", "Outbound Recording Check from 200 to 065xxxxxx") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [out@sub-record-check:2] Set("PJSIP/200-00000031", "RECMODE=dontcare") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [out@sub-record-check:3] ExecIf("PJSIP/200-00000031", "1?Goto(routewins)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx_builtins.c: Goto (sub-record-check,out,7)	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [out@sub-record-check:7] Gosub("PJSIP/200-00000031", "recordcheck,1(dontcare,out,065xxxxxx)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/200-00000031", "Starting recording check against dontcare") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/200-00000031", "dontcare") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/200-00000031", "") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [out@sub-record-check:8] Return("PJSIP/200-00000031", "") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [065xxxxxx@from-internal:3] Set("PJSIP/200-00000031", "_ROUTEID=2") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [065xxxxxx@from-internal:4] Set("PJSIP/200-00000031", "_ROUTENAME=FKKSS") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [065xxxxxx@from-internal:5] Set("PJSIP/200-00000031", "MOHCLASS=default") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [065xxxxxx@from-internal:6] Set("PJSIP/200-00000031", "_CALLERIDNAMEINTERNAL=Recepcija") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [065xxxxxx@from-internal:7] Set("PJSIP/200-00000031", "_CALLERIDNUMINTERNAL=200") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [065xxxxxx@from-internal:8] Set("PJSIP/200-00000031", "_EMAILNOTIFICATION=FALSE") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [065xxxxxx@from-internal:9] Set("PJSIP/200-00000031", "_NODEST=") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [065xxxxxx@from-internal:10] Gosub("PJSIP/200-00000031", "macro-dialout-trunk,s,1(2,065xxxxxx,,off)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:1] Set("PJSIP/200-00000031", "DIAL_TRUNK=2") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("PJSIP/200-00000031", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:3] ExecIf("PJSIP/200-00000031", "0?Set(DIAL_OPTIONS=HhTr)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("PJSIP/200-00000031", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:5] GosubIf("PJSIP/200-00000031", "0?sub-pincheck,s,1()") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:6] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(num)=200)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf("PJSIP/200-00000031", "0?disabletrunk,1") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:8] Set("PJSIP/200-00000031", "DIAL_NUMBER=065xxxxxx") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:9] Set("PJSIP/200-00000031", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:10] Set("PJSIP/200-00000031", "OUTBOUND_GROUP=OUT_2") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:11] Set("PJSIP/200-00000031", "DIAL_TRUNK_OPTIONS=T") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:12] ExecIf("PJSIP/200-00000031", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:13] GotoIf("PJSIP/200-00000031", "0?nomax") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:14] GotoIf("PJSIP/200-00000031", "0?chanfull") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:15] GotoIf("PJSIP/200-00000031", "0?skipoutcid") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:16] Gosub("PJSIP/200-00000031", "macro-outbound-callerid,s,1(2)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("PJSIP/200-00000031", "200") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("PJSIP/200-00000031", "") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("PJSIP/200-00000031", "off") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(name-pres)=)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(num-pres)=)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:6] Set("PJSIP/200-00000031", "HOTDESCKCHAN=200-00000031") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:7] Set("PJSIP/200-00000031", "HOTDESKEXTEN=200") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:8] Set("PJSIP/200-00000031", "HOTDESKCALL=0") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("PJSIP/200-00000031", "0?Set(HOTDESKCALL=1)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(name)=)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:11] Set("PJSIP/200-00000031", "ALLOWTHISROUTE=NO") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("PJSIP/200-00000031", "0?Set(ALLOWTHISROUTE=YES)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("PJSIP/200-00000031", "0?Hangup()") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("PJSIP/200-00000031", "0?Set(REALCALLERIDNUM=200)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("PJSIP/200-00000031", "0?Set(AMPUSER=200)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("PJSIP/200-00000031", "1?normcid") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:20] Set("PJSIP/200-00000031", "USEROUTCID="+38733xxxxxx"<+38733xxxxxx>") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:21] Set("PJSIP/200-00000031", "EMERGENCYCID=") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("PJSIP/200-00000031", "0?Set(EMERGENCYCID=)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:23] Set("PJSIP/200-00000031", "TRUNKOUTCID=") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("PJSIP/200-00000031", "1?trunkcid") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(all)=)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("PJSIP/200-00000031", "1?Set(CALLERID(all)="+38733xxxxxx"<+38733xxxxxx>)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(all)=)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(all)=200)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:34] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(all)=200)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:35] Set("PJSIP/200-00000031", "TIOHIDE=no") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:39] ExecIf("PJSIP/200-00000031", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:40] Set("PJSIP/200-00000031", "CDR(outbound_cnum)=+38733xxxxxx") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:41] Set("PJSIP/200-00000031", "CDR(outbound_cnam)=+38733xxxxxx") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-outbound-callerid:42] Return("PJSIP/200-00000031", "") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:17] GosubIf("PJSIP/200-00000031", "0?sub-flp-2,s,1()") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:18] Set("PJSIP/200-00000031", "OUTNUM=065xxxxxx") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:19] Set("PJSIP/200-00000031", "custom=PJSIP") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("PJSIP/200-00000031", "0?Set(DIAL_TRUNK_MOH=default)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("PJSIP/200-00000031", "0?Set(DIAL_TRUNK_OPTIONS=TU(macro-confirm))") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf("PJSIP/200-00000031", "0?AGI(allowlist-autoadd.agi,)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:23] Gosub("PJSIP/200-00000031", "macro-dialout-trunk-predial-hook,s,1()") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] Return("PJSIP/200-00000031", "") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:24] GotoIf("PJSIP/200-00000031", "0?bypass,1") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:25] ExecIf("PJSIP/200-00000031", "1?Set(CONNECTEDLINE(num,i)=065xxxxxx)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:26] ExecIf("PJSIP/200-00000031", "1?Set(CONNECTEDLINE(name,i)=CID:+38733xxxxxx)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:27] ExecIf("PJSIP/200-00000031", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)+38733xxxxxx)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:28] GotoIf("PJSIP/200-00000031", "0?customtrunk") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("PJSIP/200-00000031", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:30] Set("PJSIP/200-00000031", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@macro-dialout-trunk:31] Gosub("PJSIP/200-00000031", "trunk-dial-with-exten,065xxxxxx,1()") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [065xxxxxx@trunk-dial-with-exten:1] Dial("PJSIP/200-00000031", "PJSIP/065xxxxxx@+38733xxxxxx,300,Tb(func-apply-sipheaders^s^1,(2))U(sub-send-obroute-email^065xxxxxx^^2^1736000477^+38733xxxxxx^+38733xxxxxx,^)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] app_stack.c: PJSIP/+38733xxxxxx-00000032 Internal Gosub(func-apply-sipheaders,s,1(2)) start	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("PJSIP/+38733xxxxxx-00000032", "Applying SIP Headers to channel PJSIP/+38733xxxxxx-00000032") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:2] Set("PJSIP/+38733xxxxxx-00000032", "localchan=+38733xxxxxx-00000032") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/+38733xxxxxx-00000032", "DialMCEXT=+38733xxxxxx") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/+38733xxxxxx-00000032", "CHANNEL(hangup_handler_push)=app-missedcall-hangup,+38733xxxxxx,1") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:5] Set("PJSIP/+38733xxxxxx-00000032", "Dchan=+38733xxxxxx-00000032") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/+38733xxxxxx-00000032", "TECH=PJSIP") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:7] Set("PJSIP/+38733xxxxxx-00000032", "SIPHEADERKEYS=Alert-Info") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:8] While("PJSIP/+38733xxxxxx-00000032", "1") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:9] Set("PJSIP/+38733xxxxxx-00000032", "sipheader=unset") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("PJSIP/+38733xxxxxx-00000032", "1?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack	
[2025-01-04 14:21:17] ERROR[23364] res_pjsip_header_funcs.c: No headers had been previously added to this session.	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("PJSIP/+38733xxxxxx-00000032", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("PJSIP/+38733xxxxxx-00000032", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:13] ExecIf("PJSIP/+38733xxxxxx-00000032", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:14] EndWhile("PJSIP/+38733xxxxxx-00000032", "") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:8] While("PJSIP/+38733xxxxxx-00000032", "0") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] pbx.c: Executing [s@func-apply-sipheaders:15] Return("PJSIP/+38733xxxxxx-00000032", "") in new stack	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] app_stack.c: Spawn extension (from-pstn, 065xxxxxx, 1) exited non-zero on 'PJSIP/+38733xxxxxx-00000032'	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] app_stack.c: PJSIP/+38733xxxxxx-00000032 Internal Gosub(func-apply-sipheaders,s,1(2)) complete GOSUB_RETVAL=	
[2025-01-04 14:21:17] VERBOSE[23534][C-0000001f] app_dial.c: Called PJSIP/065xxxxxx@+38733xxxxxx	
[2025-01-04 14:21:17] VERBOSE[23364] res_pjsip_logger.c: <--- Transmitting SIP request (1156 bytes) to UDP:10.x.x.228:5060 --->

buzz11 · January 5, 2025, 1:40pm

Installed new 3CX version (linux) for the test. Old system is windows based. No effort and voila all is working even new version is lot different in the comparation to the old one. Have no clue why cannot get a FreePBX to work properly. Why is throwing authentication error when there is no authentication? For outbound calls as well. What ever I do I am getting busy now message back.

Stewart1 · January 5, 2025, 1:53pm

IMO this is not a difficult problem, but you have not provided sufficient information about the working 3CX system. Please post a SIP trace of a successful outbound call, starting from the initial INVITE through at least the first 180 ringing, 183 progress or 200 OK.