From: ;tag=6277a8e0-8191-49d8-bc0f-2841d65aa6ff To: Contact: 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 ---> OPTIONS sip:001@172.18.5.2:5060 SIP/2.0 Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj217094a8-2684-450d-95ad-a4bf6fa26efe From: ;tag=6277a8e0-8191-49d8-bc0f-2841d65aa6ff To: Contact: 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 ---> OPTIONS sip:001@172.18.5.2:5060 SIP/2.0 Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj217094a8-2684-450d-95ad-a4bf6fa26efe From: ;tag=6277a8e0-8191-49d8-bc0f-2841d65aa6ff To: Contact: 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 ---> INVITE sip:002@172.18.5.4:5060 SIP/2.0 Via: SIP/2.0/UDP 172.18.5.2:5060;branch=z9hG4bK13837-0 Max-Forwards: 70 To: From: "001";tag=9627101 Call-ID: 15637-11@172.18.5.2 CSeq: 1 INVITE Contact: 001 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 Call-ID: 15637-11@172.18.5.2 From: "001" ;tag=9627101 To: ;tag=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 ---> ACK sip:002@172.18.5.4:5060 SIP/2.0 Via: SIP/2.0/UDP 172.18.5.2:5060;branch=z9hG4bK13837-0 Max-Forwards: 70 To: ;tag=z9hG4bK13837-0 From: "001";tag=9627101 Call-ID: 15637-11@172.18.5.2 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 ---> INVITE sip:002@172.18.5.4:5060 SIP/2.0 Via: SIP/2.0/UDP 172.18.5.2:5060;branch=z9hG4bK9412-0 Max-Forwards: 70 To: From: "001";tag=9627101 Call-ID: 15637-11@172.18.5.2 CSeq: 2 INVITE Contact: 001 Accept: application/sdp Allow: INVITE,ACK,BYE,CANCEL,NOTIFY,SUBSCRIBE Authorization: Digest username="001", realm="asterisk", nonce="1776866688/8b59012d15ea75febc0fa0b17e213f3b", uri="sip:002@172.18.5.4: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 Call-ID: 15637-11@172.18.5.2 From: "001" ;tag=9627101 To: 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:002@172.18.5.2: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:002@172.18.5.2: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:002@172.18.5.2: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:002@172.18.5.2: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 ---> INVITE sip:002@172.18.5.2:5060 SIP/2.0 Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj07b43a61-88ec-46ba-b86e-b2b94ad24993 From: "001" ;tag=1542ef92-1f05-4008-ae6b-ad5910f5ea86 To: Contact: 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 P-Asserted-Identity: "001" 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:002@172.18.5.2: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 Call-ID: 15637-11@172.18.5.2 From: "001" ;tag=9627101 To: ;tag=70767946-ec01-47b8-a32a-edce41633195 CSeq: 2 INVITE Server: FPBX-17.0.28(22.8.2) Contact: Allow: OPTIONS, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INFO, MESSAGE, REFER P-Asserted-Identity: "Phone 2" 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 ---> INVITE sip:002@172.18.5.2:5060 SIP/2.0 Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj07b43a61-88ec-46ba-b86e-b2b94ad24993 From: "001" ;tag=1542ef92-1f05-4008-ae6b-ad5910f5ea86 To: Contact: 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 P-Asserted-Identity: "001" 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 ---> INVITE sip:002@172.18.5.2:5060 SIP/2.0 Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj07b43a61-88ec-46ba-b86e-b2b94ad24993 From: "001" ;tag=1542ef92-1f05-4008-ae6b-ad5910f5ea86 To: Contact: 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 P-Asserted-Identity: "001" 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 ---> OPTIONS sip:001@172.18.5.2:5060 SIP/2.0 Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj217094a8-2684-450d-95ad-a4bf6fa26efe From: ;tag=6277a8e0-8191-49d8-bc0f-2841d65aa6ff To: Contact: 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 ---> INVITE sip:002@172.18.5.2:5060 SIP/2.0 Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj07b43a61-88ec-46ba-b86e-b2b94ad24993 From: "001" ;tag=1542ef92-1f05-4008-ae6b-ad5910f5ea86 To: Contact: 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 P-Asserted-Identity: "001" 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 ---> CANCEL sip:002@172.18.5.4:5060 SIP/2.0 Via: SIP/2.0/UDP 172.18.5.2:5060;branch=z9hG4bK9412-0 Max-Forwards: 70 To: ;tag=70767946-ec01-47b8-a32a-edce41633195 From: "001";tag=9627101 Call-ID: 15637-11@172.18.5.2 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 Call-ID: 15637-11@172.18.5.2 From: "001" ;tag=9627101 To: ;tag=70767946-ec01-47b8-a32a-edce41633195 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 Call-ID: 15637-11@172.18.5.2 From: "001" ;tag=9627101 To: ;tag=70767946-ec01-47b8-a32a-edce41633195 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 P-Asserted-Identity: "Phone 2" 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 ---> ACK sip:002@172.18.5.4:5060 SIP/2.0 Via: SIP/2.0/UDP 172.18.5.2:5060;branch=z9hG4bK9412-0 Max-Forwards: 70 To: ;tag=70767946-ec01-47b8-a32a-edce41633195 From: "001";tag=9627101 Call-ID: 15637-11@172.18.5.2 CSeq: 2 ACK Content-Length: 0 [2026-04-22 10:04:53] VERBOSE[30494][C-0000000b] res_agi.c: 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: 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: 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 ---> OPTIONS sip:001@172.18.5.2:5060 SIP/2.0 Via: SIP/2.0/UDP 172.18.5.4:5060;rport;branch=z9hG4bKPj217094a8-2684-450d-95ad-a4bf6fa26efe From: ;tag=6277a8e0-8191-49d8-bc0f-2841d65aa6ff To: Contact: 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