Re: NM not seeing mi wireless until I restart service

[Louis E Garcia II <louisg00 bellsouth net>]

On Sun, 2006-10-29 at 16:29 -0500, Dan Williams wrote:
> On Sun, 2006-10-29 at 12:49 -0500, Louis E Garcia II wrote:
> > Not sure what changed between fc6test3 and fc6 but NetworkManager is not
> > seeing my ipw3945 when booting. When I login I have to restart the
> > NetworkManager service for wireless to work. 
> 
> Can you post some logs from /var/log/messages?  Better yet, get root,
> then do:
> 
> service NetworkManager stop
> /usr/sbin/NetworkManager --no-daemon
> 
> let it run for 30 seconds, then mail the output here.  Hit Ctrl-C to
> stop it.
> 
> Dan
> 
> > I noticed when running fc6test3 NM scanned for network devices more than
> > once. With fc6 NM scans only once. Is this correct?
> > 
> > -Louis
> >

What below shows is NM working fine when the service is started
manually. The problem is at boot, see boot.log attachment. NM does not
see eth1 which is the wlan card. I start ipw3945d, the regulatory
daemon, from rc.local.

[root sonlaptop louisg00]# /sbin/service NetworkManager stop
Stopping NetworkManager daemon:                            [  OK  ]
[root sonlaptop louisg00]# /usr/sbin/NetworkManager --no-daemon
NetworkManager: <information>   starting...
NetworkManager: <information>   eth1: Device is fully-supported using
driver 'ipw3945'.
NetworkManager: <information>   nm_device_init(): waiting for device's
worker thread to start
NetworkManager: <information>   nm_device_init(): device's worker thread
started, continuing.
NetworkManager: <information>   Now managing wireless (802.11) device
'eth1'.
NetworkManager: <information>   Deactivating device eth1.
NetworkManager: <information>   eth0: Device is fully-supported using
driver 'e100'.
NetworkManager: <information>   nm_device_init(): waiting for device's
worker thread to start
NetworkManager: <information>   nm_device_init(): device's worker thread
started, continuing.
NetworkManager: <information>   Now managing wired Ethernet (802.3)
device 'eth0'.
NetworkManager: <information>   Deactivating device eth0.
NetworkManager: <information>   Updating allowed wireless network lists.
NetworkManager: <information>   SWITCH: no current connection, found
better connection 'eth1'.
NetworkManager: <information>   Will activate connection
'eth1/HomeNetworkWiFi'.
NetworkManager: <information>   Device eth1 activation scheduled...
NetworkManager: <information>   Activation (eth1) started...
NetworkManager: <information>   Activation (eth1) Stage 1 of 5 (Device
Prepare) scheduled...
NetworkManager: <information>   Activation (eth1) Stage 1 of 5 (Device
Prepare) started...
NetworkManager: <information>   Activation (eth1) Stage 2 of 5 (Device
Configure) scheduled...
NetworkManager: <information>   Activation (eth1) Stage 1 of 5 (Device
Prepare) complete.
NetworkManager: <information>   Activation (eth1) Stage 2 of 5 (Device
Configure) starting...
NetworkManager: <information>   Activation (eth1/wireless): access point
'HomeNetworkWiFi' is encrypted, but NO valid key exists.  New key
needed.
NetworkManager: <information>   Activation (eth1) New wireless user key
requested for network 'HomeNetworkWiFi'.
NetworkManager: <information>   Activation (eth1) Stage 2 of 5 (Device
Configure) complete.
NetworkManager: <information>   Activation (eth1) New wireless user key
for network 'HomeNetworkWiFi' received.
NetworkManager: <information>   Activation (eth1) Stage 1 of 5 (Device
Prepare) scheduled...
NetworkManager: <information>   Activation (eth1) Stage 1 of 5 (Device
Prepare) started...
NetworkManager: <information>   Activation (eth1) Stage 2 of 5 (Device
Configure) scheduled...
NetworkManager: <information>   Activation (eth1) Stage 1 of 5 (Device
Prepare) complete.
NetworkManager: <information>   Activation (eth1) Stage 2 of 5 (Device
Configure) starting...
NetworkManager: <information>   Activation (eth1/wireless): access point
'HomeNetworkWiFi' is encrypted, and a key exists.  No new key needed.
NetworkManager: <information>   SUP: sending command 'INTERFACE_ADD eth1
wext     /var/run/wpa_supplicant '
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'AP_SCAN 2'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'ADD_NETWORK'
NetworkManager: <information>   SUP: response was '0'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 ssid
486f6d654e6574776f726b57694669'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0
scan_ssid 1'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0
proto WPA'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0
key_mgmt WPA-PSK'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 psk
<key>'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0
pairwise TKIP'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0
group TKIP'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'ENABLE_NETWORK 0'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   Activation (eth1) Stage 2 of 5 (Device
Configure) complete.
NetworkManager: <information>   Activation (eth1/wireless) Stage 2 of 5
(Device Configure) successful.  Connected to access point
'HomeNetworkWiFi'.
NetworkManager: <information>   Activation (eth1) Stage 3 of 5 (IP
Configure Start) scheduled.
NetworkManager: <information>   Activation (eth1) Stage 3 of 5 (IP
Configure Start) started...
NetworkManager: <information>   Activation (eth1) Beginning DHCP
transaction.
NetworkManager: <information>   Activation (eth1) Stage 3 of 5 (IP
Configure Start) complete.
NetworkManager: <information>   DHCP daemon state is now 12
(successfully started) for interface eth1
NetworkManager: <information>   DHCP daemon state is now 1 (starting)
for interface eth1
NetworkManager: <information>   DHCP daemon state is now 4 (reboot) for
interface eth1
NetworkManager: <information>   Activation (eth1) Stage 4 of 5 (IP
Configure Get) scheduled...
NetworkManager: <information>   Activation (eth1) Stage 4 of 5 (IP
Configure Get) started...
NetworkManager: <information>   Retrieved the following IP4
configuration from the DHCP daemon:
NetworkManager: <information>     address 10.0.0.10
NetworkManager: <information>     netmask 255.255.0.0
NetworkManager: <information>     broadcast 10.0.255.255
NetworkManager: <information>     gateway 10.0.0.1
NetworkManager: <information>     nameserver 10.0.0.1
NetworkManager: <information>   Activation (eth1) Stage 5 of 5 (IP
Configure Commit) scheduled...
NetworkManager: <information>   Activation (eth1) Stage 4 of 5 (IP
Configure Get) complete.
NetworkManager: <information>   Activation (eth1) Stage 5 of 5 (IP
Configure Commit) started...
NetworkManager: <information>   Activation (eth1) Finish handler
scheduled.
NetworkManager: <information>   Activation (eth1) Stage 5 of 5 (IP
Configure Commit) complete.
NetworkManager: <information>   Activation (eth1) successful, device
activated.

Oct 29 17:51:41 sonlaptop kernel: ieee80211: 802.11 data/management/control stack, git-1.1.13
Oct 29 17:51:41 sonlaptop kernel: ieee80211: Copyright (C) 2004-2005 Intel Corporation <jketreno linux intel com>
Oct 29 17:51:41 sonlaptop kernel: uvcvideo: Found UVC 1.00 device USB 2.0 Camera (0c45:62c0)
Oct 29 17:51:41 sonlaptop kernel: usbcore: registered new driver uvcvideo
Oct 29 17:51:41 sonlaptop kernel: USB Video Class driver (v0.1.0)
Oct 29 17:51:41 sonlaptop kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0
Oct 29 17:51:41 sonlaptop kernel: e100: Intel(R) PRO/100 Network Driver, 3.5.10-k2-NAPI
Oct 29 17:51:41 sonlaptop kernel: e100: Copyright(c) 1999-2005 Intel Corporation
Oct 29 17:51:41 sonlaptop kernel: ACPI: PCI Interrupt 0000:08:08.0[A] -> GSI 20 (level, low) -> IRQ 66
Oct 29 17:51:41 sonlaptop kernel: e100: eth0: e100_probe: addr 0xd4100000, irq 66, MAC addr 00:16:D3:0D:17:CC
Oct 29 17:51:41 sonlaptop kernel: ipw3945: Intel(R) PRO/Wireless 3945 Network Connection driver for Linux, 1.1.0d
Oct 29 17:51:41 sonlaptop kernel: ipw3945: Copyright(c) 2003-2006 Intel Corporation
Oct 29 17:51:41 sonlaptop kernel: ACPI: PCI Interrupt 0000:05:00.0[A] -> GSI 19 (level, low) -> IRQ 185
Oct 29 17:51:41 sonlaptop kernel: ipw3945: Detected Intel PRO/Wireless 3945ABG Network Connection
Oct 29 17:51:41 sonlaptop kernel: sdhci: Secure Digital Host Controller Interface driver, 0.12
Oct 29 17:51:41 sonlaptop kernel: sdhci: Copyright(c) Pierre Ossman
Oct 29 17:51:41 sonlaptop kernel: ACPI: PCI Interrupt 0000:08:09.0[A] -> GSI 16 (level, low) -> IRQ 50
Oct 29 17:51:41 sonlaptop kernel: ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[50]  MMIO=[d4101000-d41017ff]  Max Packet=[2048]  IR/IT contexts=[4/4]
Oct 29 17:51:41 sonlaptop kernel: ACPI: PCI Interrupt 0000:00:1f.3[B] -> GSI 19 (level, low) -> IRQ 185
Oct 29 17:51:41 sonlaptop kernel: sdhci: SDHCI controller found at 0000:08:09.1 [1180:0822] (rev 19)
Oct 29 17:51:41 sonlaptop kernel: ACPI: PCI Interrupt 0000:08:09.1[B] -> GSI 18 (level, low) -> IRQ 177
Oct 29 17:51:41 sonlaptop kernel: mmc0: SDHCI at 0xd4101800 irq 177 DMA
Oct 29 17:51:41 sonlaptop kernel: Intel 82802 RNG detected
Oct 29 17:51:41 sonlaptop kernel: hda: ATAPI 24X DVD-ROM DVD-R-RAM CD-R/RW drive, 2048kB Cache, DMA
Oct 29 17:51:41 sonlaptop kernel: Uniform CD-ROM driver Revision: 3.20
Oct 29 17:51:41 sonlaptop kernel: ACPI: PCI Interrupt 0000:00:1b.0[A] -> GSI 22 (level, low) -> IRQ 74
Oct 29 17:51:41 sonlaptop kernel: floppy0: no floppy controllers found
Oct 29 17:51:41 sonlaptop kernel: lp: driver loaded but no devices found
Oct 29 17:51:41 sonlaptop kernel: SELinux: initialized (dev ramfs, type ramfs), uses genfs_contexts
Oct 29 17:51:41 sonlaptop kernel: NET: Registered protocol family 10
Oct 29 17:51:41 sonlaptop kernel: lo: Disabled Privacy Extensions
Oct 29 17:51:41 sonlaptop kernel: IPv6 over IPv4 tunneling driver
Oct 29 17:51:41 sonlaptop kernel: ACPI: AC Adapter [ADP1] (off-line)
Oct 29 17:51:41 sonlaptop kernel: ACPI: Battery Slot [BAT0] (battery present)
Oct 29 17:51:41 sonlaptop kernel: ACPI: Power Button (FF) [PWRF]
Oct 29 17:51:41 sonlaptop kernel: ACPI: Lid Switch [LID0]
Oct 29 17:51:41 sonlaptop kernel: ACPI: Sleep Button (CM) [SLPB]
Oct 29 17:51:41 sonlaptop kernel: ACPI: Power Button (CM) [PWRB]
Oct 29 17:51:41 sonlaptop kernel: ibm_acpi: ec object not found
Oct 29 17:51:41 sonlaptop kernel: ACPI: Video Device [VGA] (multi-head: yes  rom: no  post: no)
Oct 29 17:51:41 sonlaptop kernel: ACPI: Video Device [GFX0] (multi-head: yes  rom: no  post: no)
Oct 29 17:51:41 sonlaptop kernel: md: Autodetecting RAID arrays.
Oct 29 17:51:41 sonlaptop kernel: md: autorun ...
Oct 29 17:51:41 sonlaptop kernel: md: ... autorun DONE.
Oct 29 17:51:41 sonlaptop kernel: device-mapper: multipath: version 1.0.4 loaded
Oct 29 17:51:41 sonlaptop kernel: EXT3 FS on sda3, internal journal
Oct 29 17:51:41 sonlaptop kernel: kjournald starting.  Commit interval 5 seconds
Oct 29 17:51:41 sonlaptop kernel: EXT3 FS on sda2, internal journal
Oct 29 17:51:41 sonlaptop kernel: EXT3-fs: mounted filesystem with ordered data mode.
Oct 29 17:51:41 sonlaptop kernel: SELinux: initialized (dev sda2, type ext3), uses xattr
Oct 29 17:51:41 sonlaptop kernel: SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
Oct 29 17:51:41 sonlaptop kernel: kjournald starting.  Commit interval 5 seconds
Oct 29 17:51:41 sonlaptop kernel: EXT3-fs warning: maximal mount count reached, running e2fsck is recommended
Oct 29 17:51:41 sonlaptop kernel: EXT3 FS on dm-0, internal journal
Oct 29 17:51:41 sonlaptop kernel: EXT3-fs: mounted filesystem with ordered data mode.
Oct 29 17:51:41 sonlaptop kernel: SELinux: initialized (dev dm-0, type ext3), uses xattr
Oct 29 17:51:41 sonlaptop kernel: Adding 1020088k swap on /dev/sda5.  Priority:-1 extents:1 across:1020088k
Oct 29 17:51:41 sonlaptop kernel: SELinux: initialized (dev binfmt_misc, type binfmt_misc), uses genfs_contexts
Oct 29 17:51:41 sonlaptop kernel: ip6_tables: (C) 2000-2006 Netfilter Core Team
Oct 29 17:51:41 sonlaptop kernel: ip_tables: (C) 2000-2006 Netfilter Core Team
Oct 29 17:51:41 sonlaptop kernel: Netfilter messages via NETLINK v0.30.
Oct 29 17:51:41 sonlaptop kernel: ip_conntrack version 2.4 (8116 buckets, 64928 max) - 228 bytes per conntrack
Oct 29 17:51:41 sonlaptop kernel: process `sysctl' is using deprecated sysctl (syscall) net.ipv6.neigh.lo.base_reachable_time; Use net.ipv6.neigh.lo.base_reachable_time_ms instead.
Oct 29 17:51:41 sonlaptop kernel: SELinux: initialized (dev rpc_pipefs, type rpc_pipefs), uses genfs_contexts
Oct 29 17:51:41 sonlaptop kernel: Bluetooth: Core ver 2.10
Oct 29 17:51:41 sonlaptop kernel: NET: Registered protocol family 31
Oct 29 17:51:41 sonlaptop kernel: Bluetooth: HCI device and connection manager initialized
Oct 29 17:51:41 sonlaptop kernel: Bluetooth: HCI socket layer initialized
Oct 29 17:51:41 sonlaptop kernel: Bluetooth: L2CAP ver 2.8
Oct 29 17:51:41 sonlaptop kernel: Bluetooth: L2CAP socket layer initialized
Oct 29 17:51:41 sonlaptop kernel: Bluetooth: RFCOMM socket layer initialized
Oct 29 17:51:41 sonlaptop kernel: Bluetooth: RFCOMM TTY layer initialized
Oct 29 17:51:41 sonlaptop kernel: Bluetooth: RFCOMM ver 1.8
Oct 29 17:51:41 sonlaptop kernel: Bluetooth: HIDP (Human Interface Emulation) ver 1.1
Oct 29 17:51:41 sonlaptop kernel: SELinux: initialized (dev autofs, type autofs), uses genfs_contexts
Oct 29 17:51:41 sonlaptop kernel: SELinux: initialized (dev autofs, type autofs), uses genfs_contexts
Oct 29 17:51:42 sonlaptop python: hpssd[2246] error: Server exited with error: Unable to bind to socket
Oct 29 17:51:42 sonlaptop gpm[2267]: *** info [startup.c(95)]: 
Oct 29 17:51:42 sonlaptop gpm[2267]: Started gpm successfully. Entered daemon mode.
Oct 29 17:51:45 sonlaptop avahi-daemon[2352]: Found user 'avahi' (UID 70) and group 'avahi' (GID 70).
Oct 29 17:51:45 sonlaptop avahi-daemon[2352]: Successfully dropped root privileges.
Oct 29 17:51:45 sonlaptop avahi-daemon[2352]: avahi-daemon 0.6.11 starting up.
Oct 29 17:51:46 sonlaptop avahi-daemon[2352]: WARNING: No NSS support for mDNS detected, consider installing nss-mdns!
Oct 29 17:51:46 sonlaptop avahi-daemon[2352]: Successfully called chroot().
Oct 29 17:51:46 sonlaptop avahi-daemon[2352]: Successfully dropped remaining capabilities.
Oct 29 17:51:46 sonlaptop avahi-daemon[2352]: No service found in /etc/avahi/services.
Oct 29 17:51:46 sonlaptop avahi-daemon[2352]: Network interface enumeration completed.
Oct 29 17:51:46 sonlaptop avahi-daemon[2352]: Registering HINFO record with values 'I686'/'LINUX'.
Oct 29 17:51:46 sonlaptop avahi-daemon[2352]: Server startup complete. Host name is sonlaptop.local. Local service cookie is 1025138811.
Oct 29 17:51:50 sonlaptop dhcdbd: Started up.
Oct 29 17:51:50 sonlaptop NetworkManager: <information> starting... 
Oct 29 17:51:51 sonlaptop kernel: ADDRCONF(NETDEV_UP): eth0: link is not ready
Oct 29 17:51:51 sonlaptop NetworkManager: <information> eth0: Device is fully-supported using driver 'e100'. 
Oct 29 17:51:51 sonlaptop NetworkManager: <information> nm_device_init(): waiting for device's worker thread to start 
Oct 29 17:51:51 sonlaptop NetworkManager: <information> nm_device_init(): device's worker thread started, continuing. 
Oct 29 17:51:51 sonlaptop NetworkManager: <information> Now managing wired Ethernet (802.3) device 'eth0'. 
Oct 29 17:51:51 sonlaptop NetworkManager: <information> Deactivating device eth0. 
Oct 29 17:51:52 sonlaptop kernel: ipw3945: Detected geography ABG (11 802.11bg channels, 13 802.11a channels)
Oct 29 17:51:52 sonlaptop smartd[2493]: smartd version 5.36 [i686-redhat-linux-gnu] Copyright (C) 2002-6 Bruce Allen 
Oct 29 17:51:52 sonlaptop smartd[2493]: Home page is http://smartmontools.sourceforge.net/  
Oct 29 17:51:52 sonlaptop smartd[2493]: Opened configuration file /etc/smartd.conf 
Oct 29 17:51:52 sonlaptop smartd[2493]: Configuration file /etc/smartd.conf parsed. 
Oct 29 17:51:52 sonlaptop smartd[2493]: Device: /dev/sda, opened 
Oct 29 17:51:52 sonlaptop smartd[2493]: Device: /dev/sda, not found in smartd database. 
Oct 29 17:51:53 sonlaptop smartd[2493]: Device: /dev/sda, is SMART capable. Adding to "monitor" list. 
Oct 29 17:51:53 sonlaptop smartd[2493]: Monitoring 1 ATA and 0 SCSI devices 
Oct 29 17:51:53 sonlaptop smartd[2517]: smartd has fork()ed into background mode. New PID=2517. 
Oct 29 17:51:55 sonlaptop pcscd: winscard.c:219:SCardConnect() Reader E-Gate 0 0 Not Found
Oct 29 17:51:55 sonlaptop last message repeated 9 times
Oct 29 17:51:56 sonlaptop kernel: [drm] Initialized drm 1.0.1 20051102
Oct 29 17:51:56 sonlaptop kernel: ACPI: PCI Interrupt 0000:00:02.0[A] -> GSI 16 (level, low) -> IRQ 50
Oct 29 17:51:56 sonlaptop kernel: [drm] Initialized i915 1.5.0 20060119 on minor 0
Oct 29 17:53:07 sonlaptop gconfd (louisg00-2745): starting (version 2.14.0), pid 2745 user 'louisg00'
Oct 29 17:53:07 sonlaptop gconfd (louisg00-2745): Resolved address "xml:readonly:/etc/gconf/gconf.xml.mandatory" to a read-only configuration source at position 0
Oct 29 17:53:07 sonlaptop gconfd (louisg00-2745): Resolved address "xml:readwrite:/home/louisg00/.gconf" to a writable configuration source at position 1
Oct 29 17:53:07 sonlaptop gconfd (louisg00-2745): Resolved address "xml:readonly:/etc/gconf/gconf.xml.defaults" to a read-only configuration source at position 2
Oct 29 17:53:09 sonlaptop hcid[2127]: Default passkey agent (:1.10, /org/bluez/applet) registered
Oct 29 17:53:09 sonlaptop gconfd (louisg00-2745): Resolved address "xml:readwrite:/home/louisg00/.gconf" to a writable configuration source at position 0
Oct 29 17:53:10 sonlaptop pcscd: winscard.c:219:SCardConnect() Reader E-Gate 0 0 Not Found
Oct 29 17:53:10 sonlaptop last message repeated 4 times
Oct 29 17:53:11 sonlaptop NetworkManager: <information> Updating allowed wireless network lists. 
Oct 29 17:53:13 sonlaptop yum-updatesd: error getting update info: Cannot find a valid baseurl for repo: core