Page 6 of 9

Re: проблема с интерфейсами IPOE

Posted: 10 Oct 2017, 08:47
by Dmitry
ну разве что в логе явно написать, что сессия не создаётся т.к. дублирующий ип

Re: проблема с интерфейсами IPOE

Posted: 10 Oct 2017, 21:12
by c3pO
Спасибо @Dmitry за анализ лога, был твердо уверен что дубликаты исключены т.к. sqlippool используем давно в связке с MDP, sql запросы уже "вылизаны" да и никогда дубликаты не вылазили. Разбираюсь, отпишусь.

Re: проблема с интерфейсами IPOE

Posted: 11 Oct 2017, 19:16
by c3pO
Доброго времени суток, проблема решена. В моем случае причиной были sqlippool clear queries которых в 3й версии freeradius стало значительно больше и внятного описания как они работают нет. ip освобождался из пула даже при условии что приходит accounting update от юзера которые его использует.

Re: проблема с интерфейсами IPOE

Posted: 18 Oct 2017, 13:24
by green
Добрый день!
Долго сидел на accel-ppp 1.9 и все было нормально, пока не случилась такая же ошибка как у топик стартера.
Решил поставить с git, сейчас accel-ppp version ec3d2f2942172d79fe74a01f5c980873e01ff5dd
Клиент не получает ip

Настройки

[ipoe]
mode=L2
verbose=5
noauth=0
ip-unnumbered=1
username=ifname
password=empty
lease-time=600
attr-remote-id=accel-ppp
attr-l4-redirect=L4-Redirect
l4-redirect-ipset=100
attr-dhcp-opt82=DHCP-Option82
proxy-arp=2
shared=0
ifcfg=1
start=dhcpv4
nat=0
relay=10.132.0.4
giaddr=10.32.0.1/32
interface=eth0.216
proto=100

Лог

[2017-10-18 13:55:38]: info: eth0.216: recv [DHCPv4 Discover xid=e6e1c073 chaddr=e4:8d:8c:b1:f3:25 <Message-Type Discover> <Request-List Subnet,Classless-Route,Router,Route,DNS,NTP,138,Vendor-Specific> <Host-Name MikroTik> <Client-ID 01e48d8cb1f325> <Relay-Agent {Agent-Circuit-ID _00d80101} {Agent-Remote-ID _e0d9e3c45900}>]
[2017-10-18 13:55:38]: info: eth0.216: send [RADIUS(1) Access-Request id=1 <User-Name "eth0.216"> <NAS-Identifier "Accel-IPoE"> <NAS-IP-Address 10.132.0.9> <NAS-Port 14> <NAS-Port-Id "eth0.216"> <NAS-Port-Type Ethernet> <Calling-Station-Id "e4:8d:8c:b1:f3:25"> <Called-Station-Id "eth0.216"> <DHCP-Option82> <Acct-Session-Id "7F2779B53A7D366E"> <User-Password>]
[2017-10-18 13:55:38]: warn: eth0.216: radius:packet: unknown attribute received (0,239)
[2017-10-18 13:55:38]: info: eth0.216: recv [RADIUS(1) Access-Accept id=1 <Session-Timeout 86400> <Service-Type Framed-User> <Framed-Protocol PPP> <Class> <Acct-Interim-Interval 60> <Filter-Id "102400">]
[2017-10-18 13:55:38]: info: eth0.216: eth0.216: authentication succeeded
[2017-10-18 13:55:38]: info: eth0.216: send [DHCPv4 relay Discover xid=e6e1c073 giaddr=10.132.0.9 chaddr=e4:8d:8c:b1:f3:25 <Message-Type Discover> <Request-List Subnet,Classless-Route,Router,Route,DNS,NTP,138,Vendor-Specific> <Host-Name MikroTik> <Client-ID 01e48d8cb1f325> <Relay-Agent {Agent-Circuit-ID _00d80101} {Agent-Remote-ID _e0d9e3c45900}>]
[2017-10-18 13:55:38]: error: dhcpv4: recv: Connection refused
[2017-10-18 13:55:38]: info: eth0.216: recv [DHCPv4 relay Offer xid=e6e1c073 yiaddr=10.32.254.3 siaddr=10.132.0.4 giaddr=10.132.0.9 chaddr=e4:8d:8c:b1:f3:25 <Subnet 255.255.255.0> <Router 10.32.254.1> <DNS 10.32.255.16,10.32.255.17> <Lease-Time 600> <Message-Type Offer> <Server-ID 10.132.0.4> <Max-Message-Size 1400> <Relay-Agent {Agent-Circuit-ID _00d80101} {Agent-Remote-ID _e0d9e3c45900}>]
[2017-10-18 13:55:38]: info: eth0.216: send [DHCPv4 Offer xid=e6e1c073 yiaddr=10.32.254.3 chaddr=e4:8d:8c:b1:f3:25 <Message-Type Offer> <Server-ID 10.32.254.1> <Lease-Time 600> <T1 300> <Router 10.32.254.1> <Subnet 255.255.255.0> <DNS 10.32.255.16,10.32.255.17> <Max-Message-Size 1400> <Relay-Agent {Agent-Circuit-ID _00d80101} {Agent-Remote-ID _e0d9e3c45900}>]
[2017-10-18 13:55:38]: info: eth0.216: recv [DHCPv4 Request xid=e6e1c073 chaddr=e4:8d:8c:b1:f3:25 <Message-Type Request> <Server-ID 10.32.254.1> <Request-IP 10.32.254.3> <Request-List Subnet,Classless-Route,Router,Route,DNS,NTP,138,Vendor-Specific> <Host-Name MikroTik> <Client-ID 01e48d8cb1f325> <Relay-Agent {Agent-Circuit-ID _00d80101} {Agent-Remote-ID _e0d9e3c45900}>]
[2017-10-18 13:55:38]: warn: eth0.216: port change detected
[2017-10-18 13:55:38]: debug: eth0.216: terminate
[2017-10-18 13:55:38]: info: eth0.216: ipoe: session finished

Подскажите куда копать, плиз.

Re: проблема с интерфейсами IPOE

Posted: 18 Oct 2017, 14:14
by Dmitry
давно работа с релеем не проверялась, может что и сломалось уже...

Re: проблема с интерфейсами IPOE

Posted: 19 Oct 2017, 13:38
by green
Попробовал изменить схему и заставить accel выдавать IP
Создал пул, на радиусе отдал имя пула
вот лог level 5
[2017-10-18 21:15:18]: msg: accel-ppp version ec3d2f2942172d79fe74a01f5c980873e01ff5dd
[2017-10-18 21:15:25]: info: eth0.216: recv [DHCPv4 Discover xid=5e1b1e4e chaddr=e4:8d:8c:b1:f3:25 <Message-Type Discover> <Request-List Subnet,Classless-Route,Router,Route,DNS,NTP,138,Vendor-Specific> <Host-Name MikroTik> <Client-ID 01e48d8cb1f325> <Relay-Agent {Agent-Circuit-ID _00d80101} {Agent-Remote-ID _e0d9e3c45900}>]
[2017-10-18 21:15:25]: info: eth0.216: send [RADIUS(1) Access-Request id=1 <User-Name "eth0.216"> <NAS-Identifier "Accel-IPoE"> <NAS-IP-Address 10.132.0.9> <NAS-Port 14> <NAS-Port-Id "eth0.216"> <NAS-Port-Type Ethernet> <Calling-Station-Id "e4:8d:8c:b1:f3:25"> <Called-Station-Id "eth0.216"> <DHCP-Option82> <Acct-Session-Id "7F2779B53A7D871F"> <User-Password>]
[2017-10-18 21:15:25]: info: eth0.216: recv [RADIUS(1) Access-Accept id=1 <Session-Timeout 86400> <Service-Type Framed-User> <Framed-Protocol PPP> <Class> <Acct-Interim-Interval 60> <Filter-Id "102400"> <Framed-Pool "red-pool">]
[2017-10-18 21:15:25]: info: eth0.216: eth0.216: authentication succeeded
[2017-10-18 21:15:25]: info: eth0.216: send [DHCPv4 Offer xid=5e1b1e4e yiaddr=10.32.254.2 chaddr=e4:8d:8c:b1:f3:25 <Message-Type Offer> <Server-ID 10.32.254.1> <Lease-Time 600> <T1 300> <Router 10.32.254.1> <Subnet 255.255.255.0> <DNS 10.32.255.16,10.32.255.17>]
[2017-10-18 21:15:25]: info: eth0.216: recv [DHCPv4 Request xid=5e1b1e4e chaddr=e4:8d:8c:b1:f3:25 <Message-Type Request> <Server-ID 10.32.254.1> <Request-IP 10.32.254.2> <Request-List Subnet,Classless-Route,Router,Route,DNS,NTP,138,Vendor-Specific> <Host-Name MikroTik> <Client-ID 01e48d8cb1f325> <Relay-Agent {Agent-Circuit-ID _00d80101} {Agent-Remote-ID _e0d9e3c45900}>]
[2017-10-18 21:15:25]: warn: eth0.216: port change detected
[2017-10-18 21:15:25]: debug: eth0.216: terminate
[2017-10-18 21:15:25]: info: eth0.216: ipoe: session finished
[2017-10-18 21:15:38]: info: terminate, sig = 15
[2017-10-18 21:15:38]: info: ipoe: stop interface eth0.216

Re: проблема с интерфейсами IPOE

Posted: 19 Oct 2017, 13:41
by green
Смущает постоянный warn port change detected и далее terminate
Подскажите как снять более детальный лог ?

Re: проблема с интерфейсами IPOE

Posted: 19 Oct 2017, 17:33
by Dmitry
этой детализации достаточно

Re: проблема с интерфейсами IPOE

Posted: 20 Oct 2017, 12:31
by Maksel
День добрый. Решил заменить(пришло) isc-dhcp на accel и похоже та же проблема. В логах по некоторым абонентом есть запись

Code: Select all

ipoe306: 00000000000A7467: mac change detected
сделал выборку
сat local-debug.log |grep 00000000000A7467
Спойлер

Code: Select all

nas102:/var/log/accel-ppp# cat local-debug.log |grep 00000000000A7467 > 00000000000A7467
nas102:/var/log/accel-ppp# cat 00000000000A7467
[2017-10-20 14:56:21.652] ipoe306: 00000000000A7467: create interface ipoe306 parent bond0.101
[2017-10-20 14:56:21.652] ipoe306: 00000000000A7467: radius(1): req_enter 1
[2017-10-20 14:56:21.652] ipoe306: 00000000000A7467: send [RADIUS(1) Access-Request id=1 <User-Name "bond0.101-f4:f2:6d:4b:fa:05"> <NAS-Identifier "nas102"> <NAS-Port 325> <NAS-Port-Id "ipoe306"> <NAS-Port-Type Ethernet> <Calling-Station-Id "f4:f2:6d:4b:fa:05"> <Called-Station-Id "bond0.101"> <User-Password>]
[2017-10-20 14:56:21.653] ipoe306: 00000000000A7467: radius(1): req_exit 0
[2017-10-20 14:56:21.653] ipoe306: 00000000000A7467: recv [RADIUS(1) Access-Accept id=1 <Acct-Interim-Interval 60> <Framed-Pool "ip_83">]
[2017-10-20 14:56:21.654] ipoe306: 00000000000A7467: bond0.101-f4:f2:6d:4b:fa:05: authentication succeeded
[2017-10-20 14:56:21.654] ipoe306: 00000000000A7467: send [DHCPv4 Offer xid=18ad4973 yiaddr=10.1.141.115 chaddr=f4:f2:6d:4b:fa:05 <Message-Type Offer> <Server-ID 10.1.128.1> <Lease-Time 300> <T1 120> <Router 10.1.128.1> <Subnet 255.255.224.0> <DNS 91.196.80.11,91.196.80.11>]
[2017-10-20 14:56:21.670] ipoe306: 00000000000A7467: recv [DHCPv4 Request xid=10855197 chaddr=b8:97:5a:69:52:d0 <Message-Type Request> <Client-ID 01b8975a6952d0> <Request-IP 10.1.143.107> <Server-ID 10.1.128.1> <Host-Name Home-PC> <Option-81 000000486f6d652d5043> <Vendor-Class 4d53465420352e30> <Request-List Subnet,Domain-Name,Router,DNS,44,46,47,31,Route,Classless-Route,249,252,Vendor-Specific> <Relay-Agent {Agent-Circuit-ID _000400650012} {Agent-Remote-ID _00061cbdb99a8be4}>]
[2017-10-20 14:56:21.670] ipoe306: 00000000000A7467: mac change detected
[2017-10-20 14:56:21.670] ipoe306: 00000000000A7467: terminate
[2017-10-20 14:56:21.673] ipoe306: 00000000000A7467: ipoe: session finished
начал исследовать дальше:
запускаю виртуалку с MAC 08:00:27:32:2c:bb
смотрю через show session запись есть
Спойлер

Code: Select all

accel-ppp# show sessions  match ifname ipoe1142
  ifname  |          username           |    calling-sid    |      ip      | rate-limit | type | comp | state  |   uptime
----------+-----------------------------+-------------------+--------------+------------+------+------+--------+------------
 ipoe1142 | bond0.113-08:00:27:32:2c:bb | 08:00:27:32:2c:bb | 10.1.142.56  |            | ipoe |      | active | 00:17:17
Потом отключаю сетевую карту, запускаю tail -f local-debug.log|grep "00000000000A769C\|ipoe1142\|08:00:27:32:2c:bb и жду и вот что наловил:
Спойлер
nas102:/var/log/accel-ppp# tail -f local-debug.log|grep "00000000000A769C\|ipoe1142\|08:00:27:32:2c:bb"
[2017-10-20 15:20:14.282] ipoe1142: 00000000000A769C: radius(1): req_enter 1
[2017-10-20 15:20:14.283] ipoe1142: 00000000000A769C: radius(1): req_exit 0
[2017-10-20 15:21:14.282] ipoe1142: 00000000000A769C: radius(1): req_enter 1
[2017-10-20 15:21:14.283] ipoe1142: 00000000000A769C: radius(1): req_exit 0
[2017-10-20 15:22:14.282] ipoe1142: 00000000000A769C: radius(1): req_enter 1
[2017-10-20 15:22:14.283] ipoe1142: 00000000000A769C: radius(1): req_exit 0
[2017-10-20 15:23:14.282] ipoe1142: 00000000000A769C: radius(1): req_enter 1
[2017-10-20 15:23:14.283] ipoe1142: 00000000000A769C: radius(1): req_exit 0
[2017-10-20 15:24:14.282] ipoe1142: 00000000000A769C: radius(1): req_enter 1
[2017-10-20 15:24:14.283] ipoe1142: 00000000000A769C: radius(1): req_exit 0
[2017-10-20 15:24:15.843] ipoe1142: 00000000000A769C: ipoe: session timed out
[2017-10-20 15:24:15.843] ipoe1142: 00000000000A769C: terminate
[2017-10-20 15:24:15.846] ipoe1142: 00000000000A769C: radius(1): req_enter 1
[2017-10-20 15:24:15.846] ipoe1142: 00000000000A769C: send [RADIUS(1) Accounting-Request id=1a <User-Name "bond0.113-08:00:27:32:2c:bb"> < NAS-Identifier "nas102"> <NAS-Port 1179> <NAS-Port-Id "ipoe1142"> <NAS-Port-Type Ethernet> <Calling-Station-Id "08:00:27:32:2c:bb"> <Calle d-Station-Id "bond0.113"> <Acct-Status-Type Stop> <Acct-Authentic RADIUS> <Acct-Session-Id "00000000000A769C"> <Acct-Session-Time 1502> <A cct-Input-Octets 117678> <Acct-Output-Octets 299752> <Acct-Input-Packets 1090> <Acct-Output-Packets 542> <Acct-Input-Gigawords 0> <Acct-Ou tput-Gigawords 0> <Framed-IP-Address 10.1.142.56> <Acct-Terminate-Cause Lost-Carrier>]
[2017-10-20 15:24:15.846] ipoe1142: 00000000000A769C: radius(1): req_exit 0
[2017-10-20 15:24:15.846] ipoe1142: 00000000000A769C: ipoe: session finished
[2017-10-20 15:25:04.277] ipoe1142: 00000000000A8A30: create interface ipoe1142 parent bond0.114
[2017-10-20 15:25:04.277] ipoe1142: 00000000000A8A30: radius(1): req_enter 1
[2017-10-20 15:25:04.277] ipoe1142: 00000000000A8A30: send [RADIUS(1) Access-Request id=1 <User-Name "bond0.114-18:d6:c7:3a:fd:d5"> <NAS-I dentifier "nas102"> <NAS-Port 1179> <NAS-Port-Id "ipoe1142"> <NAS-Port-Type Ethernet> <Calling-Station-Id "18:d6:c7:3a:fd:d5"> <Called-Sta tion-Id "bond0.114"> <User-Password>]
[2017-10-20 15:25:04.278] ipoe1142: 00000000000A8A30: radius(1): req_exit 0
[2017-10-20 15:25:04.278] ipoe1142: 00000000000A8A30: recv [RADIUS(1) Access-Accept id=1 <Acct-Interim-Interval 60> <Framed-Pool "ip_83">]
[2017-10-20 15:25:04.278] ipoe1142: 00000000000A8A30: bond0.114-18:d6:c7:3a:fd:d5: authentication succeeded
[2017-10-20 15:25:04.278] ipoe1142: 00000000000A8A30: send [DHCPv4 Offer xid=1459db7d yiaddr=10.1.136.6 chaddr=18:d6:c7:3a:fd:d5 <Message- Type Offer> <Server-ID 10.1.128.1> <Lease-Time 300> <T1 120> <Router 10.1.128.1> <Subnet 255.255.224.0> <DNS 91.196.80.11,91.196.80.11>]
[2017-10-20 15:25:04.304] ipoe1142: 00000000000A8A30: recv [DHCPv4 Request xid=1459db7d chaddr=18:d6:c7:3a:fd:d5 <Message-Type Request> <M ax-Message-Size 1024> <Client-ID 0118d6c73afdd5> <Host-Name TL-WR840N> <Vendor-Class 4d53465420352e30> <Request-IP 10.1.136.6> <Server-ID 10.1.128.1> <Request-List Subnet,Router,DNS,Domain-Name,Route,Vendor-Specific,44,46,47,Classless-Route,249> <Relay-Agent {Agent-Circuit-ID _000400720019} {Agent-Remote-ID _0006ec228025b5e0}>]
[2017-10-20 15:25:04.304] ipoe1142: 00000000000A8A30: ipoe: activate session
[2017-10-20 15:25:04.304] ipoe1142: 00000000000A8A30: radius(1): req_enter 1
[2017-10-20 15:25:04.304] ipoe1142: 00000000000A8A30: send [RADIUS(1) Accounting-Request id=1 <User-Name "bond0.114-18:d6:c7:3a:fd:d5"> <N AS-Identifier "nas102"> <NAS-Port 1179> <NAS-Port-Id "ipoe1142"> <NAS-Port-Type Ethernet> <Calling-Station-Id "18:d6:c7:3a:fd:d5"> <Called -Station-Id "bond0.114"> <Acct-Status-Type Start> <Acct-Authentic RADIUS> <Acct-Session-Id "00000000000A8A30"> <Acct-Session-Time 0> <Acct -Input-Octets 0> <Acct-Output-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.1.136.6>]
[2017-10-20 15:25:04.304] ipoe1142: 00000000000A8A30: send [DHCPv4 Ack xid=1459db7d yiaddr=10.1.136.6 chaddr=18:d6:c7:3a:fd:d5 <Message-Ty pe Ack> <Server-ID 10.1.128.1> <Lease-Time 300> <T1 120> <Router 10.1.128.1> <Subnet 255.255.224.0> <DNS 91.196.80.11,91.196.80.11>]
[2017-10-20 15:25:04.305] ipoe1142: 00000000000A8A30: radius(1): req_exit 0
[2017-10-20 15:25:04.305] ipoe1142: 00000000000A8A30: recv [RADIUS(1) Accounting-Response id=1]
[2017-10-20 15:25:04.305] ipoe1142: 00000000000A8A30: ipoe: session started
[2017-10-20 15:25:05.912] ipoe1142: 00000000000A8A30: recv [DHCPv4 Request xid=5d977aa9 ciaddr=10.1.134.147 chaddr=00:0f:ea:e5:0f:11 <Mess age-Type Request> <Client-ID 01000feae50f11> <Max-Message-Size 1492> <Request-List Subnet,Router,DNS,Host-Name,Domain-Name,Broadcast,Route ,NTP,249> <Vendor-Class 756468637020312e31372e34> <Relay-Agent {Agent-Circuit-ID _000400720019} {Agent-Remote-ID _0006ec228025b5e0}>]
[2017-10-20 15:25:05.912] ipoe1142: 00000000000A8A30: mac change detected
[2017-10-20 15:25:05.912] ipoe1142: 00000000000A8A30: terminate
[2017-10-20 15:25:05.915] ipoe1142: 00000000000A8A30: radius(1): req_enter 1
[2017-10-20 15:25:05.916] ipoe1142: 00000000000A8A30: send [RADIUS(1) Accounting-Request id=1 <User-Name "bond0.114-18:d6:c7:3a:fd:d5"> <N AS-Identifier "nas102"> <NAS-Port 1179> <NAS-Port-Id "ipoe1142"> <NAS-Port-Type Ethernet> <Calling-Station-Id "18:d6:c7:3a:fd:d5"> <Called -Station-Id "bond0.114"> <Acct-Status-Type Stop> <Acct-Authentic RADIUS> <Acct-Session-Id "00000000000A8A30"> <Acct-Session-Time 2> <Acct- Input-Octets 0> <Acct-Output-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> < Framed-IP-Address 10.1.136.6> <Acct-Terminate-Cause User-Request>]
[2017-10-20 15:25:05.916] ipoe1142: 00000000000A8A30: radius(1): req_exit 0
[2017-10-20 15:25:05.916] ipoe1142: 00000000000A8A30: ipoe: session finished
[2017-10-20 15:25:45.210] ipoe1142: 00000000000A8ACE: create interface ipoe1142 parent bond0.114
[2017-10-20 15:25:45.210] ipoe1142: 00000000000A8ACE: radius(1): req_enter 1
[2017-10-20 15:25:45.210] ipoe1142: 00000000000A8ACE: send [RADIUS(1) Access-Request id=1 <User-Name "bond0.114-f8:d1:11:cf:1a:ff"> <NAS-I dentifier "nas102"> <NAS-Port 1179> <NAS-Port-Id "ipoe1142"> <NAS-Port-Type Ethernet> <Calling-Station-Id "f8:d1:11:cf:1a:ff"> <Called-Sta tion-Id "bond0.114"> <User-Password>]
[2017-10-20 15:25:45.212] ipoe1142: 00000000000A8ACE: radius(1): req_exit 0
[2017-10-20 15:25:45.212] ipoe1142: 00000000000A8ACE: recv [RADIUS(1) Access-Accept id=1 <Acct-Interim-Interval 60> <Framed-Pool "ip_83">]
[2017-10-20 15:25:45.213] ipoe1142: 00000000000A8ACE: bond0.114-f8:d1:11:cf:1a:ff: authentication succeeded
[2017-10-20 15:25:45.213] ipoe1142: 00000000000A8ACE: send [DHCPv4 Offer xid=8a96023b yiaddr=10.1.135.11 chaddr=f8:d1:11:cf:1a:ff <Message -Type Offer> <Server-ID 10.1.128.1> <Lease-Time 300> <T1 120> <Router 10.1.128.1> <Subnet 255.255.224.0> <DNS 91.196.80.11,91.196.80.11>]
[2017-10-20 15:25:45.226] ipoe1142: 00000000000A8ACE: recv [DHCPv4 Request xid=8a96023b chaddr=f8:d1:11:cf:1a:ff <Message-Type Request> <M ax-Message-Size 1024> <Client-ID 01f8d111cf1aff> <Host-Name TL-WR740N> <Vendor-Class 4d53465420352e30> <Request-IP 10.1.135.11> <Server-ID 10.1.128.1> <Request-List Subnet,Router,DNS,Domain-Name,Route,Vendor-Specific,44,46,47,Classless-Route,249> <Relay-Agent {Agent-Circuit-I D _000400720019} {Agent-Remote-ID _0006ec228025b5e0}>]
[2017-10-20 15:25:45.226] ipoe1142: 00000000000A8ACE: ipoe: activate session
[2017-10-20 15:25:45.245] ipoe1142: 00000000000A8ACE: radius(1): req_enter 1
[2017-10-20 15:25:45.245] ipoe1142: 00000000000A8ACE: send [RADIUS(1) Accounting-Request id=1 <User-Name "bond0.114-f8:d1:11:cf:1a:ff"> <N AS-Identifier "nas102"> <NAS-Port 1179> <NAS-Port-Id "ipoe1142"> <NAS-Port-Type Ethernet> <Calling-Station-Id "f8:d1:11:cf:1a:ff"> <Called -Station-Id "bond0.114"> <Acct-Status-Type Start> <Acct-Authentic RADIUS> <Acct-Session-Id "00000000000A8ACE"> <Acct-Session-Time 0> <Acct -Input-Octets 0> <Acct-Output-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.1.135.11>]
[2017-10-20 15:25:45.246] ipoe1142: 00000000000A8ACE: send [DHCPv4 Ack xid=8a96023b yiaddr=10.1.135.11 chaddr=f8:d1:11:cf:1a:ff <Message-T ype Ack> <Server-ID 10.1.128.1> <Lease-Time 300> <T1 120> <Router 10.1.128.1> <Subnet 255.255.224.0> <DNS 91.196.80.11,91.196.80.11>]
[2017-10-20 15:25:45.246] ipoe1142: 00000000000A8ACE: radius(1): req_exit 0
[2017-10-20 15:25:45.246] ipoe1142: 00000000000A8ACE: recv [RADIUS(1) Accounting-Response id=1]
[2017-10-20 15:25:45.248] ipoe1142: 00000000000A8ACE: ipoe: session started
[2017-10-20 15:25:46.925] ipoe1142: 00000000000A8ACE: recv [DHCPv4 Request xid=5ecd8840 ciaddr=10.1.135.239 chaddr=98:de:d0:58:c0:fd <Mess age-Type Request> <Max-Message-Size 1024> <Client-ID 0198ded058c0fd> <Host-Name TL-WR840N> <Vendor-Class 4d53465420352e30> <Request-List S ubnet,Router,DNS,Domain-Name,Route,Vendor-Specific,44,46,47,Classless-Route,249> <Relay-Agent {Agent-Circuit-ID _000400720019} {Agent-Remo te-ID _0006ec228025b5e0}>]
[2017-10-20 15:25:46.925] ipoe1142: 00000000000A8ACE: mac change detected
[2017-10-20 15:25:46.925] ipoe1142: 00000000000A8ACE: terminate
[2017-10-20 15:25:46.927] ipoe1142: 00000000000A8ACE: radius(1): req_enter 1
[2017-10-20 15:25:46.927] ipoe1142: 00000000000A8ACE: send [RADIUS(1) Accounting-Request id=1 <User-Name "bond0.114-f8:d1:11:cf:1a:ff"> <N AS-Identifier "nas102"> <NAS-Port 1179> <NAS-Port-Id "ipoe1142"> <NAS-Port-Type Ethernet> <Calling-Station-Id "f8:d1:11:cf:1a:ff"> <Called -Station-Id "bond0.114"> <Acct-Status-Type Stop> <Acct-Authentic RADIUS> <Acct-Session-Id "00000000000A8ACE"> <Acct-Session-Time 2> <Acct- Input-Octets 0> <Acct-Output-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> < Framed-IP-Address 10.1.135.11> <Acct-Terminate-Cause User-Request>]
[2017-10-20 15:25:46.927] ipoe1142: 00000000000A8ACE: radius(1): req_exit 0
[2017-10-20 15:25:46.928] ipoe1142: 00000000000A8ACE: ipoe: session finished
Получается когда время сессию выходит аксель закрывает и удаляет ip интерфейса а сам интерфейс остается, потом когда приходит новый запрос на получение адреса аксел его вешает на этот же интерфейс, а потом пишет что мак изменился. и так по кругу.
Кто-то писал что это начинает при 500+ абонентах, у меня такое был до 100.
------------------------

сессий 1300+
Спойлер

Code: Select all

uptime: 2.00:01:08
cpu: 4%
mem(rss/virt): 57100/672796 kB
core:
  mempool_allocated: 11731959
  mempool_available: 513951
  thread_count: 8
  thread_active: 2
  context_count: 1342
  context_sleeping: 0
  context_pending: 0
  md_handler_count: 1338
  md_handler_pending: 1
  timer_count: 2616
  timer_pending: 0
sessions:
  starting: 2
  active: 1308
  finishing: 0
ipoe:
  starting: 2
  active: 1309
  delayed: 0
radius(1, 127.0.0.1):
  state: active
  fail count: 0
  request count: 0
  queue length: 0
  auth sent: 313056
  auth lost(total/5m/1m): 0/0/0
  auth avg query time(5m/1m): 29/22 ms
  acct sent: 484547
  acct lost(total/5m/1m): 0/0/0
  acct avg query time(5m/1m): 0/0 ms
  interim sent: 2076225
  interim lost(total/5m/1m): 5/0/0
  interim avg query time(5m/1m): 0/0 ms
конфиг
Спойлер

Code: Select all

[modules]
log_file
#pppoe
ipoe
vlan-mon
radius
ippool
#pppd_compat
shaper

[core]
log-error=/var/log/accel-ppp/core.log
thread-count=8

[common]
#20170225
single-session=replace
sid-case=upper
#sid-source=seq
#max-sessions=1000


[auth]
#any-login=0
#noauth=0

[ipoe]
noauth=0
verbose=10
lua-file=/etc/accel-ppp.lua
username=lua:username_vlan
#password=username|empty|<string>
password=username
unit-cache=1000
lease-time=300
renew-time=120
max-lease-time=300
shared=1
ifcfg=1
mode=L2
start=dhcpv4
proto=100
proxy-arp=1
ip-unnumbered=0
agent-remote-id=accel-ppp
interface=bond0
interface=re:bond0\.1[0-9][0-9],ifcfg=1,mode=L2,start=dhcpv4,shared=1,ip-unnumbered=0,username=lua:username_vlan_mac
gw-ip-address=10.1.128.1/19
gw-ip-address=10.4.128.1/19
vlan-timeout=604800
vlan-name=%I.%N
vlan-mon=bond0,100-165,550-598
l4-redirect-on-reject=600
l4-redirect-ip-pool=l4-redirect
attr-dhcp-client-ip=DHCP-Client-IP-Address
attr-dhcp-router-ip=DHCP-Router-IP-Address
attr-dhcp-mask=DHCP-Mask

[vlan-mon]
verbose=10
#autoclean = 1

[dns]
dns1=91.196.80.11
dns2=91.196.80.11

[radius]
#dictionary=/usr/local/share/accel-ppp/radius/dictionary
nas-identifier=nas102
#nas-ip-address=127.0.0.1
gw-ip-address=10.16.102.1
server=127.0.0.1,testing12334,auth-port=1812,acct-port=1813,req-limit=50,fail-timeout=0,max-fail=10,weight=1
#dae-server=127.0.0.1:3799,testing123
verbose=5
max-try=3
acct-timeout=0


[client-ip-range]
disable
10.0.0.0/8

[ip-pool]
attr=Framed-Pool
verbose=5
shuffle=0
gw-ip-address=10.1.128.1/19
10.1.128.0/19,name=ip_83

[log]
log-file=/var/log/accel-ppp/local-accel-ppp.log
log-emerg=/var/log/accel-ppp/local-emerg.log
log-fail-file=/var/log/accel-ppp/local-auth-fail.log
log-debug=/var/log/accel-ppp/local-debug.log
сopy=1
level=5
verbose=1

[pppd-compat]
#ip-pre-up=/etc/ppp/ip-pre-up
ip-up=/etc/ppp/ip-up
ip-down=/etc/ppp/ip-down
ip-change=/etc/ppp/ip-change
radattr-prefix=/var/run/radattr
verbose=1



[cli]
verbose=0
telnet=127.0.0.1:2085
tcp=192.168.79.16:2085

[accel-dp]
socket=/var/run/accel-dp.sock

Re: проблема с интерфейсами IPOE

Posted: 20 Oct 2017, 14:00
by Maksel
Поставил unit-cache=0 сделал reload.
Теперь интерфейс удаляется сразу. И чуть позже создается снова при новом запросе от другого абонента, но это решило проблему частично... все равно аксель закрывает сессию и пишет что изменился мак
вот выборка по интерфейсу ipoe27
Спойлер
[2017-10-20 16:47:52.567] ipoe27: 00000000000ACAA4: recv [DHCPv4 Request xid=6cdf988d ciaddr=10.1.141.159 chaddr=54:a0:50:b3:68:e8 <Message-Type Request> <Client-ID 0154a050b368e8> <Max-Message-Size 1492> <Request-List Subnet,Router,DNS,Host-Name,Domain-Name,Broadcast,Route,NTP,249> <Vendo r-Class 756468637020312e31372e34> <Relay-Agent {Agent-Circuit-ID _000400660012} {Agent-Remote-ID _00065cd99819e2b0}>]
[2017-10-20 16:47:52.567] ipoe27: 00000000000ACAA4: mac change detected
[2017-10-20 16:47:52.567] ipoe27: 00000000000ACAA4: terminate
[2017-10-20 16:47:52.570] ipoe27: 00000000000ACAA4: radius(1): req_enter 1
[2017-10-20 16:47:52.570] ipoe27: 00000000000ACAA4: send [RADIUS(1) Accounting-Request id=1 <User-Name "bond0.102-00:1e:68:1b:34:49"> <NAS-Identi fier "nas102"> <NAS-Port 8661> <NAS-Port-Id "ipoe27"> <NAS-Port-Type Ethernet> <Calling-Station-Id "00:1e:68:1b:34:49"> <Called-Station-Id "bond0 .102"> <Acct-Status-Type Stop> <Acct-Authentic RADIUS> <Acct-Session-Id "00000000000ACAA4"> <Acct-Session-Time 20> <Acct-Input-Octets 0> <Acct-Ou tput-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.1.144.159> <Acct-Terminate-Cause User-Request>]
[2017-10-20 16:47:52.570] ipoe27: 00000000000ACAA4: radius(1): req_exit 0
[2017-10-20 16:47:52.570] ipoe27: 00000000000ACAA4: ipoe: session finished
---------------------------------------------------------------------------------------------------------------
[2017-10-20 16:47:52.926] ipoe27: 00000000000ACAE6: create interface ipoe27 parent bond0.114
[2017-10-20 16:47:52.926] ipoe27: 00000000000ACAE6: radius(1): req_enter 1
[2017-10-20 16:47:52.926] ipoe27: 00000000000ACAE6: send [RADIUS(1) Access-Request id=1 <User-Name "bond0.114-f8:d1:11:8e:5d:b9"> <NAS-Identifier "nas102"> <NAS-Port 8727> <NAS-Port-Id "ipoe27"> <NAS-Port-Type Ethernet> <Calling-Station-Id "f8:d1:11:8e:5d:b9"> <Called-Station-Id "bond0.114 "> <User-Password>]
[2017-10-20 16:47:52.928] ipoe27: 00000000000ACAE6: radius(1): req_exit 0
[2017-10-20 16:47:52.928] ipoe27: 00000000000ACAE6: recv [RADIUS(1) Access-Accept id=1 <Acct-Interim-Interval 60> <Framed-Pool "ip_83">]
[2017-10-20 16:47:52.928] ipoe27: 00000000000ACAE6: bond0.114-f8:d1:11:8e:5d:b9: authentication succeeded
[2017-10-20 16:47:52.928] ipoe27: 00000000000ACAE6: send [DHCPv4 Offer xid=c228e797 yiaddr=10.1.142.102 chaddr=f8:d1:11:8e:5d:b9 <Message-Type Of fer> <Server-ID 10.1.128.1> <Lease-Time 300> <T1 120> <Router 10.1.128.1> <Subnet 255.255.224.0> <DNS 91.196.80.11,91.196.80.11>]
[2017-10-20 16:47:52.956] ipoe27: 00000000000ACAE6: recv [DHCPv4 Request xid=c228e797 chaddr=f8:d1:11:8e:5d:b9 <Message-Type Request> <Request-IP 10.1.142.102> <Server-ID 10.1.128.1> <Max-Message-Size 1024> <Request-List Subnet,Router,Vendor-Specific,44,46,47,DNS,Route,Classless-Route,249> <Host-Name WR340G> <Vendor-Class 4d534654203938005f> <Client-ID 01f8d1118e5db9> <Relay-Agent {Agent-Circuit-ID _000400720019} {Agent-Remote-ID _ 0006ec228025b5e0}>]
[2017-10-20 16:47:52.957] ipoe27: 00000000000ACAE6: ipoe: activate session
[2017-10-20 16:47:52.989] ipoe27: 00000000000ACAE6: radius(1): req_enter 1
[2017-10-20 16:47:52.990] ipoe27: 00000000000ACAE6: send [RADIUS(1) Accounting-Request id=1 <User-Name "bond0.114-f8:d1:11:8e:5d:b9"> <NAS-Identi fier "nas102"> <NAS-Port 8727> <NAS-Port-Id "ipoe27"> <NAS-Port-Type Ethernet> <Calling-Station-Id "f8:d1:11:8e:5d:b9"> <Called-Station-Id "bond0 .114"> <Acct-Status-Type Start> <Acct-Authentic RADIUS> <Acct-Session-Id "00000000000ACAE6"> <Acct-Session-Time 0> <Acct-Input-Octets 0> <Acct-Ou tput-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.1.142.102> ]
[2017-10-20 16:47:52.990] ipoe27: 00000000000ACAE6: send [DHCPv4 Ack xid=c228e797 yiaddr=10.1.142.102 chaddr=f8:d1:11:8e:5d:b9 <Message-Type Ack> <Server-ID 10.1.128.1> <Lease-Time 300> <T1 120> <Router 10.1.128.1> <Subnet 255.255.224.0> <DNS 91.196.80.11,91.196.80.11>]
[2017-10-20 16:47:52.995] ipoe27: 00000000000ACAE6: radius(1): req_exit 0
[2017-10-20 16:47:52.995] ipoe27: 00000000000ACAE6: recv [RADIUS(1) Accounting-Response id=1]
[2017-10-20 16:47:52.997] ipoe27: 00000000000ACAE6: ipoe: session started


[2017-10-20 16:47:54.446] ipoe27: 00000000000ACAE6: recv [DHCPv4 Discover xid=4552604a chaddr=e8:94:f6:6f:1a:e1<Message-Type Discover> <Max-Mess age-Size 1024> <Client-ID 01e894f66f1ae1> <Host-Name TL-WR741ND> <Vendor-Class 4d53465420352e30> <Request-IP 10.1.136.154> <Request-List Subnet,R outer,DNS,Domain-Name,Route,Vendor-Specific,44,46,47,Classless-Route,249> <Relay-Agent {Agent-Circuit-ID _000400720019} {Agent-Remote-ID _0006ec2 28025b5e0}>]
[2017-10-20 16:47:54.446] ipoe27: 00000000000ACAE6: mac change detected
[2017-10-20 16:47:54.446] ipoe27: 00000000000ACAE6: terminate

[2017-10-20 16:47:54.449] ipoe27: 00000000000ACAE6: radius(1): req_enter 2
[2017-10-20 16:47:54.449] ipoe27: 00000000000ACAE6: send [RADIUS(1) Accounting-Request id=1 <User-Name "bond0.114-f8:d1:11:8e:5d:b9"> <NAS-Identi fier "nas102"> <NAS-Port 8727> <NAS-Port-Id "ipoe27"> <NAS-Port-Type Ethernet> <Calling-Station-Id "f8:d1:11:8e:5d:b9"> <Called-Station-Id "bond0 .114"> <Acct-Status-Type Stop> <Acct-Authentic RADIUS> <Acct-Session-Id "00000000000ACAE6"> <Acct-Session-Time 1> <Acct-Input-Octets 0> <Acct-Out put-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.1.142.102> <Acct-Terminate-Cause User-Request>]
[2017-10-20 16:47:54.449] ipoe27: 00000000000ACAE6: radius(1): req_exit 1
[2017-10-20 16:47:54.449] ipoe27: 00000000000ACAE6: ipoe: session finished

--------------------------------------------------------------------------------------------------------------------
[2017-10-20 16:47:54.974] ipoe27: 00000000000ACAEF: create interface ipoe27 parent bond0.102
[2017-10-20 16:47:54.974] ipoe27: 00000000000ACAEF: radius(1): req_enter 2
[2017-10-20 16:47:54.975] ipoe27: 00000000000ACAEF: send [RADIUS(1) Access-Request id=1 <User-Name "bond0.102-54:04:a6:49:a4:bd"> <NAS-Identifier "nas102"> <NAS-Port 8736> <NAS-Port-Id "ipoe27"> <NAS-Port-Type Ethernet> <Calling-Station-Id "54:04:a6:49:a4:bd"> <Called-Station-Id "bond0.102 "> <User-Password>]
[2017-10-20 16:47:54.976] ipoe27: 00000000000ACAEF: radius(1): req_exit 1
[2017-10-20 16:47:54.976] ipoe27: 00000000000ACAEF: recv [RADIUS(1) Access-Accept id=1 <Acct-Interim-Interval 60> <Framed-Pool "ip_83">]
[2017-10-20 16:47:54.977] ipoe27: 00000000000ACAEF: bond0.102-54:04:a6:49:a4:bd: authentication succeeded
[2017-10-20 16:47:54.977] ipoe27: 00000000000ACAEF: send [DHCPv4 Offer xid=91c5855 yiaddr=10.1.140.242 chaddr=54:04:a6:49:a4:bd <Message-Type Off er> <Server-ID 10.1.128.1> <Lease-Time 300> <T1 120> <Router 10.1.128.1> <Subnet 255.255.224.0> <DNS 91.196.80.11,91.196.80.11>]
[2017-10-20 16:47:54.981] ipoe27: 00000000000ACAEF: recv [DHCPv4 Request xid=91c5855 chaddr=54:04:a6:49:a4:bd <Message-Type Request> <Client-ID 0 15404a649a4bd> <Request-IP 10.1.140.242> <Server-ID 10.1.128.1> <Host-Name Admin-PC> <Option-81 00000041646d696e2d5043> <Vendor-Class 4d534654203 52e30> <Request-List Subnet,Domain-Name,Router,DNS,44,46,47,31,Route,Classless-Route,249,Vendor-Specific> <Relay-Agent {Agent-Circuit-ID _0004006 60019} {Agent-Remote-ID _0006c8be19c0faa0}>]
[2017-10-20 16:47:54.981] ipoe27: 00000000000ACAEF: ipoe: activate session
[2017-10-20 16:47:55.043] ipoe27: 00000000000ACAEF: radius(1): req_enter 3
[2017-10-20 16:47:55.043] ipoe27: 00000000000ACAEF: send [RADIUS(1) Accounting-Request id=1 <User-Name "bond0.102-54:04:a6:49:a4:bd"> <NAS-Identi fier "nas102"> <NAS-Port 8736> <NAS-Port-Id "ipoe27"> <NAS-Port-Type Ethernet> <Calling-Station-Id "54:04:a6:49:a4:bd"> <Called-Station-Id "bond0 .102"> <Acct-Status-Type Start> <Acct-Authentic RADIUS> <Acct-Session-Id "00000000000ACAEF"> <Acct-Session-Time 0> <Acct-Input-Octets 0> <Acct-Ou tput-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.1.140.242> ]
[2017-10-20 16:47:55.043] ipoe27: 00000000000ACAEF: send [DHCPv4 Ack xid=91c5855 yiaddr=10.1.140.242 chaddr=54:04:a6:49:a4:bd <Message-Type Ack> <Server-ID 10.1.128.1> <Lease-Time 300> <T1 120> <Router 10.1.128.1> <Subnet 255.255.224.0> <DNS 91.196.80.11,91.196.80.11>]
[2017-10-20 16:47:55.048] ipoe27: 00000000000ACAEF: radius(1): req_exit 1
[2017-10-20 16:47:55.048] ipoe27: 00000000000ACAEF: recv [RADIUS(1) Accounting-Response id=1]
[2017-10-20 16:47:55.050] ipoe27: 00000000000ACAEF: ipoe: session started
получается создался интфейс ipoe27: 00000000000ACAE6: create interface ipoe27 parent bond0.114 - все нормально, выдался адрес абоненту, передали в радиус данные. Через 2 сек. приходит новый Discover - [2017-10-20 16:47:54.446] ipoe27: 00000000000ACAE6: recv [DHCPv4 Discover xid=4552604a chaddr=e8:94:f6:6f:1a:e1 - почему-то на этот же интерфейс ipoe27: 00000000000ACAE6: - аксель пишет изменился мак и закрывает сессию.

Есть сессии которые весят с самого запуска:
Спойлер

Code: Select all

accel-ppp# show sessions  match username bond0.115-10:fe:ed:58:ca:fb
  ifname  |          username           |    calling-sid    |      ip      | rate-limit | type | comp | state  |   uptime
----------+-----------------------------+-------------------+--------------+------------+------+------+--------+------------
 ipoe2    | bond0.115-10:fe:ed:58:ca:fb | 10:fe:ed:58:ca:fb | 10.1.128.2   |            | ipoe |      | active | 2.01:37:53

[2017-10-20 16:57:56.549] ipoe2: 000000000005C0FD: radius(1): req_enter 1
[2017-10-20 16:57:56.551] ipoe2: 000000000005C0FD: radius(1): req_exit 0
[2017-10-20 16:58:12.115] ipoe2: 000000000005C0FD: recv [DHCPv4 Request xid=68f49c68 ciaddr=10.1.128.2 chaddr=10:fe:ed:58:ca:fb <Message-Type Request> <Max-Message-Size 1024> <Client-ID 0110feed58cafb> <Host-Name TL-WR741ND> <Vendor-Class 4d53465420352e30> <Request-List Subnet,Router,DNS,Domain-Name,Route,Vendor-Specific,44,46,47,Classless-Route,249>]
[2017-10-20 16:58:12.115] ipoe2: 000000000005C0FD: send [DHCPv4 Ack xid=68f49c68 ciaddr=10.1.128.2 yiaddr=10.1.128.2 chaddr=10:fe:ed:58:ca:fb <Message-Type Ack> <Server-ID 10.1.128.1> <Lease-Time 300> <T1 60> <Router 10.1.128.1> <Subnet 255.255.224.0> <DNS 91.196.80.11,91.196.80.11>]
[2017-10-20 16:58:56.550] ipoe2: 000000000005C0FD: radius(1): req_enter 2
[2017-10-20 16:58:56.550] ipoe2: 000000000005C0FD: radius(1): req_exit 1
[2017-10-20 16:59:56.550] ipoe2: 000000000005C0FD: radius(1): req_enter 1
[2017-10-20 16:59:56.551] ipoe2: 000000000005C0FD: radius(1): req_exit 0
[2017-10-20 17:00:42.647] ipoe2: 000000000005C0FD: recv [DHCPv4 Request xid=68f49c68 ciaddr=10.1.128.2 chaddr=10:fe:ed:58:ca:fb <Message-Type Request> <Max-Message-Size 1024> <Client-ID 0110feed58cafb> <Host-Name TL-WR741ND> <Vendor-Class 4d53465420352e30> <Request-List Subnet,Router,DNS,Domain-Name,Route,Vendor-Specific,44,46,47,Classless-Route,249>]
[2017-10-20 17:00:42.647] ipoe2: 000000000005C0FD: send [DHCPv4 Ack xid=68f49c68 ciaddr=10.1.128.2 yiaddr=10.1.128.2 chaddr=10:fe:ed:58:ca:fb <Message-Type Ack> <Server-ID 10.1.128.1> <Lease-Time 300> <T1 60> <Router 10.1.128.1> <Subnet 255.255.224.0> <DNS 91.196.80.11,91.196.80.11>]
[2017-10-20 17:00:56.550] ipoe2: 000000000005C0FD: radius(1): req_enter 2
[2017-10-20 17:00:56.551] ipoe2: 000000000005C0FD: radius(1): req_exit 1


PS. Начинают звонить абоненты у которых нет роутера и жалуются, что им windows пишет конфликт ip адресов. Интернет у них через pppoe, но людей это раздражает. Получается, что клиенту адрес выдался, потом аксель сессию закрыл и выдал адрес другому абоненту.