Can’t call from 101 to 102 or from 102 to 101. I got “Address not found” on SIP SoftPhones.
Booth extensions can originate outgoing calls, but no one can answer or receive any call.
Networking is working well, DNS OK. Ignore mypbx.mydomain.com DNS and ip’s, I changed them for security.
Also, no one extension is ringing in ring groups or queues…
mypbx*CLI> pjsip set logger on
PJSIP Logging enabled
<--- Transmitting SIP request (479 bytes) to UDP:194.105.106.107:5060 --->
OPTIONS sip:kvlObTXbN3W0MYuVqKOr@sip.mysipprovider.com:5060 SIP/2.0
Via: SIP/2.0/UDP 123.123.123.123:5060;rport;branch=z9hG4bKPjda05571b-7c61-4f74-bec2-d7a4724334c5
From: <sip:kvlObTXbN3W0MYuVqKOr@123.123.123.123>;tag=268e80a6-1e13-4e60-af8c-3c268267c7dd
To: <sip:kvlObTXbN3W0MYuVqKOr@sip.mysipprovider.com>
Contact: <sip:kvlObTXbN3W0MYuVqKOr@123.123.123.123:5060>
Call-ID: 8845086a-4501-4203-b6fc-d63b6dc9f404
CSeq: 48542 OPTIONS
Max-Forwards: 70
User-Agent: VitalPBX
Content-Length: 0
<--- Received SIP response (426 bytes) from UDP:194.105.106.107:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 123.123.123.123:5060;rport=5060;branch=z9hG4bKPjda05571b-7c61-4f74-bec2-d7a4724334c5;received=123.123.123.123
From: <sip:kvlObTXbN3W0MYuVqKOr@123.123.123.123>;tag=268e80a6-1e13-4e60-af8c-3c268267c7dd
To: <sip:kvlObTXbN3W0MYuVqKOr@sip.mysipprovider.com>;tag=59325d77eddf6b32a4102c50e41e78a9.3677c316
Call-ID: 8845086a-4501-4203-b6fc-d63b6dc9f404
CSeq: 48542 OPTIONS
Server: yeti-lb
Content-Length: 0
[2024-04-03 17:00:00] ERROR[43338]: res_pjsip.c:1419 create_out_of_dialog_request: Unable to apply outbound proxy on request OPTIONS to endpoint 102 as outbound proxy URI 'mypbx.mydomain.com' is not valid
[2024-04-03 17:00:00] ERROR[43338]: res_pjsip/pjsip_options.c:880 sip_options_qualify_contact: Unable to create request to qualify contact sip:102@80.25.26.27:3718;ob on AOR 102
<--- Received SIP request (1057 bytes) from UDP:80.25.26.27:3718 --->
INVITE sip:102@mypbx.mydomain.com SIP/2.0
Via: SIP/2.0/UDP 80.25.26.27:3718;rport;branch=z9hG4bKPjc.n1rDNB7IQdjtNco2qF6FLfZ7-pTaa5
Max-Forwards: 70
From: "demo vital" <sip:101@mypbx.mydomain.com>;tag=mwDPvNZ7wtUtv.oNBbBMPSjO4KaiaG8.
To: sip:102@mypbx.mydomain.com
Contact: <sip:101@80.25.26.27:3718;ob>
Call-ID: XBk-1ilvnzIYl8RH.4fXVFG5TabeyUYx
CSeq: 9872 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
User-Agent: Telephone 1.6
Content-Type: application/sdp
Content-Length: 469
v=0
o=- 3921141601 3921141601 IN IP4 80.25.26.27
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4002 RTP/AVP 96 9 8 0 101 102
c=IN IP4 80.25.26.27
b=TIAS:96000
a=rtcp:4003 IN IP4 80.25.26.27
a=sendrecv
a=rtpmap:96 opus/48000/2
a=fmtp:96 useinbandfec=1
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/48000
a=fmtp:101 0-16
a=rtpmap:102 telephone-event/8000
a=fmtp:102 0-16
a=ssrc:58357959 cname:151cef0f1e92cc9d
<--- Transmitting SIP response (552 bytes) to UDP:80.25.26.27:3718 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 80.25.26.27:3718;rport=3718;received=80.25.26.27;branch=z9hG4bKPjc.n1rDNB7IQdjtNco2qF6FLfZ7-pTaa5
Call-ID: XBk-1ilvnzIYl8RH.4fXVFG5TabeyUYx
From: "demo vital" <sip:101@mypbx.mydomain.com>;tag=mwDPvNZ7wtUtv.oNBbBMPSjO4KaiaG8.
To: <sip:102@mypbx.mydomain.com>;tag=z9hG4bKPjc.n1rDNB7IQdjtNco2qF6FLfZ7-pTaa5
CSeq: 9872 INVITE
WWW-Authenticate: Digest realm="asterisk",nonce="1712152801/9e26b8cae729e6875a4547ed75677b8c",opaque="7f1d873e6f58c495",algorithm=MD5,qop="auth"
Server: VitalPBX
Content-Length: 0
<--- Received SIP request (389 bytes) from UDP:80.25.26.27:3718 --->
ACK sip:102@mypbx.mydomain.com SIP/2.0
Via: SIP/2.0/UDP 80.25.26.27:3718;rport;branch=z9hG4bKPjc.n1rDNB7IQdjtNco2qF6FLfZ7-pTaa5
Max-Forwards: 70
From: "demo vital" <sip:101@mypbx.mydomain.com>;tag=mwDPvNZ7wtUtv.oNBbBMPSjO4KaiaG8.
To: sip:102@mypbx.mydomain.com;tag=z9hG4bKPjc.n1rDNB7IQdjtNco2qF6FLfZ7-pTaa5
Call-ID: XBk-1ilvnzIYl8RH.4fXVFG5TabeyUYx
CSeq: 9872 ACK
Content-Length: 0
<--- Received SIP request (1352 bytes) from UDP:80.25.26.27:3718 --->
INVITE sip:102@mypbx.mydomain.com SIP/2.0
Via: SIP/2.0/UDP 80.25.26.27:3718;rport;branch=z9hG4bKPjLvpliZtE2k0ckjIWat1tNXb-hbNm.GLC
Max-Forwards: 70
From: "demo vital" <sip:101@mypbx.mydomain.com>;tag=mwDPvNZ7wtUtv.oNBbBMPSjO4KaiaG8.
To: sip:102@mypbx.mydomain.com
Contact: <sip:101@80.25.26.27:3718;ob>
Call-ID: XBk-1ilvnzIYl8RH.4fXVFG5TabeyUYx
CSeq: 9873 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
User-Agent: Telephone 1.6
Authorization: Digest username="101", realm="asterisk", nonce="1712152801/9e26b8cae729e6875a4547ed75677b8c", uri="sip:102@mypbx.mydomain.com", response="12e4235162b0ed24303045754f69aaee", algorithm=MD5, cnonce="bRGWPQxmsKvM4VSZE3CQYOG7LP4VBSgP", opaque="7f1d873e6f58c495", qop=auth, nc=00000001
Content-Type: application/sdp
Content-Length: 469
v=0
o=- 3921141601 3921141601 IN IP4 80.25.26.27
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4002 RTP/AVP 96 9 8 0 101 102
c=IN IP4 80.25.26.27
b=TIAS:96000
a=rtcp:4003 IN IP4 80.25.26.27
a=sendrecv
a=rtpmap:96 opus/48000/2
a=fmtp:96 useinbandfec=1
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/48000
a=fmtp:101 0-16
a=rtpmap:102 telephone-event/8000
a=fmtp:102 0-16
a=ssrc:58357959 cname:151cef0f1e92cc9d
<--- Transmitting SIP response (354 bytes) to UDP:80.25.26.27:3718 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 80.25.26.27:3718;rport=3718;received=80.25.26.27;branch=z9hG4bKPjLvpliZtE2k0ckjIWat1tNXb-hbNm.GLC
Call-ID: XBk-1ilvnzIYl8RH.4fXVFG5TabeyUYx
From: "demo vital" <sip:101@mypbx.mydomain.com>;tag=mwDPvNZ7wtUtv.oNBbBMPSjO4KaiaG8.
To: <sip:102@mypbx.mydomain.com>
CSeq: 9873 INVITE
Server: VitalPBX
Content-Length: 0
-- Executing [102@cos-all:1] NoOp("PJSIP/101-00000024", "More than on digit pattern") in new stack
-- Executing [102@cos-all:2] Gosub("PJSIP/101-00000024", "s,1(102)") in new stack
-- Executing [s@cos-all:1] Set("PJSIP/101-00000024", "EXTENSION=102") in new stack
-- Executing [s@cos-all:2] NoOp("PJSIP/101-00000024", "Dialing 102 from 101") in new stack
-- Executing [s@cos-all:3] Gosub("PJSIP/101-00000024", "sub-set-global-vars,s,1(b8a9df1279ec90f6,102,parking-1)") in new stack
-- Executing [s@sub-set-global-vars:1] NoOp("PJSIP/101-00000024", "Setting Global Vars") in new stack
-- Executing [s@sub-set-global-vars:2] Set("PJSIP/101-00000024", "TENANT_PATH=b8a9df1279ec90f6") in new stack
-- Executing [s@sub-set-global-vars:3] Set("PJSIP/101-00000024", "PREFIX=") in new stack
-- Executing [s@sub-set-global-vars:4] Set("PJSIP/101-00000024", "__PICKUPMARK=102") in new stack
-- Executing [s@sub-set-global-vars:5] Set("PJSIP/101-00000024", "__PARKINGLOT=parking-1") in new stack
-- Executing [s@sub-set-global-vars:6] Set("PJSIP/101-00000024", "__DYNAMIC_FEATURES=custom-features") in new stack
-- Executing [s@sub-set-global-vars:7] Set("PJSIP/101-00000024", "ICALL=no") in new stack
-- Executing [s@sub-set-global-vars:8] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s@cos-all:4] Gosub("PJSIP/101-00000024", "sub-set-call-vars,s,1(b8a9df1279ec90f6,102,1,cos-all,ARS-all)") in new stack
-- Executing [s@sub-set-call-vars:1] NoOp("PJSIP/101-00000024", "Setting Call Vars") in new stack
-- Executing [s@sub-set-call-vars:2] Set("PJSIP/101-00000024", "_TENANT=b8a9df1279ec90f6") in new stack
-- Executing [s@sub-set-call-vars:3] Set("PJSIP/101-00000024", "_CALL_DESTINATION=102") in new stack
-- Executing [s@sub-set-call-vars:4] Set("PJSIP/101-00000024", "_COS_ID=1") in new stack
-- Executing [s@sub-set-call-vars:5] Set("PJSIP/101-00000024", "_COS_NAME=cos-all") in new stack
-- Executing [s@sub-set-call-vars:6] Set("PJSIP/101-00000024", "__TRANSFER_CONTEXT=cos-all") in new stack
-- Executing [s@sub-set-call-vars:7] Set("PJSIP/101-00000024", "_ARS=ARS-all") in new stack
-- Executing [s@sub-set-call-vars:8] Set("PJSIP/101-00000024", "CDR(tenant)=vitalpbx") in new stack
-- Executing [s@sub-set-call-vars:9] Set("PJSIP/101-00000024", "CDR(destination)=102") in new stack
-- Executing [s@sub-set-call-vars:10] Set("PJSIP/101-00000024", "CDR(trunk)=") in new stack
-- Executing [s@sub-set-call-vars:11] Set("PJSIP/101-00000024", "CDR(did)=") in new stack
-- Executing [s@sub-set-call-vars:12] Set("PJSIP/101-00000024", "__CALLID=1712152801.98") in new stack
-- Executing [s@sub-set-call-vars:13] NoOp("PJSIP/101-00000024", "Channel Type: PJSIP") in new stack
-- Executing [s@sub-set-call-vars:14] ExecIf("PJSIP/101-00000024", "1?Set(__PJSIP_CALLID=XBk-1ilvnzIYl8RH.4fXVFG5TabeyUYx)") in new stack
-- Executing [s@sub-set-call-vars:15] ExecIf("PJSIP/101-00000024", "0?Set(CHANNEL(language)=)") in new stack
-- Executing [s@sub-set-call-vars:16] ExecIf("PJSIP/101-00000024", "0?Set(CHANNEL(language)=)") in new stack
-- Executing [s@sub-set-call-vars:17] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s@cos-all:5] Gosub("PJSIP/101-00000024", "sub-construct-cid,s,1") in new stack
-- Executing [s@sub-construct-cid:1] NoOp("PJSIP/101-00000024", "Building CID") in new stack
-- Executing [s@sub-construct-cid:2] Set("PJSIP/101-00000024", "ORIGINAL_CID_NAME=Operator 1") in new stack
-- Executing [s@sub-construct-cid:3] Set("PJSIP/101-00000024", "ORIGINAL_CID_NUMBER=101") in new stack
-- Executing [s@sub-construct-cid:4] Set("PJSIP/101-00000024", "_CALL_SOURCE=101") in new stack
-- Executing [s@sub-construct-cid:5] Set("PJSIP/101-00000024", "_CS=101") in new stack
-- Executing [s@sub-construct-cid:6] GotoIf("PJSIP/101-00000024", "0?return") in new stack
-- Executing [s@sub-construct-cid:7] Gosub("PJSIP/101-00000024", "sub-get-device-tree,s,1") in new stack
-- Executing [s@sub-get-device-tree:1] NoOp("PJSIP/101-00000024", "Configuring Device Tree") in new stack
-- Executing [s@sub-get-device-tree:2] Set("PJSIP/101-00000024", "DEV_TECHNOLOGY=PJSIP") in new stack
-- Executing [s@sub-get-device-tree:3] GosubIf("PJSIP/101-00000024", "0?s-local,1") in new stack
-- Executing [s@sub-get-device-tree:4] GotoIf("PJSIP/101-00000024", "0?return") in new stack
-- Executing [s@sub-get-device-tree:5] GotoIf("PJSIP/101-00000024", "1?pjsip") in new stack
-- Goto (sub-get-device-tree,s,9)
-- Executing [s@sub-get-device-tree:9] NoOp("PJSIP/101-00000024", "A PJSIP") in new stack
-- Executing [s@sub-get-device-tree:10] Set("PJSIP/101-00000024", "DEV_USER=101") in new stack
-- Executing [s@sub-get-device-tree:11] Goto("PJSIP/101-00000024", "build_interface") in new stack
-- Goto (sub-get-device-tree,s,13)
-- Executing [s@sub-get-device-tree:13] Set("PJSIP/101-00000024", "_INTERFACE=PJSIP/101") in new stack
-- Executing [s@sub-get-device-tree:14] Set("PJSIP/101-00000024", "DEV_TREE=devices/PJSIP/101") in new stack
-- Executing [s@sub-get-device-tree:15] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s@sub-construct-cid:8] GotoIf("PJSIP/101-00000024", "0?return") in new stack
-- Executing [s@sub-construct-cid:9] Set("PJSIP/101-00000024", "_CALL_SOURCE=101") in new stack
-- Executing [s@sub-construct-cid:10] Set("PJSIP/101-00000024", "_CS=101") in new stack
-- Executing [s@sub-construct-cid:11] Set("PJSIP/101-00000024", "DYNAMIC_EXTERNAL_CID=no") in new stack
-- Executing [s@sub-construct-cid:12] GotoIf("PJSIP/101-00000024", "0?cid_constructed") in new stack
-- Executing [s@sub-construct-cid:13] Set("PJSIP/101-00000024", "EXTENSION_INTERNAL_CID="Operator 1" <101>") in new stack
-- Executing [s@sub-construct-cid:14] ExecIf("PJSIP/101-00000024", "1?Set(CALLERID(all)="Operator 1" <101>)") in new stack
-- Executing [s@sub-construct-cid:15] Set("PJSIP/101-00000024", "_CID_CONSTRUCTED=yes") in new stack
-- Executing [s@sub-construct-cid:16] NoOp("PJSIP/101-00000024", "") in new stack
-- Executing [s@sub-construct-cid:17] Set("PJSIP/101-00000024", "CDR(source)=101") in new stack
-- Executing [s@sub-construct-cid:18] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s@cos-all:6] Gosub("PJSIP/101-00000024", "set-global-tenant-vars,s,1") in new stack
-- Executing [s@set-global-tenant-vars:1] NoOp("PJSIP/101-00000024", "Setting Global Vars for vitalpbx Tenant") in new stack
-- Executing [s@set-global-tenant-vars:2] Set("PJSIP/101-00000024", "__TENANT_PATH=b8a9df1279ec90f6") in new stack
-- Executing [s@set-global-tenant-vars:3] Set("PJSIP/101-00000024", "__TENANT_PREFIX=") in new stack
-- Executing [s@set-global-tenant-vars:4] Set("PJSIP/101-00000024", "__QUEUE_AGENTS_CONTEXT=queue-call-to-agents") in new stack
-- Executing [s@set-global-tenant-vars:5] Set("PJSIP/101-00000024", "__FOLLOWME_CONTEXT=ext-followme") in new stack
-- Executing [s@set-global-tenant-vars:6] Set("PJSIP/101-00000024", "__HINTS_CONTEXT=extension-hints") in new stack
-- Executing [s@set-global-tenant-vars:7] Set("PJSIP/101-00000024", "__DEFAULT_COS=cos-all") in new stack
-- Executing [s@set-global-tenant-vars:8] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s@cos-all:7] GotoIf("PJSIP/101-00000024", "0?local-dialing") in new stack
-- Executing [s@cos-all:8] NoOp("PJSIP/101-00000024", "Check if is an Emergency Call") in new stack
-- Executing [s@cos-all:9] GotoIf("PJSIP/101-00000024", "0?emergency-calls,102,1") in new stack
-- Executing [s@cos-all:10] Gosub("PJSIP/101-00000024", "sub-lockphone-check,s,1") in new stack
-- Executing [s@sub-lockphone-check:1] NoOp("PJSIP/101-00000024", "SUB: LOCK PHONE CHECK") in new stack
-- Executing [s@sub-lockphone-check:2] GotoIf("PJSIP/101-00000024", "0?unlock") in new stack
-- Executing [s@sub-lockphone-check:3] GotoIf("PJSIP/101-00000024", "0?unlock") in new stack
-- Executing [s@sub-lockphone-check:4] GotoIf("PJSIP/101-00000024", "0?unlock") in new stack
-- Executing [s@sub-lockphone-check:5] GotoIf("PJSIP/101-00000024", "0?unlock") in new stack
-- Executing [s@sub-lockphone-check:6] GotoIf("PJSIP/101-00000024", "0?:unlock") in new stack
-- Goto (sub-lockphone-check,s,12)
-- Executing [s@sub-lockphone-check:12] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s@cos-all:11] Gosub("PJSIP/101-00000024", "sub-local-dialing,s,1") in new stack
-- Executing [s@sub-local-dialing:1] NoOp("PJSIP/101-00000024", "Trying to determine if is a local extension") in new stack
-- Executing [s@sub-local-dialing:2] GotoIf("PJSIP/101-00000024", "1?:return") in new stack
-- Executing [s@sub-local-dialing:3] Set("PJSIP/101-00000024", "EXTENSION_TO_DIAL=102") in new stack
-- Executing [s@sub-local-dialing:4] Goto("PJSIP/101-00000024", "102,1") in new stack
-- Goto (sub-local-dialing,102,1)
-- Executing [102@sub-local-dialing:1] NoOp("PJSIP/101-00000024", "Dialing Local Extension") in new stack
-- Executing [102@sub-local-dialing:2] ExecIf("PJSIP/101-00000024", "0?NoCDR()") in new stack
-- Executing [102@sub-local-dialing:3] Gosub("PJSIP/101-00000024", "sub-setup-call-type,s,1(internal)") in new stack
-- Executing [s@sub-setup-call-type:1] NoOp("PJSIP/101-00000024", "Determinating Call Type") in new stack
-- Executing [s@sub-setup-call-type:2] GotoIf("PJSIP/101-00000024", "0?return") in new stack
-- Executing [s@sub-setup-call-type:3] Gosub("PJSIP/101-00000024", "s-internal,1()") in new stack
-- Executing [s-internal@sub-setup-call-type:1] NoOp("PJSIP/101-00000024", "Internal Call") in new stack
-- Executing [s-internal@sub-setup-call-type:2] Set("PJSIP/101-00000024", "__CALL_TYPE=1") in new stack
-- Executing [s-internal@sub-setup-call-type:3] Set("PJSIP/101-00000024", "__CALL_TYPE_LABEL=LOCAL") in new stack
-- Executing [s-internal@sub-setup-call-type:4] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s@sub-setup-call-type:4] Set("PJSIP/101-00000024", "__CALL_TYPE_CONFIGURED=yes") in new stack
-- Executing [s@sub-setup-call-type:5] Set("PJSIP/101-00000024", "CDR(calltype)=1") in new stack
-- Executing [s@sub-setup-call-type:6] Return("PJSIP/101-00000024", "") in new stack
-- Executing [102@sub-local-dialing:4] Set("PJSIP/101-00000024", "_DESTINATION_STATE=NOT_INUSE") in new stack
-- Executing [102@sub-local-dialing:5] Set("PJSIP/101-00000024", "_DESTINATION_NUMBER=102") in new stack
-- Executing [102@sub-local-dialing:6] Set("PJSIP/101-00000024", "_DESTINATION_COS_NAME=cos-all") in new stack
-- Executing [102@sub-local-dialing:7] Gosub("PJSIP/101-00000024", "sub-check-cos-privacy,s,1(cos-all,1,cos-all)") in new stack
-- Executing [s@sub-check-cos-privacy:1] NoOp("PJSIP/101-00000024", "Checking Privacy of CoS: cos-all") in new stack
-- Executing [s@sub-check-cos-privacy:2] GotoIf("PJSIP/101-00000024", "1?:no_local") in new stack
-- Executing [s@sub-check-cos-privacy:3] Set("PJSIP/101-00000024", "CALLER_COS_ID=1") in new stack
-- Executing [s@sub-check-cos-privacy:4] Set("PJSIP/101-00000024", "CALLER_COS_NAME=cos-all") in new stack
-- Executing [s@sub-check-cos-privacy:5] Set("PJSIP/101-00000024", "CALLED_COS_ID=1") in new stack
-- Executing [s@sub-check-cos-privacy:6] GotoIf("PJSIP/101-00000024", "1?same_cos") in new stack
-- Goto (sub-check-cos-privacy,s,17)
-- Executing [s@sub-check-cos-privacy:17] NoOp("PJSIP/101-00000024", "The caller CoS and Callee CoS are the same") in new stack
-- Executing [s@sub-check-cos-privacy:18] Goto("PJSIP/101-00000024", "return") in new stack
-- Goto (sub-check-cos-privacy,s,22)
-- Executing [s@sub-check-cos-privacy:22] Return("PJSIP/101-00000024", "") in new stack
-- Executing [102@sub-local-dialing:8] Set("PJSIP/101-00000024", "DIAL_STRING=PJSIP/102") in new stack
-- Executing [102@sub-local-dialing:9] Set("PJSIP/101-00000024", "DB(b8a9df1279ec90f6/LASTCALLER/102)=101") in new stack
-- Executing [102@sub-local-dialing:10] Set("PJSIP/101-00000024", "IGNORE_DIVERSIONS=") in new stack
-- Executing [102@sub-local-dialing:11] Set("PJSIP/101-00000024", "QUEUE_CALL=") in new stack
-- Executing [102@sub-local-dialing:12] GosubIf("PJSIP/101-00000024", "0?sub-queue-members,s,1") in new stack
-- Executing [102@sub-local-dialing:13] GosubIf("PJSIP/101-00000024", "0?:sub-check-diversions,s,1(predialing)") in new stack
-- Executing [s@sub-check-diversions:1] NoOp("PJSIP/101-00000024", "Checking diversions for extension 102") in new stack
-- Executing [s@sub-check-diversions:2] Set("PJSIP/101-00000024", "OLD_CALLER_ID="Operator 1" <101>") in new stack
-- Executing [s@sub-check-diversions:3] GosubIf("PJSIP/101-00000024", "0?:return") in new stack
-- Executing [s@sub-check-diversions:12] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s@sub-check-diversions:4] Set("PJSIP/101-00000024", "EXTENSION_INTERNAL_CID="Operator 2" <102>") in new stack
-- Executing [s@sub-check-diversions:5] Set("PJSIP/101-00000024", "CID_ON_DIVERSIONS=callee") in new stack
-- Executing [s@sub-check-diversions:6] ExecIf("PJSIP/101-00000024", "1?Set(CALLERID(all)="Operator 2" <102>)") in new stack
-- Executing [s@sub-check-diversions:7] ExecIf("PJSIP/101-00000024", "0?Set(__EXT_CID_CONSTRUCTED=yes)") in new stack
-- Executing [s@sub-check-diversions:8] Set("PJSIP/101-00000024", "__CALL_ORIGIN=diversions") in new stack
-- Executing [s@sub-check-diversions:9] Set("PJSIP/101-00000024", "__DIVERSION_ORIGIN=102") in new stack
-- Executing [s@sub-check-diversions:10] Gosub("PJSIP/101-00000024", "s-predialing,1") in new stack
-- Executing [s-predialing@sub-check-diversions:1] NoOp("PJSIP/101-00000024", "Diversions on pre-dialing") in new stack
-- Executing [s-predialing@sub-check-diversions:2] GosubIf("PJSIP/101-00000024", "0?:diversion-testing,1(Do not Disturb,DND,,,dnd)") in new stack
-- Executing [diversion-testing@sub-check-diversions:1] NoOp("PJSIP/101-00000024", "") in new stack
-- Executing [diversion-testing@sub-check-diversions:2] Set("PJSIP/101-00000024", "FROM_NUMBER=102") in new stack
-- Executing [diversion-testing@sub-check-diversions:3] GotoIf("PJSIP/101-00000024", "0?:next-diversion") in new stack
-- Goto (sub-check-diversions,diversion-testing,36)
-- Executing [diversion-testing@sub-check-diversions:36] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s-predialing@sub-check-diversions:3] Gosub("PJSIP/101-00000024", "diversion-testing,1(Call Forward Immeditely,CFI,,,toggle_cfi)") in new stack
-- Executing [diversion-testing@sub-check-diversions:1] NoOp("PJSIP/101-00000024", "") in new stack
-- Executing [diversion-testing@sub-check-diversions:2] Set("PJSIP/101-00000024", "FROM_NUMBER=102") in new stack
-- Executing [diversion-testing@sub-check-diversions:3] GotoIf("PJSIP/101-00000024", "0?:next-diversion") in new stack
-- Goto (sub-check-diversions,diversion-testing,36)
-- Executing [diversion-testing@sub-check-diversions:36] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s-predialing@sub-check-diversions:4] GosubIf("PJSIP/101-00000024", "0?:diversion-testing,1(Boss Secretray,BOSS,,,boss_secretary)") in new stack
-- Executing [diversion-testing@sub-check-diversions:1] NoOp("PJSIP/101-00000024", "") in new stack
-- Executing [diversion-testing@sub-check-diversions:2] Set("PJSIP/101-00000024", "FROM_NUMBER=102") in new stack
-- Executing [diversion-testing@sub-check-diversions:3] GotoIf("PJSIP/101-00000024", "0?:next-diversion") in new stack
-- Goto (sub-check-diversions,diversion-testing,36)
-- Executing [diversion-testing@sub-check-diversions:36] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s-predialing@sub-check-diversions:5] GosubIf("PJSIP/101-00000024", "0?:diversion-testing,1(Personal Assistant,PEA,sub-personal-assistant,s,1)") in new stack
-- Executing [diversion-testing@sub-check-diversions:1] NoOp("PJSIP/101-00000024", "") in new stack
-- Executing [diversion-testing@sub-check-diversions:2] Set("PJSIP/101-00000024", "FROM_NUMBER=102") in new stack
-- Executing [diversion-testing@sub-check-diversions:3] GotoIf("PJSIP/101-00000024", "0?:next-diversion") in new stack
-- Goto (sub-check-diversions,diversion-testing,36)
-- Executing [diversion-testing@sub-check-diversions:36] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s-predialing@sub-check-diversions:6] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s@sub-check-diversions:11] Set("PJSIP/101-00000024", "CALLERID(all)="Operator 1" <101>") in new stack
-- Executing [s@sub-check-diversions:12] Return("PJSIP/101-00000024", "") in new stack
-- Executing [102@sub-local-dialing:14] GotoIf("PJSIP/101-00000024", "0?post-dial") in new stack
-- Executing [102@sub-local-dialing:15] Gosub("PJSIP/101-00000024", "sub-crm-setup,s,1(102,101)") in new stack
-- Executing [s@sub-crm-setup:1] NoOp("PJSIP/101-00000024", "Init CRM Setup...") in new stack
-- Executing [s@sub-crm-setup:2] GotoIf("PJSIP/101-00000024", "0?:after-crm") in new stack
-- Goto (sub-crm-setup,s,12)
-- Executing [s@sub-crm-setup:12] NoOp("PJSIP/101-00000024", "CRM Processed...") in new stack
-- Executing [s@sub-crm-setup:13] Return("PJSIP/101-00000024", "") in new stack
-- Executing [102@sub-local-dialing:16] Gosub("PJSIP/101-00000024", "parse-dial-string,s,1") in new stack
-- Executing [s@parse-dial-string:1] NoOp("PJSIP/101-00000024", "Parsing dial string: PJSIP/102") in new stack
-- Executing [s@parse-dial-string:2] Set("PJSIP/101-00000024", "NEW_DIAL_STRING=") in new stack
-- Executing [s@parse-dial-string:3] Set("PJSIP/101-00000024", "COUNTER=1") in new stack
-- Executing [s@parse-dial-string:4] Set("PJSIP/101-00000024", "CURRENT_DEVICE=PJSIP/102") in new stack
-- Executing [s@parse-dial-string:5] While("PJSIP/101-00000024", "1") in new stack
-- Executing [s@parse-dial-string:6] Set("PJSIP/101-00000024", "TECHNOLOGY=PJSIP") in new stack
-- Executing [s@parse-dial-string:7] Set("PJSIP/101-00000024", "USER=102") in new stack
-- Executing [s@parse-dial-string:8] GotoIf("PJSIP/101-00000024", "1?:regular") in new stack
-- Executing [s@parse-dial-string:9] Goto("PJSIP/101-00000024", "regular-pjsip") in new stack
-- Goto (parse-dial-string,s,12)
-- Executing [s@parse-dial-string:12] Set("PJSIP/101-00000024", "PARSED_DEV=PJSIP/102/sip:102@80.25.26.27:3718;ob") in new stack
-- Executing [s@parse-dial-string:13] ExecIf("PJSIP/101-00000024", "0?Set(PARSED_DEV=PJSIP/102)") in new stack
-- Executing [s@parse-dial-string:14] Goto("PJSIP/101-00000024", "append") in new stack
-- Goto (parse-dial-string,s,16)
-- Executing [s@parse-dial-string:16] GotoIf("PJSIP/101-00000024", "1?:concatenate") in new stack
-- Executing [s@parse-dial-string:17] Set("PJSIP/101-00000024", "NEW_DIAL_STRING=PJSIP/102/sip:102@80.25.26.27:3718;ob") in new stack
-- Executing [s@parse-dial-string:18] Goto("PJSIP/101-00000024", "next") in new stack
-- Goto (parse-dial-string,s,21)
-- Executing [s@parse-dial-string:21] Set("PJSIP/101-00000024", "COUNTER=2") in new stack
-- Executing [s@parse-dial-string:22] Set("PJSIP/101-00000024", "CURRENT_DEVICE=") in new stack
-- Executing [s@parse-dial-string:23] EndWhile("PJSIP/101-00000024", "") in new stack
-- Executing [s@parse-dial-string:5] While("PJSIP/101-00000024", "0") in new stack
-- Executing [s@parse-dial-string:24] NoOp("PJSIP/101-00000024", "New dialstring: PJSIP/102/sip:102@80.25.26.27:3718;ob") in new stack
-- Executing [s@parse-dial-string:25] Set("PJSIP/101-00000024", "DIAL_STRING=PJSIP/102/sip:102@80.25.26.27:3718;ob") in new stack
-- Executing [s@parse-dial-string:26] Return("PJSIP/101-00000024", "") in new stack
-- Executing [102@sub-local-dialing:17] NoOp("PJSIP/101-00000024", "Configuring Dial Settings") in new stack
-- Executing [102@sub-local-dialing:18] Set("PJSIP/101-00000024", "RING_TIME=30") in new stack
-- Executing [102@sub-local-dialing:19] Set("PJSIP/101-00000024", "RING_TIME=30") in new stack
-- Executing [102@sub-local-dialing:20] Set("PJSIP/101-00000024", "RING_TIME=30") in new stack
-- Executing [102@sub-local-dialing:21] Set("PJSIP/101-00000024", "RING_TIME=30") in new stack
-- Executing [102@sub-local-dialing:22] Set("PJSIP/101-00000024", "DIAL_OPTIONS=kKtr") in new stack
-- Executing [102@sub-local-dialing:23] Set("PJSIP/101-00000024", "DIAL_OPTIONS=kKtr") in new stack
-- Executing [102@sub-local-dialing:24] GotoIf("PJSIP/101-00000024", "0?:answermode") in new stack
-- Goto (sub-local-dialing,102,28)
-- Executing [102@sub-local-dialing:28] Set("PJSIP/101-00000024", "ANSWER_MODE=disable") in new stack
-- Executing [102@sub-local-dialing:29] Set("PJSIP/101-00000024", "ANSWER_MODE=disable") in new stack
-- Executing [102@sub-local-dialing:30] GotoIf("PJSIP/101-00000024", "0?check-hd") in new stack
-- Executing [102@sub-local-dialing:31] ExecIf("PJSIP/101-00000024", "0?Set(EXEC_AA=yes)") in new stack
-- Executing [102@sub-local-dialing:32] ExecIf("PJSIP/101-00000024", "0?Set(EXEC_AA=yes)") in new stack
-- Executing [102@sub-local-dialing:33] Set("PJSIP/101-00000024", "IS_HOT_DESK=no") in new stack
-- Executing [102@sub-local-dialing:34] Set("PJSIP/101-00000024", "MOH_CLASS=default") in new stack
-- Executing [102@sub-local-dialing:35] GosubIf("PJSIP/101-00000024", "0?sub-set-moh,s,1(default):moh,1") in new stack
-- Executing [moh@sub-local-dialing:1] NoOp("PJSIP/101-00000024", "Set Music on Hold") in new stack
-- Executing [moh@sub-local-dialing:2] GotoIf("PJSIP/101-00000024", "0?:return") in new stack
-- Goto (sub-local-dialing,moh,4)
-- Executing [moh@sub-local-dialing:4] Return("PJSIP/101-00000024", "") in new stack
-- Executing [102@sub-local-dialing:36] GotoIf("PJSIP/101-00000024", "0?busy-here") in new stack
-- Executing [102@sub-local-dialing:37] Set("PJSIP/101-00000024", "FORCE_RECORDING=no") in new stack
-- Executing [102@sub-local-dialing:38] Set("PJSIP/101-00000024", "DIAL_OPTIONS=kKtr") in new stack
-- Executing [102@sub-local-dialing:39] Set("PJSIP/101-00000024", "DIAL_OPTIONS=kKtrb(sub-before-connecting-call^s^1(b8a9df1279ec90f6^101^102^^))") in new stack
-- Executing [102@sub-local-dialing:40] Set("PJSIP/101-00000024", "DIAL_OPTIONS=kKtrb(sub-before-connecting-call^s^1(b8a9df1279ec90f6^101^102^^))U(sub-before-bridging-call^b8a9df1279ec90f6^101^102^no)") in new stack
-- Executing [102@sub-local-dialing:41] Set("PJSIP/101-00000024", "__TRANSFERED_CALL=TRUE") in new stack
-- Executing [102@sub-local-dialing:42] NoOp("PJSIP/101-00000024", "Enable Follow-Me Ring Time If needed") in new stack
-- Executing [102@sub-local-dialing:43] GotoIf("PJSIP/101-00000024", "0?:check-rt") in new stack
-- Goto (sub-local-dialing,102,50)
-- Executing [102@sub-local-dialing:50] GotoIf("PJSIP/101-00000024", "0?post-dial") in new stack
-- Executing [102@sub-local-dialing:51] GotoIf("PJSIP/101-00000024", "0?end_call") in new stack
-- Executing [102@sub-local-dialing:52] Gosub("PJSIP/101-00000024", "sub-udpate-virtual-dev-state,s,1(102,BUSY)") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:1] NoOp("PJSIP/101-00000024", "Update Virtual Devices State") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:2] Set("PJSIP/101-00000024", "EXT_NUM=102") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:3] Set("PJSIP/101-00000024", "NEW_STATUS=BUSY") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:4] Set("PJSIP/101-00000024", "EXTENSION_ID=5") in new stack
> Found no rows [SELECT `device_id` FROM ombu_devices WHERE extension_id='5' and technology='virtual' and tenant_id=(SELECT tenant_id from ombu_tenants WHERE path='b8a9df1279ec90f6')]
-- Executing [s@sub-udpate-virtual-dev-state:5] Set("PJSIP/101-00000024", "ODBC_ID=") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:6] GotoIf("PJSIP/101-00000024", "1?no_rows") in new stack
-- Goto (sub-udpate-virtual-dev-state,s,14)
-- Executing [s@sub-udpate-virtual-dev-state:14] Return("PJSIP/101-00000024", "") in new stack
-- Executing [102@sub-local-dialing:53] Gosub("PJSIP/101-00000024", "sub-setup-callrec-name,s,1") in new stack
-- Executing [s@sub-setup-callrec-name:1] NoOp("PJSIP/101-00000024", "Setting up call recording file name for b8a9df1279ec90f6") in new stack
-- Executing [s@sub-setup-callrec-name:2] GotoIf("PJSIP/101-00000024", "0?end") in new stack
-- Executing [s@sub-setup-callrec-name:3] Set("PJSIP/101-00000024", "RECORDING_ID=1712152801.98") in new stack
-- Executing [s@sub-setup-callrec-name:4] Set("PJSIP/101-00000024", "APP_TYPE=NONE") in new stack
-- Executing [s@sub-setup-callrec-name:5] Set("PJSIP/101-00000024", "AUDIO_FILE=170001-LOCAL-NONE-101-102-1712152801.98") in new stack
-- Executing [s@sub-setup-callrec-name:6] Set("PJSIP/101-00000024", "RECORDING_FORMAT=wav") in new stack
-- Executing [s@sub-setup-callrec-name:7] Set("PJSIP/101-00000024", "__REC_FILENAME=/var/spool/asterisk/monitor/2024/04/03/170001-LOCAL-NONE-101-102-1712152801.98.wav") in new stack
-- Executing [s@sub-setup-callrec-name:8] GotoIf("PJSIP/101-00000024", "0?:end") in new stack
-- Goto (sub-setup-callrec-name,s,11)
-- Executing [s@sub-setup-callrec-name:11] Return("PJSIP/101-00000024", "") in new stack
-- Executing [102@sub-local-dialing:54] Dial("PJSIP/101-00000024", "PJSIP/102/sip:102@80.25.26.27:3718;ob,30,kKtrb(sub-before-connecting-call^s^1(b8a9df1279ec90f6^101^102^^))U(sub-before-bridging-call^b8a9df1279ec90f6^101^102^no)") in new stack
[2024-04-03 17:00:01] ERROR[18712]: res_pjsip.c:1063 ast_sip_create_dialog_uac: Could not create dialog to endpoint '102' as outbound proxy URI 'mypbx.mydomain.com' is not valid
[2024-04-03 17:00:01] ERROR[18712]: chan_pjsip.c:2698 request: Failed to create outgoing session to endpoint '102'
[2024-04-03 17:00:01] NOTICE[111692][C-0000002b]: app_dial.c:2750 dial_exec_full: Unable to create channel of type 'PJSIP' (cause 3 - No route to destination)
== Everyone is busy/congested at this time (1:0/0/1)
-- Executing [102@sub-local-dialing:55] Set("PJSIP/101-00000024", "_CALL_STATUS=CHANUNAVAIL") in new stack
-- Executing [102@sub-local-dialing:56] Set("PJSIP/101-00000024", "_CALL_STATUS=CHANUNAVAIL") in new stack
-- Executing [102@sub-local-dialing:57] Gosub("PJSIP/101-00000024", "sub-udpate-virtual-dev-state,s,1(102,NOT_INUSE)") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:1] NoOp("PJSIP/101-00000024", "Update Virtual Devices State") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:2] Set("PJSIP/101-00000024", "EXT_NUM=102") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:3] Set("PJSIP/101-00000024", "NEW_STATUS=NOT_INUSE") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:4] Set("PJSIP/101-00000024", "EXTENSION_ID=5") in new stack
> Found no rows [SELECT `device_id` FROM ombu_devices WHERE extension_id='5' and technology='virtual' and tenant_id=(SELECT tenant_id from ombu_tenants WHERE path='b8a9df1279ec90f6')]
-- Executing [s@sub-udpate-virtual-dev-state:5] Set("PJSIP/101-00000024", "ODBC_ID=") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:6] GotoIf("PJSIP/101-00000024", "1?no_rows") in new stack
-- Goto (sub-udpate-virtual-dev-state,s,14)
-- Executing [s@sub-udpate-virtual-dev-state:14] Return("PJSIP/101-00000024", "") in new stack
-- Executing [102@sub-local-dialing:58] Goto("PJSIP/101-00000024", "check-post-diversions") in new stack
-- Goto (sub-local-dialing,102,60)
-- Executing [102@sub-local-dialing:60] GosubIf("PJSIP/101-00000024", "0?:sub-check-diversions,s,1(postdialing)") in new stack
-- Executing [s@sub-check-diversions:1] NoOp("PJSIP/101-00000024", "Checking diversions for extension 102") in new stack
-- Executing [s@sub-check-diversions:2] Set("PJSIP/101-00000024", "OLD_CALLER_ID="Operator 1" <101>") in new stack
-- Executing [s@sub-check-diversions:3] GosubIf("PJSIP/101-00000024", "0?:return") in new stack
-- Executing [s@sub-check-diversions:12] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s@sub-check-diversions:4] Set("PJSIP/101-00000024", "EXTENSION_INTERNAL_CID="Operator 2" <102>") in new stack
-- Executing [s@sub-check-diversions:5] Set("PJSIP/101-00000024", "CID_ON_DIVERSIONS=callee") in new stack
-- Executing [s@sub-check-diversions:6] ExecIf("PJSIP/101-00000024", "1?Set(CALLERID(all)="Operator 2" <102>)") in new stack
-- Executing [s@sub-check-diversions:7] ExecIf("PJSIP/101-00000024", "0?Set(__EXT_CID_CONSTRUCTED=yes)") in new stack
-- Executing [s@sub-check-diversions:8] Set("PJSIP/101-00000024", "__CALL_ORIGIN=diversions") in new stack
-- Executing [s@sub-check-diversions:9] Set("PJSIP/101-00000024", "__DIVERSION_ORIGIN=102") in new stack
-- Executing [s@sub-check-diversions:10] Gosub("PJSIP/101-00000024", "s-postdialing,1") in new stack
-- Executing [s-postdialing@sub-check-diversions:1] NoOp("PJSIP/101-00000024", "Diversions on post-dialing") in new stack
-- Executing [s-postdialing@sub-check-diversions:2] Gosub("PJSIP/101-00000024", "diversion-testing,1(Follow Me,FWM,ext-followme,FW102,follow_me)") in new stack
-- Executing [diversion-testing@sub-check-diversions:1] NoOp("PJSIP/101-00000024", "") in new stack
-- Executing [diversion-testing@sub-check-diversions:2] Set("PJSIP/101-00000024", "FROM_NUMBER=102") in new stack
-- Executing [diversion-testing@sub-check-diversions:3] GotoIf("PJSIP/101-00000024", "0?:next-diversion") in new stack
-- Goto (sub-check-diversions,diversion-testing,36)
-- Executing [diversion-testing@sub-check-diversions:36] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s-postdialing@sub-check-diversions:3] GosubIf("PJSIP/101-00000024", "1?call-forward,1(CFU,toggle_cfu)") in new stack
-- Executing [call-forward@sub-check-diversions:1] NoOp("PJSIP/101-00000024", "CALL FOWARDING") in new stack
-- Executing [call-forward@sub-check-diversions:2] Set("PJSIP/101-00000024", "FROM_NUMBER=102") in new stack
-- Executing [call-forward@sub-check-diversions:3] GotoIf("PJSIP/101-00000024", "0?:return") in new stack
-- Goto (sub-check-diversions,call-forward,14)
-- Executing [call-forward@sub-check-diversions:14] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s-postdialing@sub-check-diversions:4] GosubIf("PJSIP/101-00000024", "0?call-forward,1(CFU,toggle_cfu)") in new stack
-- Executing [s-postdialing@sub-check-diversions:5] GosubIf("PJSIP/101-00000024", "0?call-forward,1(CFB,toggle_cfb)") in new stack
-- Executing [s-postdialing@sub-check-diversions:6] GosubIf("PJSIP/101-00000024", "0?call-forward,1(CFN,toggle_cfn)") in new stack
-- Executing [s-postdialing@sub-check-diversions:7] Return("PJSIP/101-00000024", "") in new stack
-- Executing [s@sub-check-diversions:11] Set("PJSIP/101-00000024", "CALLERID(all)="Operator 1" <101>") in new stack
-- Executing [s@sub-check-diversions:12] Return("PJSIP/101-00000024", "") in new stack
-- Executing [102@sub-local-dialing:61] Gosub("PJSIP/101-00000024", "sub-notify-missed-call,s,1(b8a9df1279ec90f6,102,CHANUNAVAIL,101,Operator 1,1,,)") in new stack
-- Executing [s@sub-notify-missed-call:1] NoOp("PJSIP/101-00000024", "Check if call is missed and need to be notified") in new stack
-- Executing [s@sub-notify-missed-call:2] Set("PJSIP/101-00000024", "TENANT=b8a9df1279ec90f6") in new stack
-- Executing [s@sub-notify-missed-call:3] Set("PJSIP/101-00000024", "CALLEE_NUM=102") in new stack
-- Executing [s@sub-notify-missed-call:4] Set("PJSIP/101-00000024", "STATUS=CHANUNAVAIL") in new stack
-- Executing [s@sub-notify-missed-call:5] Set("PJSIP/101-00000024", "CALLER_NUM=101") in new stack
-- Executing [s@sub-notify-missed-call:6] Set("PJSIP/101-00000024", "CALLER_NAME=Operator 1") in new stack
-- Executing [s@sub-notify-missed-call:7] Set("PJSIP/101-00000024", "CALL_DIR=1") in new stack
-- Executing [s@sub-notify-missed-call:8] Set("PJSIP/101-00000024", "DP_APP=") in new stack
-- Executing [s@sub-notify-missed-call:9] Set("PJSIP/101-00000024", "DP_APP_DESC=") in new stack
-- Executing [s@sub-notify-missed-call:10] Set("PJSIP/101-00000024", "IS_VM_ENABLED=no") in new stack
-- Executing [s@sub-notify-missed-call:11] GotoIf("PJSIP/101-00000024", "1?notify_missed_call") in new stack
-- Goto (sub-notify-missed-call,s,13)
-- Executing [s@sub-notify-missed-call:13] NoOp("PJSIP/101-00000024", "Trying to notify missed call") in new stack
-- Executing [s@sub-notify-missed-call:14] System("PJSIP/101-00000024", "/usr/share/vitalpbx/scripts/vitalpbx "notifyMissedCall" "b8a9df1279ec90f6|1|102|101|Operator 1|CHANUNAVAIL||" > /dev/null 2>&1 &") in new stack
-- Executing [s@sub-notify-missed-call:15] NoOp("PJSIP/101-00000024", "Finished missed call notification test") in new stack
-- Executing [s@sub-notify-missed-call:16] Return("PJSIP/101-00000024", "") in new stack
-- Executing [102@sub-local-dialing:62] Set("PJSIP/101-00000024", "MISSED_CALL_NOTIFIED=TRUE") in new stack
-- Executing [102@sub-local-dialing:63] GotoIf("PJSIP/101-00000024", "0?end_call") in new stack
-- Executing [102@sub-local-dialing:64] GotoIf("PJSIP/101-00000024", "0?end_call") in new stack
-- Executing [102@sub-local-dialing:65] GotoIf("PJSIP/101-00000024", "0?end_call") in new stack
-- Executing [102@sub-local-dialing:66] Gosub("PJSIP/101-00000024", "sub-leave-vm,s,1") in new stack
-- Executing [s@sub-leave-vm:1] NoOp("PJSIP/101-00000024", "Leave Voicemail") in new stack
-- Executing [s@sub-leave-vm:2] Set("PJSIP/101-00000024", "VM_OPTIONS=u") in new stack
-- Executing [s@sub-leave-vm:3] Set("PJSIP/101-00000024", "VM_OPTIONS=u") in new stack
-- Executing [s@sub-leave-vm:4] GotoIf("PJSIP/101-00000024", "0?:vm_disabled") in new stack
-- Goto (sub-leave-vm,s,8)
-- Executing [s@sub-leave-vm:8] Return("PJSIP/101-00000024", "") in new stack
-- Executing [102@sub-local-dialing:67] GosubIf("PJSIP/101-00000024", "1?sub-request-cc,s,1") in new stack
-- Executing [s@sub-request-cc:1] NoOp("PJSIP/101-00000024", "Trying to request call completion") in new stack
-- Executing [s@sub-request-cc:2] GotoIf("PJSIP/101-00000024", "1?:return") in new stack
-- Executing [s@sub-request-cc:3] GotoIf("PJSIP/101-00000024", "0?:return") in new stack
-- Goto (sub-request-cc,s,11)
-- Executing [s@sub-request-cc:11] Return("PJSIP/101-00000024", "") in new stack
-- Executing [102@sub-local-dialing:68] ExecIf("PJSIP/101-00000024", "0?Busy(5)") in new stack
-- Executing [102@sub-local-dialing:69] Hangup("PJSIP/101-00000024", "") in new stack
== Spawn extension (sub-local-dialing, 102, 69) exited non-zero on 'PJSIP/101-00000024'
-- Executing [h@sub-local-dialing:1] NoOp("PJSIP/101-00000024", "Finishing the call") in new stack
-- Executing [h@sub-local-dialing:2] System("PJSIP/101-00000024", "test -e /var/spool/asterisk/monitor/2024/04/03/170001-LOCAL-NONE-101-102-1712152801.98.wav") in new stack
-- Executing [h@sub-local-dialing:3] ExecIf("PJSIP/101-00000024", "0?Set(CDR(recfile)=/var/spool/asterisk/monitor/2024/04/03/170001-LOCAL-NONE-101-102-1712152801.98.wav)") in new stack
-- Executing [h@sub-local-dialing:4] Gosub("PJSIP/101-00000024", "sub-udpate-virtual-dev-state,s,1(102,NOT_INUSE)") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:1] NoOp("PJSIP/101-00000024", "Update Virtual Devices State") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:2] Set("PJSIP/101-00000024", "EXT_NUM=102") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:3] Set("PJSIP/101-00000024", "NEW_STATUS=NOT_INUSE") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:4] Set("PJSIP/101-00000024", "EXTENSION_ID=5") in new stack
> Found no rows [SELECT `device_id` FROM ombu_devices WHERE extension_id='5' and technology='virtual' and tenant_id=(SELECT tenant_id from ombu_tenants WHERE path='b8a9df1279ec90f6')]
-- Executing [s@sub-udpate-virtual-dev-state:5] Set("PJSIP/101-00000024", "ODBC_ID=") in new stack
-- Executing [s@sub-udpate-virtual-dev-state:6] GotoIf("PJSIP/101-00000024", "1?no_rows") in new stack
-- Goto (sub-udpate-virtual-dev-state,s,14)
-- Executing [s@sub-udpate-virtual-dev-state:14] Return("PJSIP/101-00000024", "") in new stack
-- Executing [h@sub-local-dialing:5] GosubIf("PJSIP/101-00000024", "0?dynamic-routing-connected,s,1(CHANUNAVAIL,101)") in new stack
-- Executing [h@sub-local-dialing:6] GotoIf("PJSIP/101-00000024", "1?check-if-qc") in new stack
-- Goto (sub-local-dialing,h,8)
-- Executing [h@sub-local-dialing:8] GotoIf("PJSIP/101-00000024", "0?call_ended") in new stack
-- Executing [h@sub-local-dialing:9] Gosub("PJSIP/101-00000024", "sub-log-crm-call,s,1") in new stack
-- Executing [s@sub-log-crm-call:1] NoOp("PJSIP/101-00000024", "Logging Call on CRM") in new stack
-- Executing [s@sub-log-crm-call:2] Set("PJSIP/101-00000024", "EXTERN_NUM=101") in new stack
-- Executing [s@sub-log-crm-call:3] GotoIf("PJSIP/101-00000024", "0?:end") in new stack
-- Goto (sub-log-crm-call,s,6)
-- Executing [s@sub-log-crm-call:6] Return("PJSIP/101-00000024", "") in new stack
-- Executing [h@sub-local-dialing:10] GosubIf("PJSIP/101-00000024", "0?wake-up-no-answered,s,1") in new stack
-- Executing [h@sub-local-dialing:11] Gosub("PJSIP/101-00000024", "sub-call-completion-check,s,1") in new stack
-- Executing [s@sub-call-completion-check:1] NoOp("PJSIP/101-00000024", "FEATURE: CALL COMPLETION CHECK") in new stack
-- Executing [s@sub-call-completion-check:2] Set("PJSIP/101-00000024", "CC_MODE=none") in new stack
-- Executing [s@sub-call-completion-check:3] Set("PJSIP/101-00000024", "CC_MODE=none") in new stack
-- Executing [s@sub-call-completion-check:4] Set("PJSIP/101-00000024", "CC_MODE=none") in new stack
-- Executing [s@sub-call-completion-check:5] GotoIf("PJSIP/101-00000024", "1?end") in new stack
-- Goto (sub-call-completion-check,s,17)
-- Executing [s@sub-call-completion-check:17] Return("PJSIP/101-00000024", "") in new stack
-- Executing [h@sub-local-dialing:12] NoOp("PJSIP/101-00000024", "Call ended") in new stack
-- Executing [h@sub-local-dialing:13] Gosub("PJSIP/101-00000024", "notify-call-hangup,s,1") in new stack
-- Executing [s@notify-call-hangup:1] Verbose("PJSIP/101-00000024", "0, Extension Hangup") in new stack
Extension Hangup
-- Executing [s@notify-call-hangup:2] GotoIf("PJSIP/101-00000024", "1?:incoming") in new stack
-- Executing [s@notify-call-hangup:3] UserEvent("PJSIP/101-00000024", ""EXTENSIONS_SUMMARY","Data: EXT_TO_EXT,PJSIP/101-0000001v,,LOCAL"") in new stack
-- Executing [s@notify-call-hangup:4] Goto("PJSIP/101-00000024", "end") in new stack
-- Goto (notify-call-hangup,s,7)
-- Executing [s@notify-call-hangup:7] Return("PJSIP/101-00000024", "") in new stack
-- Executing [h@sub-local-dialing:14] Hangup("PJSIP/101-00000024", "") in new stack
== Spawn extension (sub-local-dialing, h, 14) exited non-zero on 'PJSIP/101-00000024'
<--- Transmitting SIP response (421 bytes) to UDP:80.25.26.27:3718 --->
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 80.25.26.27:3718;rport=3718;received=80.25.26.27;branch=z9hG4bKPjLvpliZtE2k0ckjIWat1tNXb-hbNm.GLC
Call-ID: XBk-1ilvnzIYl8RH.4fXVFG5TabeyUYx
From: "demo vital" <sip:101@mypbx.mydomain.com>;tag=mwDPvNZ7wtUtv.oNBbBMPSjO4KaiaG8.
To: <sip:102@mypbx.mydomain.com>;tag=58dac905-b73a-4877-809e-8b41fb5b0999
CSeq: 9873 INVITE
Server: VitalPBX
Reason: Q.850;cause=3
Content-Length: 0
<--- Received SIP request (384 bytes) from UDP:80.25.26.27:3718 --->
ACK sip:102@mypbx.mydomain.com SIP/2.0
Via: SIP/2.0/UDP 80.25.26.27:3718;rport;branch=z9hG4bKPjLvpliZtE2k0ckjIWat1tNXb-hbNm.GLC
Max-Forwards: 70
From: "demo vital" <sip:101@mypbx.mydomain.com>;tag=mwDPvNZ7wtUtv.oNBbBMPSjO4KaiaG8.
To: sip:102@mypbx.mydomain.com;tag=58dac905-b73a-4877-809e-8b41fb5b0999
Call-ID: XBk-1ilvnzIYl8RH.4fXVFG5TabeyUYx
CSeq: 9873 ACK
Content-Length: 0
mypbx*CLI> c
Disconnected from Asterisk server
Asterisk cleanly ending (0).
Executing last minute cleanups
root@mypbx:~# io a
-bash: io: command not found
root@mypbx:~# ip a
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1000
link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
inet 127.0.0.1/8 scope host lo
valid_lft forever preferred_lft forever
inet6 ::1/128 scope host
valid_lft forever preferred_lft forever
2: ens192: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP group default qlen 1000
link/ether 00:50:56:84:48:16 brd ff:ff:ff:ff:ff:ff
altname enp11s0
inet 123.123.123.123/25 brd 80.97.52.255 scope global ens192
valid_lft forever preferred_lft forever
inet6 fe80::250:56ff:fe84:4816/64 scope link
valid_lft forever preferred_lft forever
root@mypbx:~#
This is the sngrep log:
2024/04/03 17:43:44.313122 80.97.52.1:3718 -> 80.97.52.169:5060
INVITE sip:102@socrate.it4all.ro SIP/2.0
Via: SIP/2.0/UDP 80.97.52.1:3718;rport;branch=z9hG4bKPjo.3muvW0I-Rm5RM2thQWdTjp4Maol2A7
Max-Forwards: 70
From: "demo vital" <sip:101@socrate.it4all.ro>;tag=5FS76Kl4q2HWp3DQNsX7yrePpo6Y0f0.
To: sip:102@socrate.it4all.ro
Contact: <sip:101@80.97.52.1:3718;ob>
Call-ID: bmqSOiLvcYwMN7KO73xw1jc1THswgtr0
CSeq: 24772 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
User-Agent: Telephone 1.6
Content-Type: application/sdp
Content-Length: 470
v=0
o=- 3921144224 3921144224 IN IP4 80.97.52.1
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4004 RTP/AVP 96 9 8 0 101 102
c=IN IP4 80.97.52.1
b=TIAS:96000
a=rtcp:4005 IN IP4 80.97.52.1
a=sendrecv
a=rtpmap:96 opus/48000/2
a=fmtp:96 useinbandfec=1
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/48000
a=fmtp:101 0-16
a=rtpmap:102 telephone-event/8000
a=fmtp:102 0-16
a=ssrc:274124307 cname:3537de3766de082c
2024/04/03 17:43:44.313819 80.97.52.169:5060 -> 80.97.52.1:3718
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 80.97.52.1:3718;rport=3718;received=80.97.52.1;branch=z9hG4bKPjo.3muvW0I-Rm5RM2thQWdTjp4Maol2A7
Call-ID: bmqSOiLvcYwMN7KO73xw1jc1THswgtr0
From: "demo vital" <sip:101@socrate.it4all.ro>;tag=5FS76Kl4q2HWp3DQNsX7yrePpo6Y0f0.
To: <sip:102@socrate.it4all.ro>;tag=z9hG4bKPjo.3muvW0I-Rm5RM2thQWdTjp4Maol2A7
CSeq: 24772 INVITE
WWW-Authenticate: Digest realm="asterisk",nonce="1712155424/6f4602cab5f1a2dcfe478e514f2f0f48",opaque="35b292726fef2d48",algorithm=MD5,qop="auth"
Server: VitalPBX
Content-Length: 0
2024/04/03 17:43:44.314559 80.97.52.1:3718 -> 80.97.52.169:5060
ACK sip:102@socrate.it4all.ro SIP/2.0
Via: SIP/2.0/UDP 80.97.52.1:3718;rport;branch=z9hG4bKPjo.3muvW0I-Rm5RM2thQWdTjp4Maol2A7
Max-Forwards: 70
From: "demo vital" <sip:101@socrate.it4all.ro>;tag=5FS76Kl4q2HWp3DQNsX7yrePpo6Y0f0.
To: sip:102@socrate.it4all.ro;tag=z9hG4bKPjo.3muvW0I-Rm5RM2thQWdTjp4Maol2A7
Call-ID: bmqSOiLvcYwMN7KO73xw1jc1THswgtr0
CSeq: 24772 ACK
Content-Length: 0
2024/04/03 17:43:44.314786 80.97.52.1:3718 -> 80.97.52.169:5060
INVITE sip:102@socrate.it4all.ro SIP/2.0
Via: SIP/2.0/UDP 80.97.52.1:3718;rport;branch=z9hG4bKPjf941U-LRK-yAYfpZjlewvkhYYSLzwoFn
Max-Forwards: 70
From: "demo vital" <sip:101@socrate.it4all.ro>;tag=5FS76Kl4q2HWp3DQNsX7yrePpo6Y0f0.
To: sip:102@socrate.it4all.ro
Contact: <sip:101@80.97.52.1:3718;ob>
Call-ID: bmqSOiLvcYwMN7KO73xw1jc1THswgtr0
CSeq: 24773 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
User-Agent: Telephone 1.6
Authorization: Digest username="101", realm="asterisk", nonce="1712155424/6f4602cab5f1a2dcfe478e514f2f0f48", uri="sip:102@socrate.it4all.ro", response="cf68211cbc50d16e0790972e5ccde4a4", algorithm=MD
cnonce="enZhrMooizKNLmwt1Zn2vhIERebejYWo", opaque="35b292726fef2d48", qop=auth, nc=00000001
Content-Type: application/sdp
Content-Length: 470
v=0
o=- 3921144224 3921144224 IN IP4 80.97.52.1
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4004 RTP/AVP 96 9 8 0 101 102
c=IN IP4 80.97.52.1
b=TIAS:96000
a=rtcp:4005 IN IP4 80.97.52.1
a=sendrecv
a=rtpmap:96 opus/48000/2
a=fmtp:96 useinbandfec=1
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/48000
a=fmtp:101 0-16
a=rtpmap:102 telephone-event/8000
a=fmtp:102 0-16
a=ssrc:274124307 cname:3537de3766de082c
2024/04/03 17:43:44.315402 80.97.52.169:5060 -> 80.97.52.1:3718
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 80.97.52.1:3718;rport=3718;received=80.97.52.1;branch=z9hG4bKPjf941U-LRK-yAYfpZjlewvkhYYSLzwoFn
Call-ID: bmqSOiLvcYwMN7KO73xw1jc1THswgtr0
From: "demo vital" <sip:101@socrate.it4all.ro>;tag=5FS76Kl4q2HWp3DQNsX7yrePpo6Y0f0.
To: <sip:102@socrate.it4all.ro>
CSeq: 24773 INVITE
Server: VitalPBX
Content-Length: 0
2024/04/03 17:43:44.429537 80.97.52.169:5060 -> 80.97.52.1:3718
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 80.97.52.1:3718;rport=3718;received=80.97.52.1;branch=z9hG4bKPjf941U-LRK-yAYfpZjlewvkhYYSLzwoFn
Call-ID: bmqSOiLvcYwMN7KO73xw1jc1THswgtr0
From: "demo vital" <sip:101@socrate.it4all.ro>;tag=5FS76Kl4q2HWp3DQNsX7yrePpo6Y0f0.
To: <sip:102@socrate.it4all.ro>;tag=24f754aa-8dae-4a78-9792-7d9c30c011d2
CSeq: 24773 INVITE
Server: VitalPBX
Reason: Q.850;cause=3
Content-Length: 0
2024/04/03 17:43:44.430529 80.97.52.1:3718 -> 80.97.52.169:5060
ACK sip:102@socrate.it4all.ro SIP/2.0
Via: SIP/2.0/UDP 80.97.52.1:3718;rport;branch=z9hG4bKPjf941U-LRK-yAYfpZjlewvkhYYSLzwoFn
Max-Forwards: 70
From: "demo vital" <sip:101@socrate.it4all.ro>;tag=5FS76Kl4q2HWp3DQNsX7yrePpo6Y0f0.
To: sip:102@socrate.it4all.ro;tag=24f754aa-8dae-4a78-9792-7d9c30c011d2
Call-ID: bmqSOiLvcYwMN7KO73xw1jc1THswgtr0
CSeq: 24773 ACK
Content-Length: 0
