Registered and unregistered extensions - repeatedly

I’m running FreePBX 15.0.17.64 on a VULTR server (static IP.) I have mostly old Panasonic IP phones. But there are two older Yealink T41P phones, as well. The two Yealink phones are a two remote sites, both with fiber internet connections. The two phones are configured to talk directly to the VULTR server, so there’s no need for a VPN. Both phones/extensions have their “qualify frequency” set in FreePBX to 180. The phones themselves are configured via the web interface under ACCOUNT to “SERVER EXPIRES 180” and “SERVER RETRY COUNTS 3”.

Yet, I’m constantly receiving “registered” and “unregistered” messages on these phones. (See the log below.) The two extensions only appear on one phone (extension 100), so I understand the “Idle for Notify User 100” messages. I’ve rebooted the FreePBX server multiple times and the problem continues. Looking closer at the log, I can see the interval between REGISTERED and UNREGISTERED for x120 is exactly 90 seconds. I don’t know what’s going on with x121. What am I missing?

[2021-12-16 19:29:26] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
[2021-12-16 19:29:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
[2021-12-16 19:29:26] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:9552
[2021-12-16 19:29:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
[2021-12-16 19:30:06] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:57693
[2021-12-16 19:30:56] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:56835
[2021-12-16 19:32:06] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:12292
[2021-12-16 19:32:26] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
[2021-12-16 19:32:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
[2021-12-16 19:32:26] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:56835
[2021-12-16 19:32:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
[2021-12-16 19:33:06] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:29654
[2021-12-16 19:34:06] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:12292
[2021-12-16 19:35:06] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:25844
[2021-12-16 19:35:26] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
[2021-12-16 19:35:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
[2021-12-16 19:35:26] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:55284
[2021-12-16 19:35:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
[2021-12-16 19:36:06] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:12292
[2021-12-16 19:38:06] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:28363
[2021-12-16 19:38:26] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
[2021-12-16 19:38:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
[2021-12-16 19:38:26] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:36931
[2021-12-16 19:38:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
[2021-12-16 19:39:56] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:32010
[2021-12-16 19:41:26] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
[2021-12-16 19:41:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
[2021-12-16 19:41:26] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:32010
[2021-12-16 19:41:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
[2021-12-16 19:42:56] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:26633
[2021-12-16 19:43:06] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:12292
[2021-12-16 19:44:26] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
[2021-12-16 19:44:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
[2021-12-16 19:44:26] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:26633
[2021-12-16 19:44:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
[2021-12-16 19:46:06] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:19980
[2021-12-16 19:47:06] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:12292
[2021-12-16 19:47:26] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
[2021-12-16 19:47:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
[2021-12-16 19:47:26] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:2382
[2021-12-16 19:47:26] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
[2021-12-16 19:48:56] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:44253
[2021-12-16 19:50:06] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:37103
[2021-12-16 19:50:27] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:9319
[2021-12-16 19:53:06] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:12292
[2021-12-16 19:53:26] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’

LOG FROM LATER IN THE DAY

77159 [2021-12-16 21:08:06] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:5572
77160 [2021-12-16 21:08:28] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
77161 [2021-12-16 21:08:28] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
77162 [2021-12-16 21:08:28] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:3001
77163 [2021-12-16 21:08:28] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
77164 [2021-12-16 21:10:28] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:31263
77165 [2021-12-16 21:11:07] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:12292
77166 [2021-12-16 21:11:28] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
77167 [2021-12-16 21:11:28] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
77168 [2021-12-16 21:11:29] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:31263
77169 [2021-12-16 21:11:29] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
77170 [2021-12-16 21:12:07] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:39928
77171 [2021-12-16 21:12:22] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘121’
77172 [2021-12-16 21:12:22] VERBOSE[3278] chan_sip.c: Extension Changed 121[ext-local] new state Unavailable for Notify User 100
77173 [2021-12-16 21:12:22] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:39928
77174 [2021-12-16 21:12:22] VERBOSE[3278] chan_sip.c: Extension Changed 121[ext-local] new state Idle for Notify User 100
77175 [2021-12-16 21:14:28] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
77176 [2021-12-16 21:14:28] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
77177 [2021-12-16 21:14:29] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:31263
77178 [2021-12-16 21:14:29] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
77179 [2021-12-16 21:15:26] NOTICE[3488] chan_sip.c: Peer ‘121’ is now UNREACHABLE! Last qualify: 55
77180 [2021-12-16 21:15:26] VERBOSE[3278] chan_sip.c: Extension Changed 121[ext-local] new state Unavailable for Notify User 100
77180 [2021-12-16 21:15:26] VERBOSE[3278] chan_sip.c: Extension Changed 121[ext-local] new state Unavailable for Notify User 100
77181 [2021-12-16 21:17:28] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
77182 [2021-12-16 21:17:28] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
77183 [2021-12-16 21:17:28] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:31263
77184 [2021-12-16 21:17:28] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
77185 [2021-12-16 21:19:29] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘121’
77186 [2021-12-16 21:19:29] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:12292
77187 [2021-12-16 21:19:29] NOTICE[3488] chan_sip.c: Peer ‘121’ is now Reachable. (20ms / 2000ms)
77188 [2021-12-16 21:19:29] VERBOSE[3278] chan_sip.c: Extension Changed 121[ext-local] new state Idle for Notify User 100
77189 [2021-12-16 21:21:30] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:27287
77190 [2021-12-16 21:22:30] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:12292
77191 [2021-12-16 21:23:30] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:7647
77192 [2021-12-16 21:26:28] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
77193 [2021-12-16 21:26:28] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
77194 [2021-12-16 21:26:28] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:10443
77195 [2021-12-16 21:26:28] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
77196 [2021-12-16 21:29:28] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
77197 [2021-12-16 21:29:28] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
77198 [2021-12-16 21:29:28] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:30762
77199 [2021-12-16 21:29:28] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
77200 [2021-12-16 21:30:30] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:12292
77201 [2021-12-16 21:32:30] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:61175
77202 [2021-12-16 21:32:31] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
77203 [2021-12-16 21:32:31] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
77204 [2021-12-16 21:32:31] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:13137
77205 [2021-12-16 21:32:31] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
77206 [2021-12-16 21:33:31] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:12292
77207 [2021-12-16 21:34:31] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:32272
77208 [2021-12-16 21:35:28] VERBOSE[3488] chan_sip.c: Unregistered SIP ‘120’
77209 [2021-12-16 21:35:28] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Unavailable for Notify User 100
77210 [2021-12-16 21:35:29] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:13137
77211 [2021-12-16 21:35:29] VERBOSE[3278] chan_sip.c: Extension Changed 120[ext-local] new state Idle for Notify User 100
77212 [2021-12-16 21:35:31] VERBOSE[3488] chan_sip.c: Registered SIP ‘121’ at 216.23x.xxx.xxx:12292
77213 [2021-12-16 21:37:28] VERBOSE[3488] chan_sip.c: Registered SIP ‘120’ at 108.20x.xxx.xxx:48782

The port number is changing with each registration, so a NAT association is being lost, probably because the router/firewall at the site has aggressive timeouts.

For the Yealink account in question, try setting Keep Alive Type to Options and Keep Alive Interval to 30.

If no luck, try setting Server Expires to 60.

If still no luck, please post for each affected site: Modem make/model? In bridge mode? Separate router/firewall, if any? Any VoIP-related settings (SIP ALG, port forwards, etc.)?

This topic was automatically closed 31 days after the last reply. New replies are no longer allowed.