This says it all:

2021/09/28 04:45:07.358826 192.168.123.10:7330 -> 10.252.1.14:5060 INVITE sip:1093@10.252.1.14 SIP/2.0

Based on the above, how do you expect this call to reach the softphone at 10.0.0.142?

Also, it's pretty easy to see from the provided traces that the call is bounding back and forth between 10.252.1.14 <=> 192.168.123.10, never reaching the softphone.

You have a rather long way to making this scenario work, which is good, you'll get to learn a few new things as an ITSP.

Good luck.

On Tue, Sep 28, 2021 at 1:00 AM Micah Quinn micah.quinn@sipiq.com wrote:

OK, then some more details and some questions. My network configuration is as follows:

10.0.0.142 10.0.0.200 10.252.1.14 10.252.1.1 192.168.123.5 192.168.123.10 [softphone] <--------> [kamailio/rtpengine] <---------VPN---------> [VPN server] <------------------> [FreePBX}

There is no NAT'ing involved/enabled. I'm running RTPEngine on the same machine as Kamailio.

With my current configuration I can call the PBX directly without issue. (i.e. access my voicemail, IVRs, conference rooms, etc.). However, I can still not make an extension-to-extension call. Asterisk responds to the INVITE with a "401 Unauthorized" message.I have two extensions registered (1093 and 10931):

Endpoint: 1093/1093 Not in use 0 of inf InAuth: 1093-auth/1093 Aor: 1093 10 Contact: 1093/sip:1093@10.252.1.14 a49a850887 Avail 85.409

Endpoint: 10931/10931 Not in use 0 of inf InAuth: 10931-auth/10931 Aor: 10931 10 Contact: 10931/sip:10931@10.252.1.14 3690dfd96d Avail 85.225

Below are two packet captures from the Kamailio machine and the Asterisk machine. If more information is needed, I'll be happy to supply the specifics. Thanks to anyone that's willing to take the time to look this over. (Alternatively, if somebody wants to suggest a kamailio.cfg file for my specific use case, I'd be happy to test that on my setup as well.)

On the Kamailio machine:

2021/09/28 04:45:07.358826 192.168.123.10:7330 -> 10.252.1.14:5060 INVITE sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: sip:1093@10.252.1.14 Contact: sip:asterisk@192.168.123.10:5060 Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 INVITE Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER Supported: 100rel, timer, replaces, norefersub, histinfo Session-Expires: 1800 Min-SE: 90 P-Asserted-Identity: "10931" sip:10931@192.168.123.10 Max-Forwards: 70 User-Agent: FPBX-16.0.10.27(17.9.4) Content-Type: application/sdp Content-Length: 341

v=0 o=- 585379038 585379038 IN IP4 192.168.123.10 s=Asterisk c=IN IP4 192.168.123.10 t=0 0 m=audio 18074 RTP/AVP 0 8 3 111 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv

2021/09/28 04:45:07.365188 10.252.1.14:5060 -> 192.168.123.10:7330 SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport=7330;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e;received=192.168.123.10 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: sip:1093@10.252.1.14 Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 INVITE Server: kamailio (5.3.2 (x86_64/linux)) Content-Length: 0

2021/09/28 04:45:07.366400 10.252.1.14:5060 -> 192.168.123.10:5060 INVITE sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 10.252.1.14;branch=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;received=192.168.123.10;rport=7330;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: sip:1093@10.252.1.14 Contact: sip:asterisk@192.168.123.10:5060 Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 INVITE Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER Supported: 100rel, timer, replaces, norefersub, histinfo Session-Expires: 1800 Min-SE: 90 P-Asserted-Identity: "10931" sip:10931@192.168.123.10 Max-Forwards: 69 User-Agent: FPBX-16.0.10.27(17.9.4) Content-Type: application/sdp Content-Length: 349

v=0 o=- 585379038 585379038 IN IP4 10.252.1.14 s=Asterisk c=IN IP4 10.252.1.14 t=0 0 m=audio 14618 RTP/AVP 0 8 3 111 9 101 a=maxptime:150 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=sendrecv a=rtcp:14619 a=ptime:20

2021/09/28 04:45:07.409622 192.168.123.10:5060 -> 10.252.1.14:5060 SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.252.1.14;rport=19725;received=192.168.123.5;branch=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport=7330;received=192.168.123.10;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

CSeq: 13326 INVITE WWW-Authenticate: Digest realm="asterisk",nonce="1632804307/c98b5b90e7cdc94fd7ab1974b7d3c44b",opaque="6e3e077334bf1910",algorithm=md5,qop="auth" Server: FPBX-16.0.10.27(17.9.4) Content-Length: 0

2021/09/28 04:45:07.412926 10.252.1.14:5060 -> 192.168.123.10:5060 ACK sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 10.252.1.14;branch=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 ACK Max-Forwards: 69 Content-Length: 0

2021/09/28 04:45:07.413090 10.252.1.14:5060 -> 192.168.123.10:7330 SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport=7330;received=192.168.123.10;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

CSeq: 13326 INVITE WWW-Authenticate: Digest realm="asterisk",nonce="1632804307/c98b5b90e7cdc94fd7ab1974b7d3c44b",opaque="6e3e077334bf1910",algorithm=md5,qop="auth" Server: FPBX-16.0.10.27(17.9.4) Content-Length: 0

2021/09/28 04:45:07.455640 192.168.123.10:7330 -> 10.252.1.14:5060 ACK sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 ACK Max-Forwards: 70 User-Agent: FPBX-16.0.10.27(17.9.4) Content-Length: 0

On the FreePBX machine:

2021/09/28 04:45:07.342242 192.168.123.10:5060 -> 10.252.1.14:5060 INVITE sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: sip:1093@10.252.1.14 Contact: sip:asterisk@192.168.123.10:5060 Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 INVITE Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER Supported: 100rel, timer, replaces, norefersub, histinfo Session-Expires: 1800 Min-SE: 90 P-Asserted-Identity: "10931" sip:10931@192.168.123.10 Max-Forwards: 70 User-Agent: FPBX-16.0.10.27(17.9.4) Content-Type: application/sdp Content-Length: 341

v=0 o=- 585379038 585379038 IN IP4 192.168.123.10 s=Asterisk c=IN IP4 192.168.123.10 t=0 0 m=audio 18074 RTP/AVP 0 8 3 111 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv

2021/09/28 04:45:07.390644 10.252.1.14:5060 -> 192.168.123.10:5060 SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport=7330;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e;received=192.168.123.10 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: sip:1093@10.252.1.14 Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 INVITE Server: kamailio (5.3.2 (x86_64/linux)) Content-Length: 0

2021/09/28 04:45:07.392235 192.168.123.5:19725 -> 192.168.123.10:5060 INVITE sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 10.252.1.14;branch=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;received=192.168.123.10;rport=7330;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: sip:1093@10.252.1.14 Contact: sip:asterisk@192.168.123.10:5060 Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 INVITE Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER Supported: 100rel, timer, replaces, norefersub, histinfo Session-Expires: 1800 Min-SE: 90 P-Asserted-Identity: "10931" sip:10931@192.168.123.10 Max-Forwards: 69 User-Agent: FPBX-16.0.10.27(17.9.4) Content-Type: application/sdp Content-Length: 349

v=0 o=- 585379038 585379038 IN IP4 10.252.1.14 s=Asterisk c=IN IP4 10.252.1.14 t=0 0 m=audio 14618 RTP/AVP 0 8 3 111 9 101 a=maxptime:150 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=sendrecv a=rtcp:14619 a=ptime:20

2021/09/28 04:45:07.393454 192.168.123.10:5060 -> 192.168.123.5:19725 SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.252.1.14;rport=19725;received=192.168.123.5;branch=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport=7330;received=192.168.123.10;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

CSeq: 13326 INVITE WWW-Authenticate: Digest realm="asterisk",nonce="1632804307/c98b5b90e7cdc94fd7ab1974b7d3c44b",opaque="6e3e077334bf1910",algorithm=md5,qop="auth" Server: FPBX-16.0.10.27(17.9.4) Content-Length: 0

2021/09/28 04:45:07.438326 192.168.123.5:19725 -> 192.168.123.10:5060 ACK sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 10.252.1.14;branch=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 ACK Max-Forwards: 69 Content-Length: 0

2021/09/28 04:45:07.438558 10.252.1.14:5060 -> 192.168.123.10:5060 SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport=7330;received=192.168.123.10;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

CSeq: 13326 INVITE WWW-Authenticate: Digest realm="asterisk",nonce="1632804307/c98b5b90e7cdc94fd7ab1974b7d3c44b",opaque="6e3e077334bf1910",algorithm=md5,qop="auth" Server: FPBX-16.0.10.27(17.9.4) Content-Length: 0

2021/09/28 04:45:07.439339 192.168.123.10:5060 -> 10.252.1.14:5060 ACK sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 ACK Max-Forwards: 70 User-Agent: FPBX-16.0.10.27(17.9.4) Content-Length: 0

---

*From:* Henning Westerholt hw@skalatan.de *Sent:* Saturday, September 11, 2021 3:16 PM *To:* Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org *Cc:* Micah Quinn micah.quinn@sipiq.com *Subject:* RE: Kamailio/RTPengine as a proxy for FreePBX/Asterisk...

Hello Micah,

using Kamailio as front-end/balancer for one or more asterisk instance(s) is a classic use case for Kamailio.

Have a look to the Asterisk log why you get some authentication request, probably you need to “tell” Asterisk to trust the Kamailio (IPs).

Cheers,

Henning

--

Henning Westerholt – https://skalatan.de/blog/

Kamailio services – https://gilawa.com

*From:* sr-users sr-users-bounces@lists.kamailio.org *On Behalf Of *Micah Quinn *Sent:* Friday, September 10, 2021 1:05 AM *To:* sr-users@lists.kamailio.org *Subject:* [SR-Users] Kamailio/RTPengine as a proxy for FreePBX/Asterisk...

Hello all,

I'm new to Kamailio, so bear with me as I stumble through this. First, I'll describe what I'm trying to achieve at a high level and then perhaps somebody can advise me on whether Kamailio is a good fit for this solution or not. I'd like to be able to deploy a small appliance type server to our customer's sites that just runs Kamailio and a VPN connection back to our datacenter. At our datacenter, we run virtualized instances of Asterisk for each of our customers. The idea is that Kamailio would act as a transparent proxy through to the Asterisk instance under nominal conditions and as a basic SIP router in the case that the Asterisk instance is unavailable. This degraded functionality would then at least allow extension to extension calling even if the Internet or Asterisk instance is down.

I'm currently using dispatcher with a single entry in preparation for a time when we might want to failover to another Asterisk instance. I'm forwarding all REGISTER and INVITE messages to the server chosen from ds_select_dst. Initially this all seems to work as I can register with a softphone and pjsip show endpoints shows my softphone connected. However, when I attempt to call any extension (my own or another) Asterisk responds to the INVITE message with a "401 Unauthorized" message and the typical "The person at extension XXXX is unavailable...".

I know that more details might be necessary to troubleshoot this, but I didn't want to include everything in one post and risk cluttering it up with unnecessary information. If anyone can confirm that this is a reasonable way to approach the problem, I can then provide whatever relevant data is necessary to get deeper into it. (I've used sngrep, logging, asterisk cli, etc.)

Thanks in advance for any help.

---

Kamailio - Users Mailing List - Non Commercial Discussions

• sr-users@lists.kamailio.org

Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe:

• https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

RFC3327

On Wed., Sep. 29, 2021, 12:50 a.m. Micah Quinn, micah.quinn@sipiq.com wrote:

Thank you for looking at this Sergiu.

Yes, you are correct that Asterisk's INVITE to the receiving extension is bouncing between 10.252.1.14 and 192.168.123.10.

Based on the above, how do you expect this call to reach the softphone at 10.0.0.142?

I suppose that is the question at issue. And you'll have to forgive me for any ignorance on the details of SIP; I'm still learning.

• Should I be adding a Via header to the message?
• Should I be modifying the Contact header? (BTW, I'm already

modifying the contact header to point to 10.252.1.14. If I don't, the endpoint shows offline/unavailable. As I understand it, Contact header has nothing to do with message routing.)

• Should I be saving the location of the endpoint in Kamailio and

doing a lookup on inbound messages?

Given that I've been assured that this is a common use case for Kamailio, I have to admit that all of the howto's and example .cfg files I've read and tried do not seem to completely address this situation.

---

*From:* sr-users sr-users-bounces@lists.kamailio.org on behalf of Sergiu Pojoga pojogas@gmail.com *Sent:* Tuesday, September 28, 2021 9:25 AM *To:* Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org *Subject:* Re: [SR-Users] Kamailio/RTPengine as a proxy for FreePBX/Asterisk...

This says it all:

2021/09/28 04:45:07.358826 192.168.123.10:7330 -> 10.252.1.14:5060 INVITE sip:1093@10.252.1.14 SIP/2.0

Based on the above, how do you expect this call to reach the softphone at 10.0.0.142?

Also, it's pretty easy to see from the provided traces that the call is bounding back and forth between 10.252.1.14 <=> 192.168.123.10, never reaching the softphone.

You have a rather long way to making this scenario work, which is good, you'll get to learn a few new things as an ITSP.

Good luck.

On Tue, Sep 28, 2021 at 1:00 AM Micah Quinn micah.quinn@sipiq.com wrote:

OK, then some more details and some questions. My network configuration is as follows:

10.0.0.142 10.0.0.200 10.252.1.14 10.252.1.1 192.168.123.5 192.168.123.10 [softphone] <--------> [kamailio/rtpengine] <---------VPN---------> [VPN server] <------------------> [FreePBX}

There is no NAT'ing involved/enabled. I'm running RTPEngine on the same machine as Kamailio.

With my current configuration I can call the PBX directly without issue. (i.e. access my voicemail, IVRs, conference rooms, etc.). However, I can still not make an extension-to-extension call. Asterisk responds to the INVITE with a "401 Unauthorized" message.I have two extensions registered (1093 and 10931):

Endpoint: 1093/1093 Not in use 0 of inf InAuth: 1093-auth/1093 Aor: 1093 10 Contact: 1093/sip:1093@10.252.1.14 a49a850887 Avail 85.409

Endpoint: 10931/10931 Not in use 0 of inf InAuth: 10931-auth/10931 Aor: 10931 10 Contact: 10931/sip:10931@10.252.1.14 3690dfd96d Avail 85.225

Below are two packet captures from the Kamailio machine and the Asterisk machine. If more information is needed, I'll be happy to supply the specifics. Thanks to anyone that's willing to take the time to look this over. (Alternatively, if somebody wants to suggest a kamailio.cfg file for my specific use case, I'd be happy to test that on my setup as well.)

On the Kamailio machine:

2021/09/28 04:45:07.358826 192.168.123.10:7330 -> 10.252.1.14:5060 INVITE sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: sip:1093@10.252.1.14 Contact: sip:asterisk@192.168.123.10:5060 Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 INVITE Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER Supported: 100rel, timer, replaces, norefersub, histinfo Session-Expires: 1800 Min-SE: 90 P-Asserted-Identity: "10931" sip:10931@192.168.123.10 Max-Forwards: 70 User-Agent: FPBX-16.0.10.27(17.9.4) Content-Type: application/sdp Content-Length: 341

v=0 o=- 585379038 585379038 IN IP4 192.168.123.10 s=Asterisk c=IN IP4 192.168.123.10 t=0 0 m=audio 18074 RTP/AVP 0 8 3 111 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv

2021/09/28 04:45:07.365188 10.252.1.14:5060 -> 192.168.123.10:7330 SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport=7330;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e;received=192.168.123.10 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: sip:1093@10.252.1.14 Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 INVITE Server: kamailio (5.3.2 (x86_64/linux)) Content-Length: 0

2021/09/28 04:45:07.366400 10.252.1.14:5060 -> 192.168.123.10:5060 INVITE sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 10.252.1.14;branch=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;received=192.168.123.10;rport=7330;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: sip:1093@10.252.1.14 Contact: sip:asterisk@192.168.123.10:5060 Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 INVITE Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER Supported: 100rel, timer, replaces, norefersub, histinfo Session-Expires: 1800 Min-SE: 90 P-Asserted-Identity: "10931" sip:10931@192.168.123.10 Max-Forwards: 69 User-Agent: FPBX-16.0.10.27(17.9.4) Content-Type: application/sdp Content-Length: 349

v=0 o=- 585379038 585379038 IN IP4 10.252.1.14 s=Asterisk c=IN IP4 10.252.1.14 t=0 0 m=audio 14618 RTP/AVP 0 8 3 111 9 101 a=maxptime:150 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=sendrecv a=rtcp:14619 a=ptime:20

2021/09/28 04:45:07.409622 192.168.123.10:5060 -> 10.252.1.14:5060 SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.252.1.14;rport=19725;received=192.168.123.5;branch=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport=7330;received=192.168.123.10;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

CSeq: 13326 INVITE WWW-Authenticate: Digest realm="asterisk",nonce="1632804307/c98b5b90e7cdc94fd7ab1974b7d3c44b",opaque="6e3e077334bf1910",algorithm=md5,qop="auth" Server: FPBX-16.0.10.27(17.9.4) Content-Length: 0

2021/09/28 04:45:07.412926 10.252.1.14:5060 -> 192.168.123.10:5060 ACK sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 10.252.1.14;branch=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 ACK Max-Forwards: 69 Content-Length: 0

2021/09/28 04:45:07.413090 10.252.1.14:5060 -> 192.168.123.10:7330 SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport=7330;received=192.168.123.10;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

CSeq: 13326 INVITE WWW-Authenticate: Digest realm="asterisk",nonce="1632804307/c98b5b90e7cdc94fd7ab1974b7d3c44b",opaque="6e3e077334bf1910",algorithm=md5,qop="auth" Server: FPBX-16.0.10.27(17.9.4) Content-Length: 0

2021/09/28 04:45:07.455640 192.168.123.10:7330 -> 10.252.1.14:5060 ACK sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 ACK Max-Forwards: 70 User-Agent: FPBX-16.0.10.27(17.9.4) Content-Length: 0

On the FreePBX machine:

2021/09/28 04:45:07.342242 192.168.123.10:5060 -> 10.252.1.14:5060 INVITE sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: sip:1093@10.252.1.14 Contact: sip:asterisk@192.168.123.10:5060 Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 INVITE Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER Supported: 100rel, timer, replaces, norefersub, histinfo Session-Expires: 1800 Min-SE: 90 P-Asserted-Identity: "10931" sip:10931@192.168.123.10 Max-Forwards: 70 User-Agent: FPBX-16.0.10.27(17.9.4) Content-Type: application/sdp Content-Length: 341

v=0 o=- 585379038 585379038 IN IP4 192.168.123.10 s=Asterisk c=IN IP4 192.168.123.10 t=0 0 m=audio 18074 RTP/AVP 0 8 3 111 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=maxptime:150 a=sendrecv

2021/09/28 04:45:07.390644 10.252.1.14:5060 -> 192.168.123.10:5060 SIP/2.0 100 trying -- your call is important to us Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport=7330;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e;received=192.168.123.10 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: sip:1093@10.252.1.14 Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 INVITE Server: kamailio (5.3.2 (x86_64/linux)) Content-Length: 0

2021/09/28 04:45:07.392235 192.168.123.5:19725 -> 192.168.123.10:5060 INVITE sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 10.252.1.14;branch=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;received=192.168.123.10;rport=7330;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: sip:1093@10.252.1.14 Contact: sip:asterisk@192.168.123.10:5060 Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 INVITE Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER Supported: 100rel, timer, replaces, norefersub, histinfo Session-Expires: 1800 Min-SE: 90 P-Asserted-Identity: "10931" sip:10931@192.168.123.10 Max-Forwards: 69 User-Agent: FPBX-16.0.10.27(17.9.4) Content-Type: application/sdp Content-Length: 349

v=0 o=- 585379038 585379038 IN IP4 10.252.1.14 s=Asterisk c=IN IP4 10.252.1.14 t=0 0 m=audio 14618 RTP/AVP 0 8 3 111 9 101 a=maxptime:150 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:111 G726-32/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=sendrecv a=rtcp:14619 a=ptime:20

2021/09/28 04:45:07.393454 192.168.123.10:5060 -> 192.168.123.5:19725 SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 10.252.1.14;rport=19725;received=192.168.123.5;branch=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport=7330;received=192.168.123.10;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

CSeq: 13326 INVITE WWW-Authenticate: Digest realm="asterisk",nonce="1632804307/c98b5b90e7cdc94fd7ab1974b7d3c44b",opaque="6e3e077334bf1910",algorithm=md5,qop="auth" Server: FPBX-16.0.10.27(17.9.4) Content-Length: 0

2021/09/28 04:45:07.438326 192.168.123.5:19725 -> 192.168.123.10:5060 ACK sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 10.252.1.14;branch=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 ACK Max-Forwards: 69 Content-Length: 0

2021/09/28 04:45:07.438558 10.252.1.14:5060 -> 192.168.123.10:5060 SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport=7330;received=192.168.123.10;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

CSeq: 13326 INVITE WWW-Authenticate: Digest realm="asterisk",nonce="1632804307/c98b5b90e7cdc94fd7ab1974b7d3c44b",opaque="6e3e077334bf1910",algorithm=md5,qop="auth" Server: FPBX-16.0.10.27(17.9.4) Content-Length: 0

2021/09/28 04:45:07.439339 192.168.123.10:5060 -> 10.252.1.14:5060 ACK sip:1093@10.252.1.14 SIP/2.0 Via: SIP/2.0/UDP 192.168.123.10:5060 ;rport;branch=z9hG4bKPj68d21815-beeb-4631-b8ba-e2b979331e0e From: "10931" <sip:10931@192.168.123.10

...

;tag=a3c6bf40-aa29-4b58-963d-36952a617a54

To: <sip:1093@10.252.1.14

...

;tag=z9hG4bKe019.4be37ea094ac7d8f4c0a037c7887e071.0

Call-ID: 4ec009f0-34c5-4356-bef9-a52b862c7a93 CSeq: 13326 ACK Max-Forwards: 70 User-Agent: FPBX-16.0.10.27(17.9.4) Content-Length: 0

---

*From:* Henning Westerholt hw@skalatan.de *Sent:* Saturday, September 11, 2021 3:16 PM *To:* Kamailio (SER) - Users Mailing List sr-users@lists.kamailio.org *Cc:* Micah Quinn micah.quinn@sipiq.com *Subject:* RE: Kamailio/RTPengine as a proxy for FreePBX/Asterisk...

Hello Micah,

using Kamailio as front-end/balancer for one or more asterisk instance(s) is a classic use case for Kamailio.

Have a look to the Asterisk log why you get some authentication request, probably you need to “tell” Asterisk to trust the Kamailio (IPs).

Cheers,

Henning

--

Henning Westerholt – https://skalatan.de/blog/

Kamailio services – https://gilawa.com

*From:* sr-users sr-users-bounces@lists.kamailio.org *On Behalf Of *Micah Quinn *Sent:* Friday, September 10, 2021 1:05 AM *To:* sr-users@lists.kamailio.org *Subject:* [SR-Users] Kamailio/RTPengine as a proxy for FreePBX/Asterisk...

Hello all,

I'm new to Kamailio, so bear with me as I stumble through this. First, I'll describe what I'm trying to achieve at a high level and then perhaps somebody can advise me on whether Kamailio is a good fit for this solution or not. I'd like to be able to deploy a small appliance type server to our customer's sites that just runs Kamailio and a VPN connection back to our datacenter. At our datacenter, we run virtualized instances of Asterisk for each of our customers. The idea is that Kamailio would act as a transparent proxy through to the Asterisk instance under nominal conditions and as a basic SIP router in the case that the Asterisk instance is unavailable. This degraded functionality would then at least allow extension to extension calling even if the Internet or Asterisk instance is down.

I'm currently using dispatcher with a single entry in preparation for a time when we might want to failover to another Asterisk instance. I'm forwarding all REGISTER and INVITE messages to the server chosen from ds_select_dst. Initially this all seems to work as I can register with a softphone and pjsip show endpoints shows my softphone connected. However, when I attempt to call any extension (my own or another) Asterisk responds to the INVITE message with a "401 Unauthorized" message and the typical "The person at extension XXXX is unavailable...".

I know that more details might be necessary to troubleshoot this, but I didn't want to include everything in one post and risk cluttering it up with unnecessary information. If anyone can confirm that this is a reasonable way to approach the problem, I can then provide whatever relevant data is necessary to get deeper into it. (I've used sngrep, logging, asterisk cli, etc.)

Thanks in advance for any help.

---

Kamailio - Users Mailing List - Non Commercial Discussions

• sr-users@lists.kamailio.org

Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe:

• https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

---

Kamailio - Users Mailing List - Non Commercial Discussions

• sr-users@lists.kamailio.org

Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe:

• https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

Hi there...

Trying to get the BLFs working... Not much luck

Registration works and device to device calls work but the BLFs dont....

Does anyone have a basic config where the BLFs work and calls work that I can try?

If not, can someone point me in the right direction?

I get LOTS of these types of NOTIFYs but never one with a body...

NOTIFY sip:305@10..10.10.223:34571 SIP/2.0 Via: SIP/2.0/UDP a.b.c.d:port;branch=z9hG4bKeb43.45649b07000000000000000000000000.0 To: sip:305@domain.net;tag=697604941 From: sip:301@domain.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-998270a1 CSeq: 2 NOTIFY Call-ID: 1023132872-34571-5@BA.CEG.HG.GC Content-Length: 0 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact: sip:a.b.c.d:port Subscription-State: terminated;reason=timeout

Below, this is my config taken from https://kb.asipto.com/kamailio:presence:k43-blf

============================

#!KAMAILIO

#!define WITH_MYSQL #!define WITH_AUTH #!define WITH_NAT #!define WITH_NATSIPPING #!define WITH_USRLOCDB #!define WITH_PRESENCE #!define WITH_MULTIDOMAIN

# # Kamailio (OpenSER) SIP Server v4.3 - default configuration script #     - web: http://www.kamailio.org #     - git: http://sip-router.org # # Direct your questions about this file to: sr-users@lists.sip-router.org # # Refer to the Core CookBook at http://www.kamailio.org/wiki/ # for an explanation of possible statements, functions and parameters. # # Several features can be enabled using '#!define WITH_FEATURE' directives: # # *** To run in debug mode: #     - define WITH_DEBUG # # *** To enable mysql: #     - define WITH_MYSQL # # *** To enable authentication execute: #     - enable mysql #     - define WITH_AUTH #     - add users using 'kamctl' # # *** To enable IP authentication execute: #     - enable mysql #     - enable authentication #     - define WITH_IPAUTH #     - add IP addresses with group id '1' to 'address' table # # *** To enable persistent user location execute: #     - enable mysql #     - define WITH_USRLOCDB # # *** To enable presence server execute: #     - enable mysql #     - define WITH_PRESENCE # # *** To enable nat traversal execute: #     - define WITH_NAT #     - install RTPProxy: http://www.rtpproxy.org #     - start RTPProxy: #        rtpproxy -l _your_public_ip_ -s udp:localhost:7722 #     - option for NAT SIP OPTIONS keepalives: WITH_NATSIPPING # # *** To enable PSTN gateway routing execute: #     - define WITH_PSTN #     - set the value of pstn.gw_ip #     - check route[PSTN] for regexp routing condition # # *** To enable database aliases lookup execute: #     - enable mysql #     - define WITH_ALIASDB # # *** To enable speed dial lookup execute: #     - enable mysql #     - define WITH_SPEEDDIAL # # *** To enable multi-domain support execute: #     - enable mysql #     - define WITH_MULTIDOMAIN # # *** To enable TLS support execute: #     - adjust CFGDIR/tls.cfg as needed #     - define WITH_TLS # # *** To enable XMLRPC support execute: #     - define WITH_XMLRPC #     - adjust route[XMLRPC] for access policy # # *** To enable anti-flood detection execute: #     - adjust pike and htable=>ipban settings as needed (default is #       block if more than 16 requests in 2 seconds and ban for 300 seconds) #     - define WITH_ANTIFLOOD # # *** To block 3XX redirect replies execute: #     - define WITH_BLOCK3XX # # *** To enable VoiceMail routing execute: #     - define WITH_VOICEMAIL #     - set the value of voicemail.srv_ip #     - adjust the value of voicemail.srv_port # # *** To enhance accounting execute: #     - enable mysql #     - define WITH_ACCDB #     - add following columns to database #!ifdef ACCDB_COMMENT   ALTER TABLE acc ADD COLUMN src_user VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE acc ADD COLUMN src_domain VARCHAR(128) NOT NULL DEFAULT '';   ALTER TABLE acc ADD COLUMN src_ip varchar(64) NOT NULL default '';   ALTER TABLE acc ADD COLUMN dst_ouser VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE acc ADD COLUMN dst_user VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE acc ADD COLUMN dst_domain VARCHAR(128) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN src_user VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN src_domain VARCHAR(128) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN src_ip varchar(64) NOT NULL default '';   ALTER TABLE missed_calls ADD COLUMN dst_ouser VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN dst_user VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN dst_domain VARCHAR(128) NOT NULL DEFAULT ''; #!endif

####### Include Local Config If Exists ######### import_file "kamailio-local.cfg"

####### Defined Values #########

# *** Value defines - IDs used later in config #!ifdef WITH_MYSQL # - database URL - used to connect to database server by modules such #       as: auth_db, acc, usrloc, a.s.o. #!ifndef DBURL #!trydef DBURL "mysql://user:passwd@127.0.0.1/kamailio" #!endif #!endif #!ifdef WITH_MULTIDOMAIN # - the value for 'use_domain' parameters #!define MULTIDOMAIN 1 #!else #!define MULTIDOMAIN 0 #!endif

# - flags #   FLT_ - per transaction (message) flags #       FLB_ - per branch flags #!define FLT_ACC 1 #!define FLT_ACCMISSED 2 #!define FLT_ACCFAILED 3 #!define FLT_NATS 5 #!define FLT_DLG 9 #!define FLT_DLGINFO 10

#!define FLB_NATB 6 #!define FLB_NATSIPPING 7

####### Global Parameters #########

### LOG Levels: 3=DBG, 2=INFO, 1=NOTICE, 0=WARN, -1=ERR #!ifdef WITH_DEBUG debug=4 log_stderror=yes #!else debug=2 log_stderror=no #!endif

memdbg=5 memlog=5

log_facility=LOG_LOCAL0

fork=yes children=4

/* uncomment the next line to disable TCP (default on) */ #disable_tcp=yes

/* uncomment the next line to disable the auto discovery of local aliases    based on reverse DNS on IPs (default on) */ #auto_aliases=no

/* add local domain aliases */ #alias="sip.mydomain.com"

/* uncomment and configure the following line if you want Kamailio to    bind on a specific interface/port/proto (default bind on all available) */ listen=udp:a.b.c.d:port

/* port to listen to  * - can be specified more than once if needed to listen on many ports */ port=8081

#!ifdef WITH_TLS enable_tls=yes #!endif

# life time of TCP connection when there is no traffic # - a bit higher than registration expires to cope with UA behind NAT tcp_connection_lifetime=3605

####### Custom Parameters #########

# These parameters can be modified runtime via RPC interface # - see the documentation of 'cfg_rpc' module. # # Format: group.id = value 'desc' description # Access: $sel(cfg_get.group.id) or @cfg_get.group.id #

#!ifdef WITH_PSTN # PSTN GW Routing # # - pstn.gw_ip: valid IP or hostname as string value, example: # pstn.gw_ip = "10.0.0.101" desc "My PSTN GW Address" # # - by default is empty to avoid misrouting pstn.gw_ip = "" desc "PSTN GW Address" pstn.gw_port = "" desc "PSTN GW Port" #!endif

#!ifdef WITH_VOICEMAIL # VoiceMail Routing on offline, busy or no answer # # - by default Voicemail server IP is empty to avoid misrouting voicemail.srv_ip = "" desc "VoiceMail IP Address" voicemail.srv_port = "5060" desc "VoiceMail Port" #!endif

####### Modules Section ########

/* set paths to location of modules */ # mpath="/usr/lib/x86_64-linux-gnu/kamailio/modules/"

#!ifdef WITH_MYSQL loadmodule "db_mysql.so" #!endif

loadmodule "jsonrpcs.so" loadmodule "kex.so" loadmodule "corex.so" loadmodule "tm.so" loadmodule "tmx.so" loadmodule "sl.so" loadmodule "rr.so" loadmodule "pv.so" loadmodule "maxfwd.so" loadmodule "usrloc.so" loadmodule "registrar.so" loadmodule "textops.so" loadmodule "siputils.so" loadmodule "xlog.so" loadmodule "sanity.so" loadmodule "ctl.so" loadmodule "cfg_rpc.so" loadmodule "acc.so"

#!ifdef WITH_AUTH loadmodule "auth.so" loadmodule "auth_db.so" #!ifdef WITH_IPAUTH loadmodule "permissions.so" #!endif #!endif

#!ifdef WITH_ALIASDB loadmodule "alias_db.so" #!endif

#!ifdef WITH_SPEEDDIAL loadmodule "speeddial.so" #!endif

#!ifdef WITH_MULTIDOMAIN loadmodule "domain.so" #!endif

#!ifdef WITH_PRESENCE loadmodule "presence.so" loadmodule "presence_xml.so" loadmodule "presence_dialoginfo.so" loadmodule "pua.so" loadmodule "pua_dialoginfo.so"

#!endif

#!ifdef WITH_NAT loadmodule "nathelper.so" loadmodule "rtpproxy.so" #!endif

#!ifdef WITH_TLS loadmodule "tls.so" #!endif

#!ifdef WITH_ANTIFLOOD loadmodule "htable.so" loadmodule "pike.so" #!endif

#!ifdef WITH_XMLRPC loadmodule "xmlrpc.so" #!endif

#!ifdef WITH_DEBUG loadmodule "debugger.so" #!endif

loadmodule "dialog.so"

# ----------------- setting module-specific parameters ---------------

# ----- tm params ----- # auto-discard branches from previous serial forking leg modparam("tm", "failure_reply_mode", 3) # default retransmission timeout: 30sec modparam("tm", "fr_timer", 30000) # default invite retransmission timeout after 1xx: 120sec modparam("tm", "fr_inv_timer", 120000)

# ----- rr params ----- # set next param to 1 to add value to ;lr param (helps with some UAs) modparam("rr", "enable_full_lr", 0) # do not append from tag to the RR (no need for this script) modparam("rr", "append_fromtag", 0)

# ----- registrar params ----- modparam("registrar", "method_filtering", 1) /* uncomment the next line to disable parallel forking via location */ # modparam("registrar", "append_branches", 0) /* uncomment the next line not to allow more than 10 contacts per AOR */ #modparam("registrar", "max_contacts", 10) # max value for expires of registrations modparam("registrar", "max_expires", 3600) # set it to 1 to enable GRUU modparam("registrar", "gruu_enabled", 0)

# ----- acc params ----- /* what special events should be accounted ? */ modparam("acc", "early_media", 0) modparam("acc", "report_ack", 0) modparam("acc", "report_cancels", 0) /* by default ww do not adjust the direct of the sequential requests.    if you enable this parameter, be sure the enable "append_fromtag"    in "rr" module */ modparam("acc", "detect_direction", 0) /* account triggers (flags) */ modparam("acc", "log_flag", FLT_ACC) modparam("acc", "log_missed_flag", FLT_ACCMISSED) modparam("acc", "log_extra",         "src_user=$fU;src_domain=$fd;src_ip=$si;"         "dst_ouser=$tU;dst_user=$rU;dst_domain=$rd") modparam("acc", "failed_transaction_flag", FLT_ACCFAILED) /* enhanced DB accounting */ #!ifdef WITH_ACCDB modparam("acc", "db_flag", FLT_ACC) modparam("acc", "db_missed_flag", FLT_ACCMISSED) modparam("acc", "db_url", DBURL) modparam("acc", "db_extra",         "src_user=$fU;src_domain=$fd;src_ip=$si;"         "dst_ouser=$tU;dst_user=$rU;dst_domain=$rd") #!endif

# ----- usrloc params ----- /* enable DB persistency for location entries */ #!ifdef WITH_USRLOCDB modparam("usrloc", "db_url", DBURL) modparam("usrloc", "db_mode", 2) modparam("usrloc", "use_domain", MULTIDOMAIN) #!endif

# ----- auth_db params ----- #!ifdef WITH_AUTH modparam("auth_db", "db_url", DBURL) modparam("auth_db", "calculate_ha1", yes) modparam("auth_db", "password_column", "password") modparam("auth_db", "load_credentials", "") modparam("auth_db", "use_domain", MULTIDOMAIN)

# ----- permissions params ----- #!ifdef WITH_IPAUTH modparam("permissions", "db_url", DBURL) modparam("permissions", "db_mode", 1) #!endif

#!endif

# ----- alias_db params ----- #!ifdef WITH_ALIASDB modparam("alias_db", "db_url", DBURL) modparam("alias_db", "use_domain", MULTIDOMAIN) #!endif

# ----- speeddial params ----- #!ifdef WITH_SPEEDDIAL modparam("speeddial", "db_url", DBURL) modparam("speeddial", "use_domain", MULTIDOMAIN) #!endif

# ----- domain params ----- #!ifdef WITH_MULTIDOMAIN modparam("domain", "db_url", DBURL) # register callback to match myself condition with domains list modparam("domain", "register_myself", 1) #!endif

#!ifdef WITH_PRESENCE # ----- presence params ----- modparam("presence", "db_url", DBURL) modparam("presence", "server_address", "sip:a.b.c.d:port" ) modparam("presence", "send_fast_notify", 0) modparam("presence", "db_update_period", 20) modparam("presence", "clean_period", 40) modparam("presence", "subs_db_mode", 2) modparam("presence", "fetch_rows", 1000)

# ----- presence_xml params ----- modparam("presence_xml", "db_url", DBURL) modparam("presence_xml", "force_active", 1)

# ----- presence_dialoginfo params ----- modparam("presence_dialoginfo", "force_single_dialog", 0)

# ----- pua params ----- modparam("pua", "db_url", DBURL) modparam("pua", "db_mode", 2) modparam("pua", "update_period", 60) modparam("pua", "dlginfo_increase_version", 0) modparam("pua", "reginfo_increase_version", 0) modparam("pua", "check_remote_contact", 1) modparam("pua", "fetch_rows", 1000)

# ----- pua_dialoginfo params ----- modparam("pua_dialoginfo", "include_callid", 1) modparam("pua_dialoginfo", "send_publish_flag", FLT_DLGINFO) modparam("pua_dialoginfo", "caller_confirmed", 0) modparam("pua_dialoginfo", "include_tags", 1) modparam("pua_dialoginfo", "override_lifetime", 124)

#!endif

# ----- dialog params ----- modparam("dialog", "db_url", DBURL) modparam("dialog", "enable_stats", 1) modparam("dialog", "db_mode", 1) modparam("dialog", "dlg_flag", FLT_DLG)

#!ifdef WITH_NAT # ----- rtpproxy params ----- modparam("rtpproxy", "rtpproxy_sock", "udp:127.0.0.1:7722")

# ----- nathelper params ----- modparam("nathelper", "natping_interval", 30) modparam("nathelper", "ping_nated_only", 1) modparam("nathelper", "sipping_bflag", FLB_NATSIPPING) modparam("nathelper", "sipping_from", "sip:pinger@kamailio.org")

# params needed for NAT traversal in other modules modparam("nathelper|registrar", "received_avp", "$avp(RECEIVED)") modparam("usrloc", "nat_bflag", FLB_NATB) #!endif

#!ifdef WITH_TLS # ----- tls params ----- modparam("tls", "config", "/usr/local/etc/kamailio/tls.cfg") #!endif

#!ifdef WITH_ANTIFLOOD # ----- pike params ----- modparam("pike", "sampling_time_unit", 2) modparam("pike", "reqs_density_per_unit", 16) modparam("pike", "remove_latency", 4)

# ----- htable params ----- # ip ban htable with autoexpire after 5 minutes modparam("htable", "htable", "ipban=>size=8;autoexpire=300;") #!endif

#!ifdef WITH_XMLRPC # ----- xmlrpc params ----- modparam("xmlrpc", "route", "XMLRPC"); modparam("xmlrpc", "url_match", "^/RPC") #!endif

#!ifdef WITH_DEBUG # ----- debugger params ----- modparam("debugger", "cfgtrace", 1) modparam("debugger", "log_level_name", "exec") #!endif

####### Routing Logic ########

# Main SIP request routing logic # - processing of any incoming SIP request starts with this route # - note: this is the same as route { ... } request_route {

# per request initial checks         route(REQINIT);

# NAT detection         route(NATDETECT);

# CANCEL processing         if (is_method("CANCEL")) {                 if (t_check_trans()) {                         route(RELAY);                 }                 exit;         }

# handle requests within SIP dialogs         route(WITHINDLG);

### only initial requests (no To tag)

# handle retransmissions         if(t_precheck_trans()) {                 t_check_trans();                 exit;         }         t_check_trans();

# authentication         route(AUTH);

# record routing for dialog forming requests (in case they are routed)         # - remove preloaded route headers         remove_hf("Route");         if (is_method("INVITE|SUBSCRIBE"))                 record_route();

# account only INVITEs         if (is_method("INVITE")) {                 setflag(FLT_ACC); # do accounting         }

# dispatch requests to foreign domains         route(SIPOUT);

### requests for my local domains

# handle presence related requests         route(PRESENCE);

# handle registrations         route(REGISTRAR);

if ($rU==$null) {                 # request with no Username in RURI                 sl_send_reply("484","Address Incomplete");                 exit;         }

# dispatch destinations to PSTN         route(PSTN);

# user location service         route(LOCATION); }

# Wrapper for relaying requests route[RELAY] {

# enable additional event routes for forwarded requests         # - serial forking, RTP relaying handling, a.s.o.         if (is_method("INVITE|BYE|SUBSCRIBE|UPDATE")) {                 if(!t_is_set("branch_route")) t_on_branch("MANAGE_BRANCH");         }         if (is_method("INVITE|SUBSCRIBE|UPDATE")) {                 if(!t_is_set("onreply_route")) t_on_reply("MANAGE_REPLY");         }         if (is_method("INVITE")) {                 if(!t_is_set("failure_route")) t_on_failure("MANAGE_FAILURE");         }

if(is_method("INVITE|BYE|UPDATE|CANCEL|ACK")) {                 setflag(FLT_DLGINFO);                 dlg_manage();         }

if (!t_relay()) {                 sl_reply_error();         }         exit; }

# Per SIP request initial checks route[REQINIT] { #!ifdef WITH_ANTIFLOOD         # flood dection from same IP and traffic ban for a while         # be sure you exclude checking trusted peers, such as pstn gateways         # - local host excluded (e.g., loop to self)         if(src_ip!=myself) {                 if($sht(ipban=>$si)!=$null) {                         # ip is already blocked                         xdbg("request from blocked IP - $rm from $fu (IP:$si:$sp)\n");                         exit;                 }                 if (!pike_check_req()) {                         xlog("L_ALERT","ALERT: pike blocking $rm from $fu (IP:$si:$sp)\n");                         $sht(ipban=>$si) = 1;                         exit;                 }         }         if($ua =~ "friendly-scanner") {                 sl_send_reply("200", "OK");                 exit;         } #!endif

if (!mf_process_maxfwd_header("10")) {                 sl_send_reply("483","Too Many Hops");                 exit;         }

if(is_method("OPTIONS") && uri==myself && $rU==$null) {                 sl_send_reply("200","Keepalive");                 exit;         }

if(!sanity_check("1511", "7")) {                 xlog("Malformed SIP message from $si:$sp\n");                 exit;         } }

# Handle requests within SIP dialogs route[WITHINDLG] {         if (!has_totag()) return;

# sequential request withing a dialog should         # take the path determined by record-routing         if (loose_route()) {                 route(DLGURI);                 if (is_method("BYE")) {                         setflag(FLT_ACC); # do accounting ...                         setflag(FLT_ACCFAILED); # ... even if the transaction fails                 }                 else if ( is_method("ACK") ) {                         # ACK is forwarded statelessy                         route(NATMANAGE);                 }                 else if ( is_method("NOTIFY") ) {                         # Add Record-Route for in-dialog NOTIFY as per RFC 6665.                         record_route();                 }                 route(RELAY);                 exit;         }

if (is_method("SUBSCRIBE") && uri == myself) {                 # in-dialog subscribe requests                 route(PRESENCE);                 exit;         }         if ( is_method("ACK") ) {                 if ( t_check_trans() ) {                         # no loose-route, but stateful ACK;                         # must be an ACK after a 487                         # or e.g. 404 from upstream server                         route(RELAY);                         exit;                 } else {                         # ACK without matching transaction ... ignore and discard                         exit;                 }         }         sl_send_reply("404","Not here");         exit; }

# Handle SIP registrations route[REGISTRAR] {         if (!is_method("REGISTER")) return;

if(isflagset(FLT_NATS)) {                 setbflag(FLB_NATB); #!ifdef WITH_NATSIPPING                 # do SIP NAT pinging                 setbflag(FLB_NATSIPPING); #!endif         }         if (!save("location"))                 sl_reply_error();         exit; }

# User location service route[LOCATION] {

#!ifdef WITH_SPEEDDIAL         # search for short dialing - 2-digit extension         if($rU=~"^[0-9][0-9]$")                 if(sd_lookup("speed_dial"))                         route(SIPOUT); #!endif

#!ifdef WITH_ALIASDB         # search in DB-based aliases         if(alias_db_lookup("dbaliases"))                 route(SIPOUT); #!endif

$avp(oexten) = $rU;         if (!lookup("location")) {                 $var(rc) = $rc;                 route(TOVOICEMAIL);                 t_newtran();                 switch ($var(rc)) {                         case -1:                         case -3:                                 send_reply("404", "Not Found");                                 exit;                         case -2:                                 send_reply("405", "Method Not Allowed");                                 exit;                 }         }

# when routing via usrloc, log the missed calls also         if (is_method("INVITE")) {                 setflag(FLT_ACCMISSED);         }

route(RELAY);         exit; }

# Presence server processing route[PRESENCE] {         if(!is_method("PUBLISH|SUBSCRIBE"))                 return;

if(is_method("SUBSCRIBE") && $hdr(Event)=="message-summary") {                 route(TOVOICEMAIL);                 # returns here if no voicemail server is configured                 sl_send_reply("404", "No voicemail service");                 exit;         }

#!ifdef WITH_PRESENCE         if (!t_newtran()) {                 sl_reply_error();                 exit;         }

if(is_method("PUBLISH")) {                 handle_publish();                 t_release();         } else if(is_method("SUBSCRIBE")) {                 handle_subscribe();                 t_release();         }         exit; #!endif

# if presence enabled, this part will not be executed         if (is_method("PUBLISH") || $rU==$null) {                 sl_send_reply("404", "Not here");                 exit;         }         return; }

# IP authorization and user uthentication route[AUTH] { #!ifdef WITH_AUTH

#!ifdef WITH_IPAUTH         if((!is_method("REGISTER")) && allow_source_address()) {                 # source IP allowed                 return;         } #!endif

if (is_method("REGISTER") || from_uri==myself)         {                 # authenticate requests                 if (!auth_check("$fd", "subscriber", "1")) {                         auth_challenge("$fd", "0");                         exit;                 }                 # user authenticated - remove auth header                 if(!is_method("REGISTER|PUBLISH"))                         consume_credentials();         }         # if caller is not local subscriber, then check if it calls         # a local destination, otherwise deny, not an open relay here         if (from_uri!=myself && uri!=myself) {                 sl_send_reply("403","Not relaying");                 exit;         }

#!endif         return; }

# Caller NAT detection route[NATDETECT] { #!ifdef WITH_NAT         force_rport();         if (nat_uac_test("19")) {                 if (is_method("REGISTER")) {                         fix_nated_register();                 } else {                         if(is_first_hop())                                 set_contact_alias();                 }                 setflag(FLT_NATS);         } #!endif         return; }

# RTPProxy control and singaling updates for NAT traversal route[NATMANAGE] { #!ifdef WITH_NAT         if (is_request()) {                 if(has_totag()) {                         if(check_route_param("nat=yes")) {                                 setbflag(FLB_NATB);                         }                 }         }         if (!(isflagset(FLT_NATS) || isbflagset(FLB_NATB)))                 return;

rtpproxy_manage("co");

if (is_request()) {                 if (!has_totag()) {                         if(t_is_branch_route()) {                                 add_rr_param(";nat=yes");                         }                 }         }         if (is_reply()) {                 if(isbflagset(FLB_NATB)) {                         if(is_first_hop())                                 set_contact_alias();                 }         } #!endif         return; }

# URI update for dialog requests route[DLGURI] { #!ifdef WITH_NAT         if(!isdsturiset()) {                 handle_ruri_alias();         } #!endif         return; }

# Routing to foreign domains route[SIPOUT] {         if (uri==myself) return;

append_hf("P-hint: outbound\r\n");         route(RELAY);         exit; }

# PSTN GW routing route[PSTN] { #!ifdef WITH_PSTN         # check if PSTN GW IP is defined         if (strempty($sel(cfg_get.pstn.gw_ip))) {                 xlog("SCRIPT: PSTN rotuing enabled but pstn.gw_ip not defined\n");                 return;         }

# route to PSTN dialed numbers starting with '+' or '00'         #     (international format)         # - update the condition to match your dialing rules for PSTN routing         if(!($rU=~"^(+|00)[1-9][0-9]{3,20}$"))                 return;

# only local users allowed to call         if(from_uri!=myself) {                 sl_send_reply("403", "Not Allowed");                 exit;         }

if (strempty($sel(cfg_get.pstn.gw_port))) {                 $ru = "sip:" + $rU + "@" + $sel(cfg_get.pstn.gw_ip);         } else {                 $ru = "sip:" + $rU + "@" + $sel(cfg_get.pstn.gw_ip) + ":"                                         + $sel(cfg_get.pstn.gw_port);         }

route(RELAY);         exit; #!endif

return; }

# XMLRPC routing #!ifdef WITH_XMLRPC route[XMLRPC] {         # allow XMLRPC from localhost         if ((method=="POST" || method=="GET")                         && (src_ip==127.0.0.1)) {                 # close connection only for xmlrpclib user agents (there is a bug in                 # xmlrpclib: it waits for EOF before interpreting the response).                 if ($hdr(User-Agent) =~ "xmlrpclib")                         set_reply_close();                 set_reply_no_connect();                 dispatch_rpc();                 exit;         }         send_reply("403", "Forbidden");         exit; } #!endif

# Routing to voicemail server route[TOVOICEMAIL] { #!ifdef WITH_VOICEMAIL         if(!is_method("INVITE|SUBSCRIBE"))                 return;

# check if VoiceMail server IP is defined         if (strempty($sel(cfg_get.voicemail.srv_ip))) {                 xlog("SCRIPT: VoiceMail rotuing enabled but IP not defined\n");                 return;         }         if(is_method("INVITE")) {                 if($avp(oexten)==$null)                         return;                 $ru = "sip:" + $avp(oexten) + "@" + $sel(cfg_get.voicemail.srv_ip)                                 + ":" + $sel(cfg_get.voicemail.srv_port);         } else {                 if($rU==$null)                         return;                 $ru = "sip:" + $rU + "@" + $sel(cfg_get.voicemail.srv_ip)                                 + ":" + $sel(cfg_get.voicemail.srv_port);         }         route(RELAY);         exit; #!endif

return; }

# Manage outgoing branches branch_route[MANAGE_BRANCH] {         xdbg("new branch [$T_branch_idx] to $ru\n");         route(NATMANAGE); }

# Manage incoming replies onreply_route[MANAGE_REPLY] {         xdbg("incoming reply\n");         if(status=~"[12][0-9][0-9]")                 route(NATMANAGE); }

# Manage failure routing cases failure_route[MANAGE_FAILURE] {         route(NATMANAGE);

if (t_is_canceled()) {                 exit;         }

#!ifdef WITH_BLOCK3XX         # block call redirect based on 3xx replies.         if (t_check_status("3[0-9][0-9]")) {                 t_reply("404","Not found");                 exit;         } #!endif

#!ifdef WITH_VOICEMAIL         # serial forking         # - route to voicemail on busy or no answer (timeout)         if (t_check_status("486|408")) {                 $du = $null;                 route(TOVOICEMAIL);                 exit;         } #!endif }

======================

Does anyone have some idea of what to look at ?

Jerry

On 9/29/2021 1:46 AM, Jerry Kendall wrote:

Forgot to add:

debian 11

Kamailio 5.4.4

Phones ARE behind NAT

Jerry

On 2021-09-29 1:35 a.m., Jerry Kendall wrote:

...

Hi there...

Trying to get the BLFs working... Not much luck

Registration works and device to device calls work but the BLFs dont....

Does anyone have a basic config where the BLFs work and calls work that I can try?

If not, can someone point me in the right direction?

I get LOTS of these types of NOTIFYs but never one with a body...

NOTIFY sip:305@10..10.10.223:34571 SIP/2.0 Via: SIP/2.0/UDP a.b.c.d:port;branch=z9hG4bKeb43.45649b07000000000000000000000000.0 To: sip:305@domain.net;tag=697604941 From: sip:301@domain.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-998270a1 CSeq: 2 NOTIFY Call-ID: 1023132872-34571-5@BA.CEG.HG.GC Content-Length: 0 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact: sip:a.b.c.d:port Subscription-State: terminated;reason=timeout

Below, this is my config taken from https://kb.asipto.com/kamailio:presence:k43-blf

============================

#!KAMAILIO

#!define WITH_MYSQL #!define WITH_AUTH #!define WITH_NAT #!define WITH_NATSIPPING #!define WITH_USRLOCDB #!define WITH_PRESENCE #!define WITH_MULTIDOMAIN

# # Kamailio (OpenSER) SIP Server v4.3 - default configuration script #     - web: http://www.kamailio.org #     - git: http://sip-router.org # # Direct your questions about this file to: sr-users@lists.sip-router.org # # Refer to the Core CookBook at http://www.kamailio.org/wiki/ # for an explanation of possible statements, functions and parameters. # # Several features can be enabled using '#!define WITH_FEATURE' directives: # # *** To run in debug mode: #     - define WITH_DEBUG # # *** To enable mysql: #     - define WITH_MYSQL # # *** To enable authentication execute: #     - enable mysql #     - define WITH_AUTH #     - add users using 'kamctl' # # *** To enable IP authentication execute: #     - enable mysql #     - enable authentication #     - define WITH_IPAUTH #     - add IP addresses with group id '1' to 'address' table # # *** To enable persistent user location execute: #     - enable mysql #     - define WITH_USRLOCDB # # *** To enable presence server execute: #     - enable mysql #     - define WITH_PRESENCE # # *** To enable nat traversal execute: #     - define WITH_NAT #     - install RTPProxy: http://www.rtpproxy.org #     - start RTPProxy: #        rtpproxy -l _your_public_ip_ -s udp:localhost:7722 #     - option for NAT SIP OPTIONS keepalives: WITH_NATSIPPING # # *** To enable PSTN gateway routing execute: #     - define WITH_PSTN #     - set the value of pstn.gw_ip #     - check route[PSTN] for regexp routing condition # # *** To enable database aliases lookup execute: #     - enable mysql #     - define WITH_ALIASDB # # *** To enable speed dial lookup execute: #     - enable mysql #     - define WITH_SPEEDDIAL # # *** To enable multi-domain support execute: #     - enable mysql #     - define WITH_MULTIDOMAIN # # *** To enable TLS support execute: #     - adjust CFGDIR/tls.cfg as needed #     - define WITH_TLS # # *** To enable XMLRPC support execute: #     - define WITH_XMLRPC #     - adjust route[XMLRPC] for access policy # # *** To enable anti-flood detection execute: #     - adjust pike and htable=>ipban settings as needed (default is #       block if more than 16 requests in 2 seconds and ban for 300 seconds) #     - define WITH_ANTIFLOOD # # *** To block 3XX redirect replies execute: #     - define WITH_BLOCK3XX # # *** To enable VoiceMail routing execute: #     - define WITH_VOICEMAIL #     - set the value of voicemail.srv_ip #     - adjust the value of voicemail.srv_port # # *** To enhance accounting execute: #     - enable mysql #     - define WITH_ACCDB #     - add following columns to database #!ifdef ACCDB_COMMENT   ALTER TABLE acc ADD COLUMN src_user VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE acc ADD COLUMN src_domain VARCHAR(128) NOT NULL DEFAULT '';   ALTER TABLE acc ADD COLUMN src_ip varchar(64) NOT NULL default '';   ALTER TABLE acc ADD COLUMN dst_ouser VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE acc ADD COLUMN dst_user VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE acc ADD COLUMN dst_domain VARCHAR(128) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN src_user VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN src_domain VARCHAR(128) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN src_ip varchar(64) NOT NULL default '';   ALTER TABLE missed_calls ADD COLUMN dst_ouser VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN dst_user VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN dst_domain VARCHAR(128) NOT NULL DEFAULT ''; #!endif

####### Include Local Config If Exists ######### import_file "kamailio-local.cfg"

####### Defined Values #########

# *** Value defines - IDs used later in config #!ifdef WITH_MYSQL # - database URL - used to connect to database server by modules such #       as: auth_db, acc, usrloc, a.s.o. #!ifndef DBURL #!trydef DBURL "mysql://user:passwd@127.0.0.1/kamailio" #!endif #!endif #!ifdef WITH_MULTIDOMAIN # - the value for 'use_domain' parameters #!define MULTIDOMAIN 1 #!else #!define MULTIDOMAIN 0 #!endif

# - flags #   FLT_ - per transaction (message) flags #       FLB_ - per branch flags #!define FLT_ACC 1 #!define FLT_ACCMISSED 2 #!define FLT_ACCFAILED 3 #!define FLT_NATS 5 #!define FLT_DLG 9 #!define FLT_DLGINFO 10

#!define FLB_NATB 6 #!define FLB_NATSIPPING 7

####### Global Parameters #########

### LOG Levels: 3=DBG, 2=INFO, 1=NOTICE, 0=WARN, -1=ERR #!ifdef WITH_DEBUG debug=4 log_stderror=yes #!else debug=2 log_stderror=no #!endif

memdbg=5 memlog=5

log_facility=LOG_LOCAL0

fork=yes children=4

/* uncomment the next line to disable TCP (default on) */ #disable_tcp=yes

/* uncomment the next line to disable the auto discovery of local aliases    based on reverse DNS on IPs (default on) */ #auto_aliases=no

/* add local domain aliases */ #alias="sip.mydomain.com"

/* uncomment and configure the following line if you want Kamailio to    bind on a specific interface/port/proto (default bind on all available) */ listen=udp:a.b.c.d:port

/* port to listen to  * - can be specified more than once if needed to listen on many ports */ port=8081

#!ifdef WITH_TLS enable_tls=yes #!endif

# life time of TCP connection when there is no traffic # - a bit higher than registration expires to cope with UA behind NAT tcp_connection_lifetime=3605

####### Custom Parameters #########

# These parameters can be modified runtime via RPC interface # - see the documentation of 'cfg_rpc' module. # # Format: group.id = value 'desc' description # Access: $sel(cfg_get.group.id) or @cfg_get.group.id #

#!ifdef WITH_PSTN # PSTN GW Routing # # - pstn.gw_ip: valid IP or hostname as string value, example: # pstn.gw_ip = "10.0.0.101" desc "My PSTN GW Address" # # - by default is empty to avoid misrouting pstn.gw_ip = "" desc "PSTN GW Address" pstn.gw_port = "" desc "PSTN GW Port" #!endif

#!ifdef WITH_VOICEMAIL # VoiceMail Routing on offline, busy or no answer # # - by default Voicemail server IP is empty to avoid misrouting voicemail.srv_ip = "" desc "VoiceMail IP Address" voicemail.srv_port = "5060" desc "VoiceMail Port" #!endif

####### Modules Section ########

/* set paths to location of modules */ # mpath="/usr/lib/x86_64-linux-gnu/kamailio/modules/"

#!ifdef WITH_MYSQL loadmodule "db_mysql.so" #!endif

loadmodule "jsonrpcs.so" loadmodule "kex.so" loadmodule "corex.so" loadmodule "tm.so" loadmodule "tmx.so" loadmodule "sl.so" loadmodule "rr.so" loadmodule "pv.so" loadmodule "maxfwd.so" loadmodule "usrloc.so" loadmodule "registrar.so" loadmodule "textops.so" loadmodule "siputils.so" loadmodule "xlog.so" loadmodule "sanity.so" loadmodule "ctl.so" loadmodule "cfg_rpc.so" loadmodule "acc.so"

#!ifdef WITH_AUTH loadmodule "auth.so" loadmodule "auth_db.so" #!ifdef WITH_IPAUTH loadmodule "permissions.so" #!endif #!endif

#!ifdef WITH_ALIASDB loadmodule "alias_db.so" #!endif

#!ifdef WITH_SPEEDDIAL loadmodule "speeddial.so" #!endif

#!ifdef WITH_MULTIDOMAIN loadmodule "domain.so" #!endif

#!ifdef WITH_PRESENCE loadmodule "presence.so" loadmodule "presence_xml.so" loadmodule "presence_dialoginfo.so" loadmodule "pua.so" loadmodule "pua_dialoginfo.so"

#!endif

#!ifdef WITH_NAT loadmodule "nathelper.so" loadmodule "rtpproxy.so" #!endif

#!ifdef WITH_TLS loadmodule "tls.so" #!endif

#!ifdef WITH_ANTIFLOOD loadmodule "htable.so" loadmodule "pike.so" #!endif

#!ifdef WITH_XMLRPC loadmodule "xmlrpc.so" #!endif

#!ifdef WITH_DEBUG loadmodule "debugger.so" #!endif

loadmodule "dialog.so"

# ----------------- setting module-specific parameters ---------------

# ----- tm params ----- # auto-discard branches from previous serial forking leg modparam("tm", "failure_reply_mode", 3) # default retransmission timeout: 30sec modparam("tm", "fr_timer", 30000) # default invite retransmission timeout after 1xx: 120sec modparam("tm", "fr_inv_timer", 120000)

# ----- rr params ----- # set next param to 1 to add value to ;lr param (helps with some UAs) modparam("rr", "enable_full_lr", 0) # do not append from tag to the RR (no need for this script) modparam("rr", "append_fromtag", 0)

# ----- registrar params ----- modparam("registrar", "method_filtering", 1) /* uncomment the next line to disable parallel forking via location */ # modparam("registrar", "append_branches", 0) /* uncomment the next line not to allow more than 10 contacts per AOR */ #modparam("registrar", "max_contacts", 10) # max value for expires of registrations modparam("registrar", "max_expires", 3600) # set it to 1 to enable GRUU modparam("registrar", "gruu_enabled", 0)

# ----- acc params ----- /* what special events should be accounted ? */ modparam("acc", "early_media", 0) modparam("acc", "report_ack", 0) modparam("acc", "report_cancels", 0) /* by default ww do not adjust the direct of the sequential requests.    if you enable this parameter, be sure the enable "append_fromtag"    in "rr" module */ modparam("acc", "detect_direction", 0) /* account triggers (flags) */ modparam("acc", "log_flag", FLT_ACC) modparam("acc", "log_missed_flag", FLT_ACCMISSED) modparam("acc", "log_extra",         "src_user=$fU;src_domain=$fd;src_ip=$si;"         "dst_ouser=$tU;dst_user=$rU;dst_domain=$rd") modparam("acc", "failed_transaction_flag", FLT_ACCFAILED) /* enhanced DB accounting */ #!ifdef WITH_ACCDB modparam("acc", "db_flag", FLT_ACC) modparam("acc", "db_missed_flag", FLT_ACCMISSED) modparam("acc", "db_url", DBURL) modparam("acc", "db_extra",         "src_user=$fU;src_domain=$fd;src_ip=$si;"         "dst_ouser=$tU;dst_user=$rU;dst_domain=$rd") #!endif

# ----- usrloc params ----- /* enable DB persistency for location entries */ #!ifdef WITH_USRLOCDB modparam("usrloc", "db_url", DBURL) modparam("usrloc", "db_mode", 2) modparam("usrloc", "use_domain", MULTIDOMAIN) #!endif

# ----- auth_db params ----- #!ifdef WITH_AUTH modparam("auth_db", "db_url", DBURL) modparam("auth_db", "calculate_ha1", yes) modparam("auth_db", "password_column", "password") modparam("auth_db", "load_credentials", "") modparam("auth_db", "use_domain", MULTIDOMAIN)

# ----- permissions params ----- #!ifdef WITH_IPAUTH modparam("permissions", "db_url", DBURL) modparam("permissions", "db_mode", 1) #!endif

#!endif

# ----- alias_db params ----- #!ifdef WITH_ALIASDB modparam("alias_db", "db_url", DBURL) modparam("alias_db", "use_domain", MULTIDOMAIN) #!endif

# ----- speeddial params ----- #!ifdef WITH_SPEEDDIAL modparam("speeddial", "db_url", DBURL) modparam("speeddial", "use_domain", MULTIDOMAIN) #!endif

# ----- domain params ----- #!ifdef WITH_MULTIDOMAIN modparam("domain", "db_url", DBURL) # register callback to match myself condition with domains list modparam("domain", "register_myself", 1) #!endif

#!ifdef WITH_PRESENCE # ----- presence params ----- modparam("presence", "db_url", DBURL) modparam("presence", "server_address", "sip:a.b.c.d:port" ) modparam("presence", "send_fast_notify", 0) modparam("presence", "db_update_period", 20) modparam("presence", "clean_period", 40) modparam("presence", "subs_db_mode", 2) modparam("presence", "fetch_rows", 1000)

# ----- presence_xml params ----- modparam("presence_xml", "db_url", DBURL) modparam("presence_xml", "force_active", 1)

# ----- presence_dialoginfo params ----- modparam("presence_dialoginfo", "force_single_dialog", 0)

# ----- pua params ----- modparam("pua", "db_url", DBURL) modparam("pua", "db_mode", 2) modparam("pua", "update_period", 60) modparam("pua", "dlginfo_increase_version", 0) modparam("pua", "reginfo_increase_version", 0) modparam("pua", "check_remote_contact", 1) modparam("pua", "fetch_rows", 1000)

# ----- pua_dialoginfo params ----- modparam("pua_dialoginfo", "include_callid", 1) modparam("pua_dialoginfo", "send_publish_flag", FLT_DLGINFO) modparam("pua_dialoginfo", "caller_confirmed", 0) modparam("pua_dialoginfo", "include_tags", 1) modparam("pua_dialoginfo", "override_lifetime", 124)

#!endif

# ----- dialog params ----- modparam("dialog", "db_url", DBURL) modparam("dialog", "enable_stats", 1) modparam("dialog", "db_mode", 1) modparam("dialog", "dlg_flag", FLT_DLG)

#!ifdef WITH_NAT # ----- rtpproxy params ----- modparam("rtpproxy", "rtpproxy_sock", "udp:127.0.0.1:7722")

# ----- nathelper params ----- modparam("nathelper", "natping_interval", 30) modparam("nathelper", "ping_nated_only", 1) modparam("nathelper", "sipping_bflag", FLB_NATSIPPING) modparam("nathelper", "sipping_from", "sip:pinger@kamailio.org")

# params needed for NAT traversal in other modules modparam("nathelper|registrar", "received_avp", "$avp(RECEIVED)") modparam("usrloc", "nat_bflag", FLB_NATB) #!endif

#!ifdef WITH_TLS # ----- tls params ----- modparam("tls", "config", "/usr/local/etc/kamailio/tls.cfg") #!endif

#!ifdef WITH_ANTIFLOOD # ----- pike params ----- modparam("pike", "sampling_time_unit", 2) modparam("pike", "reqs_density_per_unit", 16) modparam("pike", "remove_latency", 4)

# ----- htable params ----- # ip ban htable with autoexpire after 5 minutes modparam("htable", "htable", "ipban=>size=8;autoexpire=300;") #!endif

#!ifdef WITH_XMLRPC # ----- xmlrpc params ----- modparam("xmlrpc", "route", "XMLRPC"); modparam("xmlrpc", "url_match", "^/RPC") #!endif

#!ifdef WITH_DEBUG # ----- debugger params ----- modparam("debugger", "cfgtrace", 1) modparam("debugger", "log_level_name", "exec") #!endif

####### Routing Logic ########

# Main SIP request routing logic # - processing of any incoming SIP request starts with this route # - note: this is the same as route { ... } request_route {

# per request initial checks         route(REQINIT);

# NAT detection         route(NATDETECT);

# CANCEL processing         if (is_method("CANCEL")) {                 if (t_check_trans()) {                         route(RELAY);                 }                 exit;         }

# handle requests within SIP dialogs         route(WITHINDLG);

### only initial requests (no To tag)

# handle retransmissions         if(t_precheck_trans()) {                 t_check_trans();                 exit;         }         t_check_trans();

# authentication         route(AUTH);

# record routing for dialog forming requests (in case they are routed)         # - remove preloaded route headers         remove_hf("Route");         if (is_method("INVITE|SUBSCRIBE"))                 record_route();

# account only INVITEs         if (is_method("INVITE")) {                 setflag(FLT_ACC); # do accounting         }

# dispatch requests to foreign domains         route(SIPOUT);

### requests for my local domains

# handle presence related requests         route(PRESENCE);

# handle registrations         route(REGISTRAR);

if ($rU==$null) {                 # request with no Username in RURI                 sl_send_reply("484","Address Incomplete");                 exit;         }

# dispatch destinations to PSTN         route(PSTN);

# user location service         route(LOCATION); }

# Wrapper for relaying requests route[RELAY] {

# enable additional event routes for forwarded requests         # - serial forking, RTP relaying handling, a.s.o.         if (is_method("INVITE|BYE|SUBSCRIBE|UPDATE")) {                 if(!t_is_set("branch_route")) t_on_branch("MANAGE_BRANCH");         }         if (is_method("INVITE|SUBSCRIBE|UPDATE")) {                 if(!t_is_set("onreply_route")) t_on_reply("MANAGE_REPLY");         }         if (is_method("INVITE")) {                 if(!t_is_set("failure_route")) t_on_failure("MANAGE_FAILURE");         }

if(is_method("INVITE|BYE|UPDATE|CANCEL|ACK")) {                 setflag(FLT_DLGINFO);                 dlg_manage();         }

if (!t_relay()) {                 sl_reply_error();         }         exit; }

# Per SIP request initial checks route[REQINIT] { #!ifdef WITH_ANTIFLOOD         # flood dection from same IP and traffic ban for a while         # be sure you exclude checking trusted peers, such as pstn gateways         # - local host excluded (e.g., loop to self)         if(src_ip!=myself) {                 if($sht(ipban=>$si)!=$null) {                         # ip is already blocked                         xdbg("request from blocked IP - $rm from $fu (IP:$si:$sp)\n");                         exit;                 }                 if (!pike_check_req()) {                         xlog("L_ALERT","ALERT: pike blocking $rm from $fu (IP:$si:$sp)\n");                         $sht(ipban=>$si) = 1;                         exit;                 }         }         if($ua =~ "friendly-scanner") {                 sl_send_reply("200", "OK");                 exit;         } #!endif

if (!mf_process_maxfwd_header("10")) {                 sl_send_reply("483","Too Many Hops");                 exit;         }

if(is_method("OPTIONS") && uri==myself && $rU==$null) {                 sl_send_reply("200","Keepalive");                 exit;         }

if(!sanity_check("1511", "7")) {                 xlog("Malformed SIP message from $si:$sp\n");                 exit;         } }

# Handle requests within SIP dialogs route[WITHINDLG] {         if (!has_totag()) return;

# sequential request withing a dialog should         # take the path determined by record-routing         if (loose_route()) {                 route(DLGURI);                 if (is_method("BYE")) {                         setflag(FLT_ACC); # do accounting ...                         setflag(FLT_ACCFAILED); # ... even if the transaction fails                 }                 else if ( is_method("ACK") ) {                         # ACK is forwarded statelessy                         route(NATMANAGE);                 }                 else if ( is_method("NOTIFY") ) {                         # Add Record-Route for in-dialog NOTIFY as per RFC 6665.                         record_route();                 }                 route(RELAY);                 exit;         }

if (is_method("SUBSCRIBE") && uri == myself) {                 # in-dialog subscribe requests                 route(PRESENCE);                 exit;         }         if ( is_method("ACK") ) {                 if ( t_check_trans() ) {                         # no loose-route, but stateful ACK;                         # must be an ACK after a 487                         # or e.g. 404 from upstream server                         route(RELAY);                         exit;                 } else {                         # ACK without matching transaction ... ignore and discard                         exit;                 }         }         sl_send_reply("404","Not here");         exit; }

# Handle SIP registrations route[REGISTRAR] {         if (!is_method("REGISTER")) return;

if(isflagset(FLT_NATS)) {                 setbflag(FLB_NATB); #!ifdef WITH_NATSIPPING                 # do SIP NAT pinging                 setbflag(FLB_NATSIPPING); #!endif         }         if (!save("location"))                 sl_reply_error();         exit; }

# User location service route[LOCATION] {

#!ifdef WITH_SPEEDDIAL         # search for short dialing - 2-digit extension         if($rU=~"^[0-9][0-9]$")                 if(sd_lookup("speed_dial"))                         route(SIPOUT); #!endif

#!ifdef WITH_ALIASDB         # search in DB-based aliases         if(alias_db_lookup("dbaliases"))                 route(SIPOUT); #!endif

$avp(oexten) = $rU;         if (!lookup("location")) {                 $var(rc) = $rc;                 route(TOVOICEMAIL);                 t_newtran();                 switch ($var(rc)) {                         case -1:                         case -3:                                 send_reply("404", "Not Found");                                 exit;                         case -2:                                 send_reply("405", "Method Not Allowed");                                 exit;                 }         }

# when routing via usrloc, log the missed calls also         if (is_method("INVITE")) {                 setflag(FLT_ACCMISSED);         }

route(RELAY);         exit; }

# Presence server processing route[PRESENCE] {         if(!is_method("PUBLISH|SUBSCRIBE"))                 return;

if(is_method("SUBSCRIBE") && $hdr(Event)=="message-summary") {                 route(TOVOICEMAIL);                 # returns here if no voicemail server is configured                 sl_send_reply("404", "No voicemail service");                 exit;         }

#!ifdef WITH_PRESENCE         if (!t_newtran()) {                 sl_reply_error();                 exit;         }

if(is_method("PUBLISH")) {                 handle_publish();                 t_release();         } else if(is_method("SUBSCRIBE")) {                 handle_subscribe();                 t_release();         }         exit; #!endif

# if presence enabled, this part will not be executed         if (is_method("PUBLISH") || $rU==$null) {                 sl_send_reply("404", "Not here");                 exit;         }         return; }

# IP authorization and user uthentication route[AUTH] { #!ifdef WITH_AUTH

#!ifdef WITH_IPAUTH         if((!is_method("REGISTER")) && allow_source_address()) {                 # source IP allowed                 return;         } #!endif

if (is_method("REGISTER") || from_uri==myself)         {                 # authenticate requests                 if (!auth_check("$fd", "subscriber", "1")) {                         auth_challenge("$fd", "0");                         exit;                 }                 # user authenticated - remove auth header                 if(!is_method("REGISTER|PUBLISH"))                         consume_credentials();         }         # if caller is not local subscriber, then check if it calls         # a local destination, otherwise deny, not an open relay here         if (from_uri!=myself && uri!=myself) {                 sl_send_reply("403","Not relaying");                 exit;         }

#!endif         return; }

# Caller NAT detection route[NATDETECT] { #!ifdef WITH_NAT         force_rport();         if (nat_uac_test("19")) {                 if (is_method("REGISTER")) {                         fix_nated_register();                 } else {                         if(is_first_hop())                                 set_contact_alias();                 }                 setflag(FLT_NATS);         } #!endif         return; }

# RTPProxy control and singaling updates for NAT traversal route[NATMANAGE] { #!ifdef WITH_NAT         if (is_request()) {                 if(has_totag()) {                         if(check_route_param("nat=yes")) {                                 setbflag(FLB_NATB);                         }                 }         }         if (!(isflagset(FLT_NATS) || isbflagset(FLB_NATB)))                 return;

rtpproxy_manage("co");

if (is_request()) {                 if (!has_totag()) {                         if(t_is_branch_route()) {                                 add_rr_param(";nat=yes");                         }                 }         }         if (is_reply()) {                 if(isbflagset(FLB_NATB)) {                         if(is_first_hop())                                 set_contact_alias();                 }         } #!endif         return; }

# URI update for dialog requests route[DLGURI] { #!ifdef WITH_NAT         if(!isdsturiset()) {                 handle_ruri_alias();         } #!endif         return; }

# Routing to foreign domains route[SIPOUT] {         if (uri==myself) return;

append_hf("P-hint: outbound\r\n");         route(RELAY);         exit; }

# PSTN GW routing route[PSTN] { #!ifdef WITH_PSTN         # check if PSTN GW IP is defined         if (strempty($sel(cfg_get.pstn.gw_ip))) {                 xlog("SCRIPT: PSTN rotuing enabled but pstn.gw_ip not defined\n");                 return;         }

# route to PSTN dialed numbers starting with '+' or '00'         #     (international format)         # - update the condition to match your dialing rules for PSTN routing         if(!($rU=~"^(+|00)[1-9][0-9]{3,20}$"))                 return;

# only local users allowed to call         if(from_uri!=myself) {                 sl_send_reply("403", "Not Allowed");                 exit;         }

if (strempty($sel(cfg_get.pstn.gw_port))) {                 $ru = "sip:" + $rU + "@" + $sel(cfg_get.pstn.gw_ip);         } else {                 $ru = "sip:" + $rU + "@" + $sel(cfg_get.pstn.gw_ip) + ":"                                         + $sel(cfg_get.pstn.gw_port);         }

route(RELAY);         exit; #!endif

return; }

# XMLRPC routing #!ifdef WITH_XMLRPC route[XMLRPC] {         # allow XMLRPC from localhost         if ((method=="POST" || method=="GET")                         && (src_ip==127.0.0.1)) {                 # close connection only for xmlrpclib user agents (there is a bug in                 # xmlrpclib: it waits for EOF before interpreting the response).                 if ($hdr(User-Agent) =~ "xmlrpclib")                         set_reply_close();                 set_reply_no_connect();                 dispatch_rpc();                 exit;         }         send_reply("403", "Forbidden");         exit; } #!endif

# Routing to voicemail server route[TOVOICEMAIL] { #!ifdef WITH_VOICEMAIL         if(!is_method("INVITE|SUBSCRIBE"))                 return;

# check if VoiceMail server IP is defined         if (strempty($sel(cfg_get.voicemail.srv_ip))) {                 xlog("SCRIPT: VoiceMail rotuing enabled but IP not defined\n");                 return;         }         if(is_method("INVITE")) {                 if($avp(oexten)==$null)                         return;                 $ru = "sip:" + $avp(oexten) + "@" + $sel(cfg_get.voicemail.srv_ip)                                 + ":" + $sel(cfg_get.voicemail.srv_port);         } else {                 if($rU==$null)                         return;                 $ru = "sip:" + $rU + "@" + $sel(cfg_get.voicemail.srv_ip)                                 + ":" + $sel(cfg_get.voicemail.srv_port);         }         route(RELAY);         exit; #!endif

return; }

# Manage outgoing branches branch_route[MANAGE_BRANCH] {         xdbg("new branch [$T_branch_idx] to $ru\n");         route(NATMANAGE); }

# Manage incoming replies onreply_route[MANAGE_REPLY] {         xdbg("incoming reply\n");         if(status=~"[12][0-9][0-9]")                 route(NATMANAGE); }

# Manage failure routing cases failure_route[MANAGE_FAILURE] {         route(NATMANAGE);

if (t_is_canceled()) {                 exit;         }

#!ifdef WITH_BLOCK3XX         # block call redirect based on 3xx replies.         if (t_check_status("3[0-9][0-9]")) {                 t_reply("404","Not found");                 exit;         } #!endif

#!ifdef WITH_VOICEMAIL         # serial forking         # - route to voicemail on busy or no answer (timeout)         if (t_check_status("486|408")) {                 $du = $null;                 route(TOVOICEMAIL);                 exit;         } #!endif }

======================

---

Kamailio - Users Mailing List - Non Commercial Discussions *sr-users@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe: *https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

---

Kamailio - Users Mailing List - Non Commercial Discussions

• sr-users@lists.kamailio.org

Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe:

• https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

So, an update...

I had captured a few packets with sngrep....

I then changed the IP address of the DNS records... In the first capture below, the DNS resolves to the IP address of the FreeSWITCH server and all is good.

I then changed the IP address of the DNS records again... In the second capture below, the DNS resolves to the IP address of the Kamailio server and BLFs fail....

The NOTIFY from Kamailio does not have the XML payload...AND, the contact does not have the username part (301 in this case)

THIS NOTIFY WORKS ------------------- 2021/09/30 20:59:29.433220 FREESWITCH_IP:PORT -> the.phone.ip.address:43368 NOTIFY sip:305@10.0.0.10:43368 SIP/2.0 Via: SIP/2.0/UDP FREESWITCH_IP:PORT;rport;branch=z9hG4bKXyXm7SvDt6caB Route: sip:the.phone.ip.address:43368;transport=udp Max-Forwards: 70 From: sip:301@DOMAIN.net:PORT;tag=GK4Tfhtm1Lxb To: sip:305@DOMAIN.net:PORT;tag=364451462 Call-ID: 1032455482-43368-6@BA.CEG.HG.GC CSeq: 1178818451 NOTIFY Contact: sip:301@FREESWITCH_IP:PORT User-Agent: FreeSWITCH-mod_sofia/1.10.6-release+git~20210325T131609Z~1ff9d0a60e~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Event: dialog Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Subscription-State: active;expires=60 Content-Type: application/dialog-info+xml Content-Length: 168

<?xml version="1.0"?> <dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="310" state="full" entity="sip:301@DOMAIN.net"> </dialog-info>

-------------------

THIS NOTIFY DOES NOT WORK ------------------- 2021/09/30 19:08:44.798682 KAMAILIO_IP:PORT -> the.phone.ip.address:45752 NOTIFY sip:305@10.0.0.10:45752 SIP/2.0 Via: SIP/2.0/UDP KAMAILIO_IP:PORT;branch=z9hG4bK5042.f8943dd4000000000000000000000000.0 To: sip:305@DOMAIN.net;tag=171346255 From: sip:301@DOMAIN.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-d60bd478 CSeq: 2 NOTIFY Call-ID: 489946038-45752-6@BA.CEG.HG.GC Content-Length: 0 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact: sip:KAMAILIO_IP:PORT Subscription-State: active;expires=60

-------------------

So, any ideas?????

On 2021-09-30 1:44 p.m., Jerry Kendall wrote:

So, a little change in status here:

The phones are setup to register as ext@domain.com

Kamailio has users defined as ext@domain.com

The users are registered AND can call each other without issues

I have a GXP2170 reg'd as 301@domain with BLF for 305@domain

I have a GXP1782 reg'd as 305@domain with BLF for 301@domain

I am running sngrep and see LOTS of SUBCRIBE from both phones with the following...

========================

SUBSCRIBE sip:301@test.example.com:port SIP/2.0 Via: SIP/2.0/UDP 10.0.0.62:53748;branch=z9hG4bK1748153224;rport From: sip:305@test.example.com:port;tag=1412038754 To: sip:301@test.example.com:port Call-ID: 1965507655-53748-9@BA.CEG.HG.GC CSeq: 20150 SUBSCRIBE Contact: sip:305@10.0.0.62:53748 X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP1782 1.0.1.116 Expires: 60 Supported: replaces, path, timer, eventlist Event: dialog Accept: application/dialog-info+xml,multipart/related,application/rlmi+xml Accept-Encoding: zlib+base64 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.0.0.62:53748;branch=z9hG4bK1748153224;rport=53748;received=184.148.140.29 From: sip:305@test.example.com:port;tag=1412038754 To: sip:301@test.example.com:port;tag=c814f383b05cbe6e319f0457961f79e4.13649c3f Call-ID: 1965507655-53748-9@BA.CEG.HG.GC CSeq: 20150 SUBSCRIBE Proxy-Authenticate: Digest realm="test.example.com", nonce="YVX2EmFV9OaLlg1aA240dgWcVgPq277p" Server: kamailio (5.4.4 (x86_64/linux)) Content-Length: 0

SUBSCRIBE sip:301@test.example.com:port SIP/2.0 Via: SIP/2.0/UDP 10.0.0.62:53748;branch=z9hG4bK1844436054;rport From: sip:305@test.example.com:port;tag=1412038754 To: sip:301@test.example.com:port Call-ID: 1965507655-53748-9@BA.CEG.HG.GC CSeq: 20151 SUBSCRIBE Contact: sip:305@10.0.0.62:53748 Proxy-Authorization: Digest username="305", realm="test.example.com", nonce="YVX2EmFV9OaLlg1aA240dgWcVgPq277p", uri="sip:301@test.example.com:port", response="177 b262785786c4b7c3aa68adf4246", algorithm=MD5 X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP1782 1.0.1.116 Expires: 60 Supported: replaces, path, timer, eventlist Event: dialog Accept: application/dialog-info+xml,multipart/related,application/rlmi+xml Accept-Encoding: zlib+base64 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

SIP/2.0 202 OK Via: SIP/2.0/UDP 10.0.0.62:53748;branch=z9hG4bK1844436054;rport=53748;received=184.148.140.29 From: sip:305@test.example.com:port;tag=1412038754 To: sip:301@test.example.com:port;tag=5e1ee053cf7168b9d3ee9c05740f837c-13649c3f Call-ID: 1965507655-53748-9@BA.CEG.HG.GC CSeq: 20151 SUBSCRIBE Expires: 60 Contact: sip:a.b.c.d:port;transport=udp Server: kamailio (5.4.4 (x86_64/linux)) Content-Length: 0

NOTIFY sip:305@10.0.0.62:53748 SIP/2.0 Via: SIP/2.0/UDP a.b.c.d:port;branch=z9hG4bKd05f.da500673000000000000000000000000.0 To: sip:305@test.example.com;tag=190302571 From: sip:301@test.example.com;tag=5e1ee053cf7168b9d3ee9c05740f837c-13649c3f CSeq: 2 NOTIFY Call-ID: 1965507655-53748-9@BA.CEG.HG.GC Content-Length: 0 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact: sip:a.b.c.d:port;transport=udp Subscription-State: terminated;reason=timeout

SIP/2.0 481 Subscription Does Not Exist Via: SIP/2.0/UDP a.b.c.d:port;branch=z9hG4bKd05f.da500673000000000000000000000000.0 From: sip:301@test.example.com;tag=5e1ee053cf7168b9d3ee9c05740f837c-13649c3f To: sip:305@test.example.com;tag=190302571 Call-ID: 1965507655-53748-9@BA.CEG.HG.GC CSeq: 2 NOTIFY Supported: replaces, path, timer User-Agent: Grandstream GXP1782 1.0.1.116 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

========================

On 2021-09-30 11:56 a.m., Jerry Kendall wrote:

...

Does anyone have some idea of what to look at ?

Jerry

On 9/29/2021 1:46 AM, Jerry Kendall wrote:

...

Forgot to add:

debian 11

Kamailio 5.4.4

Phones ARE behind NAT

Jerry

On 2021-09-29 1:35 a.m., Jerry Kendall wrote:

...

Hi there...

Trying to get the BLFs working... Not much luck

Registration works and device to device calls work but the BLFs dont....

Does anyone have a basic config where the BLFs work and calls work that I can try?

If not, can someone point me in the right direction?

I get LOTS of these types of NOTIFYs but never one with a body...

NOTIFY sip:305@10..10.10.223:34571 SIP/2.0 Via: SIP/2.0/UDP a.b.c.d:port;branch=z9hG4bKeb43.45649b07000000000000000000000000.0 To: sip:305@domain.net;tag=697604941 From: sip:301@domain.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-998270a1 CSeq: 2 NOTIFY Call-ID: 1023132872-34571-5@BA.CEG.HG.GC Content-Length: 0 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact: sip:a.b.c.d:port Subscription-State: terminated;reason=timeout

Below, this is my config taken from https://kb.asipto.com/kamailio:presence:k43-blf

============================

#!KAMAILIO

#!define WITH_MYSQL #!define WITH_AUTH #!define WITH_NAT #!define WITH_NATSIPPING #!define WITH_USRLOCDB #!define WITH_PRESENCE #!define WITH_MULTIDOMAIN

# # Kamailio (OpenSER) SIP Server v4.3 - default configuration script #     - web: http://www.kamailio.org #     - git: http://sip-router.org # # Direct your questions about this file to: sr-users@lists.sip-router.org # # Refer to the Core CookBook at http://www.kamailio.org/wiki/ # for an explanation of possible statements, functions and parameters. # # Several features can be enabled using '#!define WITH_FEATURE' directives: # # *** To run in debug mode: #     - define WITH_DEBUG # # *** To enable mysql: #     - define WITH_MYSQL # # *** To enable authentication execute: #     - enable mysql #     - define WITH_AUTH #     - add users using 'kamctl' # # *** To enable IP authentication execute: #     - enable mysql #     - enable authentication #     - define WITH_IPAUTH #     - add IP addresses with group id '1' to 'address' table # # *** To enable persistent user location execute: #     - enable mysql #     - define WITH_USRLOCDB # # *** To enable presence server execute: #     - enable mysql #     - define WITH_PRESENCE # # *** To enable nat traversal execute: #     - define WITH_NAT #     - install RTPProxy: http://www.rtpproxy.org #     - start RTPProxy: #        rtpproxy -l _your_public_ip_ -s udp:localhost:7722 #     - option for NAT SIP OPTIONS keepalives: WITH_NATSIPPING # # *** To enable PSTN gateway routing execute: #     - define WITH_PSTN #     - set the value of pstn.gw_ip #     - check route[PSTN] for regexp routing condition # # *** To enable database aliases lookup execute: #     - enable mysql #     - define WITH_ALIASDB # # *** To enable speed dial lookup execute: #     - enable mysql #     - define WITH_SPEEDDIAL # # *** To enable multi-domain support execute: #     - enable mysql #     - define WITH_MULTIDOMAIN # # *** To enable TLS support execute: #     - adjust CFGDIR/tls.cfg as needed #     - define WITH_TLS # # *** To enable XMLRPC support execute: #     - define WITH_XMLRPC #     - adjust route[XMLRPC] for access policy # # *** To enable anti-flood detection execute: #     - adjust pike and htable=>ipban settings as needed (default is #       block if more than 16 requests in 2 seconds and ban for 300 seconds) #     - define WITH_ANTIFLOOD # # *** To block 3XX redirect replies execute: #     - define WITH_BLOCK3XX # # *** To enable VoiceMail routing execute: #     - define WITH_VOICEMAIL #     - set the value of voicemail.srv_ip #     - adjust the value of voicemail.srv_port # # *** To enhance accounting execute: #     - enable mysql #     - define WITH_ACCDB #     - add following columns to database #!ifdef ACCDB_COMMENT   ALTER TABLE acc ADD COLUMN src_user VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE acc ADD COLUMN src_domain VARCHAR(128) NOT NULL DEFAULT '';   ALTER TABLE acc ADD COLUMN src_ip varchar(64) NOT NULL default '';   ALTER TABLE acc ADD COLUMN dst_ouser VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE acc ADD COLUMN dst_user VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE acc ADD COLUMN dst_domain VARCHAR(128) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN src_user VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN src_domain VARCHAR(128) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN src_ip varchar(64) NOT NULL default '';   ALTER TABLE missed_calls ADD COLUMN dst_ouser VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN dst_user VARCHAR(64) NOT NULL DEFAULT '';   ALTER TABLE missed_calls ADD COLUMN dst_domain VARCHAR(128) NOT NULL DEFAULT ''; #!endif

####### Include Local Config If Exists ######### import_file "kamailio-local.cfg"

####### Defined Values #########

# *** Value defines - IDs used later in config #!ifdef WITH_MYSQL # - database URL - used to connect to database server by modules such #       as: auth_db, acc, usrloc, a.s.o. #!ifndef DBURL #!trydef DBURL "mysql://user:passwd@127.0.0.1/kamailio" #!endif #!endif #!ifdef WITH_MULTIDOMAIN # - the value for 'use_domain' parameters #!define MULTIDOMAIN 1 #!else #!define MULTIDOMAIN 0 #!endif

# - flags #   FLT_ - per transaction (message) flags #       FLB_ - per branch flags #!define FLT_ACC 1 #!define FLT_ACCMISSED 2 #!define FLT_ACCFAILED 3 #!define FLT_NATS 5 #!define FLT_DLG 9 #!define FLT_DLGINFO 10

#!define FLB_NATB 6 #!define FLB_NATSIPPING 7

####### Global Parameters #########

### LOG Levels: 3=DBG, 2=INFO, 1=NOTICE, 0=WARN, -1=ERR #!ifdef WITH_DEBUG debug=4 log_stderror=yes #!else debug=2 log_stderror=no #!endif

memdbg=5 memlog=5

log_facility=LOG_LOCAL0

fork=yes children=4

/* uncomment the next line to disable TCP (default on) */ #disable_tcp=yes

/* uncomment the next line to disable the auto discovery of local aliases    based on reverse DNS on IPs (default on) */ #auto_aliases=no

/* add local domain aliases */ #alias="sip.mydomain.com"

/* uncomment and configure the following line if you want Kamailio to    bind on a specific interface/port/proto (default bind on all available) */ listen=udp:a.b.c.d:port

/* port to listen to  * - can be specified more than once if needed to listen on many ports */ port=8081

#!ifdef WITH_TLS enable_tls=yes #!endif

# life time of TCP connection when there is no traffic # - a bit higher than registration expires to cope with UA behind NAT tcp_connection_lifetime=3605

####### Custom Parameters #########

# These parameters can be modified runtime via RPC interface # - see the documentation of 'cfg_rpc' module. # # Format: group.id = value 'desc' description # Access: $sel(cfg_get.group.id) or @cfg_get.group.id #

#!ifdef WITH_PSTN # PSTN GW Routing # # - pstn.gw_ip: valid IP or hostname as string value, example: # pstn.gw_ip = "10.0.0.101" desc "My PSTN GW Address" # # - by default is empty to avoid misrouting pstn.gw_ip = "" desc "PSTN GW Address" pstn.gw_port = "" desc "PSTN GW Port" #!endif

#!ifdef WITH_VOICEMAIL # VoiceMail Routing on offline, busy or no answer # # - by default Voicemail server IP is empty to avoid misrouting voicemail.srv_ip = "" desc "VoiceMail IP Address" voicemail.srv_port = "5060" desc "VoiceMail Port" #!endif

####### Modules Section ########

/* set paths to location of modules */ # mpath="/usr/lib/x86_64-linux-gnu/kamailio/modules/"

#!ifdef WITH_MYSQL loadmodule "db_mysql.so" #!endif

loadmodule "jsonrpcs.so" loadmodule "kex.so" loadmodule "corex.so" loadmodule "tm.so" loadmodule "tmx.so" loadmodule "sl.so" loadmodule "rr.so" loadmodule "pv.so" loadmodule "maxfwd.so" loadmodule "usrloc.so" loadmodule "registrar.so" loadmodule "textops.so" loadmodule "siputils.so" loadmodule "xlog.so" loadmodule "sanity.so" loadmodule "ctl.so" loadmodule "cfg_rpc.so" loadmodule "acc.so"

#!ifdef WITH_AUTH loadmodule "auth.so" loadmodule "auth_db.so" #!ifdef WITH_IPAUTH loadmodule "permissions.so" #!endif #!endif

#!ifdef WITH_ALIASDB loadmodule "alias_db.so" #!endif

#!ifdef WITH_SPEEDDIAL loadmodule "speeddial.so" #!endif

#!ifdef WITH_MULTIDOMAIN loadmodule "domain.so" #!endif

#!ifdef WITH_PRESENCE loadmodule "presence.so" loadmodule "presence_xml.so" loadmodule "presence_dialoginfo.so" loadmodule "pua.so" loadmodule "pua_dialoginfo.so"

#!endif

#!ifdef WITH_NAT loadmodule "nathelper.so" loadmodule "rtpproxy.so" #!endif

#!ifdef WITH_TLS loadmodule "tls.so" #!endif

#!ifdef WITH_ANTIFLOOD loadmodule "htable.so" loadmodule "pike.so" #!endif

#!ifdef WITH_XMLRPC loadmodule "xmlrpc.so" #!endif

#!ifdef WITH_DEBUG loadmodule "debugger.so" #!endif

loadmodule "dialog.so"

# ----------------- setting module-specific parameters ---------------

# ----- tm params ----- # auto-discard branches from previous serial forking leg modparam("tm", "failure_reply_mode", 3) # default retransmission timeout: 30sec modparam("tm", "fr_timer", 30000) # default invite retransmission timeout after 1xx: 120sec modparam("tm", "fr_inv_timer", 120000)

# ----- rr params ----- # set next param to 1 to add value to ;lr param (helps with some UAs) modparam("rr", "enable_full_lr", 0) # do not append from tag to the RR (no need for this script) modparam("rr", "append_fromtag", 0)

# ----- registrar params ----- modparam("registrar", "method_filtering", 1) /* uncomment the next line to disable parallel forking via location */ # modparam("registrar", "append_branches", 0) /* uncomment the next line not to allow more than 10 contacts per AOR */ #modparam("registrar", "max_contacts", 10) # max value for expires of registrations modparam("registrar", "max_expires", 3600) # set it to 1 to enable GRUU modparam("registrar", "gruu_enabled", 0)

# ----- acc params ----- /* what special events should be accounted ? */ modparam("acc", "early_media", 0) modparam("acc", "report_ack", 0) modparam("acc", "report_cancels", 0) /* by default ww do not adjust the direct of the sequential requests.    if you enable this parameter, be sure the enable "append_fromtag"    in "rr" module */ modparam("acc", "detect_direction", 0) /* account triggers (flags) */ modparam("acc", "log_flag", FLT_ACC) modparam("acc", "log_missed_flag", FLT_ACCMISSED) modparam("acc", "log_extra",         "src_user=$fU;src_domain=$fd;src_ip=$si;"         "dst_ouser=$tU;dst_user=$rU;dst_domain=$rd") modparam("acc", "failed_transaction_flag", FLT_ACCFAILED) /* enhanced DB accounting */ #!ifdef WITH_ACCDB modparam("acc", "db_flag", FLT_ACC) modparam("acc", "db_missed_flag", FLT_ACCMISSED) modparam("acc", "db_url", DBURL) modparam("acc", "db_extra",         "src_user=$fU;src_domain=$fd;src_ip=$si;"         "dst_ouser=$tU;dst_user=$rU;dst_domain=$rd") #!endif

# ----- usrloc params ----- /* enable DB persistency for location entries */ #!ifdef WITH_USRLOCDB modparam("usrloc", "db_url", DBURL) modparam("usrloc", "db_mode", 2) modparam("usrloc", "use_domain", MULTIDOMAIN) #!endif

# ----- auth_db params ----- #!ifdef WITH_AUTH modparam("auth_db", "db_url", DBURL) modparam("auth_db", "calculate_ha1", yes) modparam("auth_db", "password_column", "password") modparam("auth_db", "load_credentials", "") modparam("auth_db", "use_domain", MULTIDOMAIN)

# ----- permissions params ----- #!ifdef WITH_IPAUTH modparam("permissions", "db_url", DBURL) modparam("permissions", "db_mode", 1) #!endif

#!endif

# ----- alias_db params ----- #!ifdef WITH_ALIASDB modparam("alias_db", "db_url", DBURL) modparam("alias_db", "use_domain", MULTIDOMAIN) #!endif

# ----- speeddial params ----- #!ifdef WITH_SPEEDDIAL modparam("speeddial", "db_url", DBURL) modparam("speeddial", "use_domain", MULTIDOMAIN) #!endif

# ----- domain params ----- #!ifdef WITH_MULTIDOMAIN modparam("domain", "db_url", DBURL) # register callback to match myself condition with domains list modparam("domain", "register_myself", 1) #!endif

#!ifdef WITH_PRESENCE # ----- presence params ----- modparam("presence", "db_url", DBURL) modparam("presence", "server_address", "sip:a.b.c.d:port" ) modparam("presence", "send_fast_notify", 0) modparam("presence", "db_update_period", 20) modparam("presence", "clean_period", 40) modparam("presence", "subs_db_mode", 2) modparam("presence", "fetch_rows", 1000)

# ----- presence_xml params ----- modparam("presence_xml", "db_url", DBURL) modparam("presence_xml", "force_active", 1)

# ----- presence_dialoginfo params ----- modparam("presence_dialoginfo", "force_single_dialog", 0)

# ----- pua params ----- modparam("pua", "db_url", DBURL) modparam("pua", "db_mode", 2) modparam("pua", "update_period", 60) modparam("pua", "dlginfo_increase_version", 0) modparam("pua", "reginfo_increase_version", 0) modparam("pua", "check_remote_contact", 1) modparam("pua", "fetch_rows", 1000)

# ----- pua_dialoginfo params ----- modparam("pua_dialoginfo", "include_callid", 1) modparam("pua_dialoginfo", "send_publish_flag", FLT_DLGINFO) modparam("pua_dialoginfo", "caller_confirmed", 0) modparam("pua_dialoginfo", "include_tags", 1) modparam("pua_dialoginfo", "override_lifetime", 124)

#!endif

# ----- dialog params ----- modparam("dialog", "db_url", DBURL) modparam("dialog", "enable_stats", 1) modparam("dialog", "db_mode", 1) modparam("dialog", "dlg_flag", FLT_DLG)

#!ifdef WITH_NAT # ----- rtpproxy params ----- modparam("rtpproxy", "rtpproxy_sock", "udp:127.0.0.1:7722")

# ----- nathelper params ----- modparam("nathelper", "natping_interval", 30) modparam("nathelper", "ping_nated_only", 1) modparam("nathelper", "sipping_bflag", FLB_NATSIPPING) modparam("nathelper", "sipping_from", "sip:pinger@kamailio.org")

# params needed for NAT traversal in other modules modparam("nathelper|registrar", "received_avp", "$avp(RECEIVED)") modparam("usrloc", "nat_bflag", FLB_NATB) #!endif

#!ifdef WITH_TLS # ----- tls params ----- modparam("tls", "config", "/usr/local/etc/kamailio/tls.cfg") #!endif

#!ifdef WITH_ANTIFLOOD # ----- pike params ----- modparam("pike", "sampling_time_unit", 2) modparam("pike", "reqs_density_per_unit", 16) modparam("pike", "remove_latency", 4)

# ----- htable params ----- # ip ban htable with autoexpire after 5 minutes modparam("htable", "htable", "ipban=>size=8;autoexpire=300;") #!endif

#!ifdef WITH_XMLRPC # ----- xmlrpc params ----- modparam("xmlrpc", "route", "XMLRPC"); modparam("xmlrpc", "url_match", "^/RPC") #!endif

#!ifdef WITH_DEBUG # ----- debugger params ----- modparam("debugger", "cfgtrace", 1) modparam("debugger", "log_level_name", "exec") #!endif

####### Routing Logic ########

# Main SIP request routing logic # - processing of any incoming SIP request starts with this route # - note: this is the same as route { ... } request_route {

# per request initial checks         route(REQINIT);

# NAT detection         route(NATDETECT);

# CANCEL processing         if (is_method("CANCEL")) {                 if (t_check_trans()) {                         route(RELAY);                 }                 exit;         }

# handle requests within SIP dialogs         route(WITHINDLG);

### only initial requests (no To tag)

# handle retransmissions         if(t_precheck_trans()) {                 t_check_trans();                 exit;         }         t_check_trans();

# authentication         route(AUTH);

# record routing for dialog forming requests (in case they are routed)         # - remove preloaded route headers         remove_hf("Route");         if (is_method("INVITE|SUBSCRIBE"))                 record_route();

# account only INVITEs         if (is_method("INVITE")) {                 setflag(FLT_ACC); # do accounting         }

# dispatch requests to foreign domains         route(SIPOUT);

### requests for my local domains

# handle presence related requests         route(PRESENCE);

# handle registrations         route(REGISTRAR);

if ($rU==$null) {                 # request with no Username in RURI                 sl_send_reply("484","Address Incomplete");                 exit;         }

# dispatch destinations to PSTN         route(PSTN);

# user location service         route(LOCATION); }

# Wrapper for relaying requests route[RELAY] {

# enable additional event routes for forwarded requests         # - serial forking, RTP relaying handling, a.s.o.         if (is_method("INVITE|BYE|SUBSCRIBE|UPDATE")) {                 if(!t_is_set("branch_route")) t_on_branch("MANAGE_BRANCH");         }         if (is_method("INVITE|SUBSCRIBE|UPDATE")) {                 if(!t_is_set("onreply_route")) t_on_reply("MANAGE_REPLY");         }         if (is_method("INVITE")) {                 if(!t_is_set("failure_route")) t_on_failure("MANAGE_FAILURE");         }

if(is_method("INVITE|BYE|UPDATE|CANCEL|ACK")) {                 setflag(FLT_DLGINFO);                 dlg_manage();         }

if (!t_relay()) {                 sl_reply_error();         }         exit; }

# Per SIP request initial checks route[REQINIT] { #!ifdef WITH_ANTIFLOOD         # flood dection from same IP and traffic ban for a while         # be sure you exclude checking trusted peers, such as pstn gateways         # - local host excluded (e.g., loop to self)         if(src_ip!=myself) {                 if($sht(ipban=>$si)!=$null) {                         # ip is already blocked                         xdbg("request from blocked IP - $rm from $fu (IP:$si:$sp)\n");                         exit;                 }                 if (!pike_check_req()) {                         xlog("L_ALERT","ALERT: pike blocking $rm from $fu (IP:$si:$sp)\n");                         $sht(ipban=>$si) = 1;                         exit;                 }         }         if($ua =~ "friendly-scanner") {                 sl_send_reply("200", "OK");                 exit;         } #!endif

if (!mf_process_maxfwd_header("10")) {                 sl_send_reply("483","Too Many Hops");                 exit;         }

if(is_method("OPTIONS") && uri==myself && $rU==$null) {                 sl_send_reply("200","Keepalive");                 exit;         }

if(!sanity_check("1511", "7")) {                 xlog("Malformed SIP message from $si:$sp\n");                 exit;         } }

# Handle requests within SIP dialogs route[WITHINDLG] {         if (!has_totag()) return;

# sequential request withing a dialog should         # take the path determined by record-routing         if (loose_route()) {                 route(DLGURI);                 if (is_method("BYE")) {                         setflag(FLT_ACC); # do accounting ...                         setflag(FLT_ACCFAILED); # ... even if the transaction fails                 }                 else if ( is_method("ACK") ) {                         # ACK is forwarded statelessy                         route(NATMANAGE);                 }                 else if ( is_method("NOTIFY") ) {                         # Add Record-Route for in-dialog NOTIFY as per RFC 6665.                         record_route();                 }                 route(RELAY);                 exit;         }

if (is_method("SUBSCRIBE") && uri == myself) {                 # in-dialog subscribe requests                 route(PRESENCE);                 exit;         }         if ( is_method("ACK") ) {                 if ( t_check_trans() ) {                         # no loose-route, but stateful ACK;                         # must be an ACK after a 487                         # or e.g. 404 from upstream server                         route(RELAY);                         exit;                 } else {                         # ACK without matching transaction ... ignore and discard                         exit;                 }         }         sl_send_reply("404","Not here");         exit; }

# Handle SIP registrations route[REGISTRAR] {         if (!is_method("REGISTER")) return;

if(isflagset(FLT_NATS)) {                 setbflag(FLB_NATB); #!ifdef WITH_NATSIPPING                 # do SIP NAT pinging                 setbflag(FLB_NATSIPPING); #!endif         }         if (!save("location"))                 sl_reply_error();         exit; }

# User location service route[LOCATION] {

#!ifdef WITH_SPEEDDIAL         # search for short dialing - 2-digit extension         if($rU=~"^[0-9][0-9]$")                 if(sd_lookup("speed_dial"))                         route(SIPOUT); #!endif

#!ifdef WITH_ALIASDB         # search in DB-based aliases         if(alias_db_lookup("dbaliases"))                 route(SIPOUT); #!endif

$avp(oexten) = $rU;         if (!lookup("location")) {                 $var(rc) = $rc;                 route(TOVOICEMAIL);                 t_newtran();                 switch ($var(rc)) {                         case -1:                         case -3:                                 send_reply("404", "Not Found");                                 exit;                         case -2:                                 send_reply("405", "Method Not Allowed");                                 exit;                 }         }

# when routing via usrloc, log the missed calls also         if (is_method("INVITE")) {                 setflag(FLT_ACCMISSED);         }

route(RELAY);         exit; }

# Presence server processing route[PRESENCE] {         if(!is_method("PUBLISH|SUBSCRIBE"))                 return;

if(is_method("SUBSCRIBE") && $hdr(Event)=="message-summary") {                 route(TOVOICEMAIL);                 # returns here if no voicemail server is configured                 sl_send_reply("404", "No voicemail service");                 exit;         }

#!ifdef WITH_PRESENCE         if (!t_newtran()) {                 sl_reply_error();                 exit;         }

if(is_method("PUBLISH")) {                 handle_publish();                 t_release();         } else if(is_method("SUBSCRIBE")) {                 handle_subscribe();                 t_release();         }         exit; #!endif

# if presence enabled, this part will not be executed         if (is_method("PUBLISH") || $rU==$null) {                 sl_send_reply("404", "Not here");                 exit;         }         return; }

# IP authorization and user uthentication route[AUTH] { #!ifdef WITH_AUTH

#!ifdef WITH_IPAUTH         if((!is_method("REGISTER")) && allow_source_address()) {                 # source IP allowed                 return;         } #!endif

if (is_method("REGISTER") || from_uri==myself)         {                 # authenticate requests                 if (!auth_check("$fd", "subscriber", "1")) {                         auth_challenge("$fd", "0");                         exit;                 }                 # user authenticated - remove auth header                 if(!is_method("REGISTER|PUBLISH"))                         consume_credentials();         }         # if caller is not local subscriber, then check if it calls         # a local destination, otherwise deny, not an open relay here         if (from_uri!=myself && uri!=myself) {                 sl_send_reply("403","Not relaying");                 exit;         }

#!endif         return; }

# Caller NAT detection route[NATDETECT] { #!ifdef WITH_NAT         force_rport();         if (nat_uac_test("19")) {                 if (is_method("REGISTER")) {                         fix_nated_register();                 } else {                         if(is_first_hop())                                 set_contact_alias();                 }                 setflag(FLT_NATS);         } #!endif         return; }

# RTPProxy control and singaling updates for NAT traversal route[NATMANAGE] { #!ifdef WITH_NAT         if (is_request()) {                 if(has_totag()) {                         if(check_route_param("nat=yes")) {                                 setbflag(FLB_NATB);                         }                 }         }         if (!(isflagset(FLT_NATS) || isbflagset(FLB_NATB)))                 return;

rtpproxy_manage("co");

if (is_request()) {                 if (!has_totag()) {                         if(t_is_branch_route()) {                                 add_rr_param(";nat=yes");                         }                 }         }         if (is_reply()) {                 if(isbflagset(FLB_NATB)) {                         if(is_first_hop())                                 set_contact_alias();                 }         } #!endif         return; }

# URI update for dialog requests route[DLGURI] { #!ifdef WITH_NAT         if(!isdsturiset()) {                 handle_ruri_alias();         } #!endif         return; }

# Routing to foreign domains route[SIPOUT] {         if (uri==myself) return;

append_hf("P-hint: outbound\r\n");         route(RELAY);         exit; }

# PSTN GW routing route[PSTN] { #!ifdef WITH_PSTN         # check if PSTN GW IP is defined         if (strempty($sel(cfg_get.pstn.gw_ip))) {                 xlog("SCRIPT: PSTN rotuing enabled but pstn.gw_ip not defined\n");                 return;         }

# route to PSTN dialed numbers starting with '+' or '00'         #     (international format)         # - update the condition to match your dialing rules for PSTN routing         if(!($rU=~"^(+|00)[1-9][0-9]{3,20}$"))                 return;

# only local users allowed to call         if(from_uri!=myself) {                 sl_send_reply("403", "Not Allowed");                 exit;         }

if (strempty($sel(cfg_get.pstn.gw_port))) {                 $ru = "sip:" + $rU + "@" + $sel(cfg_get.pstn.gw_ip);         } else {                 $ru = "sip:" + $rU + "@" + $sel(cfg_get.pstn.gw_ip)

• ":"

+ $sel(cfg_get.pstn.gw_port);         }

route(RELAY);         exit; #!endif

return; }

# XMLRPC routing #!ifdef WITH_XMLRPC route[XMLRPC] {         # allow XMLRPC from localhost         if ((method=="POST" || method=="GET")                         && (src_ip==127.0.0.1)) {                 # close connection only for xmlrpclib user agents (there is a bug in                 # xmlrpclib: it waits for EOF before interpreting the response).                 if ($hdr(User-Agent) =~ "xmlrpclib")                         set_reply_close();                 set_reply_no_connect();                 dispatch_rpc();                 exit;         }         send_reply("403", "Forbidden");         exit; } #!endif

# Routing to voicemail server route[TOVOICEMAIL] { #!ifdef WITH_VOICEMAIL         if(!is_method("INVITE|SUBSCRIBE"))                 return;

# check if VoiceMail server IP is defined         if (strempty($sel(cfg_get.voicemail.srv_ip))) {                 xlog("SCRIPT: VoiceMail rotuing enabled but IP not defined\n");                 return;         }         if(is_method("INVITE")) {                 if($avp(oexten)==$null)                         return;                 $ru = "sip:" + $avp(oexten) + "@" + $sel(cfg_get.voicemail.srv_ip)                                 + ":" + $sel(cfg_get.voicemail.srv_port);         } else {                 if($rU==$null)                         return;                 $ru = "sip:" + $rU + "@" + $sel(cfg_get.voicemail.srv_ip)                                 + ":" + $sel(cfg_get.voicemail.srv_port);         }         route(RELAY);         exit; #!endif

return; }

# Manage outgoing branches branch_route[MANAGE_BRANCH] {         xdbg("new branch [$T_branch_idx] to $ru\n");         route(NATMANAGE); }

# Manage incoming replies onreply_route[MANAGE_REPLY] {         xdbg("incoming reply\n");         if(status=~"[12][0-9][0-9]")                 route(NATMANAGE); }

# Manage failure routing cases failure_route[MANAGE_FAILURE] {         route(NATMANAGE);

if (t_is_canceled()) {                 exit;         }

#!ifdef WITH_BLOCK3XX         # block call redirect based on 3xx replies.         if (t_check_status("3[0-9][0-9]")) {                 t_reply("404","Not found");                 exit;         } #!endif

#!ifdef WITH_VOICEMAIL         # serial forking         # - route to voicemail on busy or no answer (timeout)         if (t_check_status("486|408")) {                 $du = $null;                 route(TOVOICEMAIL);                 exit;         } #!endif }

======================

---

Kamailio - Users Mailing List - Non Commercial Discussions    *sr-users@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe: *https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

---

Kamailio - Users Mailing List - Non Commercial Discussions    * sr-users@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe:    * https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

---

Kamailio - Users Mailing List - Non Commercial Discussions  * sr-users@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe:  * https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

---

Kamailio - Users Mailing List - Non Commercial Discussions  * sr-users@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe:  * https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

Hello,

if you haven't done it so far, run kamailio with debug=3 in kamailio.cfg, there are a lot of debug messages printed by presence modules that should give hints of what happens in the c code, which can help to figure out what is wrong or what params have to be configured.

Cheers, Daniel

On 01.10.21 04:10, Jerry Kendall wrote:

Some promising events...

Added:

# ----- presence_dialoginfo params ----- modparam("presence_dialoginfo", "force_single_dialog", 1)       # JTK modparam("presence_dialoginfo", "force_dummy_dialog", 1)        # JTK

Now at least a few of the phones are green BLFs that turn/flash red as appropriate...

Time for more testing...

On 2021-09-30 9:08 p.m., Jerry Kendall wrote:

...

So, an update...

I had captured a few packets with sngrep....

I then changed the IP address of the DNS records... In the first capture below, the DNS resolves to the IP address of the FreeSWITCH server and all is good.

I then changed the IP address of the DNS records again... In the second capture below, the DNS resolves to the IP address of the Kamailio server and BLFs fail....

The NOTIFY from Kamailio does not have the XML payload...AND, the contact does not have the username part (301 in this case)

THIS NOTIFY WORKS

2021/09/30 20:59:29.433220 FREESWITCH_IP:PORT -> the.phone.ip.address:43368 NOTIFY sip:305@10.0.0.10:43368 SIP/2.0 Via: SIP/2.0/UDP FREESWITCH_IP:PORT;rport;branch=z9hG4bKXyXm7SvDt6caB Route: sip:the.phone.ip.address:43368;transport=udp Max-Forwards: 70 From: sip:301@DOMAIN.net:PORT;tag=GK4Tfhtm1Lxb To: sip:305@DOMAIN.net:PORT;tag=364451462 Call-ID: 1032455482-43368-6@BA.CEG.HG.GC CSeq: 1178818451 NOTIFY Contact: sip:301@FREESWITCH_IP:PORT User-Agent: FreeSWITCH-mod_sofia/1.10.6-release+git~20210325T131609Z~1ff9d0a60e~64bit Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, path, replaces Event: dialog Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Subscription-State: active;expires=60 Content-Type: application/dialog-info+xml Content-Length: 168

<?xml version="1.0"?>

<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="310" state="full" entity="sip:301@DOMAIN.net">

</dialog-info>

---

THIS NOTIFY DOES NOT WORK

2021/09/30 19:08:44.798682 KAMAILIO_IP:PORT -> the.phone.ip.address:45752 NOTIFY sip:305@10.0.0.10:45752 SIP/2.0 Via: SIP/2.0/UDP KAMAILIO_IP:PORT;branch=z9hG4bK5042.f8943dd4000000000000000000000000.0 To: sip:305@DOMAIN.net;tag=171346255 From: sip:301@DOMAIN.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-d60bd478 CSeq: 2 NOTIFY Call-ID: 489946038-45752-6@BA.CEG.HG.GC Content-Length: 0 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact: sip:KAMAILIO_IP:PORT Subscription-State: active;expires=60

---

So, any ideas?????

On 2021-09-30 1:44 p.m., Jerry Kendall wrote:

...

So, a little change in status here:

The phones are setup to register as ext@domain.com

Kamailio has users defined as ext@domain.com

The users are registered AND can call each other without issues

I have a GXP2170 reg'd as 301@domain with BLF for 305@domain

I have a GXP1782 reg'd as 305@domain with BLF for 301@domain

I am running sngrep and see LOTS of SUBCRIBE from both phones with the following...

========================

SUBSCRIBE sip:301@test.example.com:port SIP/2.0 Via: SIP/2.0/UDP 10.0.0.62:53748;branch=z9hG4bK1748153224;rport From: sip:305@test.example.com:port;tag=1412038754 To: sip:301@test.example.com:port Call-ID: 1965507655-53748-9@BA.CEG.HG.GC CSeq: 20150 SUBSCRIBE Contact: sip:305@10.0.0.62:53748 X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP1782 1.0.1.116 Expires: 60 Supported: replaces, path, timer, eventlist Event: dialog Accept: application/dialog-info+xml,multipart/related,application/rlmi+xml Accept-Encoding: zlib+base64 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 10.0.0.62:53748;branch=z9hG4bK1748153224;rport=53748;received=184.148.140.29 From: sip:305@test.example.com:port;tag=1412038754 To: sip:301@test.example.com:port;tag=c814f383b05cbe6e319f0457961f79e4.13649c3f Call-ID: 1965507655-53748-9@BA.CEG.HG.GC CSeq: 20150 SUBSCRIBE Proxy-Authenticate: Digest realm="test.example.com", nonce="YVX2EmFV9OaLlg1aA240dgWcVgPq277p" Server: kamailio (5.4.4 (x86_64/linux)) Content-Length: 0

SUBSCRIBE sip:301@test.example.com:port SIP/2.0 Via: SIP/2.0/UDP 10.0.0.62:53748;branch=z9hG4bK1844436054;rport From: sip:305@test.example.com:port;tag=1412038754 To: sip:301@test.example.com:port Call-ID: 1965507655-53748-9@BA.CEG.HG.GC CSeq: 20151 SUBSCRIBE Contact: sip:305@10.0.0.62:53748 Proxy-Authorization: Digest username="305", realm="test.example.com", nonce="YVX2EmFV9OaLlg1aA240dgWcVgPq277p", uri="sip:301@test.example.com:port", response="177 b262785786c4b7c3aa68adf4246", algorithm=MD5 X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP1782 1.0.1.116 Expires: 60 Supported: replaces, path, timer, eventlist Event: dialog Accept: application/dialog-info+xml,multipart/related,application/rlmi+xml Accept-Encoding: zlib+base64 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

SIP/2.0 202 OK Via: SIP/2.0/UDP 10.0.0.62:53748;branch=z9hG4bK1844436054;rport=53748;received=184.148.140.29 From: sip:305@test.example.com:port;tag=1412038754 To: sip:301@test.example.com:port;tag=5e1ee053cf7168b9d3ee9c05740f837c-13649c3f Call-ID: 1965507655-53748-9@BA.CEG.HG.GC CSeq: 20151 SUBSCRIBE Expires: 60 Contact: sip:a.b.c.d:port;transport=udp Server: kamailio (5.4.4 (x86_64/linux)) Content-Length: 0

NOTIFY sip:305@10.0.0.62:53748 SIP/2.0 Via: SIP/2.0/UDP a.b.c.d:port;branch=z9hG4bKd05f.da500673000000000000000000000000.0 To: sip:305@test.example.com;tag=190302571 From: sip:301@test.example.com;tag=5e1ee053cf7168b9d3ee9c05740f837c-13649c3f CSeq: 2 NOTIFY Call-ID: 1965507655-53748-9@BA.CEG.HG.GC Content-Length: 0 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact: sip:a.b.c.d:port;transport=udp Subscription-State: terminated;reason=timeout

SIP/2.0 481 Subscription Does Not Exist Via: SIP/2.0/UDP a.b.c.d:port;branch=z9hG4bKd05f.da500673000000000000000000000000.0 From: sip:301@test.example.com;tag=5e1ee053cf7168b9d3ee9c05740f837c-13649c3f To: sip:305@test.example.com;tag=190302571 Call-ID: 1965507655-53748-9@BA.CEG.HG.GC CSeq: 2 NOTIFY Supported: replaces, path, timer User-Agent: Grandstream GXP1782 1.0.1.116 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

========================

On 2021-09-30 11:56 a.m., Jerry Kendall wrote:

...

Does anyone have some idea of what to look at ?

Jerry

[...]

Hey Daniel,

here is the (hopefully) sanitized syslog parts around the BLF issue.

I do appreciate your insights here..

------------------

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/udp_server.c:493]: udp_rcv_loop(): received on udp socket: (106/100/726) [[SUBSCRIBE sip:301@domain.net:PORT SIP/2.0 0D  0A Via: SIP/2.0/UDP PRIVATE_IP:44762;br]] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:611]: parse_msg():  method:  <SUBSCRIBE> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:613]: parse_msg():  uri:     sip:301@domain.net:PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:615]: parse_msg():  version: <SIP/2.0> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/parse_via.c:1300]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK1251409407>; state=6 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/parse_via.c:1300]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=17 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:170]: get_hdr_field(): <To> [46]; uri=[sip:301@domain.net:PORT] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body (46)[sip:301@domain.net:PORT#015#012], to tag (0)[] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:150]: get_hdr_field(): cseq <CSeq>: <21000> <SUBSCRIBE> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/receive.c:319]: receive_msg(): --- received sip message - request - call-id: [551473572-44762-6@BA.CEG.HG.GC] - cseq: [21000 SUBSCRIBE] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/receive.c:379]: receive_msg(): preparing to run routing scripts... Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=533 a=5 n=route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=759 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=25 n=mf_process_maxfwd_header Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=764 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=759 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=768 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=764 a=26 n=sanity_check Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:247]: check_ruri_sip_version(): check_ruri_sip_version entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:270]: check_ruri_sip_version(): check_ruri_sip_version passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:277]: check_ruri_scheme(): check_ruri_scheme entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:297]: check_ruri_scheme(): check_ruri_scheme passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:318]: check_required_headers(): check_required_headers entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:340]: check_required_headers(): check_required_headers passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:348]: check_via1_header(): check via1 header Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:506]: check_cseq_method(): check_cseq_method entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:539]: check_cseq_method(): check_cseq_method passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:548]: check_cseq_value(): check_cseq_value entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:576]: check_cseq_value(): check_cseq_value passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:585]: check_cl(): check_cl entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:610]: check_cl(): check_cl passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:622]: check_expires_value(): check_expires_value entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:657]: check_expires_value(): check_expires_value passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:759]: check_parse_uris(): check_parse_uris entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:763]: check_parse_uris(): parsing ruri Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:777]: check_parse_uris(): looking up From header Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:791]: check_parse_uris(): parsing From header Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=138358676 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:817]: check_parse_uris(): parsing From URI Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:835]: check_parse_uris(): looking up To header Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:857]: check_parse_uris(): check_parse_uris(): parsing To URI Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:902]: check_parse_uris(): check_parse_uris passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sanity [sanity_mod.c:254]: w_sanity_check(): sanity checks result: 1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=536 a=5 n=route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=952 a=55 n=force_rport Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=965 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=953 a=25 n=nat_uac_test Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/msg_translator.c:161]: check_via_address(): (184.148.140.29, PRIVATE_IP, 0) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=954 a=25 n=xinfo Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=961 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=955 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=961 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=958 a=24 n=is_first_hop Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} siputils [sipops.c:159]: is_first_hop(): no 2nd via found - first hop Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=959 a=24 n=set_contact_alias Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/dset.c:919]: uri_add_rcv_alias(): encoded sip:305@PRIVATE_IP:44762 => [sip:305@PRIVATE_IP:44762;alias=184.148.140.29~44762~1] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=962 a=39 n=setflag Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=965 a=2 n=return Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=550 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=542 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=559 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=550 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=555 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=551 a=24 n=t_precheck_trans Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=555 a=24 n=t_check_trans Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1034]: t_check_msg(): msg (0x7f1ef9d784e8) id=15/15920 global id=14/15920 T start=0xffffffffffffffff Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:497]: t_lookup_request(): start searching: hash=54253, isACK=0 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:455]: matching_3261(): RFC3261 transaction matching failed - via branch [z9hG4bK1251409407] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:675]: t_lookup_request(): no transaction found Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1104]: t_check_msg(): msg (0x7f1ef9d784e8) id=15/15920 global id=15/15920 T end=(nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=559 a=5 n=route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=776 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=772 a=24 n=has_totag Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} siputils [checks.c:120]: has_totag(): no totag Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=772 a=2 n=return Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=564 a=5 n=route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=932 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=920 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=946 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:641]: grep_sock_info(): checking if host==us: 29==14 && [domain.net] == [PUBLIC_IP] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:648]: grep_sock_info(): checking if port PORT (advertise 0) matches port PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:641]: grep_sock_info(): checking if host==us: 29==9 && [domain.net] == [127.0.0.1] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:648]: grep_sock_info(): checking if port 8080 (advertise 0) matches port PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/name_alias.h:62]: grep_aliases(): matching (0:domain.net:PORT) vs. (2:localhost:8080) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/name_alias.h:62]: grep_aliases(): matching (0:domain.net:PORT) vs. (1:Kam01:PORT) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/forward.c:422]: check_self(): host (0:domain.net:PORT) == me Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=946 a=2 n=return Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=568 a=25 n=remove_hf Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=574 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=569 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=570 a=24 n=record_route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} rr [record.c:479]: record_route(): inserted record route (r2: 1 - plen: 0) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=579 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=574 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=579 a=5 n=route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=1017 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:641]: grep_sock_info(): checking if host==us: 29==14 && [domain.net] == [PUBLIC_IP] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:648]: grep_sock_info(): checking if port PORT (advertise 0) matches port PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:641]: grep_sock_info(): checking if host==us: 29==9 && [domain.net] == [127.0.0.1] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:648]: grep_sock_info(): checking if port 8080 (advertise 0) matches port PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/name_alias.h:62]: grep_aliases(): matching (0:domain.net:PORT) vs. (2:localhost:8080) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/name_alias.h:62]: grep_aliases(): matching (0:domain.net:PORT) vs. (1:Kam01:PORT) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/forward.c:422]: check_self(): host (0:domain.net:PORT) == me Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=1015 a=2 n=return Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=584 a=5 n=route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=877 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=875 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=885 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=877 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=890 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=885 a=24 n=t_newtran Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1328]: t_newtran(): msg (0x7f1ef9d784e8) id=15/15920 global id=15/15920 T start=(nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:497]: t_lookup_request(): start searching: hash=54253, isACK=0 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:455]: matching_3261(): RFC3261 transaction matching failed - via branch [z9hG4bK1251409407] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:675]: t_lookup_request(): no transaction found Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_hooks.c:346]: run_reqin_callbacks_internal(): trans=0x7f1ef323f8c8, callback type 1, id 0 entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_hooks.c:346]: run_reqin_callbacks_internal(): trans=0x7f1ef323f8c8, callback type 1, id 0 entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/crypto/md5utils.c:67]: MD5StringArray(): MD5 calculated: 7e9980da4edb60eee121f954c89f5972 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=897 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=890 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=897 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=893 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=894 a=24 n=handle_subscribe Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [event_list.c:324]: search_event(): start event= [dialog/5] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:1312]: extract_sdialog_info_ex(): 'Expires' header found, value= 60 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:1346]: extract_sdialog_info_ex(): 'To' header ALREADY PARSED: sip:301@domain.net:PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:1403]: extract_sdialog_info_ex(): generating to_tag Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sl [sl.c:354]: get_reply_totag(): totag stateful mode (tm)

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:1452]: extract_sdialog_info_ex(): subs->contact= sip:305@PRIVATE_IP:44762;alias=184.148.140.29~44762~1 - len = 55 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:1207]: handle_subscribe(): subscription status= active - inserted Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:562]: update_subscription(): update subscription Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:127]: printf_subs(): pres_uri: sip:301@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:128]: printf_subs(): watcher_user@watcher_domain: 305@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:131]: printf_subs(): to_user@to_domain: 301@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:133]: printf_subs(): from_user@from_domain: 305@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:135]: printf_subs(): callid/from_tag/to_tag: 551473572-44762-6@BA.CEG.HG.GC/138358676/5e1ee053cf7168b9d3ee9c05740f837c-eb6836a8 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:138]: printf_subs(): local_cseq/remote_cseq: 0/21000 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:140]: printf_subs(): local_contact/contact: sip:PUBLIC_IP:PORT/sip:305@PRIVATE_IP:44762;alias=184.148.140.29~44762~1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:142]: printf_subs(): record_route: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:144]: printf_subs(): sockinfo_str: udp:PUBLIC_IP:PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:147]: printf_subs(): event: dialog Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:148]: printf_subs(): status: active Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:149]: printf_subs(): reason: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:150]: printf_subs(): version: 1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:151]: printf_subs(): expires: 60 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:153]: printf_subs(): updated/updated_winfo: -1/-1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:625]: update_subscription(): subscription not in dialog Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:628]: update_subscription(): inserting in shtable Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1034]: t_check_msg(): msg (0x7f1ef9d784e8) id=15/15920 global id=15/15920 T start=0x7f1ef323f8c8 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1109]: t_check_msg(): T (0x7f1ef323f8c8) already found for msg (0x7f1ef9d784e8)! Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_reply.c:1765]: cleanup_uac_timers(): RETR/FR timers reset Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_reply.c:637]: _reply_light(): reply sent out - buf=0x7f1ef9d83a38: SIP/2.0 202 OK#015#012Via:... shmem=0x7f1ef321abc0: SIP/2.0 202 OK#015#012Via: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_reply.c:648]: _reply_light(): finished Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} sl [sl.c:297]: send_reply(): reply in stateful mode (tm)

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:1622]: send_notify_request(): dialog info: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:127]: printf_subs(): pres_uri: sip:301@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:128]: printf_subs(): watcher_user@watcher_domain: 305@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:131]: printf_subs(): to_user@to_domain: 301@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:133]: printf_subs(): from_user@from_domain: 305@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:135]: printf_subs(): callid/from_tag/to_tag: 551473572-44762-6@BA.CEG.HG.GC/138358676/5e1ee053cf7168b9d3ee9c05740f837c-eb6836a8 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:138]: printf_subs(): local_cseq/remote_cseq: 1/21000 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:140]: printf_subs(): local_contact/contact: sip:PUBLIC_IP:PORT/sip:305@PRIVATE_IP:44762;alias=184.148.140.29~44762~1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:142]: printf_subs(): record_route: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:144]: printf_subs(): sockinfo_str: udp:PUBLIC_IP:PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:147]: printf_subs(): event: dialog Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:148]: printf_subs(): status: active Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:149]: printf_subs(): reason: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:150]: printf_subs(): version: 1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:151]: printf_subs(): expires: 60 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:153]: printf_subs(): updated/updated_winfo: -1/-1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [hash.c:522]: search_phtable(): pres_uri= sip:301@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:617]: ps_db_get_p_notify_body(): No record exists in hash_table

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:125]: dlginfo_agg_nbody(): [pres_user]=301 [pres_domain]= domain.net, [n]=0 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:81]: dlginfo_agg_nbody_empty(): creating empty dialog for [pres_user]=301 [pres_domain]= domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:180]: agregate_xmls(): [pres_user]=301 [pres_domain]= domain.net, [n]=1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:282]: agregate_xmls(): node type: Element, name: dialog Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:103]: dlginfo_agg_nbody_empty(): [n_body]=0x7f1ef9d83b80 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:105]: dlginfo_agg_nbody_empty(): [*n_body]=<?xml version="1.0"?>#012<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="00000000000" state="full" entity="sip:301@domain.net">#012  <dialog id="615293b33c62dec073e05d9421e9f48b" direction="recipient">#011<state>terminated</state>#011</dialog>#012</dialog-info>#012 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:608]: dlginfo_body_setversion(): replace version with "1"

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:1707]: send_notify_request(): headers:#012Max-Forwards: 70#015#012Event: dialog#015#012Contact: sip:PUBLIC_IP:PORT#015#012Subscription-State: active;expires=60#015#012Content-Type: application/dialog-info+xml#015#012 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:1131]: ps_build_dlg_t(): CONTACT = sip:305@PRIVATE_IP:44762;alias=184.148.140.29~44762~1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:641]: grep_sock_info(): checking if host==us: 14==14 && [PUBLIC_IP] == [PUBLIC_IP] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:648]: grep_sock_info(): checking if port PORT (advertise 0) matches port PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:1716]: send_notify_request(): expires 60 status 1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/dset.c:1026]: uri_restore_rcv_alias(): decoded sip:305@PRIVATE_IP:44762;alias=184.148.140.29~44762~1 => [sip:305@PRIVATE_IP:44762] [sip:184.148.140.29:44762]

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [uac.c:450]: t_uac_prepare(): next_hop=sip:184.148.140.29:44762 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [uac.c:158]: dlg2hash(): hashid 35506 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [uac.c:678]: send_prepared_request_impl(): uac: 0x7f1ef31d9830  branch: 0  to 184.148.140.29:44762

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/onsend.c:50]: run_onsend(): required parameters are not available - ignoring Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: INFO:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:1737]: send_notify_request(): NOTIFY sip:305@domain.net via on behalf of sip:301@domain.net for event dialog : 551473572-44762-6@BA.CEG.HG.GC Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=895 a=24 n=t_release Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1034]: t_check_msg(): msg (0x7f1ef9d784e8) id=15/15920 global id=15/15920 T start=0x7f1ef323f8c8 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1109]: t_check_msg(): T (0x7f1ef323f8c8) already found for msg (0x7f1ef9d784e8)! Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_reply.c:1765]: cleanup_uac_timers(): RETR/FR timers reset Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/timer.c:557]: timer_add_safe(): timer_add called on an active timer 0x7f1ef323f950 (0x7f1ef2ec8f10, 0x7f1ef323d4a8), flags 201 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} tm [t_funcs.c:146]: put_on_wait(): transaction 0x7f1ef323f8c8 already on wait Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=897 a=2 n=exit Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/receive.c:436]: receive_msg(): request-route executed in: 39617 usec Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE 551473572-44762-6@BA.CEG.HG.GC} <core> [core/receive.c:528]: receive_msg(): cleaning up

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/udp_server.c:493]: udp_rcv_loop(): received on udp socket: (106/100/531) [[SIP/2.0 481 Subscription Does Not Exist 0D  0A Via: SIP/2.0/UDP PUBLIC_IP:PORT;branch=z9hG4bK2ba8.5e7]] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:620]: parse_msg(): SIP Reply  (status): Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:621]: parse_msg():  version: <SIP/2.0> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:623]: parse_msg():  status:  <481> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:625]: parse_msg():  reason:  <Subscription Does Not Exist> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/parse_via.c:1300]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK2ba8.5e7ce1f0000000000000000000000000.0>; state=16 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=138358676 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:170]: get_hdr_field(): <To> [55]; uri=[sip:305@domain.net] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body (39)[sip:305@domain.net], to tag (9)[138358676] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:150]: get_hdr_field(): cseq <CSeq>: <2> <NOTIFY> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/receive.c:319]: receive_msg(): --- received sip message - reply - call-id: [551473572-44762-6@BA.CEG.HG.GC] - cseq: [2 NOTIFY] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header

-------------

As I said in my last posting, the 1782s are still having issues "481 Subscription Does Not Exist" is response to a NOTIFY for SUBSCRIBE the the device just sent in...

Jerry

On 2021-10-01 1:11 p.m., Jerry Kendall wrote:

Thanks Daniel,

Now it seems almost done.... I have 6 Grandstream phones connected and they all have 4-6 BLFs watching each other.

(I also have Yealink/Cisco/SNOM/Polycom phones to test later - one thing at a time.....)

The 2x GRP2604P all look great

The GXP2130 seems OK as well

The GXP2170 with side car seems OK

But, the 2x GXP1782 are not quit working yet.

A couple of the BLFs are OK but most are sending back 481 Subscription Does Not Exist... See blow (gotta love sngrep)

---

2021/10/01 12:31:13.024698 SITE_IP:44762 -> PUBLIC_IP:8081 SUBSCRIBE sip:301@domain.net:8081 SIP/2.0 Via: SIP/2.0/UDP PRIVATE_IP:44762;branch=z9hG4bK806864449;rport From: sip:305@domain.net:8081;tag=1883014019 To: sip:301@domain.net:8081 Call-ID: 551473572-44762-6@BA.CEG.HG.GC CSeq: 27840 SUBSCRIBE Contact: sip:305@PRIVATE_IP:44762 X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP1782 1.0.1.116 Expires: 60 Supported: replaces, path, timer, eventlist Event: dialog Accept: application/dialog-info+xml,multipart/related,application/rlmi+xml Accept-Encoding: zlib+base64 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

2021/10/01 12:31:13.026561 PUBLIC_IP:8081 -> SITE_IP:44762 SIP/2.0 202 OK Via: SIP/2.0/UDP PRIVATE_IP:44762;branch=z9hG4bK806864449;rport=44762;received=SITE_IP From: sip:305@domain.net:8081;tag=1883014019 To: sip:301@domain.net:8081;tag=5e1ee053cf7168b9d3ee9c05740f837c-eb6836a8 Call-ID: 551473572-44762-6@BA.CEG.HG.GC CSeq: 27840 SUBSCRIBE Expires: 60 Contact: sip:PUBLIC_IP:8081 Server: kamailio (5.4.4 (x86_64/linux)) Content-Length: 0

2021/10/01 12:31:13.027436 PUBLIC_IP:8081 -> SITE_IP:44762 NOTIFY sip:305@PRIVATE_IP:44762 SIP/2.0 Via: SIP/2.0/UDP PUBLIC_IP:8081;branch=z9hG4bK2ba8.555213e5000000000000000000000000.0 To: sip:305@domain.net;tag=1883014019 From: sip:301@domain.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-eb6836a8 CSeq: 2 NOTIFY Call-ID: 551473572-44762-6@BA.CEG.HG.GC Content-Length: 273 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact: sip:PUBLIC_IP:8081 Subscription-State: active;expires=60 Content-Type: application/dialog-info+xml

<?xml version="1.0"?>

<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="1" state="full" entity="sip:301@domain.net">   <dialog id="615293b33c62dec073e05d9421e9f48b" direction="recipient"> <state>terminated</state> </dialog>

</dialog-info>

2021/10/01 12:31:13.332763 SITE_IP:44762 -> PUBLIC_IP:8081 SIP/2.0 481 Subscription Does Not Exist Via: SIP/2.0/UDP PUBLIC_IP:8081;branch=z9hG4bK2ba8.555213e5000000000000000000000000.0 From: sip:301@domain.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-eb6836a8 To: sip:305@domain.net;tag=1883014019 Call-ID: 551473572-44762-6@BA.CEG.HG.GC CSeq: 2 NOTIFY Supported: replaces, path, timer User-Agent: Grandstream GXP1782 1.0.1.116 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

---

Now, I am sure it's not likely the phone setup as I have a FreeSWITCH system that these 6 phones normally talk to.

All i do is change the DNS A Record between the IP of the Kamailio server and the FreeSWITCH server... This way I don't touch the phone configs..

I just change the A Records and take an hour long break...

On the FreeSWITCH server, the phones do exactly as expected...

I am trying to stick the Kamailio server between the phones and FS but, one step at a time.

So, any thoughts as to why the GXP1782 BLFs are not accepting the NOTIFY messages?

Jerry

---

Kamailio - Users Mailing List - Non Commercial Discussions  * sr-users@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe:  * https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

So, I went for dinner and one of the BLFs started working while I was away from my desk... I left sngrep running and it captured something puzzling....

------ BEFORE - NOT WORKING ---------

2021/10/02 19:05:43.842970 CLIENT_IP:55318 -> PUBLIC_IP:PORT SUBSCRIBE sip:301@domain.net:PORT SIP/2.0 Via: SIP/2.0/UDP 10.10.10.62:55318;branch=z9hG4bK89955442;rport From: sip:305@domain.net:PORT;tag=1703808353 To: sip:301@domain.net:PORT Call-ID: 2104033891-55318-6@BA.CEG.HG.GC CSeq: 86910 SUBSCRIBE Contact: sip:305@10.10.10.62:55318 X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP1782 1.0.1.116 Expires: 60 Supported: replaces, path, timer, eventlist Event: dialog Accept: application/dialog-info+xml,multipart/related,application/rlmi+xml Accept-Encoding: zlib+base64 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

2021/10/02 19:05:43.843367 PUBLIC_IP:PORT -> CLIENT_IP:55318 SIP/2.0 202 OK Via: SIP/2.0/UDP 10.10.10.62:55318;branch=z9hG4bK89955442;rport=55318;received=CLIENT_IP From: sip:305@domain.net:PORT;tag=1703808353 To: sip:301@domain.net:PORT;tag=5e1ee053cf7168b9d3ee9c05740f837c-270107a6 Call-ID: 2104033891-55318-6@BA.CEG.HG.GC CSeq: 86910 SUBSCRIBE Expires: 60 Contact: sip:PUBLIC_IP:PORT Server: kamailio (5.4.4 (x86_64/linux)) Content-Length: 0

2021/10/02 19:05:43.844037 PUBLIC_IP:PORT -> CLIENT_IP:55318 NOTIFY sip:305@10.10.10.62:55318 SIP/2.0 Via: SIP/2.0/UDP PUBLIC_IP:PORT;branch=z9hG4bKffd9.6b44c1b2000000000000000000000000.0 To: sip:305@domain.net;tag=1703808353 From: sip:301@domain.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-270107a6 CSeq: 2 NOTIFY Call-ID: 2104033891-55318-6@BA.CEG.HG.GC Content-Length: 273 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact: sip:PUBLIC_IP:PORT Subscription-State: active;expires=60 Content-Type: application/dialog-info+xml

<?xml version="1.0"?> <dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="1" state="full" entity="sip:301@domain.net">   <dialog id="615293b33c62dec073e05d9421e9f48b" direction="recipient"> <state>terminated</state> </dialog> </dialog-info> ------ BEFORE - NOT WORKING ---------

------ AFTER - WORKING ---------

2021/10/02 19:06:04.291019 CLIENT_IP:55318 -> PUBLIC_IP:PORT SUBSCRIBE sip:301@domain.net:PORT SIP/2.0 Via: SIP/2.0/UDP 10.10.10.62:55318;branch=z9hG4bK1198306856;rport From: sip:305@domain.net:PORT;tag=998538926 To: sip:301@domain.net:PORT Call-ID: 2104033891-55318-6@BA.CEG.HG.GC CSeq: 86920 SUBSCRIBE Contact: sip:305@10.10.10.62:55318 X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP1782 1.0.1.116 Expires: 60 Supported: replaces, path, timer, eventlist Event: dialog Accept: application/dialog-info+xml,multipart/related,application/rlmi+xml Accept-Encoding: zlib+base64 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

2021/10/02 19:06:04.291688 PUBLIC_IP:PORT -> CLIENT_IP:55318 SIP/2.0 202 OK Via: SIP/2.0/UDP 10.10.10.62:55318;branch=z9hG4bK1198306856;rport=55318;received=CLIENT_IP From: sip:305@domain.net:PORT;tag=998538926 To: sip:301@domain.net:PORT;tag=5e1ee053cf7168b9d3ee9c05740f837c-270107a6 Call-ID: 2104033891-55318-6@BA.CEG.HG.GC CSeq: 86920 SUBSCRIBE Expires: 60 Contact: sip:PUBLIC_IP:PORT Server: kamailio (5.4.4 (x86_64/linux)) Content-Length: 0

2021/10/02 19:06:04.292671 PUBLIC_IP:PORT -> CLIENT_IP:55318 NOTIFY sip:305@10.10.10.62:55318 SIP/2.0 Via: SIP/2.0/UDP PUBLIC_IP:PORT;branch=z9hG4bKffd9.40e91c22000000000000000000000000.0 To: sip:305@domain.net;tag=998538926 From: sip:301@domain.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-270107a6 CSeq: 2 NOTIFY Call-ID: 2104033891-55318-6@BA.CEG.HG.GC Content-Length: 273 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact: sip:PUBLIC_IP:PORT Subscription-State: active;expires=60 Content-Type: application/dialog-info+xml

<?xml version="1.0"?> <dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="1" state="full" entity="sip:301@domain.net">   <dialog id="615293b33c62dec073e05d9421e9f48b" direction="recipient"> <state>terminated</state> </dialog> </dialog-info> ------ AFTER - WORKING ---------

The careful eye will notice NOTHING CHANGED.....

Almost nothing... Of course the branch and (from/from/to) tags are different BUT THATS IT...

So, not working, go for food, working... I changed nothing.

I thought it was the never change version attribute in the dialog-info but, maybe not.

Has anyone seen this ? If so, how did you get past it ?

In case you're new to this thread, these are Grandstream GXP1782 desk phones.

When I change the DNS A record for the host and point it to a FreeSWITCH system, the BLFs are fine. It's just when they reach Kamailio 5.4.4 that they don't sync up properly.

Any pointers are welcome...

Jerry

On 2021-10-02 7:07 p.m., Jerry Kendall wrote:

So, after much more testing and staring at logs....

The BLFs ARE indeed working for all my Yealinks and MOST of my Grandstreams

The ones that are not working don't like the 'version=1' - these are sending back "481 Subscription Does Not Exist"

---

NOTIFY sip:305@PRIVATE_IP:55318 SIP/2.0 Via: SIP/2.0/UDP IP_ADDRESS:PORT;branch=z9hG4bKffd9.a74b11b5000000000000000000000000.0 To: sip:305@domain.net;tag=2001402711 From: sip:301@domain.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-270107a6 CSeq: 2 NOTIFY Call-ID: 2104033891-55318-6@BA.CEG.HG.GC Content-Length: 273 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact: sip:IP_ADDRESS:PORT Subscription-State: active;expires=60 Content-Type: application/dialog-info+xml

<?xml version="1.0"?>

<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="1" state="full" entity="sip:301@domain.net">   <dialog id="615293b33c62dec073e05d9421e9f48b" direction="recipient"> <state>terminated</state> </dialog>

</dialog-info> --------------

Other wise everything seems OK.

So, any ideas why Kamilio is sending Version=1 repeatedly?

Jerry

On 2021-10-01 1:33 p.m., Jerry Kendall wrote:

...

Hey Daniel, here is the (hopefully) sanitized syslog parts around the BLF issue. I do appreciate your insights here..

---

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/udp_server.c:493]: udp_rcv_loop(): received on udp socket: (106/100/726) [[SUBSCRIBEsip:301@domain.net:PORT SIP/2.0 0D  0A Via: SIP/2.0/UDP PRIVATE_IP:44762;br]] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:610]: parse_msg(): SIP Request: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:611]: parse_msg():  method:  <SUBSCRIBE> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:613]: parse_msg():  uri:sip:301@domain.net:PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:615]: parse_msg():  version: <SIP/2.0> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/parse_via.c:1300]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK1251409407>; state=6 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/parse_via.c:1300]: parse_via_param(): Found param type 235, <rport> = <n/a>; state=17 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=10 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:170]: get_hdr_field(): <To> [46]; uri=[sip:301@domain.net:PORT] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body (46)[sip:301@domain.net:PORT#015#012], to tag (0)[] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:150]: get_hdr_field(): cseq <CSeq>: <21000> <SUBSCRIBE> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/receive.c:319]: receive_msg(): --- received sip message - request - call-id: [551473572-44762-6@BA.CEG.HG.GC] - cseq: [21000 SUBSCRIBE] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/receive.c:379]: receive_msg(): preparing to run routing scripts... Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=533 a=5 n=route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=759 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=754 a=25 n=mf_process_maxfwd_header Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} maxfwd [mf_funcs.c:74]: is_maxfwd_present(): value = 70 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=764 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=759 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=768 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[REQINIT] c=[/etc/kamailio/kamailio.cfg] l=764 a=26 n=sanity_check Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:247]: check_ruri_sip_version(): check_ruri_sip_version entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:270]: check_ruri_sip_version(): check_ruri_sip_version passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:277]: check_ruri_scheme(): check_ruri_scheme entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:297]: check_ruri_scheme(): check_ruri_scheme passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:318]: check_required_headers(): check_required_headers entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:340]: check_required_headers(): check_required_headers passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:348]: check_via1_header(): check via1 header Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:506]: check_cseq_method(): check_cseq_method entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:539]: check_cseq_method(): check_cseq_method passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:548]: check_cseq_value(): check_cseq_value entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:576]: check_cseq_value(): check_cseq_value passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:585]: check_cl(): check_cl entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:610]: check_cl(): check_cl passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:622]: check_expires_value(): check_expires_value entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:657]: check_expires_value(): check_expires_value passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:759]: check_parse_uris(): check_parse_uris entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:763]: check_parse_uris(): parsing ruri Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:777]: check_parse_uris(): looking up From header Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:791]: check_parse_uris(): parsing From header Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=138358676 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:817]: check_parse_uris(): parsing From URI Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:835]: check_parse_uris(): looking up To header Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:857]: check_parse_uris(): check_parse_uris(): parsing To URI Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity.c:902]: check_parse_uris(): check_parse_uris passed Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sanity [sanity_mod.c:254]: w_sanity_check(): sanity checks result: 1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=536 a=5 n=route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=952 a=55 n=force_rport Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=965 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=953 a=25 n=nat_uac_test Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/msg_translator.c:161]: check_via_address(): (184.148.140.29, PRIVATE_IP, 0) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=954 a=25 n=xinfo Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=961 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=955 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=961 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=958 a=24 n=is_first_hop Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} siputils [sipops.c:159]: is_first_hop(): no 2nd via found - first hop Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=959 a=24 n=set_contact_alias Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/dset.c:919]: uri_add_rcv_alias(): encodedsip:305@PRIVATE_IP:44762 => [sip:305@PRIVATE_IP:44762;alias=184.148.140.29~44762~1] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=962 a=39 n=setflag Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[NATDETECT] c=[/etc/kamailio/kamailio.cfg] l=965 a=2 n=return Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=550 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=542 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=559 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=550 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=555 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=551 a=24 n=t_precheck_trans Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=555 a=24 n=t_check_trans Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1034]: t_check_msg(): msg (0x7f1ef9d784e8) id=15/15920 global id=14/15920 T start=0xffffffffffffffff Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:497]: t_lookup_request(): start searching: hash=54253, isACK=0 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:455]: matching_3261(): RFC3261 transaction matching failed - via branch [z9hG4bK1251409407] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:675]: t_lookup_request(): no transaction found Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1104]: t_check_msg(): msg (0x7f1ef9d784e8) id=15/15920 global id=15/15920 T end=(nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=559 a=5 n=route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=776 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=772 a=24 n=has_totag Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} siputils [checks.c:120]: has_totag(): no totag Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[WITHINDLG] c=[/etc/kamailio/kamailio.cfg] l=772 a=2 n=return Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=564 a=5 n=route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=932 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=920 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=946 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:641]: grep_sock_info(): checking if host==us: 29==14 && [domain.net] == [PUBLIC_IP] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:648]: grep_sock_info(): checking if port PORT (advertise 0) matches port PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:641]: grep_sock_info(): checking if host==us: 29==9 && [domain.net] == [127.0.0.1] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:648]: grep_sock_info(): checking if port 8080 (advertise 0) matches port PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/name_alias.h:62]: grep_aliases(): matching (0:domain.net:PORT) vs. (2:localhost:8080) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/name_alias.h:62]: grep_aliases(): matching (0:domain.net:PORT) vs. (1:Kam01:PORT) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/forward.c:422]: check_self(): host (0:domain.net:PORT) == me Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[AUTH] c=[/etc/kamailio/kamailio.cfg] l=946 a=2 n=return Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=568 a=25 n=remove_hf Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=574 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=569 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=570 a=24 n=record_route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} rr [record.c:479]: record_route(): inserted record route (r2: 1 - plen: 0) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=579 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=574 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=579 a=5 n=route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=1017 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:641]: grep_sock_info(): checking if host==us: 29==14 && [domain.net] == [PUBLIC_IP] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:648]: grep_sock_info(): checking if port PORT (advertise 0) matches port PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:641]: grep_sock_info(): checking if host==us: 29==9 && [domain.net] == [127.0.0.1] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:648]: grep_sock_info(): checking if port 8080 (advertise 0) matches port PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/name_alias.h:62]: grep_aliases(): matching (0:domain.net:PORT) vs. (2:localhost:8080) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/name_alias.h:62]: grep_aliases(): matching (0:domain.net:PORT) vs. (1:Kam01:PORT) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/forward.c:422]: check_self(): host (0:domain.net:PORT) == me Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[SIPOUT] c=[/etc/kamailio/kamailio.cfg] l=1015 a=2 n=return Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[DEFAULT_ROUTE] c=[/etc/kamailio/kamailio.cfg] l=584 a=5 n=route Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=877 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=875 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=885 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=877 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=890 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=885 a=24 n=t_newtran Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1328]: t_newtran(): msg (0x7f1ef9d784e8) id=15/15920 global id=15/15920 T start=(nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:497]: t_lookup_request(): start searching: hash=54253, isACK=0 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:455]: matching_3261(): RFC3261 transaction matching failed - via branch [z9hG4bK1251409407] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:675]: t_lookup_request(): no transaction found Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_hooks.c:346]: run_reqin_callbacks_internal(): trans=0x7f1ef323f8c8, callback type 1, id 0 entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_hooks.c:346]: run_reqin_callbacks_internal(): trans=0x7f1ef323f8c8, callback type 1, id 0 entered Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/crypto/md5utils.c:67]: MD5StringArray(): MD5 calculated: 7e9980da4edb60eee121f954c89f5972 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=897 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=890 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=897 a=16 n=if Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=893 a=25 n=is_method Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=894 a=24 n=handle_subscribe Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [event_list.c:324]: search_event(): start event= [dialog/5] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:1312]: extract_sdialog_info_ex(): 'Expires' header found, value= 60 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:1346]: extract_sdialog_info_ex(): 'To' header ALREADY PARSED:sip:301@domain.net:PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:1403]: extract_sdialog_info_ex(): generating to_tag Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sl [sl.c:354]: get_reply_totag(): totag stateful mode (tm)

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:1452]: extract_sdialog_info_ex(): subs->contact=sip:305@PRIVATE_IP:44762;alias=184.148.140.29~44762~1 - len = 55 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:1207]: handle_subscribe(): subscription status= active - inserted Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:562]: update_subscription(): update subscription Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:127]: printf_subs(): pres_uri:sip:301@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:128]: printf_subs(): watcher_user@watcher_domain:305@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:131]: printf_subs(): to_user@to_domain:301@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:133]: printf_subs(): from_user@from_domain:305@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:135]: printf_subs(): callid/from_tag/to_tag:551473572-44762-6@BA.CEG.HG.GC/138358676/5e1ee053cf7168b9d3ee9c05740f837c-eb6836a8 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:138]: printf_subs(): local_cseq/remote_cseq: 0/21000 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:140]: printf_subs(): local_contact/contact:sip:PUBLIC_IP:PORT/sip:305@PRIVATE_IP:44762;alias=184.148.140.29~44762~1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:142]: printf_subs(): record_route: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:144]: printf_subs(): sockinfo_str: udp:PUBLIC_IP:PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:147]: printf_subs(): event: dialog Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:148]: printf_subs(): status: active Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:149]: printf_subs(): reason: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:150]: printf_subs(): version: 1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:151]: printf_subs(): expires: 60 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:153]: printf_subs(): updated/updated_winfo: -1/-1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:625]: update_subscription(): subscription not in dialog Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [subscribe.c:628]: update_subscription(): inserting in shtable Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1034]: t_check_msg(): msg (0x7f1ef9d784e8) id=15/15920 global id=15/15920 T start=0x7f1ef323f8c8 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1109]: t_check_msg(): T (0x7f1ef323f8c8) already found for msg (0x7f1ef9d784e8)! Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_reply.c:1765]: cleanup_uac_timers(): RETR/FR timers reset Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_reply.c:637]: _reply_light(): reply sent out - buf=0x7f1ef9d83a38: SIP/2.0 202 OK#015#012Via:... shmem=0x7f1ef321abc0: SIP/2.0 202 OK#015#012Via: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_reply.c:648]: _reply_light(): finished Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} sl [sl.c:297]: send_reply(): reply in stateful mode (tm)

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:1622]: send_notify_request(): dialog info: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:127]: printf_subs(): pres_uri:sip:301@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:128]: printf_subs(): watcher_user@watcher_domain:305@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:131]: printf_subs(): to_user@to_domain:301@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:133]: printf_subs(): from_user@from_domain:305@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:135]: printf_subs(): callid/from_tag/to_tag:551473572-44762-6@BA.CEG.HG.GC/138358676/5e1ee053cf7168b9d3ee9c05740f837c-eb6836a8 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:138]: printf_subs(): local_cseq/remote_cseq: 1/21000 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:140]: printf_subs(): local_contact/contact:sip:PUBLIC_IP:PORT/sip:305@PRIVATE_IP:44762;alias=184.148.140.29~44762~1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:142]: printf_subs(): record_route: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:144]: printf_subs(): sockinfo_str: udp:PUBLIC_IP:PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:147]: printf_subs(): event: dialog Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:148]: printf_subs(): status: active Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:149]: printf_subs(): reason: Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:150]: printf_subs(): version: 1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:151]: printf_subs(): expires: 60 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:153]: printf_subs(): updated/updated_winfo: -1/-1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [hash.c:522]: search_phtable(): pres_uri=sip:301@domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:617]: ps_db_get_p_notify_body(): No record exists in hash_table

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:125]: dlginfo_agg_nbody(): [pres_user]=301 [pres_domain]= domain.net, [n]=0 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:81]: dlginfo_agg_nbody_empty(): creating empty dialog for [pres_user]=301 [pres_domain]= domain.net Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:180]: agregate_xmls(): [pres_user]=301 [pres_domain]= domain.net, [n]=1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:282]: agregate_xmls(): node type: Element, name: dialog Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:103]: dlginfo_agg_nbody_empty(): [n_body]=0x7f1ef9d83b80 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:105]: dlginfo_agg_nbody_empty(): [*n_body]=<?xml version="1.0"?>#012<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="00000000000" state="full" entity="sip:301@domain.net">#012  <dialog id="615293b33c62dec073e05d9421e9f48b" direction="recipient">#011<state>terminated</state>#011</dialog>#012</dialog-info>#012 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence_dialoginfo [notify_body.c:608]: dlginfo_body_setversion(): replace version with "1"

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:1707]: send_notify_request(): headers:#012Max-Forwards: 70#015#012Event: dialog#015#012Contact:sip:PUBLIC_IP:PORT#015#012Subscription-State: active;expires=60#015#012Content-Type: application/dialog-info+xml#015#012 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:1131]: ps_build_dlg_t(): CONTACT =sip:305@PRIVATE_IP:44762;alias=184.148.140.29~44762~1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:641]: grep_sock_info(): checking if host==us: 14==14 && [PUBLIC_IP] == [PUBLIC_IP] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/socket_info.c:648]: grep_sock_info(): checking if port PORT (advertise 0) matches port PORT Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:1716]: send_notify_request(): expires 60 status 1 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/dset.c:1026]: uri_restore_rcv_alias(): decodedsip:305@PRIVATE_IP:44762;alias=184.148.140.29~44762~1 => [sip:305@PRIVATE_IP:44762] [sip:184.148.140.29:44762]

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [uac.c:450]: t_uac_prepare(): next_hop=sip:184.148.140.29:44762 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [uac.c:158]: dlg2hash(): hashid 35506 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [uac.c:678]: send_prepared_request_impl(): uac: 0x7f1ef31d9830  branch: 0  to 184.148.140.29:44762

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/onsend.c:50]: run_onsend(): required parameters are not available - ignoring Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: INFO:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} presence [notify.c:1737]: send_notify_request(): NOTIFYsip:305@domain.net via on behalf ofsip:301@domain.net for event dialog :551473572-44762-6@BA.CEG.HG.GC Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=895 a=24 n=t_release Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1034]: t_check_msg(): msg (0x7f1ef9d784e8) id=15/15920 global id=15/15920 T start=0x7f1ef323f8c8 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_lookup.c:1109]: t_check_msg(): T (0x7f1ef323f8c8) already found for msg (0x7f1ef9d784e8)! Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_reply.c:1765]: cleanup_uac_timers(): RETR/FR timers reset Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/timer.c:557]: timer_add_safe(): timer_add called on an active timer 0x7f1ef323f950 (0x7f1ef2ec8f10, 0x7f1ef323d4a8), flags 201 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} tm [t_funcs.c:146]: put_on_wait(): transaction 0x7f1ef323f8c8 already on wait Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: exec:  {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} *** cfgtrace:dbg_cfg_trace(): request_route=[PRESENCE] c=[/etc/kamailio/kamailio.cfg] l=897 a=2 n=exit Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/receive.c:436]: receive_msg(): request-route executed in: 39617 usec Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/usr_avp.c:636]: destroy_avp_list(): destroying list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/xavp.c:541]: xavp_destroy_list(): destroying xavp list (nil) Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15920]: DEBUG: {1 21000 SUBSCRIBE551473572-44762-6@BA.CEG.HG.GC} <core> [core/receive.c:528]: receive_msg(): cleaning up

Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/udp_server.c:493]: udp_rcv_loop(): received on udp socket: (106/100/531) [[SIP/2.0 481 Subscription Does Not Exist 0D  0A Via: SIP/2.0/UDP PUBLIC_IP:PORT;branch=z9hG4bK2ba8.5e7]] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:620]: parse_msg(): SIP Reply  (status): Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:621]: parse_msg():  version: <SIP/2.0> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:623]: parse_msg():  status:  <481> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:625]: parse_msg():  reason:  <Subscription Does Not Exist> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/parse_via.c:1300]: parse_via_param(): Found param type 232, <branch> = <z9hG4bK2ba8.5e7ce1f0000000000000000000000000.0>; state=16 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/parse_via.c:2639]: parse_via(): end of header reached, state=5 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:498]: parse_headers(): Via found, flags=2 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:500]: parse_headers(): this is the first via Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=138358676 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/parse_addr_spec.c:864]: parse_addr_spec(): end of header reached, state=29 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:170]: get_hdr_field(): <To> [55]; uri=[sip:305@domain.net] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): to body (39)[sip:305@domain.net], to tag (9)[138358676] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:150]: get_hdr_field(): cseq <CSeq>: <2> <NOTIFY> Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/receive.c:319]: receive_msg(): --- received sip message - reply - call-id: [551473572-44762-6@BA.CEG.HG.GC] - cseq: [2 NOTIFY] Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:185]: get_hdr_field(): content_length=0 Oct  1 11:31:37 Kam01 /usr/sbin/kamailio[15918]: DEBUG: <core> [core/parser/msg_parser.c:89]: get_hdr_field(): found end of header

---

As I said in my last posting, the 1782s are still having issues "481 Subscription Does Not Exist" is response to a NOTIFY for SUBSCRIBE the the device just sent in...

Jerry

On 2021-10-01 1:11 p.m., Jerry Kendall wrote:

...

Thanks Daniel,

Now it seems almost done.... I have 6 Grandstream phones connected and they all have 4-6 BLFs watching each other.

(I also have Yealink/Cisco/SNOM/Polycom phones to test later - one thing at a time.....)

The 2x GRP2604P all look great

The GXP2130 seems OK as well

The GXP2170 with side car seems OK

But, the 2x GXP1782 are not quit working yet.

A couple of the BLFs are OK but most are sending back 481 Subscription Does Not Exist... See blow (gotta love sngrep)

---

2021/10/01 12:31:13.024698 SITE_IP:44762 -> PUBLIC_IP:8081 SUBSCRIBE sip:301@domain.net:8081 SIP/2.0 Via: SIP/2.0/UDP PRIVATE_IP:44762;branch=z9hG4bK806864449;rport From: sip:305@domain.net:8081;tag=1883014019 To: sip:301@domain.net:8081 Call-ID: 551473572-44762-6@BA.CEG.HG.GC CSeq: 27840 SUBSCRIBE Contact: sip:305@PRIVATE_IP:44762 X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP1782 1.0.1.116 Expires: 60 Supported: replaces, path, timer, eventlist Event: dialog Accept: application/dialog-info+xml,multipart/related,application/rlmi+xml Accept-Encoding: zlib+base64 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

2021/10/01 12:31:13.026561 PUBLIC_IP:8081 -> SITE_IP:44762 SIP/2.0 202 OK Via: SIP/2.0/UDP PRIVATE_IP:44762;branch=z9hG4bK806864449;rport=44762;received=SITE_IP From: sip:305@domain.net:8081;tag=1883014019 To: sip:301@domain.net:8081;tag=5e1ee053cf7168b9d3ee9c05740f837c-eb6836a8 Call-ID: 551473572-44762-6@BA.CEG.HG.GC CSeq: 27840 SUBSCRIBE Expires: 60 Contact: sip:PUBLIC_IP:8081 Server: kamailio (5.4.4 (x86_64/linux)) Content-Length: 0

2021/10/01 12:31:13.027436 PUBLIC_IP:8081 -> SITE_IP:44762 NOTIFY sip:305@PRIVATE_IP:44762 SIP/2.0 Via: SIP/2.0/UDP PUBLIC_IP:8081;branch=z9hG4bK2ba8.555213e5000000000000000000000000.0 To: sip:305@domain.net;tag=1883014019 From: sip:301@domain.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-eb6836a8 CSeq: 2 NOTIFY Call-ID: 551473572-44762-6@BA.CEG.HG.GC Content-Length: 273 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact: sip:PUBLIC_IP:8081 Subscription-State: active;expires=60 Content-Type: application/dialog-info+xml

<?xml version="1.0"?>

<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="1" state="full" entity="sip:301@domain.net">   <dialog id="615293b33c62dec073e05d9421e9f48b" direction="recipient"> <state>terminated</state> </dialog>

</dialog-info>

2021/10/01 12:31:13.332763 SITE_IP:44762 -> PUBLIC_IP:8081 SIP/2.0 481 Subscription Does Not Exist Via: SIP/2.0/UDP PUBLIC_IP:8081;branch=z9hG4bK2ba8.555213e5000000000000000000000000.0 From: sip:301@domain.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-eb6836a8 To: sip:305@domain.net;tag=1883014019 Call-ID: 551473572-44762-6@BA.CEG.HG.GC CSeq: 2 NOTIFY Supported: replaces, path, timer User-Agent: Grandstream GXP1782 1.0.1.116 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

---

Now, I am sure it's not likely the phone setup as I have a FreeSWITCH system that these 6 phones normally talk to.

All i do is change the DNS A Record between the IP of the Kamailio server and the FreeSWITCH server... This way I don't touch the phone configs..

I just change the A Records and take an hour long break...

On the FreeSWITCH server, the phones do exactly as expected...

I am trying to stick the Kamailio server between the phones and FS but, one step at a time.

So, any thoughts as to why the GXP1782 BLFs are not accepting the NOTIFY messages?

Jerry

---

Kamailio - Users Mailing List - Non Commercial Discussions  * sr-users@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe:  * https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

---

Kamailio - Users Mailing List - Non Commercial Discussions *sr-users@lists.kamailio.org Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe: *https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

---

Kamailio - Users Mailing List - Non Commercial Discussions

• sr-users@lists.kamailio.org

Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe:

• https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

So, it now seems confirmed..

Grandstream GXP1782 FW 1.0.1.116 does not work with the NOTIFY process of Kamailio 5.4.4

Grandstream GXP1782 FW 1.0.1.119 DOES...

On review of the Grandstream 1.0.1.118 update, • Fixed BLFs are not updating sometimes when using certain SIP servers

So, there ya go...

Jerry

On 10/3/2021 12:22 AM, Jerry Kendall wrote:

Talk about embarrassing.....

I just updated the firmware on the GXP1782 phones and when they booted up ------ The BLFs came right up and are working...

Let's hope it's not just some cosmic annoying coincidence....

Time for much testing..

On 2021-10-02 7:58 p.m., Jerry Kendall wrote:

...

So, I went for dinner and one of the BLFs started working while I was away from my desk... I left sngrep running and it captured something puzzling.... ------ BEFORE - NOT WORKING --------- 2021/10/02 19:05:43.842970 CLIENT_IP:55318 -> PUBLIC_IP:PORT SUBSCRIBEsip:301@domain.net:PORT SIP/2.0 Via: SIP/2.0/UDP 10.10.10.62:55318;branch=z9hG4bK89955442;rport From:sip:305@domain.net:PORT;tag=1703808353 To:sip:301@domain.net:PORT Call-ID:2104033891-55318-6@BA.CEG.HG.GC CSeq: 86910 SUBSCRIBE Contact:sip:305@10.10.10.62:55318 X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP1782 1.0.1.116 Expires: 60 Supported: replaces, path, timer, eventlist Event: dialog Accept: application/dialog-info+xml,multipart/related,application/rlmi+xml Accept-Encoding: zlib+base64 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

2021/10/02 19:05:43.843367 PUBLIC_IP:PORT -> CLIENT_IP:55318 SIP/2.0 202 OK Via: SIP/2.0/UDP 10.10.10.62:55318;branch=z9hG4bK89955442;rport=55318;received=CLIENT_IP From:sip:305@domain.net:PORT;tag=1703808353 To:sip:301@domain.net:PORT;tag=5e1ee053cf7168b9d3ee9c05740f837c-270107a6 Call-ID:2104033891-55318-6@BA.CEG.HG.GC CSeq: 86910 SUBSCRIBE Expires: 60 Contact:sip:PUBLIC_IP:PORT Server: kamailio (5.4.4 (x86_64/linux)) Content-Length: 0

2021/10/02 19:05:43.844037 PUBLIC_IP:PORT -> CLIENT_IP:55318 NOTIFYsip:305@10.10.10.62:55318 SIP/2.0 Via: SIP/2.0/UDP PUBLIC_IP:PORT;branch=z9hG4bKffd9.6b44c1b2000000000000000000000000.0 To:sip:305@domain.net;tag=1703808353 From:sip:301@domain.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-270107a6 CSeq: 2 NOTIFY Call-ID:2104033891-55318-6@BA.CEG.HG.GC Content-Length: 273 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact:sip:PUBLIC_IP:PORT Subscription-State: active;expires=60 Content-Type: application/dialog-info+xml

<?xml version="1.0"?>

<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="1" state="full" entity="sip:301@domain.net">   <dialog id="615293b33c62dec073e05d9421e9f48b" direction="recipient"> <state>terminated</state> </dialog> </dialog-info> ------ BEFORE - NOT WORKING ---------

------ AFTER - WORKING ---------

2021/10/02 19:06:04.291019 CLIENT_IP:55318 -> PUBLIC_IP:PORT SUBSCRIBEsip:301@domain.net:PORT SIP/2.0 Via: SIP/2.0/UDP 10.10.10.62:55318;branch=z9hG4bK1198306856;rport From:sip:305@domain.net:PORT;tag=998538926 To:sip:301@domain.net:PORT Call-ID:2104033891-55318-6@BA.CEG.HG.GC CSeq: 86920 SUBSCRIBE Contact:sip:305@10.10.10.62:55318 X-Grandstream-PBX: true Max-Forwards: 70 User-Agent: Grandstream GXP1782 1.0.1.116 Expires: 60 Supported: replaces, path, timer, eventlist Event: dialog Accept: application/dialog-info+xml,multipart/related,application/rlmi+xml Accept-Encoding: zlib+base64 Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE Content-Length: 0

2021/10/02 19:06:04.291688 PUBLIC_IP:PORT -> CLIENT_IP:55318 SIP/2.0 202 OK Via: SIP/2.0/UDP 10.10.10.62:55318;branch=z9hG4bK1198306856;rport=55318;received=CLIENT_IP From:sip:305@domain.net:PORT;tag=998538926 To:sip:301@domain.net:PORT;tag=5e1ee053cf7168b9d3ee9c05740f837c-270107a6 Call-ID:2104033891-55318-6@BA.CEG.HG.GC CSeq: 86920 SUBSCRIBE Expires: 60 Contact:sip:PUBLIC_IP:PORT Server: kamailio (5.4.4 (x86_64/linux)) Content-Length: 0

2021/10/02 19:06:04.292671 PUBLIC_IP:PORT -> CLIENT_IP:55318 NOTIFYsip:305@10.10.10.62:55318 SIP/2.0 Via: SIP/2.0/UDP PUBLIC_IP:PORT;branch=z9hG4bKffd9.40e91c22000000000000000000000000.0 To:sip:305@domain.net;tag=998538926 From:sip:301@domain.net;tag=5e1ee053cf7168b9d3ee9c05740f837c-270107a6 CSeq: 2 NOTIFY Call-ID:2104033891-55318-6@BA.CEG.HG.GC Content-Length: 273 User-Agent: kamailio (5.4.4 (x86_64/linux)) Max-Forwards: 70 Event: dialog Contact:sip:PUBLIC_IP:PORT Subscription-State: active;expires=60 Content-Type: application/dialog-info+xml

<?xml version="1.0"?>

<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="1" state="full" entity="sip:301@domain.net">   <dialog id="615293b33c62dec073e05d9421e9f48b" direction="recipient"> <state>terminated</state> </dialog> </dialog-info> ------ AFTER - WORKING ---------

The careful eye will notice NOTHING CHANGED..... Almost nothing... Of course the branch and (from/from/to) tags are different BUT THATS IT... So, not working, go for food, working... I changed nothing. I thought it was the never change version attribute in the dialog-info but, maybe not. Has anyone seen this ? If so, how did you get past it ? In case you're new to this thread, these are Grandstream GXP1782 desk phones. When I change the DNS A record for the host and point it to a FreeSWITCH system, the BLFs are fine. It's just when they reach Kamailio 5.4.4 that they don't sync up properly. Any pointers are welcome... Jerry

---

Kamailio - Users Mailing List - Non Commercial Discussions

• sr-users@lists.kamailio.org

Important: keep the mailing list in the recipients, do not reply only to the sender! Edit mailing list options or unsubscribe:

• https://lists.kamailio.org/cgi-bin/mailman/listinfo/sr-users

I had posted an example of one way of doing it:

https://github.com/fredposner/scripts/blob/master/kamailio/mid-registrar.cfg

Like most things in Kamailio, there's many ways to accomplish this; depending on the back end as well.

Fred Posner | palner.com Matrix: @fred:matrix.lod.com o: +1 (212) 937-7844

On 9/29/21 10:45 AM, Jerry Kendall wrote:

Hey there

I seem to recall a posting by Fred Posner on setting up mid-reg using Kamailio...

Does anyone have the link ?

Thanks...