Обслуживаю сервер клиента стоит биллинг 5.2.
Проблема такая же как в топике
viewtopic.php?f=5&t=8576к, сожалению, там нет решения.
ISG успешно авторизуется через радиус, создает сессию и отсылает Update.
В логах биллинга видно, все три запроса к радиусу, в логах алиаса на договоре видно все, кроме Update.
То есть, биллинг принял сообщение, записал его в логи, но проигнорировал и не обработал.
Как лечить?
------------------------
Выдержка из BGRadiusDialup/log/radius.log
Код:
09-11/01:28:29 INFO [rdsLstnr-p-6-t-153] RadiusListenerWorker - REQUEST:
Packet type: Access-Request
Identifier: 93
Authenticator: {6A 45 86 A7 EA 87 09 2D D1 45 75 30 F8 9F 38 D0}
Attributes:
User-Name=172.25.4.26
NAS-Identifier=10.0.224.8
User-Password=172.25.4.26
NAS-IP-Address=10.0.224.8
NAS-Port=1
Service-Type=2
NAS-Port-Type=5
Calling-Station-Id=0011.bc49.3c00
Called-Station-Id=10.0.224.8
cisco-avpair=client-mac-address=0011.bc49.3c00
09-11/01:28:29 DEBUG [rdsLstnr-p-6-t-153] RadiusProcessor - Create new radius session.
09-11/01:28:29 DEBUG [rdsLstnr-p-6-t-153] AbstractRadiusProcessor - Finding login..
09-11/01:28:29 DEBUG [rdsLstnr-p-6-t-153] AbstractRadiusProcessor - Login found: 41179
09-11/01:28:29 DEBUG [rdsLstnr-p-6-t-153] AbstractRadiusProcessor - Checking access allow...
09-11/01:28:29 DEBUG [rdsLstnr-p-6-t-153] AbstractRadiusProcessor - Checking password...
09-11/01:28:29 DEBUG [rdsLstnr-p-6-t-153] AbstractRadiusProcessor - Checking contract status
09-11/01:28:29 DEBUG [rdsLstnr-p-6-t-153] AbstractRadiusProcessor - Checking balance...
09-11/01:28:29 DEBUG [rdsLstnr-p-6-t-153] ContractTariffOptionList - Create:.
09-11/01:28:29 DEBUG [rdsLstnr-p-6-t-153] DialUpRadiusProcessor - Checking tariff...
09-11/01:28:29 DEBUG [rdsLstnr-p-6-t-153] DialUpRadiusProcessor - Set address time: 0
09-11/01:28:29 ERROR [rdsLstnr-p-6-t-153] DialUpNasConnection - Stop without start!
09-11/01:28:29 DEBUG [rdsLstnr-p-6-t-153] ProcessorRequest - Sending to /10.0.224.8:41655
09-11/01:28:29 INFO [rdsLstnr-p-6-t-153] RadiusListenerWorker - RESPONSE:
Packet type: Access-Accept
Identifier: 93
Authenticator: {85 E5 0C 68 DD 88 95 13 2D B1 8C C8 4D A3 AF 52}
Attributes:
Acct-Interim-Interval=120
Service-Type=2
Framed-Protocol=1
Framed-IP-Address=10.0.226.44
cisco-SSG-Account-Info=QU;52428800;1280000;D;102400000;2560000
Process time auth: 20
<--------------------->
09-11/01:28:29 INFO [rdsLstnr-p-7-t-137] RadiusListenerWorker - REQUEST:
Packet type: Accounting-Request
Identifier: 95
Authenticator: {8E C3 E9 79 70 AA BC 32 F3 14 49 3D 69 26 CA 16}
Attributes:
User-Name=172.25.4.26
NAS-Identifier=10.0.224.8
NAS-IP-Address=10.0.224.8
NAS-Port=1
Service-Type=2
Acct-Input-Octets=0
Framed-IP-Address=10.0.226.44
Acct-Output-Octets=0
Acct-Status-Type=1
Acct-Session-Time=0
Acct-Input-Packets=0
Acct-Session-Id=B7C28F61D969B413
Acct-Authentic=1
Acct-Output-Packets=0
Acct-Output-Gigawords=0
Acct-Input-Gigawords=0
Calling-Station-Id=0011.bc49.3c00
NAS-Port-Type=5
Called-Station-Id=10.0.224.8
cisco-avpair=client-mac-address=0011.bc49.3c00
cisco-SSG-Control-Info=I0;0
cisco-SSG-Control-Info=O0;0
09-11/01:28:29 DEBUG [rdsLstnr-p-7-t-137] ProcessorRequest - Sending to /10.0.224.8:32178
09-11/01:28:29 INFO [rdsLstnr-p-7-t-137] RadiusListenerWorker - RESPONSE:
Packet type: Accounting-Response
Identifier: 95
Authenticator: {FA 82 DE 14 2C 71 67 A8 03 A1 DB 9A C8 B7 48 6E}
Attributes:
Process time start: 10
<------------------------------------------------------------------------------------------->
<------------------------------------------------------------------------------------------->
09-11/01:30:29 INFO [rdsLstnr-p-7-t-34] update - REQUEST:
Packet type: Accounting-Request
Identifier: 208
Authenticator: {12 EA 9D 18 62 0E C3 53 7B 0F 6F BD B1 BB FE 94}
Attributes:
User-Name=172.25.4.26
NAS-Identifier=10.0.224.8
NAS-IP-Address=10.0.224.8
NAS-Port=1
Service-Type=2
Acct-Input-Octets=4809
Framed-IP-Address=10.0.226.44
Acct-Output-Octets=3216
Acct-Status-Type=3
Acct-Session-Time=120
Acct-Input-Packets=22
Acct-Session-Id=B7C28F61D969B413
Acct-Authentic=1
Acct-Output-Packets=11
Acct-Output-Gigawords=0
Acct-Input-Gigawords=0
Calling-Station-Id=0011.bc49.3c00
NAS-Port-Type=5
Called-Station-Id=10.0.224.8
cisco-avpair=client-mac-address=0011.bc49.3c00
cisco-SSG-Control-Info=I0;4809
cisco-SSG-Control-Info=O0;3216
09-11/01:30:29 DEBUG [rdsLstnr-p-7-t-34] ProcessorRequest - Sending to /10.0.224.8:53640
09-11/01:30:29 INFO [rdsLstnr-p-7-t-34] update - RESPONSE:
Packet type: Accounting-Response
Identifier: 208
Authenticator: {2B EE 25 AA D2 80 D0 D9 FF CD 92 BC F8 4A 88 9E}
Attributes:
Process time update: 7
<----------------------------------------------------------------------------------------->
09-11/01:32:28 INFO [rdsLstnr-p-7-t-78] update - REQUEST:
Packet type: Accounting-Request
Identifier: 51
Authenticator: {4F 2C 9A BB 81 1D 6C EE 8B FA F4 48 8C 19 1B E4}
Attributes:
User-Name=172.25.4.26
NAS-Identifier=10.0.224.8
NAS-IP-Address=10.0.224.8
NAS-Port=1
Service-Type=2
Acct-Input-Octets=5434
Framed-IP-Address=10.0.226.44
Acct-Output-Octets=3782
Acct-Status-Type=3
Acct-Session-Time=240
Acct-Input-Packets=26
Acct-Session-Id=B7C28F61D969B413
Acct-Authentic=1
Acct-Output-Packets=16
Acct-Output-Gigawords=0
Acct-Input-Gigawords=0
Calling-Station-Id=0011.bc49.3c00
NAS-Port-Type=5
Called-Station-Id=10.0.224.8
cisco-avpair=client-mac-address=0011.bc49.3c00
cisco-SSG-Control-Info=I0;5434
cisco-SSG-Control-Info=O0;3782
09-11/01:32:28 DEBUG [rdsLstnr-p-7-t-78] ProcessorRequest - Sending to /10.0.224.8:35603
09-11/01:32:28 INFO [rdsLstnr-p-7-t-78] update - RESPONSE:
Packet type: Accounting-Response
Identifier: 51
Authenticator: {B5 9D 36 03 A0 29 55 6B DF F1 E7 5C BA 15 07 E3}
Attributes:
Process time update: 8
<---------------------------------------------------------------------------------------->
09-11/01:34:28 INFO [rdsLstnr-p-7-t-72] update - REQUEST:
Packet type: Accounting-Request
Identifier: 156
Authenticator: {66 F1 3D 3A C7 63 CA ED CA 4A A9 D2 44 26 5D 22}
Attributes:
User-Name=172.25.4.26
NAS-Identifier=10.0.224.8
NAS-IP-Address=10.0.224.8
NAS-Port=1
Service-Type=2
Acct-Input-Octets=5434
Framed-IP-Address=10.0.226.44
Acct-Output-Octets=3782
Acct-Status-Type=3
Acct-Session-Time=361
Acct-Input-Packets=26
Acct-Session-Id=B7C28F61D969B413
Acct-Authentic=1
Acct-Output-Packets=16
Acct-Output-Gigawords=0
Acct-Input-Gigawords=0
Calling-Station-Id=0011.bc49.3c00
NAS-Port-Type=5
Called-Station-Id=10.0.224.8
cisco-avpair=client-mac-address=0011.bc49.3c00
cisco-SSG-Control-Info=I0;5434
cisco-SSG-Control-Info=O0;3782
09-11/01:34:28 DEBUG [rdsLstnr-p-7-t-72] ProcessorRequest - Sending to /10.0.224.8:28298
09-11/01:34:28 INFO [rdsLstnr-p-7-t-72] update - RESPONSE:
Packet type: Accounting-Response
Identifier: 156
Authenticator: {77 E0 3B 1D CE A8 C1 10 FF 09 AA 5F 0F E9 BD C9}
Attributes:
Process time update: 6
<----------------------------------------------------------------------------------------->
09-11/01:35:02 INFO [rdsLstnr-p-7-t-2] RadiusListenerWorker - REQUEST:
Packet type: Accounting-Request
Identifier: 227
Authenticator: {EA E2 81 E2 8A 00 B9 E1 78 64 CD F9 56 AD 00 52}
Attributes:
User-Name=172.25.4.26
NAS-Identifier=10.0.224.8
NAS-IP-Address=10.0.224.8
NAS-Port=1
Service-Type=2
Acct-Input-Octets=5434
Framed-IP-Address=10.0.226.44
Acct-Output-Octets=3782
Acct-Status-Type=2
Acct-Session-Time=395
Acct-Input-Packets=26
Acct-Session-Id=B7C28F61D969B413
Acct-Authentic=1
Acct-Output-Packets=16
Acct-Output-Gigawords=0
Acct-Input-Gigawords=0
Calling-Station-Id=0011.bc49.3c00
NAS-Port-Type=5
Called-Station-Id=10.0.224.8
cisco-avpair=client-mac-address=0011.bc49.3c00
cisco-SSG-Control-Info=I0;5434
cisco-SSG-Control-Info=O0;3782
09-11/01:35:02 DEBUG [rdsLstnr-p-7-t-2] ProcessorRequest - Sending to /10.0.224.8:23155
09-11/01:35:02 INFO [rdsLstnr-p-7-t-2] RadiusListenerWorker - RESPONSE:
Packet type: Accounting-Response
Identifier: 227
Authenticator: {41 A7 95 F6 DC 5E 9E 79 89 AC 03 4E EF 3B E9 23}
Attributes:
Process time stop: 4
А вот как выглядит лог по сессии на радиусе (в ява-клиенте)
Код:
11 01:28:29
Packet type: Access-Request
Identifier: 93
Authenticator: {6A 45 86 A7 EA 87 09 2D D1 45 75 30 F8 9F 38 D0}
Attributes:
User-Name=172.25.4.26
NAS-Identifier=10.0.224.8
User-Password=172.25.4.26
NAS-IP-Address=10.0.224.8
NAS-Port=1
Service-Type=2
NAS-Port-Type=5
Calling-Station-Id=0011.bc49.3c00
Called-Station-Id=10.0.224.8
cisco-avpair=client-mac-address=0011.bc49.3c00
11 01:28:29
Packet type: Access-Accept
Identifier: 93
Authenticator: {}
Attributes:
Acct-Interim-Interval=120
Service-Type=2
Framed-Protocol=1
Framed-IP-Address=10.0.226.44
cisco-SSG-Account-Info=QU;52428800;1280000;D;102400000;2560000
Process time auth: 19
11 01:28:29
Packet type: Accounting-Request
Identifier: 95
Authenticator: {8E C3 E9 79 70 AA BC 32 F3 14 49 3D 69 26 CA 16}
Attributes:
User-Name=172.25.4.26
NAS-Identifier=10.0.224.8
NAS-IP-Address=10.0.224.8
NAS-Port=1
Service-Type=2
Acct-Input-Octets=0
Framed-IP-Address=10.0.226.44
Acct-Output-Octets=0
Acct-Status-Type=1
Acct-Session-Time=0
Acct-Input-Packets=0
Acct-Session-Id=B7C28F61D969B413
Acct-Authentic=1
Acct-Output-Packets=0
Acct-Output-Gigawords=0
Acct-Input-Gigawords=0
Calling-Station-Id=0011.bc49.3c00
NAS-Port-Type=5
Called-Station-Id=10.0.224.8
cisco-avpair=client-mac-address=0011.bc49.3c00
cisco-SSG-Control-Info=I0;0
cisco-SSG-Control-Info=O0;0