/etc/resolv.conf isn't updated if "Router" option isn't returned by DHCP Server

[dori eldar]

Binary package hint: network-manager

Network-Manager doesn't update /etc/resolv.conf, following a dhcp transaction if the DHCP server doesn't return the dhcp router option (option 3)
source-package: network-manager: 0.7~~svn20081018t105859-0ubuntu1
Release: ubuntu 8-10
If I run dhclient directly with the same dhcp server configuration, /etc/resolv.conf is update correctly.

Here's the corresponding test cases with syslog output:

[test case 1: set dhcp server to return options 6 DNS server & option 15 DNS Domain (don't return Router option)]
[type in console: ]
[d:~$ sudo killall NetworkManager]
[d:~$ sudo modprobe -r e1000]

Feb 23 20:18:23 dori-laptop NetworkManager: <WARN> nm_signal_handler(): Caught signal 15, shutting down normally.
Feb 23 20:18:23 dori-laptop NetworkManager: <info> (eth0): now unmanaged
Feb 23 20:18:23 dori-laptop NetworkManager: <info> (eth0): device state change: 8 -> 1
Feb 23 20:18:23 dori-laptop NetworkManager: <info> (eth0): deactivating device (reason: 36).
Feb 23 20:18:23 dori-laptop NetworkManager: <info> eth0: canceled DHCP transaction, dhcp client pid 8676
Feb 23 20:18:23 dori-laptop NetworkManager: <info> (eth0): cleaning up...
Feb 23 20:18:23 dori-laptop NetworkManager: <info> (eth0): taking down device.
Feb 23 20:18:23 dori-laptop NetworkManager: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Feb 23 20:18:36 dori-laptop nm-system-settings: SCPlugin-Ifupdown: devices removed (udi: /org/freedesktop/Hal/devices/net_08_00_27_c6_0c_47)
Feb 23 20:18:36 dori-laptop kernel: [10731.637957] e1000 0000:00:03.0: PCI INT A disabled

[d:~$ sudo rm /etc/resolv.conf]
[d:~$ sudo modprobe e1000]
[d:~$ sudo NetworkManager]

Feb 23 20:19:10 dori-laptop kernel: [10765.546153] Intel(R) PRO/1000 Network Driver - version 7.3.20-k3-NAPI
Feb 23 20:19:10 dori-laptop kernel: [10765.546169] Copyright (c) 1999-2006 Intel Corporation.
Feb 23 20:19:10 dori-laptop kernel: [10765.572385] e1000 0000:00:03.0: PCI INT A -> Link[LNKC] -> GSI 11 (level, low) -> IRQ 11
Feb 23 20:19:11 dori-laptop kernel: [10766.024494] e1000: 0000:00:03.0: e1000_probe: (PCI:33MHz:32-bit) 08:00:27:c6:0c:47
Feb 23 20:19:11 dori-laptop kernel: [10766.081597] e1000: eth0: e1000_probe: Intel(R) PRO/1000 Network Connection
Feb 23 20:19:11 dori-laptop nm-system-settings: SCPlugin-Ifupdown: device added (udi: /org/freedesktop/Hal/devices/net_08_00_27_c6_0c_47, iface: eth0): not well known
Feb 23 20:19:15 dori-laptop nm-system-settings: Adding default connection 'Auto eth0' for /org/freedesktop/Hal/devices/net_08_00_27_c6_0c_47
Feb 23 20:19:16 dori-laptop NetworkManager: <info> starting...
Feb 23 20:19:17 dori-laptop NetworkManager: <info> eth0: driver is 'e1000'.
Feb 23 20:19:17 dori-laptop NetworkManager: <info> Found new Ethernet device 'eth0'.
Feb 23 20:19:17 dori-laptop NetworkManager: <info> (eth0): exported as /org/freedesktop/Hal/devices/net_08_00_27_c6_0c_47
Feb 23 20:19:21 dori-laptop NetworkManager: <info> (eth0): device state change: 1 -> 2
Feb 23 20:19:21 dori-laptop NetworkManager: <info> (eth0): bringing up device.
Feb 23 20:19:21 dori-laptop kernel: [10775.699999] ADDRCONF(NETDEV_UP): eth0: link is not ready
Feb 23 20:19:21 dori-laptop NetworkManager: <info> (eth0): preparing device.
Feb 23 20:19:21 dori-laptop NetworkManager: <info> (eth0): deactivating device (reason: 2).
Feb 23 20:19:21 dori-laptop NetworkManager: <info> (eth0): carrier now ON (device state 2)
Feb 23 20:19:21 dori-laptop NetworkManager: <info> (eth0): device state change: 2 -> 3
Feb 23 20:19:21 dori-laptop NetworkManager: <info> Activation (eth0) starting connection 'Auto eth0'
Feb 23 20:19:21 dori-laptop NetworkManager: <info> (eth0): device state change: 3 -> 4
Feb 23 20:19:21 dori-laptop NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
Feb 23 20:19:21 dori-laptop NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) started...
Feb 23 20:19:21 dori-laptop NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
Feb 23 20:19:21 dori-laptop NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
Feb 23 20:19:21 dori-laptop NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) starting...
Feb 23 20:19:21 dori-laptop NetworkManager: <info> (eth0): device state change: 4 -> 5
Feb 23 20:19:21 dori-laptop NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) successful.
Feb 23 20:19:21 dori-laptop NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
Feb 23 20:19:21 dori-laptop NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) complete.
Feb 23 20:19:21 dori-laptop NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
Feb 23 20:19:21 dori-laptop NetworkManager: <info> (eth0): device state change: 5 -> 7
Feb 23 20:19:21 dori-laptop NetworkManager: <info> Activation (eth0) Beginning DHCP transaction.
Feb 23 20:19:21 dori-laptop kernel: [10775.700464] e1000: eth0: e1000_watchdog: NIC Link is Up 1000 Mbps Full Duplex, Flow Control: None
Feb 23 20:19:21 dori-laptop dhclient: Internet Systems Consortium DHCP Client V3.1.1
Feb 23 20:19:21 dori-laptop kernel: [10775.711205] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Feb 23 20:19:21 dori-laptop NetworkManager: <info> dhclient started with pid 8951
Feb 23 20:19:21 dori-laptop dhclient: Copyright 2004-2008 Internet Systems Consortium.
Feb 23 20:19:21 dori-laptop dhclient: All rights reserved.
Feb 23 20:19:21 dori-laptop dhclient: For info, please visit http://www.isc.org/sw/dhcp/
Feb 23 20:19:21 dori-laptop dhclient:
Feb 23 20:19:21 dori-laptop NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
Feb 23 20:19:21 dori-laptop NetworkManager: <info> DHCP: device eth0 state changed (null) -> preinit
Feb 23 20:19:21 dori-laptop dhclient: Listening on LPF/eth0/08:00:27:c6:0c:47
Feb 23 20:19:21 dori-laptop dhclient: Sending on LPF/eth0/08:00:27:c6:0c:47
Feb 23 20:19:21 dori-laptop dhclient: Sending on Socket/fallback
Feb 23 20:19:23 dori-laptop dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 7
Feb 23 20:19:23 dori-laptop dhclient: DHCPOFFER of 192.168.0.10 from 192.168.0.1
Feb 23 20:19:23 dori-laptop dhclient: DHCPREQUEST of 192.168.0.10 on eth0 to 255.255.255.255 port 67
Feb 23 20:19:23 dori-laptop dhclient: DHCPACK of 192.168.0.10 from 192.168.0.1
Feb 23 20:19:23 dori-laptop NetworkManager: <info> DHCP: device eth0 state changed preinit -> bound
Feb 23 20:19:23 dori-laptop NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP Configure Get) scheduled...
Feb 23 20:19:23 dori-laptop NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP Configure Get) started...
Feb 23 20:19:23 dori-laptop NetworkManager: <info> address 192.168.0.10
Feb 23 20:19:23 dori-laptop NetworkManager: <info> prefix 24 (255.255.255.0)
Feb 23 20:19:23 dori-laptop NetworkManager: <info> nameserver '192.168.0.1'
Feb 23 20:19:23 dori-laptop NetworkManager: <info> domain name 'test.local'
Feb 23 20:19:23 dori-laptop NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
Feb 23 20:19:23 dori-laptop NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP Configure Get) complete.
Feb 23 20:19:23 dori-laptop NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
Feb 23 20:19:23 dori-laptop dhclient: bound to 192.168.0.10 -- renewal in 386420 seconds.
Feb 23 20:19:24 dori-laptop NetworkManager: <info> (eth0): device state change: 7 -> 8
Feb 23 20:19:24 dori-laptop NetworkManager: <info> Activation (eth0) successful, device activated.
Feb 23 20:19:24 dori-laptop NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.
Feb 23 20:19:24 dori-laptop ntpdate[8991]: can't find host ntp.ubuntu.com
Feb 23 20:19:24 dori-laptop ntpdate[8991]: no servers can be used, exiting
Feb 23 20:19:31 dori-laptop kernel: [10786.576054] eth0: no IPv6 routers present

[d:~$ ls /etc/resolv.conf]
[ls: cannot access /etc/resolv.conf: No such file or directory]

[test case 2: run dhclient, with the same DHCP server configuration ]
[d:~$ sudo killall NetworkManager]
[d:~$ sudo dhclient eth0]

Feb 23 20:19:48 dori-laptop NetworkManager: <WARN> nm_signal_handler(): Caught signal 15, shutting down normally.
Feb 23 20:19:48 dori-laptop NetworkManager: <info> (eth0): now unmanaged
Feb 23 20:19:48 dori-laptop NetworkManager: <info> (eth0): device state change: 8 -> 1
Feb 23 20:19:48 dori-laptop NetworkManager: <info> (eth0): deactivating device (reason: 36).
Feb 23 20:19:49 dori-laptop NetworkManager: <info> eth0: canceled DHCP transaction, dhcp client pid 8951
Feb 23 20:19:49 dori-laptop NetworkManager: <info> (eth0): cleaning up...
Feb 23 20:19:49 dori-laptop NetworkManager: <info> (eth0): taking down device.
Feb 23 20:19:49 dori-laptop NetworkManager: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Feb 23 20:19:57 dori-laptop dhclient: There is already a pid file /var/run/dhclient.pid with pid 8480
Feb 23 20:19:57 dori-laptop dhclient: killed old client process, removed PID file
Feb 23 20:19:57 dori-laptop dhclient: Internet Systems Consortium DHCP Client V3.1.1
Feb 23 20:19:57 dori-laptop dhclient: Copyright 2004-2008 Internet Systems Consortium.
Feb 23 20:19:57 dori-laptop dhclient: All rights reserved.
Feb 23 20:19:57 dori-laptop dhclient: For info, please visit http://www.isc.org/sw/dhcp/
Feb 23 20:19:57 dori-laptop dhclient:
Feb 23 20:19:57 dori-laptop kernel: [10812.604405] e1000: eth0: e1000_watchdog: NIC Link is Up 1000 Mbps Full Duplex, Flow Control: None
Feb 23 20:19:57 dori-laptop kernel: [10812.634613] ADDRCONF(NETDEV_UP): eth0: link is not ready
Feb 23 20:19:58 dori-laptop kernel: [10812.636485] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Feb 23 20:19:59 dori-laptop dhclient: Listening on LPF/eth0/08:00:27:c6:0c:47
Feb 23 20:19:59 dori-laptop dhclient: Sending on LPF/eth0/08:00:27:c6:0c:47
Feb 23 20:19:59 dori-laptop dhclient: Sending on Socket/fallback
Feb 23 20:20:01 dori-laptop dhclient: DHCPREQUEST of 192.168.0.10 on eth0 to 255.255.255.255 port 67
Feb 23 20:20:01 dori-laptop dhclient: DHCPACK of 192.168.0.10 from 192.168.0.1
Feb 23 20:20:01 dori-laptop dhclient: bound to 192.168.0.10 -- renewal in 369771 seconds.

[dhclient works as expected:]
[d:~$ cat /etc/resolv.conf ]
[domain test.local]
[search test.local]
[nameserver 192.168.0.1]

[test case 3:]
[configure dhcp server to send the Router option]
[d:~$ sudo killall NetworkManager]
[d:~$ sudo modprobe -r e1000]
[d:~$ sudo rm /etc/resolv.conf]
[d:~$ sudo modprobe e1000]
[d:~$ sudo NetworkManager]

Feb 23 20:20:08 dori-laptop kernel: [10822.680917] eth0: no IPv6 routers present
Feb 23 20:21:09 dori-laptop NetworkManager: <info> starting...
Feb 23 20:21:09 dori-laptop NetworkManager: <info> eth0: driver is 'e1000'.
Feb 23 20:21:09 dori-laptop NetworkManager: <info> Found new Ethernet device 'eth0'.
Feb 23 20:21:09 dori-laptop NetworkManager: <info> (eth0): exported as /org/freedesktop/Hal/devices/net_08_00_27_c6_0c_47
Feb 23 20:21:09 dori-laptop NetworkManager: <info> (eth0): carrier now ON (device state 1)
Feb 23 20:21:13 dori-laptop NetworkManager: <info> (eth0): device state change: 1 -> 2
Feb 23 20:21:13 dori-laptop NetworkManager: <info> (eth0): preparing device.
Feb 23 20:21:13 dori-laptop NetworkManager: <info> (eth0): deactivating device (reason: 2).
Feb 23 20:21:13 dori-laptop NetworkManager: <info> (eth0): device state change: 2 -> 3
Feb 23 20:21:13 dori-laptop NetworkManager: <info> Activation (eth0) starting connection 'Auto eth0'
Feb 23 20:21:13 dori-laptop NetworkManager: <info> (eth0): device state change: 3 -> 4
Feb 23 20:21:13 dori-laptop NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
Feb 23 20:21:13 dori-laptop NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) started...
Feb 23 20:21:13 dori-laptop NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
Feb 23 20:21:13 dori-laptop NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
Feb 23 20:21:13 dori-laptop NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) starting...
Feb 23 20:21:13 dori-laptop NetworkManager: <info> (eth0): device state change: 4 -> 5
Feb 23 20:21:13 dori-laptop NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) successful.
Feb 23 20:21:13 dori-laptop NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
Feb 23 20:21:13 dori-laptop NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) complete.
Feb 23 20:21:13 dori-laptop NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
Feb 23 20:21:13 dori-laptop NetworkManager: <info> (eth0): device state change: 5 -> 7
Feb 23 20:21:13 dori-laptop NetworkManager: <info> Activation (eth0) Beginning DHCP transaction.
Feb 23 20:21:13 dori-laptop NetworkManager: <info> dhclient started with pid 9081
Feb 23 20:21:13 dori-laptop dhclient: Internet Systems Consortium DHCP Client V3.1.1
Feb 23 20:21:13 dori-laptop dhclient: Copyright 2004-2008 Internet Systems Consortium.
Feb 23 20:21:13 dori-laptop dhclient: All rights reserved.
Feb 23 20:21:13 dori-laptop dhclient: For info, please visit http://www.isc.org/sw/dhcp/
Feb 23 20:21:13 dori-laptop dhclient:
Feb 23 20:21:13 dori-laptop NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
Feb 23 20:21:13 dori-laptop NetworkManager: <info> DHCP: device eth0 state changed (null) -> preinit
Feb 23 20:21:13 dori-laptop dhclient: Listening on LPF/eth0/08:00:27:c6:0c:47
Feb 23 20:21:13 dori-laptop dhclient: Sending on LPF/eth0/08:00:27:c6:0c:47
Feb 23 20:21:13 dori-laptop dhclient: Sending on Socket/fallback
Feb 23 20:21:16 dori-laptop dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5
Feb 23 20:21:16 dori-laptop dhclient: DHCPOFFER of 192.168.0.10 from 192.168.0.1
Feb 23 20:21:16 dori-laptop dhclient: DHCPREQUEST of 192.168.0.10 on eth0 to 255.255.255.255 port 67
Feb 23 20:21:16 dori-laptop dhclient: DHCPACK of 192.168.0.10 from 192.168.0.1
Feb 23 20:21:16 dori-laptop NetworkManager: <info> DHCP: device eth0 state changed preinit -> bound
Feb 23 20:21:16 dori-laptop NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP Configure Get) scheduled...
Feb 23 20:21:16 dori-laptop NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP Configure Get) started...
Feb 23 20:21:16 dori-laptop NetworkManager: <info> address 192.168.0.10
Feb 23 20:21:16 dori-laptop NetworkManager: <info> prefix 24 (255.255.255.0)
Feb 23 20:21:16 dori-laptop NetworkManager: <info> gateway 1.1.1.1
Feb 23 20:21:16 dori-laptop NetworkManager: <info> nameserver '192.168.0.1'
Feb 23 20:21:16 dori-laptop NetworkManager: <info> domain name 'test.local'
Feb 23 20:21:16 dori-laptop NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
Feb 23 20:21:16 dori-laptop NetworkManager: <info> Activation (eth0) Stage 4 of 5 (IP Configure Get) complete.
Feb 23 20:21:16 dori-laptop NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
Feb 23 20:21:16 dori-laptop dhclient: bound to 192.168.0.10 -- renewal in 423988 seconds.
Feb 23 20:21:17 dori-laptop NetworkManager: <info> (eth0): device state change: 7 -> 8
Feb 23 20:21:17 dori-laptop NetworkManager: <WARN> nm_system_replace_default_ip4_route(): Failed to set IPv4 default route on 'eth0': Sucess
Feb 23 20:21:17 dori-laptop NetworkManager: <info> Policy set 'Auto eth0' (eth0) as default for routing and DNS.
Feb 23 20:21:17 dori-laptop NetworkManager: <info> Activation (eth0) successful, device activated.
Feb 23 20:21:17 dori-laptop NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.
Feb 23 20:21:17 dori-laptop ntpdate[9120]: can't find host ntp.ubuntu.com
Feb 23 20:21:17 dori-laptop ntpdate[9120]: no servers can be used, exiting

[Now resolv.conf update correctly:]
[d:~$ cat /etc/resolv.conf ]
[# Generated by NetworkManager]
[domain test.local]
[search test.local]
[nameserver 192.168.0.1]

[Alexander Sack (asac)]

nice find. do you have access to a system with jaunty or a live CD of latest alpha/beta and could try there?

[Alexander Sack (asac)]

prod: "nice find. do you have access to a system with jaunty or a live CD of latest alpha/beta and could try there?"

If not, please test the 0.7 final packages i made available for intrepid in the network-manager team PPA: http://launchpad.net/~network-manager/+archive

Let me know if that helps.

[dori eldar (dori-eldar)]

Check this both with Jaunty Beta and 0.7 final with intrepid and issue is resolved.
Thanks !

[Philip Muškovac (yofel)]

This bug report is being closed due to your last comment regarding this being fixed with an update. For future reference you can manage the status of your own bugs by clicking on the current status in the yellow line and then choosing a new status in the revealed drop down box. You can learn more about bug statuses at https://wiki.ubuntu.com/Bugs/Status. Thank you again for taking the time to report this bug and helping to make Ubuntu better. Please submit any future bugs you may find.