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

обрыв сессий
http://forum.bitel.ru/viewtopic.php?f=44&t=7951		 Страница 1 из 1
Автор:  sergey-xxi [ 11 апр 2013, 14:15 ]
Заголовок сообщения:  обрыв сессий
Сессия стартует в 00:00, после разрывается в 3 часа ночи и тут же через минуту восстанавливается.
При этом в логе радиуса присутствуют данные других абонентов в момент когда сессия закрывается.
Код:
Time: 10.04.2013 03:23:32
Packet type: Access-Request
Identifier: 181
Authenticator: {28 2A 60 62 3D D0 4B AD 93 80 89 86 85 38 29 D8}
Attributes:
  User-Name=18:a9:05:a2:d4:19
  NAS-Port-Id=2/3 clips 193859
  NAS-Identifier=RedBack
  User-Password=�6�9�T��n�\u3�ӷ�3
  NAS-IP-Address=192.168.12.247
  NAS-Port=33751040
  Service-Type=5
  NAS-Port-Type=5
  Called-Station-Id=10.21.37.1
  Platform-Type=4
  UNKNOWN[2352-201]={01 0A 15 25 01}
  Medium-Type=11
  Agent-Remote-Id={00 06 00 12 CF C8 9D D5}
  DHCP-Option={3D 3D 07 01 18 A9 05 A2 D4 19}
  DHCP-Option={0C 0C 06 68 65 61 76 65 6E}
  Agent-Circuit-Id={00 04 04 0D 01 05}
  OS-Version=6.5.1.5
  Mac-Addr=18-a9-05-a2-d4-19
  UNKNOWN[2352-125]={4D 53 46 54 20 35 2E 30}
  NAS-Real-Port=33751040
  UNKNOWN[3561--1]={02 0A 00 06 00 12 CF C8 9D D5}
  UNKNOWN[3561--1]={01 08 00 04 04 0D 01 05}

Time: 10.04.2013 03:23:32
Packet type: Access-Accept
Identifier: 181
Authenticator: {D9 DA 30 1D 2D 75 2F 17 85 FA C5 D9 BA AE 65 7C}
Attributes:
  Acct-Interim-Interval=900
  DHCP-Max-Leases=1
  Service-Name=SE
  Service-Options:0=1
  Service-Parameter=Rate=30000 Burst=3750000
  IP-Interface-Name=clients

Process time auth: 0


Time: 10.04.2013 03:23:32
Packet type: Accounting-Request
Identifier: 190
Authenticator: {36 DD E1 62 4A 6E F5 32 AE 64 E5 7F BD 15 79 83}
Attributes:
  User-Name=18:a9:05:a2:d4:19
  NAS-Identifier=RedBack
  NAS-IP-Address=192.168.12.247
  NAS-Port=33751040
  Service-Type=5
  Acct-Status-Type=1
  Acct-Session-Id=0102FFFF7800EC78-51649CF2
  Acct-Authentic=1
  Acct-Interim-Interval=900
  NAS-Port-Id=2/3 clips 193859
  Event-Timestamp=1365548274
  NAS-Port-Type=5
  Called-Station-Id=10.21.37.1
  DHCP-Max-Leases=1
  Medium-Type=11
  UNKNOWN[2352-201]={01 0A 15 25 01}
  Platform-Type=4
  Agent-Remote-Id={00 06 00 12 CF C8 9D D5}
  Agent-Circuit-Id={00 04 04 0D 01 05}
  UNKNOWN[2352-202]={3D 3D 07 01 18 A9 05 A2 D4 19}
  UNKNOWN[2352-202]={0C 0C 06 68 65 61 76 65 6E}
  IP-Interface-Name=clients
  NAT-Policy-Name=nat-policy
  Qos-Policing-Profile-Name=DEF-IPOE-IN
  OS-Version=6.5.1.5
  Acct-Update-Reason=1
  Mac-Addr=18-a9-05-a2-d4-19
  UNKNOWN[2352-125]={4D 53 46 54 20 35 2E 30}
  Qos-Metering-Profile-Name=DEF-IPOE-OUT
  NAS-Real-Port=33751040
  UNKNOWN[3561--1]={02 0A 00 06 00 12 CF C8 9D D5}
  UNKNOWN[3561--1]={01 08 00 04 04 0D 01 05}


Time: 10.04.2013 03:23:32
Packet type: Access-Request
Identifier: 182
Authenticator: {21 BB A8 D0 62 17 19 DF AA 07 4C 19 17 41 5A 54}
Attributes:
  User-Name=00:25:22:f7:cc:17
  NAS-Port-Id=2/3 clips 193613
  NAS-Identifier=RedBack
  User-Password=e�^�0KV�'L=^\u28�
  NAS-IP-Address=192.168.12.247
  NAS-Port=33751040
  Service-Type=5
  NAS-Port-Type=5
  Called-Station-Id=10.21.54.1
  Platform-Type=4
  UNKNOWN[2352-201]={01 0A 15 36 01}
  Medium-Type=11
  Agent-Remote-Id={00 06 70 72 CF 30 16 60}
  DHCP-Option={3D 3D 07 01 00 25 22 F7 CC 17}
  DHCP-Option={0C 0C 06 62 6F 62 2D 70 6B}
  Agent-Circuit-Id={00 04 04 1E 01 0A}
  OS-Version=6.5.1.5
  Mac-Addr=00-25-22-f7-cc-17
  UNKNOWN[2352-125]={4D 53 46 54 20 35 2E 30}
  NAS-Real-Port=33751040
  UNKNOWN[3561--1]={02 0A 00 06 70 72 CF 30 16 60}
  UNKNOWN[3561--1]={01 08 00 04 04 1E 01 0A}

Time: 10.04.2013 03:23:32
Packet type: Access-Accept
Identifier: 182
Authenticator: {90 52 17 9C 59 F4 90 19 4C 1B 3D 11 DA 9C 20 7A}
Attributes:
  Acct-Interim-Interval=900
  DHCP-Max-Leases=1
  Service-Name=SE
  Service-Options:0=1
  Service-Parameter=Rate=30000 Burst=3750000
  IP-Interface-Name=clients

Process time auth: 0


Time: 10.04.2013 03:23:33
Message type: BOOT_REQUEST
Dhcp message type: DHCP Request{3}
htype: 1, hlen: 6, hops: 2
xid: 237156400, secs: 0, flags: 0
Client IP: 0.0.0.0
Your IP: 0.0.0.0
Server IP: 0.0.0.0
Relay IP: 192.168.12.247
Client MAC: {18A905A2D419}
  {61}={0118A905A2D419}
  Requested IP Address{50}=10.21.37.153
  Server Identifier{54}={C0A80CFD}
  Host name{12}={heaven}
  {81}={00000068656176656E2E}
  {60}={4D53465420352E30}
  Parameter request list{55}={1, 15, 3, 6, 44, 46, 47, 31, 33, -7, 43}
  {43}={DC0100}
  Agent information{82}=
    sub{1}={0004040D0105}
    sub{2}={00060012CFC89DD5}

Time: 10.04.2013 03:23:33
Message type: BOOT_RESPONSE
Dhcp message type: DHCP ACK{5}
htype: 1, hlen: 6, hops: 2
xid: 237156400, secs: 0, flags: 0
Client IP: 0.0.0.0
Your IP: 10.21.37.153
Server IP: 0.0.0.0
Relay IP: 192.168.12.247
Client MAC: {18A905A2D419}
  Server Identifier{54}={00000000}
  IP Address Lease Time{51}=900
  Subnet mask{1}=255.255.255.0
  Router{3}=10.21.37.1
  DNS{6}={C0A80C03C0A81101}
  Agent information{82}=
    sub{1}={0004040D0105}
    sub{2}={00060012CFC89DD5}


Кто нибудь сталкивался с такой проблемой ?
схема Clips Redback
Автор:  Amir [ 11 апр 2013, 14:32 ]
Заголовок сообщения:  Re: обрыв сессий
То, что отображаются запросы от других абонентов - проблема выборки пакетов (которая происходит когда вы открываете лог запросов) и того, какие атрибуты в пакетах.
В Access-Request нет Acct-Session-Id, поэтому выборка идет по NAS-Port, а он совпадает у обоих абонентов NAS-Port=33751040, потому при выборке попадает Access-Request другого абонента (Dialup при совпадающих NAS-Port вообще не умеет работать).

Т.е. проблема отключения не должна быть с этим связана (если она не связана с совпадением NAS-Port, чего, вроде бы, не должно быть).
Посмотрите в RADIUS-логах оборвавшейся сессии, нет ли там CoA/PoD пакетов и Terminate-Cause в стоп-пакете.
Автор:  sergey-xxi [ 11 апр 2013, 14:41 ]
Заголовок сообщения:  Re: обрыв сессий
вот еще заметил акцесс валит ошибку весь день.
Код:
dhcp 04-07/03:13:50 ERROR [dhcpLstnr-p-11-t-8] InetDhcpHelperProcessor - Unsupported message type: 8


а в 3 часа ночи добавляет к ней вот это
Код:
dhcp 04-07/03:14:28 ERROR [dhcpLstnr-p-11-t-9] InetDhcpHelperProcessor - Not found params for request: 2:64:17:0007264A025E
dhcp 04-07/03:14:36 ERROR [dhcpLstnr-p-11-t-6] InetDhcpHelperProcessor - Not found params for request: 2:16:1:B8A386219FA1
dhcp 04-07/03:14:52 ERROR [dhcpLstnr-p-11-t-3] InetDhcpHelperProcessor - Not found params for request: 2:19:23:002401186328
dhcp 04-07/03:14:53 ERROR [dhcpLstnr-p-11-t-1] InetDhcpHelperProcessor - Not found params for request: 2:19:11:0007264D54F2


после чего и происходит разрыв.
Автор:  Amir [ 11 апр 2013, 14:47 ]
Заголовок сообщения:  Re: обрыв сессий
Похоже, он закрывает соединение, т.е. где-то выше он пишет Connection close, обрабатывая от кого-то сообщение о необходимости закрыть соединение InetSaStateModifyEvent - нужно смотреть от кого приходит, какой accessCode в нем.
Возможно Accounting его посылает, т.к. в 3 часа находитошибку в тарифе.
Автор:  sergey-xxi [ 11 апр 2013, 15:00 ]
Заголовок сообщения:  Re: обрыв сессий
вот часть лога
Код:
04-10/03:14:40 DEBUG [sa-p-13-t-73] AcknowledgeConsumer - Caught BGInetAccounting:Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaStateModifyEven
t] moduleId: 14; pluginId: no; cid: 6399; scid: -1; userId: 0; deviceId: 2; inetServId: 3166; connectionId: 349950; state: 1; accessCode: 0; timestamp: 13655
49280886
04-10/03:14:40  INFO [sa-p-13-t-73] ServiceActivatorDeviceWorker - Do task deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaStateModi
fyEvent] moduleId: 14; pluginId: no; cid: 6399; scid: -1; userId: 0; deviceId: 2; inetServId: 3166; connectionId: 349950; state: 1; accessCode: 0; timestamp:
 1365549280886
04-10/03:14:40  INFO [sa-p-13-t-73] InetApplication - TariffOptionMap: {}
04-10/03:14:40  INFO [sa-p-13-t-73] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=2; inetServId: 3166; call: true; oldState
: 0; newState: 1; oldOptionSet: 10; newOptionSet: 10
04-10/03:14:40  INFO [sa-p-13-t-73] ServiceActivatorDeviceWorker - Processing deviceId:2; command ServiceActivatorEvent type=2; inetServId: 3166; call: true;
 oldState: 0; newState: 1; oldOptionSet: 10; newOptionSet: 10
04-10/03:14:40  INFO [sa-p-13-t-73] ServiceActivatorSet - Invoking connectionModify
04-10/03:14:40  INFO [sa-p-13-t-73] SmartEdgeServiceActivator - Connection modify: oldState: 0; newState: 1; oldOptionSet: [10]; newOptionSet: [10]
04-10/03:14:40  INFO [sa-p-13-t-73] InetDhcpHelperProcessor - Remove connection remove 2:139:3:001E8CD06B82
04-10/03:14:40  INFO [sa-p-13-t-73] ServiceActivatorDeviceWorker - Process event type[2] result=true
04-10/03:14:45  INFO [sa-p-13-t-73] ServiceActivatorSet - Disconnecting from device
04-10/03:14:49  INFO [sa-p-13-t-49] ServiceActivatorSet - Connecting to device
04-10/03:14:49 DEBUG [sa-p-13-t-49] AcknowledgeConsumer - Caught BGInetAccounting:Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEvent
] moduleId: 14; pluginId: no; cid: 6308; scid: -1; userId: 0; type: 1; deviceId: 2; connectionId: 350202; timestamp: 1365549289600
04-10/03:14:49  INFO [sa-p-13-t-49] ServiceActivatorDeviceWorker - Do task deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountin
gEvent] moduleId: 14; pluginId: no; cid: 6308; scid: -1; userId: 0; type: 1; deviceId: 2; connectionId: 350202; timestamp: 1365549289600
04-10/03:14:49  INFO [sa-p-13-t-49] InetApplication - TariffOptionMap: {}
04-10/03:14:49  INFO [sa-p-13-t-49] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=4; inetServId: 2932; call: true; oldState
: 1; newState: 1; oldOptionSet: 5; newOptionSet: 5
04-10/03:14:49  INFO [sa-p-13-t-49] ServiceActivatorDeviceWorker - Processing deviceId:2; command ServiceActivatorEvent type=4; inetServId: 2932; call: true;
 oldState: 1; newState: 1; oldOptionSet: 5; newOptionSet: 5
04-10/03:14:49  INFO [sa-p-13-t-49] ServiceActivatorSet - Invoking onAccountingStart
04-10/03:14:49  INFO [sa-p-13-t-49] ServiceActivatorDeviceWorker - Process event type[4] result=true
04-10/03:14:49 DEBUG [sa-p-13-t-49] AcknowledgeConsumer - Caught BGInetAccounting:Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEvent
] moduleId: 14; pluginId: no; cid: 6308; scid: -1; userId: 0; type: 1; deviceId: 2; connectionId: 350203; timestamp: 1365549289632
04-10/03:14:49  INFO [sa-p-13-t-49] ServiceActivatorDeviceWorker - Do task deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountin
gEvent] moduleId: 14; pluginId: no; cid: 6308; scid: -1; userId: 0; type: 1; deviceId: 2; connectionId: 350203; timestamp: 1365549289632
04-10/03:14:49  INFO [sa-p-13-t-49] InetApplication - TariffOptionMap: {}
04-10/03:14:49  INFO [sa-p-13-t-49] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=4; inetServId: 2932; call: true; oldState
: 1; newState: 1; oldOptionSet: 5; newOptionSet: 5
04-10/03:14:49  INFO [sa-p-13-t-49] ServiceActivatorDeviceWorker - Processing deviceId:2; command ServiceActivatorEvent type=4; inetServId: 2932; call: true;
 oldState: 1; newState: 1; oldOptionSet: 5; newOptionSet: 5
04-10/03:14:49  INFO [sa-p-13-t-49] ServiceActivatorSet - Invoking onAccountingStart
04-10/03:14:49  INFO [sa-p-13-t-49] ServiceActivatorDeviceWorker - Process event type[4] result=true
04-10/03:14:54  INFO [sa-p-13-t-49] ServiceActivatorSet - Disconnecting from device
04-10/03:14:55  INFO [sa-p-13-t-87] ServiceActivatorSet - Connecting to device
04-10/03:14:55 DEBUG [sa-p-13-t-87] AcknowledgeConsumer - Caught BGInetAccounting:Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountingEvent
] moduleId: 14; pluginId: no; cid: 2088; scid: -1; userId: 0; type: 2; deviceId: 2; connectionId: 349762; timestamp: 1365549295228
04-10/03:14:55  INFO [sa-p-13-t-87] ServiceActivatorDeviceWorker - Do task deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAccountin
gEvent] moduleId: 14; pluginId: no; cid: 2088; scid: -1; userId: 0; type: 2; deviceId: 2; connectionId: 349762; timestamp: 1365549295228
04-10/03:14:55  INFO [sa-p-13-t-87] InetApplication - TariffOptionMap: {1=ru.bitel.bgbilling.kernel.tariff.option.server.bean.ContractTariffOptionList$Option
Item@1549221}
04-10/03:14:55  INFO [sa-p-13-t-87] ServiceActivatorDeviceWorker - Command result event: ServiceActivatorEvent type=5; inetServId: 2779; call: true; oldState
: 1; newState: 1; oldOptionSet: 10; newOptionSet: 10
04-10/03:14:55  INFO [sa-p-13-t-87] ServiceActivatorDeviceWorker - Processing deviceId:2; command ServiceActivatorEvent type=5; inetServId: 2779; call: true;
 oldState: 1; newState: 1; oldOptionSet: 10; newOptionSet: 10
04-10/03:14:55  INFO [sa-p-13-t-87] ServiceActivatorSet - Invoking onAccountingStop
04-10/03:14:55  INFO [sa-p-13-t-87] ServiceActivatorDeviceWorker - Process event type[5] result=true
Автор:  Amir [ 11 апр 2013, 15:06 ]
Заголовок сообщения:  Re: обрыв сессий
Цитата:
04-10/03:14:40 INFO [sa-p-13-t-73] SmartEdgeServiceActivator - Connection modify: oldState: 0; newState: 1; oldOptionSet: [10]; newOptionSet: [10]
04-10/03:14:40 INFO [sa-p-13-t-73] InetDhcpHelperProcessor - Remove connection remove 2:139:3:001E8CD06B82
Как-будто соединение было в состоянии отключено (т.е. доступ ограничен) и понадобилось его включить. Событие послал BGInetAccounting.
Автор:  sergey-xxi [ 11 апр 2013, 15:11 ]
Заголовок сообщения:  Re: обрыв сессий
В клиенте биллинга показывает состояние подключена, новую в 00:00 открывает и закрывает в 3 часа. После этого обрыва до 12 ночи, все нормально работает. Не можем понять в чем проблема...
Автор:  Amir [ 11 апр 2013, 15:16 ]
Заголовок сообщения:  Re: обрыв сессий
Тогда надо в 00:00 смотреть, почему переключает соединение в состояние отключен, то же событие, тоже accessCode. Также у этой сессии в inet_session_log_mid_201304 можно посмотреть значение accessCode.
Точно в тарифе нет зависимостей от времени? Может быть там цена или услуга не установлена в этот промежуток?
Автор:  sergey-xxi [ 11 апр 2013, 15:24 ]
Заголовок сообщения:  Re: обрыв сессий
вот тариф, без разницы во времени, деньги снимаются скриптом, без использования модуля абон.платы,

Вложения:
tarif.JPG
tarif.JPG [ 14.67 КБ | Просмотров: 6165 ]
Автор:  Amir [ 11 апр 2013, 15:34 ]
Заголовок сообщения:  Re: обрыв сессий
Нужны логи BGInetAccounting, когда закрывается сессия в 00:00 и запись в inet_session_log_mid_201304 сессии, которая началась в 00:00.
Автор:  sergey-xxi [ 12 апр 2013, 12:49 ]
Заголовок сообщения:  Re: обрыв сессий
вот
Код:
radius 04-11/23:59:40  INFO [rdsLstnr-p-8-t-4] update - REQUEST:
Packet type: Accounting-Request
Identifier: 225
Authenticator: {25 20 F1 F0 BF 95 A1 26 9F 55 3F E6 8F 49 9D 55}
Attributes:
  User-Name=88:ae:1d:20:5c:8c
  NAS-Identifier=RedBack
  NAS-IP-Address=192.168.12.247
  NAS-Port=33751040
  Service-Type=5
  Acct-Input-Octets=838384230
  Acct-Output-Octets=-1754228528
  Acct-Status-Type=3
  Acct-Session-Time=19800
  Acct-Input-Packets=7340593
  Acct-Session-Id=0102FFFF7800FAC7-5166C385
  Acct-Authentic=1
  Acct-Interim-Interval=900
  NAS-Port-Id=2/3 clips 196164
  Acct-Output-Packets=8172779
  Event-Timestamp=1365709021
  Acct-Output-Gigawords=2
  Acct-Input-Gigawords=0
  NAS-Port-Type=5
  Called-Station-Id=10.21.37.1
  Acct-Mcast-Out-Packets-64={00 00 00 00 00 00 00 00}
  DHCP-Max-Leases=1
  UNKNOWN[2352-201]={01 0A 15 25 01}
  UNKNOWN[2352-202]={3D 3D 07 01 88 AE 1D 20 5C 8C}
  Acct-Output-Octets-64={00 00 00 02 97 70 98 D0}
  Acct-Input-Octets-64={00 00 00 00 31 F8 BA 66}
  Acct-Output-Packets-64={00 00 00 00 00 7C B4 EB}
  Acct-Input-Packets-64={00 00 00 00 00 70 02 31}
  Acct-Mcast-In-Octets-64={00 00 00 00 00 00 00 00}
  Assigned-IP-Address=10.21.37.213
  Acct-Mcast-In-Packets-64={00 00 00 00 00 00 00 00}
  Acct-Mcast-Out-Octets-64={00 00 00 00 00 00 00 00}
  Qos-Policing-Profile-Name=DEF-IPOE-IN
  Acct-Update-Reason=3
  Mac-Addr=88-ae-1d-20-5c-8c
  Acct-Mcast-In-Octets=0
  Acct-Mcast-Out-Octets=0
  Qos-Metering-Profile-Name=DEF-IPOE-OUT
  Acct-Mcast-In-Packets=0
  Acct-Mcast-Out-Packets=0
  Platform-Type=4
  Medium-Type=11
  Agent-Remote-Id={00 06 00 12 CF C8 9D D5}
  Agent-Circuit-Id={00 04 04 0D 01 09}
  IP-Interface-Name=clients
  NAT-Policy-Name=nat-policy
  OS-Version=6.5.1.5
  UNKNOWN[2352-125]={75 64 68 63 70 20 30 2E 39 2E 38}
  NAS-Real-Port=33751040
  UNKNOWN[3561--1]={02 0A 00 06 00 12 CF C8 9D D5}
  UNKNOWN[3561--1]={01 08 00 04 04 0D 01 09}

radius 04-11/23:59:40  INFO [rdsLstnr-p-8-t-4] InetRadiusProcessor - REQUEST_AFTER_PREPROCESS:
Packet type: Accounting-Request
Identifier: 225
Authenticator: {25 20 F1 F0 BF 95 A1 26 9F 55 3F E6 8F 49 9D 55}
Attributes:
  User-Name=00060012cfc89dd5:0004040d0109
  NAS-Identifier=RedBack
  NAS-IP-Address=192.168.12.247
  NAS-Port=33751040
  Service-Type=5
  Acct-Input-Octets=838384230
  Acct-Output-Octets=-1754228528
  Acct-Status-Type=3
  Acct-Session-Time=19800
  Acct-Input-Packets=7340593
  Acct-Session-Id=0102FFFF7800FAC7-5166C385
  Acct-Authentic=1
  Acct-Interim-Interval=900
  NAS-Port-Id=2/3 clips 196164
  Acct-Output-Packets=8172779
  Event-Timestamp=1365709021
  Acct-Output-Gigawords=2
  Acct-Input-Gigawords=0
  Calling-Station-Id=88ae1d205c8c
  NAS-Port-Type=5
  Called-Station-Id=10.21.37.1
  Acct-Mcast-Out-Packets-64={00 00 00 00 00 00 00 00}
  DHCP-Max-Leases=1
  UNKNOWN[2352-201]={01 0A 15 25 01}
  UNKNOWN[2352-202]={3D 3D 07 01 88 AE 1D 20 5C 8C}
  Acct-Output-Octets-64={00 00 00 02 97 70 98 D0}
  Acct-Input-Octets-64={00 00 00 00 31 F8 BA 66}
  Acct-Output-Packets-64={00 00 00 00 00 7C B4 EB}
  Acct-Input-Packets-64={00 00 00 00 00 70 02 31}
  Acct-Mcast-In-Octets-64={00 00 00 00 00 00 00 00}
  Assigned-IP-Address=10.21.37.213
  Acct-Mcast-In-Packets-64={00 00 00 00 00 00 00 00}
  Acct-Mcast-Out-Octets-64={00 00 00 00 00 00 00 00}
  Qos-Policing-Profile-Name=DEF-IPOE-IN
  Acct-Update-Reason=3
  Mac-Addr=88-ae-1d-20-5c-8c
  Acct-Mcast-In-Octets=0
  Acct-Mcast-Out-Octets=0
  Qos-Metering-Profile-Name=DEF-IPOE-OUT
  Acct-Mcast-In-Packets=0
  Acct-Mcast-Out-Packets=0
  Platform-Type=4
  Medium-Type=11
  Agent-Remote-Id={00 06 00 12 CF C8 9D D5}
  Agent-Circuit-Id={00 04 04 0D 01 09}
  IP-Interface-Name=clients
  NAT-Policy-Name=nat-policy
  OS-Version=6.5.1.5
  UNKNOWN[2352-125]={75 64 68 63 70 20 30 2E 39 2E 38}
  NAS-Real-Port=33751040
  UNKNOWN[3561--1]={02 0A 00 06 00 12 CF C8 9D D5}
  UNKNOWN[3561--1]={01 08 00 04 04 0D 01 09}

radius 04-11/23:59:40  INFO [rdsLstnr-p-8-t-4] InetRadiusProcessor - Session 0102FFFF7800FAC7-5166C385 found.
radius 04-11/23:59:40  INFO [rdsLstnr-p-8-t-4] update - RESPONSE:
Packet type: Accounting-Response
Identifier: 225
Authenticator: {E1 F8 72 DB 2F 3B 10 1B 21 DB A7 85 AC 72 A3 B8}
Attributes:

Process time update: 0


запись в таблице
Код:
464281   0   0   354618   0   2   33751040   80   0102FFFF7800FAC7-5166C385   00060012cfc89dd5:0004040d0109      0   0   1427   10.21.37.1   88ae1d205c8c   68   
\u21%Х   2013-04-11 18:30:28   2013-04-11 18:30:28   2013-04-11 23:59:59   2013-04-11 23:59:40   1   10   19772   0.00000   4

465079   0   464281   354618   0   2   33751040   80   0102FFFF7800FAC7-5166C385   00060012cfc89dd5:0004040d0109      6   0   1427   10.21.37.1   88ae1d205c8c   68   
\u21%Х   2013-04-11 18:30:28   2013-04-12 00:00:00   2013-04-12 03:15:07   2013-04-12 03:15:23   1   10   11708   0.00000   4


логи аккаутинга в момент разрыва
Код:
radius 04-12/03:17:44  INFO [rdsLstnr-p-8-t-5] update - REQUEST:
Packet type: Accounting-Request
Identifier: 244
Authenticator: {67 BB D7 9B C2 48 C7 E1 FC 9F B8 88 89 0B 6C 31}
Attributes:
  User-Name=88:ae:1d:20:5c:8c
  NAS-Identifier=RedBack
  NAS-IP-Address=192.168.12.247
  NAS-Port=33751040
  Service-Type=5
  Acct-Input-Octets=842262769
  Acct-Output-Octets=-1682729066
  Acct-Status-Type=3
  Acct-Session-Time=31500
  Acct-Input-Packets=7377291
  Acct-Session-Id=0102FFFF7800FAC7-5166C385
  Acct-Authentic=1
  Acct-Interim-Interval=900
  NAS-Port-Id=2/3 clips 196164
  Acct-Output-Packets=8229728
  Event-Timestamp=1365720721
  Acct-Output-Gigawords=2
  Acct-Input-Gigawords=0
  NAS-Port-Type=5
  Called-Station-Id=10.21.37.1
  Acct-Mcast-Out-Packets-64={00 00 00 00 00 00 00 00}
  DHCP-Max-Leases=1
  UNKNOWN[2352-201]={01 0A 15 25 01}
  UNKNOWN[2352-202]={3D 3D 07 01 88 AE 1D 20 5C 8C}
  Acct-Output-Octets-64={00 00 00 02 9B B3 97 96}
  Acct-Input-Octets-64={00 00 00 00 32 33 E8 F1}
  Acct-Output-Packets-64={00 00 00 00 00 7D 93 60}
  Acct-Input-Packets-64={00 00 00 00 00 70 91 8B}
  Acct-Mcast-In-Octets-64={00 00 00 00 00 00 00 00}
  Assigned-IP-Address=10.21.37.213
  Acct-Mcast-In-Packets-64={00 00 00 00 00 00 00 00}
  Acct-Mcast-Out-Octets-64={00 00 00 00 00 00 00 00}
  Qos-Policing-Profile-Name=DEF-IPOE-IN
  Acct-Update-Reason=3
  Mac-Addr=88-ae-1d-20-5c-8c
  Acct-Mcast-In-Octets=0
  Acct-Mcast-Out-Octets=0
  Qos-Metering-Profile-Name=DEF-IPOE-OUT
  Acct-Mcast-In-Packets=0
  Acct-Mcast-Out-Packets=0
  Platform-Type=4
  Medium-Type=11
  Agent-Remote-Id={00 06 00 12 CF C8 9D D5}
  Agent-Circuit-Id={00 04 04 0D 01 09}
  IP-Interface-Name=clients
  NAT-Policy-Name=nat-policy
  OS-Version=6.5.1.5
  UNKNOWN[2352-125]={75 64 68 63 70 20 30 2E 39 2E 38}
  NAS-Real-Port=33751040
  UNKNOWN[3561--1]={02 0A 00 06 00 12 CF C8 9D D5}
  UNKNOWN[3561--1]={01 08 00 04 04 0D 01 09}

radius 04-12/03:17:44  INFO [rdsLstnr-p-8-t-5] InetRadiusProcessor - REQUEST_AFTER_PREPROCESS:
Packet type: Accounting-Request
Identifier: 244
Authenticator: {67 BB D7 9B C2 48 C7 E1 FC 9F B8 88 89 0B 6C 31}
Attributes:
  User-Name=00060012cfc89dd5:0004040d0109
  NAS-Identifier=RedBack
  NAS-IP-Address=192.168.12.247
  NAS-Port=33751040
  Service-Type=5
  Acct-Input-Octets=842262769
  Acct-Output-Octets=-1682729066
  Acct-Status-Type=3
  Acct-Session-Time=31500
  Acct-Input-Packets=7377291
  Acct-Session-Id=0102FFFF7800FAC7-5166C385
  Acct-Authentic=1
  Acct-Interim-Interval=900
  NAS-Port-Id=2/3 clips 196164
  Acct-Output-Packets=8229728
  Event-Timestamp=1365720721
  Acct-Output-Gigawords=2
  Acct-Input-Gigawords=0
  Calling-Station-Id=88ae1d205c8c
  NAS-Port-Type=5
  Called-Station-Id=10.21.37.1
  Acct-Mcast-Out-Packets-64={00 00 00 00 00 00 00 00}
  DHCP-Max-Leases=1
  UNKNOWN[2352-201]={01 0A 15 25 01}
  UNKNOWN[2352-202]={3D 3D 07 01 88 AE 1D 20 5C 8C}
  Acct-Output-Octets-64={00 00 00 02 9B B3 97 96}
  Acct-Input-Octets-64={00 00 00 00 32 33 E8 F1}
  Acct-Output-Packets-64={00 00 00 00 00 7D 93 60}
  Acct-Input-Packets-64={00 00 00 00 00 70 91 8B}
  Acct-Mcast-In-Octets-64={00 00 00 00 00 00 00 00}
  Assigned-IP-Address=10.21.37.213
  Acct-Mcast-In-Packets-64={00 00 00 00 00 00 00 00}
  Acct-Mcast-Out-Octets-64={00 00 00 00 00 00 00 00}
  Qos-Policing-Profile-Name=DEF-IPOE-IN
  Acct-Update-Reason=3
  Mac-Addr=88-ae-1d-20-5c-8c
  Acct-Mcast-In-Octets=0
  Acct-Mcast-Out-Octets=0
  Qos-Metering-Profile-Name=DEF-IPOE-OUT
  Acct-Mcast-In-Packets=0
  Acct-Mcast-Out-Packets=0
  Platform-Type=4
  Medium-Type=11
  Agent-Remote-Id={00 06 00 12 CF C8 9D D5}
  Agent-Circuit-Id={00 04 04 0D 01 09}
  IP-Interface-Name=clients
  NAT-Policy-Name=nat-policy
  OS-Version=6.5.1.5
  UNKNOWN[2352-125]={75 64 68 63 70 20 30 2E 39 2E 38}
  NAS-Real-Port=33751040
  UNKNOWN[3561--1]={02 0A 00 06 00 12 CF C8 9D D5}
  UNKNOWN[3561--1]={01 08 00 04 04 0D 01 09}

radius 04-12/03:17:44  INFO [rdsLstnr-p-8-t-5] InetNas - Found agentDevice:80
radius 04-12/03:17:44  INFO [rdsLstnr-p-8-t-5] InetNas - Search serv on deviceId=80; interfaceId=9
radius 04-12/03:17:44  WARN [rdsLstnr-p-8-t-5] InetRadiusProcessor - Duplicate radius packet (session already stopped by accounting-stop packet).
radius 04-12/03:17:44  WARN [rdsLstnr-p-8-t-5] InetNas - NasConnection not found
radius 04-12/03:17:44  INFO [rdsLstnr-p-8-t-5] update - RESPONSE:
Packet type: Accounting-Response
Identifier: 244
Authenticator: {E0 06 24 B1 00 97 CF 69 E2 74 CA 38 11 96 5C 1D}
Attributes:

Process time update: 1
Автор:  Amir [ 12 апр 2013, 17:24 ]
Заголовок сообщения:  Re: обрыв сессий
Какие сервисные сессии у сессии, которая в 3 часа закончилась?
Можете отправить логи за промежуток около 0 часов и 3-х часов на email?
Также конфигурацию устройства (кнопка текущая).
Автор:  Alex-XXI [ 12 апр 2013, 18:36 ]
Заголовок сообщения:  Re: обрыв сессий
Amir писал(а):
Какие сервисные сессии у сессии, которая в 3 часа закончилась?
Можете отправить логи за промежуток около 0 часов и 3-х часов на email?
Также конфигурацию устройства (кнопка текущая).

Сервисная сессия как и на других сессиях только одна, с открытым доступом. Логи и конфиг отправил на почту.
Автор:  Amir [ 12 апр 2013, 20:03 ]
Заголовок сообщения:  Re: обрыв сессий
Судя по логу последние update-пакеты по сессии 0102FFFF7800FAC7-5166C385 и ее сервисной сессии пришли в 2:59.
Далее сессия закрывается по таймауту:
Код:
accounting 04-12/03:14:47  INFO [accwrkr-3-p-10-t-1] connection - 354618:465079 Suspend call connection by sessionSuspendTimeout
accounting 04-12/03:15:07  INFO [accwrkr-3-p-10-t-1] connection - 354618:465079 Close suspended call connection by sessionCloseTimeout
Далее, соответственно, начинает отвечать DHCP-NAK.
Может быть connection.suspend.timeout= connection.close.timeout= слишком маленькие (должны быть больше, чем Acct-Interim-Interval=900) или не указаны?
Автор:  sergey-xxi [ 14 апр 2013, 17:49 ]
Заголовок сообщения:  Re: обрыв сессий
у нас взято из вики
connection.suspend.timeout=900
connection.close.timeout=900
предлагаете поставить по больше ?
Автор:  Amir [ 14 апр 2013, 23:27 ]
Заголовок сообщения:  Re: обрыв сессий
Да, лучше побольше, с запасом, например 2*Acct-Interim-Interval. А то сейчас получается, что если один раз пакеты по родительской и сервисной сессии не пришли (или чуть запоздали, или, например, много RADIUS-пакетов пришло на обработку, Accounting не успел обработать пакет этой сессии), то сессия закроется по таймауту.
Автор:  sergey-xxi [ 15 апр 2013, 11:51 ]
Заголовок сообщения:  Re: обрыв сессий
Cпасибо! вроде помогло.
Помониторим несколько дней.
Страница 1 из 1 Часовой пояс: UTC + 5 часов [ Летнее время ]
Powered by phpBB® Forum Software © phpBB Group
http://www.phpbb.com/