dhcp discover times out

redmaw · 2017-05-27 01:43:03

For some reason after finishing the installation process and rebooting Arch is unable to get an IP address from my router. My router is old, and was simple even back then, so all the visibility I have from it is that as far as it is concerned no one is asking for an IP address. After messing with it for a while not making any progress I decided to give up and switch to Fedora instead, only to run into the same issue so now I am back to trying to solve it. The log below is from the Fedora live system but it is basically the same on Arch. I do not really understand all the information, what little there really is, but "eno1.IPv6" stands out because I don't think my router supports IPv6 so if it is only trying to connect via IPv6 that could explain the issue. For what it's worth Arch has managed to get an IP address 1/3 (2/6 times) of the time. Does anyone have suggestions on where to look for more information to figure out what is going on?

May 26 17:54:43 fedora-4770k dhclient[3898]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 8 (xid=0x1bf3bf10)
May 26 17:54:43 fedora-4770k anaconda[2181]: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fe2edf2e048> status to: Connecting...
May 26 17:54:44 fedora-4770k avahi-daemon[1154]: Joining mDNS multicast group on interface eno1.IPv6 with address fe80::da50:e6ff:fec0:5de7.
May 26 17:54:44 fedora-4770k avahi-daemon[1154]: New relevant interface eno1.IPv6 for mDNS.
May 26 17:54:44 fedora-4770k avahi-daemon[1154]: Registering new address record for fe80::da50:e6ff:fec0:5de7 on eno1.*.
May 26 17:54:51 fedora-4770k dhclient[3898]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 11 (xid=0x1bf3bf10)
May 26 17:55:02 fedora-4770k dhclient[3898]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 13 (xid=0x1bf3bf10)
^[[BMay 26 17:55:15 fedora-4770k dhclient[3898]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 14 (xid=0x1bf3bf10)
May 26 17:55:28 fedora-4770k NetworkManager[1262]: <warn>  [1495835728.1806] dhcp4 (eno1): request timed out
May 26 17:55:28 fedora-4770k NetworkManager[1262]: <info>  [1495835728.1807] dhcp4 (eno1): state changed unknown -> timeout
May 26 17:55:28 fedora-4770k NetworkManager[1262]: <info>  [1495835728.1969] dhcp4 (eno1): canceled DHCP transaction, DHCP client pid 3898
May 26 17:55:28 fedora-4770k NetworkManager[1262]: <info>  [1495835728.1970] dhcp4 (eno1): state changed timeout -> done
May 26 17:55:28 fedora-4770k NetworkManager[1262]: <info>  [1495835728.1973] device (eno1): state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
May 26 17:55:28 fedora-4770k NetworkManager[1262]: <info>  [1495835728.1975] manager: NetworkManager state is now DISCONNECTED
May 26 17:55:28 fedora-4770k NetworkManager[1262]: <info>  [1495835728.1976] policy: disabling autoconnect for connection 'eno1'.
May 26 17:55:28 fedora-4770k NetworkManager[1262]: <warn>  [1495835728.1979] device (eno1): Activation: failed for connection 'eno1'
May 26 17:55:28 fedora-4770k NetworkManager[1262]: <info>  [1495835728.1984] device (eno1): state change: failed -> disconnected (reason 'none') [120 30 0]
May 26 17:55:28 fedora-4770k avahi-daemon[1154]: Withdrawing address record for fe80::da50:e6ff:fec0:5de7 on eno1.
May 26 17:55:28 fedora-4770k avahi-daemon[1154]: Leaving mDNS multicast group on interface eno1.IPv6 with address fe80::da50:e6ff:fec0:5de7.
May 26 17:55:28 fedora-4770k avahi-daemon[1154]: Interface eno1.IPv6 no longer relevant for mDNS.
May 26 17:55:28 fedora-4770k audit: NETFILTER_CFG table=filter family=2 entries=125
May 26 17:55:28 fedora-4770k audit: NETFILTER_CFG table=nat family=2 entries=74
May 26 17:55:28 fedora-4770k audit: NETFILTER_CFG table=mangle family=2 entries=51
May 26 17:55:28 fedora-4770k audit: NETFILTER_CFG table=filter family=10 entries=127
May 26 17:55:28 fedora-4770k audit: NETFILTER_CFG table=nat family=10 entries=74
May 26 17:55:28 fedora-4770k audit: NETFILTER_CFG table=mangle family=10 entries=51
May 26 17:55:28 fedora-4770k anaconda[2181]: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fe2edf2e048> status to: Not connected
May 26 17:55:28 fedora-4770k anaconda[2181]: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fe2edf2e048> status to: Not connected
May 26 17:55:46 fedora-4770k systemd[1]: Starting Cleanup of Temporary Directories...
May 26 17:55:46 fedora-4770k systemd[1]: Started Cleanup of Temporary Directories.
May 26 17:55:46 fedora-4770k audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 26 17:55:46 fedora-4770k audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-tmpfiles-clean comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 26 17:56:08 fedora-4770k dbus-daemon[1129]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.104' (uid=1000 pid=3853 comm="gnome-control-center --overview " label="unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023")
May 26 17:56:08 fedora-4770k systemd[1]: Starting Hostname Service...
May 26 17:56:08 fedora-4770k dbus-daemon[1129]: [system] Successfully activated service 'org.freedesktop.hostname1'
May 26 17:56:08 fedora-4770k systemd[1]: Started Hostname Service.
May 26 17:56:08 fedora-4770k audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 26 17:56:23 fedora-4770k NetworkManager[1262]: <info>  [1495835783.7498] device (eno1): Activation: starting connection 'eno1' (37147987-cc72-308a-9337-7f3d2f40e16e)
May 26 17:56:23 fedora-4770k NetworkManager[1262]: <info>  [1495835783.7501] audit: op="connection-activate" uuid="37147987-cc72-308a-9337-7f3d2f40e16e" name="eno1" pid=3853 uid=1000 result="success"
May 26 17:56:23 fedora-4770k NetworkManager[1262]: <info>  [1495835783.7501] device (eno1): state change: disconnected -> prepare (reason 'none') [30 40 0]
May 26 17:56:23 fedora-4770k NetworkManager[1262]: <info>  [1495835783.7502] manager: NetworkManager state is now CONNECTING
May 26 17:56:23 fedora-4770k NetworkManager[1262]: <info>  [1495835783.7503] device (eno1): state change: prepare -> config (reason 'none') [40 50 0]
May 26 17:56:23 fedora-4770k audit: NETFILTER_CFG table=filter family=2 entries=122
May 26 17:56:23 fedora-4770k audit: NETFILTER_CFG table=nat family=2 entries=72
May 26 17:56:23 fedora-4770k audit: NETFILTER_CFG table=mangle family=2 entries=50
May 26 17:56:23 fedora-4770k audit: NETFILTER_CFG table=filter family=10 entries=124
May 26 17:56:23 fedora-4770k audit: NETFILTER_CFG table=nat family=10 entries=72
May 26 17:56:23 fedora-4770k audit: NETFILTER_CFG table=mangle family=10 entries=50
May 26 17:56:23 fedora-4770k NetworkManager[1262]: <info>  [1495835783.7623] device (eno1): state change: config -> ip-config (reason 'none') [50 70 0]
May 26 17:56:23 fedora-4770k NetworkManager[1262]: <info>  [1495835783.7625] dhcp4 (eno1): activation: beginning transaction (timeout in 45 seconds)
May 26 17:56:23 fedora-4770k NetworkManager[1262]: <info>  [1495835783.7637] dhcp4 (eno1): dhclient started with pid 3969
May 26 17:56:23 fedora-4770k dhclient[3969]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 6 (xid=0xb361c65f)
May 26 17:56:23 fedora-4770k anaconda[2181]: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fe2edf2e048> status to: Connecting...
May 26 17:56:25 fedora-4770k avahi-daemon[1154]: Joining mDNS multicast group on interface eno1.IPv6 with address fe80::da50:e6ff:fec0:5de7.
May 26 17:56:25 fedora-4770k avahi-daemon[1154]: New relevant interface eno1.IPv6 for mDNS.
May 26 17:56:25 fedora-4770k avahi-daemon[1154]: Registering new address record for fe80::da50:e6ff:fec0:5de7 on eno1.*.
May 26 17:56:29 fedora-4770k dhclient[3969]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 8 (xid=0xb361c65f)
May 26 17:56:37 fedora-4770k dhclient[3969]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 9 (xid=0xb361c65f)
May 26 17:56:38 fedora-4770k audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 26 17:56:46 fedora-4770k dhclient[3969]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 7 (xid=0xb361c65f)
May 26 17:56:53 fedora-4770k dhclient[3969]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 10 (xid=0xb361c65f)
May 26 17:57:03 fedora-4770k dhclient[3969]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 15 (xid=0xb361c65f)
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <warn>  [1495835829.1708] dhcp4 (eno1): request timed out
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.1710] dhcp4 (eno1): state changed unknown -> timeout
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.2032] dhcp4 (eno1): canceled DHCP transaction, DHCP client pid 3969
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.2033] dhcp4 (eno1): state changed timeout -> done
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.2036] device (eno1): state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.2038] manager: NetworkManager state is now DISCONNECTED
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <warn>  [1495835829.2042] device (eno1): Activation: failed for connection 'eno1'
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.2050] device (eno1): state change: failed -> disconnected (reason 'none') [120 30 0]
May 26 17:57:09 fedora-4770k avahi-daemon[1154]: Withdrawing address record for fe80::da50:e6ff:fec0:5de7 on eno1.
May 26 17:57:09 fedora-4770k avahi-daemon[1154]: Leaving mDNS multicast group on interface eno1.IPv6 with address fe80::da50:e6ff:fec0:5de7.
May 26 17:57:09 fedora-4770k avahi-daemon[1154]: Interface eno1.IPv6 no longer relevant for mDNS.
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.2080] policy: auto-activating connection 'eno1'
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.2093] device (eno1): Activation: starting connection 'eno1' (37147987-cc72-308a-9337-7f3d2f40e16e)
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.2095] device (eno1): state change: disconnected -> prepare (reason 'none') [30 40 0]
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.2097] manager: NetworkManager state is now CONNECTING
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.2102] device (eno1): state change: prepare -> config (reason 'none') [40 50 0]
May 26 17:57:09 fedora-4770k audit: NETFILTER_CFG table=filter family=2 entries=125
May 26 17:57:09 fedora-4770k audit: NETFILTER_CFG table=nat family=2 entries=74
May 26 17:57:09 fedora-4770k audit: NETFILTER_CFG table=mangle family=2 entries=51
May 26 17:57:09 fedora-4770k audit: NETFILTER_CFG table=filter family=10 entries=127
May 26 17:57:09 fedora-4770k audit: NETFILTER_CFG table=nat family=10 entries=74
May 26 17:57:09 fedora-4770k audit: NETFILTER_CFG table=mangle family=10 entries=51
May 26 17:57:09 fedora-4770k audit: NETFILTER_CFG table=filter family=2 entries=122
May 26 17:57:09 fedora-4770k audit: NETFILTER_CFG table=nat family=2 entries=72
May 26 17:57:09 fedora-4770k audit: NETFILTER_CFG table=mangle family=2 entries=50
May 26 17:57:09 fedora-4770k audit: NETFILTER_CFG table=filter family=10 entries=124
May 26 17:57:09 fedora-4770k audit: NETFILTER_CFG table=nat family=10 entries=72
May 26 17:57:09 fedora-4770k audit: NETFILTER_CFG table=mangle family=10 entries=50
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.2339] device (eno1): state change: config -> ip-config (reason 'none') [50 70 0]
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.2341] dhcp4 (eno1): activation: beginning transaction (timeout in 45 seconds)
May 26 17:57:09 fedora-4770k NetworkManager[1262]: <info>  [1495835829.2348] dhcp4 (eno1): dhclient started with pid 3987
May 26 17:57:09 fedora-4770k anaconda[2181]: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fe2edf2e048> status to: Connecting...
May 26 17:57:09 fedora-4770k anaconda[2181]: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fe2edf2e048> status to: Connecting...
May 26 17:57:09 fedora-4770k dhclient[3987]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 4 (xid=0x72701351)
May 26 17:57:10 fedora-4770k avahi-daemon[1154]: Joining mDNS multicast group on interface eno1.IPv6 with address fe80::da50:e6ff:fec0:5de7.
May 26 17:57:10 fedora-4770k avahi-daemon[1154]: New relevant interface eno1.IPv6 for mDNS.
May 26 17:57:10 fedora-4770k avahi-daemon[1154]: Registering new address record for fe80::da50:e6ff:fec0:5de7 on eno1.*.
May 26 17:57:13 fedora-4770k dhclient[3987]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 5 (xid=0x72701351)
May 26 17:57:18 fedora-4770k dhclient[3987]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 8 (xid=0x72701351)
May 26 17:57:27 fedora-4770k dhclient[3987]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 8 (xid=0x72701351)
May 26 17:57:35 fedora-4770k dhclient[3987]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 8 (xid=0x72701351)
May 26 17:57:43 fedora-4770k dhclient[3987]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 19 (xid=0x72701351)
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <warn>  [1495835874.1801] dhcp4 (eno1): request timed out
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.1804] dhcp4 (eno1): state changed unknown -> timeout
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.1965] dhcp4 (eno1): canceled DHCP transaction, DHCP client pid 3987
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.1965] dhcp4 (eno1): state changed timeout -> done
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.1969] device (eno1): state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.1971] manager: NetworkManager state is now DISCONNECTED
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <warn>  [1495835874.1975] device (eno1): Activation: failed for connection 'eno1'
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.1987] device (eno1): state change: failed -> disconnected (reason 'none') [120 30 0]
May 26 17:57:54 fedora-4770k avahi-daemon[1154]: Withdrawing address record for fe80::da50:e6ff:fec0:5de7 on eno1.
May 26 17:57:54 fedora-4770k avahi-daemon[1154]: Leaving mDNS multicast group on interface eno1.IPv6 with address fe80::da50:e6ff:fec0:5de7.
May 26 17:57:54 fedora-4770k avahi-daemon[1154]: Interface eno1.IPv6 no longer relevant for mDNS.
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.2014] policy: auto-activating connection 'eno1'
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.2043] device (eno1): Activation: starting connection 'eno1' (37147987-cc72-308a-9337-7f3d2f40e16e)
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.2045] device (eno1): state change: disconnected -> prepare (reason 'none') [30 40 0]
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.2047] manager: NetworkManager state is now CONNECTING
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.2053] device (eno1): state change: prepare -> config (reason 'none') [40 50 0]
May 26 17:57:54 fedora-4770k audit: NETFILTER_CFG table=filter family=2 entries=125
May 26 17:57:54 fedora-4770k audit: NETFILTER_CFG table=nat family=2 entries=74
May 26 17:57:54 fedora-4770k audit: NETFILTER_CFG table=mangle family=2 entries=51
May 26 17:57:54 fedora-4770k audit: NETFILTER_CFG table=filter family=10 entries=127
May 26 17:57:54 fedora-4770k audit: NETFILTER_CFG table=nat family=10 entries=74
May 26 17:57:54 fedora-4770k audit: NETFILTER_CFG table=mangle family=10 entries=51
May 26 17:57:54 fedora-4770k audit: NETFILTER_CFG table=filter family=2 entries=122
May 26 17:57:54 fedora-4770k audit: NETFILTER_CFG table=nat family=2 entries=72
May 26 17:57:54 fedora-4770k audit: NETFILTER_CFG table=mangle family=2 entries=50
May 26 17:57:54 fedora-4770k audit: NETFILTER_CFG table=filter family=10 entries=124
May 26 17:57:54 fedora-4770k audit: NETFILTER_CFG table=nat family=10 entries=72
May 26 17:57:54 fedora-4770k audit: NETFILTER_CFG table=mangle family=10 entries=50
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.2256] device (eno1): state change: config -> ip-config (reason 'none') [50 70 0]
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.2259] dhcp4 (eno1): activation: beginning transaction (timeout in 45 seconds)
May 26 17:57:54 fedora-4770k NetworkManager[1262]: <info>  [1495835874.2268] dhcp4 (eno1): dhclient started with pid 4008
May 26 17:57:54 fedora-4770k anaconda[2181]: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fe2edf2e048> status to: Connecting...
May 26 17:57:54 fedora-4770k anaconda[2181]: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fe2edf2e048> status to: Connecting...
May 26 17:57:54 fedora-4770k dhclient[4008]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 5 (xid=0x1ca7c87f)
May 26 17:57:54 fedora-4770k anaconda[2181]: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fe2edf2e048> status to: Connecting...
May 26 17:57:55 fedora-4770k avahi-daemon[1154]: Joining mDNS multicast group on interface eno1.IPv6 with address fe80::da50:e6ff:fec0:5de7.
May 26 17:57:55 fedora-4770k avahi-daemon[1154]: New relevant interface eno1.IPv6 for mDNS.
May 26 17:57:55 fedora-4770k avahi-daemon[1154]: Registering new address record for fe80::da50:e6ff:fec0:5de7 on eno1.*.
May 26 17:57:59 fedora-4770k dhclient[4008]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 11 (xid=0x1ca7c87f)
May 26 17:58:10 fedora-4770k dhclient[4008]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 21 (xid=0x1ca7c87f)
May 26 17:58:31 fedora-4770k dhclient[4008]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 10 (xid=0x1ca7c87f)
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <warn>  [1495835919.1804] dhcp4 (eno1): request timed out
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.1805] dhcp4 (eno1): state changed unknown -> timeout
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.1966] dhcp4 (eno1): canceled DHCP transaction, DHCP client pid 4008
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.1966] dhcp4 (eno1): state changed timeout -> done
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.1969] device (eno1): state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.1972] manager: NetworkManager state is now DISCONNECTED
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <warn>  [1495835919.1976] device (eno1): Activation: failed for connection 'eno1'
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.1986] device (eno1): state change: failed -> disconnected (reason 'none') [120 30 0]
May 26 17:58:39 fedora-4770k avahi-daemon[1154]: Withdrawing address record for fe80::da50:e6ff:fec0:5de7 on eno1.
May 26 17:58:39 fedora-4770k avahi-daemon[1154]: Leaving mDNS multicast group on interface eno1.IPv6 with address fe80::da50:e6ff:fec0:5de7.
May 26 17:58:39 fedora-4770k avahi-daemon[1154]: Interface eno1.IPv6 no longer relevant for mDNS.
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.2016] policy: auto-activating connection 'eno1'
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.2040] device (eno1): Activation: starting connection 'eno1' (37147987-cc72-308a-9337-7f3d2f40e16e)
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.2043] device (eno1): state change: disconnected -> prepare (reason 'none') [30 40 0]
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.2045] manager: NetworkManager state is now CONNECTING
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.2052] device (eno1): state change: prepare -> config (reason 'none') [40 50 0]
May 26 17:58:39 fedora-4770k audit: NETFILTER_CFG table=filter family=2 entries=125
May 26 17:58:39 fedora-4770k audit: NETFILTER_CFG table=nat family=2 entries=74
May 26 17:58:39 fedora-4770k audit: NETFILTER_CFG table=mangle family=2 entries=51
May 26 17:58:39 fedora-4770k anaconda[2181]: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fe2edf2e048> status to: Connecting...
May 26 17:58:39 fedora-4770k audit: NETFILTER_CFG table=filter family=10 entries=127
May 26 17:58:39 fedora-4770k audit: NETFILTER_CFG table=nat family=10 entries=74
May 26 17:58:39 fedora-4770k audit: NETFILTER_CFG table=mangle family=10 entries=51
May 26 17:58:39 fedora-4770k audit: NETFILTER_CFG table=filter family=2 entries=122
May 26 17:58:39 fedora-4770k audit: NETFILTER_CFG table=nat family=2 entries=72
May 26 17:58:39 fedora-4770k audit: NETFILTER_CFG table=mangle family=2 entries=50
May 26 17:58:39 fedora-4770k audit: NETFILTER_CFG table=filter family=10 entries=124
May 26 17:58:39 fedora-4770k audit: NETFILTER_CFG table=nat family=10 entries=72
May 26 17:58:39 fedora-4770k audit: NETFILTER_CFG table=mangle family=10 entries=50
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.2338] device (eno1): state change: config -> ip-config (reason 'none') [50 70 0]
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.2340] dhcp4 (eno1): activation: beginning transaction (timeout in 45 seconds)
May 26 17:58:39 fedora-4770k NetworkManager[1262]: <info>  [1495835919.2351] dhcp4 (eno1): dhclient started with pid 4028
May 26 17:58:39 fedora-4770k dhclient[4028]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 8 (xid=0xc0768876)
May 26 17:58:39 fedora-4770k anaconda[2181]: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fe2edf2e048> status to: Connecting...
May 26 17:58:40 fedora-4770k avahi-daemon[1154]: Joining mDNS multicast group on interface eno1.IPv6 with address fe80::da50:e6ff:fec0:5de7.
May 26 17:58:40 fedora-4770k avahi-daemon[1154]: New relevant interface eno1.IPv6 for mDNS.
May 26 17:58:40 fedora-4770k avahi-daemon[1154]: Registering new address record for fe80::da50:e6ff:fec0:5de7 on eno1.*.
May 26 17:58:47 fedora-4770k dhclient[4028]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 15 (xid=0xc0768876)
May 26 17:59:02 fedora-4770k dhclient[4028]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 9 (xid=0xc0768876)
May 26 17:59:11 fedora-4770k dhclient[4028]: DHCPDISCOVER on eno1 to 255.255.255.255 port 67 interval 20 (xid=0xc0768876)
May 26 17:59:24 fedora-4770k NetworkManager[1262]: <warn>  [1495835964.1805] dhcp4 (eno1): request timed out
May 26 17:59:24 fedora-4770k NetworkManager[1262]: <info>  [1495835964.1806] dhcp4 (eno1): state changed unknown -> timeout
May 26 17:59:24 fedora-4770k NetworkManager[1262]: <info>  [1495835964.1885] dhcp4 (eno1): canceled DHCP transaction, DHCP client pid 4028
May 26 17:59:24 fedora-4770k NetworkManager[1262]: <info>  [1495835964.1885] dhcp4 (eno1): state changed timeout -> done
May 26 17:59:24 fedora-4770k NetworkManager[1262]: <info>  [1495835964.1889] device (eno1): state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
May 26 17:59:24 fedora-4770k NetworkManager[1262]: <info>  [1495835964.1892] manager: NetworkManager state is now DISCONNECTED
May 26 17:59:24 fedora-4770k NetworkManager[1262]: <info>  [1495835964.1893] policy: disabling autoconnect for connection 'eno1'.
May 26 17:59:24 fedora-4770k NetworkManager[1262]: <warn>  [1495835964.1896] device (eno1): Activation: failed for connection 'eno1'
May 26 17:59:24 fedora-4770k NetworkManager[1262]: <info>  [1495835964.1904] device (eno1): state change: failed -> disconnected (reason 'none') [120 30 0]
May 26 17:59:24 fedora-4770k avahi-daemon[1154]: Withdrawing address record for fe80::da50:e6ff:fec0:5de7 on eno1.
May 26 17:59:24 fedora-4770k avahi-daemon[1154]: Leaving mDNS multicast group on interface eno1.IPv6 with address fe80::da50:e6ff:fec0:5de7.
May 26 17:59:24 fedora-4770k avahi-daemon[1154]: Interface eno1.IPv6 no longer relevant for mDNS.
May 26 17:59:24 fedora-4770k audit: NETFILTER_CFG table=filter family=2 entries=125
May 26 17:59:24 fedora-4770k audit: NETFILTER_CFG table=nat family=2 entries=74
May 26 17:59:24 fedora-4770k audit: NETFILTER_CFG table=mangle family=2 entries=51
May 26 17:59:24 fedora-4770k audit: NETFILTER_CFG table=filter family=10 entries=127
May 26 17:59:24 fedora-4770k audit: NETFILTER_CFG table=nat family=10 entries=74
May 26 17:59:24 fedora-4770k audit: NETFILTER_CFG table=mangle family=10 entries=51
May 26 17:59:24 fedora-4770k anaconda[2181]: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fe2edf2e048> status to: Not connected
May 26 17:59:24 fedora-4770k anaconda[2181]: setting <pyanaconda.ui.gui.spokes.network.NetworkSpoke object at 0x7fe2edf2e048> status to: Not connected

Last edited by redmaw (2017-05-27 03:34:35)

R00KIE · 2017-05-27 02:41:39

Please edit your post and use code tags instead of quote tags.

seth · 2017-05-27 07:08:08

The log below is from the Fedora live system but it is basically the same on Arch.

https://bbs.archlinux.org/viewtopic.php?id=57855

Also add the output of

systemctl list-units | grep -iE '(wicd|conn|dhcp|net)'

Next thing (if you're not trying to use multiple network managing services) is to disable and stop networkmanager, set the device up and run "dhcpcd -dB" and see what happens.

ewaller · 2017-05-27 16:03:42

Everything indicates this is a wired network, so I completely agree with Seth; why use anything but dhcpcd.
I think the IPv6 is a red herring. The fe80::::: IPv6 addresses are basically self assigned addresses that can be used to talk behind a firewall and are not routable on the public Internet. All devices will generate one, usually based on their MAC address.

Silly question, have you rebooted your router?

redmaw · 2017-05-27 20:08:56

Correct this is a wired network connection connected directly to the router.

My apologies for bringing Fedora logs here in my previous post, everything going forward will be from Arch.

# systemctl list-units | grep -iE '(wicd|conn|dhcp|net)'
  sys-devices-pci0000:00-0000:00:19.0-net-eno1.device                                                                               loaded active plugged   Ethernet Connection I217-V                                        
  sys-subsystem-net-devices-eno1.device                                                                                             loaded active plugged   Ethernet Connection I217-V                                        
● dhcpcd@eno1.service                                                                                                               loaded failed failed    dhcpcd on eno1                                                    
  system-dhcpcd.slice                                                                                                               loaded active active    system-dhcpcd.slice                                               
  network.target                                                                                                                    loaded active active    Network

Output of "dhcpcd -dB"

dhcpcd-6.11.5 starting
dev: loaded udev
eno1: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' PREINIT
eno1: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' CARRIER
DUID 00:01:00:01:20:bc:20:b6:d8:50:e6:c0:5d:e7
eno1: IAID e6:c0:5d:e7
eno1: delaying IPv6 router solicitation for 0.7 seconds
eno1: delaying IPv4 for 0.9 seconds
eno1: soliciting an IPv6 router
eno1: sending Router Solicitation
eno1: soliciting a DHCP lease
eno1: sending DISCOVER (xid 0x477a690c), next in 3.1 seconds
eno1: sending DISCOVER (xid 0x477a690c), next in 7.1 seconds
eno1: sending Router Solicitation
eno1: sending Router Solicitation
eno1: sending DISCOVER (xid 0x477a690c), next in 16.8 seconds
eno1: sending Router Solicitation
eno1: no IPv6 Routers available
eno1: sending DISCOVER (xid 0x477a690c), next in 31.8 seconds
eno1: sending DISCOVER (xid 0x477a690c), next in 64.9 seconds
eno1: sending DISCOVER (xid 0x477a690c), next in 63.4 seconds
eno1: sending DISCOVER (xid 0x477a690c), next in 64.7 seconds
received SIGINT, stopping
eno1: removing interface
eno1: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' STOPPED
dev: unloaded udev
dhcpcd exited

journal: https://pastebin.com/rdu4bqmq
dmesg: https://pastebin.com/LJb9Tms6

I have not tried rebooting the router since nothing else has an issue with it. Even this system with other OSes, apart from Fedora apparently, don't have any issues with that router.

ewaller, you are probably right. I don't know much about IPv6, or dhcp either really, so I was just starting to speculate since I can't think of any reason the discovery should time out in this situation. Interesting tidbit about the fe80 addresses though.

seth · 2017-05-27 21:16:16

https://bbs.archlinux.org/viewtopic.php … 3#p1340883

redmaw · 2017-05-27 22:29:08

seth wrote:

https://bbs.archlinux.org/viewtopic.php … 3#p1340883

Well that is interesting. Gives me some ideas to chew on later.

After some more introspection on my part I realized that the two times dhcp worked with Arch was when the system passed through G3 or S5 power states. Normally I just do a "restart" which be doing a warm reset rather than the full shutdown and boot flow so this may be related to https://bbs.archlinux.org/viewtopic.php?id=214653.

seth · 2017-05-28 05:52:21

You can also try the behavior of "dhclient -4"

redmaw · 2017-05-28 20:51:34

I tried dhcpcd -4dB when running dhcpcd -dB but it had the result. I also made a network capture from the host perspective from both the working and failing case and while I have not looked into the packet details it looks like the problem is the dhcp server is ignoring the DHCP DISCOVERY from the client. Per my understanding the dhcp server should be responding, but in reality the protocol is probably not that simple so I will need read up on it and do some detailed analysis of the packets. If anyone is interested I can upload the pcapng files.

seth · 2017-05-28 21:03:00

dhclient != dhcpcd
Did you try swapping duid for clientid?

redmaw · 2017-05-29 05:24:17

Sorry, I did not make the distinction between the two. My system as far as I can tell does not have dhclient installed so I will try that tomorrow. I did try using clientid instead of duid by modifying /etc/dhcpcd.conf however that did not seem to change anything.

For what it is worth I inspected the DHCP DISCOVERY packets and in both cases they are identical.

Last edited by redmaw (2017-05-29 05:25:44)

Arch Linux

#1 2017-05-27 01:43:03

dhcp discover times out

#2 2017-05-27 02:41:39

Re: dhcp discover times out

#3 2017-05-27 07:08:08

Re: dhcp discover times out

#4 2017-05-27 16:03:42

Re: dhcp discover times out

#5 2017-05-27 20:08:56

Re: dhcp discover times out

#6 2017-05-27 21:16:16

Re: dhcp discover times out

#7 2017-05-27 22:29:08

Re: dhcp discover times out

#8 2017-05-28 05:52:21

Re: dhcp discover times out

#9 2017-05-28 20:51:34

Re: dhcp discover times out

#10 2017-05-28 21:03:00

Re: dhcp discover times out

#11 2017-05-29 05:24:17

Re: dhcp discover times out

Board footer