package: iwd
version: 2.0-1

I reported the first issue I encountered trying to switch to iwd v2.0
in  https://bugs.debian.org/cgi-bin/bugreport.cgi?bug=1025345 (iwd
service not starting after installation).

Continuing... With a reboot having fixed the issue starting the iwd
service, I was still not connected to wifi, so I opened gnome's wifi
settings. It was now giving me a list of access points again, and mine
was listed, but connecting failed every time I tried to select it.

I then deleted and recreated the access point (a hidden type as
previously mentioned), having remembered having needed to do this last
time I tried iwd. This made no difference.

I then toggled wifi on/off and immediately it tried auto-connecting and
was successful.

WTH, why was that necessary.

It then worked just fine for the next X number of hours until I shut
down my laptop for the night. The router remained on.

Coming back to it today, router still on, I turned on my laptop, and no
auto-connected wifi. I went into gnome wifi settings and selected the
access point, but it failed to connect, over and over. I tried toggling
wifi on/off like before, but no difference. It just outright refused to
connect. I then turned off and restarted the router. Laptop still
refused to connect. I then rebooted the laptop, and then it worked.
WTH.

This is reminiscent of my troubles last time. I'd hoped that they'd
have fixed this by now. :/

Btw, I'm now using an Intel wifi module. At some point in the past I'd
had a different brand's, but I can't remember which of the two I was
using the last time I tried iwd.

The following is a chunk of journalctl output from when the laptop was
refusing to connect today:

Dec 02 19:02:28 debian1 kernel: iwlwifi 0000:3a:00.0: Not associated and the 
session protection is over already...
Dec 02 19:02:28 debian1 kernel: wlan0: Connection to AP 8c:<snip> lost
Dec 02 19:02:28 debian1 kernel: wlan0: send auth to 8c:<snip> (try 2/3)
Dec 02 19:02:29 debian1 kernel: iwlwifi 0000:3a:00.0: Not associated and the 
session protection is over already...
Dec 02 19:02:29 debian1 kernel: wlan0: Connection to AP 8c:<snip> lost
Dec 02 19:02:29 debian1 kernel: wlan0: send auth to 8c:<snip> (try 3/3)
Dec 02 19:02:30 debian1 kernel: iwlwifi 0000:3a:00.0: Not associated and the 
session protection is over already...
Dec 02 19:02:30 debian1 kernel: wlan0: Connection to AP 8c:<snip> lost
Dec 02 19:02:30 debian1 kernel: wlan0: authentication with 8c:<snip> timed out
Dec 02 19:02:30 debian1 iwd[818]: connect event timed out, reason=2
Dec 02 19:02:30 debian1 NetworkManager[815]: <error> [1670007750.7101] device 
(wlan0): Activation: (wifi) Network.Connect failed: 
GDBus.Error:net.connman.iwd.Failed: Operation failed
Dec 02 19:02:30 debian1 NetworkManager[815]: <info>  [1670007750.7107] device 
(wlan0): state change: config -> failed (reason 'no-secrets', sys-iface-state: 
'managed')
Dec 02 19:02:30 debian1 NetworkManager[815]: <info>  [1670007750.7121] manager: 
NetworkManager state is now CONNECTED_LOCAL
Dec 02 19:02:30 debian1 NetworkManager[815]: <warn>  [1670007750.7133] device 
(wlan0): Activation: failed for connection '<ssid>'
Dec 02 19:02:30 debian1 NetworkManager[815]: <info>  [1670007750.7136] device 
(wlan0): new IWD device state is disconnected
Dec 02 19:02:30 debian1 NetworkManager[815]: <info>  [1670007750.7149] device 
(wlan0): state change: failed -> disconnected (reason 'none', sys-iface-state: 
'managed')
Dec 02 19:02:38 debian1 sudo[2919]:   <user> : TTY=pts/0 ; PWD=/home/<user> ; 
USER=root ; COMMAND=/usr/bin/journalctl -n100
Dec 02 19:02:38 debian1 sudo[2919]: pam_unix(sudo:session): session opened for 
user root(uid=0) by (uid=1000)
Dec 02 19:05:01 debian1 CRON[2939]: pam_unix(cron:session): session opened for 
user root(uid=0) by (uid=0)
Dec 02 19:05:01 debian1 CRON[2940]: (root) CMD (command -v debian-sa1 > 
/dev/null && debian-sa1 1 1)
Dec 02 19:05:01 debian1 CRON[2939]: pam_unix(cron:session): session closed for 
user root
Dec 02 19:07:42 debian1 sudo[2919]: pam_unix(sudo:session): session closed for 
user root
Dec 02 19:07:45 debian1 NetworkManager[815]: <info>  [1670008065.5780] device 
(wlan0): Activation: starting connection '<ssid>' (<uuid>)
Dec 02 19:07:45 debian1 NetworkManager[815]: <info>  [1670008065.5781] audit: 
op="connection-activate" uuid="<uuid>" name="<ssid>" pid=2800 uid=1000 result=">
Dec 02 19:07:45 debian1 NetworkManager[815]: <info>  [1670008065.5782] device 
(wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 
'managed')
Dec 02 19:07:45 debian1 NetworkManager[815]: <info>  [1670008065.5785] manager: 
NetworkManager state is now CONNECTING
Dec 02 19:07:45 debian1 NetworkManager[815]: <info>  [1670008065.5787] device 
(wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 
'managed')
Dec 02 19:07:45 debian1 NetworkManager[815]: <info>  [1670008065.5860] device 
(wlan0): new IWD device state is connecting
Dec 02 19:07:45 debian1 kernel: wlan0: authenticate with 8c:<snip>
Dec 02 19:07:45 debian1 kernel: wlan0: bad VHT capabilities, disabling VHT
Dec 02 19:07:45 debian1 kernel: wlan0: Invalid HE elem, Disable HE
Dec 02 19:07:45 debian1 kernel: wlan0: 80 MHz not supported, disabling VHT
Dec 02 19:07:45 debian1 kernel: wlan0: send auth to 8c:<snip> (try 1/3)
Dec 02 19:07:46 debian1 kernel: wlan0: send auth to 8c:<snip> (try 2/3)
Dec 02 19:07:47 debian1 kernel: iwlwifi 0000:3a:00.0: Not associated and the 
session protection is over already...
Dec 02 19:07:47 debian1 kernel: wlan0: Connection to AP 8c:<snip> lost
Dec 02 19:07:47 debian1 kernel: wlan0: send auth to 8c:<snip> (try 3/3)
Dec 02 19:07:48 debian1 kernel: iwlwifi 0000:3a:00.0: Not associated and the 
session protection is over already...
Dec 02 19:07:48 debian1 kernel: wlan0: Connection to AP 8c:<snip> lost
Dec 02 19:07:48 debian1 kernel: wlan0: authentication with 8c:<snip> timed out
Dec 02 19:07:48 debian1 iwd[818]: connect event timed out, reason=2
Dec 02 19:07:48 debian1 NetworkManager[815]: <error> [1670008068.6977] device 
(wlan0): Activation: (wifi) Network.Connect failed: 
GDBus.Error:net.connman.iwd.Failed: Operation failed
Dec 02 19:07:48 debian1 NetworkManager[815]: <info>  [1670008068.6986] device 
(wlan0): state change: config -> failed (reason 'no-secrets', sys-iface-state: 
'managed')
Dec 02 19:07:48 debian1 NetworkManager[815]: <info>  [1670008068.7006] manager: 
NetworkManager state is now CONNECTED_LOCAL
Dec 02 19:07:48 debian1 NetworkManager[815]: <warn>  [1670008068.7018] device 
(wlan0): Activation: failed for connection '<ssid>'
Dec 02 19:07:48 debian1 NetworkManager[815]: <info>  [1670008068.7023] device 
(wlan0): new IWD device state is disconnected
Dec 02 19:07:48 debian1 NetworkManager[815]: <info>  [1670008068.7037] device 
(wlan0): state change: failed -> disconnected (reason 'none', sys-iface-state: 
'managed')
Dec 02 19:07:54 debian1 kernel: Lockdown: systemd-logind: hibernation is 
restricted; see man kernel_lockdown.7
Dec 02 19:07:56 debian1 NetworkManager[815]: <info>  [1670008076.1467] manager: 
rfkill: Wi-Fi hardware radio set disabled
Dec 02 19:07:56 debian1 NetworkManager[815]: <info>  [1670008076.1513] device 
(wlan0): state change: disconnected -> unavailable (reason 'none', 
sys-iface-state: 'managed')
Dec 02 19:07:56 debian1 NetworkManager[815]: <info>  [1670008076.1527] audit: 
op="radio-control" arg="wireless-enabled:off" pid=2058 uid=1000 result="success"
Dec 02 19:07:56 debian1 NetworkManager[815]: <info>  [1670008076.1561] manager: 
rfkill: Wi-Fi now disabled by radio killswitch
Dec 02 19:07:56 debian1 NetworkManager[815]: <error> [1670008076.1567] device 
(/net/connman/iwd/0): .Set failed: GDBus.Error:net.connman.iwd.Failed: 
Operation failed
Dec 02 19:07:56 debian1 systemd[1]: Starting Load/Save RF Kill Switch Status...
Dec 02 19:07:56 debian1 systemd[1]: Started Load/Save RF Kill Switch Status.
Dec 02 19:07:57 debian1 NetworkManager[815]: <info>  [1670008077.1786] manager: 
rfkill: Wi-Fi hardware radio set enabled
Dec 02 19:07:57 debian1 NetworkManager[815]: <info>  [1670008077.1813] audit: 
op="radio-control" arg="wireless-enabled:on" pid=2058 uid=1000 result="success"
Dec 02 19:07:57 debian1 NetworkManager[815]: <info>  [1670008077.1843] manager: 
rfkill: Wi-Fi now enabled by radio killswitch
Dec 02 19:07:57 debian1 iwd[818]: Could not register frame watch type 00d0: -114
Dec 02 19:07:57 debian1 NetworkManager[815]: <info>  [1670008077.4377] device 
(wlan0): new IWD device state is disconnected
Dec 02 19:07:57 debian1 NetworkManager[815]: <info>  [1670008077.4381] device 
(wlan0): state change: unavailable -> disconnected (reason 
'supplicant-available', sys-iface-state: 'managed')
Dec 02 19:08:02 debian1 systemd[1]: systemd-rfkill.service: Deactivated 
successfully.
Dec 02 19:08:24 debian1 systemd[1857]: app-gnome-org.gnome.Settings-2800.scope: 
Consumed 17.256s CPU time.
Dec 02 19:08:28 debian1 sudo[2984]:   <user> : TTY=pts/0 ; PWD=/home/<user> ; 
USER=root ; COMMAND=/usr/bin/journalctl -n100
Dec 02 19:08:28 debian1 sudo[2984]: pam_unix(sudo:session): session opened for 
user root(uid=0) by (uid=1000)

The following is some output from when it successfully connected after
rebooting first the AP and then the laptop:

Dec 02 19:11:48 debian1 NetworkManager[821]: <info>  [1670008308.5388] device 
(wlan0): Activation: starting connection '<ssid>' (<uuid>)
Dec 02 19:11:48 debian1 NetworkManager[821]: <info>  [1670008308.5389] audit: 
op="connection-activate" uuid="<uuid>" name="<ssid>" pid=2737 uid=1000 result=">
Dec 02 19:11:48 debian1 NetworkManager[821]: <info>  [1670008308.5390] device 
(wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 
'managed')
Dec 02 19:11:48 debian1 NetworkManager[821]: <info>  [1670008308.5393] manager: 
NetworkManager state is now CONNECTING
Dec 02 19:11:48 debian1 NetworkManager[821]: <info>  [1670008308.5395] device 
(wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 
'managed')
Dec 02 19:11:48 debian1 NetworkManager[821]: <info>  [1670008308.5475] device 
(wlan0): new IWD device state is connecting
Dec 02 19:11:48 debian1 kernel: wlan0: authenticate with 8c:<snip>
Dec 02 19:11:48 debian1 kernel: wlan0: bad VHT capabilities, disabling VHT
Dec 02 19:11:48 debian1 kernel: wlan0: Invalid HE elem, Disable HE
Dec 02 19:11:48 debian1 kernel: wlan0: 80 MHz not supported, disabling VHT
Dec 02 19:11:48 debian1 kernel: wlan0: send auth to 8c:<snip> (try 1/3)
Dec 02 19:11:49 debian1 kernel: iwlwifi 0000:3a:00.0: Not associated and the 
session protection is over already...
Dec 02 19:11:49 debian1 kernel: wlan0: Connection to AP 8c:<snip> lost
Dec 02 19:11:49 debian1 kernel: wlan0: send auth to 8c:<snip> (try 2/3)
Dec 02 19:11:49 debian1 kernel: wlan0: authenticated
Dec 02 19:11:49 debian1 kernel: wlan0: associating to AP 8c:<snip> with corrupt 
probe response
Dec 02 19:11:49 debian1 kernel: wlan0: associate with 8c:<snip> (try 1/3)
Dec 02 19:11:49 debian1 kernel: wlan0: RX AssocResp from 8c:<snip> (capab=0x411 
status=0 aid=1)
Dec 02 19:11:49 debian1 kernel: wlan0: associated
Dec 02 19:11:50 debian1 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link 
becomes ready
Dec 02 19:11:50 debian1 NetworkManager[821]: <info>  [1670008310.0564] device 
(wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  
Connected to '<ssid>'.
Dec 02 19:11:50 debian1 NetworkManager[821]: <info>  [1670008310.0585] device 
(wlan0): new IWD device state is connected
Dec 02 19:11:50 debian1 NetworkManager[821]: <info>  [1670008310.1025] device 
(wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 
'managed')
Dec 02 19:11:50 debian1 NetworkManager[821]: <info>  [1670008310.1047] dhcp4 
(wlan0): activation: beginning transaction (timeout in 45 seconds)
Dec 02 19:11:50 debian1 dnsmasq[1298]: reading /etc/resolv.conf
Dec 02 19:11:50 debian1 dnsmasq[1298]: using nameserver <snip>
Dec 02 19:11:50 debian1 dnsmasq[1298]: using nameserver <snip>
Dec 02 19:11:50 debian1 dnsmasq[1298]: using nameserver <snip>
Dec 02 19:11:50 debian1 dnsmasq[1298]: using nameserver <snip>
Dec 02 19:11:50 debian1 avahi-daemon[637]: Joining mDNS multicast group on 
interface wlan0.IPv6 with address <snip>.
Dec 02 19:11:50 debian1 avahi-daemon[637]: New relevant interface wlan0.IPv6 
for mDNS.
Dec 02 19:11:50 debian1 avahi-daemon[637]: Registering new address record for 
<snip> on wlan0.*.
Dec 02 19:11:50 debian1 NetworkManager[821]: <info>  [1670008310.1628] dhcp4 
(wlan0): state changed no lease
Dec 02 19:11:50 debian1 NetworkManager[821]: <info>  [1670008310.1719] dhcp4 
(wlan0): state changed new lease, address=<snip>
Dec 02 19:11:50 debian1 NetworkManager[821]: <info>  [1670008310.1724] policy: 
set '<ssid>' (wlan0) as default for IPv4 routing and DNS
Dec 02 19:11:50 debian1 avahi-daemon[637]: Joining mDNS multicast group on 
interface wlan0.IPv4 with address <snip>.
Dec 02 19:11:50 debian1 avahi-daemon[637]: New relevant interface wlan0.IPv4 
for mDNS.
Dec 02 19:11:50 debian1 avahi-daemon[637]: Registering new address record for 
<snip> on wlan0.IPv4.
Dec 02 19:11:50 debian1 dbus-daemon[640]: [system] Activating via systemd: 
service name='org.freedesktop.nm_dispatcher' 
unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.14' (uid=0>
Dec 02 19:11:50 debian1 NetworkManager[821]: <info>  [1670008310.2092] device 
(wlan0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 
'managed')
Dec 02 19:11:50 debian1 systemd[1]: Starting Network Manager Script Dispatcher 
Service...
Dec 02 19:11:50 debian1 dbus-daemon[640]: [system] Successfully activated 
service 'org.freedesktop.nm_dispatcher'
Dec 02 19:11:50 debian1 systemd[1]: Started Network Manager Script Dispatcher 
Service.
Dec 02 19:11:50 debian1 NetworkManager[821]: <info>  [1670008310.2514] device 
(wlan0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 
'managed')
Dec 02 19:11:50 debian1 NetworkManager[821]: <info>  [1670008310.2518] device 
(wlan0): state change: secondaries -> activated (reason 'none', 
sys-iface-state: 'managed')
Dec 02 19:11:50 debian1 NetworkManager[821]: <info>  [1670008310.2524] manager: 
NetworkManager state is now CONNECTED_SITE
Dec 02 19:11:50 debian1 NetworkManager[821]: <info>  [1670008310.2530] device 
(wlan0): Activation: successful, device activate

Reply via email to