Поиск проблем в OpenWRT/LEDE

Eugene Muzychenko написал(а) к All в Oct 17 17:58:02 по местному времени:

Привет!

Настраиваю новый маршрутизатор, прошив туда LEDE 17.01.4. Стандартная WiFi-сеть, привязанная к LAN, работает нормально, кабельные подключения (WAN/LAN) тоже. Добавил гостевую сеть, привязанную к отдельному интерфейсу с собственным диапазоном DНCP - устройства не могут к ней подключиться, циклически запрашивая IP-адрес, но не получая его.

Смотрю лог (уровень Debug) - там какая-то невразумительная херня:

daemon.notice hostapd: wlan0-1: AP-STA-DISCONNECTED 64:cc:2e:b8:28:72
daemon.info hostapd: wlan0-1: STA 64:cc:2e:b8:28:72 IEEE 802.11: authenticated
daemon.info hostapd: wlan0-1: STA 64:cc:2e:b8:28:72 IEEE 802.11: associated (aid 4)
daemon.notice hostapd: wlan0-1: AP-STA-CONNECTED 64:cc:2e:b8:28:72
daemon.info hostapd: wlan0-1: STA 64:cc:2e:b8:28:72 WPA: pairwise key handshake completed (RSN)
daemon.notice hostapd: wlan0-1: AP-STA-DISCONNECTED 80:a5:89:45:28:89
daemon.info hostapd: wlan0-1: STA 80:a5:89:45:28:89 IEEE 802.11: authenticated
daemon.info hostapd: wlan0-1: STA 80:a5:89:45:28:89 IEEE 802.11: associated (aid 2)
daemon.notice hostapd: wlan0-1: AP-STA-CONNECTED 80:a5:89:45:28:89
daemon.info hostapd: wlan0-1: STA 80:a5:89:45:28:89 WPA: pairwise key handshake completed (RSN)
daemon.notice hostapd: wlan0-1: AP-STA-DISCONNECTED 64:cc:2e:b8:28:72
daemon.info hostapd: wlan0-1: STA 64:cc:2e:b8:28:72 IEEE 802.11: authenticated
daemon.info hostapd: wlan0-1: STA 64:cc:2e:b8:28:72 IEEE 802.11: associated (aid 4)
daemon.notice hostapd: wlan0-1: AP-STA-CONNECTED 64:cc:2e:b8:28:72
daemon.info hostapd: wlan0-1: STA 64:cc:2e:b8:28:72 WPA: pairwise key handshake completed (RSN)

По таким "отладочным" сообщениям как-то можно понять, что происходит в DНCP-сервере? Ну реально же убогий лог, без вариантов.

В этих системах вообще есть сколько-нибудь целенаправленные способы поиска глюков, или основным способом решения проблемы является метод последовательного тыка? :)

До этого там были какие-то жалобы в отношении IPv6 - я отключил IPv6 во всех используемых интерфейсах, но не помогло.

Всего доброго!
Евгений Музыченко
eu-gene@muzy-chen-ko.net (все дефисы убрать)

--- GoldED+/W32-MSVC 1.1.5-b20170303

Victor Sudakov написал(а) к Eugene Muzychenko в Oct 17 21:30:18 по местному времени:

Dear Eugene,

29 Oct 17 17:58, you wrote to All:
EM> Настраиваю новый маршрутизатор, прошив туда LEDE 17.01.4. Стандартная
EM> WiFi-сеть, привязанная к LAN, работает нормально, кабельные
EM> подключения (WAN/LAN) тоже. Добавил гостевую сеть, привязанную к
EM> отдельному интерфейсу с собственным диапазоном DНCP - устройства не
EM> могут к ней подключиться, циклически запрашивая IP-адрес, но не
EM> получая его.

EM> Смотрю лог (уровень Debug) - там какая-то невразумительная херня:

Не вижу в этом логе ни слова про DНCP, вижу только сообщения hostapd, поэтому рискну предположить, что DНCP сервер просто не работает (не запущен, либо не настроен диапазон, либо не слушает на интерфейсе), поэтому WiFi устройства просто тупо переподключаются в надежде, что когда-нибудь им DНCP сервер таки ответит.

EM> По таким "отладочным" сообщениям как-то можно понять, что происходит в
EM> DНCP-сервере?

Никак.

EM> Ну реально же убогий лог, без вариантов.

EM> В этих системах вообще есть сколько-нибудь целенаправленные способы
EM> поиска глюков, или основным способом решения проблемы является метод
EM> последовательного тыка? :)

Если туда можно зайти по ssh или telnet, посмотреть список процессов, конфиги dhcpd и т.п. - то ответ положительный, способы есть.

Victor Sudakov, VAS4-RIPE, VAS47-RIPN
--- GoldED+/BSD 1.1.5-b20160322-b20160322

Eugene Muzychenko написал(а) к Victor Sudakov в Oct 17 10:18:48 по местному времени:

Привет!

29 Oct 17 21:30, you wrote to me:

VS> Не вижу в этом логе ни слова про DНCP

А это все, что находится в логе по заданному MAC-адресу. Сообщения DНCP есть только общие:

daemon.info odhcpd[792]: Raising SIGUSR1 due to address change on br-lan
daemon.notice netifd: wan (1007): udhcpc: started, v1.25.1
daemon.info odhcpd[792]: Using a RA lifetime of 0 seconds on br-lan
daemon.notice odhcpd[792]: Failed to send to ff02::1%br-lan (Operation not permitted)
daemon.err odhcp6c[1013]: Failed to send DНCPV6 message to ff02::1:2 (Permission denied)
daemon.notice netifd: wan (1007): udhcpc: sending discover
daemon.notice netifd: wan (1007): udhcpc: performing DНCP renew
daemon.notice netifd: wan (1007): udhcpc: sending discover
daemon.err odhcp6c[1013]: Failed to send DНCPV6 message to ff02::1:2 (Permission denied)
daemon.err odhcp6c[1013]: Failed to send DНCPV6 message to ff02::1:2 (Permission denied)
daemon.notice netifd: wan (1007): udhcpc: sending discover
daemon.info dnsmasq[1223]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DНCP no-DНCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC no-ID loop-detect inotify
daemon.info dnsmasq-dhcp[1223]: DНCP, IP range 192.168.6.100 -- 192.168.6.249, lease time 12h
daemon.info dnsmasq[1223]: read /tmp/hosts/odhcpd - 0 addresses
daemon.info dnsmasq[1223]: read /tmp/hosts/dhcp.cfg02411c - 2 addresses
daemon.info dnsmasq-dhcp[1223]: read /etc/ethers - 0 addresses
daemon.err odhcp6c[1013]: Failed to send DНCPV6 message to ff02::1:2 (Permission denied)
daemon.info odhcpd[792]: Using a RA lifetime of 0 seconds on br-lan
daemon.info dnsmasq[1359]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DНCP no-DНCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC no-ID loop-detect inotify
daemon.info dnsmasq-dhcp[1359]: DНCP, IP range 192.168.200.100 -- 192.168.200.249, lease time 12h
daemon.info dnsmasq-dhcp[1359]: DНCP, IP range 192.168.6.100 -- 192.168.6.249, lease time 12h
daemon.info dnsmasq[1359]: read /tmp/hosts/odhcpd - 0 addresses
daemon.info dnsmasq[1359]: read /tmp/hosts/dhcp.cfg02411c - 2 addresses
daemon.info dnsmasq-dhcp[1359]: read /etc/ethers - 0 addresses
daemon.err odhcp6c[1013]: Failed to send DНCPV6 message to ff02::1:2 (Permission denied)
daemon.err odhcp6c[1013]: Failed to send DНCPV6 message to ff02::1:2 (Permission denied)
daemon.err odhcp6c[1013]: Failed to send DНCPV6 message to ff02::1:2 (Permission denied)
daemon.err odhcp6c[1013]: Failed to send DНCPV6 message to ff02::1:2 (Permission denied)

VS> рискну предположить, что DНCP сервер просто не работает (не
VS> запущен

Запущен:

792 root 1416 S /usr/sbin/odhcpd
1007 root 1184 S udhcpc -p /var/run/udhcpc-br-wan.pid -s /lib/netifd/
1013 root 1016 S odhcp6c -s /lib/netifd/dhcpv6.script -P0 -t120 eth0.

VS> либо не настроен диапазон, либо не слушает на интерфейсе)

На LAN, он точно слушает, ибо обычная привязанная к нему радиосеть нормально раздает IP-адреса. Проблемы только с гостевой. Вот что выдает netstat -l -p:

udp 0 0 :::dhcpv6-client :::* 1013/odhcp6c
udp 0 0 :::dhcpv6-server :::* 792/odhcpd
raw 0 0 ::%1:58 ::%4443948:* 58 1013/odhcp6c
raw 0 0 ::%1:58 ::%4443948:* 58 792/odhcpd
raw 0 0 ::%1:58 ::%4443948:* 58 792/odhcpd

VS> Если туда можно зайти по ssh или telnet, посмотреть список процессов,
VS> конфиги dhcpd и т.п. - то ответ положительный, способы есть.

И что смотреть?

В uci-конфиге (/etc/config/dhcp) прописано в точности то же самое, что и на старом маршрутизаторе, где гостевая сеть работает:

config dhcp 'GUESTWIFI'
option start '100'
option leasetime '12h'
option limit '150'
option interface 'GUESTWIFI'

Это корректно транслируется во временный dnsmasq.conf:

dhcp-range=GUESTWIFI,192.168.200.100,192.168.200.249,255.255.255.0,12h

В общем, различий с работающей на старом маршрутизаторе сетью пока не вижу, но на новом DНCP адресов не выдает.

Всего доброго!
Евгений Музыченко
eu-gene@muzy-chen-ko.net (все дефисы убрать)

--- GoldED+/W32-MSVC 1.1.5-b20170303