Re: NM-0.7 hang on at "supplicant interface is now in state 2 (from 1)"

[Dan Williams <dcbw redhat com>]

On Tue, 2007-11-20 at 10:13 +0800, Yung-Ting Chang wrote:
> 2007/11/20, Yung-Ting Chang <foreverr gmail com>:
> > Thank for your reply.
> >
> > I just tried to run NetworkManager today and it's seem to execute
> > nm-applet automatically by NetworkManager.
> >
> > and it's still shown the nm_dhcp_client dbus Error.
> >
> > Error: Could not acquire the NM DHCP client service. Message:
> > (org.freedesktop.DBus.Error.AccessDenied) Connection ":1.43" is not
> > allowed to own the service "org.freedesktop.nm_dhcp_client" due to
> > security policies in the configuration file
> >
> > oh.. and my distribution is ubuntu 4.7.10
> ubuntu 7.10  ... forgive me..

Make sure you have /etc/dbus-1/system.d/nm-dhcp-client.conf and that the
dbus system daemon has been sent a HUP or that you've restarted.

Dan

> >
> > how should I fix the nm_dhcp_client dbus problem?
> > What should I do?
> >
> > following is the entire NM message:
> >
> > # NetworkManager --no-daemon
> >
> > NetworkManager: <info>  starting...
> > NetworkManager: <info>  eth0: Device is fully-supported using driver 'sky2'.
> > NetworkManager: <info>  (eth0): exporting device as
> > /org/freedesktop/NetworkManager/Device/2
> > NetworkManager: <info>  Now managing wired Ethernet (802.3) device 'eth0'.
> > NetworkManager: <info>  Bringing up device eth0
> > NetworkManager: <info>  Deactivating device eth0.
> > NetworkManager: <info>  eth1: Device is fully-supported using driver 'bcm43xx'.
> > NetworkManager: <info>  (eth1): exporting device as
> > /org/freedesktop/NetworkManager/Device/3
> > NetworkManager: <info>  Now managing wireless (802.11) device 'eth1'.
> > NetworkManager: <info>  Bringing up device eth1
> > NetworkManager: <info>  Deactivating device eth1.
> > NetworkManager: <info>  (eth0) supplicant interface is now in state 2 (from 1).
> > NetworkManager: <info>  (eth1) supplicant interface is now in state 2 (from 1).
> > NetworkManager: <info>  SWITCH: no current connection, found better
> > connection 'Auto KGT700_DI624S (eth1)'.
> > NetworkManager: <info>  Activating device eth1
> > NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device
> > Prepare) scheduled...
> > NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device
> > Prepare) started...
> > NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device
> > Configure) scheduled...
> > NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device
> > Prepare) complete.
> > NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device
> > Configure) starting...
> > NetworkManager: <info>  Activation (eth1/wireless): access point 'Auto
> > KGT700_DI624S' has security, but secrets are required.
> > NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device
> > Configure) complete.
> > NetworkManager: Missing or invalid key management
> > NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device
> > Prepare) scheduled...
> > NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device
> > Prepare) started...
> > NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device
> > Configure) scheduled...
> > NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device
> > Prepare) complete.
> > NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device
> > Configure) starting...
> > NetworkManager: <info>  Activation (eth1/wireless): connection 'Auto
> > KGT700_DI624S' has security, and secrets exist.  No new secrets
> > needed.
> > NetworkManager: <info>  Config: added 'ssid' value 'KGT700_DI624S'
> > NetworkManager: <info>  Config: added 'key_mgmt' value 'NONE'
> > NetworkManager: <info>  Config: added 'auth_alg' value 'OPEN'
> > NetworkManager: <info>  Config: added 'wep_key0' value '<omitted>'
> > NetworkManager: <info>  Config: added 'wep_tx_keyidx' value '0'
> > NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device
> > Configure) complete.
> > NetworkManager: <info>  (eth1) Supplicant interface state change: 1 -> 2
> > NetworkManager: <info>  (eth1) Supplicant interface state change: 2 -> 3
> > NetworkManager: <info>  (eth1) Supplicant interface state change: 3 -> 0
> > NetworkManager: <info>  (eth1) Supplicant interface state change: 0 -> 2
> > NetworkManager: <info>  (eth1) Supplicant interface state change: 2 -> 3
> > NetworkManager: <info>  (eth1) Supplicant interface state change: 3 -> 4
> > NetworkManager: <info>  (eth1) Supplicant interface state change: 4 -> 7
> > NetworkManager: <info>  Activation (eth1/wireless) Stage 2 of 5
> > (Device Configure) successful.  Connected to wireless network
> > 'KGT700_DI624S'.
> > NetworkManager: <info>  Activation (eth1) Stage 3 of 5 (IP Configure
> > Start) scheduled.
> > NetworkManager: <info>  Activation (eth1) Stage 3 of 5 (IP Configure
> > Start) started...
> > NetworkManager: <info>  Activation (eth1) Beginning DHCP transaction.
> > Internet Systems Consortium DHCP Client V3.0.5
> > Copyright 2004-2006 Internet Systems Consortium.
> > All rights reserved.
> > For info, please visit http://www.isc.org/sw/dhcp/
> >
> > NetworkManager: <info>  dhclient started with pid 6167
> > NetworkManager: <info>  Activation (eth1) Stage 3 of 5 (IP Configure
> > Start) complete.
> > Error: Could not acquire the NM DHCP client service. Message:
> > (org.freedesktop.DBus.Error.AccessDenied) Connection ":1.42" is not
> > allowed to own the service "org.freedesktop.nm_dhcp_client" due to
> > security policies in the configuration file
> > Listening on LPF/eth1/00:19:7e:8c:05:b0
> > Sending on   LPF/eth1/00:19:7e:8c:05:b0
> > Sending on   Socket/fallback
> > DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 6
> > DHCPOFFER from 192.168.2.1
> > DHCPREQUEST on eth1 to 255.255.255.255 port 67
> > DHCPACK from 192.168.2.1
> > Error: Could not acquire the NM DHCP client service. Message:
> > (org.freedesktop.DBus.Error.AccessDenied) Connection ":1.43" is not
> > allowed to own the service "org.freedesktop.nm_dhcp_client" due to
> > security policies in the configuration file
> > bound to 192.168.2.53 -- renewal in 284945 seconds.
> >
> >
> >
> > Thank you for helping me.
> >
> > Sincerely,
> >
> > Richard Chang
> >
> >
> > 2007/11/20, Dan Williams <dcbw redhat com>:
> > > On Mon, 2007-11-19 at 20:33 +0800, Yung-Ting Chang wrote:
> > > > Sorry for bothering all guys.
> > > > I tried to svn check out Networkmanager-0.7 and nm-applet from SVN
> > > > (revision 3095)
> > > > i followed some information from mail-list and following is what's i did
> > >
> > > You'll likely want to run nm-applet to get a connection.  NM won't do
> > > anything without being told what connections it can use, and those are
> > > provided by the applet.
> > >
> > > Cheers,
> > > Dan
> > >
> > > >
> > > > and following is the wpa_supplicant message:
> > > >
> > > > #wpa_supplicant -u -dd
> > > > Providing DBus service 'fi.epitest.hostap.WPASupplicant'.
> > > > Initializing interface 'eth0' conf 'N/A' driver 'wired' ctrl_interface
> > > > 'N/A' bridge 'N/A'
> > > > Initializing interface (2) 'eth0'
> > > > EAPOL: SUPP_PAE entering state DISCONNECTED
> > > > EAPOL: KEY_RX entering state NO_KEY_RECEIVE
> > > > EAPOL: SUPP_BE entering state INITIALIZE
> > > > EAP: EAP entering state DISABLED
> > > > EAPOL: External notification - portEnabled=0
> > > > EAPOL: External notification - portValid=0
> > > > wpa_driver_wired_init: Added multicast membership with packet socket
> > > > Own MAC address: 00:16:d3:5b:d2:ae
> > > > Setting scan request: 0 sec 100000 usec
> > > > Added interface eth0
> > > > Using wired driver - overriding ap_scan configuration
> > > > Initializing interface 'eth1' conf 'N/A' driver 'wext' ctrl_interface
> > > > 'N/A' bridge 'N/A'
> > > > Initializing interface (2) 'eth1'
> > > > EAPOL: SUPP_PAE entering state DISCONNECTED
> > > > EAPOL: KEY_RX entering state NO_KEY_RECEIVE
> > > > EAPOL: SUPP_BE entering state INITIALIZE
> > > > EAP: EAP entering state DISABLED
> > > > EAPOL: External notification - portEnabled=0
> > > > EAPOL: External notification - portValid=0
> > > > SIOCSIFFLAGS: No such device
> > > > Could not set interface 'eth1' UP
> > > > SIOCGIWRANGE: WE(compiled)=22 WE(source)=18 enc_capa=0xf
> > > >   capabilities: key_mgmt 0xf enc 0xf
> > > > WEXT: Operstate: linkmode=1, operstate=5
> > > > Own MAC address: 00:19:7e:8c:05:b0
> > > > wpa_driver_wext_set_wpa
> > > > wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
> > > > wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
> > > > wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
> > > > wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
> > > > wpa_driver_wext_set_countermeasures
> > > > wpa_driver_wext_set_drop_unencrypted
> > > > Setting scan request: 1 sec 100000 usec
> > > > Added interface eth1
> > > > RTM_NEWLINK: operstate=0 ifi_flags=0x1002 ()
> > > > Wireless event: cmd=0x8b06 len=8
> > > > RTM_NEWLINK: operstate=0 ifi_flags=0x1002 ()
> > > > Wireless event: cmd=0x8b1a len=8
> > > > RTM_NEWLINK: operstate=0 ifi_flags=0x1002 ()
> > > > Wireless event: cmd=0x8b2a len=8
> > > > l2_packet_receive - recvfrom: Network is down
> > > > Setting scan request: 0 sec 0 usec
> > > > State: DISCONNECTED -> SCANNING
> > > > Starting AP scan (broadcast SSID)
> > > > Trying to get current scan results first without requesting a new scan
> > > > to speed up initial association
> > > > Received 0 bytes of scan results (0 BSSes)
> > > > Scan results: 0
> > > > No suitable AP found.
> > > > Setting scan request: 0 sec 0 usec
> > > > No enabled networks - do not scan
> > > > State: SCANNING -> INACTIVE
> > > > Received 0 bytes of scan results (0 BSSes)
> > > > Scan results: 0
> > > > Ignore event for foreign ifindex 2
> > > > ----------------
> > > >
> > > > #NetworkManager --no-daemon
> > > >
> > > > NetworkManager: <info>  starting...
> > > > NetworkManager: <info>  eth0: Device is fully-supported using driver 'sky2'.
> > > > NetworkManager: <info>  (eth0): exporting device as
> > > > /org/freedesktop/NetworkManager/Device/2
> > > > NetworkManager: <info>  Now managing wired Ethernet (802.3) device 'eth0'.
> > > > NetworkManager: <info>  Bringing down device eth0
> > > > NetworkManager: <info>  Bringing up device eth0
> > > > NetworkManager: <info>  Deactivating device eth0.
> > > > NetworkManager: <info>  eth1: Device is fully-supported using driver 'bcm43xx'.
> > > > NetworkManager: <info>  (eth1): exporting device as
> > > > /org/freedesktop/NetworkManager/Device/3
> > > > NetworkManager: <info>  Now managing wireless (802.11) device 'eth1'.
> > > > NetworkManager: <info>  Bringing down device eth1
> > > > NetworkManager: <info>  Bringing up device eth1
> > > > NetworkManager: <info>  Deactivating device eth1.
> > > > NetworkManager: <info>  (eth0) supplicant interface is now in state 2 (from 1).
> > > > NetworkManager: <info>  (eth1) supplicant interface is now in state 2 (from 1).
> > > > -----------------
> > > >
> > > >
> > > > Then the NetworkManager hang on here.
> > > > Is there any advise for me to solve this? thank you a lot!
> > > > should I run nm-applet by myself?
> > > > i ever tried to run nm-applet by myself but still doesn't work and
> > > > shown the following message:
> > > >
> > > > NetworkManager: <info>  starting...
> > > > NetworkManager: <info>  eth0: Device is fully-supported using driver 'sky2'.
> > > > NetworkManager: <info>  (eth0): exporting device as
> > > > /org/freedesktop/NetworkManager/Device/2
> > > > NetworkManager: <info>  Now managing wired Ethernet (802.3) device 'eth0'.
> > > > NetworkManager: <info>  Bringing up device eth0
> > > > NetworkManager: <info>  Deactivating device eth0.
> > > > NetworkManager: <info>  eth1: Device is fully-supported using driver 'bcm43xx'.
> > > > NetworkManager: <info>  (eth1): exporting device as
> > > > /org/freedesktop/NetworkManager/Device/3
> > > > NetworkManager: <info>  Now managing wireless (802.11) device 'eth1'.
> > > > NetworkManager: <info>  Bringing up device eth1
> > > > NetworkManager: <info>  Deactivating device eth1.
> > > > NetworkManager: <info>  (eth0) supplicant interface is now in state 2 (from 1).
> > > > NetworkManager: <info>  (eth1) supplicant interface is now in state 2 (from 1).
> > > > NetworkManager: <info>  SWITCH: no current connection, found better
> > > > connection 'Auto Ethernet (eth0)'.
> > > > NetworkManager: <info>  Activating device eth0
> > > > NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device
> > > > Prepare) scheduled...
> > > > NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device
> > > > Prepare) started...
> > > > NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device
> > > > Configure) scheduled...
> > > > NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device
> > > > Prepare) complete.
> > > > NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device
> > > > Configure) starting...
> > > > NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device
> > > > Configure) successful.
> > > > NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure
> > > > Start) scheduled.
> > > > NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device
> > > > Configure) complete.
> > > > NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure
> > > > Start) started...
> > > > NetworkManager: <info>  Activation (eth0) Beginning DHCP transaction.
> > > > Internet Systems Consortium DHCP Client V3.0.5
> > > > Copyright 2004-2006 Internet Systems Consortium.
> > > > All rights reserved.
> > > > For info, please visit http://www.isc.org/sw/dhcp/
> > > >
> > > > NetworkManager: <info>  dhclient started with pid 17573
> > > > NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure
> > > > Start) complete.
> > > > Error: Could not acquire the NM DHCP client service. Message:
> > > > (org.freedesktop.DBus.Error.AccessDenied) Connection ":1.41" is not
> > > > allowed to own the service "org.freedesktop.nm_dhcp_client" due to
> > > > security policies in the configuration file
> > > > Listening on LPF/eth0/00:16:d3:5b:d2:ae
> > > > Sending on   LPF/eth0/00:16:d3:5b:d2:ae
> > > > Sending on   Socket/fallback
> > > > DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 3
> > > > DHCPOFFER from 10.36.251.1
> > > > DHCPREQUEST on eth0 to 255.255.255.255 port 67
> > > > DHCPACK from 10.36.251.1
> > > > Error: Could not acquire the NM DHCP client service. Message:
> > > > (org.freedesktop.DBus.Error.AccessDenied) Connection ":1.42" is not
> > > > allowed to own the service "org.freedesktop.nm_dhcp_client" due to
> > > > security policies in the configuration file
> > > > bound to 10.36.253.137 -- renewal in 128334 seconds.
> > > > -----------
> > > >
> > > > here is my device info:
> > > > wpa_supplicant v0.5.8
> > > >
> > > > NetworkManager Tool
> > > >
> > > > State: disconnected
> > > >
> > > > - Device: eth0 ----------------------------------------------------------------
> > > >   Type:              Wired
> > > >   Driver:            sky2
> > > >   Active:            no
> > > >   HW Address:        XX:XX:XX:XX:XX:XX
> > > >
> > > >   Capabilities:
> > > >     Supported:       yes
> > > >     Carrier Detect:  yes
> > > >     Speed:           100 Mb/s
> > > >
> > > >   Wired Settings
> > > >
> > > >
> > > > - Device: eth1 ----------------------------------------------------------------
> > > >   Type:              802.11 Wireless
> > > >   Driver:            bcm43xx
> > > >   Active:            no
> > > >   HW Address:        XX:XX:XX:XX:XX:XX
> > > >
> > > >   Capabilities:
> > > >     Supported:       yes
> > > >
> > > >   Wireless Settings
> > > >     WEP Encryption:  yes
> > > >     WPA Encryption:  yes
> > > >     WPA2 Encryption: yes
> > > >
> > > > thank you for your help
> > > >
> > > > Richard Chang.
> > > > _______________________________________________
> > > > NetworkManager-list mailing list
> > > > NetworkManager-list gnome org
> > > > http://mail.gnome.org/mailman/listinfo/networkmanager-list
> > >
> > >
> >