[asterisk-users] SIP registry fails during night

Tue Jul 7 02:37:08 CDT 2009

Yet another night has passed...

This morning :

Verbosity is at least 25
asterisk*CLI> sip show peers
Name/username              Host            Dyn Nat ACL Port     Status
Realtime  
twinkle-candy/twinkle-can  (Unspecified)    D          0
UNKNOWN              
twinkle-jonas/twinkle-jon  (Unspecified)    D          0
UNKNOWN              
grandstream/grandstream    192.168.1.13     D          5060     OK (27
ms)           
3starsnet/092779077        85.119.188.3         N      5060     OK (17
ms)           
4 sip peers [Monitored: 2 online, 2 offline Unmonitored: 0 online, 0
offline]
asterisk*CLI> sip show registry 
Host                            Username       Refresh State
Reg.Time                 
85.119.188.3:5060       092779077          105 Failed              Mon,
06 Jul 2009 23:37:05

Then a 'sip reload' :

asterisk*CLI> sip reload
[Jul  7 09:28:15]  Reloading SIP
[Jul  7 09:28:15]   == Parsing '/etc/asterisk/sip.conf': [Jul  7
09:28:15] Found
[Jul  7 09:28:15]   == Parsing '/etc/asterisk/users.conf': [Jul  7
09:28:15] Found
[Jul  7 09:28:15]   == Parsing '/etc/asterisk/sip_notify.conf': [Jul  7
09:28:15] Found
asterisk*CLI> sip show peers
Name/username              Host            Dyn Nat ACL Port     Status
Realtime  
twinkle-candy/twinkle-can  (Unspecified)    D          0
UNKNOWN              
twinkle-jonas/twinkle-jon  (Unspecified)    D          0
UNKNOWN              
grandstream/grandstream    192.168.1.13     D          5060     OK (9
ms)            
3starsnet/092779077        85.119.188.3         N      5060     OK (18
ms)           
4 sip peers [Monitored: 2 online, 2 offline Unmonitored: 0 online, 0
offline]
asterisk*CLI> sip show registry
Host                            Username       Refresh State
Reg.Time                 
85.119.188.3:5060       092779077          105 Registered   Tue, 07 Jul
2009 09:28:16

So a 'sip reload' got me registered again... Did not need to restart
Asterisk...

/var/log/asterisk/messages :

[Jul  6 23:37:46] NOTICE[24975] chan_sip.c: Peer 'grandstream' is now
UNREACHABLE!  Last qualify: 33
[Jul  6 23:37:46] WARNING[24975] res_config_mysql.c: MySQL RealTime:
Failed to query database. Check debug for more info.
[Jul  6 23:38:03] NOTICE[24975] chan_sip.c: Peer '3starsnet' is now
UNREACHABLE!  Last qualify: 18
[Jul  6 23:38:03] WARNING[24975] res_config_mysql.c: MySQL RealTime:
Failed to query database. Check debug for more info.
[Jul  6 23:39:10] WARNING[24975] chan_sip.c: Maximum retries exceeded on
transmission 399240fc5f0fe8fb0e548a152c2c35b6 at 127.0.0.1 for seqno 248
(Critical Req
uest) -- See doc/sip-retransmit.txt.
[Jul  6 23:39:50] NOTICE[24975] chan_sip.c:    -- Registration for
'092779077 at 85.119.188.3' timed out, trying again (Attempt #1)
[Jul  6 23:40:30] WARNING[24975] chan_sip.c: Maximum retries exceeded on
transmission 399240fc5f0fe8fb0e548a152c2c35b6 at 127.0.0.1 for seqno 249
(Critical Req
uest) -- See doc/sip-retransmit.txt.
[Jul  6 23:41:10] NOTICE[24975] chan_sip.c:    -- Registration for
'092779077 at 85.119.188.3' timed out, trying again (Attempt #2)
[Jul  6 23:41:50] WARNING[24975] chan_sip.c: Maximum retries exceeded on
transmission 399240fc5f0fe8fb0e548a152c2c35b6 at 127.0.0.1 for seqno 250
(Critical Req
uest) -- See doc/sip-retransmit.txt.
[Jul  6 23:42:30] NOTICE[24975] chan_sip.c:    -- Registration for
'092779077 at 85.119.188.3' timed out, trying again (Attempt #3)
...
[Jul  7 00:55:51] NOTICE[24975] chan_sip.c:    -- Registration for
'092779077 at 85.119.188.3' timed out, trying again (Attempt #58)
[Jul  7 00:56:31] WARNING[24975] chan_sip.c: Maximum retries exceeded on
transmission 399240fc5f0fe8fb0e548a152c2c35b6 at 127.0.0.1 for seqno 306
(Critical Req
uest) -- See doc/sip-retransmit.txt.
[Jul  7 00:57:11] NOTICE[24975] chan_sip.c:    -- Registration for
'092779077 at 85.119.188.3' timed out, trying again (Attempt #59)
[Jul  7 00:57:51] WARNING[24975] chan_sip.c: Maximum retries exceeded on
transmission 399240fc5f0fe8fb0e548a152c2c35b6 at 127.0.0.1 for seqno 307
(Critical Req
uest) -- See doc/sip-retransmit.txt.
[Jul  7 00:58:31] NOTICE[24975] chan_sip.c:    -- Registration for
'092779077 at 85.119.188.3' timed out, trying again (Attempt #60)
[Jul  7 00:59:11] WARNING[24975] chan_sip.c: Maximum retries exceeded on
transmission 399240fc5f0fe8fb0e548a152c2c35b6 at 127.0.0.1 for seqno 308
(Critical Req
uest) -- See doc/sip-retransmit.txt.
[Jul  7 00:59:51] NOTICE[24975] chan_sip.c:    -- Registration for
'092779077 at 85.119.188.3' timed out, trying again (Attempt #61)
[Jul  7 00:59:51] NOTICE[24975] chan_sip.c:    -- Giving up forever
trying to register '092779077 at 85.119.188.3'

Trying to register goes on for an hour, every minute...
I don't know why @ 23:39 registration suddenly fails...

Philipp, in my register-statement I use the IP-address of my
SIP-provider, not the hostname.tld.

Jonas.

On Mon, 2009-07-06 at 15:14 +0200, Philipp von Klitzing wrote:

> Hi!
> 
> > Every morning I check my SIP registry to the SIP-provider. And I must
> > conclude that during the night somewhere registry has failed.
> > 
> > I must do a 'sip reload' to get registered again.
> 
> Can you ALWAYS solve this with a SIP RELOAD, or is it sometimes necessary 
> to restart Asterisk?
> 
> Anyway, take a look:
> 
> https://issues.asterisk.org/view.php?id=15052
> https://issues.asterisk.org/view.php?id=15139
> https://issues.asterisk.org/view.php?id=14518
> https://issues.asterisk.org/view.php?id=12312
> 
> Maybe these bugs will be of help to you - try to change the register 
> address to use the IP address instead of hostname and see if that 
> improves the situation.
> 
> Philipp
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20090707/abb29e2f/attachment.htm 