Jump to content
Калькуляторы

accel pptpd accel pptpd

12 минут назад, agent2011 сказал:

В момент загрузки этих 300 мегабит на сессии в htop вижу что 1 и 7 ядро нагружаются на 50 %.

RPS для интерфейса, на котором висят vlan-ы в сторону клиента включен?

Share this post


Link to post
Share on other sites
38 минут назад, taf_321 сказал:

RPS для интерфейса, на котором висят vlan-ы в сторону клиента включен?

Можно поподробнее об этом? прошу прощения за свою неграмотность.

 

Конкретно в этом случае скорость проверялась не в стандартном 1 вилане. Клиентское устройство было в подсети eth1 интерфейса, не в vlan

Share this post


Link to post
Share on other sites

подробнее тут: https://access.redhat.com/documentation/en-us/red_hat_enterprise_linux/6/html/performance_tuning_guide/network-rps

 

Так же будет неплохо сделать поиск по "RPS" в этой же теме. Вопрос поднимался многократно и подробнейше разжевывался.

Share this post


Link to post
Share on other sites
35 минут назад, taf_321 сказал:

подробнее тут: https://access.redhat.com/documentation/en-us/red_hat_enterprise_linux/6/html/performance_tuning_guide/network-rps

 

Так же будет неплохо сделать поиск по "RPS" в этой же теме. Вопрос поднимался многократно и подробнейше разжевывался.

Прошу прощения.

 

Спасибо за наводку, по нагрузке на процессоры проблему решили. Использовали autorps из netutils-linux. Всё равномерно распределилось.

Но по скорости нужного результата не добились.

 

Может быть еще нужно куда-то поковырять?

 

Немного оффтоп, подскажите пожалуйста, как избавиться из конфига от этого:

interface=vlan2
...
...
...
interface=vlan50

По примеру из самого конфига не могу сообразить, как же все же указать одной строкой по интерфейсам использовать с 2 vlan по 1000 например :-)

Share this post


Link to post
Share on other sites

В interface можно применять регулярные выражения. У меня это все прописано так: interface=re:^vlanPPPoE(\d+)$ в результате accel слушает все интерфейсы vlanPPPoE с любыми цифрами в конце.

 

Кстати, возможно что проьлема с 300 мегабитами из-за применения tbf в качестве шейпера. Попробуйте использовать policy и для down-limiter

Share this post


Link to post
Share on other sites
5 часов назад, taf_321 сказал:

В interface можно применять регулярные выражения. У меня это все прописано так: interface=re:^vlanPPPoE(\d+)$ в результате accel слушает все интерфейсы vlanPPPoE с любыми цифрами в конце.

 

Кстати, возможно что проьлема с 300 мегабитами из-за применения tbf в качестве шейпера. Попробуйте использовать policy и для down-limiter

 

Вас понял, может там вообще тогда все закомментировать?

Мы используем ipt-ratelimit

Share this post


Link to post
Share on other sites
18 часов назад, agent2011 сказал:

Вас понял, может там вообще тогда все закомментировать?

Прошу прощения за введение в заблуждение, shaper у вас уже закомментирован в [modules] и не задействован.

Share this post


Link to post
Share on other sites
9 часов назад, taf_321 сказал:

Прошу прощения за введение в заблуждение, shaper у вас уже закомментирован в [modules] и не задействован.

Да, а мы и сами не заметили :-)

 

в чем же может быть проблема у нас еще?

так скажем напрямую - все Ок, а с PPPoE соединением вот такое, хотя по исходящему как и писал выше - до 700 доходит.

Share this post


Link to post
Share on other sites

Я бы порекомендовал в секции [ppp] раскоментировать строки:


 

accomp=deny

pcomp=deny

ccp=0

это выключит любое сжатие.

 

И обязательно прописать

 

mppe=deny

 

Дело в том, что поведение по-умолчанию включает шифрование, когда клиент его поддерживает.

Share this post


Link to post
Share on other sites
19 часов назад, taf_321 сказал:

Я бы порекомендовал в секции [ppp] раскоментировать строки:


 


accomp=deny

pcomp=deny

ccp=0

это выключит любое сжатие.

 

И обязательно прописать

 


mppe=deny

 

Дело в том, что поведение по-умолчанию включает шифрование, когда клиент его поддерживает.

 

К сожалению безрезультатно(

 

Чтобы такого еще придумать, где поковыряться :-)

Share this post


Link to post
Share on other sites

@agent2011, вы же на разных устройствах тестируете? Попробуйте убрать все шейперы/лимитеры и добавить такие правила для теста
 

/sbin/tc qdisc del dev ppp0 root    > /dev/null
/sbin/tc qdisc del dev ppp0 ingress > /dev/null
/sbin/tc qdisc add dev ppp0 root handle 1: htb default 20 r2q 300
/sbin/tc class add dev ppp0 parent 1: classid 1:1 htb rate 9000mbit
/sbin/tc class add dev ppp0 parent 1:1 classid 1:10 htb rate 9000mbit prio 1
/sbin/tc class add dev ppp0 parent 1:1 classid 1:20 htb rate 9000mbit prio 2
/sbin/tc qdisc add dev ppp0 parent 1:10 handle 10: sfq perturb 10 quantum 1500
/sbin/tc qdisc add dev ppp0 parent 1:20 handle 20: sfq perturb 10 quantum 1500
/sbin/tc filter add dev ppp0 parent 1:0 protocol ip prio 10 u32 match ip tos 0x10 0xff flowid 1:10
/sbin/tc filter add dev ppp0 parent 1:0 protocol ip prio 10 u32 match ip protocol 1 0xff flowid 1:10

 

Share this post


Link to post
Share on other sites

Кто то выдает ipv6 адреса из [ipv6-pool] и при этом использует [ipoe]4-redirect-on-reject? Суть в том, что и эти гостевые сессии получают ipv6, набросали патчик в комьюнити чате предотвращающий выдачу ipv6 для этих клиентов, может кому будет полезен.

https://raw.githubusercontent.com/...redirect.diff

ps:// В патче еще решена проблема с ответом NAK на третий пакет DHCP Request с одинаковым xid (патч @theMIROn), не должно ломать балансировку при резервирование серверов.

Share this post


Link to post
Share on other sites

Коллеги, SSTP в этом софте работает вообще?

Сервер не предъявляет даже сертификат.

 

 

Share this post


Link to post
Share on other sites
В 09.07.2019 в 08:14, ShyLion сказал:

Коллеги, SSTP в этом софте работает вообще?

Сервер не предъявляет даже сертификат.

 

 

Работает, нужно будет гайд написать. Много информации в 

man accel-ppp.conf

 

Share this post


Link to post
Share on other sites
On 7/20/2019 at 1:17 AM, Dimka88 said:

Работает, нужно будет гайд написать. Много информации в 


man accel-ppp.conf

 

Спасибо, мне в телеграме помогли. Изнините что тут не отписался, запарился.

Всего-то нужно было в секции [SSTP] добавить "accept=ssl".

 

Share this post


Link to post
Share on other sites

Доброго времени суток. Есть такая проблема. Установил релиз 1.12.0, ядро 4.19.73. Использую PPTP. Если у клиента включен контроль сессии, то происходит следующая ситуация:

Клиент отправляет pptp echo request, но от сервера отправляется pptp echo reply с большой задержкой (она меняется 10сек и выше). В итоге клиент не дождавшись сообщения разрывает сессию.

Share this post


Link to post
Share on other sites

[pptp]
verbose=1
echo-interval=5
echo-failure=12
timeout=14
 

 

Share this post


Link to post
Share on other sites
On 9/27/2019 at 1:02 AM, user said:

[pptp]
verbose=1
echo-interval=5
echo-failure=12
timeout=14
 

 

Не помогло. Собственно проблема именно в том что механизм контроля сессий включен  у клиента. И клиент отправляет эхо запросы. Но accel отвечает на них с задержкой и она меняется, как итог настает момент когда задержка ответа превышает допустимый интервал у клиента (у меня было 60 сек) и клиент разрывает сессию. 

В общем, покрутил разные параметры у асселя, итог: если выключить в секции pptp механизм контроля сессий, и включить его в секции ppp, то ассель отвечает на эхо запросы от клиента вовремя.

Share this post


Link to post
Share on other sites

Именно эту проблему я так и решил.

Уменьшил до 5 сек echo-interval на стороне сервера

 

 

[ppp]
verbose=1
min-mtu=512
mtu=1410
mru=1410
ccp=0
check-ip=1
mppe=deny
ipv4=require
ipv6=deny
#ipv6-intf-id=0:0:0:1
#ipv6-peer-intf-id=0:0:0:2
#ipv6-accept-peer-intf-id=1
#lcp-echo-interval=5
#lcp-echo-failure=5
#lcp-echo-timeout=120
#unit-cache=1000
#single-session=replace
 

[pptp]
verbose=1
echo-interval=5
echo-failure=12
timeout=14
#echo-interval=0
#echo-failure=0
#timeout=30

 

 

Share this post


Link to post
Share on other sites

Господа, прошу помочь.
Ни как не могу завести accel-ppp. 


Схема, которую хочется получить:

Клиент формирует запрос на получение IP по DHCP, долетает всё то дело до accel-ppp, accel-ppp делает запрос lbarcd, lbarcd смотрит в базу, делает вывод и отдаёт в accel-ppp данные с ип, маской и.т.д. = Клиент в сети.

На данный момент, насколько я понимаю, несколько проблем:

почему, не знаю, LB не может определить принадлежность макаки 18:a9:05:cb:c3:0a пользователю.

и опять же, LB не может определить pool ip адресов.

 

 

Что есть:

 

Цитата

Centos6.10
Linux srv-h-1 3.16.74-ricos #1 SMP Thu Sep 26 19:00:40 +05 2019 x86_64 x86_64 x86_64 GNU/Linux

 

Цитата

lbinet-2.0.24.0-10.git829eef2.x86_64

lbweb-admin-3.0.10-1.201712260738git021d8dfa98a5b594589dab16fb0c870294835ef6.el7.centos.noarch
lbsnmpcd-2.0.24.0-4.gitafc6816.x86_64
lbcore-2.0.24.0-58.gitb967ce8f.x86_64
lbarcd-2.0.24.0-24.git8d5db3f.x86_64
lbweb-common-2.19.0-1.201810191545gita7e5992e321d.noarch

Коммутатор 

Цитата

DES-3200-10:5#show config current_config inclu "dhcp_lo"
Command: show config current_config include "dhcp_lo"
enable dhcp_local_relay
config dhcp_local_relay option_82 circuit_id default
config dhcp_local_relay option_82 remote_id default
config dhcp_local_relay vlan vlanid 111 state enable
config dhcp_local_relay option_82 ports 1-2,4-10 policy keep
config dhcp_local_relay option_82 ports 3 policy replace

accel-ppp.conf

 

Скрытый текст

[ipoe]
mode=L2
shared=1

gw-ip-address=10.11.0.1/24
gw-ip-address=10.12.0.1/24
ip-unnumbered=1
interface=eth0.111,ifcfg=1,start=dhcpv4,range=10.11.0.0/24
#range=10.11.0.0/24
username=lua:u1
lease-time=300
renew-time=150
max-lease-time=600
password=accel-ppp.
#username
lua-file=/etc/accel-ppp.lua
verbose=99
attr-dhcp-client-ip=Framed-IP-Address
attr-dhcp-router-ip=DHCP-Router-IP-Address
attr-dhcp-mask=Framed-IP-Netmask
#attr-dhcp-opt82=DHCP-Option82
attr-dhcp-opt82-remote-id=DHCP-Agent-Remote-Id
attr-dhcp-opt82-circuit-id=DHCP-Agent-Circuit-Id
#vlan-mon=eth0.111
calling-sid=mac
proxy-arp=1
#attr-l4-redirect=L4-Redirect
#attr-l4-redirect-ipset=L4-Redirect-ipset

[ip-pool]
verbose=1
shuffle=1
attr=Framed-Pool
gw-ip-address=10.11.0.1
10.11.0.2-254,name=ipoe-1-local
 

[radius]
dictionary=/usr/share/accel-ppp/radius/dictionary
nas-identifier=accel-ppp
nas-ip-address=192.168.200.200
server=192.168.200.200,lbtest,auth-port=1812,acct-port=1813,req-limit=0,fail-time=0
dae-server=192.168.200.200:3799,lbtest
verbose=100
timeout=3
max-try=3
acct-timeout=0
acct-on=1
acct-interim-interval=60
acct-delay-time=0
sid-in-auth=1
attr-tunnel-type=NAS-Identifier


Логи с lbarcd

Скрытый текст

02.10.2019 22:18:00.152380 VERBOSE  LWP2507 [src/radius.cpp:1682] Flushing accounting cache
02.10.2019 22:18:02.782228 VERBOSE  LWP2510 [src/radius_base.cpp:56] ============== Authorization packet #18 received from 192.168.200.200, size: 184 ==============
02.10.2019 22:18:02.782261 VERBOSE  LWP2510 [src/radius_record.cpp:90] Access-Request (1), id: 0x1, length: 184
02.10.2019 22:18:02.782274 VERBOSE  LWP2510 [src/radius_record.cpp:108] Authenticator: 28b55b3efd653092788810d112ff155b
02.10.2019 22:18:02.782285 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
02.10.2019 22:18:02.782294 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
02.10.2019 22:18:02.782302 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
02.10.2019 22:18:02.782309 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "493"
02.10.2019 22:18:02.782316 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
02.10.2019 22:18:02.782323 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
02.10.2019 22:18:02.782329 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
02.10.2019 22:18:02.782335 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
02.10.2019 22:18:02.782342 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "ipoe"
02.10.2019 22:18:02.782352 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "DHCP-Option82", value: "01060004006f000302080006340804644984"
02.10.2019 22:18:02.782359 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "DHCP-Agent-Remote-Id", value: "0006340804644984"
02.10.2019 22:18:02.782366 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "DHCP-Agent-Circuit-Id", value: "0004006f0003"
02.10.2019 22:18:02.782373 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac49ce7d"
02.10.2019 22:18:02.782381 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "Password", value: "80416f78d0643f57811f81f522327005"
02.10.2019 22:18:02.782388 VERBOSE  LWP2510 [src/radius_record.cpp:186] User-Password = "accel-ppp"
02.10.2019 22:18:02.782398 VERBOSE  LWP2510 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
02.10.2019 22:18:02.782449 INFO     LWP2510 [src/radius_packet.cpp:914] All auth tries failed: methods='opt82', Session-Id 774dcff2ac49ce7d
02.10.2019 22:18:02.783412 WARNING  LWP2510 [src/radius.cpp:2136] No more free guest IP addresses for user 18:a9:05:cb:c3:0a
02.10.2019 22:18:02.783433 WARNING  LWP2510 [src/radius.cpp:536] Unable to allocate delegated prefix
02.10.2019 22:18:02.783438 WARNING  LWP2510 [src/radius.cpp:538] Unable to allocate framed prefix
02.10.2019 22:18:02.783453 INFO     LWP2510 [src/radius.cpp:591] Access-Reject, <18:a9:05:cb:c3:0a>, Session-Id 774dcff2ac49ce7d, {no free IP}
02.10.2019 22:18:02.783471 VERBOSE  LWP2510 [src/radius_record.cpp:90] Access-Reject (3), id: 0x1, length: 20
02.10.2019 22:18:02.783509 VERBOSE  LWP2510 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Authentication answer sent successfully, time: 1 <=<=<=<=<=<=<=<
02.10.2019 22:18:09.657016 VERBOSE  LWP2510 [src/radius_base.cpp:56] ============== Authorization packet #19 received from 192.168.200.200, size: 184 ==============
02.10.2019 22:18:09.657052 VERBOSE  LWP2510 [src/radius_record.cpp:90] Access-Request (1), id: 0x1, length: 184
02.10.2019 22:18:09.657065 VERBOSE  LWP2510 [src/radius_record.cpp:108] Authenticator: 427b046041e354e18d21eb43347de3f9
02.10.2019 22:18:09.657077 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
02.10.2019 22:18:09.657087 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
02.10.2019 22:18:09.657095 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
02.10.2019 22:18:09.657103 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "494"
02.10.2019 22:18:09.657110 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
02.10.2019 22:18:09.657130 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
02.10.2019 22:18:09.657139 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
02.10.2019 22:18:09.657154 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
02.10.2019 22:18:09.657161 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "ipoe"
02.10.2019 22:18:09.657170 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "DHCP-Option82", value: "01060004006f000302080006340804644984"
02.10.2019 22:18:09.657178 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "DHCP-Agent-Remote-Id", value: "0006340804644984"
02.10.2019 22:18:09.657186 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "DHCP-Agent-Circuit-Id", value: "0004006f0003"
02.10.2019 22:18:09.657192 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac49ce7e"
02.10.2019 22:18:09.657200 VERBOSE  LWP2510 [src/radius_record.cpp:113] Attribute "Password", value: "9177cee68da7f9141ce9a653e7f8bdca"
02.10.2019 22:18:09.657208 VERBOSE  LWP2510 [src/radius_record.cpp:186] User-Password = "accel-ppp"
02.10.2019 22:18:09.657217 VERBOSE  LWP2510 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
02.10.2019 22:18:09.657269 INFO     LWP2510 [src/radius_packet.cpp:914] All auth tries failed: methods='opt82', Session-Id 774dcff2ac49ce7e
02.10.2019 22:18:09.658193 WARNING  LWP2510 [src/radius.cpp:2136] No more free guest IP addresses for user 18:a9:05:cb:c3:0a
02.10.2019 22:18:09.658213 WARNING  LWP2510 [src/radius.cpp:536] Unable to allocate delegated prefix
02.10.2019 22:18:09.658218 WARNING  LWP2510 [src/radius.cpp:538] Unable to allocate framed prefix
02.10.2019 22:18:09.658233 INFO     LWP2510 [src/radius.cpp:591] Access-Reject, <18:a9:05:cb:c3:0a>, Session-Id 774dcff2ac49ce7e, {no free IP}
02.10.2019 22:18:09.658249 VERBOSE  LWP2510 [src/radius_record.cpp:90] Access-Reject (3), id: 0x1, length: 20
02.10.2019 22:18:09.658289 VERBOSE  LWP2510 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Authentication answer sent successfully, time: 1 <=<=<=<=<=<=<=<
 

С Lbinetd

Скрытый текст

02.10.2019 22:17:51.924042 [0x0000fffff700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
02.10.2019 22:17:51.924054 [0x0000fffff700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
02.10.2019 22:18:01.924149 [0x0000fffff700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
02.10.2019 22:18:01.924161 [0x0000fffff700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
02.10.2019 22:18:02.780440 [0x000007fff700] DEBUG    recv_udp_sock:91: IP_PKTINFO control data found
02.10.2019 22:18:02.780452 [0x000007fff700] DEBUG    iface_by_index:108: lookup interface by index 5
02.10.2019 22:18:02.780466 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 53/0x35, type: 2, len: 01, value: u8 01 / 0x01
02.10.2019 22:18:02.780481 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 61/0x3D, type: 5, len: 07, value: HEX: 01 18 A9 05 CB C3 0A ASCII '.......' HEX:01 18 a9 05 cb c3 0a  proto unassigned
02.10.2019 22:18:02.780487 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 12/0x0C, type: 1, len: 13, value: 'Administrator'
02.10.2019 22:18:02.780492 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 60/0x3C, type: 1, len: 08, value: 'MSFT 5.0'
02.10.2019 22:18:02.780503 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 55/0x37, type: 5, len: 12, value: HEX: 01 0F 03 06 2C 2E 2F 1F 21 79 F9 2B ReqOp: 1 15 3 6 44 46 47 31 33 121 -7 43
02.10.2019 22:18:02.780516 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 82/0x52, type: 5, len: 18, value: HEX: 01 06 00 04 00 6F 00 03 02 08 00 06 34 08 04 64 49 84 ASCII '.....o......4..dI.'
02.10.2019 22:18:02.780527 [0x000007fff700] DEBUG    Dump DHCP packet: op=1, htype=1, hlen=6, hops=0x00, xid=0x7f93a40f, secs=3, flags=0x0000, ciaddr=0.0.0.0, yiaddr=0.0.0.0, siaddr=0.0.0.0, giaddr=0.0.0.0, chaddr=18:a9:, sname=, filenam
02.10.2019 22:18:02.780541 [0x000007fff700] DEBUG    dhcp_op82_parse:466: Op82 Circuit ID 006F0003:VLAN 111, Unit 0, Port 3 (l=4), Remote ID 340804644984 (l=6)
02.10.2019 22:18:02.780547 [0x000007fff700] ERROR    dhcp_allocate_ip:4680: DHCP IP pool uninitialized lookup!
02.10.2019 22:18:02.780552 [0x000007fff700] DEBUG    dhcp_msg_dispatch:108: DHCPDISCOVER from unknown client HW MAC 18:a9:05:cb:c3:0a
02.10.2019 22:18:02.780556 [0x000007fff700] DEBUG    dhcp_main:298: dhcp_msg_dispatch(0x7f16f80009f0) failed
02.10.2019 22:18:02.780563 [0x000007fff700] DEBUG    dhcp_main:290: waiting for req
02.10.2019 22:18:09.655352 [0x000007fff700] DEBUG    recv_udp_sock:91: IP_PKTINFO control data found
02.10.2019 22:18:09.655364 [0x000007fff700] DEBUG    iface_by_index:108: lookup interface by index 5
02.10.2019 22:18:09.655379 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 53/0x35, type: 2, len: 01, value: u8 01 / 0x01
02.10.2019 22:18:09.655394 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 61/0x3D, type: 5, len: 07, value: HEX: 01 18 A9 05 CB C3 0A ASCII '.......' HEX:01 18 a9 05 cb c3 0a  proto unassigned
02.10.2019 22:18:09.655400 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 12/0x0C, type: 1, len: 13, value: 'Administrator'
02.10.2019 22:18:09.655405 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 60/0x3C, type: 1, len: 08, value: 'MSFT 5.0'
02.10.2019 22:18:09.655416 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 55/0x37, type: 5, len: 12, value: HEX: 01 0F 03 06 2C 2E 2F 1F 21 79 F9 2B ReqOp: 1 15 3 6 44 46 47 31 33 121 -7 43
02.10.2019 22:18:09.655429 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 82/0x52, type: 5, len: 18, value: HEX: 01 06 00 04 00 6F 00 03 02 08 00 06 34 08 04 64 49 84 ASCII '.....o......4..dI.'
02.10.2019 22:18:09.655440 [0x000007fff700] DEBUG    Dump DHCP packet: op=1, htype=1, hlen=6, hops=0x00, xid=0x7f93a40f, secs=10, flags=0x0000, ciaddr=0.0.0.0, yiaddr=0.0.0.0, siaddr=0.0.0.0, giaddr=0.0.0.0, chaddr=18:a9:, sname=, filena
02.10.2019 22:18:09.655455 [0x000007fff700] DEBUG    dhcp_op82_parse:466: Op82 Circuit ID 006F0003:VLAN 111, Unit 0, Port 3 (l=4), Remote ID 340804644984 (l=6)
02.10.2019 22:18:09.655462 [0x000007fff700] ERROR    dhcp_allocate_ip:4680: DHCP IP pool uninitialized lookup!
02.10.2019 22:18:09.655466 [0x000007fff700] DEBUG    dhcp_msg_dispatch:108: DHCPDISCOVER from unknown client HW MAC 18:a9:05:cb:c3:0a
02.10.2019 22:18:09.655470 [0x000007fff700] DEBUG    dhcp_main:298: dhcp_msg_dispatch(0x7f16f80009f0) failed
02.10.2019 22:18:09.655477 [0x000007fff700] DEBUG    dhcp_main:290: waiting for req
02.10.2019 22:18:11.924256 [0x0000fffff700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
02.10.2019 22:18:11.924269 [0x0000fffff700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
02.10.2019 22:18:21.924375 [0x0000fffff700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
02.10.2019 22:18:21.924387 [0x0000fffff700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
02.10.2019 22:18:25.874038 [0x000007fff700] DEBUG    recv_udp_sock:91: IP_PKTINFO control data found
02.10.2019 22:18:25.874051 [0x000007fff700] DEBUG    iface_by_index:108: lookup interface by index 5
02.10.2019 22:18:25.874066 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 53/0x35, type: 2, len: 01, value: u8 01 / 0x01
02.10.2019 22:18:25.874081 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 61/0x3D, type: 5, len: 07, value: HEX: 01 18 A9 05 CB C3 0A ASCII '.......' HEX:01 18 a9 05 cb c3 0a  proto unassigned
02.10.2019 22:18:25.874087 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 12/0x0C, type: 1, len: 13, value: 'Administrator'
02.10.2019 22:18:25.874092 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 60/0x3C, type: 1, len: 08, value: 'MSFT 5.0'
02.10.2019 22:18:25.874104 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 55/0x37, type: 5, len: 12, value: HEX: 01 0F 03 06 2C 2E 2F 1F 21 79 F9 2B ReqOp: 1 15 3 6 44 46 47 31 33 121 -7 43
02.10.2019 22:18:25.874131 [0x000007fff700] DEBUG    dhcp_option_print:233: DHCP Op: 82/0x52, type: 5, len: 18, value: HEX: 01 06 00 04 00 6F 00 03 02 08 00 06 34 08 04 64 49 84 ASCII '.....o......4..dI.'
02.10.2019 22:18:25.874144 [0x000007fff700] DEBUG    Dump DHCP packet: op=1, htype=1, hlen=6, hops=0x00, xid=0x7f93a40f, secs=26, flags=0x0000, ciaddr=0.0.0.0, yiaddr=0.0.0.0, siaddr=0.0.0.0, giaddr=0.0.0.0, chaddr=18:a9:, sname=, filena
02.10.2019 22:18:25.874166 [0x000007fff700] DEBUG    dhcp_op82_parse:466: Op82 Circuit ID 006F0003:VLAN 111, Unit 0, Port 3 (l=4), Remote ID 340804644984 (l=6)
02.10.2019 22:18:25.874173 [0x000007fff700] ERROR    dhcp_allocate_ip:4680: DHCP IP pool uninitialized lookup!
02.10.2019 22:18:25.874178 [0x000007fff700] DEBUG    dhcp_msg_dispatch:108: DHCPDISCOVER from unknown client HW MAC 18:a9:05:cb:c3:0a
02.10.2019 22:18:25.874182 [0x000007fff700] DEBUG    dhcp_main:298: dhcp_msg_dispatch(0x7f16f80009f0) failed
02.10.2019 22:18:25.874188 [0x000007fff700] DEBUG    dhcp_main:290: waiting for req
02.10.2019 22:18:31.924481 [0x0000fffff700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
02.10.2019 22:18:31.924493 [0x0000fffff700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
02.10.2019 22:18:41.924589 [0x0000fffff700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
02.10.2019 22:18:41.924600 [0x0000fffff700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
02.10.2019 22:18:51.924706 [0x0000fffff700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
02.10.2019 22:18:51.924718 [0x0000fffff700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
02.10.2019 22:19:01.924825 [0x0000fffff700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
02.10.2019 22:19:01.924838 [0x0000fffff700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
02.10.2019 22:19:05.962696 [0x000006ffd700] DEBUG    json_process_notification:324: change_vgroup from JSON: { "id": 1, "method": "notify", "params": { "message": "change_vgroup", "params": { "vg_id": 1 } } }
02.10.2019 22:19:05.966573 [0x000006ffd700] DEBUG    dhcp_update_vg_id:194: request: "{"method": "Lbinet_getVgroups", "params": {"vg_id": 1, "agent_id": 9}} " @ js_data vg_id=1
02.10.2019 22:19:05.967732 [0x000006ffd700] DEBUG    received updated vg_id=1 resp ("{ "result": [  ] } ")
 

accel-ppp.log

Скрытый текст

[1;32m[2019-10-03 19:44:06]:  info: eth0.111: .[0;39mrecv [DHCPv4 Discover xid=28d958d4 chaddr=18:a9:05:cb:c3:0a <Message-Type Discover> <Client-ID 0118a905cbc30a> <Host-Name Administrator> <Vendo
.[1;32m[2019-10-03 19:44:06]:  info: ipoe0: .[0;39mcreate interface ipoe0 parent eth0.111
.[1;32m[2019-10-03 19:44:06]:  info: ipoe0: .[0;39msend [RADIUS(1) Access-Request id=1 <User-Name "18:a9:05:cb:c3:0a"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 192.168.200.200> <NAS-Port 520> <
.[1;32m[2019-10-03 19:44:06]:  info: ipoe0: .[0;39mrecv [RADIUS(1) Access-Reject id=1]
.[1;34m[2019-10-03 19:44:06]: debug: ipoe0: .[0;39mterminate
.[1;32m[2019-10-03 19:44:06]:  info: ipoe0: .[0;39mipoe: session finished
 

 

Share this post


Link to post
Share on other sites

Дело сдвинулось, изменил атрибуты opt82 на
 

Цитата

attr-dhcp-opt82-remote-id=Redback-Agent-Remote-Id
attr-dhcp-opt82-circuit-id=Redback-Agent-Circuit-Id

Прописал в словаре и

 

ИТОГ:

 

cat lbarcd_mikrotik.log

Скрытый текст


03.10.2019 21:00:07.025523 VERBOSE  LWP7890 [src/radius_base.cpp:56] ============== Authorization packet #16 received from 192.168.200.200, size: 174 ==============
03.10.2019 21:00:07.025728 VERBOSE  LWP7890 [src/radius_record.cpp:90] Access-Request (1), id: 0x1, length: 174
03.10.2019 21:00:07.025750 VERBOSE  LWP7890 [src/radius_record.cpp:108] Authenticator: 400ffe78d0f24051c223af7fb714f6c7
03.10.2019 21:00:07.025762 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 21:00:07.025772 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 21:00:07.025780 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 21:00:07.025789 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "543"
03.10.2019 21:00:07.025796 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 21:00:07.025803 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 21:00:07.025810 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 21:00:07.025817 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 21:00:07.025826 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 21:00:07.025839 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 21:00:07.025848 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac49f5bf"
03.10.2019 21:00:07.025864 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Password", value: "ae30be2209aff974d6d36da9b13fe3934db05901e70be38986b18090e26bd3f2"
03.10.2019 21:00:07.025876 VERBOSE  LWP7890 [src/radius_record.cpp:186] User-Password = "18:a9:05:cb:c3:0a"
03.10.2019 21:00:07.025887 VERBOSE  LWP7890 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 21:00:07.025967 VERBOSE  LWP7890 [src/radius_packet.cpp:652] Trying to authorize user by Opt82 params: "340804644984", 3
03.10.2019 21:00:07.026638 INFO     LWP7890 [src/radius_packet.cpp:896] Auth by 'opt82' ok: [1], Session-Id 774dcff2ac49f5bf
03.10.2019 21:00:07.028280 VERBOSE  LWP7890 [src/radius_packet.cpp:1163] ANI '18:a9:05:cb:c3:0a' (User '18:a9:05:cb:c3:0a') is clean
03.10.2019 21:00:07.028570 VERBOSE  LWP7890 [src/radius_packet.cpp:1074] User: '1234', bill by traffic, unlimited session timeout (86400)
03.10.2019 21:00:07.030742 WARNING  LWP7890 [src/radius.cpp:2062] ANI '18:a9:05:cb:c3:0a' is bound to 10.11.0.8/255.255.255.255 for user '18:a9:05:cb:c3:0a'
03.10.2019 21:00:07.030766 VERBOSE  LWP7890 [src/radius.cpp:2144] Client IP/Netmask: 10.11.0.8/255.255.255.255, ANI: "18:a9:05:cb:c3:0a"
03.10.2019 21:00:07.030774 WARNING  LWP7890 [src/radius.cpp:536] Unable to allocate delegated prefix
03.10.2019 21:00:07.030779 WARNING  LWP7890 [src/radius.cpp:538] Unable to allocate framed prefix
03.10.2019 21:00:07.030800 INFO     LWP7890 [src/radius.cpp:609] Access-Accept, <18:a9:05:cb:c3:0a> [1], Session-Id 774dcff2ac49f5bf
03.10.2019 21:00:07.030807 VERBOSE  LWP7890 [src/radius.cpp:612] =============== Output attributes dump: ===============
03.10.2019 21:00:07.030820 VERBOSE  LWP7890 [src/radius.cpp:706] Attribute "Session-Timeout", value: "86400"
03.10.2019 21:00:07.030828 VERBOSE  LWP7890 [src/radius.cpp:711] Attribute "Service-Type", value: "2"
03.10.2019 21:00:07.030836 VERBOSE  LWP7890 [src/radius.cpp:715] Attribute "Framed-Protocol", value: "1"
03.10.2019 21:00:07.030845 VERBOSE  LWP7890 [src/radius.cpp:726] Attribute "Framed-IP-Address", value: "10.11.0.8"
03.10.2019 21:00:07.030854 VERBOSE  LWP7890 [src/radius.cpp:746] Attribute "Framed-IP-Netmask", value: "255.255.255.255"
03.10.2019 21:00:07.032002 VERBOSE  LWP7890 [src/radius.cpp:756] Attribute "Class", tag: "174", value: "45b930d6-a255-4fae-a696-1e150d467aae/1"
03.10.2019 21:00:07.032058 VERBOSE  LWP7890 [src/radius.cpp:799] Attribute "Acct-Interim-Interval", value: "60"
03.10.2019 21:00:07.032075 VERBOSE  LWP7890 [src/radius.cpp:820] INSERT INTO CACHE NAS_IP_ADDRESS: 192.168.200.200; UUID(session_id): 774dcff2ac49f5bf
03.10.2019 21:00:07.035420 WARNING  LWP7890 [src/radius.cpp:879] Packet received from MAC = '18:a9:05:cb:c3:0a' while another MAC = '18:a9:05:cb:c3:0a' is assigned to vg_id = 1
03.10.2019 21:00:07.035455 VERBOSE  LWP7890 [src/radius_record.cpp:90] Access-Accept (2), id: 0x1, length: 96
03.10.2019 21:00:07.035499 VERBOSE  LWP7890 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Authentication answer sent successfully, time: 9 <=<=<=<=<=<=<=<
03.10.2019 21:00:15.025670 VERBOSE  LWP7890 [src/radius_base.cpp:56] ============== Authorization packet #17 received from 192.168.200.200, size: 174 ==============
03.10.2019 21:00:15.025703 VERBOSE  LWP7890 [src/radius_record.cpp:90] Access-Request (1), id: 0x1, length: 174
03.10.2019 21:00:15.025715 VERBOSE  LWP7890 [src/radius_record.cpp:108] Authenticator: 185d3620ca9eaa71868eb66a511e35d0
03.10.2019 21:00:15.025726 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 21:00:15.025734 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 21:00:15.025742 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 21:00:15.025749 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "544"
03.10.2019 21:00:15.025756 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 21:00:15.025762 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 21:00:15.025768 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 21:00:15.025774 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 21:00:15.025783 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 21:00:15.025793 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 21:00:15.025800 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac49f5c0"
03.10.2019 21:00:15.025812 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Password", value: "d55d0630f64df275880a6b2170c07dd87121e66619dc9072e4ffb6d5753437ae"
03.10.2019 21:00:15.025820 VERBOSE  LWP7890 [src/radius_record.cpp:186] User-Password = "18:a9:05:cb:c3:0a"
03.10.2019 21:00:15.025829 VERBOSE  LWP7890 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 21:00:15.025879 VERBOSE  LWP7890 [src/radius_packet.cpp:652] Trying to authorize user by Opt82 params: "340804644984", 3
03.10.2019 21:00:15.026478 INFO     LWP7890 [src/radius_packet.cpp:896] Auth by 'opt82' ok: [1], Session-Id 774dcff2ac49f5c0
03.10.2019 21:00:15.027790 VERBOSE  LWP7890 [src/radius_packet.cpp:1163] ANI '18:a9:05:cb:c3:0a' (User '18:a9:05:cb:c3:0a') is clean
03.10.2019 21:00:15.028050 VERBOSE  LWP7890 [src/radius_packet.cpp:1074] User: '1234', bill by traffic, unlimited session timeout (86400)
03.10.2019 21:00:15.029125 VERBOSE  LWP7890 [src/radius.cpp:920] Found existing session: 774dcff2ac49f5bf
03.10.2019 21:00:15.029609 VERBOSE  LWP7890 [src/radius.cpp:931] login '18:a9:05:cb:c3:0a' is already connected (1 sessions >= 1 max)
03.10.2019 21:00:15.029651 INFO     LWP7890 [src/radius.cpp:591] Access-Reject, <18:a9:05:cb:c3:0a> [1], Session-Id 774dcff2ac49f5c0, {already connected}
03.10.2019 21:00:15.030225 WARNING  LWP7890 [src/radius.cpp:879] Packet received from MAC = '18:a9:05:cb:c3:0a' while another MAC = '18:a9:05:cb:c3:0a' is assigned to vg_id = 1
03.10.2019 21:00:15.030255 VERBOSE  LWP7890 [src/radius_record.cpp:90] Access-Reject (3), id: 0x1, length: 20
03.10.2019 21:00:15.030297 VERBOSE  LWP7890 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Authentication answer sent successfully, time: 4 <=<=<=<=<=<=<=<
03.10.2019 21:00:30.011880 VERBOSE  LWP7885 [src/radius.cpp:1682] Flushing accounting cache
03.10.2019 21:00:32.026212 VERBOSE  LWP7890 [src/radius_base.cpp:56] ============== Authorization packet #18 received from 192.168.200.200, size: 174 ==============
03.10.2019 21:00:32.026246 VERBOSE  LWP7890 [src/radius_record.cpp:90] Access-Request (1), id: 0x1, length: 174
03.10.2019 21:00:32.026260 VERBOSE  LWP7890 [src/radius_record.cpp:108] Authenticator: cce6be2ddad59a2671280d3082e9dd38
03.10.2019 21:00:32.026271 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 21:00:32.026281 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 21:00:32.026289 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 21:00:32.026297 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "545"
03.10.2019 21:00:32.026305 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 21:00:32.026312 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 21:00:32.026319 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 21:00:32.026326 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 21:00:32.026335 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 21:00:32.026346 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 21:00:32.026354 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac49f5c1"
03.10.2019 21:00:32.026366 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Password", value: "12c37a8e52f89c3dcc7c5f16e3243ef169f26de8cfca2b327b586e26374078dc"
03.10.2019 21:00:32.026375 VERBOSE  LWP7890 [src/radius_record.cpp:186] User-Password = "18:a9:05:cb:c3:0a"
03.10.2019 21:00:32.026385 VERBOSE  LWP7890 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 21:00:32.026440 VERBOSE  LWP7890 [src/radius_packet.cpp:652] Trying to authorize user by Opt82 params: "340804644984", 3
03.10.2019 21:00:32.027033 INFO     LWP7890 [src/radius_packet.cpp:896] Auth by 'opt82' ok: [1], Session-Id 774dcff2ac49f5c1
03.10.2019 21:00:32.028409 VERBOSE  LWP7890 [src/radius_packet.cpp:1163] ANI '18:a9:05:cb:c3:0a' (User '18:a9:05:cb:c3:0a') is clean
03.10.2019 21:00:32.028689 VERBOSE  LWP7890 [src/radius_packet.cpp:1074] User: '1234', bill by traffic, unlimited session timeout (86400)
03.10.2019 21:00:32.029853 VERBOSE  LWP7890 [src/radius.cpp:920] Found existing session: 774dcff2ac49f5bf
03.10.2019 21:00:32.030580 VERBOSE  LWP7890 [src/radius.cpp:931] login '18:a9:05:cb:c3:0a' is already connected (1 sessions >= 1 max)
03.10.2019 21:00:32.030624 INFO     LWP7890 [src/radius.cpp:591] Access-Reject, <18:a9:05:cb:c3:0a> [1], Session-Id 774dcff2ac49f5c1, {already connected}
03.10.2019 21:00:32.031188 WARNING  LWP7890 [src/radius.cpp:879] Packet received from MAC = '18:a9:05:cb:c3:0a' while another MAC = '18:a9:05:cb:c3:0a' is assigned to vg_id = 1
03.10.2019 21:00:32.031219 VERBOSE  LWP7890 [src/radius_record.cpp:90] Access-Reject (3), id: 0x1, length: 20
03.10.2019 21:00:32.031262 VERBOSE  LWP7890 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Authentication answer sent successfully, time: 5 <=<=<=<=<=<=<=<
03.10.2019 21:01:04.285104 VERBOSE  LWP7890 [src/radius_base.cpp:56] ============== Authorization packet #19 received from 192.168.200.200, size: 174 ==============
03.10.2019 21:01:04.285195 VERBOSE  LWP7890 [src/radius_record.cpp:90] Access-Request (1), id: 0x1, length: 174
03.10.2019 21:01:04.285212 VERBOSE  LWP7890 [src/radius_record.cpp:108] Authenticator: f631e7cdd06e5eba5fe23b6724d532bf
03.10.2019 21:01:04.285223 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 21:01:04.285232 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 21:01:04.285240 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 21:01:04.285248 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "546"
03.10.2019 21:01:04.285255 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 21:01:04.285262 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 21:01:04.285269 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 21:01:04.285275 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 21:01:04.285284 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 21:01:04.285295 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 21:01:04.285303 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac49f5c2"
03.10.2019 21:01:04.285315 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Password", value: "d8e438fb32710e0be0d1f35359ee1a7eafb7c1c6bb9afc1486e7704f2aeab838"
03.10.2019 21:01:04.285324 VERBOSE  LWP7890 [src/radius_record.cpp:186] User-Password = "18:a9:05:cb:c3:0a"
03.10.2019 21:01:04.285334 VERBOSE  LWP7890 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 21:01:04.285391 VERBOSE  LWP7890 [src/radius_packet.cpp:652] Trying to authorize user by Opt82 params: "340804644984", 3
03.10.2019 21:01:04.285971 INFO     LWP7890 [src/radius_packet.cpp:896] Auth by 'opt82' ok: [1], Session-Id 774dcff2ac49f5c2
03.10.2019 21:01:04.287429 VERBOSE  LWP7890 [src/radius_packet.cpp:1163] ANI '18:a9:05:cb:c3:0a' (User '18:a9:05:cb:c3:0a') is clean
03.10.2019 21:01:04.287697 VERBOSE  LWP7890 [src/radius_packet.cpp:1074] User: '1234', bill by traffic, unlimited session timeout (86400)
03.10.2019 21:01:04.288838 VERBOSE  LWP7890 [src/radius.cpp:920] Found existing session: 774dcff2ac49f5bf
03.10.2019 21:01:04.289330 VERBOSE  LWP7890 [src/radius.cpp:931] login '18:a9:05:cb:c3:0a' is already connected (1 sessions >= 1 max)
03.10.2019 21:01:04.289372 INFO     LWP7890 [src/radius.cpp:591] Access-Reject, <18:a9:05:cb:c3:0a> [1], Session-Id 774dcff2ac49f5c2, {already connected}
03.10.2019 21:01:04.289991 WARNING  LWP7890 [src/radius.cpp:879] Packet received from MAC = '18:a9:05:cb:c3:0a' while another MAC = '18:a9:05:cb:c3:0a' is assigned to vg_id = 1
03.10.2019 21:01:04.290022 VERBOSE  LWP7890 [src/radius_record.cpp:90] Access-Reject (3), id: 0x1, length: 20
03.10.2019 21:01:04.290071 VERBOSE  LWP7890 [src/radius_base.cpp:92] <=<=<=<=<=<=<=< Authentication answer sent successfully, time: 4 <=<=<=<=<=<=<=<
03.10.2019 21:01:07.358113 VERBOSE  LWP7890 [src/radius_base.cpp:56] ============== Authorization packet #20 received from 192.168.200.200, size: 174 ==============
03.10.2019 21:01:07.358148 VERBOSE  LWP7890 [src/radius_record.cpp:90] Access-Request (1), id: 0x1, length: 174
03.10.2019 21:01:07.358162 VERBOSE  LWP7890 [src/radius_record.cpp:108] Authenticator: 1adbc6dd368bf45c3a8720cdee48922a
03.10.2019 21:01:07.358173 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "User-Name", value: "18:a9:05:cb:c3:0a"
03.10.2019 21:01:07.358182 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Identifier", value: "accel-ppp"
03.10.2019 21:01:07.358190 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-IP-Address", value: "192.168.200.200"
03.10.2019 21:01:07.358207 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port", value: "547"
03.10.2019 21:01:07.358214 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port-Id", value: "ipoe0"
03.10.2019 21:01:07.358221 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "NAS-Port-Type", value: "15"
03.10.2019 21:01:07.358228 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Calling-Station-Id", value: "18:a9:05:cb:c3:0a"
03.10.2019 21:01:07.358235 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Called-Station-Id", value: "eth0.111"
03.10.2019 21:01:07.358244 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Redback-Agent-Remote-Id", value: "0006340804644984"
03.10.2019 21:01:07.358256 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Redback-Agent-Circuit-Id", value: "0004006f0003"
03.10.2019 21:01:07.358264 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Acct-Session-Id", value: "774dcff2ac49f5c3"
03.10.2019 21:01:07.358277 VERBOSE  LWP7890 [src/radius_record.cpp:113] Attribute "Password", value: "2d84f1ddd0e437dc90a2703e0d25e2beb031cc557f5b76b2a026c73628fd2d5f"
03.10.2019 21:01:07.358286 VERBOSE  LWP7890 [src/radius_record.cpp:186] User-Password = "18:a9:05:cb:c3:0a"
03.10.2019 21:01:07.358295 VERBOSE  LWP7890 [src/radius_packet.cpp:75] NAS IP replaced to 192.168.200.200 from NAS-Ip-Address
03.10.2019 21:01:07.358350 VERBOSE  LWP7890 [src/radius_packet.cpp:652] Trying to authorize user by Opt82 params: "340804644984", 3
03.10.2019 21:01:07.358985 INFO     LWP7890 [src/radius_packet.cpp:896] Auth by 'opt82' ok: [1], Session-Id 774dcff2ac49f5c3
03.10.2019 21:01:07.360347 VERBOSE  LWP7890 [src/radius_packet.cpp:1163] ANI '18:a9:05:cb:c3:0a' (User '18:a9:05:cb:c3:0a') is clean
03.10.2019 21:01:07.360633 VERBOSE  LWP7890 [src/radius_packet.cpp:1074] User: '1234', bill by traffic, unlimited session timeout (86400)
03.10.2019 21:01:07.361805 VERBOSE  LWP7890 [src/radius.cpp:920] Found existing session: 774dcff2ac49f5bf
03.10.2019 21:01:07.362293 VERBOSE  LWP7890 [src/radius.cpp:931] login '18:a9:05:cb:c3:0a' is already connected (1 sessions >= 1 max)
03.10.2019 21:01:07.362338 INFO     LWP7890 [src/radius.cpp:591] Access-Reject, <18:a9:05:cb:c3:0a> [1], Session-Id 774dcff2ac49f5c3, {already connected}
03.10.2019 21:01:07.363225 WARNING  LWP7890 [src/radius.cpp:879] Packet received from MAC = '18:a9:05:cb:c3:0a' while another MAC = '18:a9:05:cb:c3:0a' is assigned to vg_id = 1
03.10.2019 21:01:07.363258 VERBOSE  LWP7890 [src/radius_record.cpp:90] Access-Reject (3), id: 0x1, length: 20

 

cat lbinet.log

Скрытый текст


03.10.2019 21:00:05.670007 [0x0000f27fc700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
03.10.2019 21:00:05.670019 [0x0000f27fc700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
03.10.2019 21:00:07.024518 [0x0000f2ffd700] DEBUG    recv_udp_sock:91: IP_PKTINFO control data found
03.10.2019 21:00:07.024542 [0x0000f2ffd700] DEBUG    iface_by_index:108: lookup interface by index 5
03.10.2019 21:00:07.024566 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 53/0x35, type: 2, len: 01, value: u8 01 / 0x01
03.10.2019 21:00:07.024581 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 61/0x3D, type: 5, len: 07, value: HEX: 01 18 A9 05 CB C3 0A ASCII '.......' HEX:01 18 a9 05 cb c3 0a  proto unassigned
03.10.2019 21:00:07.024587 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 12/0x0C, type: 1, len: 13, value: 'Administrator'
03.10.2019 21:00:07.024593 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 60/0x3C, type: 1, len: 08, value: 'MSFT 5.0'
03.10.2019 21:00:07.024604 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 55/0x37, type: 5, len: 12, value: HEX: 01 0F 03 06 2C 2E 2F 1F 21 79 F9 2B ReqOp: 1 15 3 6 44 46 47 31 33 121 -7 43
03.10.2019 21:00:07.024619 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 82/0x52, type: 5, len: 18, value: HEX: 01 06 00 04 00 6F 00 03 02 08 00 06 34 08 04 64 49 84 ASCII '.....o......4..dI.'
03.10.2019 21:00:07.024630 [0x0000f2ffd700] DEBUG    Dump DHCP packet: op=1, htype=1, hlen=6, hops=0x00, xid=0xb44314ea, secs=4, flags=0x0000, ciaddr=0.0.0.0, yiaddr=0.0.0.0, siaddr=0.0.0.0, giaddr=0.0.0.0, chaddr=18:a9:, sname=, filename=, cookie=0x63825363, len=312, sin_addr=0.0.0.0, sin_port=0, client_sockaddr=(67, 0, 0.0.0.0), dhcp_nopt=6, client_hw_addr=e0:12:, client_dhcp_pkt_len_max=0, dhcp_req_type=NOT SET, dhcp_reply_type=NOT SET
03.10.2019 21:00:07.024645 [0x0000f2ffd700] DEBUG    dhcp_op82_parse:466: Op82 Circuit ID 006F0003:VLAN 111, Unit 0, Port 3 (l=4), Remote ID 340804644984 (l=6)
03.10.2019 21:00:07.024652 [0x0000f2ffd700] ERROR    dhcp_allocate_ip:4680: DHCP IP pool uninitialized lookup!
03.10.2019 21:00:07.024657 [0x0000f2ffd700] DEBUG    dhcp_msg_dispatch:108: DHCPDISCOVER from unknown client HW MAC 18:a9:05:cb:c3:0a
03.10.2019 21:00:07.024661 [0x0000f2ffd700] DEBUG    dhcp_main:298: dhcp_msg_dispatch(0x7f43e40009f0) failed
03.10.2019 21:00:07.024668 [0x0000f2ffd700] DEBUG    dhcp_main:290: waiting for req
03.10.2019 21:00:15.024714 [0x0000f2ffd700] DEBUG    recv_udp_sock:91: IP_PKTINFO control data found
03.10.2019 21:00:15.024726 [0x0000f2ffd700] DEBUG    iface_by_index:108: lookup interface by index 5
03.10.2019 21:00:15.024740 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 53/0x35, type: 2, len: 01, value: u8 01 / 0x01
03.10.2019 21:00:15.024754 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 61/0x3D, type: 5, len: 07, value: HEX: 01 18 A9 05 CB C3 0A ASCII '.......' HEX:01 18 a9 05 cb c3 0a  proto unassigned
03.10.2019 21:00:15.024759 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 12/0x0C, type: 1, len: 13, value: 'Administrator'
03.10.2019 21:00:15.024764 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 60/0x3C, type: 1, len: 08, value: 'MSFT 5.0'
03.10.2019 21:00:15.024774 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 55/0x37, type: 5, len: 12, value: HEX: 01 0F 03 06 2C 2E 2F 1F 21 79 F9 2B ReqOp: 1 15 3 6 44 46 47 31 33 121 -7 43
03.10.2019 21:00:15.024787 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 82/0x52, type: 5, len: 18, value: HEX: 01 06 00 04 00 6F 00 03 02 08 00 06 34 08 04 64 49 84 ASCII '.....o......4..dI.'
03.10.2019 21:00:15.024797 [0x0000f2ffd700] DEBUG    Dump DHCP packet: op=1, htype=1, hlen=6, hops=0x00, xid=0xb44314ea, secs=12, flags=0x0000, ciaddr=0.0.0.0, yiaddr=0.0.0.0, siaddr=0.0.0.0, giaddr=0.0.0.0, chaddr=18:a9:, sname=, filename=, cookie=0x63825363, len=312, sin_addr=0.0.0.0, sin_port=0, client_sockaddr=(67, 0, 0.0.0.0), dhcp_nopt=6, client_hw_addr=e0:12:, client_dhcp_pkt_len_max=0, dhcp_req_type=NOT SET, dhcp_reply_type=NOT SET
03.10.2019 21:00:15.024810 [0x0000f2ffd700] DEBUG    dhcp_op82_parse:466: Op82 Circuit ID 006F0003:VLAN 111, Unit 0, Port 3 (l=4), Remote ID 340804644984 (l=6)
03.10.2019 21:00:15.024817 [0x0000f2ffd700] ERROR    dhcp_allocate_ip:4680: DHCP IP pool uninitialized lookup!
03.10.2019 21:00:15.024821 [0x0000f2ffd700] DEBUG    dhcp_msg_dispatch:108: DHCPDISCOVER from unknown client HW MAC 18:a9:05:cb:c3:0a
03.10.2019 21:00:15.024825 [0x0000f2ffd700] DEBUG    dhcp_main:298: dhcp_msg_dispatch(0x7f43e40009f0) failed
03.10.2019 21:00:15.024832 [0x0000f2ffd700] DEBUG    dhcp_main:290: waiting for req
03.10.2019 21:00:15.670105 [0x0000f27fc700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
03.10.2019 21:00:15.670118 [0x0000f27fc700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
03.10.2019 21:00:25.670204 [0x0000f27fc700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
03.10.2019 21:00:25.670216 [0x0000f27fc700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
03.10.2019 21:00:32.025196 [0x0000f2ffd700] DEBUG    recv_udp_sock:91: IP_PKTINFO control data found
03.10.2019 21:00:32.025209 [0x0000f2ffd700] DEBUG    iface_by_index:108: lookup interface by index 5
03.10.2019 21:00:32.025223 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 53/0x35, type: 2, len: 01, value: u8 01 / 0x01
03.10.2019 21:00:32.025238 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 61/0x3D, type: 5, len: 07, value: HEX: 01 18 A9 05 CB C3 0A ASCII '.......' HEX:01 18 a9 05 cb c3 0a  proto unassigned
03.10.2019 21:00:32.025244 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 12/0x0C, type: 1, len: 13, value: 'Administrator'
03.10.2019 21:00:32.025249 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 60/0x3C, type: 1, len: 08, value: 'MSFT 5.0'
03.10.2019 21:00:32.025259 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 55/0x37, type: 5, len: 12, value: HEX: 01 0F 03 06 2C 2E 2F 1F 21 79 F9 2B ReqOp: 1 15 3 6 44 46 47 31 33 121 -7 43
03.10.2019 21:00:32.025272 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 82/0x52, type: 5, len: 18, value: HEX: 01 06 00 04 00 6F 00 03 02 08 00 06 34 08 04 64 49 84 ASCII '.....o......4..dI.'
03.10.2019 21:00:32.025284 [0x0000f2ffd700] DEBUG    Dump DHCP packet: op=1, htype=1, hlen=6, hops=0x00, xid=0xb44314ea, secs=29, flags=0x0000, ciaddr=0.0.0.0, yiaddr=0.0.0.0, siaddr=0.0.0.0, giaddr=0.0.0.0, chaddr=18:a9:, sname=, filename=, cookie=0x63825363, len=312, sin_addr=0.0.0.0, sin_port=0, client_sockaddr=(67, 0, 0.0.0.0), dhcp_nopt=6, client_hw_addr=e0:12:, client_dhcp_pkt_len_max=0, dhcp_req_type=NOT SET, dhcp_reply_type=NOT SET
03.10.2019 21:00:32.025297 [0x0000f2ffd700] DEBUG    dhcp_op82_parse:466: Op82 Circuit ID 006F0003:VLAN 111, Unit 0, Port 3 (l=4), Remote ID 340804644984 (l=6)
03.10.2019 21:00:32.025304 [0x0000f2ffd700] ERROR    dhcp_allocate_ip:4680: DHCP IP pool uninitialized lookup!
03.10.2019 21:00:32.025309 [0x0000f2ffd700] DEBUG    dhcp_msg_dispatch:108: DHCPDISCOVER from unknown client HW MAC 18:a9:05:cb:c3:0a
03.10.2019 21:00:32.025313 [0x0000f2ffd700] DEBUG    dhcp_main:298: dhcp_msg_dispatch(0x7f43e40009f0) failed
03.10.2019 21:00:32.025319 [0x0000f2ffd700] DEBUG    dhcp_main:290: waiting for req
03.10.2019 21:00:35.670308 [0x0000f27fc700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
03.10.2019 21:00:35.670320 [0x0000f27fc700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
03.10.2019 21:00:45.670414 [0x0000f27fc700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
03.10.2019 21:00:45.670426 [0x0000f27fc700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
03.10.2019 21:00:55.670546 [0x0000f27fc700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
03.10.2019 21:00:55.670568 [0x0000f27fc700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
03.10.2019 21:01:04.284100 [0x0000f2ffd700] DEBUG    recv_udp_sock:91: IP_PKTINFO control data found
03.10.2019 21:01:04.284112 [0x0000f2ffd700] DEBUG    iface_by_index:108: lookup interface by index 5
03.10.2019 21:01:04.284126 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 53/0x35, type: 2, len: 01, value: u8 01 / 0x01
03.10.2019 21:01:04.284140 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 61/0x3D, type: 5, len: 07, value: HEX: 01 18 A9 05 CB C3 0A ASCII '.......' HEX:01 18 a9 05 cb c3 0a  proto unassigned
03.10.2019 21:01:04.284146 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 12/0x0C, type: 1, len: 13, value: 'Administrator'
03.10.2019 21:01:04.284151 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 60/0x3C, type: 1, len: 08, value: 'MSFT 5.0'
03.10.2019 21:01:04.284162 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 55/0x37, type: 5, len: 12, value: HEX: 01 0F 03 06 2C 2E 2F 1F 21 79 F9 2B ReqOp: 1 15 3 6 44 46 47 31 33 121 -7 43
03.10.2019 21:01:04.284175 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 82/0x52, type: 5, len: 18, value: HEX: 01 06 00 04 00 6F 00 03 02 08 00 06 34 08 04 64 49 84 ASCII '.....o......4..dI.'
03.10.2019 21:01:04.284186 [0x0000f2ffd700] DEBUG    Dump DHCP packet: op=1, htype=1, hlen=6, hops=0x00, xid=0x9fa960eb, secs=0, flags=0x0080, ciaddr=0.0.0.0, yiaddr=0.0.0.0, siaddr=0.0.0.0, giaddr=0.0.0.0, chaddr=18:a9:, sname=, filename=, cookie=0x63825363, len=312, sin_addr=0.0.0.0, sin_port=0, client_sockaddr=(67, 0, 0.0.0.0), dhcp_nopt=6, client_hw_addr=e0:12:, client_dhcp_pkt_len_max=0, dhcp_req_type=NOT SET, dhcp_reply_type=NOT SET
03.10.2019 21:01:04.284200 [0x0000f2ffd700] DEBUG    dhcp_op82_parse:466: Op82 Circuit ID 006F0003:VLAN 111, Unit 0, Port 3 (l=4), Remote ID 340804644984 (l=6)
03.10.2019 21:01:04.284207 [0x0000f2ffd700] ERROR    dhcp_allocate_ip:4680: DHCP IP pool uninitialized lookup!
03.10.2019 21:01:04.284212 [0x0000f2ffd700] DEBUG    dhcp_msg_dispatch:108: DHCPDISCOVER from unknown client HW MAC 18:a9:05:cb:c3:0a
03.10.2019 21:01:04.284216 [0x0000f2ffd700] DEBUG    dhcp_main:298: dhcp_msg_dispatch(0x7f43e40009f0) failed
03.10.2019 21:01:04.284223 [0x0000f2ffd700] DEBUG    dhcp_main:290: waiting for req
03.10.2019 21:01:05.670643 [0x0000f27fc700] DEBUG    dhcp_pool_expire_ip:3722: req to free "(null)" but dhcp_ip_pool uninitialized
03.10.2019 21:01:05.670664 [0x0000f27fc700] DEBUG    dhcp_pool_expire_offer:3807: req to free but dhcp_ip_pool uninitialized
03.10.2019 21:01:07.357124 [0x0000f2ffd700] DEBUG    recv_udp_sock:91: IP_PKTINFO control data found
03.10.2019 21:01:07.357136 [0x0000f2ffd700] DEBUG    iface_by_index:108: lookup interface by index 5
03.10.2019 21:01:07.357150 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 53/0x35, type: 2, len: 01, value: u8 01 / 0x01
03.10.2019 21:01:07.357165 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 61/0x3D, type: 5, len: 07, value: HEX: 01 18 A9 05 CB C3 0A ASCII '.......' HEX:01 18 a9 05 cb c3 0a  proto unassigned
03.10.2019 21:01:07.357171 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 12/0x0C, type: 1, len: 13, value: 'Administrator'
03.10.2019 21:01:07.357176 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 60/0x3C, type: 1, len: 08, value: 'MSFT 5.0'
03.10.2019 21:01:07.357187 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 55/0x37, type: 5, len: 12, value: HEX: 01 0F 03 06 2C 2E 2F 1F 21 79 F9 2B ReqOp: 1 15 3 6 44 46 47 31 33 121 -7 43
03.10.2019 21:01:07.357200 [0x0000f2ffd700] DEBUG    dhcp_option_print:233: DHCP Op: 82/0x52, type: 5, len: 18, value: HEX: 01 06 00 04 00 6F 00 03 02 08 00 06 34 08 04 64 49 84 ASCII '.....o......4..dI.'
03.10.2019 21:01:07.357211 [0x0000f2ffd700] DEBUG    Dump DHCP packet: op=1, htype=1, hlen=6, hops=0x00, xid=0x9fa960eb, secs=3, flags=0x0080, ciaddr=0.0.0.0, yiaddr=0.0.0.0, siaddr=0.0.0.0, giaddr=0.0.0.0, chaddr=18:a9:, sname=, filename=, cookie=0x63825363, len=312, sin_addr=0.0.0.0, sin_port=0, client_sockaddr=(67, 0, 0.0.0.0), dhcp_nopt=6, client_hw_addr=e0:12:, client_dhcp_pkt_len_max=0, dhcp_req_type=NOT SET, dhcp_reply_type=NOT SET
03.10.2019 21:01:07.357225 [0x0000f2ffd700] DEBUG    dhcp_op82_parse:466: Op82 Circuit ID 006F0003:VLAN 111, Unit 0, Port 3 (l=4), Remote ID 340804644984 (l=6)
03.10.2019 21:01:07.357232 [0x0000f2ffd700] ERROR    dhcp_allocate_ip:4680: DHCP IP pool uninitialized lookup!
03.10.2019 21:01:07.357237 [0x0000f2ffd700] DEBUG    dhcp_msg_dispatch:108: DHCPDISCOVER from unknown client HW MAC 18:a9:05:cb:c3:0a
03.10.2019 21:01:07.357241 [0x0000f2ffd700] DEBUG    dhcp_main:298: dhcp_msg_dispatch(0x7f43e40009f0) failed
03.10.2019 21:01:07.357248 [0x0000f2ffd700] DEBUG    dhcp_main:290: waiting for req
03.10.2019 21:01:15.357417 [0x0000f2ffd700] DEBUG    recv_udp_sock:91: IP_PKTINFO control data found

 

accel-ppp

Скрытый текст

.[1;32m[2019-10-03 21:01:15]:  info: eth0.111: .[0;39mrecv [DHCPv4 Discover xid=9fa960eb chaddr=18:a9:05:cb:c3:0a <Message-Type Discover> <Client-ID 0118a905cbc30a> <Host-Name Administrator> <Vendo
.[1;32m[2019-10-03 21:01:15]:  info: ipoe0: .[0;39mcreate interface ipoe0 parent eth0.111
.[1;32m[2019-10-03 21:01:15]:  info: ipoe0: .[0;39msend [RADIUS(1) Access-Request id=1 <User-Name "18:a9:05:cb:c3:0a"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 192.168.200.200> <NAS-Port 548> <
.[1;32m[2019-10-03 21:01:15]:  info: ipoe0: .[0;39mrecv [RADIUS(1) Access-Accept id=1 <Session-Timeout 86400> <Service-Type Framed-User> <Framed-Protocol PPP> <Framed-IP-Address 10.11.0.8> <Framed-
.[1;32m[2019-10-03 21:01:15]:  info: ipoe0: .[0;39m18:a9:05:cb:c3:0a: authentication succeeded
.[1;31m[2019-10-03 21:01:15]: error: ipoe0: .[0;39mcan't determine router address
.[1;34m[2019-10-03 21:01:15]: debug: ipoe0: .[0;39mterminate
.[1;32m[2019-10-03 21:01:15]:  info: ipoe0: .[0;39mipoe: session finished
.[1;32m[2019-10-03 21:01:30]:  info: eth0.111: .[0;39mrecv [DHCPv4 Discover xid=9fa960eb chaddr=18:a9:05:cb:c3:0a <Message-Type Discover> <Client-ID 0118a905cbc30a> <Host-Name Administrator> <Vendo
.[1;32m[2019-10-03 21:01:30]:  info: ipoe0: .[0;39mcreate interface ipoe0 parent eth0.111
.[1;32m[2019-10-03 21:01:30]:  info: ipoe0: .[0;39msend [RADIUS(1) Access-Request id=1 <User-Name "18:a9:05:cb:c3:0a"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 192.168.200.200> <NAS-Port 549> <
.[1;32m[2019-10-03 21:01:30]:  info: ipoe0: .[0;39mrecv [RADIUS(1) Access-Reject id=1]
.[1;34m[2019-10-03 21:01:30]: debug: ipoe0: .[0;39mterminate
.[1;32m[2019-10-03 21:01:30]:  info: ipoe0: .[0;39mipoe: session finished

 

tcpdump

Скрытый текст

tcpdump -i eth0.111  port 67 or port 68  -nn -vvv
tcpdump: listening on eth0.111, link-type EN10MB (Ethernet), capture size 65535 bytes


21:23:14.989346 IP (tos 0x0, ttl 128, id 2791, offset 0, flags [none], proto UDP (17), length 340)
    0.0.0.0.68 > 255.255.255.255.67: [udp sum ok] BOOTP/DHCP, Request from 18:a9:05:cb:c3:0a, length 312, xid 0x230c13a9, secs 1280, Flags [none] (0x0000)
          Client-Ethernet-Address 18:a9:05:cb:c3:0a
          Vendor-rfc1048 Extensions
            Magic Cookie 0x63825363
            DHCP-Message Option 53, length 1: Discover
            Client-ID Option 61, length 7: ether 18:a9:05:cb:c3:0a
            Hostname Option 12, length 13: "Administrator"
            Vendor-Class Option 60, length 8: "MSFT 5.0"
            Parameter-Request Option 55, length 12:
              Subnet-Mask, Domain-Name, Default-Gateway, Domain-Name-Server
              Netbios-Name-Server, Netbios-Node, Netbios-Scope, Router-Discovery
              Static-Route, Classless-Static-Route, Classless-Static-Route-Microsoft, Vendor-Option
            Agent-Information Option 82, length 18:
              Circuit-ID SubOption 1, length 6: ^@^D^@o^@^C
              Remote-ID SubOption 2, length 8: ^@^F4^H^DdIM-^D
            END Option 255, length 0

21:23:22.894645 IP (tos 0x0, ttl 128, id 2866, offset 0, flags [none], proto UDP (17), length 340)
    0.0.0.0.68 > 255.255.255.255.67: [udp sum ok] BOOTP/DHCP, Request from 18:a9:05:cb:c3:0a, length 312, xid 0x230c13a9, secs 3328, Flags [none] (0x0000)
          Client-Ethernet-Address 18:a9:05:cb:c3:0a
          Vendor-rfc1048 Extensions
            Magic Cookie 0x63825363
            DHCP-Message Option 53, length 1: Discover
            Client-ID Option 61, length 7: ether 18:a9:05:cb:c3:0a
            Hostname Option 12, length 13: "Administrator"
            Vendor-Class Option 60, length 8: "MSFT 5.0"
            Parameter-Request Option 55, length 12:
              Subnet-Mask, Domain-Name, Default-Gateway, Domain-Name-Server
              Netbios-Name-Server, Netbios-Node, Netbios-Scope, Router-Discovery
              Static-Route, Classless-Static-Route, Classless-Static-Route-Microsoft, Vendor-Option
            Agent-Information Option 82, length 18:
              Circuit-ID SubOption 1, length 6: ^@^D^@o^@^C
              Remote-ID SubOption 2, length 8: ^@^F4^H^DdIM-^D
            END Option 255, length 0
 

Однако, дело дальше не идёт, клиенту выдаётся но так и не назначается IP адрес, хотя видно что ему он определен и тот, что закреплён в биллинге. Биллинг фиксирует успешную авторизацию, однако более ни где нет подтверждения того, что сессия жива. Ни на accel-ppp, ни в биллинге.

Идеи в голову не прилетают, вопрос открыт, копаю дальше.

Share this post


Link to post
Share on other sites

Create an account or sign in to comment

You need to be a member in order to leave a comment

Create an account

Sign up for a new account in our community. It's easy!

Register a new account

Sign in

Already have an account? Sign in here.

Sign In Now