NM fails to connect after updating existing gconf keys

[Rene Rask <rene grain dk>]

Hi list

I've been having a lot of problems with NM but no time to report them.

This one is the:

NetworkManager: <WARNING>         ():
org.freedesktop.NetworkManagerInfo.BadNetworkData raised
'NetworkManagerInfo::getNetworkProperties could not access essid for
network 'networks''

type of error. I've been connecting to my office network without
problems for a while. On a slow day I decided to sit in the couch in our
lobby and read som Rails documentation. Did that for a while and
suddenly the network connection just died on me. There was nothing I
could do to connect again.

The usual suspects.

1. I moved, so I was closest to our second AP. Which I guess triggered
the problem by needing an gconf update.

2. The gconf handling is broken in some odd way. Deleting the wireless
network details for my office allows me to connect again. 

This localized to the network I'm trying to connect to. At home I have
not problems with my single AP.

Hardware details: IBM x40 with an Ipw2200.  

I've attached the log for a failed attempt. After that I deleted the
keys in gconf related the the 'duck' essid and got a connection without
any issues.

Cheers
Rene

[root xrene ~]# NetworkManager --no-daemon
NetworkManager: <information>   starting...
NetworkManager: <information>   eth0: Driver support level for 'e1000' is fully-supported
NetworkManager: <information>   nm_device_new(): waiting for device's worker thread to start
NetworkManager: <information>   nm_device_new(): device's worker thread started, continuing.
NetworkManager: <information>   Now managing wired device 'eth0'.
NetworkManager: <information>   eth1: Driver support level for 'ipw2200' is fully-supported
NetworkManager: <information>   nm_device_new(): waiting for device's worker thread to start
NetworkManager: <information>   nm_device_new(): device's worker thread started, continuing.
NetworkManager: <information>   Now managing wireless device 'eth1'.
NetworkManager: <information>   named started with pid 10361
NetworkManager: <information>   Updating allowed wireless network lists.
NetworkManager: <information>       SWITCH: best device changed
NetworkManager: <information>   Activation (eth0) started...
NetworkManager: dhcp_interface_init: MAC address = 00:0a:e4:2d:8d:70
NetworkManager: Broadcasting DHCP_DISCOVER
NetworkManager: DHCP: Starting request loop, overall start_time = {1125994187s, 933956us}
NetworkManager: DHCP: Request sent, waiting for reply...
NetworkManager: DHCP: Got some data of length 48.
NetworkManager: DHCP: Reply message was not UDP (ip_hdr->protocol = 1, IPPROTO_UDP = 17), won't use it.
NetworkManager: DHCP: Got some data of length 345.
NetworkManager: Server replied with 9 DHCP options:
NetworkManager:         Subnet Mask (1):        255.255.255.0
NetworkManager:         Router (3):     192.168.1.1
NetworkManager:         Domain Server (6):      192.168.1.229
NetworkManager:         Domain Server (6):      192.168.1.230
NetworkManager:         Domain Name (15):       "duckling.dk"
NetworkManager:         NIS Domain (40):        "duckling.dk"
NetworkManager:         Address Time (51):      4000
NetworkManager:         DHCP Msg Type (53):     2
NetworkManager:         DHCP Server Id (54):    192.168.1.229
NetworkManager:         unknown (81):   "duckling.dk"
NetworkManager:         Your IP Address:        192.168.1.199
NetworkManager:         DHCP Server Address:    192.168.1.229 (HW=00:30:48:71:2C:AC)
NetworkManager: Broadcast Address option is missing in DHCP server response. Assuming 192.168.1.255.
NetworkManager: Lease Time = 4000 in DHCP server response.
NetworkManager: Renewal Time (T1) is missing in DHCP server response. Assuming 2000s.
NetworkManager: Rebind Time (T2) is missing in DHCP server response. Assuming 3500s.
NetworkManager: DHCP_OFFER received from  (192.168.1.229)
NetworkManager: Broadcasting DHCP_REQUEST for 192.168.1.199
NetworkManager: DHCP: Starting request loop, overall start_time = {1125994188s, 508434us}
NetworkManager: DHCP: Request sent, waiting for reply...
NetworkManager: DHCP: Got some data of length 345.
NetworkManager: Server replied with 12 DHCP options:
NetworkManager:         Subnet Mask (1):        255.255.255.0
NetworkManager:         Router (3):     192.168.1.1
NetworkManager:         Domain Server (6):      192.168.1.229
NetworkManager:         Domain Server (6):      192.168.1.230
NetworkManager:         Domain Name (15):       "duckling.dk"
NetworkManager:         Broadcast Address (28): 192.168.1.255
NetworkManager:         NIS Domain (40):        "duckling.dk"
NetworkManager:         Address Time (51):      4000
NetworkManager:         DHCP Msg Type (53):     5
NetworkManager:         DHCP Server Id (54):    192.168.1.229
NetworkManager:         Renewal Time (58):      0
NetworkManager:         Rebinding Time (59):    0
NetworkManager:         unknown (81):   "duckling.dk"
NetworkManager:         Your IP Address:        192.168.1.199
NetworkManager:         DHCP Server Address:    192.168.1.229 (HW=00:30:48:71:2C:AC)
NetworkManager: Lease Time = 4000 in DHCP server response.
NetworkManager: Renewal Time (T1) is missing in DHCP server response. Assuming 2000s.
NetworkManager: Rebind Time (T2) is missing in DHCP server response. Assuming 3500s.
NetworkManager: DHCP_ACK received from  (192.168.1.229)
NetworkManager: <information>   Your IP address = 192.168.1.199
NetworkManager: <information>   Adding nameserver: 192.168.1.229
NetworkManager: <information>   Adding nameserver: 192.168.1.230
NetworkManager: <WARNING>         (): Adding domain search: duckling.dk

NetworkManager: <information>   Restarting mDNSResponder.
NetworkManager: <information>   Activation (eth0) IP configuration/DHCP successful!
NetworkManager: <information>   Activation (eth0) ended.
NetworkManager: <information>   Activation (eth0) successful, device activated.
NetworkManager: <information>       SWITCH: best device changed
NetworkManager: <information>   Activation (eth1) started...
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 0)
NetworkManager: <information>   Activation (eth1/wireless): found access point 'duck' to use.
NetworkManager: <information>   Activation (eth1/wireless): access point 'duck' is encrypted, and a key exists.  No new key needed.
NetworkManager: <information>   Activation (eth1/wireless): using essid 'duck', with Shared Key authentication.
NetworkManager: <debug info>    [1125994212.188391]  (): Activation (eth1/wireless): no hardware link to 'duck' in Shared Key mode, trying anot her access point.
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 0)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 100)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 200)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 300)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 400)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 500)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 600)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 700)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 800)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 900)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 1000)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 1100)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 1200)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 1300)
NetworkManager: <debug info>    [1125994241.510896]  (): NetworkManagerInfo triggered update of wireless network 'networks'
NetworkManager: <WARNING>         (): org.freedesktop.NetworkManagerInfo.BadNetworkData raised 'NetworkManagerInfo::getNetworkProperties could not access essid for network 'networks''
NetworkManager: <information>   FORCE: device '/org/freedesktop/NetworkManager/Devices/eth1', network 'duck'
NetworkManager: <debug info>    [1125994241.537089]  (): Activation (eth1/wireless): cancelling...
NetworkManager: <debug info>    [1125994241.537174]  (): Activation (eth1/wireless): waiting on dhcp to cease or device to finish activation
NetworkManager: <debug info>    [1125994241.551611]  (): activation of device 'eth1' canceled.
NetworkManager: <debug info>    [1125994241.551723]  (): activation of device 'eth1' canceled.
NetworkManager: <information>   Activation (eth1) ended.
NetworkManager: <debug info>    [1125994241.588604]  (): Activation (eth1/wireless): cancelled.
NetworkManager: <debug info>    [1125994243.605086]  (): Forcing AP 'duck'
NetworkManager: <information>       SWITCH: need to associate with new access point or create a wireless network.
NetworkManager: <information>   Activation (eth1) started...
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 0)
NetworkManager: <information>   Activation (eth1/wireless): found access point 'duck' to use.
NetworkManager: <information>   Activation (eth1/wireless): access point 'duck' is encrypted, and a key exists.  No new key needed.
NetworkManager: <information>   Activation (eth1/wireless): using essid 'duck', with Shared Key authentication.
NetworkManager: <debug info>    [1125994265.149927]  (): Activation (eth1/wireless): no hardware link to 'duck' in Shared Key mode, trying anot her access point.
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 0)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 100)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 200)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 300)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 400)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 500)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 600)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 700)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 800)
NetworkManager: <information>   Activation (eth1/wireless): waiting for access point. (attempt 900)
NetworkManager: <information>   Caught SIGINT/SIGTERM
NetworkManager: <information>   Caught terminiation signal
NetworkManager: <debug info>    [1125994287.181814]  (): Activation (eth1/wireless): cancelling...
NetworkManager: <debug info>    [1125994287.187992]  (): Activation (eth1/wireless): waiting on dhcp to cease or device to finish activation
NetworkManager: <debug info>    [1125994287.205319]  (): activation of device 'eth1' canceled.
NetworkManager: <debug info>    [1125994287.205445]  (): activation of device 'eth1' canceled.
NetworkManager: <information>   Activation (eth1) ended.
NetworkManager: <debug info>    [1125994287.240306]  (): Activation (eth1/wireless): cancelled.
NetworkManager: <debug info>    [1125994289.260087]  (): Open Sockets List:
NetworkManager: <debug info>    [1125994289.260319]  (): Open Sockets List Done.