[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