On Mon, 26 Jan 2009 07:21:05 -0700, Christopher A. Williams wrote:
On Mon, 2009-01-26 at 08:47 -0500, Tom Horsley wrote:
> On Mon, 26 Jan 2009 23:23:49 +1100
> David Timms wrote:
>
> > Tom Horsley wrote:
> > > On Fri, 23 Jan 2009 13:32:08 -0500
> > > McGuffey, David C. wrote:
> > >
> > >> Update: Intermittent dns is still present.
> > >
> > > I'd strongly suspect this is NM getting the resolv.conf file
> > > correct on initial connect, then screwing it up on
> > > subsequent DHCP lease renewal.
> > I get the no entries in /etc/resolv.conf directly after logging in
> > (workaround - an NM entry with static IP, and dns). Is there a
more
> > permanent fix for Fedora 10 (upgraded from F9) ?
>
> In /etc/sysconfig/network-scripts/ifcfg-eth0
>
> I have the line:
>
> PEERDNS=no
>
> which (in theory) prevents NM from doing anything at all to
> the resolv.conf file when acting on that interface (of course
> you then have to make sure you set resolv.conf yourself).
I thought NM had a know bug where it did not yet respect the
PEERDNS=no
entry.
Has this been fixed?
The only (and most unfortunate) way I have been able to reliably keep
my
resolf.conf file from getting waxed by NM has been to set it as
immutable. A _very_ nasty way of doing things, but it does work.
Cheers,
Chris
Problem persists. Have had several batches of patches pushed through
yum auto update, but nothing concerning NM to fix this problem.
I set PEERDNS=no with no affect.
I set /etc/resolv.conf to a workable solution and then removed the write
bits and set it immutable. I get errors in /var/log/messages about it,
but the behavior is still the same. After a reboot, all is OK. After a
while, dns dies. Restarting NM brings it back to life...then it dies a
few minutes later. As long as someone is actively accessing the net,
dns seems to stay up. But give it a few minutes of idle time and
something causes it to stop. Here is a snapshot of /var/log/messages
before, during, and after a NM restart. Can anyone figure out what is
going wrong with dns?
Feb 2 21:23:45 desk NetworkManager: <WARN> nm_signal_handler():
Caught signal 15, shutting down normally.
Feb 2 21:23:45 desk NetworkManager: <info> (eth0): now unmanaged Feb
2 21:23:45 desk NetworkManager: <info> (eth0): device state
change: 8 -> 1
Feb 2 21:23:45 desk NetworkManager: <info> (eth0): deactivating device
(reason: 36).
Feb 2 21:23:45 desk NetworkManager: <info> eth0: canceled DHCP
transaction, dhcp client pid 2034 Feb 2 21:23:45 desk NetworkManager:
<WARN>
nm_named_manager_remove_ip4_config(): Could not commit DNS changes.
Error: 'Could not replace /etc/resolv.conf: Operation not permitted#012'
Feb 2 21:23:45 desk NetworkManager: <WARN> check_one_route(): (eth0)
error -34 returned from rtnl_route_del(): Sucess#012 Feb 2 21:23:45
desk avahi-daemon[2071]: Withdrawing address record for
192.168.1.2 on eth0.
Feb 2 21:23:45 desk avahi-daemon[2071]: Leaving mDNS multicast group on
interface eth0.IPv4 with address 192.168.1.2.
Feb 2 21:23:45 desk avahi-daemon[2071]: Interface eth0.IPv4 no longer
relevant for mDNS.
Feb 2 21:23:45 desk NetworkManager: <info> (eth0): cleaning up...
Feb 2 21:23:45 desk NetworkManager: <info> (eth0): taking down device.
Feb 2 21:23:45 desk avahi-daemon[2071]: Withdrawing address record for
fe80::207:e9ff:fef2:b64d on eth0.
Feb 2 21:23:45 desk NetworkManager: <info> disconnected by the system
bus.
Feb 2 21:23:45 desk nm-dispatcher.action: Error in get_property: The
name org.freedesktop.NetworkManager was not provided by any .service
files Feb 2 21:23:46 desk NetworkManager: <info> starting...
Feb 2 21:23:46 desk NetworkManager: <WARN>
nm_generic_enable_loopback(): error -17 returned from
rtnl_addr_add():#012Sucess#012
Feb 2 21:23:46 desk NetworkManager: <info> eth0: driver is 'e100'.
Feb 2 21:23:46 desk NetworkManager: <info> Found new Ethernet device
'eth0'.
Feb 2 21:23:46 desk NetworkManager: <info> (eth0): exported as
/org/freedesktop/Hal/devices/net_00_07_e9_f2_b6_4d
Feb 2 21:23:50 desk NetworkManager: <info> (eth0): device state
change: 1 -> 2
Feb 2 21:23:50 desk NetworkManager: <info> (eth0): bringing up device.
Feb 2 21:23:50 desk kernel: e100: eth0: e100_watchdog: link up,
100Mbps, full-duplex Feb 2 21:23:50 desk kernel: ADDRCONF(NETDEV_UP):
eth0: link is not ready Feb 2 21:23:50 desk kernel:
ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Feb 2 21:23:50 desk
NetworkManager: <info> (eth0): preparing device.
Feb 2 21:23:50 desk NetworkManager: <info> (eth0): deactivating device
(reason: 2).
Feb 2 21:23:50 desk NetworkManager: <info> (eth0): carrier now ON
(device state 2) Feb 2 21:23:50 desk NetworkManager: <info> (eth0):
device state
change: 2 -> 3
Feb 2 21:23:50 desk NetworkManager: <info> Activation (eth0) starting
connection 'System eth0'
Feb 2 21:23:50 desk NetworkManager: <info> (eth0): device state
change: 3 -> 4
Feb 2 21:23:50 desk NetworkManager: <info> Activation (eth0) Stage 1
of 5 (Device Prepare) scheduled...
Feb 2 21:23:50 desk NetworkManager: <info> Activation (eth0) Stage 1
of 5 (Device Prepare) started...
Feb 2 21:23:50 desk NetworkManager: <info> Activation (eth0) Stage 2
of 5 (Device Configure) scheduled...
Feb 2 21:23:50 desk NetworkManager: <info> Activation (eth0) Stage 1
of 5 (Device Prepare) complete.
Feb 2 21:23:50 desk NetworkManager: <info> Activation (eth0) Stage 2
of 5 (Device Configure) starting...
Feb 2 21:23:50 desk NetworkManager: <info> (eth0): device state
change: 4 -> 5
Feb 2 21:23:50 desk NetworkManager: <info> Activation (eth0) Stage 2
of 5 (Device Configure) successful.
Feb 2 21:23:50 desk NetworkManager: <info> Activation (eth0) Stage 3
of 5 (IP Configure Start) scheduled.
Feb 2 21:23:50 desk NetworkManager: <info> Activation (eth0) Stage 2
of 5 (Device Configure) complete.
Feb 2 21:23:50 desk NetworkManager: <info> Activation (eth0) Stage 3
of 5 (IP Configure Start) started...
Feb 2 21:23:50 desk NetworkManager: <info> (eth0): device state
change: 5 -> 7
Feb 2 21:23:50 desk NetworkManager: <info> Activation (eth0) Beginning
DHCP transaction.
Feb 2 21:23:50 desk dhclient: Internet Systems Consortium DHCP Client
4.0.0 Feb 2 21:23:50 desk dhclient: Copyright 2004-2007 Internet
Systems Consortium.
Feb 2 21:23:50 desk dhclient: All rights reserved.
Feb 2 21:23:50 desk dhclient: For info, please visit
http://www.isc.org/sw/dhcp/ Feb 2 21:23:50 desk dhclient:
Feb 2 21:23:50 desk NetworkManager: <info> dhclient started with pid
4747
Feb 2 21:23:50 desk dhclient: Listening on LPF/eth0/00:07:e9:f2:b6:4d
Feb 2 21:23:50 desk dhclient: Sending on LPF/eth0/00:07:e9:f2:b6:4d
Feb 2 21:23:50 desk dhclient: Sending on Socket/fallback
Feb 2 21:23:50 desk NetworkManager: <info> Activation (eth0) Stage 3
of 5 (IP Configure Start) complete.
Feb 2 21:23:50 desk NetworkManager: <info> DHCP: device eth0 state
changed (null) -> preinit Feb 2 21:23:52 desk dhclient: DHCPDISCOVER on
eth0 to 255.255.255.255 port 67 interval 4 Feb 2 21:23:52 desk
avahi-daemon[2071]: Registering new address record for
fe80::207:e9ff:fef2:b64d on eth0.*.
Feb 2 21:23:53 desk dhclient: DHCPOFFER from 192.168.1.1 Feb 2
21:23:53 desk dhclient: DHCPREQUEST on eth0 to 255.255.255.255 port 67
Feb 2 21:23:53 desk dhclient: DHCPACK from 192.168.1.1 Feb 2 21:23:53
desk NetworkManager: <info> DHCP: device eth0 state changed preinit ->
bound Feb 2 21:23:53 desk NetworkManager: <info> Activation (eth0)
Stage 4 of 5 (IP Configure Get) scheduled...
Feb 2 21:23:53 desk NetworkManager: <info> Activation (eth0) Stage 4
of 5 (IP Configure Get) started...
Feb 2 21:23:53 desk NetworkManager: <info> address 192.168.1.2
Feb 2 21:23:53 desk NetworkManager: <info> prefix 24 (255.255.255.0)
Feb 2 21:23:53 desk NetworkManager: <info> gateway 192.168.1.1
Feb 2 21:23:53 desk NetworkManager: <info> hostname 'desk'
Feb 2 21:23:53 desk NetworkManager: <info> nameserver '192.168.1.1'
Feb 2 21:23:53 desk NetworkManager: <info> domain name
'seclab.net'
Feb 2 21:23:53 desk NetworkManager: <info> Activation (eth0) Stage 5
of 5 (IP Configure Commit) scheduled...
Feb 2 21:23:53 desk NetworkManager: <info> Activation (eth0) Stage 4
of 5 (IP Configure Get) complete.
Feb 2 21:23:53 desk NetworkManager: <info> Activation (eth0) Stage 5
of 5 (IP Configure Commit) started...
Feb 2 21:23:53 desk avahi-daemon[2071]: Joining mDNS multicast group on
interface eth0.IPv4 with address 192.168.1.2.
Feb 2 21:23:53 desk avahi-daemon[2071]: New relevant interface
eth0.IPv4 for mDNS.
Feb 2 21:23:53 desk avahi-daemon[2071]: Registering new address record
for 192.168.1.2 on eth0.IPv4.
Feb 2 21:23:53 desk dhclient: bound to 192.168.1.2 -- renewal in 80417
seconds.
Feb 2 21:23:54 desk NetworkManager: <WARN>
nm_named_manager_add_ip4_config(): Could not commit DNS changes. Error:
'Could not replace /etc/resolv.conf: Operation not permitted#012'
Feb 2 21:23:54 desk NetworkManager: <info> (eth0): device state
change: 7 -> 8
Feb 2 21:23:54 desk NetworkManager: <WARN>
nm_named_manager_add_ip4_config(): Could not commit DNS changes. Error:
'Could not replace /etc/resolv.conf: Operation not permitted#012'
Feb 2 21:23:54 desk NetworkManager: <info> Policy set 'System eth0'
(eth0) as default for routing and DNS.
Feb 2 21:23:54 desk NetworkManager: <info> Activation (eth0)
successful, device activated.
Feb 2 21:23:54 desk NetworkManager: <info> Activation (eth0) Stage 5
of 5 (IP Configure Commit) complete.
Feb 2 22:56:55 desk NetworkManager: <WARN> nm_signal_handler(): Caught
signal 15, shutting down normally.
Feb 2 22:56:55 desk NetworkManager: <info> (eth0): now unmanaged Feb
2 22:56:55 desk NetworkManager: <info> (eth0): device state
change: 8 -> 1
Feb 2 22:56:55 desk NetworkManager: <info> (eth0): deactivating device
(reason: 36).
Feb 2 22:56:55 desk NetworkManager: <info> eth0: canceled DHCP
transaction, dhcp client pid 4747 Feb 2 22:56:55 desk NetworkManager:
<WARN>
nm_named_manager_remove_ip4_config(): Could not commit DNS changes.
Error: 'Could not replace /etc/resolv.conf: Operation not permitted#012'
Feb 2 22:56:55 desk NetworkManager: <WARN> check_one_route(): (eth0)
error -34 returned from rtnl_route_del(): Sucess#012 Feb 2 22:56:55
desk avahi-daemon[2071]: Withdrawing address record for
192.168.1.2 on eth0.
Feb 2 22:56:55 desk avahi-daemon[2071]: Leaving mDNS multicast group on
interface eth0.IPv4 with address 192.168.1.2.
Feb 2 22:56:55 desk avahi-daemon[2071]: Interface eth0.IPv4 no longer
relevant for mDNS.
Feb 2 22:56:55 desk NetworkManager: <info> (eth0): cleaning up...
Feb 2 22:56:55 desk NetworkManager: <info> (eth0): taking down device.
Feb 2 22:56:55 desk avahi-daemon[2071]: Withdrawing address record for
fe80::207:e9ff:fef2:b64d on eth0.
Feb 2 22:56:55 desk NetworkManager: <info> disconnected by the system
bus.
Feb 2 22:56:56 desk NetworkManager: <info> starting...
Feb 2 22:56:56 desk NetworkManager: <WARN>
nm_generic_enable_loopback(): error -17 returned from
rtnl_addr_add():#012Sucess#012
Feb 2 22:56:56 desk NetworkManager: <info> eth0: driver is 'e100'.
Feb 2 22:56:56 desk NetworkManager: <info> Found new Ethernet device
'eth0'.
Feb 2 22:56:56 desk NetworkManager: <info> (eth0): exported as
/org/freedesktop/Hal/devices/net_00_07_e9_f2_b6_4d
Feb 2 22:57:00 desk NetworkManager: <info> (eth0): device state
change: 1 -> 2
Feb 2 22:57:00 desk NetworkManager: <info> (eth0): bringing up device.
Feb 2 22:57:00 desk kernel: e100: eth0: e100_watchdog: link up,
100Mbps, full-duplex Feb 2 22:57:00 desk kernel: ADDRCONF(NETDEV_UP):
eth0: link is not ready Feb 2 22:57:00 desk kernel:
ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Feb 2 22:57:00 desk
NetworkManager: <info> (eth0): preparing device.
Feb 2 22:57:00 desk NetworkManager: <info> (eth0): deactivating device
(reason: 2).
Feb 2 22:57:00 desk NetworkManager: <info> (eth0): carrier now ON
(device state 2) Feb 2 22:57:00 desk NetworkManager: <info> (eth0):
device state
change: 2 -> 3
Feb 2 22:57:00 desk NetworkManager: <info> Activation (eth0) starting
connection 'System eth0'
Feb 2 22:57:00 desk NetworkManager: <info> (eth0): device state
change: 3 -> 4
Feb 2 22:57:00 desk NetworkManager: <info> Activation (eth0) Stage 1
of 5 (Device Prepare) scheduled...
Feb 2 22:57:00 desk NetworkManager: <info> Activation (eth0) Stage 1
of 5 (Device Prepare) started...
Feb 2 22:57:00 desk NetworkManager: <info> Activation (eth0) Stage 2
of 5 (Device Configure) scheduled...
Feb 2 22:57:00 desk NetworkManager: <info> Activation (eth0) Stage 1
of 5 (Device Prepare) complete.
Feb 2 22:57:00 desk NetworkManager: <info> Activation (eth0) Stage 2
of 5 (Device Configure) starting...
Feb 2 22:57:00 desk NetworkManager: <info> (eth0): device state
change: 4 -> 5
Feb 2 22:57:00 desk NetworkManager: <info> Activation (eth0) Stage 2
of 5 (Device Configure) successful.
Feb 2 22:57:00 desk NetworkManager: <info> Activation (eth0) Stage 3
of 5 (IP Configure Start) scheduled.
Feb 2 22:57:00 desk NetworkManager: <info> Activation (eth0) Stage 2
of 5 (Device Configure) complete.
Feb 2 22:57:00 desk NetworkManager: <info> Activation (eth0) Stage 3
of 5 (IP Configure Start) started...
Feb 2 22:57:00 desk NetworkManager: <info> (eth0): device state
change: 5 -> 7
Feb 2 22:57:00 desk NetworkManager: <info> Activation (eth0) Beginning
DHCP transaction.
Feb 2 22:57:00 desk dhclient: Internet Systems Consortium DHCP Client
4.0.0 Feb 2 22:57:00 desk dhclient: Copyright 2004-2007 Internet
Systems Consortium.
Feb 2 22:57:00 desk dhclient: All rights reserved.
Feb 2 22:57:00 desk dhclient: For info, please visit
http://www.isc.org/sw/dhcp/ Feb 2 22:57:00 desk dhclient:
Feb 2 22:57:00 desk NetworkManager: <info> dhclient started with pid
13377
Feb 2 22:57:00 desk dhclient: Listening on LPF/eth0/00:07:e9:f2:b6:4d
Feb 2 22:57:00 desk dhclient: Sending on LPF/eth0/00:07:e9:f2:b6:4d
Feb 2 22:57:00 desk dhclient: Sending on Socket/fallback
Feb 2 22:57:00 desk dhclient: DHCPDISCOVER on eth0 to 255.255.255.255
port 67 interval 6 Feb 2 22:57:00 desk NetworkManager: <info>
Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
Feb 2 22:57:00 desk NetworkManager: <info> DHCP: device eth0 state
changed (null) -> preinit Feb 2 22:57:01 desk avahi-daemon[2071]:
Registering new address record for fe80::207:e9ff:fef2:b64d on eth0.*.
Feb 2 22:57:02 desk dhclient: DHCPOFFER from 192.168.1.1 Feb 2
22:57:02 desk dhclient: DHCPREQUEST on eth0 to 255.255.255.255 port 67
Feb 2 22:57:02 desk dhclient: DHCPACK from 192.168.1.1 Feb 2 22:57:02
desk NetworkManager: <info> DHCP: device eth0 state changed preinit ->
bound Feb 2 22:57:02 desk NetworkManager: <info> Activation (eth0)
Stage 4 of 5 (IP Configure Get) scheduled...
Feb 2 22:57:02 desk NetworkManager: <info> Activation (eth0) Stage 4
of 5 (IP Configure Get) started...
Feb 2 22:57:02 desk NetworkManager: <info> address 192.168.1.2
Feb 2 22:57:02 desk NetworkManager: <info> prefix 24 (255.255.255.0)
Feb 2 22:57:02 desk NetworkManager: <info> gateway 192.168.1.1
Feb 2 22:57:02 desk NetworkManager: <info> hostname 'desk'
Feb 2 22:57:02 desk NetworkManager: <info> nameserver '192.168.1.1'
Feb 2 22:57:02 desk NetworkManager: <info> domain name
'seclab.net'
Feb 2 22:57:02 desk NetworkManager: <info> Activation (eth0) Stage 5
of 5 (IP Configure Commit) scheduled...
Feb 2 22:57:02 desk NetworkManager: <info> Activation (eth0) Stage 4
of 5 (IP Configure Get) complete.
Feb 2 22:57:02 desk NetworkManager: <info> Activation (eth0) Stage 5
of 5 (IP Configure Commit) started...
Feb 2 22:57:02 desk avahi-daemon[2071]: Joining mDNS multicast group on
interface eth0.IPv4 with address 192.168.1.2.
Feb 2 22:57:02 desk avahi-daemon[2071]: New relevant interface
eth0.IPv4 for mDNS.
Feb 2 22:57:02 desk avahi-daemon[2071]: Registering new address record
for 192.168.1.2 on eth0.IPv4.
Feb 2 22:57:02 desk dhclient: bound to 192.168.1.2 -- renewal in 84449
seconds.
Feb 2 22:57:03 desk NetworkManager: <WARN>
nm_named_manager_add_ip4_config(): Could not commit DNS changes. Error:
'Could not replace /etc/resolv.conf: Operation not permitted#012'
Feb 2 22:57:03 desk NetworkManager: <info> (eth0): device state
change: 7 -> 8
Feb 2 22:57:03 desk NetworkManager: <WARN>
nm_named_manager_add_ip4_config(): Could not commit DNS changes. Error:
'Could not replace /etc/resolv.conf: Operation not permitted#012'
Feb 2 22:57:03 desk NetworkManager: <info> Policy set 'System eth0'
(eth0) as default for routing and DNS.
Feb 2 22:57:03 desk NetworkManager: <info> Activation (eth0)
successful, device activated.
Feb 2 22:57:03 desk NetworkManager: <info> Activation (eth0) Stage 5
of 5 (IP Configure Commit) complete.
[root@desk ~]#
The error message concerning /etc/resolv.conf is due to the removal of
write bit and setting it immutable. NM does create a file called
/etc/resolv.conf.tmp which appears to be a copy of my immutable
resolv.conf.
Dave McGuffey
Principal Information System Security Engineer // NSA-IEM, NSA-IAM
Save a Tree...Unless necessary don't print this e-mail