[Pkg-utopia-maintainers] Bug#1023004: network-manager: does not attempt reconnect after a temporary wifi failure
Vincent Lefevre
vincent at vinc17.net
Sat Oct 29 00:26:22 BST 2022
Package: network-manager
Version: 1.40.2-1
Severity: normal
In case of temporary wifi failure, network-manager doesn't try to
reconnect. Here are the logs:
Oct 29 00:58:06 zira wpa_supplicant[851]: wlp61s0: CTRL-EVENT-BEACON-LOSS
Oct 29 00:58:09 zira wpa_supplicant[851]: wlp61s0: CTRL-EVENT-BEACON-LOSS
Oct 29 00:58:09 zira kernel: wlp61s0: Connection to AP 02:03:aa:bd:c4:e0 lost
Oct 29 00:58:09 zira wpa_supplicant[851]: wlp61s0: CTRL-EVENT-DISCONNECTED bssid=02:03:aa:bd:c4:e0 reason=4 locally_generated=1
Oct 29 00:58:09 zira wpa_supplicant[851]: wlp61s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Oct 29 00:58:09 zira NetworkManager[956]: <info> [1666997889.6866] device (wlp61s0): supplicant interface state: completed -> disconnected
Oct 29 00:58:09 zira NetworkManager[956]: <info> [1666997889.6866] device (p2p-dev-wlp61s0): supplicant management interface state: completed -> disconnected
Oct 29 00:58:09 zira NetworkManager[956]: <info> [1666997889.7628] device (wlp61s0): supplicant interface state: disconnected -> scanning
Oct 29 00:58:09 zira NetworkManager[956]: <info> [1666997889.7629] device (p2p-dev-wlp61s0): supplicant management interface state: disconnected -> scanning
Oct 29 00:58:12 zira wpa_supplicant[851]: wlp61s0: SME: Trying to authenticate with 02:03:aa:bd:c4:e0 (SSID='Cosmo WiFi_3428' freq=2442 MHz)
Oct 29 00:58:12 zira kernel: wlp61s0: authenticate with 02:03:aa:bd:c4:e0
Oct 29 00:58:13 zira kernel: wlp61s0: bad VHT capabilities, disabling VHT
Oct 29 00:58:13 zira kernel: wlp61s0: 80 MHz not supported, disabling VHT
Oct 29 00:58:13 zira kernel: wlp61s0: send auth to 02:03:aa:bd:c4:e0 (try 1/3)
Oct 29 00:58:13 zira NetworkManager[956]: <info> [1666997893.0255] device (wlp61s0): supplicant interface state: scanning -> authenticating
Oct 29 00:58:13 zira NetworkManager[956]: <info> [1666997893.0256] device (p2p-dev-wlp61s0): supplicant management interface state: scanning -> authenticating
Oct 29 00:58:13 zira wpa_supplicant[851]: wlp61s0: Trying to associate with 02:03:aa:bd:c4:e0 (SSID='Cosmo WiFi_3428' freq=2442 MHz)
Oct 29 00:58:13 zira kernel: wlp61s0: send auth to 02:03:aa:bd:c4:e0 (try 2/3)
Oct 29 00:58:13 zira kernel: wlp61s0: authenticated
Oct 29 00:58:13 zira NetworkManager[956]: <info> [1666997893.1573] device (wlp61s0): supplicant interface state: authenticating -> associating
Oct 29 00:58:13 zira NetworkManager[956]: <info> [1666997893.1574] device (p2p-dev-wlp61s0): supplicant management interface state: authenticating -> associating
Oct 29 00:58:13 zira kernel: wlp61s0: associate with 02:03:aa:bd:c4:e0 (try 1/3)
Oct 29 00:58:13 zira wpa_supplicant[851]: wlp61s0: Associated with 02:03:aa:bd:c4:e0
Oct 29 00:58:13 zira kernel: wlp61s0: RX AssocResp from 02:03:aa:bd:c4:e0 (capab=0x431 status=0 aid=2)
Oct 29 00:58:13 zira kernel: wlp61s0: associated
Oct 29 00:58:13 zira wpa_supplicant[851]: wlp61s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Oct 29 00:58:13 zira NetworkManager[956]: <info> [1666997893.1775] device (wlp61s0): supplicant interface state: associating -> 4way_handshake
Oct 29 00:58:13 zira NetworkManager[956]: <info> [1666997893.1776] device (p2p-dev-wlp61s0): supplicant management interface state: associating -> 4way_handshake
Oct 29 00:58:13 zira kernel: iwlwifi 0000:3d:00.0: No beacon heard and the time event is over already...
Oct 29 00:58:13 zira kernel: wlp61s0: Connection to AP 02:03:aa:bd:c4:e0 lost
Oct 29 00:58:13 zira wpa_supplicant[851]: wlp61s0: CTRL-EVENT-DISCONNECTED bssid=02:03:aa:bd:c4:e0 reason=4 locally_generated=1
Oct 29 00:58:13 zira wpa_supplicant[851]: wlp61s0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect
Oct 29 00:58:13 zira wpa_supplicant[851]: wlp61s0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Cosmo WiFi_3428" auth_failures=1 duration=10 reason=WRONG_KEY
Oct 29 00:58:13 zira wpa_supplicant[851]: wlp61s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Oct 29 00:58:13 zira NetworkManager[956]: <info> [1666997893.7310] device (wlp61s0): supplicant interface state: 4way_handshake -> disconnected
Oct 29 00:58:13 zira NetworkManager[956]: <info> [1666997893.7310] device (p2p-dev-wlp61s0): supplicant management interface state: 4way_handshake -> disconnected
Oct 29 00:58:13 zira NetworkManager[956]: <info> [1666997893.8313] device (wlp61s0): supplicant interface state: disconnected -> scanning
Oct 29 00:58:13 zira NetworkManager[956]: <info> [1666997893.8314] device (p2p-dev-wlp61s0): supplicant management interface state: disconnected -> scanning
Oct 29 00:58:24 zira NetworkManager[956]: <warn> [1666997904.9268] device (wlp61s0): link timed out.
Oct 29 00:58:24 zira NetworkManager[956]: <info> [1666997904.9275] device (wlp61s0): state change: activated -> failed (reason 'supplicant-timeout', sys-iface-state: 'managed')
Oct 29 00:58:24 zira NetworkManager[956]: <info> [1666997904.9282] manager: NetworkManager state is now DISCONNECTED
Oct 29 00:58:24 zira NetworkManager[956]: <warn> [1666997904.9291] device (wlp61s0): Activation: failed for connection 'cosmo'
Oct 29 00:58:24 zira wpa_supplicant[851]: wlp61s0: Reject scan trigger since one is already pending
Oct 29 00:58:24 zira dbus-daemon[788]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.11' (uid=0 pid=956 comm="/usr/sbin/NetworkManager --no-daemon")
Oct 29 00:58:24 zira NetworkManager[956]: <info> [1666997904.9357] device (wlp61s0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Oct 29 00:58:24 zira avahi-daemon[784]: Withdrawing address record for fe80::2d28:4ce2:f8e4:f7e1 on wlp61s0.
Oct 29 00:58:24 zira avahi-daemon[784]: Leaving mDNS multicast group on interface wlp61s0.IPv6 with address fe80::2d28:4ce2:f8e4:f7e1.
Oct 29 00:58:24 zira avahi-daemon[784]: Interface wlp61s0.IPv6 no longer relevant for mDNS.
Oct 29 00:58:24 zira systemd[1]: Starting Network Manager Script Dispatcher Service...
Oct 29 00:58:24 zira NetworkManager[956]: <info> [1666997904.9686] dhcp4 (wlp61s0): canceled DHCP transaction, DHCP client pid 1677327
Oct 29 00:58:24 zira NetworkManager[956]: <info> [1666997904.9687] dhcp4 (wlp61s0): activation: beginning transaction (timeout in 45 seconds)
Oct 29 00:58:24 zira NetworkManager[956]: <info> [1666997904.9688] dhcp4 (wlp61s0): state changed no lease
Oct 29 00:58:24 zira avahi-daemon[784]: Withdrawing address record for 192.168.43.172 on wlp61s0.
Oct 29 00:58:24 zira avahi-daemon[784]: Leaving mDNS multicast group on interface wlp61s0.IPv4 with address 192.168.43.172.
Oct 29 00:58:24 zira avahi-daemon[784]: Interface wlp61s0.IPv4 no longer relevant for mDNS.
Oct 29 00:58:24 zira dbus-daemon[788]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Oct 29 00:58:24 zira systemd[1]: Started Network Manager Script Dispatcher Service.
Oct 29 00:58:24 zira ifupdown[1677994]: IFACE=wlp61s0 ADDRFAM=inet METHOD=NetworkManager PHASE=post-down
Oct 29 00:58:35 zira systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
So there was some instability at 00:58:13. But it did not try to
reconnect a few seconds later.
-- System Information:
Debian Release: bookworm/sid
APT prefers unstable-debug
APT policy: (500, 'unstable-debug'), (500, 'stable-updates'), (500, 'stable-security'), (500, 'unstable'), (500, 'testing'), (500, 'stable'), (1, 'experimental')
merged-usr: no
Architecture: amd64 (x86_64)
Kernel: Linux 6.0.0-2-amd64 (SMP w/8 CPU threads; PREEMPT)
Kernel taint flags: TAINT_PROPRIETARY_MODULE, TAINT_OOT_MODULE, TAINT_UNSIGNED_MODULE
Locale: LANG=POSIX, LC_CTYPE=C.UTF-8 (charmap=UTF-8), LANGUAGE not set
Shell: /bin/sh linked to /bin/dash
Init: systemd (via /run/systemd/system)
LSM: AppArmor: enabled
Versions of packages network-manager depends on:
ii adduser 3.129
ii dbus 1.14.4-1
ii libaudit1 1:3.0.7-1.1+b1
ii libbluetooth3 5.65-1+b1
ii libc6 2.35-1
ii libcurl3-gnutls 7.86.0-1
ii libglib2.0-0 2.74.1-1
ii libgnutls30 3.7.8-2
ii libjansson4 2.14-2
ii libmm-glib0 1.18.12-1+b1
ii libndp0 1.8-1
ii libnewt0.52 0.52.21-6
ii libnm0 1.40.2-1
ii libpsl5 0.21.0-1.2
ii libreadline8 8.2-1
ii libselinux1 3.4-1+b2
ii libsystemd0 251.6-1
ii libteamdctl0 1.31-1
ii libudev1 251.6-1
ii policykit-1 121+compat0.1-6
ii udev 251.6-1
Versions of packages network-manager recommends:
ii dnsmasq-base [dnsmasq-base] 2.87-1.1
ii libpam-systemd 251.6-1
ii modemmanager 1.18.12-1+b1
ii ppp 2.4.9-1+1.1+b1
ii wireless-regdb 2022.06.06-1
ii wpasupplicant 2:2.10-9+b2
Versions of packages network-manager suggests:
ii iptables 1.8.8-1
pn libteam-utils <none>
Versions of packages network-manager is related to:
ii isc-dhcp-client 4.4.3-P1-1
-- no debconf information
--
Vincent Lefèvre <vincent at vinc17.net> - Web: <https://www.vinc17.net/>
100% accessible validated (X)HTML - Blog: <https://www.vinc17.net/blog/>
Work: CR INRIA - computer arithmetic / AriC project (LIP, ENS-Lyon)
More information about the Pkg-utopia-maintainers
mailing list