Call-ID: 908959f5-4d8a-42fc-bf65-4d998934ad09
CSeq: 42138 OPTIONS
Max-Forwards: 70
User-Agent: FPBX-17.0.28(22.8.2)
Content-Length: 0
[2026-04-22 10:04:43] VERBOSE[2591] res_pjsip_logger.c: <--- Transmitting SIP request (407 bytes) to UDP:172.18.5.2:5060 --->
Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj217094a8-2684-450d-95ad-a4bf6fa26efe
Call-ID: 908959f5-4d8a-42fc-bf65-4d998934ad09
CSeq: 42138 OPTIONS
Max-Forwards: 70
User-Agent: FPBX-17.0.28(22.8.2)
Content-Length: 0
[2026-04-22 10:04:47] VERBOSE[2591] res_pjsip_logger.c: <--- Transmitting SIP request (407 bytes) to UDP:172.18.5.2:5060 --->
Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj217094a8-2684-450d-95ad-a4bf6fa26efe
Call-ID: 908959f5-4d8a-42fc-bf65-4d998934ad09
CSeq: 42138 OPTIONS
Max-Forwards: 70
User-Agent: FPBX-17.0.28(22.8.2)
Content-Length: 0
[2026-04-22 10:04:48] VERBOSE[2591] res_pjsip_logger.c: <--- Received SIP request (699 bytes) from UDP:172.18.5.2:5060 --->
Via: SIP/2.0/UDP 172.18.5.2:5060;branch=z9hG4bK13837-0
Max-Forwards: 70
CSeq: 1 INVITE
Accept: application/sdp
Allow: INVITE,ACK,BYE,CANCEL,NOTIFY,SUBSCRIBE
Content-Type: application/sdp
Content-Length: 292
v=0
o=CMG-4.01D 9627101 9627101 IN IP4 172.18.5.2
s=RTP Audio
c=IN IP4 172.18.5.2
t=0 0
m=audio 19999 RTP/AVP 0 8 102 98 2 99
a=ptime:20
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:102 G726-16/8000
a=rtpmap:98 G726-24/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:99 G726-40/8000
[2026-04-22 10:04:48] VERBOSE[2586] res_pjsip_logger.c: <--- Transmitting SIP response (448 bytes) to UDP:172.18.5.2:5060 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 172.18.5.2:5060;rport=5060;received=172.18.5.2;branch=z9hG4bK13837-0
CSeq: 1 INVITE
WWW-Authenticate: Digest realm="asterisk",nonce="1776866688/8b59012d15ea75febc0fa0b17e213f3b",opaque="6b54b40156a20cbe",algorithm=MD5,qop="auth"
Server: FPBX-17.0.28(22.8.2)
Content-Length: 0
[2026-04-22 10:04:48] VERBOSE[2591] res_pjsip_logger.c: <--- Received SIP request (275 bytes) from UDP:172.18.5.2:5060 --->
Via: SIP/2.0/UDP 172.18.5.2:5060;branch=z9hG4bK13837-0
Max-Forwards: 70
CSeq: 1 ACK
Content-Length: 0
[2026-04-22 10:04:48] VERBOSE[2591] res_pjsip_logger.c: <--- Received SIP request (925 bytes) from UDP:172.18.5.2:5060 --->
Via: SIP/2.0/UDP 172.18.5.2:5060;branch=z9hG4bK9412-0
Max-Forwards: 70
CSeq: 2 INVITE
Accept: application/sdp
Allow: INVITE,ACK,BYE,CANCEL,NOTIFY,SUBSCRIBE
Authorization: Digest username="001", realm="asterisk", nonce="1776866688/8b59012d15ea75febc0fa0b17e213f3b", uri="sip:
[email protected]:5060", response="4b519cfb3acf813cbdfc89b54cdaf96c", algorithm=MD5, opaque="6b54b40156a20cbe"
Content-Type: application/sdp
Content-Length: 292
v=0
o=CMG-4.01D 9627101 9627101 IN IP4 172.18.5.2
s=RTP Audio
c=IN IP4 172.18.5.2
t=0 0
m=audio 19999 RTP/AVP 0 8 102 98 2 99
a=ptime:20
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:102 G726-16/8000
a=rtpmap:98 G726-24/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:99 G726-40/8000
[2026-04-22 10:04:48] VERBOSE[2586] res_pjsip_logger.c: <--- Transmitting SIP response (276 bytes) to UDP:172.18.5.2:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 172.18.5.2:5060;rport=5060;received=172.18.5.2;branch=z9hG4bK9412-0
CSeq: 2 INVITE
Server: FPBX-17.0.28(22.8.2)
Content-Length: 0
[2026-04-22 10:04:48] VERBOSE[2586] netsock2.c: Using SIP RTP Audio TOS bits 184
[2026-04-22 10:04:48] VERBOSE[2586] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2026-04-22 10:04:48] VERBOSE[2586] netsock2.c: Using SIP RTP Audio CoS mark 5
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@from-internal:1] GotoIf("PJSIP/001-0000000e", "1?ext-local,002,1:followme-check,002,1") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (ext-local,002,1)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@ext-local:1] Set("PJSIP/001-0000000e", "__RINGTIMER=10") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@ext-local:2] ExecIf("PJSIP/001-0000000e", "0?Set(__CWIGNORE=)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@ext-local:3] Gosub("PJSIP/001-0000000e", "macro-exten-vm,s,1(002,002,0,0,0)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:1] Gosub("PJSIP/001-0000000e", "macro-user-callerid,s,1(002,002,,,,macro-exten-vm)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/001-0000000e", "TOUCH_MONITOR=1776866688.24") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/001-0000000e", "CHANCONTEXT=") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/001-0000000e", "CHANCONTEXT=") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/001-0000000e", "CHANEXTENCONTEXT=001-0000000e") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/001-0000000e", "CHANEXTEN=001-0000000e") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/001-0000000e", "CALLERID(number)=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/001-0000000e", "AMPUSER=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/001-0000000e", "HOTDESCKCHAN=001-0000000e") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/001-0000000e", "HOTDESKEXTEN=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/001-0000000e", "HOTDESKCALL=0") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("PJSIP/001-0000000e", "0?Set(HOTDESKCALL=1)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/001-0000000e", "0?Set(CALLERID(name)=)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/001-0000000e", "0?report") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/001-0000000e", "1?Set(REALCALLERIDNUM=001)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/001-0000000e", "AMPUSER=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/001-0000000e", "0?limit") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/001-0000000e", "AMPUSERCIDNAME=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("PJSIP/001-0000000e", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/001-0000000e", "0?report") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:20] Set("PJSIP/001-0000000e", "AMPUSERCID=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:21] Set("PJSIP/001-0000000e", "__DIAL_OPTIONS=HhTtr") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:22] Set("PJSIP/001-0000000e", "CALLERID(all)="001" <001>") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:23] ExecIf("PJSIP/001-0000000e", "0?Set(CUSDIAL=002)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/001-0000000e", "0?Set(CALLERID(all)="001" <001>)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("PJSIP/001-0000000e", "0?limit") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:26] ExecIf("PJSIP/001-0000000e", "0?Set(GROUP(concurrency_limit)=001)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("PJSIP/001-0000000e", "0?Set(CHANNEL(language)=)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:28] NoOp("PJSIP/001-0000000e", "Macro depricated!! To keep the same line numbers") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:29] NoOp("PJSIP/001-0000000e", "Macro depricated !! To keep the same line numbers") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("PJSIP/001-0000000e", "0?continue") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("PJSIP/001-0000000e", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:32] Set("PJSIP/001-0000000e", "__TTL=64") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("PJSIP/001-0000000e", "1?continue") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/001-0000000e", "CALLERID(number)=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/001-0000000e", "CALLERID(name)=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("PJSIP/001-0000000e", "0?cnum") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/001-0000000e", "__MCNUM=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/001-0000000e", "__MCNAME=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/001-0000000e", "__MCEXTEN=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:55] Set("PJSIP/001-0000000e", "__MCORGCHAN=PJSIP/001-0000000e") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:56] Set("PJSIP/001-0000000e", "CDR(cnam)=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:57] Set("PJSIP/001-0000000e", "CDR(cnum)=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-user-callerid:58] Return("PJSIP/001-0000000e", "") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:2] Set("PJSIP/001-0000000e", "RingGroupMethod=none") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:3] Set("PJSIP/001-0000000e", "__EXTTOCALL=002") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:4] Set("PJSIP/001-0000000e", "__PICKUPMARK=002") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:5] Set("PJSIP/001-0000000e", "RT=10") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:6] GotoIf("PJSIP/001-0000000e", "1?startcheck:exitcheck") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (macro-exten-vm,s,7)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:7] GotoIf("PJSIP/001-0000000e", "0?featureSIP:featurePJSIP") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (macro-exten-vm,s,11)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:11] ExecIf("PJSIP/001-0000000e", "0?Gosub(macro-vm,002,DIRECTDIAL,)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:12] ExecIf("PJSIP/001-0000000e", "0?Return()") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:13] GotoIf("PJSIP/001-0000000e", "1?featuremoniPJSIP:featuremoniSIP") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (macro-exten-vm,s,14)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:14] ExecIf("PJSIP/001-0000000e", "0?ChanSpy(PJSIP/002,q)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:15] ExecIf("PJSIP/001-0000000e", "0?Return()") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:16] GotoIf("PJSIP/001-0000000e", "1?check-ext-intercom:featuremoniSIP") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (macro-exten-vm,s,19)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:19] GotoIf("PJSIP/001-0000000e", "0?ext-intercomSIP:ext-intercomPJSIP") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (macro-exten-vm,s,20)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:20] ExecIf("PJSIP/001-0000000e", "0?Gosub(ext-intercom,*80002,1())") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:21] ExecIf("PJSIP/001-0000000e", "0?Return()") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:22] GotoIf("PJSIP/001-0000000e", "1?exitcheck:ext-intercomSIP") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (macro-exten-vm,s,25)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:25] NoOp("PJSIP/001-0000000e", "Exiting Checks") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:26] Gosub("PJSIP/001-0000000e", "sub-record-check,s,1(exten,002,dontcare)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/001-0000000e", "0?initialized") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/001-0000000e", "__REC_STATUS=INITIALIZED") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/001-0000000e", "NOW=1776866688") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/001-0000000e", "__DAY=22") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/001-0000000e", "__MONTH=04") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/001-0000000e", "__YEAR=2026") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/001-0000000e", "__TIMESTR=20260422-100448") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/001-0000000e", "__FROMEXTEN=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/001-0000000e", "__MON_FMT=wav") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/001-0000000e", "Recordings initialized") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/001-0000000e", "0?Set(ARG3=dontcare)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/001-0000000e", "REC_POLICY_MODE_SAVE=") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/001-0000000e", "0?Set(REC_STATUS=NO)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/001-0000000e", "5?checkaction") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (sub-record-check,s,17)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/001-0000000e", "1?sub-record-check,exten,1") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:1] NoOp("PJSIP/001-0000000e", "Exten Recording Check between 001 and 002") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:2] Set("PJSIP/001-0000000e", "CALLTYPE=internal") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:3] ExecIf("PJSIP/001-0000000e", "0?Set(CALLTYPE=)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:4] Set("PJSIP/001-0000000e", "CALLEE=dontcare") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:5] ExecIf("PJSIP/001-0000000e", "0?Set(CALLEE=dontcare)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:6] GotoIf("PJSIP/001-0000000e", "0?callee") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:7] GotoIf("PJSIP/001-0000000e", "1?caller") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (sub-record-check,exten,13)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:13] Set("PJSIP/001-0000000e", "RECMODE=dontcare") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:14] Set("PJSIP/001-0000000e", "CALLERRECMODE=dontcare") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:15] Set("PJSIP/001-0000000e", "CALEERECMODE=dontcare") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:16] GotoIf("PJSIP/001-0000000e", "0?processnormal") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:17] ExecIf("PJSIP/001-0000000e", "0?Set(RECMODE=dontcare)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:18] ExecIf("PJSIP/001-0000000e", "0?Set(RECMODE=dontcare)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:19] ExecIf("PJSIP/001-0000000e", "0?Set(RECMODE=dontcare)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:20] ExecIf("PJSIP/001-0000000e", "0?Set(RECMODE=dontcare)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:21] ExecIf("PJSIP/001-0000000e", "0?Set(RECMODE=dontcare)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:22] ExecIf("PJSIP/001-0000000e", "1?Set(RECMODE=dontcare)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:23] Gosub("PJSIP/001-0000000e", "recordcheck,1(dontcare,internal,002)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/001-0000000e", "Starting recording check against dontcare") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/001-0000000e", "dontcare") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/001-0000000e", "") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [exten@sub-record-check:24] Return("PJSIP/001-0000000e", "") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:27] GotoIf("PJSIP/001-0000000e", "1?macrodial") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (macro-exten-vm,s,33)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:33] GosubIf("PJSIP/001-0000000e", "0?clrheader,1()") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-exten-vm:34] Gosub("PJSIP/001-0000000e", "macro-dial-one,s,1(10,HhTtr,002)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:1] Set("PJSIP/001-0000000e", "DEXTEN=002") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:2] Set("PJSIP/001-0000000e", "__CRM_SOURCE=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:3] Set("PJSIP/001-0000000e", "__MCEXTTOCALL=002") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:4] Set("PJSIP/001-0000000e", "__MCMULTI=45ba384dbf899ecdcfb14f27f4883b4d") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:5] ExecIf("PJSIP/001-0000000e", "0?Set(__EXTTOCALL=002)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:6] Set("PJSIP/001-0000000e", "DIALSTATUS_CW=") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:7] GosubIf("PJSIP/001-0000000e", "0?screen,1()") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:8] GosubIf("PJSIP/001-0000000e", "0?cf,1()") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:9] GotoIf("PJSIP/001-0000000e", "1?skip1") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (macro-dial-one,s,12)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:12] GotoIf("PJSIP/001-0000000e", "0?nodial") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:13] GotoIf("PJSIP/001-0000000e", "0?continue") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:14] Set("PJSIP/001-0000000e", "EXTHASCW=ENABLED") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:15] GotoIf("PJSIP/001-0000000e", "0?next1:cwinusebusy") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (macro-dial-one,s,27)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:27] GotoIf("PJSIP/001-0000000e", "0?next3:continue") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (macro-dial-one,s,29)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:29] GotoIf("PJSIP/001-0000000e", "0?nodial") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:30] GosubIf("PJSIP/001-0000000e", "1?dstring,1():dlocal,1()") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:1] Set("PJSIP/001-0000000e", "DSTRING=") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:2] Set("PJSIP/001-0000000e", "DEVICES=002") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("PJSIP/001-0000000e", "0?Return()") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("PJSIP/001-0000000e", "0?Set(DEVICES=02)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:5] Set("PJSIP/001-0000000e", "LOOPCNT=1") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:6] Set("PJSIP/001-0000000e", "ITER=1") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:7] Set("PJSIP/001-0000000e", "THISDIAL=PJSIP/002") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:8] GotoIf("PJSIP/001-0000000e", "0?docheck") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:9] NoOp("PJSIP/001-0000000e", "Debug: Found PJSIP Destination PJSIP/002") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:10] GotoIf("PJSIP/001-0000000e", "0?doset") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:11] NoOp("PJSIP/001-0000000e", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:12] Set("PJSIP/001-0000000e", "THISDIAL=PJSIP/002/sip:
[email protected]:5060") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:13] ExecIf("PJSIP/001-0000000e", "0?Set(DIALSTATUS=CHANUNAVAIL)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("PJSIP/001-0000000e", "0?skipset") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:15] Set("PJSIP/001-0000000e", "DSTRING=PJSIP/002/sip:
[email protected]:5060&") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:16] Set("PJSIP/001-0000000e", "ITER=2") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf("PJSIP/001-0000000e", "0?begin") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf("PJSIP/001-0000000e", "0?Return()") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:19] Set("PJSIP/001-0000000e", "DSTRING=PJSIP/002/sip:
[email protected]:5060") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [dstring@macro-dial-one:20] Return("PJSIP/001-0000000e", "") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:31] GotoIf("PJSIP/001-0000000e", "0?nodial") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:32] GotoIf("PJSIP/001-0000000e", "0?skiptrace") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:33] GosubIf("PJSIP/001-0000000e", "1?ctset,1():ctclear,1()") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [ctset@macro-dial-one:1] Set("PJSIP/001-0000000e", "DB(CALLTRACE/002)=001") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [ctset@macro-dial-one:2] Return("PJSIP/001-0000000e", "") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:34] Set("PJSIP/001-0000000e", "D_OPTIONS=HhTtr") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:35] GosubIf("PJSIP/001-0000000e", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:36] NoOp("PJSIP/001-0000000e", "Blind Transfer: , Attended Transfer: , User: 001, Alert Info: ") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:37] ExecIf("PJSIP/001-0000000e", "1?Set(ALERT_INFO=)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:38] ExecIf("PJSIP/001-0000000e", "0?Set(ALERT_INFO=)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:39] ExecIf("PJSIP/001-0000000e", "0?Set(ALERT_INFO=)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:40] ExecIf("PJSIP/001-0000000e", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:41] ExecIf("PJSIP/001-0000000e", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:42] GosubIf("PJSIP/001-0000000e", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:43] ExecIf("PJSIP/001-0000000e", "0?Set(CHANNEL(musicclass)=)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:44] GosubIf("PJSIP/001-0000000e", "0?qwait,1()") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:45] Set("PJSIP/001-0000000e", "__CWIGNORE=") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:46] Set("PJSIP/001-0000000e", "__KEEPCID=TRUE") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:47] GotoIf("PJSIP/001-0000000e", "0?usegoto,1") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:48] GotoIf("PJSIP/001-0000000e", "0?godial") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:49] Gosub("PJSIP/001-0000000e", "sub-presencestate-display,s,1(002)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@sub-presencestate-display:1] Goto("PJSIP/001-0000000e", "state-available,1") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (sub-presencestate-display,state-available,1)
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [state-available@sub-presencestate-display:1] Set("PJSIP/001-0000000e", "PRESENCESTATE_DISPLAY=(Available)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [state-available@sub-presencestate-display:2] Return("PJSIP/001-0000000e", "") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:50] Set("PJSIP/001-0000000e", "CONNECTEDLINE(name,i)=Phone 2 (Available)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:51] Set("PJSIP/001-0000000e", "CONNECTEDLINE(num)=002") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:52] Set("PJSIP/001-0000000e", "D_OPTIONS=HhTtr") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:53] Gosub("PJSIP/001-0000000e", "macro-dialout-one-predial-hook,s,1()") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] Return("PJSIP/001-0000000e", "") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:54] ExecIf("PJSIP/001-0000000e", "0?Set(D_OPTIONS=HhtrI)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:55] ExecIf("PJSIP/001-0000000e", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:56] NoOp("PJSIP/001-0000000e", "") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:57] ExecIf("PJSIP/001-0000000e", "0?Set(D_OPTIONS=Hhtr)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:58] ExecIf("PJSIP/001-0000000e", "0?Set(D_OPTIONS=HhTtrg)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-dial-one:59] Gosub("PJSIP/001-0000000e", "dialOne-with-exten,002,1(10)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@dialOne-with-exten:1] Set("PJSIP/001-0000000e", "CHANNEL(hangup_handler_push)=app-missedcall-hangup,,1") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@dialOne-with-exten:2] Dial("PJSIP/001-0000000e", "PJSIP/002/sip:
[email protected]:5060,10,HhTtrb(func-apply-sipheaders^s^1)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] app_stack.c: PJSIP/002-0000000f Internal Gosub(func-apply-sipheaders,s,1) start
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/002-0000000f", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/002-0000000f", "Applying SIP Headers to channel PJSIP/002-0000000f") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/002-0000000f", "localchan=002-0000000f") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/002-0000000f", "DialMCEXT=002") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:5] Set("PJSIP/002-0000000f", "CHANNEL(hangup_handler_push)=app-missedcall-hangup,002,1") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/002-0000000f", "Dchan=002-0000000f") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:7] Set("PJSIP/002-0000000f", "TECH=PJSIP") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:8] Set("PJSIP/002-0000000f", "SIPHEADERKEYS=") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:9] While("PJSIP/002-0000000f", "0") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] app_while.c: Jumping to priority 15
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@func-apply-sipheaders:16] Return("PJSIP/002-0000000f", "") in new stack
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] app_stack.c: Spawn extension (from-internal, 002, 1) exited non-zero on 'PJSIP/002-0000000f'
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] app_stack.c: PJSIP/002-0000000f Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2026-04-22 10:04:48] VERBOSE[2586] netsock2.c: Using SIP RTP Audio TOS bits 184
[2026-04-22 10:04:48] VERBOSE[2586] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2026-04-22 10:04:48] VERBOSE[2586] netsock2.c: Using SIP RTP Audio CoS mark 5
[2026-04-22 10:04:48] VERBOSE[2586] res_pjsip_logger.c: <--- Transmitting SIP request (1044 bytes) to UDP:172.18.5.2:5060 --->
Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj07b43a61-88ec-46ba-b86e-b2b94ad24993
Call-ID: 86f37e21-a3d0-483c-82ac-555758497b97
CSeq: 10939 INVITE
Allow: OPTIONS, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INFO, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub, histinfo
Session-Expires: 1800
Min-SE: 90
Max-Forwards: 70
User-Agent: FPBX-17.0.28(22.8.2)
Content-Type: application/sdp
Content-Length: 335
v=0
o=- 1297886677 1297886677 IN IP4 172.18.5.4
s=Asterisk
c=IN IP4 172.18.5.4
t=0 0
m=audio 16038 RTP/AVP 0 8 111 3 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:140
a=sendrecv
[2026-04-22 10:04:48] VERBOSE[30494][C-0000000b] app_dial.c: Called PJSIP/002/sip:
[email protected]:5060
[2026-04-22 10:04:48] VERBOSE[2586] res_pjsip_logger.c: <--- Transmitting SIP response (520 bytes) to UDP:172.18.5.2:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 172.18.5.2:5060;rport=5060;received=172.18.5.2;branch=z9hG4bK9412-0
CSeq: 2 INVITE
Server: FPBX-17.0.28(22.8.2)
Contact: <sip:172.18.5.4:5060>
Allow: OPTIONS, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INFO, MESSAGE, REFER
Content-Length: 0
[2026-04-22 10:04:49] VERBOSE[2591] res_pjsip_logger.c: <--- Transmitting SIP request (1044 bytes) to UDP:172.18.5.2:5060 --->
Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj07b43a61-88ec-46ba-b86e-b2b94ad24993
Call-ID: 86f37e21-a3d0-483c-82ac-555758497b97
CSeq: 10939 INVITE
Allow: OPTIONS, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INFO, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub, histinfo
Session-Expires: 1800
Min-SE: 90
Max-Forwards: 70
User-Agent: FPBX-17.0.28(22.8.2)
Content-Type: application/sdp
Content-Length: 335
v=0
o=- 1297886677 1297886677 IN IP4 172.18.5.4
s=Asterisk
c=IN IP4 172.18.5.4
t=0 0
m=audio 16038 RTP/AVP 0 8 111 3 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:140
a=sendrecv
[2026-04-22 10:04:50] VERBOSE[2591] res_pjsip_logger.c: <--- Transmitting SIP request (1044 bytes) to UDP:172.18.5.2:5060 --->
Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj07b43a61-88ec-46ba-b86e-b2b94ad24993
Call-ID: 86f37e21-a3d0-483c-82ac-555758497b97
CSeq: 10939 INVITE
Allow: OPTIONS, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INFO, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub, histinfo
Session-Expires: 1800
Min-SE: 90
Max-Forwards: 70
User-Agent: FPBX-17.0.28(22.8.2)
Content-Type: application/sdp
Content-Length: 335
v=0
o=- 1297886677 1297886677 IN IP4 172.18.5.4
s=Asterisk
c=IN IP4 172.18.5.4
t=0 0
m=audio 16038 RTP/AVP 0 8 111 3 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:140
a=sendrecv
[2026-04-22 10:04:51] VERBOSE[2591] res_pjsip_logger.c: <--- Transmitting SIP request (407 bytes) to UDP:172.18.5.2:5060 --->
Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj217094a8-2684-450d-95ad-a4bf6fa26efe
Call-ID: 908959f5-4d8a-42fc-bf65-4d998934ad09
CSeq: 42138 OPTIONS
Max-Forwards: 70
User-Agent: FPBX-17.0.28(22.8.2)
Content-Length: 0
[2026-04-22 10:04:52] VERBOSE[2591] res_pjsip_logger.c: <--- Transmitting SIP request (1044 bytes) to UDP:172.18.5.2:5060 --->
Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj07b43a61-88ec-46ba-b86e-b2b94ad24993
Call-ID: 86f37e21-a3d0-483c-82ac-555758497b97
CSeq: 10939 INVITE
Allow: OPTIONS, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INFO, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub, histinfo
Session-Expires: 1800
Min-SE: 90
Max-Forwards: 70
User-Agent: FPBX-17.0.28(22.8.2)
Content-Type: application/sdp
Content-Length: 335
v=0
o=- 1297886677 1297886677 IN IP4 172.18.5.4
s=Asterisk
c=IN IP4 172.18.5.4
t=0 0
m=audio 16038 RTP/AVP 0 8 111 3 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:140
a=sendrecv
[2026-04-22 10:04:53] VERBOSE[2591] res_pjsip_logger.c: <--- Received SIP request (302 bytes) from UDP:172.18.5.2:5060 --->
Via: SIP/2.0/UDP 172.18.5.2:5060;branch=z9hG4bK9412-0
Max-Forwards: 70
CSeq: 2 CANCEL
Content-Length: 0
[2026-04-22 10:04:53] VERBOSE[2586] res_pjsip_logger.c: <--- Transmitting SIP response (313 bytes) to UDP:172.18.5.2:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.18.5.2:5060;rport=5060;received=172.18.5.2;branch=z9hG4bK9412-0
CSeq: 2 CANCEL
Server: FPBX-17.0.28(22.8.2)
Content-Length: 0
[2026-04-22 10:04:53] VERBOSE[2586] res_pjsip_logger.c: <--- Transmitting SIP response (499 bytes) to UDP:172.18.5.2:5060 --->
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 172.18.5.2:5060;rport=5060;received=172.18.5.2;branch=z9hG4bK9412-0
CSeq: 2 INVITE
Server: FPBX-17.0.28(22.8.2)
Allow: OPTIONS, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INFO, MESSAGE, REFER
Content-Length: 0
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] app_stack.c: PJSIP/002-0000000f Internal Gosub(app-missedcall-hangup,002,1) start
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:1] NoOp("PJSIP/002-0000000f", "Dialed: 002") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:2] NoOp("PJSIP/002-0000000f", "Caller: 001") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:3] GotoIf("PJSIP/002-0000000f", "0?exit") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:4] Set("PJSIP/002-0000000f", "EXTENNUM=002") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:5] Set("PJSIP/002-0000000f", "FEXTENNUM=002") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:6] GotoIf("PJSIP/002-0000000f", "0?exit") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:7] AGI("PJSIP/002-0000000f", "agi://127.0.0.1/missedcallnotify.php,002,,002,0,,PJSIP/002-0000000f,,,,") in new stack
[2026-04-22 10:04:53] VERBOSE[2591] res_pjsip_logger.c: <--- Received SIP request (296 bytes) from UDP:172.18.5.2:5060 --->
Via: SIP/2.0/UDP 172.18.5.2:5060;branch=z9hG4bK9412-0
Max-Forwards: 70
CSeq: 2 ACK
Content-Length: 0
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] res_agi.c: <PJSIP/002-0000000f>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:8] Return("PJSIP/002-0000000f", "") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] app_stack.c: Spawn extension (from-internal, 002, 1) exited non-zero on 'PJSIP/002-0000000f'
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] app_stack.c: PJSIP/002-0000000f Internal Gosub(app-missedcall-hangup,002,1) complete GOSUB_RETVAL=
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Spawn extension (dialOne-with-exten, 002, 2) exited non-zero on 'PJSIP/001-0000000e'
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [h@dialOne-with-exten:1] Gosub("PJSIP/001-0000000e", "macro-hangupcall,s,1()") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-hangupcall:1] Set("PJSIP/001-0000000e", "__MCVMSTATUS=") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-hangupcall:2] Gosub("PJSIP/001-0000000e", "app-missedcall-hangup,s,1()") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@app-missedcall-hangup:1] NoOp("PJSIP/001-0000000e", "Dialed: s") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@app-missedcall-hangup:2] NoOp("PJSIP/001-0000000e", "Caller: 001") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@app-missedcall-hangup:3] GotoIf("PJSIP/001-0000000e", "0?exit") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@app-missedcall-hangup:4] Set("PJSIP/001-0000000e", "EXTENNUM=s") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@app-missedcall-hangup:5] Set("PJSIP/001-0000000e", "FEXTENNUM=s") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@app-missedcall-hangup:6] GotoIf("PJSIP/001-0000000e", "0?exit") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@app-missedcall-hangup:7] AGI("PJSIP/001-0000000e", "agi://127.0.0.1/missedcallnotify.php,s,,s,0,,PJSIP/001-0000000e,CANCEL,,,") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] res_agi.c: <PJSIP/001-0000000e>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@app-missedcall-hangup:8] Return("PJSIP/001-0000000e", "") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-hangupcall:3] UserEvent("PJSIP/001-0000000e", "MES,RTPAUDIOQOSMESBRIDGED:,RTPAUDIOQOSMES:") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-hangupcall:4] GotoIf("PJSIP/001-0000000e", "1?theend") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx_builtins.c: Goto (macro-hangupcall,s,6)
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-hangupcall:6] ExecIf("PJSIP/001-0000000e", "0?Set(CDR(recordingfile)=)") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [s@macro-hangupcall:7] Hangup("PJSIP/001-0000000e", "") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/001-0000000e'
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] app_stack.c: PJSIP/001-0000000e Internal Gosub(app-missedcall-hangup,002,1) start
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:1] NoOp("PJSIP/001-0000000e", "Dialed: 002") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:2] NoOp("PJSIP/001-0000000e", "Caller: 001") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:3] GotoIf("PJSIP/001-0000000e", "0?exit") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:4] Set("PJSIP/001-0000000e", "EXTENNUM=002") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:5] Set("PJSIP/001-0000000e", "FEXTENNUM=002") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:6] GotoIf("PJSIP/001-0000000e", "0?exit") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:7] AGI("PJSIP/001-0000000e", "agi://127.0.0.1/missedcallnotify.php,002,,002,0,,PJSIP/001-0000000e,CANCEL,,,") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] res_agi.c: <PJSIP/001-0000000e>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] pbx.c: Executing [002@app-missedcall-hangup:8] Return("PJSIP/001-0000000e", "") in new stack
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] app_stack.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/001-0000000e'
[2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] app_stack.c: PJSIP/001-0000000e Internal Gosub(app-missedcall-hangup,002,1) complete GOSUB_RETVAL=
[2026-04-22 10:04:55] VERBOSE[2591] res_pjsip_logger.c: <--- Transmitting SIP request (407 bytes) to UDP:172.18.5.2:5060 --->
Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj217094a8-2684-450d-95ad-a4bf6fa26efe
Call-ID: 908959f5-4d8a-42fc-bf65-4d998934ad09
CSeq: 42138 OPTIONS
Max-Forwards: 70
User-Agent: FPBX-17.0.28(22.8.2)
Content-Length: 0