dhcpcd upgrade breaks netctl wlan access (rpi4 v11 and v15)

[jayuu]

EDIT - 2023.11.15: Looks to be resolved with release of dhcpcd 10.0.5-1

Following running a pacman -Syu (as of 2023.10.29) dhcpcd 10.0.4-2 seems to have broken netctl, no longer enabling wireless access.

Edit following comment from PhilB38: This is with WLAN access only.

Copies of journalctl from both the failed upgrade (following a pacman -Syu) and following a downgrade of dhcpcd to 10.0.2-1 using a previous cached pkg are listed below:

Appreciate any insight into this, I've currently got dhcpcd as an exception in pacman.conf.

---------------------------------------------------------------------------------------
Journalctl following update to dhcpcd 10.0.4-2 and restart of netctl profile.

Oct 30 22:03:22 pi4v11 systemd[1]: Starting A simple WPA encrypted wireless connection...
Oct 30 22:03:22 pi4v11 network[1031]: Starting network profile '<my_profile_name>'...
Oct 30 22:03:22 pi4v11 systemd-networkd[1006]: wlan0: Link UP
Oct 30 22:03:22 pi4v11 systemd[1]: Started A simple WPA encrypted wireless connection.
Oct 30 22:03:26 pi4v11 kernel: net_ratelimit: 1 callbacks suppressed
Oct 30 22:03:26 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0x100e fail, reason -52
Oct 30 22:03:26 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd022 fail, reason -52
Oct 30 22:03:26 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd026 fail, reason -52
Oct 30 22:03:26 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd02a fail, reason -52
Oct 30 22:03:26 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd02e fail, reason -52
Oct 30 22:03:28 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd090 fail, reason -52
Oct 30 22:03:28 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd095 fail, reason -52
Oct 30 22:03:28 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd099 fail, reason -52
Oct 30 22:03:28 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd09d fail, reason -52
Oct 30 22:03:28 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd0a1 fail, reason -52
Oct 30 22:03:37 pi4v11 kernel: net_ratelimit: 1 callbacks suppressed
Oct 30 22:03:37 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0x100e fail, reason -52
Oct 30 22:03:37 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd022 fail, reason -52
Oct 30 22:03:37 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd026 fail, reason -52
Oct 30 22:03:37 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd02a fail, reason -52
Oct 30 22:03:37 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd02e fail, reason -52
Oct 30 22:03:39 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd090 fail, reason -52
Oct 30 22:03:39 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd095 fail, reason -52
Oct 30 22:03:39 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd099 fail, reason -52
Oct 30 22:03:39 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd09d fail, reason -52
Oct 30 22:03:39 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd0a1 fail, reason -52
Oct 30 22:03:39 pi4v11 systemd-networkd[1006]: wlan0: Connected WiFi access point: <My_SSID> (<ssid_code>)
Oct 30 22:03:39 pi4v11 systemd-networkd[1006]: wlan0: Gained carrier
Oct 30 22:03:39 pi4v11 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Oct 30 22:03:39 pi4v11 systemd-timesyncd[417]: Network configuration changed, trying to establish connection.
Oct 30 22:03:39 pi4v11 dhcpcd[1360]: dhcpcd-10.0.4 starting
Oct 30 22:03:39 pi4v11 dhcpcd[1363]: DUID <my_DUID>:<my_IAID>
Oct 30 22:03:39 pi4v11 dhcpcd[1363]: wlan0: connected to Access Point: <My_SSID>
Oct 30 22:03:39 pi4v11 dhcpcd[1363]: wlan0: IAID <my_IAID>
Oct 30 22:03:39 pi4v11 dhcpcd[1363]: wlan0: rebinding lease of 192.168.1.141
Oct 30 22:03:41 pi4v11 systemd-networkd[1006]: wlan0: Gained IPv6LL
Oct 30 22:03:41 pi4v11 systemd-timesyncd[417]: Network configuration changed, trying to establish connection.
Oct 30 22:03:43 pi4v11 dhcpcd[1363]: wlan0: leased 192.168.1.141 for 86399 seconds
Oct 30 22:03:43 pi4v11 dhcpcd[1363]: wlan0: adding route to 192.168.1.0/24
Oct 30 22:03:43 pi4v11 dhcpcd[1363]: wlan0: adding default route via 192.168.1.254
Oct 30 22:03:43 pi4v11 kernel: audit: type=1326 audit(1698703423.334:3): auid=4294967295 uid=974 gid=974 ses=4294967295 pid=1362 comm="dhcpcd" exe="/usr/bin/dhcpcd" sig=31 arch=c00000b7 syscall=24 compat=0 ip=0xffffb3cecb4c code=0x0
Oct 30 22:03:43 pi4v11 systemd[1]: Started Process Core Dump (PID 1381/UID 0).
Oct 30 22:03:43 pi4v11 systemd-coredump[1382]: Process 1362 (dhcpcd) of user 974 dumped core.
Stack trace of thread 1362:
#0 0x0000ffffb3cecb4c __dup2 (libc.so.6 + 0xdcb4c)
#1 0x0000aaaac01f0680 dhcpcd_daemonised (dhcpcd + 0x10680)
#2 0x0000aaaac02230b4 ps_daemonised (dhcpcd + 0x430b4)
#3 0x0000aaaac01f07a8 dhcpcd_daemonise (dhcpcd + 0x107a8)
#4 0x0000aaaac0210120 ipv4_applyaddr (dhcpcd + 0x30120)
#5 0x0000aaaac020aa58 dhcp_bind (dhcpcd + 0x2aa58)
#6 0x0000aaaac020e30c dhcp_packet (dhcpcd + 0x2e30c)
#7 0x0000aaaac0227cc4 ps_bpf_dispatch (dhcpcd + 0x47cc4)
#8 0x0000aaaac02240fc n/a (dhcpcd + 0x440fc)
#9 0x0000aaaac0223290 ps_recvpsmsg (dhcpcd + 0x43290)
#10 0x0000aaaac0223f1c n/a (dhcpcd + 0x43f1c)
#11 0x0000aaaac01f4130 eloop_start (dhcpcd + 0x14130)
#12 0x0000aaaac01ec4b4 main (dhcpcd + 0xc4b4)
#13 0x0000ffffb3c37b80 n/a (libc.so.6 + 0x27b80)
#14 0x0000ffffb3c37c60 __libc_start_main (libc.so.6 + 0x27c60)
#15 0x0000aaaac01ed5f0 _start (dhcpcd + 0xd5f0)
ELF object binary architecture: AARCH64
Oct 30 22:03:43 pi4v11 network[1360]: dhcpcd_fork_cb: truncated read 0 (expected 4)
Oct 30 22:03:43 pi4v11 network[1031]: DHCP IPv4 lease attempt failed on interface 'wlan0'
Oct 30 22:03:43 pi4v11 systemd[1]:make_clickable_callback(MAGIC_URL_EMAIL, ' ',make_clickable_callback(MAGIC_URL_EMAIL, ' ',make_clickable_callback(MAGIC_URL_EMAIL, ' ',make_clickable_callback(MAGIC_URL_EMAIL, ' ',make_clickable_callback(MAGIC_URL_EMAIL, ' ',make_clickable_callback(MAGIC_URL_EMAIL, ' ',make_clickable_callback(MAGIC_URL_EMAIL, ' ',make_clickable_callback(MAGIC_URL_EMAIL, ' ',make_clickable_callback(MAGIC_URL_EMAIL, ' ', '''''''''systemd-coredump@1-1381-0.service', '', '')', '', '')', '', '')', '', '')', '', '')', '', '')', '', '')', '', '')', '', ''): Deactivated successfully.
Oct 30 22:03:43 pi4v11 systemd-networkd[1006]: wlan0: Lost carrier
Oct 30 22:03:43 pi4v11 systemd-networkd[1006]: wlan0: Gained carrier
Oct 30 22:03:43 pi4v11 systemd-networkd[1006]: wlan0: Unmanaging interface.
Oct 30 22:03:43 pi4v11 systemd-timesyncd[417]: Network configuration changed, trying to establish connection.
Oct 30 22:03:44 pi4v11 systemd-networkd[1006]: wlan0: Link DOWN
Oct 30 22:03:44 pi4v11 systemd-networkd[1006]: wlan0: Lost carrier
Oct 30 22:03:44 pi4v11 network[1031]: Failed to bring the network up for profile '<my_profile_name>'
Oct 30 22:03:44 pi4v11 systemd[1]: netctl@<my_profile_name>.service: Main process exited, code=exited, status=1/FAILURE
Oct 30 22:03:44 pi4v11 systemd[1]: netctl@<my_profile_name>.service: Failed with result 'exit-code'.
Oct 30 22:03:44 pi4v11 systemd[1]: netctl@<my_profile_name>.service: Consumed 1.232s CPU time.

--------------------------------------------------------------------------------------------
Journalctl following downgrade to dhcpcd 10.0.2-1 and restart of netctl profile.

Oct 30 22:33:56 pi4v11 systemd[1]: Starting A simple WPA encrypted wireless connection...
Oct 30 22:33:56 pi4v11 network[2164]: Starting network profile '<my_profile_name>'...
Oct 30 22:33:56 pi4v11 systemd-networkd[1006]: wlan0: Link UP
Oct 30 22:33:56 pi4v11 systemd[1]: Started A simple WPA encrypted wireless connection.
Oct 30 22:34:00 pi4v11 kernel: net_ratelimit: 1 callbacks suppressed
Oct 30 22:34:00 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0x100e fail, reason -52
Oct 30 22:34:00 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd022 fail, reason -52
Oct 30 22:34:00 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd026 fail, reason -52
Oct 30 22:34:01 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd02a fail, reason -52
Oct 30 22:34:01 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd02e fail, reason -52
Oct 30 22:34:02 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd090 fail, reason -52
Oct 30 22:34:02 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd095 fail, reason -52
Oct 30 22:34:02 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd099 fail, reason -52
Oct 30 22:34:02 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd09d fail, reason -52
Oct 30 22:34:02 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd0a1 fail, reason -52
Oct 30 22:34:11 pi4v11 kernel: net_ratelimit: 1 callbacks suppressed
Oct 30 22:34:11 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0x100e fail, reason -52
Oct 30 22:34:11 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd022 fail, reason -52
Oct 30 22:34:11 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd026 fail, reason -52
Oct 30 22:34:11 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd02a fail, reason -52
Oct 30 22:34:11 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd02e fail, reason -52
Oct 30 22:34:13 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd090 fail, reason -52
Oct 30 22:34:13 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd095 fail, reason -52
Oct 30 22:34:13 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd099 fail, reason -52
Oct 30 22:34:13 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd09d fail, reason -52
Oct 30 22:34:13 pi4v11 kernel: brcmfmac: cfg80211_set_channel: set chanspec 0xd0a1 fail, reason -52
Oct 30 22:34:13 pi4v11 systemd-networkd[1006]: wlan0: Connected WiFi access point: <my_ssid> (<ssid_code>)
Oct 30 22:34:13 pi4v11 systemd-networkd[1006]: wlan0: Gained carrier
Oct 30 22:34:13 pi4v11 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Oct 30 22:34:13 pi4v11 systemd-timesyncd[417]: Network configuration changed, trying to establish connection.
Oct 30 22:34:13 pi4v11 dhcpcd[2487]: dhcpcd-10.0.2 starting
Oct 30 22:34:13 pi4v11 dhcpcd[2490]: DUID <my_DUID>:<my_iaid>
Oct 30 22:34:13 pi4v11 dhcpcd[2490]: wlan0: connected to Access Point: <my_ssid>
Oct 30 22:34:13 pi4v11 dhcpcd[2490]: wlan0: IAID <my_iaid>
Oct 30 22:34:14 pi4v11 dhcpcd[2490]: wlan0: rebinding lease of 192.168.1.141
Oct 30 22:34:15 pi4v11 systemd-networkd[1006]: wlan0: Gained IPv6LL
Oct 30 22:34:15 pi4v11 systemd-timesyncd[417]: Network configuration changed, trying to establish connection.
Oct 30 22:34:19 pi4v11 network[2487]: wlan0: DHCP lease expired
Oct 30 22:34:19 pi4v11 dhcpcd[2490]: wlan0: DHCP lease expired
Oct 30 22:34:19 pi4v11 systemd-timesyncd[417]: Network configuration changed, trying to establish connection.
Oct 30 22:34:19 pi4v11 dhcpcd[2490]: wlan0: soliciting a DHCP lease
Oct 30 22:34:21 pi4v11 dhcpcd[2490]: wlan0: offered 192.168.1.141 from 192.168.1.254
Oct 30 22:34:21 pi4v11 dhcpcd[2490]: wlan0: probing address 192.168.1.141/24
Oct 30 22:34:26 pi4v11 dhcpcd[2490]: wlan0: leased 192.168.1.141 for 86400 seconds
Oct 30 22:34:26 pi4v11 systemd-timesyncd[417]: Network configuration changed, trying to establish connection.
Oct 30 22:34:26 pi4v11 dhcpcd[2490]: wlan0: adding route to 192.168.1.0/24
Oct 30 22:34:26 pi4v11 dhcpcd[2490]: wlan0: adding default route via 192.168.1.254
Oct 30 22:34:26 pi4v11 network[2164]: Started network profile '<my_profile_name>'
Oct 30 22:34:31 pi4v11 systemd-timesyncd[417]: Contacted time server 195.171.43.12:123 (0.arch.pool.ntp.org).
Oct 30 22:35:34 pi4v11 dbus-daemon[431]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.23' (uid=0 pid=2596 comm="timedatectl")
Oct 30 22:35:34 pi4v11 systemd[1]: Starting Time & Date Service...
Oct 30 22:35:34 pi4v11 dbus-daemon[431]: [system] Successfully activated service 'org.freedesktop.timedate1'
Oct 30 22:35:34 pi4v11 systemd[1]: Started Time & Date Service.
Oct 30 22:36:04 pi4v11 systemd[1]: systemd-timedated.service: Deactivated successfully.

[PhilB38]

I do have the same issue on a rpi3b, where dhcpcd-10.0.4-1 coredumps when trying to connect on WLAN only, but works when using a wired connexion (or both).
Reinstalling dhcpcd-10.0.3-1 resolves the issue.

[jayuu]

Thanks for the comment - this looks to now be resolved with the release of dhcpcd 10.0.5-1