Bug 371500

Summary: wpa_supplicant no wireless access possible
Product: [openSUSE] openSUSE 11.0 Reporter: Casual J. Programmer <casualprogrammer>
Component: NetworkAssignee: Helmut Schaa <hschaa>
Status: RESOLVED FIXED QA Contact: E-mail List <qa-bugs>
Severity: Critical    
Priority: P5 - None CC: felix
Version: Alpha 2plus   
Target Milestone: ---   
Hardware: x86   
OS: openSUSE 11.0   
Whiteboard:
Found By: Beta-Customer Services Priority:
Business Priority: Blocker: ---
Marketing QA Status: --- IT Deployment: ---
Attachments: /var/log/NetworkManager
/var/log/wpa_supplicant.log
wpa_supplicant patch

Description Casual J. Programmer 2008-03-15 14:09:24 UTC
After update from factory network manager does not connect to wireless networks, none are shown, wpa_supplicat not running

iwlist wlan0 shows networks alright.

Notebook: Fujitsu Siemens Amilo Si 1520
Graphics: Fujitsu Siemens Mobile 945GM/GMS/GME, 943/940GML Express
Monitor:  QUANTADISPLAY LCD Monitor 1280x800@60Hz
Wireless: Intel PRO/Wireless 3945ABG Network Connection
Sound:    82801G (ICH7 Family) High Definition Audio Controller
Desktop:  gnome2-SuSE-10.3-168
YaST GUI: yast2-qt-2.16.33-2
OS:       openSUSE 11.0 (i586) Alpha3 VERSION = 11.0
Kernel:   2.6.25-rc4-git1-2-pae

NetworkManager-0.7.0-37
NetworkManager-glib-0.7.0-37
NetworkManager-gnome-0.7.0-35
Comment 1 Casual J. Programmer 2008-03-15 14:12:49 UTC
Created attachment 202314 [details]
/var/log/NetworkManager
Comment 2 Casual J. Programmer 2008-03-16 09:14:46 UTC
It just occurred to me that it doesn't challenge me with the keyring password on starting up either.
Comment 3 Casual J. Programmer 2008-03-16 17:01:48 UTC
Just tried again to connect via wireless, this time open AP, log shows:

Mar 16 17:49:41 workstation6l NetworkManager: <info>  User request for activation of wlan0.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Deactivating device eth0.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  eth0: canceled DHCP transaction, dhclient pid 2810
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activating device wlan0
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (wlan0/wireless): connection 'Auto FON_UMC711' requires no security.  No secrets needed.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Config: added 'ssid' value 'FON_UMC711'
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Config: added 'scan_ssid' value '1'
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Config: added 'key_mgmt' value 'NONE'
Mar 16 17:49:41 workstation6l NetworkManager: nm_supplicant_interface_set_config: assertion `NM_IS_SUPPLICANT_INTERFACE (self)' failed
Mar 16 17:49:41 workstation6l NetworkManager: <WARN>  real_act_stage2_config(): Activation (wlan0/wireless): couldn't send wireless configuration to the supplicant.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (wlan0) failed for access point (FON_UMC711)
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Marking connection 'Auto FON_UMC711' invalid.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (wlan0) failed.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Deactivating device wlan0.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  SWITCH: no current connection, found better connection 'System eth0 (eth0)'.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activating device eth0
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) started...
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) starting...
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) successful.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) complete.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (eth0) Beginning DHCP transaction.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  dhclient started with pid 4647
Mar 16 17:49:41 workstation6l NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
Mar 16 17:49:41 workstation6l NetworkManager: <info>  DHCP: device eth0 state changed normal exit -> preinit
Mar 16 17:49:48 workstation6l NetworkManager: <info>  DHCP: device eth0 state changed preinit -> bound
Mar 16 17:49:48 workstation6l NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) scheduled...
Mar 16 17:49:48 workstation6l NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) started...
Mar 16 17:49:48 workstation6l NetworkManager: <info>    address 192.168.0.30
Mar 16 17:49:48 workstation6l NetworkManager: <info>    netmask 255.255.255.0
Mar 16 17:49:48 workstation6l NetworkManager: <info>    broadcast 192.168.0.255
Mar 16 17:49:48 workstation6l NetworkManager: <info>    gateway 192.168.0.1
Mar 16 17:49:48 workstation6l NetworkManager: <info>    nameserver '192.168.0.1'
Mar 16 17:49:48 workstation6l NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
Mar 16 17:49:48 workstation6l NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) complete.
Mar 16 17:49:48 workstation6l NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
Mar 16 17:49:49 workstation6l NetworkManager: <info>  Clearing nscd hosts cache.
Mar 16 17:49:49 workstation6l NetworkManager: <info>  Setting MTU of interface 'eth0' to 1492
Mar 16 17:49:49 workstation6l NetworkManager: <info>  Activation (eth0) successful, device activated.
Mar 16 17:49:49 workstation6l NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 16 17:50:53 workstation6l NetworkManager: <info>  Trying to start the supplicant...
Mar 16 17:52:53 workstation6l NetworkManager: <info>  Trying to start the supplicant...
Mar 16 17:54:43 workstation6l NetworkManager: <info>  User request for activation of wlan0.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Deactivating device eth0.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  eth0: canceled DHCP transaction, dhclient pid 4647
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activating device wlan0
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (wlan0/wireless): connection 'Auto FON_UMC711' requires no security.  No secrets needed.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Config: added 'ssid' value 'FON_UMC711'
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Config: added 'scan_ssid' value '1'
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Config: added 'key_mgmt' value 'NONE'
Mar 16 17:54:43 workstation6l NetworkManager: nm_supplicant_interface_set_config: assertion `NM_IS_SUPPLICANT_INTERFACE (self)' failed
Mar 16 17:54:43 workstation6l NetworkManager: <WARN>  real_act_stage2_config(): Activation (wlan0/wireless): couldn't send wireless configuration to the supplicant.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (wlan0) failed for access point (FON_UMC711)
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Marking connection 'Auto FON_UMC711' invalid.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (wlan0) failed.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Deactivating device wlan0.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  SWITCH: no current connection, found better connection 'System eth0 (eth0)'.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activating device eth0
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) started...
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) starting...
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) successful.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) complete.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (eth0) Beginning DHCP transaction.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  dhclient started with pid 5036
Mar 16 17:54:43 workstation6l NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
Mar 16 17:54:43 workstation6l NetworkManager: <info>  DHCP: device eth0 state changed normal exit -> preinit
Mar 16 17:54:52 workstation6l NetworkManager: <info>  DHCP: device eth0 state changed preinit -> bound
Mar 16 17:54:52 workstation6l NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) scheduled...
Mar 16 17:54:52 workstation6l NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) started...
Mar 16 17:54:52 workstation6l NetworkManager: <info>    address 192.168.0.30
Mar 16 17:54:52 workstation6l NetworkManager: <info>    netmask 255.255.255.0
Mar 16 17:54:52 workstation6l NetworkManager: <info>    broadcast 192.168.0.255
Mar 16 17:54:52 workstation6l NetworkManager: <info>    gateway 192.168.0.1
Mar 16 17:54:52 workstation6l NetworkManager: <info>    nameserver '192.168.0.1'
Mar 16 17:54:52 workstation6l NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
Mar 16 17:54:52 workstation6l NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) complete.
Mar 16 17:54:52 workstation6l NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
Mar 16 17:54:53 workstation6l NetworkManager: <info>  Clearing nscd hosts cache.
Mar 16 17:54:53 workstation6l NetworkManager: <info>  Setting MTU of interface 'eth0' to 1492
Mar 16 17:54:53 workstation6l NetworkManager: <info>  Activation (eth0) successful, device activated.
Mar 16 17:54:53 workstation6l NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.
Mar 16 17:54:53 workstation6l NetworkManager: <info>  Trying to start the supplicant...
Comment 4 Casual J. Programmer 2008-03-16 17:17:23 UTC
As it appears the http://download.opensuse.org/distribution/SL-OSS-factory/inst-source/suse/i586/wpa_supplicant-0.5.10-3.i586.rpm is broken. Downgrading to the one from alpha DVD 

wpa_supplicant-0.5.8-82

gets me back on air.

Changing summary and component accordingly.
Comment 5 Casual J. Programmer 2008-03-16 17:18:38 UTC
Created attachment 202346 [details]
/var/log/wpa_supplicant.log
Comment 6 Casual J. Programmer 2008-03-16 17:22:26 UTC
Comment on attachment 202346 [details]
/var/log/wpa_supplicant.log

The wpa_supplicant.log may not be helpful after all, as NetworkManager failed to start wpa_supplicant.
Comment 7 JP Rosevear 2008-03-17 12:36:56 UTC
Seeing the same thing with latest factory.
Comment 8 Helmut Schaa 2008-03-17 13:35:11 UTC
*** Bug 371571 has been marked as a duplicate of this bug. ***
Comment 9 Helmut Schaa 2008-03-17 13:35:59 UTC
I guess that one is for me...
Comment 10 Helmut Schaa 2008-03-17 14:09:48 UTC
Just submitted a fixed wpa_supplicant to STABLE. Should be available soon.

Thanks for reporting!
Comment 11 Felix Möller 2008-03-17 14:47:12 UTC
Thanks alot.

Helmut it would be nice if you could attach the patch.
Comment 12 Helmut Schaa 2008-03-17 14:52:37 UTC
Sure :)

The culprit is /usr/share/dbus-1/system-services/fi.epitest.hostap.WPASupplicant.service.

Just have a look at the attached patch...
Comment 13 Helmut Schaa 2008-03-17 14:53:18 UTC
Created attachment 202588 [details]
wpa_supplicant patch