Добрый день.
На контроллере была отключена точка доступа, тк нет необходимости в вещании в эфир информации, что вот мол я, цепляйтесь.
Но как итог, львиная доля лога начала забиваться записями от wpa_supplicant. Каждые 2 минуты добавляется строка
06-05-2024 09:52:52.364 [wpa_supplicant] wlan0: CTRL-EVENT-SCAN-FAILED ret=-16
Можно как-нибудь от неё избавиться, чтоб не забивало журнал бесполезной информацией?
Добрый день.
А отключение wpa_supplicant.service - не останавливает службу?
Ну и мало информации. ЧТо именно было отключено, какой релиз, какой контроллер?
Для диагностики проблемы пришлите, пожалуйста, архив с диагностической информацией контроллера. Создание архива описано в документации.
Контроллер 7.3.4 с последним релизом и расширеным рутом до 2Гб.
По умолчанию на wlan0 был настроен wb-ap, дополнительно на wlan1 идёт подключение к точке доступа.
После отключения интерфейса wb-ap в журнале появляются выше означенные записи.
приложен диагностический архив, доступен только сотрудникам поддержки
(311,3 КБ)
Так что такое “отключение”?
Какой командой или редактированием какого конфига?
У меня служба wpa_supplicant.service активна, но сообщений никаких после отключения нет.
Отключение через интерфейс кнопкой “Отключить” и снятие галки “Подключаться автоматически” в настройках “Сетевые соединения”.
К сожалению не воспроизводится.
Отключаю интерфейсы - нет сообщений.
Возможно - дело в каком-то существующем VPN соединении.
VPN не влияет на работу wpa_supplicant.
Если сделать ifconfig, то получаем следующую картинку:
dbg0: flags=4099<UP,BROADCAST,MULTICAST> mtu 1500
ether 1a:55:89:a2:69:43 txqueuelen 1000 (Ethernet)
RX packets 0 bytes 0 (0.0 B)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 0 bytes 0 (0.0 B)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
eth0: flags=4099<UP,BROADCAST,MULTICAST> mtu 1500
ether 00:85:01:01:81:cb txqueuelen 1000 (Ethernet)
RX packets 0 bytes 0 (0.0 B)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 0 bytes 0 (0.0 B)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
device interrupt 65 base 0xe000
eth1: flags=4099<UP,BROADCAST,MULTICAST> mtu 1500
ether 00:85:01:01:e7:13 txqueuelen 1000 (Ethernet)
RX packets 0 bytes 0 (0.0 B)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 0 bytes 0 (0.0 B)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
device interrupt 64
lo: flags=73<UP,LOOPBACK,RUNNING> mtu 65536
inet 127.0.0.1 netmask 255.0.0.0
inet6 ::1 prefixlen 128 scopeid 0x10<host>
loop txqueuelen 1000 (Local Loopback)
RX packets 3604513 bytes 198743737 (189.5 MiB)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 3604513 bytes 198743737 (189.5 MiB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
tun0: flags=4305<UP,POINTOPOINT,RUNNING,NOARP,MULTICAST> mtu 6000
inet 192.168.127.202 netmask 255.255.255.255 destination 192.168.127.201
inet6 fe80::cd93:bad5:7035:adc5 prefixlen 64 scopeid 0x20<link>
unspec 00-00-00-00-00-00-00-00-00-00-00-00-00-00-00-00 txqueuelen 500 (UNSPEC)
RX packets 1159116 bytes 175925477 (167.7 MiB)
RX errors 0 dropped 0 overruns 0 frame 0
TX packets 292666 bytes 27478743 (26.2 MiB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0
wlan0: flags=4099<UP,BROADCAST,MULTICAST> mtu 1500
ether c4:3c:b0:e2:25:0a txqueuelen 1000 (Ethernet)
RX packets 0 bytes 0 (0.0 B)
RX errors 0 dropped 23526 overruns 0 frame 0
TX packets 0 bytes 3646796 (3.4 MiB)
TX errors 0 dropped 5118 overruns 0 carrier 0 collisions 0
wlan1: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet 192.168.10.10 netmask 255.255.255.0 broadcast 192.168.10.255
inet6 fe80::c498:c7a8:846a:4a0d prefixlen 64 scopeid 0x20<link>
ether c6:3c:b0:e2:25:0a txqueuelen 1000 (Ethernet)
RX packets 24643044 bytes 3993653864 (3.7 GiB)
RX errors 0 dropped 21064 overruns 0 frame 0
TX packets 66785630 bytes 529190682 (504.6 MiB)
TX errors 0 dropped 119 overruns 0 carrier 0 collisions 0
После выполнения команды ifconfig wlan0 down в журнале получаем записи
06-05-2024 20:05:56.364 [wpa_supplicant] wlan0: CTRL-EVENT-SCAN-FAILED ret=-100
После обратного поднятия интерфейса ifconfig wlan0 up получаем снова
06-05-2024 20:07:57.365 [wpa_supplicant] wlan0: CTRL-EVENT-SCAN-FAILED ret=-16
Сейчас, ради эксперимента перезагружусь…
Перезагрузка не помогла. В журнал всё равно сыпится
[wpa_supplicant] wlan0: CTRL-EVENT-SCAN-FAILED ret=-16
А почему ifconfig, зачем?
Проверяю.
nmcli connection up XXXXXX && nmcli connection down XXXXXX
Получаю в логах:
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8119] device (wlan1): Activation: starting connection 'XXXXXX' (7df212c5-7e3a-42e7-ab2f-a07b169b7fe3)
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8125] audit: op="connection-activate" uuid="7df212c5-7e3a-42e7-ab2f-a07b169b7fe3" name="XXXXXX" pid=2318 uid=0 result="success"
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8132] device (wlan1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8163] device (wlan1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8182] device (wlan1): Activation: (wifi) access point 'XXXXXX' has security, but secrets are required.
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8184] device (wlan1): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8292] device (wlan1): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8312] device (wlan1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8331] device (wlan1): Activation: (wifi) connection 'XXXXXX' has security, and secrets exist. No new secrets needed.
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8335] Config: added 'ssid' value 'TerHorse_Global'
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8336] Config: added 'scan_ssid' value '1'
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8337] Config: added 'bgscan' value 'simple:30:-70:86400'
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8338] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK'
May 07 07:17:09 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066229.8339] Config: added 'psk' value '<hidden>'
May 07 07:17:09 wirenboard-AWI3MCGC wb-mqtt-nm-helper[2318]: INFO:root:New active connection XXXXXX 7df212c5-7e3a-42e7-ab2f-a07b169b7fe3 /org/freedesktop/NetworkManager/Settings/1 /org/freedesktop/NetworkManager/ActiveConnection/6
May 07 07:17:15 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066235.6498] device (wlan1): supplicant interface state: inactive -> scanning
May 07 07:17:15 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066235.6500] device (p2p-dev-wlan1): supplicant management interface state: inactive -> scanning
May 07 07:17:17 wirenboard-AWI3MCGC wpa_supplicant[325]: wlan1: Trying to associate with 10:fe:ed:93:09:2e (SSID='TerHorse_Global' freq=2427 MHz)
May 07 07:17:17 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066237.6875] device (wlan1): supplicant interface state: scanning -> associating
May 07 07:17:17 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066237.6877] device (p2p-dev-wlan1): supplicant management interface state: scanning -> associating
May 07 07:17:17 wirenboard-AWI3MCGC kernel: RTL871X: rtw_set_802_11_connect(wlan1) fw_state=0x00000008
May 07 07:17:19 wirenboard-AWI3MCGC kernel: RTL871X: start auth
May 07 07:17:19 wirenboard-AWI3MCGC kernel: RTL871X: auth success, start assoc
May 07 07:17:19 wirenboard-AWI3MCGC kernel: RTL871X: rtw_cfg80211_indicate_connect(wlan1) BSS not found !!
May 07 07:17:19 wirenboard-AWI3MCGC kernel: RTL871X: assoc success
May 07 07:17:19 wirenboard-AWI3MCGC wpa_supplicant[325]: wlan1: Associated with 10:fe:ed:93:09:2e
May 07 07:17:19 wirenboard-AWI3MCGC wpa_supplicant[325]: wlan1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
May 07 07:17:19 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066239.4380] device (wlan1): supplicant interface state: associating -> 4way_handshake
May 07 07:17:19 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066239.4382] device (p2p-dev-wlan1): supplicant management interface state: associating -> 4way_handshake
May 07 07:17:19 wirenboard-AWI3MCGC kernel: RTL871X: send eapol packet
May 07 07:17:19 wirenboard-AWI3MCGC kernel: RTL871X: send eapol packet
May 07 07:17:19 wirenboard-AWI3MCGC wpa_supplicant[325]: wlan1: WPA: Key negotiation completed with 10:fe:ed:93:09:2e [PTK=CCMP GTK=CCMP]
May 07 07:17:19 wirenboard-AWI3MCGC wpa_supplicant[325]: wlan1: CTRL-EVENT-CONNECTED - Connection to 10:fe:ed:93:09:2e completed [id=0 id_str=]
May 07 07:17:19 wirenboard-AWI3MCGC kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
May 07 07:17:19 wirenboard-AWI3MCGC wpa_supplicant[325]: bgscan simple: Failed to enable signal strength monitoring
May 07 07:17:19 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066239.4571] device (wlan1): supplicant interface state: 4way_handshake -> completed
May 07 07:17:19 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066239.4574] device (wlan1): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "TerHorse_Global"
May 07 07:17:19 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066239.4575] device (p2p-dev-wlan1): supplicant management interface state: 4way_handshake -> completed
May 07 07:17:19 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066239.4581] device (wlan1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
May 07 07:17:19 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066239.4610] dhcp4 (wlan1): activation: beginning transaction (timeout in 45 seconds)
May 07 07:17:19 wirenboard-AWI3MCGC avahi-daemon[319]: Joining mDNS multicast group on interface wlan1.IPv6 with address fe80::920e:c468:34a5:4153.
May 07 07:17:19 wirenboard-AWI3MCGC avahi-daemon[319]: New relevant interface wlan1.IPv6 for mDNS.
May 07 07:17:19 wirenboard-AWI3MCGC avahi-daemon[319]: Registering new address record for fe80::920e:c468:34a5:4153 on wlan1.*.
May 07 07:17:19 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066239.4929] dhcp4 (wlan1): state changed new lease, address=10.0.0.126
May 07 07:17:19 wirenboard-AWI3MCGC avahi-daemon[319]: Joining mDNS multicast group on interface wlan1.IPv4 with address 10.0.0.126.
May 07 07:17:19 wirenboard-AWI3MCGC avahi-daemon[319]: New relevant interface wlan1.IPv4 for mDNS.
May 07 07:17:19 wirenboard-AWI3MCGC avahi-daemon[319]: Registering new address record for 10.0.0.126 on wlan1.IPv4.
May 07 07:17:19 wirenboard-AWI3MCGC dbus-daemon[284]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.8' (uid=0 pid=405 comm="/usr/sbin/NetworkManager --no-daemon ")
May 07 07:17:19 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066239.5902] device (wlan1): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
May 07 07:17:19 wirenboard-AWI3MCGC systemd[1]: Starting Network Manager Script Dispatcher Service...
May 07 07:17:19 wirenboard-AWI3MCGC dbus-daemon[284]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
May 07 07:17:19 wirenboard-AWI3MCGC systemd[1]: Started Network Manager Script Dispatcher Service.
May 07 07:17:19 wirenboard-AWI3MCGC kernel: RTL871X: set pairwise key camid:4, addr:10:fe:ed:93:09:2e, kid:0, type:AES
May 07 07:17:19 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066239.6726] device (wlan1): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
May 07 07:17:19 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066239.6738] device (wlan1): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
May 07 07:17:19 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066239.6776] device (wlan1): Activation: successful, device activated.
May 07 07:17:19 wirenboard-AWI3MCGC kernel: RTL871X: set group key camid:5, addr:10:fe:ed:93:09:2e, kid:2, type:AES
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: ntpd exiting on signal 15 (Terminated)
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 162.159.200.1 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC systemd[1]: Stopping Network Time Service...
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 81.88.210.197 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 91.209.94.10 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 85.21.78.91 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 82.142.168.18 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 192.36.143.130 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC systemd[1]: ntp.service: Succeeded.
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 176.215.178.239 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 195.3.254.2 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC systemd[1]: Stopped Network Time Service.
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 195.218.227.230 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 91.206.16.3 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 188.120.233.26 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 91.207.136.55 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 213.33.141.134 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 193.192.36.3 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[5653]: 213.234.203.30 local addr 10.0.0.78 -> <null>
May 07 07:17:20 wirenboard-AWI3MCGC systemd[1]: Starting Network Time Service...
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6160]: ntpd 4.2.8p15@1.3728-o Wed Sep 23 11:46:38 UTC 2020 (1): Starting
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6160]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:108
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6160]: ----------------------------------------------------
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6160]: ntp-4 is maintained by Network Time Foundation,
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6160]: Inc. (NTF), a non-profit 501(c)(3) public-benefit
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6160]: corporation. Support and training for ntp-4 are
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6160]: available at https://www.nwtime.org/support
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6160]: ----------------------------------------------------
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: proto: precision = 1.000 usec (-20)
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: basedate set to 2020-09-11
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: gps base set to 2020-09-13 (week 2123)
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: Listen and drop on 0 v6wildcard [::]:123
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: Listen and drop on 1 v4wildcard 0.0.0.0:123
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: Listen normally on 2 lo 127.0.0.1:123
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: Listen normally on 3 eth0 10.0.0.78:123
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: Listen normally on 4 wlan1 10.0.0.126:123
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: Listen normally on 5 lo [::1]:123
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: Listen normally on 6 eth0 [fe80::5937:2f8e:c99e:d8ae%3]:123
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: bind(23) AF_INET6 fe80::920e:c468:34a5:4153%6#123 flags 0x11 failed: Cannot assign requested address
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: unable to create socket on wlan1 (7) for fe80::920e:c468:34a5:4153%6#123
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: failed to init interface for address fe80::920e:c468:34a5:4153%6
May 07 07:17:20 wirenboard-AWI3MCGC ntpd[6166]: Listening on routing socket on fd #23 for interface updates
May 07 07:17:20 wirenboard-AWI3MCGC systemd[1]: Started Network Time Service.
May 07 07:17:22 wirenboard-AWI3MCGC ntpd[6166]: Listen normally on 8 wlan1 [fe80::920e:c468:34a5:4153%6]:123
May 07 07:17:22 wirenboard-AWI3MCGC ntpd[6166]: new interface(s) found: waking up resolver
May 07 07:17:30 wirenboard-AWI3MCGC kernel: RTL871X: linked_status_chk(wlan1) disconnect or roaming
May 07 07:17:30 wirenboard-AWI3MCGC systemd[1]: NetworkManager-dispatcher.service: Succeeded.
May 07 07:17:32 wirenboard-AWI3MCGC kernel: RTL871X: start auth
May 07 07:17:32 wirenboard-AWI3MCGC kernel: RTL871X: auth success, start assoc
May 07 07:17:32 wirenboard-AWI3MCGC kernel: RTL871X: rtw_cfg80211_indicate_connect(wlan1) BSS not found !!
May 07 07:17:32 wirenboard-AWI3MCGC kernel: RTL871X: assoc success
May 07 07:17:32 wirenboard-AWI3MCGC kernel: RTL871X: send eapol packet
May 07 07:17:32 wirenboard-AWI3MCGC wpa_supplicant[325]: wlan1: Associated with 64:d1:54:f4:83:34
May 07 07:17:32 wirenboard-AWI3MCGC wpa_supplicant[325]: wlan1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
May 07 07:17:32 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066252.2368] device (wlan1): supplicant interface state: completed -> 4way_handshake
May 07 07:17:32 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066252.2370] device (p2p-dev-wlan1): supplicant management interface state: completed -> 4way_handshake
May 07 07:17:32 wirenboard-AWI3MCGC kernel: RTL871X: rtw_aes_decrypt(wlan1) no_gkey_bc_cnt:4, no_gkey_mc_cnt:1
May 07 07:17:33 wirenboard-AWI3MCGC kernel: RTL871X: send eapol packet
May 07 07:17:33 wirenboard-AWI3MCGC wpa_supplicant[325]: wlan1: WPA: IE in 3/4 msg does not match with IE in Beacon/ProbeResp (src=64:d1:54:f4:83:34)
May 07 07:17:33 wirenboard-AWI3MCGC wpa_supplicant[325]: WPA: RSN IE in Beacon/ProbeResp - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 0c 00
May 07 07:17:33 wirenboard-AWI3MCGC wpa_supplicant[325]: WPA: RSN IE in 3/4 msg - hexdump(len=22): 30 14 01 00 00 0f ac 04 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
May 07 07:17:33 wirenboard-AWI3MCGC wpa_supplicant[325]: wlan1: CTRL-EVENT-DISCONNECTED bssid=64:d1:54:f4:83:34 reason=17 locally_generated=1
May 07 07:17:33 wirenboard-AWI3MCGC wpa_supplicant[325]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
May 07 07:17:33 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066253.2499] device (wlan1): supplicant interface state: 4way_handshake -> disconnected
May 07 07:17:33 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066253.2503] device (wlan1): Activation: (wifi) disconnected during association, asking for new key
May 07 07:17:33 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066253.2509] device (wlan1): state change: activated -> need-auth (reason 'supplicant-disconnect', sys-iface-state: 'managed')
May 07 07:17:33 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066253.2918] dhcp4 (wlan1): canceled DHCP transaction
May 07 07:17:33 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066253.2919] dhcp4 (wlan1): activation: beginning transaction (timeout in 45 seconds)
May 07 07:17:33 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066253.2920] dhcp4 (wlan1): state changed no lease
May 07 07:17:33 wirenboard-AWI3MCGC dbus-daemon[284]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.8' (uid=0 pid=405 comm="/usr/sbin/NetworkManager --no-daemon ")
May 07 07:17:33 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066253.3133] device (p2p-dev-wlan1): supplicant management interface state: 4way_handshake -> disconnected
May 07 07:17:33 wirenboard-AWI3MCGC systemd[1]: Starting Network Manager Script Dispatcher Service...
May 07 07:17:33 wirenboard-AWI3MCGC avahi-daemon[319]: Withdrawing address record for 10.0.0.126 on wlan1.
May 07 07:17:33 wirenboard-AWI3MCGC avahi-daemon[319]: Leaving mDNS multicast group on interface wlan1.IPv4 with address 10.0.0.126.
May 07 07:17:33 wirenboard-AWI3MCGC avahi-daemon[319]: Interface wlan1.IPv4 no longer relevant for mDNS.
May 07 07:17:33 wirenboard-AWI3MCGC avahi-daemon[319]: Withdrawing address record for fe80::920e:c468:34a5:4153 on wlan1.
May 07 07:17:33 wirenboard-AWI3MCGC avahi-daemon[319]: Leaving mDNS multicast group on interface wlan1.IPv6 with address fe80::920e:c468:34a5:4153.
May 07 07:17:33 wirenboard-AWI3MCGC avahi-daemon[319]: Interface wlan1.IPv6 no longer relevant for mDNS.
May 07 07:17:33 wirenboard-AWI3MCGC dbus-daemon[284]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
May 07 07:17:33 wirenboard-AWI3MCGC systemd[1]: Started Network Manager Script Dispatcher Service.
May 07 07:17:33 wirenboard-AWI3MCGC NetworkManager[405]: <warn> [1715066253.4443] device (wlan1): no secrets: No agents were available for this request.
May 07 07:17:33 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066253.4445] device (wlan1): state change: need-auth -> failed (reason 'no-secrets', sys-iface-state: 'managed')
May 07 07:17:33 wirenboard-AWI3MCGC NetworkManager[405]: <warn> [1715066253.4475] device (wlan1): Activation: failed for connection 'XXXXXX'
May 07 07:17:33 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066253.4480] device (wlan1): supplicant interface state: disconnected -> inactive
May 07 07:17:33 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066253.4482] device (p2p-dev-wlan1): supplicant management interface state: disconnected -> inactive
May 07 07:17:33 wirenboard-AWI3MCGC NetworkManager[405]: <info> [1715066253.4576] device (wlan1): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
May 07 07:17:33 wirenboard-AWI3MCGC wb-mqtt-nm-helper[2318]: INFO:root:Remove active connection /org/freedesktop/NetworkManager/Settings/1 /org/freedesktop/NetworkManager/ActiveConnection/6
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6166]: ntpd exiting on signal 15 (Terminated)
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6166]: 10.0.0.2 local addr 10.0.0.78 -> <null>
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6166]: 217.26.24.119 local addr 10.0.0.78 -> <null>
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6166]: 88.147.254.230 local addr 10.0.0.78 -> <null>
May 07 07:17:33 wirenboard-AWI3MCGC systemd[1]: Stopping Network Time Service...
May 07 07:17:33 wirenboard-AWI3MCGC systemd[1]: ntp.service: Succeeded.
May 07 07:17:33 wirenboard-AWI3MCGC systemd[1]: Stopped Network Time Service.
May 07 07:17:33 wirenboard-AWI3MCGC systemd[1]: Starting Network Time Service...
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6290]: ntpd 4.2.8p15@1.3728-o Wed Sep 23 11:46:38 UTC 2020 (1): Starting
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6290]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:108
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6290]: ----------------------------------------------------
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6290]: ntp-4 is maintained by Network Time Foundation,
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6290]: Inc. (NTF), a non-profit 501(c)(3) public-benefit
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6290]: corporation. Support and training for ntp-4 are
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6290]: available at https://www.nwtime.org/support
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6290]: ----------------------------------------------------
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6296]: proto: precision = 1.000 usec (-20)
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6296]: basedate set to 2020-09-11
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6296]: gps base set to 2020-09-13 (week 2123)
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6296]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6296]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6296]: Listen and drop on 0 v6wildcard [::]:123
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6296]: Listen and drop on 1 v4wildcard 0.0.0.0:123
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6296]: Listen normally on 2 lo 127.0.0.1:123
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6296]: Listen normally on 3 eth0 10.0.0.78:123
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6296]: Listen normally on 4 lo [::1]:123
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6296]: Listen normally on 5 eth0 [fe80::5937:2f8e:c99e:d8ae%3]:123
May 07 07:17:33 wirenboard-AWI3MCGC ntpd[6296]: Listening on routing socket on fd #22 for interface updates
May 07 07:17:33 wirenboard-AWI3MCGC systemd[1]: Started Network Time Service.
May 07 07:17:34 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 82.142.168.18
May 07 07:17:35 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 176.215.178.239
May 07 07:17:35 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 195.3.254.2
May 07 07:17:36 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 188.120.233.26
May 07 07:17:36 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 162.159.200.1
May 07 07:17:36 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 193.192.36.3
May 07 07:17:37 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 194.190.168.1
May 07 07:17:37 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 91.209.94.10
May 07 07:17:37 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 81.88.210.197
May 07 07:17:37 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 162.159.200.123
May 07 07:17:38 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 217.10.42.30
May 07 07:17:38 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 91.207.136.55
May 07 07:17:38 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 85.21.78.91
May 07 07:17:39 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 195.211.77.68
May 07 07:17:39 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 2a00:18c0:1:1::5
May 07 07:17:40 wirenboard-AWI3MCGC ntpd[6296]: Soliciting pool server 83.217.206.10
May 07 07:17:44 wirenboard-AWI3MCGC systemd[1]: NetworkManager-dispatcher.service: Succeeded.
May 07 07:17:44 wirenboard-AWI3MCGC wpa_supplicant[325]: wlan1: Reject scan trigger since one is already pending
Ну и нет сторонних сообщений от сервисов.
А как у вас настроен в интерфейсе wlan0 ?
Ника не настроен, нет соединений работающих через этот интерфейс.
Удаление интерфейса wb-ap в настройках так же не меняет ситуацию…
Спасает только настройка точки доступа, чтоб оно светило. К сожалению даже нельзя сделать скрытую сеть.
А так, из разряда бредней, а что у вас в каталоге /run/wpa_supplicant ?
nmcli connection show
NAME UUID TYPE DEVICE
wb-eth0 91f1c71d-2d97-4675-886f-ecbe52b8451e ethernet eth0
lo a225db5d-a58a-4e03-9f4f-8058fdb8fcae loopback lo
XXXXXX 7df212c5-7e3a-42e7-ab2f-a07b169b7fe3 wifi --
Wired connection 1 93a7c56d-f3d5-356c-81da-c7733d291a73 ethernet --
wb-debug 0f098677-2b49-4167-a534-207567b1751b ethernet --
wb-gsm-sim1 5d4297ba-c319-4c05-a153-17cb42e6e196 gsm --
root@wirenboard-AWI3MCGC:~# ls -l /run/wpa_supplicant/
total 0
srwxrwx--- 1 root root 0 Apr 27 11:33 wlan0
srwxrwx--- 1 root root 0 Apr 27 11:33 wlan1
попробуйте кстати testing - такое же поведение?
Хм, а у вас wifi вообще не настроен и подключен по шнурку. Может из-за этого…
А тестинг боюсь ставить, ибо контроллер за 700 вёрст, а когда получится доехать, не знаю…
Попробую воспроизвести, но пока не получается.