forum.bitel.ru
http://forum.bitel.ru/

Наложение даты сессий друг на друга.
http://forum.bitel.ru/viewtopic.php?f=5&t=562		 Страница 1 из 1
Автор:  S [ 21 ноя 2007, 12:50 ]
Заголовок сообщения:  Наложение даты сессий друг на друга.
Версия биллинга 4.2.
Возникла проблема с наложением даты\времени у РРРоЕ-сессий. Данный тип сессий у нас не отключается сутками, и потом, когда происходит сброс сессии, в отчетах у клиента и в админке видно наложение времени сброса\окончания сесси и начала следующей. Время на NAS, BGBilling синхронизируется с NTP-сервером. Однако, если это VPN-сессия, они как правило не более пары дней работают, то все там ок.

Лог клиента:
Код:
Время входа   Время выхода   Длительность[в секундах]
01.11.2007 12:55:05   13.11.2007 09:45:07   284:50:02 [1025402]
13.11.2007 10:02:28   22.11.2007 00:57:49   206:55:21 [744921]
21.11.2007 11:19:01   21.11.2007 13:07:04   01:48:03 [6483]   

Лог BGRadius:
Код:
INFO   29.10.2007 12:36:08  DialUpNASConnection startConnection mode=1
INFO   31.10.2007 07:21:44  DialUpNASConnection stoppingConnection
INFO   31.10.2007 07:21:44  DialUpNASConnection has stop Packet => true
INFO   31.10.2007 07:21:44  DialUpNASConnection sessionTime => 150243
INFO   31.10.2007 07:22:36  DialUpNASConnection startConnection mode=1
INFO   01.11.2007 12:55:02  DialUpNASConnection stoppingConnection
INFO   01.11.2007 12:55:02  DialUpNASConnection has stop Packet => false
INFO   01.11.2007 12:55:05  DialUpNASConnection startConnection mode=1
INFO   13.11.2007 09:34:34 DialUpNASConnection stoppingConnection
INFO   13.11.2007 09:34:34 DialUpNASConnection has stop Packet => true
INFO   13.11.2007 09:34:34 DialUpNASConnection sessionTime => 1025402
INFO   13.11.2007 10:02:28 DialUpNASConnection startConnection mode=1
INFO   21.11.2007 09:46:38  DialUpNASConnection stoppingConnection
INFO   21.11.2007 09:46:38  DialUpNASConnection has stop Packet => true
INFO   21.11.2007 09:46:38  DialUpNASConnection sessionTime => 744921
INFO   21.11.2007 11:19:01  DialUpNASConnection startConnection mode=1

Пока писал пост, понял почему наложение происходит. Но вот почему прилетает пакет останова, а он false и сессия висит дальше, вот вопрос.
Логи одной сессии:
Код:
13 10:02:28
Type=AUTHENTICATION_REQUEST
Attributes:
NAS-Port=9
Service-Type=2
Framed-Protocol=1
Calling-Station-Id=008048495e5e
NAS-Port-Type=5

13 10:02:28
Type=AUTHENTICATION_ACCEPT
Process time:104
Attributes:
Acct-Interim-Interval=20
Service-Type=2
Framed-Protocol=1
Framed-IP-Address=***********

13 10:02:28
Type=ACCOUNTING_REQUEST
Attributes:
NAS-Port=9
Service-Type=2
Framed-Protocol=1
Framed-IP-Address=**********
Acct-Status-Type=1
Acct-Session-Id=4922948-pppoe9
Acct-Authentic=1
Acct-Link-Count=1
Acct-Multi-Session-Id=4922948-pppoe9
NAS-Port-Type=5
Calling-Station-Id=008048495e5e

21 09:46:38
Type=ACCOUNTING_REQUEST
Attributes:
NAS-Port=9
Service-Type=2
Framed-Protocol=1
Acct-Input-Octets=1232503019
Framed-IP-Address=**************
Acct-Output-Octets=543527433
Acct-Status-Type=2
Acct-Session-Time=744921
Acct-Input-Packets=5796375
Acct-Session-Id=4922948-pppoe9
Acct-Authentic=1
Acct-Link-Count=1
Acct-Multi-Session-Id=4922948-pppoe9
Acct-Terminate-Cause=1
Acct-Output-Packets=6468889
Acct-Output-Gigawords=1
Acct-Input-Gigawords=0
NAS-Port-Type=5
Calling-Station-Id=008048495e5e


Думаю дело где-то в NAS, или я пропускаю что-то. Не понятно откуда береться пакет останова и почему он отвергается.
Автор:  Администратор [ 22 ноя 2007, 19:16 ]
Заголовок сообщения: 
Вытащите из connection.log логи для такой сессии. Вы точно в мониторе обновить нажали?
Автор:  S [ 26 ноя 2007, 08:57 ]
Заголовок сообщения: 
Монитор тут ни при чем, в мониторе как раз все тип-топ, логи я вытащил как раз из connection.log, за те числа, что представил выше, и вот тут непонятно, почему происходит такой процесс, когда прилетает стоповый пакет, он игнорируется и следом стартует сессия, вот почему это происходит и как это отследить не понятно. В логах радиуса вроде как все сходится, я еще раз просмотрю логи наса, может что там не в порядке. Ни у кого такого нет?
Автор:  Администратор [ 26 ноя 2007, 13:52 ]
Заголовок сообщения: 
Этому вот стопу
Код:
21 09:46:38
Type=ACCOUNTING_REQUEST
Attributes:
NAS-Port=9
Service-Type=2
Framed-Protocol=1
Acct-Input-Octets=1232503019
Framed-IP-Address=**************
Acct-Output-Octets=543527433
Acct-Status-Type=2
Acct-Session-Time=744921
Acct-Input-Packets=5796375
Acct-Session-Id=4922948-pppoe9
Acct-Authentic=1
Acct-Link-Count=1
Acct-Multi-Session-Id=4922948-pppoe9
Acct-Terminate-Cause=1
Acct-Output-Packets=6468889
Acct-Output-Gigawords=1
Acct-Input-Gigawords=0
NAS-Port-Type=5
Calling-Station-Id=008048495e5e


соответсвует запись в логе:

Код:
INFO   21.11.2007 09:46:38  DialUpNASConnection stoppingConnection
INFO   21.11.2007 09:46:38  DialUpNASConnection has stop Packet => true
INFO   21.11.2007 09:46:38  DialUpNASConnection sessionTime => 744921
INFO   21.11.2007 11:19:01  DialUpNASConnection startConnection mode=1


все отработало корректно.
"Игнорирование" может произойти, если например, стоп пакет пришел после того как на этот же порт пришла авторизация следующего юзера.
Попробуйте найти пакеты из radius.log, соответсвующие "сомнительным" строкам connection.log
Страница 1 из 1 Часовой пояс: UTC + 5 часов [ Летнее время ]
Powered by phpBB® Forum Software © phpBB Group
http://www.phpbb.com/