Arch Linux

Description:

After updating to dhcpcd 9.0.0-2 from 8.1.7-1, NetworkManager fails to lease an IPv4 address via DHCP.

relevant journal logs:

Apr 12 14:07:18 lenny NetworkManager[803]: <info> [1586693238.0959] policy: auto-activating connection 'DHCP Ethernet' (6d37eafb-68ac-3cf2-bbe2-30d0a38e4a94)
Apr 12 14:07:18 lenny NetworkManager[803]: <info> [1586693238.0964] device (enp3s0): Activation: starting connection 'DHCP Ethernet' (6d37eafb-68ac-3cf2-bbe2-30d0a38e4a94)
Apr 12 14:07:18 lenny NetworkManager[803]: <info> [1586693238.0966] device (enp3s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Apr 12 14:07:18 lenny NetworkManager[803]: <info> [1586693238.0970] manager: NetworkManager state is now CONNECTING
Apr 12 14:07:18 lenny NetworkManager[803]: <info> [1586693238.0973] device (enp3s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Apr 12 14:07:18 lenny NetworkManager[803]: <info> [1586693238.0980] device (enp3s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Apr 12 14:07:18 lenny NetworkManager[803]: <info> [1586693238.0987] dhcp4 (enp3s0): activation: beginning transaction (timeout in 45 seconds)
Apr 12 14:07:18 lenny NetworkManager[803]: <info> [1586693238.1005] dhcp4 (enp3s0): dhcpcd started with pid 856
Apr 12 14:07:18 lenny dhcpcd[856]: ps_init: /var/lib/dhcpcd/var/lib/dhcpcd: Operation not permitted
Apr 12 14:07:18 lenny NetworkManager[803]: <warn> [1586693238.1280] dhcp-listener: dhcp-event: didn't have associated interface.
Apr 12 14:07:18 lenny dhcpcd[862]: if_learnaddrs: if_addrflags6: ::1%lo: No such file or directory
Apr 12 14:07:18 lenny dhcpcd[862]: if_learnaddrs: if_addrflags6: fe80::f71f:b77d:3530:14d%enp3s0: No such file or directory
Apr 12 14:07:18 lenny NetworkManager[803]: <warn> [1586693238.1460] dhcp-listener: dhcp-event: (pid 862) unhandled DHCP event for interface enp3s0
Apr 12 14:07:18 lenny dhcpcd[862]: /var/lib/dhcpcd/duid: Permission denied
Apr 12 14:07:18 lenny dhcpcd[862]: DUID 00:03:00:01:54:ee:75:fe:7e:c9
Apr 12 14:07:18 lenny dhcpcd[862]: enp3s0: IAID 75:fe:7e:c9
Apr 12 14:07:19 lenny dhcpcd[862]: enp3s0: soliciting a DHCP lease
Apr 12 14:07:20 lenny dhcpcd[862]: enp3s0: leased 10.0.0.1 for 86400 seconds
Apr 12 14:07:20 lenny dhcpcd[862]: dhcp_bind: Permission denied
Apr 12 14:07:20 lenny dhcpcd[862]: enp3s0: adding route to 10.0.0.0/24
Apr 12 14:07:20 lenny NetworkManager[803]: <warn> [1586693240.9901] dhcp-listener: dhcp-event: (pid 862) unhandled DHCP event for interface enp3s0

Steps to reproduce:

I only ever observed this on my own machine with a regular default DHCP ethernet connection config. I didn't try this on a different machine yet.
Note: I removed irrelevant log entries from the wifi, which tried to connect in parallel. If needed I can set up a clean config and try to provide fresh logs.