D6X phones show offline after a period of time


#1

FreePBX 15 Distro
Asterisk 17.9.1
EPM with DPMA
PJSip Extensions

Have a mix of D65,62 and 60 phones connecting to a cloud PBX using the built in OpenVPN server. The phones connect and operate fine. Then after a while they all go offline however, I can still ping their VPN IP’s from the PBX.

Right around the time they go offline I see this in the logs:

[2021-03-31 07:56:09] VERBOSE[24530] res_pjsip/pjsip_configuration.c: Endpoint 1201 is now Unreachable
[2021-03-31 07:56:09] VERBOSE[24530] res_pjsip/pjsip_options.c: Contact 1201/sip:1201@10.8.0.4:5070;ob is now Unreachable. RTT: 0.000 msec
[2021-03-31 07:56:41] VERBOSE[19676] res_pjsip/pjsip_configuration.c: Endpoint 1203 is now Unreachable
[2021-03-31 07:56:41] VERBOSE[19676] res_pjsip/pjsip_options.c: Contact 1203/sip:1203@10.8.0.5:5070;ob is now Unreachable. RTT: 0.000 msec
[2021-03-31 07:56:45] VERBOSE[19676] res_pjsip/pjsip_configuration.c: Endpoint 1205 is now Unreachable
[2021-03-31 07:56:45] VERBOSE[19676] res_pjsip/pjsip_options.c: Contact 1205/sip:1205@10.8.0.7:5070;ob is now Unreachable. RTT: 0.000 msec
[2021-03-31 07:56:45] VERBOSE[19676] res_pjsip/pjsip_configuration.c: Endpoint 1207 is now Unreachable
[2021-03-31 07:56:45] VERBOSE[19676] res_pjsip/pjsip_options.c: Contact 1207/sip:1207@10.8.0.9:5070;ob is now Unreachable. RTT: 0.000 msec
[2021-03-31 07:56:46] VERBOSE[13935] res_pjsip/pjsip_configuration.c: Endpoint 1200 is now Unreachable
[2021-03-31 07:56:46] VERBOSE[13935] res_pjsip/pjsip_options.c: Contact 1200/sip:1200@10.8.0.3:5070;ob is now Unreachable. RTT: 0.000 msec
[2021-03-31 07:56:46] VERBOSE[19584] res_pjsip/pjsip_configuration.c: Endpoint 1204 is now Unreachable
[2021-03-31 07:56:46] VERBOSE[19584] res_pjsip/pjsip_options.c: Contact 1204/sip:1204@10.8.0.6:5070;ob is now Unreachable. RTT: 0.000 msec
[2021-03-31 07:56:47] VERBOSE[13935] res_pjsip/pjsip_configuration.c: Endpoint 1206 is now Unreachable
[2021-03-31 07:56:47] VERBOSE[13935] res_pjsip/pjsip_options.c: Contact 1206/sip:1206@10.8.0.8:5070;ob is now Unreachable. RTT: 0.000 msec
[2021-03-31 07:56:48] VERBOSE[19676] res_pjsip/pjsip_configuration.c: Endpoint 1202 is now Unreachable
[2021-03-31 07:56:48] VERBOSE[19676] res_pjsip/pjsip_options.c: Contact 1202/sip:1202@10.8.0.14:5070;ob is now Unreachable. RTT: 0.000 msec
[2021-03-31 07:56:51] VERBOSE[19676] res_pjsip/pjsip_configuration.c: Endpoint 1208 is now Unreachable
[2021-03-31 07:56:51] VERBOSE[19676] res_pjsip/pjsip_options.c: Contact 1208/sip:1208@10.8.0.10:5070;ob is now Unreachable. RTT: 0.000 msec
[2021-03-31 07:59:06] VERBOSE[15469] res_pjsip_registrar.c: Removed contact ‘sip:1201@10.8.0.4:5070;ob’ from AOR ‘1201’ due to expiration
[2021-03-31 07:59:06] VERBOSE[24530] res_pjsip/pjsip_options.c: Contact 1201/sip:1201@10.8.0.4:5070;ob has been deleted
[2021-03-31 08:00:06] VERBOSE[15469] res_pjsip_registrar.c: Removed contact ‘sip:1200@10.8.0.3:5070;ob’ from AOR ‘1200’ due to expiration
[2021-03-31 08:00:06] VERBOSE[15469] res_pjsip_registrar.c: Removed contact ‘sip:1202@10.8.0.14:5070;ob’ from AOR ‘1202’ due to expiration
[2021-03-31 08:00:06] VERBOSE[15469] res_pjsip_registrar.c: Removed contact ‘sip:1203@10.8.0.5:5070;ob’ from AOR ‘1203’ due to expiration
[2021-03-31 08:00:06] VERBOSE[15469] res_pjsip_registrar.c: Removed contact ‘sip:1204@10.8.0.6:5070;ob’ from AOR ‘1204’ due to expiration
[2021-03-31 08:00:06] VERBOSE[15469] res_pjsip_registrar.c: Removed contact ‘sip:1205@10.8.0.7:5070;ob’ from AOR ‘1205’ due to expiration
[2021-03-31 08:00:06] VERBOSE[15469] res_pjsip_registrar.c: Removed contact ‘sip:1206@10.8.0.8:5070;ob’ from AOR ‘1206’ due to expiration
[2021-03-31 08:00:06] VERBOSE[15469] res_pjsip_registrar.c: Removed contact ‘sip:1207@10.8.0.9:5070;ob’ from AOR ‘1207’ due to expiration
[2021-03-31 08:00:06] VERBOSE[15469] res_pjsip_registrar.c: Removed contact ‘sip:1208@10.8.0.10:5070;ob’ from AOR ‘1208’ due to expiration
[2021-03-31 08:00:06] VERBOSE[19584] res_pjsip/pjsip_options.c: Contact 1200/sip:1200@10.8.0.3:5070;ob has been deleted
[2021-03-31 08:00:06] VERBOSE[24530] res_pjsip/pjsip_options.c: Contact 1202/sip:1202@10.8.0.14:5070;ob has been deleted
[2021-03-31 08:00:06] VERBOSE[19676] res_pjsip/pjsip_options.c: Contact 1203/sip:1203@10.8.0.5:5070;ob has been deleted
[2021-03-31 08:00:06] VERBOSE[13935] res_pjsip/pjsip_options.c: Contact 1204/sip:1204@10.8.0.6:5070;ob has been deleted
[2021-03-31 08:00:06] VERBOSE[19584] res_pjsip/pjsip_options.c: Contact 1205/sip:1205@10.8.0.7:5070;ob has been deleted
[2021-03-31 08:00:06] VERBOSE[24530] res_pjsip/pjsip_options.c: Contact 1206/sip:1206@10.8.0.8:5070;ob has been deleted
[2021-03-31 08:00:06] VERBOSE[19676] res_pjsip/pjsip_options.c: Contact 1207/sip:1207@10.8.0.9:5070;ob has been deleted
[2021-03-31 08:00:06] VERBOSE[13935] res_pjsip/pjsip_options.c: Contact 1208/sip:1208@10.8.0.10:5070;ob has been deleted

I have a test phone setup identically but at a differently location and it’s remained online for over 24 hours.

Is there a firewall (not FreePBX) setting for the location’s firewall that is perhaps timing out connections? If the extensions are offline, why can I still ping their VPN Address?

A reboot of all the phones gets them back online for a while, how long seems to vary.


#2

I’ve changed out the local firewall/router for a model I have in other locations. still phones drop off. the latest in the logs shows here:

5710 [2021-04-02 11:16:04] VERBOSE[24530] res_pjsip_registrar.c: Added contact ‘sip:1200@10.8.0.3:5070;ob’ to AOR ‘1200’ with expiration of 300 seconds
5711 [2021-04-02 11:16:04] VERBOSE[19584] res_pjsip/pjsip_configuration.c: Endpoint 1200 is now Reachable
5712 [2021-04-02 11:16:04] VERBOSE[19584] res_pjsip/pjsip_options.c: Contact 1200/sip:1200@10.8.0.3:5070;ob is now Reachable. RTT: 58.510 msec
5713 [2021-04-02 11:32:07] VERBOSE[24530] res_pjsip/pjsip_configuration.c: Endpoint 1200 is now Unreachable
5714 [2021-04-02 11:32:07] VERBOSE[24530] res_pjsip/pjsip_options.c: Contact 1200/sip:1200@10.8.0.3:5070;ob is now Unreachable. RTT: 0.000 msec
5715 [2021-04-02 11:36:13] VERBOSE[15469] res_pjsip_registrar.c: Removed contact ‘sip:1200@10.8.0.3:5070;ob’ from AOR ‘1200’ due to expiration
5716 [2021-04-02 11:36:13] VERBOSE[13935] res_pjsip/pjsip_options.c: Contact 1200/sip:1200@10.8.0.3:5070;ob has been deleted

Any idea why this is happening? I have a test phone at another location, same model firewall/router that has been online for days with no issues.