Intermittent wifi issues with iwd

TheSunCat · 2023-09-22 16:41:56

Hi! I'm using NetworkManager with the iwd backend on a Intel AX210 card which I installed in my laptop. Sometimes it randomly enters a mode where I cannot connect to certain networks. When I press Connect in iwgtk, it says "Connected", then I instantly get a "Connection aborted" error and it's back to disconnected. Other times, connecting to a network will enter an infinite loop of iwgtk refreshing the network list, while journalctl prints the following in loop:

Sep 22 18:29:30 apg iwd[1290]: 4-Way handshake failed for ifindex: 3, reason: 15
Sep 22 18:29:30 apg kernel: wlan0: deauthenticating from 1c:61:b4:26:ba:25 by local choice (Reason: 15=4WAY_HANDSHAKE_TIMEOUT)
Sep 22 18:29:30 apg NetworkManager[1167]: <info>  [1695400170.0529] device (wlan0): new IWD device state is disconnected
Sep 22 18:29:32 apg /usr/lib/gdm-wayland-session[73270]: [2023-09-22 18:29:32.931] [net] [error] sync error: (connection: Couldn't resolve host name)
Sep 22 18:29:37 apg /usr/lib/gdm-wayland-session[73270]: [2023-09-22 18:29:37.932] [net] [error] sync error: (connection: Couldn't resolve host name)
Sep 22 18:29:42 apg /usr/lib/gdm-wayland-session[73270]: [2023-09-22 18:29:42.931] [net] [info] connectivity lost
Sep 22 18:29:42 apg /usr/lib/gdm-wayland-session[73270]: [2023-09-22 18:29:42.931] [net] [error] sync error: (connection: Operation was aborted by an application callback, parser: [json.exception.parse_error.101] parse error at line 1, column 1: syntax error while parsing value - unexpected end of input; expected '[', '{', or a literal [while parsing error]: )
Sep 22 18:29:47 apg /usr/lib/gdm-wayland-session[73270]: [2023-09-22 18:29:47.931] [net] [error] sync error: (connection: Couldn't resolve host name)
Sep 22 18:29:48 apg kernel: wlan0: authenticate with 1c:61:b4:26:ba:27
Sep 22 18:29:48 apg NetworkManager[1167]: <info>  [1695400188.9998] device (wlan0): new IWD device state is connecting
Sep 22 18:29:49 apg kernel: wlan0: send auth to 1c:61:b4:26:ba:27 (try 1/3)
Sep 22 18:29:49 apg kernel: wlan0: authenticated
Sep 22 18:29:49 apg kernel: wlan0: associate with 1c:61:b4:26:ba:27 (try 1/3)
Sep 22 18:29:49 apg kernel: wlan0: RX AssocResp from 1c:61:b4:26:ba:27 (capab=0x1811 status=0 aid=1)
Sep 22 18:29:49 apg kernel: wlan0: associated
Sep 22 18:29:49 apg iwd[1290]: [DHCPv4] l_dhcp_client_start:1219 Entering state: DHCP_STATE_SELECTING
Sep 22 18:29:49 apg iwd[1290]: [DHCPv4] dhcp_client_handle_offer:811 Entering state: DHCP_STATE_REQUESTING
Sep 22 18:29:49 apg iwd[1290]: netconfig agent call returned org.freedesktop.NetworkManager.Device.InvalidConnection("Couldn't set netconfig data")
Sep 22 18:29:49 apg iwd[1290]: [DHCPv4] dhcp_client_rx_message:915 Entering state: DHCP_STATE_BOUND
Sep 22 18:29:49 apg iwd[1290]: [DHCPv4] dhcp_client_rx_message:948 T1 expiring in 43200254 ms
Sep 22 18:29:49 apg iwd[1290]: netconfig agent call returned org.freedesktop.NetworkManager.Device.InvalidConnection("Couldn't set netconfig data")
Sep 22 18:29:49 apg iwd[1290]: [DHCPv4] l_dhcp_client_stop:1264 Entering state: DHCP_STATE_INIT
Sep 22 18:29:49 apg kernel: wlan0: deauthenticating from 1c:61:b4:26:ba:27 by local choice (Reason: 3=DEAUTH_LEAVING)
Sep 22 18:29:49 apg NetworkManager[1167]: <info>  [1695400189.3781] device (wlan0): new IWD device state is disconnecting
Sep 22 18:29:49 apg NetworkManager[1167]: <info>  [1695400189.3812] device (wlan0): new IWD device state is disconnected
Sep 22 18:29:50 apg NetworkManager[1167]: <info>  [1695400190.5312] device (wlan0): new IWD device state is connecting
Sep 22 18:29:50 apg kernel: wlan0: authenticate with 1c:61:b4:26:ba:27
Sep 22 18:29:50 apg kernel: wlan0: send auth to 1c:61:b4:26:ba:27 (try 1/3)
Sep 22 18:29:50 apg kernel: wlan0: authenticated
Sep 22 18:29:50 apg kernel: wlan0: associate with 1c:61:b4:26:ba:27 (try 1/3)
Sep 22 18:29:50 apg kernel: wlan0: RX AssocResp from 1c:61:b4:26:ba:27 (capab=0x1811 status=0 aid=1)
Sep 22 18:29:50 apg kernel: wlan0: associated
Sep 22 18:29:50 apg iwd[1290]: [DHCPv4] l_dhcp_client_start:1219 Entering state: DHCP_STATE_SELECTING
Sep 22 18:29:50 apg iwd[1290]: [DHCPv4] dhcp_client_handle_offer:811 Entering state: DHCP_STATE_REQUESTING
Sep 22 18:29:50 apg iwd[1290]: [DHCPv4] dhcp_client_rx_message:915 Entering state: DHCP_STATE_BOUND
Sep 22 18:29:50 apg iwd[1290]: [DHCPv4] dhcp_client_rx_message:948 T1 expiring in 43199963 ms

What could be wrong with my system to cause this? It goes away after rebooting.

Last edited by TheSunCat (2023-10-01 09:26:15)

TheSunCat · 2023-09-29 21:48:38

When I search Google for "Couldn't set netconfig data", this post is the sole result, which is not very encouraging haha.
The issue has come and gone for the past week, but it started again today and hasn't gone away despite rebooting multiple times.

Last edited by TheSunCat (2023-09-29 21:49:15)

V1del · 2023-09-29 23:59:07

You are mixing incompatible networkmanagers. Pick one of iwd or NetworkManager, disable the other. If your intention is to use iwd as a NetworkManager backend, do not enable the iwd service but use: https://wiki.archlinux.org/title/Networ … Fi_backend -- and do not use iwd, but networkmanager frontends to handle the connection.

Last edited by V1del (2023-09-30 00:00:04)

seth · 2023-09-30 06:41:38

Also you seem to be using https://wiki.archlinux.org/title/Iwd#En … figuration ?
That shows up a lot lately…

TheSunCat · 2023-09-30 09:00:15

Oh, I was not aware I had to use NetworkManager interfaces when using iwd as a backend! I uninstalled NetworkManager and enabled iwd.service. I was the able to connect fine, but could only connect to direct IP addresses. Enabling systemd-resolved fixed that, and I seem to have a functional internet connection again. Thanks!

TheSunCat · 2023-10-01 09:28:19

Aaaand it's back. NetworkManager is uninstalled and wpa_supplicant.service is masked, but iwd refuses to connect to anything all of a sudden. When I type `station wlan0 connect -_-` (the ssid of my network), it just prints "Operation failed" in red. There are no relevant logs printed in `systemctl status iwd` or in journalctl.

seth · 2023-10-01 13:17:24

There are no relevant logs printed in `systemctl status iwd` or in journalctl.

it just prints "Operation failed" in red

I suggest you post both, notably please post your complete system journal for the boot:

sudo journalctl -b | curl -F 'file=@-' 0x0.st

TheSunCat · 2023-10-09 20:30:32

The issue where it disconnects for a few seconds, then reconnects has come back.

Here is a complete system journal: https://0x0.st/H4o5.txt

systemctl status iwd (it only shows the end of the log):

 15 Oct 09 22:27:40 apg iwd[1254]: [DHCPv4] dhcp_client_handle_offer:796 Entering state: DHCP_STATE_REQUESTING
 16 Oct 09 22:27:40 apg iwd[1254]: [DHCPv4] dhcp_client_receive_offer:777 Server sent another offer, using it instead
 17 Oct 09 22:27:40 apg iwd[1254]: [DHCPv4] dhcp_client_handle_offer:796 Entering state: DHCP_STATE_REQUESTING
 18 Oct 09 22:27:40 apg iwd[1254]: [DHCPv4] dhcp_client_rx_message:900 Entering state: DHCP_STATE_BOUND
 19 Oct 09 22:27:40 apg iwd[1254]: [DHCPv4] dhcp_client_rx_message:933 T1 expiring in 43199229 ms
 20 Oct 09 22:27:46 apg iwd[1254]: [DHCPv4] l_dhcp_client_stop:1249 Entering state: DHCP_STATE_INIT
 21 Oct 09 22:27:46 apg iwd[1254]: [DHCPv4] l_dhcp_client_start:1204 Entering state: DHCP_STATE_SELECTING
 22 Oct 09 22:27:46 apg iwd[1254]: [DHCPv4] dhcp_client_handle_offer:796 Entering state: DHCP_STATE_REQUESTING
 23 Oct 09 22:27:46 apg iwd[1254]: [DHCPv4] dhcp_client_rx_message:900 Entering state: DHCP_STATE_BOUND
 24 Oct 09 22:27:46 apg iwd[1254]: [DHCPv4] dhcp_client_rx_message:933 T1 expiring in 43199545 ms

seth · 2023-10-09 20:46:14

There're ~140 incidents of

Oct 09 11:30:17 apg kernel: wlan0: disconnect from AP a0:b5:3c:ec:04:f4 for new auth to a0:b5:3c:ec:04:f0
Oct 09 11:31:17 apg kernel: wlan0: disconnect from AP a0:b5:3c:ec:04:f0 for new auth to a0:b5:3c:ec:04:f4

so you're likely cycling between 2.4GHz and 5GHz ?

Edit:

Oct 09 11:29:54 archlinux kernel: Linux version 6.5.5-201.fsync.fc38.x86_64 (mockbuild@f0c5d13468a743b7b69e2fa336ac8d63) (gcc (GCC) 13.2.1 20230728 (Red Hat 13.2.1-1), GNU ld version 2.39-9.fc38) #1 SMP PREEMPT_DYNAMIC Sun Oct  1 17:23:57 UTC 2023
Oct 09 11:29:54 archlinux kernel: Loaded X.509 cert 'Fedora kernel signing key: 1f248320ef8424b76bfa7a09b4b21ffc010d59b3'
Oct 09 11:29:54 archlinux kernel: Loaded X.509 cert 'Fedora kernel signing key: 1f248320ef8424b76bfa7a09b4b21ffc010d59b3'
Oct 09 11:29:54 archlinux kernel: Loaded X.509 cert 'Fedora kernel signing key: 1f248320ef8424b76bfa7a09b4b21ffc010d59b3'
Oct 09 11:29:54 archlinux kernel: Loaded X.509 cert 'Fedora kernel signing key: 1f248320ef8424b76bfa7a09b4b21ffc010d59b3'

Why are you booting a fedora kernel?

Last edited by seth (2023-10-09 20:48:33)

TheSunCat · 2023-10-09 20:47:47

That sounds correct. The disconnects last a few seconds, which is about how long it takes it to connect to a network. Can I disable this behavior in iwd?

seth · 2023-10-09 20:49:44

https://wiki.archlinux.org/title/Iwd#iwd_keeps_roaming

TheSunCat · 2023-10-09 20:52:04

Thanks! I see my current RSSI is -70dBm (this router is not ideal). I changed the thresholds to -100 and -110, let's hope this stops it!

TheSunCat · 2023-10-12 16:58:57

Nope, it's back to roaming. It seemed to stop it for a while, but it started doing it again today.
Just this over and over in dmesg:

[ 4211.716026] wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by a0:b5:3c:ec:04:f0
[ 4255.848527] wlan0: Connection to AP a0:b5:3c:ec:04:f0 lost
[ 4257.534757] wlan0: authenticate with a0:b5:3c:ec:04:f0
[ 4257.534791] wlan0: 80 MHz not supported, disabling VHT
[ 4257.547472] wlan0: send auth to a0:b5:3c:ec:04:f0 (try 1/3)
[ 4257.649686] wlan0: send auth to a0:b5:3c:ec:04:f0 (try 2/3)
[ 4257.721454] wlan0: send auth to a0:b5:3c:ec:04:f0 (try 3/3)
[ 4257.842283] wlan0: authentication with a0:b5:3c:ec:04:f0 timed out
[ 4276.462959] wlan0: authenticate with a0:b5:3c:ec:04:f0
[ 4276.462978] wlan0: 80 MHz not supported, disabling VHT
[ 4276.469265] wlan0: send auth to a0:b5:3c:ec:04:f0 (try 1/3)
[ 4276.532229] wlan0: authenticated
[ 4276.532786] wlan0: associate with a0:b5:3c:ec:04:f0 (try 1/3)
[ 4276.551570] wlan0: RX AssocResp from a0:b5:3c:ec:04:f0 (capab=0x1411 status=0 aid=20)
[ 4276.566613] wlan0: associated
[ 4278.057753] wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by a0:b5:3c:ec:04:f0

Also, just saw your question about the Fedora kernel. I'm using aur/linux-fsync-nobara-bin; it seems to reduce the random freezing & sleep issues my laptop suffers from. I have only had a couple freezes since switching to it, where they used to happen daily on the default kernel.

seth · 2023-10-12 20:07:09

https://man.archlinux.org/man/iwd.config.5#Rank

Unfortunately it seems not possible to connect to a BSSID w/ iwctl

Arch Linux

#1 2023-09-22 16:41:56

Intermittent wifi issues with iwd

#2 2023-09-29 21:48:38

Re: Intermittent wifi issues with iwd

#3 2023-09-29 23:59:07

Re: Intermittent wifi issues with iwd

#4 2023-09-30 06:41:38

Re: Intermittent wifi issues with iwd

#5 2023-09-30 09:00:15

Re: Intermittent wifi issues with iwd

#6 2023-10-01 09:28:19

Re: Intermittent wifi issues with iwd

#7 2023-10-01 13:17:24

Re: Intermittent wifi issues with iwd

#8 2023-10-09 20:30:32

Re: Intermittent wifi issues with iwd

#9 2023-10-09 20:46:14

Re: Intermittent wifi issues with iwd

#10 2023-10-09 20:47:47

Re: Intermittent wifi issues with iwd

#11 2023-10-09 20:49:44

Re: Intermittent wifi issues with iwd

#12 2023-10-09 20:52:04

Re: Intermittent wifi issues with iwd

#13 2023-10-12 16:58:57

Re: Intermittent wifi issues with iwd

#14 2023-10-12 20:07:09

Re: Intermittent wifi issues with iwd

Board footer