Добрый день столкнулись с такой же проблемой:
ошибка в dhcp.log:
Код:
Not found params for request: 5:000684C9B2AEBE00:000401D00004:002454AC2AA7
+ к этому в radius.log:
Код:
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] InetAccountingPeriodList - Create:
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] InetAccountingPeriodList - AccountingPeriod: 1: 13.03.2012 00:00:00.0 - 30.03.2013 23:59:59.999
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetApplication - inetServ[id=5] balance ok: 1111.00 [0]
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] ContractTariffOptionList - Create:
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetApplication - TariffOptionMap: {}
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] InetNas - agentRemoteId=null
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetRadiusProcessor - Set ip from serv
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetRadiusProcessor - Return code=0
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetDhcpHelperProcessor - Put auth accept 4:000684C9B2AEBE00:000401D00004:002454AC2AA7
08-28/14:39:31 ERROR [rdsLstnr-p-7-t-1] InetRadiusProcessor -
java.lang.NullPointerException
at ru.bitel.bgbilling.modules.inet.dhcp.InetDhcpHelperProcessor.put(InetDhcpHelperProcessor.java:119)
at ru.bitel.bgbilling.modules.inet.dhcp.InetDhcpHelperProcessor.access$4(InetDhcpHelperProcessor.java:108)
at ru.bitel.bgbilling.modules.inet.dhcp.InetDhcpHelperProcessor$1.notify(InetDhcpHelperProcessor.java:275)
at ru.bitel.bgbilling.modules.inet.dhcp.InetDhcpHelperProcessor$1.notify(InetDhcpHelperProcessor.java:1)
at ru.bitel.bgbilling.kernel.event.LocalEventProcessor.request(LocalEventProcessor.java:240)
at ru.bitel.bgbilling.kernel.event.EventProcessor.request(EventProcessor.java:850)
at ru.bitel.bgbilling.kernel.event.EventProcessor.request(EventProcessor.java:819)
at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.accessRequestImpl(InetRadiusProcessor.java:381)
at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.accessRequestImpl(InetRadiusProcessor.java:1)
at ru.bitel.bgbilling.kernel.network.radius.RadiusProcessor.accessRequest(RadiusProcessor.java:361)
at ru.bitel.bgbilling.modules.inet.radius.InetRadiusListenerWorker.accessRequest(InetRadiusListenerWorker.java:58)
at ru.bitel.bgbilling.modules.inet.radius.InetRadiusListenerWorker.accessRequest(InetRadiusListenerWorker.java:1)
at ru.bitel.bgbilling.kernel.network.radius.RadiusListenerWorker.processPacket(RadiusListenerWorker.java:213)
at ru.bitel.bgbilling.kernel.network.radius.RadiusListenerWorker.runImpl(RadiusListenerWorker.java:135)
at ru.bitel.common.worker.WorkerTask.run(WorkerTask.java:86)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
at ru.bitel.common.worker.WorkerThread.run(WorkerThread.java:40)
Подскажите, пожалуйста, где ошиблись?
полные логи:
Код:
08-28/14:39:14 INFO [main] DatagramChannelListener - Open socket [0.0.0.0/0.0.0.0:1812]
08-28/14:39:14 INFO [main] DatagramChannelListener - ru.bitel.bgbilling.modules.inet.radius.InetRadiusListener socket init ok.
08-28/14:39:14 INFO [main] Application - Started ru.bitel.bgbilling.modules.inet.radius.InetRadiusListener@1798928 [queue_size: 0; threads_active: 0; largest: 0; core: 10; pool_size: 0; recv_socket_buf_size: 56 320; recv_buf_size: 524 288]
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] RadiusListenerWorker - REQUEST:
Packet type: Access-Request
Identifier: 10
Authenticator: {ED 09 C1 B8 D0 C8 40 9A 84 29 FC 7D 0A D7 FC BC}
Attributes:
User-Name=000684c9b2aebe00:000401d00004:0024.54ac.2aa7
NAS-Identifier=isg-gw
NAS-Port-Id=0/0/1/1
User-Password=123
Event-Timestamp=1346150624
NAS-IP-Address=XX.YY.ZZ.181
NAS-Port=96702
Service-Type=5
Acct-Session-Id=77000000000179BE
NAS-Port-Type=33
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-avpair=circuit-id-tag=000401d00004
cisco-avpair=remote-id-tag=000684c9b2aebe00
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-NAS-Port=0/0/1/1
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetRadiusProcessor - REQUEST_AFTER_PREPROCESS:
Packet type: Access-Request
Identifier: 10
Authenticator: {ED 09 C1 B8 D0 C8 40 9A 84 29 FC 7D 0A D7 FC BC}
Attributes:
User-Name=000684c9b2aebe00:000401d00004
NAS-Identifier=isg-gw
NAS-Port-Id=0/0/1/1
User-Password=123
Event-Timestamp=1346150624
NAS-IP-Address=XX.YY.ZZ.181
NAS-Port=96702
Service-Type=5
Calling-Station-Id=0024.54ac.2aa7
Acct-Session-Id=77000000000179BE
NAS-Port-Type=33
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-avpair=circuit-id-tag=000401d00004
cisco-avpair=remote-id-tag=000684c9b2aebe00
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-NAS-Port=0/0/1/1
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] RadiusProcessor - Create new radius session.
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetNas - Search by username=000684c9b2aebe00:000401d00004
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetRadiusProcessor - [username=000684c9b2aebe00:000401d00004] Authenticated as inetServId:5
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] InetRadiusProcessor - MAC-address from request:
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] InetAccountingPeriodList - Create:
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] InetAccountingPeriodList - AccountingPeriod: 1: 13.03.2012 00:00:00.0 - 30.03.2013 23:59:59.999
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetApplication - inetServ[id=5] balance ok: 1111.00 [0]
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] ContractTariffOptionList - Create:
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetApplication - TariffOptionMap: {}
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] InetNas - agentRemoteId=null
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetRadiusProcessor - Set ip from serv
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetRadiusProcessor - Return code=0
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetDhcpHelperProcessor - Put auth accept 4:000684C9B2AEBE00:000401D00004:002454AC2AA7
08-28/14:39:31 ERROR [rdsLstnr-p-7-t-1] InetRadiusProcessor -
java.lang.NullPointerException
at ru.bitel.bgbilling.modules.inet.dhcp.InetDhcpHelperProcessor.put(InetDhcpHelperProcessor.java:119)
at ru.bitel.bgbilling.modules.inet.dhcp.InetDhcpHelperProcessor.access$4(InetDhcpHelperProcessor.java:108)
at ru.bitel.bgbilling.modules.inet.dhcp.InetDhcpHelperProcessor$1.notify(InetDhcpHelperProcessor.java:275)
at ru.bitel.bgbilling.modules.inet.dhcp.InetDhcpHelperProcessor$1.notify(InetDhcpHelperProcessor.java:1)
at ru.bitel.bgbilling.kernel.event.LocalEventProcessor.request(LocalEventProcessor.java:240)
at ru.bitel.bgbilling.kernel.event.EventProcessor.request(EventProcessor.java:850)
at ru.bitel.bgbilling.kernel.event.EventProcessor.request(EventProcessor.java:819)
at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.accessRequestImpl(InetRadiusProcessor.java:381)
at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.accessRequestImpl(InetRadiusProcessor.java:1)
at ru.bitel.bgbilling.kernel.network.radius.RadiusProcessor.accessRequest(RadiusProcessor.java:361)
at ru.bitel.bgbilling.modules.inet.radius.InetRadiusListenerWorker.accessRequest(InetRadiusListenerWorker.java:58)
at ru.bitel.bgbilling.modules.inet.radius.InetRadiusListenerWorker.accessRequest(InetRadiusListenerWorker.java:1)
at ru.bitel.bgbilling.kernel.network.radius.RadiusListenerWorker.processPacket(RadiusListenerWorker.java:213)
at ru.bitel.bgbilling.kernel.network.radius.RadiusListenerWorker.runImpl(RadiusListenerWorker.java:135)
at ru.bitel.common.worker.WorkerTask.run(WorkerTask.java:86)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
at ru.bitel.common.worker.WorkerThread.run(WorkerThread.java:40)
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetRadiusProcessor - RESPONSE_BEFORE_POSTPROCESS:
Packet type: Access-Accept
Identifier: 10
Authenticator: {}
Attributes:
Framed-IP-Address=XX.YY.ZZ.3
cisco-SSG-Account-Info=AINET
Process time auth: 75
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] HourlyDataLogger - Create log file...
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] HourlyLogFileUtils - Next log file: /opt/bg/ver.5.2/inetacs/data/radius/source_4/2012/2012-08/2012-08-28/log_2012-08-28-14.004.bgdl
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] HourlyDataLogEntry - Create dataLog file: /opt/bg/ver.5.2/inetacs/data/radius/source_4/2012/2012-08/2012-08-28/log_2012-08-28-14.004.bgdl
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] BGDataLog - DataLog file [data/radius/source_4/2012/2012-08/2012-08-28/log_2012-08-28-14.004.bgdl] was opened
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] DataLog - Writing TLV finished(3)=[0]
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] DataLog - Writing TLV buffer(4)=[type=chunked, size=524288]
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] DataLog - Writing TLV compression(5)=[type=none, level=1, strategy=1]
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] DataLog - Writing TLV distributed(6)=[1]
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] DataLog - Writing TLV streaming(7)=[1]
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] BGDataLog - Header length=51
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-1] ProcessorRequest - Sending to /XX.YY.ZZ.181:1645
08-28/14:39:31 INFO [rdsLstnr-p-7-t-1] InetRadiusListenerWorker - RESPONSE:
Packet type: Access-Accept
Identifier: 10
Authenticator: {88 70 3B D3 58 21 F0 AF 3A B2 2D D3 3C D4 13 7A}
Attributes:
Framed-IP-Address=XX.YY.ZZ.3
cisco-SSG-Account-Info=AINET
Process time auth: 514
08-28/14:39:31 INFO [rdsLstnr-p-7-t-2] RadiusListenerWorker - REQUEST:
Packet type: Access-Request
Identifier: 11
Authenticator: {C8 ED AC 5B 5E 45 92 FF 67 DF D4 2F F9 A7 C3 CE}
Attributes:
User-Name=INET
NAS-Identifier=isg-gw
NAS-Port-Id=0/0/1/1
User-Password=cisco
Event-Timestamp=1346150625
NAS-IP-Address=XX.YY.ZZ.181
NAS-Port=96702
Service-Type=5
Acct-Session-Id=77000000000179BE
NAS-Port-Type=33
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-avpair=circuit-id-tag=000401d00004
cisco-avpair=remote-id-tag=000684c9b2aebe00
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-NAS-Port=0/0/1/1
08-28/14:39:31 INFO [rdsLstnr-p-7-t-2] InetRadiusProcessor - REQUEST_AFTER_PREPROCESS:
Packet type: Access-Request
Identifier: 11
Authenticator: {C8 ED AC 5B 5E 45 92 FF 67 DF D4 2F F9 A7 C3 CE}
Attributes:
User-Name=INET
NAS-Identifier=isg-gw
NAS-Port-Id=0/0/1/1
User-Password=cisco
Event-Timestamp=1346150625
NAS-IP-Address=XX.YY.ZZ.181
NAS-Port=96702
Service-Type=5
Acct-Session-Id=77000000000179BE
NAS-Port-Type=33
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-avpair=circuit-id-tag=000401d00004
cisco-avpair=remote-id-tag=000684c9b2aebe00
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-NAS-Port=0/0/1/1
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-2] RadiusProcessor - Create new radius session.
08-28/14:39:31 INFO [rdsLstnr-p-7-t-2] InetNas - Search by username=INET
08-28/14:39:31 INFO [rdsLstnr-p-7-t-2] InetRadiusProcessor - [username=INET] Authenticated as inetServId:8
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-2] InetRadiusProcessor - MAC-address from request:
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-2] InetAccountingPeriodList - Create:
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-2] InetAccountingPeriodList - AccountingPeriod: 3: 23.08.2012 00:00:00.0 - 31.08.2012 23:59:59.999
08-28/14:39:31 INFO [rdsLstnr-p-7-t-2] InetApplication - inetServ[id=8] balance ok: 0.00 [0]
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-2] ContractTariffOptionList - Create:
08-28/14:39:31 INFO [rdsLstnr-p-7-t-2] InetApplication - TariffOptionMap: {}
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-2] InetNas - agentRemoteId=null
08-28/14:39:31 INFO [rdsLstnr-p-7-t-2] InetRadiusProcessor - Return code=0
08-28/14:39:31 INFO [rdsLstnr-p-7-t-2] InetDhcpHelperProcessor - Skip userName: INET
08-28/14:39:31 INFO [rdsLstnr-p-7-t-2] InetRadiusProcessor - RESPONSE_BEFORE_POSTPROCESS:
Packet type: Access-Accept
Identifier: 11
Authenticator: {}
Attributes:
Acct-Interim-Interval=60
cisco-avpair=ip:traffic-class=in access-group 196 priority 200
cisco-avpair=ip:traffic-class=in default drop
cisco-avpair=ip:traffic-class=out access-group 196 priority 200
cisco-avpair=ip:traffic-class=out default drop
cisco-avpair=subscriber:accounting-list=ISG-AUTH-1
cisco-SSG-Service-Info=QU;500000;750000;D;500000;750000
Process time auth: 17
08-28/14:39:31 DEBUG [rdsLstnr-p-7-t-2] ProcessorRequest - Sending to /XX.YY.ZZ.181:1645
08-28/14:39:31 INFO [rdsLstnr-p-7-t-2] InetRadiusListenerWorker - RESPONSE:
Packet type: Access-Accept
Identifier: 11
Authenticator: {8E 16 3D 15 81 C0 32 D9 34 01 02 0A 72 A9 A3 3C}
Attributes:
Acct-Interim-Interval=60
cisco-avpair=ip:traffic-class=in access-group 196 priority 200
cisco-avpair=ip:traffic-class=in default drop
cisco-avpair=ip:traffic-class=out access-group 196 priority 200
cisco-avpair=ip:traffic-class=out default drop
cisco-avpair=subscriber:accounting-list=ISG-AUTH-1
cisco-SSG-Service-Info=QU;500000;750000;D;500000;750000
Process time auth: 20
08-28/14:39:38 INFO [rdsLstnr-p-7-t-3] RadiusListenerWorker - REQUEST:
Packet type: Access-Request
Identifier: 12
Authenticator: {E1 B1 23 00 57 F8 93 7C 35 0B 5A 3B 5D 6E F9 B1}
Attributes:
User-Name=000684c9b2aebe00:000401d00004:0024.54ac.2aa7
NAS-Identifier=isg-gw
NAS-Port-Id=0/0/1/1
User-Password=123
Event-Timestamp=1346150632
NAS-IP-Address=XX.YY.ZZ.181
NAS-Port=96704
Service-Type=5
Acct-Session-Id=77000000000179C0
NAS-Port-Type=33
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-avpair=circuit-id-tag=000401d00004
cisco-avpair=remote-id-tag=000684c9b2aebe00
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-NAS-Port=0/0/1/1
08-28/14:39:38 INFO [rdsLstnr-p-7-t-3] InetRadiusProcessor - REQUEST_AFTER_PREPROCESS:
Packet type: Access-Request
Identifier: 12
Authenticator: {E1 B1 23 00 57 F8 93 7C 35 0B 5A 3B 5D 6E F9 B1}
Attributes:
User-Name=000684c9b2aebe00:000401d00004
NAS-Identifier=isg-gw
NAS-Port-Id=0/0/1/1
User-Password=123
Event-Timestamp=1346150632
NAS-IP-Address=XX.YY.ZZ.181
NAS-Port=96704
Service-Type=5
Calling-Station-Id=0024.54ac.2aa7
Acct-Session-Id=77000000000179C0
NAS-Port-Type=33
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-avpair=circuit-id-tag=000401d00004
cisco-avpair=remote-id-tag=000684c9b2aebe00
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-NAS-Port=0/0/1/1
08-28/14:39:38 DEBUG [rdsLstnr-p-7-t-3] RadiusProcessor - Create new radius session.
08-28/14:39:38 INFO [rdsLstnr-p-7-t-3] InetNas - Search by username=000684c9b2aebe00:000401d00004
08-28/14:39:38 INFO [rdsLstnr-p-7-t-3] InetRadiusProcessor - [username=000684c9b2aebe00:000401d00004] Authenticated as inetServId:5
08-28/14:39:38 DEBUG [rdsLstnr-p-7-t-3] InetRadiusProcessor - MAC-address from request:
08-28/14:39:38 DEBUG [rdsLstnr-p-7-t-3] InetAccountingPeriodList - Create:
08-28/14:39:38 DEBUG [rdsLstnr-p-7-t-3] InetAccountingPeriodList - AccountingPeriod: 1: 13.03.2012 00:00:00.0 - 30.03.2013 23:59:59.999
08-28/14:39:38 INFO [rdsLstnr-p-7-t-3] InetApplication - inetServ[id=5] balance ok: 1111.00 [0]
08-28/14:39:38 INFO [rdsLstnr-p-7-t-3] InetApplication - TariffOptionMap: {}
08-28/14:39:38 DEBUG [rdsLstnr-p-7-t-3] InetNas - agentRemoteId=null
08-28/14:39:38 INFO [rdsLstnr-p-7-t-3] InetRadiusProcessor - Set ip from serv
08-28/14:39:38 INFO [rdsLstnr-p-7-t-3] InetRadiusProcessor - Return code=0
08-28/14:39:38 INFO [rdsLstnr-p-7-t-3] InetDhcpHelperProcessor - Put auth accept 4:000684C9B2AEBE00:000401D00004:002454AC2AA7
08-28/14:39:38 INFO [rdsLstnr-p-7-t-3] InetRadiusProcessor - RESPONSE_BEFORE_POSTPROCESS:
Packet type: Access-Accept
Identifier: 12
Authenticator: {}
Attributes:
Framed-IP-Address=XX.YY.ZZ.3
cisco-SSG-Account-Info=AINET
Process time auth: 9
08-28/14:39:38 DEBUG [rdsLstnr-p-7-t-3] ProcessorRequest - Sending to /XX.YY.ZZ.181:1645
08-28/14:39:38 INFO [rdsLstnr-p-7-t-3] InetRadiusListenerWorker - RESPONSE:
Packet type: Access-Accept
Identifier: 12
Authenticator: {35 82 2C 1D A9 0E 52 56 31 A1 BD AF 88 F6 6B A2}
Attributes:
Framed-IP-Address=XX.YY.ZZ.3
cisco-SSG-Account-Info=AINET
Process time auth: 35
08-28/14:39:38 INFO [rdsLstnr-p-7-t-4] RadiusListenerWorker - REQUEST:
Packet type: Access-Request
Identifier: 13
Authenticator: {30 94 4F A1 F8 FC C9 EF 97 0C 84 38 C4 CB 7C D5}
Attributes:
User-Name=INET
NAS-Identifier=isg-gw
NAS-Port-Id=0/0/1/1
User-Password=cisco
Event-Timestamp=1346150632
NAS-IP-Address=XX.YY.ZZ.181
NAS-Port=96704
Service-Type=5
Acct-Session-Id=77000000000179C0
NAS-Port-Type=33
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-avpair=circuit-id-tag=000401d00004
cisco-avpair=remote-id-tag=000684c9b2aebe00
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-NAS-Port=0/0/1/1
08-28/14:39:38 INFO [rdsLstnr-p-7-t-4] InetRadiusProcessor - REQUEST_AFTER_PREPROCESS:
Packet type: Access-Request
Identifier: 13
Authenticator: {30 94 4F A1 F8 FC C9 EF 97 0C 84 38 C4 CB 7C D5}
Attributes:
User-Name=INET
NAS-Identifier=isg-gw
NAS-Port-Id=0/0/1/1
User-Password=cisco
Event-Timestamp=1346150632
NAS-IP-Address=XX.YY.ZZ.181
NAS-Port=96704
Service-Type=5
Acct-Session-Id=77000000000179C0
NAS-Port-Type=33
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-avpair=circuit-id-tag=000401d00004
cisco-avpair=remote-id-tag=000684c9b2aebe00
cisco-avpair=vendor-class-id-tag=MSFT 5.0
cisco-NAS-Port=0/0/1/1
08-28/14:39:38 DEBUG [rdsLstnr-p-7-t-4] RadiusProcessor - Create new radius session.
08-28/14:39:38 INFO [rdsLstnr-p-7-t-4] InetNas - Search by username=INET
08-28/14:39:38 INFO [rdsLstnr-p-7-t-4] InetRadiusProcessor - [username=INET] Authenticated as inetServId:8
08-28/14:39:38 DEBUG [rdsLstnr-p-7-t-4] InetRadiusProcessor - MAC-address from request:
08-28/14:39:38 DEBUG [rdsLstnr-p-7-t-4] InetAccountingPeriodList - Create:
08-28/14:39:38 DEBUG [rdsLstnr-p-7-t-4] InetAccountingPeriodList - AccountingPeriod: 3: 23.08.2012 00:00:00.0 - 31.08.2012 23:59:59.999
08-28/14:39:38 INFO [rdsLstnr-p-7-t-4] InetApplication - inetServ[id=8] balance ok: 0.00 [0]
08-28/14:39:38 INFO [rdsLstnr-p-7-t-4] InetApplication - TariffOptionMap: {}
08-28/14:39:38 DEBUG [rdsLstnr-p-7-t-4] InetNas - agentRemoteId=null
08-28/14:39:38 INFO [rdsLstnr-p-7-t-4] InetRadiusProcessor - Return code=0
08-28/14:39:38 INFO [rdsLstnr-p-7-t-4] InetDhcpHelperProcessor - Skip userName: INET
08-28/14:39:38 INFO [rdsLstnr-p-7-t-4] InetRadiusProcessor - RESPONSE_BEFORE_POSTPROCESS:
Packet type: Access-Accept
Identifier: 13
Authenticator: {}
Attributes:
Acct-Interim-Interval=60
cisco-avpair=ip:traffic-class=in access-group 196 priority 200
cisco-avpair=ip:traffic-class=in default drop
cisco-avpair=ip:traffic-class=out access-group 196 priority 200
cisco-avpair=ip:traffic-class=out default drop
cisco-avpair=subscriber:accounting-list=ISG-AUTH-1
cisco-SSG-Service-Info=QU;500000;750000;D;500000;750000
Process time auth: 14
08-28/14:39:38 DEBUG [rdsLstnr-p-7-t-4] ProcessorRequest - Sending to /XX.YY.ZZ.181:1645
08-28/14:39:38 INFO [rdsLstnr-p-7-t-4] InetRadiusListenerWorker - RESPONSE:
Packet type: Access-Accept
Identifier: 13
Authenticator: {B3 5E 36 5F 2C 20 66 A8 B9 74 77 56 73 AC C9 79}
Attributes:
Acct-Interim-Interval=60
cisco-avpair=ip:traffic-class=in access-group 196 priority 200
cisco-avpair=ip:traffic-class=in default drop
cisco-avpair=ip:traffic-class=out access-group 196 priority 200
cisco-avpair=ip:traffic-class=out default drop
cisco-avpair=subscriber:accounting-list=ISG-AUTH-1
cisco-SSG-Service-Info=QU;500000;750000;D;500000;750000
Process time auth: 17
Код:
08-28/14:39:14 INFO [main] Application - Starting ru.bitel.bgbilling.modules.inet.dhcp.InetDhcpHelperProcessor@8d5aad
08-28/14:39:14 INFO [main] InetDhcpDeviceMap - Load DHCP relay list, types: [3,4].
08-28/14:39:14 INFO [main] InetDhcpDeviceMap - Loaded device: 4
08-28/14:39:14 INFO [main] InetDhcpDeviceMap - Bind device: 4; /XX.YY.ZZ.65
08-28/14:39:14 INFO [main] InetDhcpDeviceMap - Loaded device: 5
08-28/14:39:14 INFO [main] InetDhcpDeviceMap - Bind device: 5; /10.10.10.145
08-28/14:39:14 DEBUG [main] EventProcessor - Add ru.bitel.bgbilling.modules.inet.dhcp.InetDhcpDeviceMap$1@8d2280 to BG.Event.ru.bitel.bgbilling.modules.inet.api.server.event.InetReloadEvent WHERE moduleId=1
08-28/14:39:14 INFO [main] InetDhcpHelperProcessor - Restore connections on DhcpHelper
08-28/14:39:14 INFO [main] Application - Started ru.bitel.bgbilling.modules.inet.dhcp.InetDhcpHelperProcessor@8d5aad
08-28/14:39:14 INFO [main] Application - Starting ru.bitel.bgbilling.kernel.network.dhcp.DhcpListener@1d4340c
08-28/14:39:14 INFO [main] Application - Started ru.bitel.bgbilling.kernel.network.dhcp.DhcpListener@1d4340c
08-28/14:39:31 DEBUG [dhcpLstnr-p-9-t-1] InetDhcpDeviceMap - Found device by giaddr id=4
08-28/14:39:31 DEBUG [dhcpLstnr-p-9-t-1] InetDhcpDeviceMap - Found subDevice by identifier id=5
08-28/14:39:31 INFO [dhcpLstnr-p-9-t-1] InetAbstractDhcpProcessor - REQUEST:
Message type: BOOT_REQUEST
Dhcp message type: DHCP Discover{1}
htype: 1, hlen: 6, hops: 1
xid: -547448078, secs: 1280, flags: 0
Client IP: 0.0.0.0
Your IP: 0.0.0.0
Server IP: 0.0.0.0
Relay IP: XX.YY.ZZ.65
Client MAC: {002454AC2AA7}
{61}={01002454AC2AA7}
Host name{12}={rollan-��}
{60}={4D53465420352E30}
Parameter request list{55}={1, 15, 3, 6, 44, 46, 47, 31, 33, 121, -7, 43}
Agent information{82}=
sub{1}={000401D00004}
sub{2}={000684C9B2AEBE00}
08-28/14:39:31 DEBUG [dhcpLstnr-p-9-t-1] InetDhcpHelperProcessor - OP_BOOT_REQUEST
08-28/14:39:31 DEBUG [dhcpLstnr-p-9-t-1] InetDhcpHelperProcessor - request.giaddr = XX.YY.ZZ.65
08-28/14:39:31 ERROR [dhcpLstnr-p-9-t-1] InetDhcpHelperProcessor - Not found params for request: 5:000684C9B2AEBE00:000401D00004:002454AC2AA7
08-28/14:39:31 INFO [dhcpLstnr-p-9-t-1] InetAbstractDhcpProcessor - RESPONSE:
Message type: BOOT_RESPONSE
Dhcp message type: DHCP NAK{6}
htype: 1, hlen: 6, hops: 1
xid: -547448078, secs: 0, flags: 0
Client IP: 0.0.0.0
Your IP: 0.0.0.0
Server IP: 0.0.0.0
Relay IP: XX.YY.ZZ.65
Client MAC: {002454AC2AA7}
Agent information{82}=
sub{1}={000401D00004}
sub{2}={000684C9B2AEBE00}
08-28/14:39:31 DEBUG [dhcpLstnr-p-9-t-1] ProcessorRequest - Sending to /XX.YY.ZZ.65:67
08-28/14:39:38 DEBUG [dhcpLstnr-p-9-t-2] InetDhcpDeviceMap - Found device by giaddr id=4
08-28/14:39:38 DEBUG [dhcpLstnr-p-9-t-2] InetDhcpDeviceMap - Found subDevice by identifier id=5
08-28/14:39:38 INFO [dhcpLstnr-p-9-t-2] InetAbstractDhcpProcessor - REQUEST:
Message type: BOOT_REQUEST
Dhcp message type: DHCP Discover{1}
htype: 1, hlen: 6, hops: 1
xid: -547448078, secs: 3328, flags: 0
Client IP: 0.0.0.0
Your IP: 0.0.0.0
Server IP: 0.0.0.0
Relay IP: XX.YY.ZZ.65
Client MAC: {002454AC2AA7}
{61}={01002454AC2AA7}
Host name{12}={rollan-��}
{60}={4D53465420352E30}
Parameter request list{55}={1, 15, 3, 6, 44, 46, 47, 31, 33, 121, -7, 43}
Agent information{82}=
sub{1}={000401D00004}
sub{2}={000684C9B2AEBE00}
08-28/14:39:38 DEBUG [dhcpLstnr-p-9-t-2] InetDhcpHelperProcessor - OP_BOOT_REQUEST
08-28/14:39:38 DEBUG [dhcpLstnr-p-9-t-2] InetDhcpHelperProcessor - request.giaddr = XX.YY.ZZ.65
08-28/14:39:38 ERROR [dhcpLstnr-p-9-t-2] InetDhcpHelperProcessor - Not found params for request: 5:000684C9B2AEBE00:000401D00004:002454AC2AA7
08-28/14:39:38 INFO [dhcpLstnr-p-9-t-2] InetAbstractDhcpProcessor - RESPONSE:
Message type: BOOT_RESPONSE
Dhcp message type: DHCP NAK{6}
htype: 1, hlen: 6, hops: 1
xid: -547448078, secs: 0, flags: 0
Client IP: 0.0.0.0
Your IP: 0.0.0.0
Server IP: 0.0.0.0
Relay IP: XX.YY.ZZ.65
Client MAC: {002454AC2AA7}
Agent information{82}=
sub{1}={000401D00004}
sub{2}={000684C9B2AEBE00}
08-28/14:39:38 DEBUG [dhcpLstnr-p-9-t-2] ProcessorRequest - Sending to /XX.YY.ZZ.65:67
08-28/14:40:01 INFO [Thread-12] Application - Stopping ru.bitel.bgbilling.kernel.network.dhcp.DhcpListener@1d4340c