[Home]

Summary:ASTERISK-11607: chan_iax2.c expiring registrations prematurely on an Asterisk that has been running a long time
Reporter:Steve Davies . (stevedavies)Labels:
Date Opened:2008-07-21 10:17:00Date Closed:2011-06-07 14:07:21
Priority:MinorRegression?No
Status:Closed/CompleteComponents:Channels/chan_iax2
Versions:Frequency of
Occurrence
Related
Issues:
Environment:Attachments:
Description:I have a busy Asterisk system.
It has an uptime of 5 weeks, 5 days, 16 hours, 20 minutes, 16 seconds at the moment.
This system has started prematurely expiring IAX2 registrations.
See in the "additional information".  Expiry of the registration should be 179 seconds, but it is actually being expired after 40 seconds.

Looking back in the logs, this problem started happening today as far as I can see.

It appears to be affecting some other peer registrations, but not all.

Steve

****** ADDITIONAL INFORMATION ******

/var/log/asterisk/full, relevant stuff:

REGISTRATION received at 16:07:13 with 179 second expiry:

[Jul 21 16:07:13] VERBOSE[1310] logger.c: [Jul 21 16:07:13] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: REGREQ
[Jul 21 16:07:13] VERBOSE[1310] logger.c: [Jul 21 16:07:13]    Timestamp: 00017ms  SCall: 00008  DCall: 00000 [41.195.42.195:4569]
[Jul 21 16:07:13] VERBOSE[1310] logger.c: [Jul 21 16:07:13]    USERNAME        : ctelpbx-1
[Jul 21 16:07:13] VERBOSE[1310] logger.c: [Jul 21 16:07:13]    REFRESH         : 179
[Jul 21 16:07:13] VERBOSE[1310] logger.c: [Jul 21 16:07:13]

[Jul 21 16:07:13] VERBOSE[10652] logger.c: [Jul 21 16:07:13] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: REGAUTH
[Jul 21 16:07:13] VERBOSE[10652] logger.c: [Jul 21 16:07:13]    Timestamp: 00003ms  SCall: 10765  DCall: 00008 [41.195.42.195:4569]
[Jul 21 16:07:13] VERBOSE[10652] logger.c: [Jul 21 16:07:13]    AUTHMETHODS     : 2
[Jul 21 16:07:13] VERBOSE[10652] logger.c: [Jul 21 16:07:13]    CHALLENGE       : 124547351
[Jul 21 16:07:13] VERBOSE[10652] logger.c: [Jul 21 16:07:13]    USERNAME        : ctelpbx-1
[Jul 21 16:07:13] VERBOSE[10652] logger.c: [Jul 21 16:07:13]

[Jul 21 16:07:13] VERBOSE[23554] logger.c: [Jul 21 16:07:13] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: REGREQ
[Jul 21 16:07:13] VERBOSE[23554] logger.c: [Jul 21 16:07:13]    Timestamp: 00086ms  SCall: 00008  DCall: 10765 [41.195.42.195:4569]
[Jul 21 16:07:13] VERBOSE[23554] logger.c: [Jul 21 16:07:13]    USERNAME        : ctelpbx-1
[Jul 21 16:07:13] VERBOSE[23554] logger.c: [Jul 21 16:07:13]    REFRESH         : 179
[Jul 21 16:07:13] VERBOSE[23554] logger.c: [Jul 21 16:07:13]    MD5 RESULT      : cd732da08f6584c3c6c255cc5b05fd62
[Jul 21 16:07:13] VERBOSE[23554] logger.c: [Jul 21 16:07:13]

[Jul 21 16:07:13] VERBOSE[23554] logger.c: [Jul 21 16:07:13]     -- Registered IAX2 'ctelpbx-1' (AUTHENTICATED) at 41.195.42.195:4569

[Jul 21 16:07:13] VERBOSE[10652] logger.c: [Jul 21 16:07:13] Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: REGACK
[Jul 21 16:07:13] VERBOSE[10652] logger.c: [Jul 21 16:07:13]    Timestamp: 00074ms  SCall: 10765  DCall: 00008 [41.195.42.195:4569]
[Jul 21 16:07:13] VERBOSE[10652] logger.c: [Jul 21 16:07:13]    USERNAME        : ctelpbx-1
[Jul 21 16:07:13] VERBOSE[10652] logger.c: [Jul 21 16:07:13]    DATE TIME       : 2008-07-21  16:07:12
[Jul 21 16:07:13] VERBOSE[10652] logger.c: [Jul 21 16:07:13]    REFRESH         : 179
[Jul 21 16:07:13] VERBOSE[10652] logger.c: [Jul 21 16:07:13]    APPARENT ADDRES : IPV4 41.195.42.195:4569
[Jul 21 16:07:13] VERBOSE[10652] logger.c: [Jul 21 16:07:13]

Expired just 40 seconds later:

[Jul 21 16:07:53] DEBUG[31505] chan_iax2.c: Expiring registration for peer 'ctelpbx-1'

[Jul 21 16:09:42] VERBOSE[194] logger.c: [Jul 21 16:09:42] Rx-Frame Retry[ No] -- OSeqno: 000 ISeqno: 000 Type: IAX     Subclass: REGREQ
[Jul 21 16:09:42] VERBOSE[194] logger.c: [Jul 21 16:09:42]    Timestamp: 00001ms  SCall: 00010  DCall: 00000 [41.195.42.195:4569]
[Jul 21 16:09:42] VERBOSE[194] logger.c: [Jul 21 16:09:42]    USERNAME        : ctelpbx-1
[Jul 21 16:09:42] VERBOSE[194] logger.c: [Jul 21 16:09:42]    REFRESH         : 179
[Jul 21 16:09:42] VERBOSE[194] logger.c: [Jul 21 16:09:42]

[Jul 21 16:09:42] VERBOSE[10652] logger.c: [Jul 21 16:09:42] Tx-Frame Retry[000] -- OSeqno: 000 ISeqno: 001 Type: IAX     Subclass: REGAUTH
[Jul 21 16:09:42] VERBOSE[10652] logger.c: [Jul 21 16:09:42]    Timestamp: 00010ms  SCall: 03758  DCall: 00010 [41.195.42.195:4569]
[Jul 21 16:09:42] VERBOSE[10652] logger.c: [Jul 21 16:09:42]    AUTHMETHODS     : 2
[Jul 21 16:09:42] VERBOSE[10652] logger.c: [Jul 21 16:09:42]    CHALLENGE       : 177268742
[Jul 21 16:09:42] VERBOSE[10652] logger.c: [Jul 21 16:09:42]    USERNAME        : ctelpbx-1
[Jul 21 16:09:42] VERBOSE[10652] logger.c: [Jul 21 16:09:42]

[Jul 21 16:09:42] VERBOSE[30483] logger.c: [Jul 21 16:09:42] Rx-Frame Retry[ No] -- OSeqno: 001 ISeqno: 001 Type: IAX     Subclass: REGREQ
[Jul 21 16:09:42] VERBOSE[30483] logger.c: [Jul 21 16:09:42]    Timestamp: 00070ms  SCall: 00010  DCall: 03758 [41.195.42.195:4569]
[Jul 21 16:09:42] VERBOSE[30483] logger.c: [Jul 21 16:09:42]    USERNAME        : ctelpbx-1
[Jul 21 16:09:42] VERBOSE[30483] logger.c: [Jul 21 16:09:42]    REFRESH         : 179
[Jul 21 16:09:42] VERBOSE[30483] logger.c: [Jul 21 16:09:42]    MD5 RESULT      : 5fef3011028ae7b5950b32f424731e5e
[Jul 21 16:09:42] VERBOSE[30483] logger.c: [Jul 21 16:09:42]

[Jul 21 16:09:43] VERBOSE[30483] logger.c: [Jul 21 16:09:43]     -- Registered IAX2 'ctelpbx-1' (AUTHENTICATED) at 41.195.42.195:4569

[Jul 21 16:09:43] VERBOSE[10652] logger.c: [Jul 21 16:09:43] Tx-Frame Retry[000] -- OSeqno: 001 ISeqno: 002 Type: IAX     Subclass: REGACK
[Jul 21 16:09:43] VERBOSE[10652] logger.c: [Jul 21 16:09:43]    Timestamp: 00085ms  SCall: 03758  DCall: 00010 [41.195.42.195:4569]
[Jul 21 16:09:43] VERBOSE[10652] logger.c: [Jul 21 16:09:43]    USERNAME        : ctelpbx-1
[Jul 21 16:09:43] VERBOSE[10652] logger.c: [Jul 21 16:09:43]    DATE TIME       : 2008-07-21  16:09:42
[Jul 21 16:09:43] VERBOSE[10652] logger.c: [Jul 21 16:09:43]    REFRESH         : 179
[Jul 21 16:09:43] VERBOSE[10652] logger.c: [Jul 21 16:09:43]    APPARENT ADDRES : IPV4 41.195.42.195:4569
[Jul 21 16:09:43] VERBOSE[10652] logger.c: [Jul 21 16:09:43]

[Jul 21 16:10:22] DEBUG[15720] chan_iax2.c: Expiring registration for peer 'ctelpbx-1'

Comments:By: Leif Madsen (lmadsen) 2008-10-14 12:15:03

I'm acknowledging this issue, but I debated leaving it set to new, as I'm not entirely sure how to start debugging this issue, or even what to ask you for. Unfortunately I'm not an IAX2 user really, so I'm going to assign this to file, just in order to see if he has any ideas for initial triage work. Please reassign as appropriate. Thanks!
By: Tilghman Lesher (tilghman) 2009-01-09 19:03:59.000-0600

Do you get any additional warnings when you compile with './configure --enable-dev-mode' ?
By: Steve Davies . (stevedavies) 2009-01-12 04:26:28.000-0600

Hi,

This ticket has been in Mantis for ages.  I have not had a repeat of this issue since that time.

So I'd propose you simply close it!

Steve
By: Leif Madsen (lmadsen) 2009-01-12 09:48:11.000-0600

Closed at the request of the reporter.