Теперь непонятно почему мгновенно скидывает когда пользователь подключается в режиме Reject-to-Accept. Вот как выглядят логи:
Код:
radius 12-26/20:47:18 INFO [rdsLstnr-p-7-t-267] RadiusListenerWorker - REQUEST:
Packet type: Access-Request
Identifier: 22
Authenticator: {C1 34 5F 26 A6 47 E5 17 85 37 FE 5C B7 5C CA 73}
Attributes:
User-Name=test
NAS-Port-Id=2/2 vlan-id 101 pppoe 271
NAS-Identifier=bras0
User-Password=testtest
NAS-IP-Address=x.x.108.128
NAS-Port=570425615
Service-Type=2
Framed-Protocol=1
NAS-Port-Type=5
Platform-Type=4
Medium-Type=11
OS-Version=6.2.1.11
Mac-Addr=00-14-85-38-05-67
NAS-Real-Port=570425445
radius 12-26/20:47:18 INFO [rdsLstnr-p-7-t-267] InetRadiusProcessor - REQUEST_A
FTER_PREPROCESS:
Packet type: Access-Request
Identifier: 22
Authenticator: {C1 34 5F 26 A6 47 E5 17 85 37 FE 5C B7 5C CA 73}
Attributes:
User-Name=test
NAS-Port-Id=2/2 vlan-id 101 pppoe 271
NAS-Identifier=bras0
User-Password=testtest
NAS-IP-Address=x.x.108.128
NAS-Port=570425615
Service-Type=2
Framed-Protocol=1
NAS-Port-Type=5
Platform-Type=4
Medium-Type=11
OS-Version=6.2.1.11
Mac-Addr=00-14-85-38-05-67
NAS-Real-Port=570425445
Common options: {macAddress=00-14-85-38-05-67, deviceState=1}
radius 12-26/20:47:18 INFO [rdsLstnr-p-7-t-267] InetNas - Search by username=te
st
radius 12-26/20:47:18 INFO [rdsLstnr-p-7-t-267] InetRadiusProcessor - [username
=test] Authenticated as inetServId:1
radius 12-26/20:47:18 INFO [rdsLstnr-p-7-t-267] InetApplication - inetServ[id=1
] deviceState not active (accessCode=0).
radius 12-26/20:47:18 INFO [rdsLstnr-p-7-t-267] InetRadiusProcessor - Set ip fr
om serv:1
radius 12-26/20:47:18 INFO [rdsLstnr-p-7-t-267] InetRadiusProcessor - Write new waiting connection to DB
radius 12-26/20:47:18 INFO [rdsLstnr-p-7-t-267] InetRadiusProcessor - New conne
ction id=21
radius 12-26/20:47:18 INFO [rdsLstnr-p-7-t-267] InetRadiusProcessor - Return co
de=0
radius 12-26/20:47:18 INFO [rdsLstnr-p-7-t-267] InetRadiusProcessor - RESPONSE_
BEFORE_POSTPROCESS:
Packet type: Access-Accept
Identifier: 22
Authenticator: {}
Attributes:
Acct-Interim-Interval=900
Reply-Message=10
Framed-IP-Address=x.x.109.1
Forward-Policy=in:NOAUTH-PPPOE
HTTP-Redirect-Profile-Name=NOAUTH
Process time auth: 43
radius 12-26/20:47:18 INFO [rdsLstnr-p-7-t-267] InetRadiusListenerWorker - RESP
ONSE:
Packet type: Access-Accept
Identifier: 22
Authenticator: {D4 A7 29 D8 94 2A C6 CC E5 AF 0F B0 93 2C 1F 12}
Attributes:
Acct-Interim-Interval=900
Reply-Message=10
Framed-IP-Address=x.x.109.1
Forward-Policy=in:NOAUTH-PPPOE
HTTP-Redirect-Profile-Name=NOAUTH
Process time auth: 45
connection 12-26/20:47:18 INFO [sa-p-9-t-4] ServiceActivatorSet - Connecting to
device
connection 12-26/20:47:18 INFO [sa-p-9-t-4] ServiceActivatorDeviceWorker - Do t
ask deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAcc
ountingEvent] moduleId: 1; pluginId: no; cid: 3; scid: -1; userId: 0; type: 1; d
eviceId: 2; connectionId: 21; timestamp: 1356540438138
connection 12-26/20:47:18 INFO [sa-p-9-t-4] InetApplication - TariffOptionMap:
{}
connection 12-26/20:47:18 INFO [sa-p-9-t-4] ServiceActivatorDeviceWorker - Comm
and result event: ServiceActivatorEvent type=4; inetServId: 1; call: true; oldSt
ate: 0; newState: 0; oldOptionSet: 16,12; newOptionSet: 16,12
connection 12-26/20:47:18 INFO [sa-p-9-t-4] ServiceActivatorDeviceWorker - Proc
essing deviceId:2; command ServiceActivatorEvent type=4; inetServId: 1; call: tr
ue; oldState: 0; newState: 0; oldOptionSet: 16,12; newOptionSet: 16,12
connection 12-26/20:47:18 INFO [sa-p-9-t-4] ServiceActivatorSet - Invoking onAc
countingStart
connection 12-26/20:47:18 INFO [sa-p-9-t-4] ServiceActivatorDeviceWorker - Process event type[4] result=true
mq 12-26/20:47:18 INFO [event-proc-p-2-t-1] InetConnectionManager - Add connect
ion: InetConnection [id=21-0, iface=2:570425615, sessId=0101FFFF6800010F-50DB2A2
F, start=26.12.2012 20:47:18, uname=test, addr=x.x.109.1]
connection 12-26/20:47:20 INFO [sa-p-9-t-4] ServiceActivatorDeviceWorker - Do t
ask deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaSta
teModifyEvent] moduleId: 1; pluginId: no; cid: 3; scid: -1; userId: 0; deviceId:
2; inetServId: 1; connectionId: 21; state: 0; accessCode: 10; timestamp: 135654
0440781
connection 12-26/20:47:20 INFO [sa-p-9-t-4] InetApplication - TariffOptionMap:
{}
connection 12-26/20:47:20 INFO [sa-p-9-t-4] ServiceActivatorDeviceWorker - Comm
and result event: ServiceActivatorEvent type=2; inetServId: 1; call: true; oldSt
ate: 1; newState: 0; oldOptionSet: ; newOptionSet: 16,12
connection 12-26/20:47:20 INFO [sa-p-9-t-4] ServiceActivatorDeviceWorker - Proc
essing deviceId:2; command ServiceActivatorEvent type=2; inetServId: 1; call: tr
ue; oldState: 1; newState: 0; oldOptionSet: ; newOptionSet: 16,12
connection 12-26/20:47:20 INFO [sa-p-9-t-4] ServiceActivatorSet - Invoking conn
ectionModify
connection 12-26/20:47:20 INFO [sa-p-9-t-4] CoAServiceActivator - Connection mo
dify: oldState: 1; newState: 0; oldOptionSet: []; newOptionSet: [16, 12]
connection 12-26/20:47:20 INFO [sa-p-9-t-4] CoAServiceActivator - Connection cl
ose
connection 12-26/20:47:20 INFO [sa-p-9-t-4] CoAServiceActivator - Send PoD:
Packet type: Disconnect-Request
Identifier: 3
Authenticator: {34 D4 A8 2F 83 06 73 DA 68 77 13 7D 0A 1E DE 94}
Attributes:
Acct-Session-Id=0101FFFF6800010F-50DB2A2F
connection 12-26/20:47:20 INFO [sa-p-9-t-4] RadiusClient - Sending to /x.x.1
08.128:3799
Packet type: Disconnect-Request
Identifier: 3
Authenticator: {34 D4 A8 2F 83 06 73 DA 68 77 13 7D 0A 1E DE 94}
Attributes:
Acct-Session-Id=0101FFFF6800010F-50DB2A2F
connection 12-26/20:47:20 INFO [sa-p-9-t-4] ServiceActivatorDeviceWorker - Proc
ess event type[2] result=true
connection 12-26/20:47:20 INFO [rds-clnt-/x.x.108.128-3799] RadiusClient - R
ecieved from /x.x.108.128:3799
Packet type: Disconnect-ACK
Identifier: 3
Authenticator: {56 85 F2 35 E6 B7 5D 45 B9 B7 33 AD A1 A1 D4 67}
Attributes:
Error-Cause=200
Event-Timestamp=1356540466
connection 12-26/20:47:21 INFO [sa-p-9-t-4] ServiceActivatorDeviceWorker - Do task deviceId: 2; Event[ru.bitel.bgbilling.modules.inet.access.sa.event.InetSaAcc
ountingEvent] moduleId: 1; pluginId: no; cid: 3; scid: -1; userId: 0; type: 2; d
eviceId: 2; connectionId: 21; timestamp: 1356540441636
connection 12-26/20:47:21 INFO [sa-p-9-t-4] InetApplication - TariffOptionMap:
{}
connection 12-26/20:47:21 INFO [sa-p-9-t-4] ServiceActivatorDeviceWorker - Comm
and result event: ServiceActivatorEvent type=5; inetServId: 1; call: true; oldSt
ate: 0; newState: 0; oldOptionSet: 16,12; newOptionSet: 16,12
connection 12-26/20:47:21 INFO [sa-p-9-t-4] ServiceActivatorDeviceWorker - Proc
essing deviceId:2; command ServiceActivatorEvent type=5; inetServId: 1; call: tr
ue; oldState: 0; newState: 0; oldOptionSet: 16,12; newOptionSet: 16,12
connection 12-26/20:47:21 INFO [sa-p-9-t-4] ServiceActivatorSet - Invoking onAc
countingStop
connection 12-26/20:47:21 INFO [sa-p-9-t-4] ServiceActivatorDeviceWorker - Proc
ess event type[5] result=true
mq 12-26/20:47:21 INFO [event-proc-p-2-t-1] InetConnectionManager - Remove conn
ection: InetConnection [id=21-0, iface=2:570425615, sessId=0101FFFF6800010F-50DB
2A2F, start=26.12.2012 20:47:18, uname=test, addr=x.x.109.1]
connection 12-26/20:47:26 INFO [sa-p-9-t-4] EventWorker - Future is done
connection 12-26/20:47:26 INFO [sa-p-9-t-4] ServiceActivatorDeviceWorker - Chan
ging InetConnection:21 state and/or options
connection 12-26/20:47:26 INFO [sa-p-9-t-4] ServiceActivatorSet - Disconnecting
from device