BiTel

10:21:10.396603 IP h109-233-170-1.broadband.nettrans.ru.bootps > 192.168.1.1.bootps: BOOTP/DHCP, Request from b8:88:e3:a0:12:7e (oui Unknown), length 320
10:26:28.348342 IP 192.168.1.2.datametrics > 192.168.1.1.radius: RADIUS, Access Request (1), id: 0xb5 length: 235
10:26:28.355346 IP h109-233-170-1.broadband.nettrans.ru.bootps > 192.168.1.1.bootps: BOOTP/DHCP, Request from b8:88:e3:a0:12:7e (oui Unknown), length 320
10:26:28.405953 ARP, Request who-has 192.168.1.2 tell 192.168.1.1, length 28
10:26:28.406229 ARP, Reply 192.168.1.2 is-at 54:75:d0:93:cd:00 (oui Unknown), length 46
10:26:28.406245 IP 192.168.1.1.radius > 192.168.1.2.datametrics: RADIUS, Access Accept (2), id: 0xb5 length: 26
10:26:32.810076 IP h109-233-170-1.broadband.nettrans.ru.bootps > 192.168.1.1.bootps: BOOTP/DHCP, Request from b8:88:e3:a0:12:7e (oui Unknown), length 320
10:26:36.579563 IP h109-233-170-1.broadband.nettrans.ru.bootps > 192.168.1.1.bootps: BOOTP/DHCP, Request from b8:88:e3:a0:12:7e (oui Unknown), length 320
10:26:44.444058 IP h109-233-170-1.broadband.nettrans.ru.bootps > 192.168.1.1.bootps: BOOTP/DHCP, Request from b8:88:e3:a0:12:7e (oui Unknown), length 320
10:26:59.934250 IP h109-233-170-1.broadband.nettrans.ru.bootps > 192.168.1.1.bootps: BOOTP/DHCP, Request from b8:88:e3:a0:12:7e (oui Unknown), length 320
10:27:32.353924 IP h109-233-170-1.broadband.nettrans.ru.bootps > 192.168.1.1.bootps: BOOTP/DHCP, Request from b8:88:e3:a0:12:7e (oui Unknown), length 320

        <!-- Создание Access -->
        <bean name="access" class="ru.bitel.bgbilling.modules.inet.access.Access" />

        <context name="radius">
                <!-- Cоздание процессора radius-пакетов -->
                <bean name="radiusProcessor" class="ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor"/>

                <!-- Служебный ScheduledExecutorService, необходимый для dataLogger -->
                <scheduledExecutorService name="hrlydtlggr" corePoolSize="1" />

                <!-- Cоздание dataLogger, сохраняющего radius-пакеты на диск (только один экземпляр) -->
                <bean name="radiusDataLogger" class="ru.bitel.bgbilling.modules.inet.radius.RadiusHourlyDataLogger">
                        <param name="scheduledExecutor">hrlydtlggr</param>
                </bean>

                <!-- Cоздание слушателя radius-пакетов на порту с передачей ему процессора и dataLogger -->
                <bean name="radiusListener" class="ru.bitel.bgbilling.modules.inet.radius.InetRadiusListener">
                        <constructor>
                                <!-- Хост (интерфейс), на котором будет открыт сокет. Если пусто - на всех -->
                                <param name="host" value=""/>
                                <!-- Порт, на котором будет открыт сокет -->
                                <param name="port" value="1812"/>
                                <!-- Размер буфера приема слушателя -->
                                <param name="recvBufferSize">512 * 1024</param>
                                <!-- Рекомендуемый SO_RCVBUF сокета -->
                                <param name="soRCVBUF"></param>
                                <!-- Количество потоков-обработчиков -->
                                <param name="threadCount">10</param>
                                <!-- Максимальное количество пакетов в очереди на обработку -->
                                <param name="maxQueueSize">200</param>
                                <!-- Передача процессора -->
                                <param name="processor">radiusProcessor</param>
                                <!-- Режим работы, RadiusListener.Mode.authentication -->
                                <param name="mode">RadiusListener.Mode.authentication</param>
                                <!-- Передача dataLogger -->
                                <param name="dataLogger">radiusDataLogger</param>
                        </constructor>
                </bean>
        </context>

        <context name="dhcp">
                <!-- Cоздание процессора dhcp-пакетов -->
                <bean name="dhcpProcessor" class="ru.bitel.bgbilling.modules.inet.dhcp.InetDhcpHelperProcessor"/>

                <scheduledExecutorService name="hrlydtlggr" corePoolSize="1" />

                <!-- Cоздание dataLogger, сохраняющего dhcp-пакеты на диск  -->
                <bean name="dhcpDataLogger" class="ru.bitel.bgbilling.modules.inet.dhcp.DhcpHourlyDataLogger">
                        <param name="scheduledExecutor">hrlydtlggr</param>
                </bean>

                <!-- Cоздание слушателя dhcp-пакетов на порту с передачей ему процессора и dataLogger -->
                <bean name="dhcpListener" class="ru.bitel.bgbilling.kernel.network.dhcp.DhcpListener">
                        <constructor>
                                <!-- Хост (интерфейс), на котором будет открыт сокет. Если пусто - на всех -->
                                <param name="host" value=""/>
                                <!-- Порт, на котором будет открыт сокет -->
                                <param name="port" value="67"/>
                                <!-- Размер буфера приема слушателя -->
                                <param name="recvBufferSize">512 * 1024</param>
                                <!-- Количество потоков-обработчиков -->
                                <param name="threadCount">10</param>
                                <!-- Максимальное количество пакетов в очереди на обработку -->
                                <param name="maxQueueSize">200</param>
                                <!-- Передача процессора -->
                                <param name="processor">dhcpProcessor</param>
                                <!-- Передача dataLogger -->
                                <param name="dataLogger">dhcpDataLogger</param>
                        </constructor>
                </bean>
        </context>

02-08/10:26:28  INFO [rdsLstnr-p-8-t-2] RadiusListenerWorker - REQUEST:
Packet type: Access-Request
Identifier: 181
Authenticator: {B3 8F B6 95 DF A9 22 52 23 C2 24 A9 A3 3D ED 58}
Attributes:
  User-Name=0006781dba46adfa:000400aa0001:b888.e3a0.127e
  NAS-Identifier=Router
  NAS-Port-Id=0/0/0/170
  User-Password=123
  NAS-IP-Address=192.168.1.2
  NAS-Port=1015
  Service-Type=5
  Acct-Session-Id=71000000000003F7
  NAS-Port-Type=33
  cisco-avpair=circuit-id-tag=000400aa0001
  cisco-avpair=remote-id-tag=0006781dba46adfa
  cisco-NAS-Port=0/0/0/170

02-08/10:26:28  INFO [rdsLstnr-p-8-t-2] InetRadiusProcessor - REQUEST_AFTER_PREPROCESS:
Packet type: Access-Request
Identifier: 181
Authenticator: {B3 8F B6 95 DF A9 22 52 23 C2 24 A9 A3 3D ED 58}
Attributes:
  User-Name=0006781dba46adfa:000400aa0001
  NAS-Identifier=Router
  NAS-Port-Id=0/0/0/170
  User-Password=123
  NAS-IP-Address=192.168.1.2
  NAS-Port=1015
  Service-Type=5
  Calling-Station-Id=b888.e3a0.127e
  Acct-Session-Id=71000000000003F7
  NAS-Port-Type=33
  cisco-avpair=circuit-id-tag=000400aa0001
  cisco-avpair=remote-id-tag=0006781dba46adfa
  cisco-NAS-Port=0/0/0/170
Common options: {agentRemoteId=781dba46adfa, agentCircuitId=00aa0001}
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] RadiusProcessor - Create new radius session.
02-08/10:26:28  INFO [rdsLstnr-p-8-t-2] InetNas - Search by username=0006781dba46adfa:000400aa0001
02-08/10:26:28  INFO [rdsLstnr-p-8-t-2] InetRadiusProcessor - [username=0006781dba46adfa:000400aa0001] Authenticated as inetServId:16
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] InetNas - agentRemoteId=781dba46adfa
02-08/10:26:28  INFO [rdsLstnr-p-8-t-2] InetNas - Found agentDevice:3
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] InetRadiusProcessor - Identifier from request: null
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] InetRadiusProcessor - MAC-address from request:
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] InetAccountingPeriodList - Create:
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] InetAccountingPeriodList - AccountingPeriod: 2: 01.02.2013 00:00:00.0 - 28.02.2013 23:59:59.999
02-08/10:26:28  INFO [rdsLstnr-p-8-t-2] InetApplication - inetServ[id=16] balance ok: 2.00 [-100.00]
02-08/10:26:28  INFO [rdsLstnr-p-8-t-2] InetApplication - TariffOptionMap: {}
02-08/10:26:28  INFO [rdsLstnr-p-8-t-2] InetRadiusProcessor - Set ip from pool
02-08/10:26:28  INFO [rdsLstnr-p-8-t-2] InetRadiusProcessor - Return code=0
02-08/10:26:28  INFO [rdsLstnr-p-8-t-2] InetDhcpHelperProcessor - Put auth accept 2:0006781DBA46ADFA:000400AA0001:B888E3A0127E
02-08/10:26:28  INFO [rdsLstnr-p-8-t-2] InetRadiusProcessor - RESPONSE_BEFORE_POSTPROCESS:
Packet type: Access-Accept
Identifier: 181
Authenticator: {}
Attributes:
  Framed-IP-Address=109.233.170.52

Process time auth: 10

02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] HourlyDataLogger - Create log file...
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] HourlyLogFileUtils - Next log file: /opt/BG/BGInetAccess/data/radius/source_2/2013/2013-02/2013-02-08/log_2013-02-08-10.001.bgdl
02-08/10:26:28  INFO [rdsLstnr-p-8-t-2] HourlyDataLogEntry - Create dataLog file: /opt/BG/BGInetAccess/data/radius/source_2/2013/2013-02/2013-02-08/log_2013-02-08-10.001.bgdl
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] BGDataLog - DataLog file [data/radius/source_2/2013/2013-02/2013-02-08/log_2013-02-08-10.001.bgdl] was opened
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] DataLog - Writing TLV finished(3)=[0]
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] DataLog - Writing TLV buffer(4)=[type=chunked, size=262144]
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] DataLog - Writing TLV compression(5)=[type=zlib, level=1, strategy=1]
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] DataLog - Writing TLV distributed(6)=[1]
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] DataLog - Writing TLV streaming(7)=[1]
02-08/10:26:28 DEBUG [rdsLstnr-p-8-t-2] BGDataLog - Header length=51

radius 02-08/13:21:48 ERROR [rdsLstnr-p-8-t-1] InetNas - Can't reserve ip address for inetNas:2 and realm: default!
radius 02-08/13:21:48 ERROR [rdsLstnr-p-8-t-1] InetRadiusProcessor - Can't reserve ip address!

dhcp 02-08/15:21:08 ERROR [dhcpLstnr-p-10-t-1] InetDhcpHelperProcessor - Not found params for request: 3:0006781DBA46ADFA:000400AA0002:00E081417029 (pattern: $deviceId:$remoteId:$circuitId:$mac, servSearchMode: 0, deviceId: 3, agentDeviceId: 3, mac: 00E081417029)
dhcp 02-08/15:21:12 ERROR [dhcpLstnr-p-10-t-2] InetDhcpHelperProcessor - Not found params for request: 3:0006781DBA46ADFA:000400AA0002:00E081417029 (pattern: $deviceId:$remoteId:$circuitId:$mac, servSearchMode: 0, deviceId: 3, agentDeviceId: 3, mac: 00E081417029)
dhcp 02-08/15:21:19 ERROR [dhcpLstnr-p-10-t-3] InetDhcpHelperProcessor - Not found params for request: 3:0006781DBA46ADFA:000400AA0002:00E081417029 (pattern: $deviceId:$remoteId:$circuitId:$mac, servSearchMode: 0, deviceId: 3, agentDeviceId: 3, mac: 00E081417029)

Put auth accept 2:0006781DBA46ADFA:000400AA0001:B888E3A0127E

dhcp 02-08/16:02:46 ERROR [dhcpLstnr-p-10-t-10] InetDhcpHelperProcessor - Not found params for request: 3:3:1:B888E3A0127E (pattern: $deviceId:$remoteId:$circuitId:$mac, servSearchMode: 1, deviceId: 3, agentDeviceId: 3, mac: B888E3A0127E)
radius 02-08/16:03:03 ERROR [rdsLstnr-p-8-t-1] RadiusListenerWorker - 5
java.lang.ArrayIndexOutOfBoundsException: 5
        at ru.bitel.bgbilling.modules.inet.api.server.InetUtils.parseInt(InetUtils.java:485)
        at ru.bitel.bgbilling.modules.inet.runtime.device.AbstractInetDeviceRuntime.getOption82InterfaceId(AbstractInetDeviceRuntime.java:329)
        at ru.bitel.bgbilling.modules.inet.runtime.device.InetDeviceRuntime.getOption82InterfaceId(InetDeviceRuntime.java:1)
        at ru.bitel.bgbilling.modules.inet.radius.InetNas.findServRuntime(InetNas.java:1265)
        at ru.bitel.bgbilling.modules.inet.radius.InetNas.findServRuntime(InetNas.java:1175)
        at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.findServRuntime(InetRadiusProcessor.java:1377)
        at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.authenticationImpl(InetRadiusProcessor.java:508)
        at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.authentication(InetRadiusProcessor.java:466)
        at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.authentication(InetRadiusProcessor.java:1)
        at ru.bitel.bgbilling.kernel.network.radius.RadiusSession.authentication(RadiusSession.java:115)
        at ru.bitel.bgbilling.kernel.network.radius.RadiusSession.accessRequest(RadiusSession.java:92)
        at ru.bitel.bgbilling.kernel.network.radius.RadiusProcessor.accessRequestImpl(RadiusProcessor.java:390)
        at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.accessRequestImpl(InetRadiusProcessor.java:382)
        at ru.bitel.bgbilling.modules.inet.radius.InetRadiusProcessor.accessRequestImpl(InetRadiusProcessor.java:1)
        at ru.bitel.bgbilling.kernel.network.radius.RadiusProcessor.accessRequest(RadiusProcessor.java:377)
        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)
radius 02-08/16:03:03 ERROR [rdsLstnr-p-8-t-10] RadiusListenerWorker - 5

dhcp.option82.agentRemoteId.code=
dhcp.option82.agentRemoteId.position=
dhcp.option82.agentRemoteId.length=
# обрезание заголовка-длины 0006
dhcp.option82.removeHeader=2

dhcp.option82.interfaceId.code=
dhcp.option82.interfaceId.position=
dhcp.option82.interfaceId.length=

dhcp 02-11/09:21:45 ERROR [dhcpLstnr-p-10-t-4] InetDhcpHelperProcessor - Not found params for request: 3:0006781DBA46ADFA:00AA0002:00E081417029 (pattern: $deviceId:$remoteId:$circuitId:$mac, servSearchMode: 0, deviceId: 3, agentDeviceId: 3, mac: 00E081417029)

# Параметр привязки IP-адреса (сессии) к DHCP
dhcp.key.pattern=$remoteId:$circuitId:$mac

[root@zabb log]# cat ./radius.log | grep Put
02-11/11:25:48  INFO [rdsLstnr-p-8-t-1] InetDhcpHelperProcessor - Put auth accept 0006781DBA46ADFA:000400AA0002:00E081417029
[root@zabb log]#

[root@zabb log]# cat ./error.log
dhcp 02-11/11:25:48 ERROR [dhcpLstnr-p-10-t-1] InetDhcpHelperProcessor - Not found params for request: 0006781DBA46ADFA:000400AA0002:00E081417029 (pattern: $remoteId:$circuitId:$mac, servSearchMode: 0, deviceId: 3, agentDeviceId: 3, mac: 00E081417029)
[root@zabb log]#