I've been having problems with NetworkManager dhcp on my Fedora27 Workstation (desktop, wired).. Note, because I'm using VMs on that workstation, the interface is a bridge (br0 with slave eno1).
What seems to happen is this:
Workstation wakes up from sleeping, reactivates connection.
dhclient issues 4 DHCPDISCOVER tries, none of which get a response.
the interface state changes "unknown-->timeout->done", and the DHCPv4 transaction is cancelled.
A restart is scheduled for 120 seconds later.
The restart 120 seconds later succeeds with DHCPDISCOVER, DHCPREQUEST, DHCPOFFER, DHCPACK.
All the other machines served by my DHCP server have no problems at all, however, they are MacBooks, various storage servers, and RaspberryPis.
It seems to be that NetworkManager somehow interferes with the DHCPDISCOVER after the workstation wakes up.
The attached log file shows this sequence of events.
(I'm wondering if there is a timing issue because the first dhclient call is issued when eno1 is in the "unavailable" state, and before it is captured as a slave to br0)
Jan 03 19:13:00 swift.localnet NetworkManager[13133]: <info> [1515024780.8989] manager: sleep requested (sleeping: no enabled: yes) Jan 03 19:13:00 swift.localnet NetworkManager[13133]: <info> [1515024780.8992] manager: sleeping... Jan 03 19:13:00 swift.localnet NetworkManager[13133]: <info> [1515024780.8997] device (wlp3s0): state change: unavailable -> unmanaged (reason 'sleeping', internal state 'managed') Jan 03 19:13:00 swift.localnet NetworkManager[13133]: <info> [1515024780.9000] device (wlp3s0): set-hw-addr: reset MAC address to 00:C2:C6:DD:85:79 (unmanage) Jan 03 19:13:00 swift.localnet NetworkManager[13133]: <info> [1515024780.9004] manager: NetworkManager state is now ASLEEP Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.3984] manager: wake requested (sleeping: yes enabled: yes) Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.3986] manager: waking up... Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.3989] device (eno1): state change: activated -> unmanaged (reason 'sleeping', internal state 'managed') Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.5213] device (br0): detached bridge port eno1 Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.5217] device (eno1): released from master device br0 Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.5222] manager: NetworkManager state is now CONNECTED_GLOBAL Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.5301] device (br0): DHCPv4 lease renewal requested Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.5463] dhcp4 (br0): canceled DHCP transaction, DHCP client pid 13352 Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.5464] dhcp4 (br0): state changed bound -> done Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.5469] dhcp4 (br0): activation: beginning transaction (timeout in 45 seconds) Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.5496] dhcp4 (br0): dhclient started with pid 13923 Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.5496] device (eno1): state change: unmanaged -> unavailable (reason 'managed', internal state 'managed') Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.7608] device (wlp3s0): state change: unmanaged -> unavailable (reason 'managed', internal state 'managed') Jan 04 15:06:50 swift.localnet NetworkManager[13133]: <info> [1515096410.7628] device (wlp3s0): set-hw-addr: set MAC address to DE:8D:E8:9F:5E:3C (scanning) Jan 04 15:06:50 swift.localnet dhclient[13923]: DHCPDISCOVER on br0 to 255.255.255.255 port 67 interval 7 (xid=0xd79e263) Jan 04 15:06:53 swift.localnet NetworkManager[13133]: <info> [1515096413.4244] device (eno1): link connected Jan 04 15:06:53 swift.localnet NetworkManager[13133]: <info> [1515096413.4255] device (eno1): state change: unavailable -> disconnected (reason 'carrier-changed', internal state 'managed') Jan 04 15:06:53 swift.localnet NetworkManager[13133]: <info> [1515096413.4275] policy: auto-activating connection 'bridge-slave-eno1' Jan 04 15:06:53 swift.localnet NetworkManager[13133]: <info> [1515096413.4294] device (eno1): Activation: starting connection 'bridge-slave-eno1' (c52687d1-d7c3-4828-8219-232090775ef3) Jan 04 15:06:53 swift.localnet NetworkManager[13133]: <info> [1515096413.4296] device (eno1): state change: disconnected -> prepare (reason 'none', internal state 'managed') Jan 04 15:06:53 swift.localnet NetworkManager[13133]: <info> [1515096413.4300] device (eno1): state change: prepare -> config (reason 'none', internal state 'managed') Jan 04 15:06:53 swift.localnet NetworkManager[13133]: <info> [1515096413.4464] device (eno1): state change: config -> ip-config (reason 'none', internal state 'managed') Jan 04 15:06:53 swift.localnet NetworkManager[13133]: <info> [1515096413.4472] device (br0): attached bridge port eno1 Jan 04 15:06:53 swift.localnet NetworkManager[13133]: <info> [1515096413.4473] device (eno1): Activation: connection 'bridge-slave-eno1' enslaved, continuing activation Jan 04 15:06:53 swift.localnet NetworkManager[13133]: <info> [1515096413.4481] device (br0): link connected Jan 04 15:06:53 swift.localnet NetworkManager[13133]: <info> [1515096413.4481] device (eno1): state change: ip-config -> secondaries (reason 'none', internal state 'managed') Jan 04 15:06:53 swift.localnet NetworkManager[13133]: <info> [1515096413.4483] device (eno1): state change: secondaries -> activated (reason 'none', internal state 'managed') Jan 04 15:06:53 swift.localnet NetworkManager[13133]: <info> [1515096413.4867] device (eno1): Activation: successful, device activated. Jan 04 15:06:57 swift.localnet dhclient[13923]: DHCPDISCOVER on br0 to 255.255.255.255 port 67 interval 18 (xid=0xd79e263) Jan 04 15:07:15 swift.localnet dhclient[13923]: DHCPDISCOVER on br0 to 255.255.255.255 port 67 interval 12 (xid=0xd79e263) Jan 04 15:07:27 swift.localnet dhclient[13923]: DHCPDISCOVER on br0 to 255.255.255.255 port 67 interval 19 (xid=0xd79e263) Jan 04 15:07:36 swift.localnet NetworkManager[13133]: <warn> [1515096456.0277] dhcp4 (br0): request timed out Jan 04 15:07:36 swift.localnet NetworkManager[13133]: <info> [1515096456.0283] dhcp4 (br0): state changed unknown -> timeout Jan 04 15:07:36 swift.localnet NetworkManager[13133]: <info> [1515096456.0452] dhcp4 (br0): canceled DHCP transaction, DHCP client pid 13923 Jan 04 15:07:36 swift.localnet NetworkManager[13133]: <info> [1515096456.0452] dhcp4 (br0): state changed timeout -> done Jan 04 15:07:36 swift.localnet NetworkManager[13133]: <info> [1515096456.0457] device (br0): scheduling DHCPv4 restart in 120 seconds, 3 tries left (reason: lease expired) Jan 04 15:09:36 swift.localnet NetworkManager[13133]: <info> [1515096576.0053] dhcp4 (br0): activation: beginning transaction (timeout in 45 seconds) Jan 04 15:09:36 swift.localnet NetworkManager[13133]: <info> [1515096576.0097] dhcp4 (br0): dhclient started with pid 14173 Jan 04 15:09:36 swift.localnet dhclient[14173]: DHCPDISCOVER on br0 to 255.255.255.255 port 67 interval 6 (xid=0xa2c05641) Jan 04 15:09:36 swift.localnet dhclient[14173]: DHCPREQUEST on br0 to 255.255.255.255 port 67 (xid=0xa2c05641) Jan 04 15:09:36 swift.localnet dhclient[14173]: DHCPOFFER from 192.168.192.1 Jan 04 15:09:36 swift.localnet dhclient[14173]: DHCPACK from 192.168.192.1 (xid=0xa2c05641) Jan 04 15:09:36 swift.localnet NetworkManager[13133]: <info> [1515096576.0630] dhcp4 (br0): address 192.168.192.46 Jan 04 15:09:36 swift.localnet NetworkManager[13133]: <info> [1515096576.0630] dhcp4 (br0): plen 24 (255.255.255.0) Jan 04 15:09:36 swift.localnet NetworkManager[13133]: <info> [1515096576.0631] dhcp4 (br0): gateway 192.168.192.1 Jan 04 15:09:36 swift.localnet NetworkManager[13133]: <info> [1515096576.0631] dhcp4 (br0): lease time 14400 Jan 04 15:09:36 swift.localnet NetworkManager[13133]: <info> [1515096576.0631] dhcp4 (br0): hostname 'swift' Jan 04 15:09:36 swift.localnet NetworkManager[13133]: <info> [1515096576.0631] dhcp4 (br0): nameserver '192.168.192.1' Jan 04 15:09:36 swift.localnet NetworkManager[13133]: <info> [1515096576.0631] dhcp4 (br0): domain name 'localnet' Jan 04 15:09:36 swift.localnet NetworkManager[13133]: <info> [1515096576.0631] dhcp4 (br0): state changed unknown -> bound Jan 04 15:09:36 swift.localnet dhclient[14173]: bound to 192.168.192.46 -- renewal in 6707 seconds.
_______________________________________________ networkmanager-list mailing list [email protected] https://mail.gnome.org/mailman/listinfo/networkmanager-list
