DHCP timeout problem

[Tony Murray <tonymurray fastmail fm>]

I have been trying to get NetworkManager working and I am almost there
after many hurdles.

When NetworkManager is waiting for dhcdbd/dhclient to recieve an IP it
times out and sets up a zeroconf ip...  but dhclient gets a correct ip
and sets it.  So, the problem is somewhere between NetworkManager /
dhcdbd / dhclient.  Confirmation of a successful DHCP_DISCOVER is not
getting from one end to the other.

I have attach the full section from my logs.  It looks informative, but
I do not know enough to fix it...

Also, please note that I have used my own compiled dhclient-3.0.2 (with
-x patch), dhcdbd-1.6 and NetworkManager-CVS_HEAD and a patch to the
Gentoo backend.  I am using Gentoo on my laptop with a b44 and ipw2200.

Thanks.  Please ask me for more info I am more than willing ;)

Tony Murray

Jul  7 23:10:17 pixie NetworkManager: <information>	FORCE: device '/org/freedesktop/NetworkManager/Devices/eth1', network 'murrayg'
Jul  7 23:10:17 pixie NetworkManager: <information>	Deactivating device eth1.
Jul  7 23:10:19 pixie dhcdbd: message_handler: message handler not found under /com/redhat/dhcp/eth1 for sub-path eth1.dbus.get.reason
Jul  7 23:10:19 pixie NetworkManager: <debug info>	[1120795819.259030]  (): Forcing AP 'murrayg'
Jul  7 23:10:19 pixie NetworkManager: <information>	Device eth1 activation scheduled...
Jul  7 23:10:19 pixie NetworkManager: <debug info>	[1120795819.264496]  (): NetworkManagerInfo triggered update of wireless network 'murrayg'
Jul  7 23:10:19 pixie NetworkManager: <information>	Activation (eth1) started...
Jul  7 23:10:19 pixie NetworkManager: <information>	Activation (eth1) Stage 1 (Device Prepare) scheduled...
Jul  7 23:10:19 pixie NetworkManager: <information>	Activation (eth1) Stage 1 (Device Prepare) started...
Jul  7 23:10:19 pixie NetworkManager: <information>	Activation (eth1) Stage 2 (Device Configure) scheduled...
Jul  7 23:10:19 pixie NetworkManager: <information>	Activation (eth1) Stage 1 (Device Prepare) complete.
Jul  7 23:10:19 pixie NetworkManager: <information>	Activation (eth1) Stage 2 (Device Configure) starting...
Jul  7 23:10:19 pixie NetworkManager: <information>	Activation (eth1/wireless) Stage 1 (Device Configure) will connect to access point 'murrayg'.
Jul  7 23:10:19 pixie NetworkManager: <information>	Activation (eth1/wireless): access point 'murrayg' is unencrypted, no key needed.
Jul  7 23:10:19 pixie NetworkManager: <information>	Old device 'eth1' activating, won't change.
Jul  7 23:10:21 pixie NetworkManager: <information>	Activation (eth1/wireless): using essid 'murrayg', with no authentication.
Jul  7 23:10:21 pixie NetworkManager: <information>	Activation (eth1/wireless) Stage 2 (Device Configure) successful.  Connected to access point 'murrayg'.
Jul  7 23:10:21 pixie NetworkManager: <information>	Activation (eth1) Stage 3 (IP Configure Start) scheduled.
Jul  7 23:10:21 pixie NetworkManager: <information>	Activation (eth1) Stage 2 (Device Configure) complete.
Jul  7 23:10:21 pixie NetworkManager: <information>	Activation (eth1) Stage 3 (IP Configure Start) started...
Jul  7 23:10:22 pixie NetworkManager: <information>	Activation (eth1) Beginning DHCP transaction.
Jul  7 23:10:22 pixie dhclient: Internet Systems Consortium DHCP Client V3.0.2
Jul  7 23:10:22 pixie dhclient: Copyright 2004 Internet Systems Consortium.
Jul  7 23:10:22 pixie dhclient: All rights reserved.
Jul  7 23:10:22 pixie dhclient: For info, please visit http://www.isc.org/products/DHCP
Jul  7 23:10:22 pixie dhclient: 
Jul  7 23:10:22 pixie NetworkManager: <information>	Activation (eth1) Stage 3 (IP Configure Start) complete.
Jul  7 23:10:22 pixie NetworkManager: <information>	DHCP daemon state now 12 for interface eth1
Jul  7 23:10:23 pixie dhclient: Listening on LPF/eth1/00:0e:35:43:77:cc
Jul  7 23:10:23 pixie dhclient: Sending on   LPF/eth1/00:0e:35:43:77:cc
Jul  7 23:10:23 pixie dhclient: Sending on   Socket/fallback
Jul  7 23:10:25 pixie dhclient: DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 4
Jul  7 23:10:25 pixie dhclient: DHCPOFFER from 192.168.0.2
Jul  7 23:10:25 pixie dhclient: DHCPREQUEST on eth1 to 255.255.255.255 port 67
Jul  7 23:10:25 pixie dhclient: DHCPACK from 192.168.0.2
Jul  7 23:10:25 pixie dhclient: bound to 192.168.0.80 -- renewal in 42277 seconds.
Jul  7 23:10:47 pixie NetworkManager: <information>	Device 'eth1' DHCP transaction took too long (>25s), stopping it.
Jul  7 23:10:47 pixie NetworkManager: <information>	Activation (eth1) Stage 4 (IP Configure Timeout) scheduled...
Jul  7 23:10:47 pixie NetworkManager: <information>	Activation (eth1) Stage 4 (IP Configure Timeout) started...
Jul  7 23:10:47 pixie NetworkManager: <information>	No DHCP reply received.  Automatically obtaining IP via Zeroconf.
Jul  7 23:10:47 pixie dhclient: Internet Systems Consortium DHCP Client V3.0.2
Jul  7 23:10:47 pixie dhclient: Copyright 2004 Internet Systems Consortium.
Jul  7 23:10:47 pixie dhclient: All rights reserved.
Jul  7 23:10:47 pixie dhclient: For info, please visit http://www.isc.org/products/DHCP
Jul  7 23:10:47 pixie dhclient: 
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Sending probe #0 for IP address 169.254.154.172.
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Waiting for reply...
Jul  7 23:10:47 pixie dhclient: Listening on LPF/eth1/00:0e:35:43:77:cc
Jul  7 23:10:47 pixie dhclient: Sending on   LPF/eth1/00:0e:35:43:77:cc
Jul  7 23:10:47 pixie dhclient: Sending on   Socket/fallback
Jul  7 23:10:47 pixie dhclient: DHCPRELEASE on eth1 to 192.168.0.2 port 67
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Got some data to check for reply packet.
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  (): autoip: (eth1) recv arp type=2054, op=1, 
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  ():  source = 192.168.0.80 00:0E:35:43:77:CC, 
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  ():  target = 192.168.0.2 00:00:00:00:00:00
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Sending probe #1 for IP address 169.254.154.172.
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Waiting for reply...
Jul  7 23:10:47 pixie NetworkManager: <information>	DHCP daemon state now 14 for interface eth1
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Got some data to check for reply packet.
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  (): autoip: (eth1) recv arp type=2048, op=0, 
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  ():  source = 0.2.192.168 40:01:5A:EE:C0:A8, 
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  ():  target = 87.32.0.0 00:50:08:00:A0:DF
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Sending probe #2 for IP address 169.254.154.172.
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Waiting for reply...
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Got some data to check for reply packet.
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  (): autoip: (eth1) recv arp type=2054, op=2, 
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  ():  source = 192.168.0.2 00:12:17:61:32:66, 
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  ():  target = 192.168.0.80 00:0E:35:43:77:CC
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Sending announce #0 for IP address 169.254.154.172.
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Waiting for reply...
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Got some data to check for reply packet.
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  (): autoip: (eth1) recv arp type=2048, op=16384, 
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  ():  source = 0.80.192.168 40:11:B8:02:C0:A8, 
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  ():  target = 1.52.21.241 00:02:00:44:00:43
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Sending announce #1 for IP address 169.254.154.172.
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Waiting for reply...
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Got some data to check for reply packet.
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  (): autoip: (eth1) recv arp type=2048, op=0, 
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  ():  source = 0.80.192.168 40:01:3C:A9:C0:A8, 
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  ():  target = 87.32.0.0 00:02:00:00:A8:DF
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Sending announce #2 for IP address 169.254.154.172.
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Waiting for reply...
Jul  7 23:10:47 pixie NetworkManager: <information>	autoip: Got some data to check for reply packet.
Jul  7 23:10:47 pixie NetworkManager: <WARNING>	  (): autoip: packet receive failure, ignoring it.
Jul  7 23:10:47 pixie NetworkManager: <information>	Activation (eth1) Stage 5 (IP Configure Commit) scheduled...
Jul  7 23:10:47 pixie NetworkManager: <information>	Activation (eth1) Stage 4 (IP Configure Timeout) complete.
Jul  7 23:10:47 pixie NetworkManager: <information>	Activation (eth1) Stage 5 (IP Configure Commit) started...
Jul  7 23:10:47 pixie NetworkManager: <information>	DHCP daemon state now 14 for interface eth1
Jul  7 23:10:47 pixie NetworkManager: <information>	Your IP address = 169.254.154.172
Jul  7 23:10:49 pixie mDNSResponder: Unable to parse DNS server list. Unicast DNS-SD unavailable
Jul  7 23:10:49 pixie NetworkManager: <information>	Activation (eth1) Finish handler scheduled.
Jul  7 23:10:49 pixie NetworkManager: <information>	Activation (eth1) Stage 5 (IP Configure Commit) complete.
Jul  7 23:10:49 pixie NetworkManager: <information>	Activation (eth1) successful, device activated.