Re: two system eth, chooses one w/o a gateway, makes up gateway

[Chuck Anderson <cra WPI EDU>]

On Wed, Dec 03, 2008 at 06:10:08PM -0500, Dan Williams wrote:
> Do you have a gateway in /etc/sysconfig/networking at all?

No.  This is the entire /etc/sysconfig/network file:

NETWORKING=yes
HOSTNAME=dustpuppy.wpi.edu
NETWORKWAIT=yes

and I checked for GATEWAY in all network script files:

>grep -r GATEWAY /etc/sysconfig/networking/ /etc/sysconfig/network /etc/sysconfig/network-scripts/ifcfg-*
/etc/sysconfig/networking/devices/ifcfg-eth1:#GATEWAY=
/etc/sysconfig/networking/profiles/default/ifcfg-eth1:#GATEWAY=
/etc/sysconfig/network-scripts/ifcfg-eth1:#GATEWAY=

> Can you post some bits from /var/log/messages that show NM bringing up
> the connection so I can see where the bogus gateway might be coming
> from?

Attached.  This is the sequence from system bootup.

Dec  1 15:07:10 dustpuppy NetworkManager: <info>  starting...
Dec  1 15:07:10 dustpuppy NetworkManager: <WARN>  nm_generic_enable_loopback(): error -17 returned from rtnl_addr_add():#012Sucess#012
Dec  1 15:07:10 dustpuppy NetworkManager: <info>  eth1: driver is 'tulip'.
Dec  1 15:07:10 dustpuppy NetworkManager: <info>  Found new Ethernet device 'eth1'.
Dec  1 15:07:10 dustpuppy NetworkManager: <info>  (eth1): exported as /org/freedesktop/Hal/devices/net_00_a0_cc_da_ed_04
Dec  1 15:07:10 dustpuppy NetworkManager: <info>  eth0: driver is 'tg3'.
Dec  1 15:07:10 dustpuppy NetworkManager: <info>  Found new Ethernet device 'eth0'.
Dec  1 15:07:10 dustpuppy NetworkManager: <info>  (eth0): exported as /org/freedesktop/Hal/devices/net_00_13_72_74_da_15
Dec  1 15:07:10 dustpuppy NetworkManager: <info>  Trying to start the supplicant...
Dec  1 15:07:10 dustpuppy NetworkManager: <info>  Trying to start the system settings daemon...
Dec  1 15:07:11 dustpuppy nm-system-settings: Loaded plugin ifcfg-fedora: (c) 2007 - 2008 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
Dec  1 15:07:12 dustpuppy nm-system-settings:    ifcfg-fedora: parsing /etc/sysconfig/network-scripts/ifcfg-eth0 ... 
Dec  1 15:07:12 dustpuppy nm-system-settings:    ifcfg-fedora:     read connection 'System eth0'
Dec  1 15:07:12 dustpuppy nm-system-settings:    ifcfg-fedora: parsing /etc/sysconfig/network-scripts/ifcfg-lo ... 
Dec  1 15:07:12 dustpuppy nm-system-settings:    ifcfg-fedora:     error: Ignoring loopback device config.
Dec  1 15:07:12 dustpuppy nm-system-settings:    ifcfg-fedora: parsing /etc/sysconfig/network-scripts/ifcfg-eth1 ... 
Dec  1 15:07:12 dustpuppy nm-system-settings:    ifcfg-fedora:     read connection 'System eth1'
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  (eth1): device state change: 1 -> 2
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  (eth1): bringing up device.
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  (eth1): preparing device.
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  (eth1): deactivating device (reason: 2).
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  (eth1): carrier now ON (device state 2)
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  (eth1): device state change: 2 -> 3
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) starting connection 'System eth1'
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  (eth1): device state change: 3 -> 4
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) scheduled...
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) started...
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) scheduled...
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) complete.
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) starting...
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  (eth1): device state change: 4 -> 5
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) successful.
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 3 of 5 (IP Configure Start) scheduled.
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) complete.
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 3 of 5 (IP Configure Start) started...
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  (eth1): device state change: 5 -> 7
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 4 of 5 (IP Configure Get) scheduled...
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 3 of 5 (IP Configure Start) complete.
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 4 of 5 (IP Configure Get) started...
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 5 of 5 (IP Configure Commit) scheduled...
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 4 of 5 (IP Configure Get) complete.
Dec  1 15:07:14 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 5 of 5 (IP Configure Commit) started...
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  (eth1): device state change: 7 -> 8
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  Policy set 'System eth1' (eth1) as default for routing and DNS.
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  Activation (eth1) successful, device activated.
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  Activation (eth1) Stage 5 of 5 (IP Configure Commit) complete.
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  (eth0): device state change: 1 -> 2
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  (eth0): bringing up device.
Dec  1 15:07:16 dustpuppy kernel: ADDRCONF(NETDEV_UP): eth0: link is not ready
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  (eth0): preparing device.
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  (eth0): deactivating device (reason: 2).
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  Policy set 'System eth1' (eth1) as default for routing and DNS.
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  (eth0): carrier now ON (device state 2)
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  (eth0): device state change: 2 -> 3
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  (eth0): carrier now OFF (device state 3)
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  (eth0): device state change: 3 -> 2
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  (eth0): deactivating device (reason: 40).
Dec  1 15:07:16 dustpuppy NetworkManager: <info>  Policy set 'System eth1' (eth1) as default for routing and DNS.
Dec  1 15:07:16 dustpuppy NetworkManager: <WARN>  auto_activate_device(): Connection 'System eth0' auto-activation failed: (2) Device not managed by NetworkManager
Dec  1 15:07:18 dustpuppy kernel: tg3: eth0: Link is up at 1000 Mbps, full duplex.
Dec  1 15:07:18 dustpuppy kernel: tg3: eth0: Flow control is on for TX and on for RX.
Dec  1 15:07:18 dustpuppy kernel: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Dec  1 15:07:21 dustpuppy kernel: tg3: eth0: Link is up at 1000 Mbps, full duplex.
Dec  1 15:07:21 dustpuppy kernel: tg3: eth0: Flow control is on for TX and on for RX.
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  (eth0): carrier now ON (device state 2)
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  (eth0): device state change: 2 -> 3
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  Activation (eth0) starting connection 'System eth0'
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  (eth0): device state change: 3 -> 4
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) started...
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) starting...
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  (eth0): device state change: 4 -> 5
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) successful.
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) complete.
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  (eth0): device state change: 5 -> 7
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  Activation (eth0) Beginning DHCP transaction.
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  dhclient started with pid 2320
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
Dec  1 15:07:21 dustpuppy dhclient: Internet Systems Consortium DHCP Client 4.0.0
Dec  1 15:07:21 dustpuppy dhclient: Copyright 2004-2007 Internet Systems Consortium.
Dec  1 15:07:21 dustpuppy dhclient: All rights reserved.
Dec  1 15:07:21 dustpuppy dhclient: For info, please visit http://www.isc.org/sw/dhcp/
Dec  1 15:07:21 dustpuppy dhclient: 
Dec  1 15:07:21 dustpuppy NetworkManager: <info>  DHCP: device eth0 state changed normal exit -> preinit
Dec  1 15:07:21 dustpuppy dhclient: Listening on LPF/eth0/00:13:72:74:da:15
Dec  1 15:07:21 dustpuppy dhclient: Sending on   LPF/eth0/00:13:72:74:da:15
Dec  1 15:07:21 dustpuppy dhclient: Sending on   Socket/fallback
Dec  1 15:07:24 dustpuppy dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 6
Dec  1 15:07:30 dustpuppy dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 6
Dec  1 15:07:30 dustpuppy dhclient: DHCPOFFER from 130.215.201.3
Dec  1 15:07:30 dustpuppy dhclient: DHCPREQUEST on eth0 to 255.255.255.255 port 67
Dec  1 15:07:30 dustpuppy dhclient: DHCPACK from 130.215.201.3
Dec  1 15:07:30 dustpuppy NetworkManager: <info>  DHCP: device eth0 state changed preinit -> bound
Dec  1 15:07:30 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) scheduled...
Dec  1 15:07:30 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) started...
Dec  1 15:07:30 dustpuppy NetworkManager: <info>    address 130.215.201.30
Dec  1 15:07:30 dustpuppy NetworkManager: <info>    prefix 24 (255.255.255.0)
Dec  1 15:07:30 dustpuppy NetworkManager: <info>    gateway 130.215.201.1
Dec  1 15:07:30 dustpuppy NetworkManager: <info>    hostname 'DUSTPUPPY.WPI.EDU'
Dec  1 15:07:30 dustpuppy NetworkManager: <info>    nameserver '130.215.32.18'
Dec  1 15:07:30 dustpuppy NetworkManager: <info>    nameserver '130.215.39.18'
Dec  1 15:07:30 dustpuppy NetworkManager: <info>    nameserver '130.215.36.18'
Dec  1 15:07:30 dustpuppy NetworkManager: <info>    nameserver '130.215.39.82'
Dec  1 15:07:30 dustpuppy NetworkManager: <info>    domain name 'wpi.edu'
Dec  1 15:07:30 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
Dec  1 15:07:30 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) complete.
Dec  1 15:07:30 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
Dec  1 15:07:30 dustpuppy dhclient: bound to 130.215.201.30 -- renewal in 3284 seconds.
Dec  1 15:07:31 dustpuppy NetworkManager: <info>  Policy set 'System eth1' (eth1) as default for routing and DNS.
Dec  1 15:07:31 dustpuppy NetworkManager: <info>  (eth0): device state change: 7 -> 8
Dec  1 15:07:31 dustpuppy NetworkManager: <info>  Activation (eth0) successful, device activated.
Dec  1 15:07:31 dustpuppy NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.