GNOME Bugzilla – Bug 334612
Doesn't reconnect when disabling/reenabling wireless by hardware switch
Last modified: 2007-06-21 05:07:49 UTC
Please describe the problem: I have a laptop with a ipw2100 wireless card, connected to an open wireless network. My wireless card can be disabled directly by hardware with a button on the laptop. When the wireless card is enabled, NM works as it is supposed to. It switches networks automatically. But when I use the hardware button to enable/disable my card, everything is messed up. Steps to reproduce: Let's take a simple situation. 1) I am connected to my open wireless network. 2) I disable my wireless card with the switch. 3) NM detect the loss and disconnect my laptop. I get the following to syslog: Mar 14 22:26:25 localhost NetworkManager: <information>^IDeactivating device eth1. Mar 14 22:26:25 localhost dhclient: DHCPRELEASE on eth1 to 171.64.7.89 port 67 Mar 14 22:26:26 localhost NetworkManager: compute_nameservers: assertion `config != NULL' failed Mar 14 22:26:28 localhost NetworkManager: <information>^ISWITCH: no current connection, found better connection 'eth1'. Mar 14 22:26:28 localhost NetworkManager: <information>^IWill activate connection 'eth1/Stanford'. Mar 14 22:26:28 localhost NetworkManager: <information>^IDevice eth1 activation scheduled... Mar 14 22:26:28 localhost NetworkManager: <information>^IActivation (eth1) started... Mar 14 22:26:28 localhost NetworkManager: <information>^IActivation (eth1) Stage 1 (Device Prepare) scheduled... Mar 14 22:26:28 localhost NetworkManager: <information>^IActivation (eth1) Stage 1 (Device Prepare) started... Mar 14 22:26:28 localhost NetworkManager: <information>^IActivation (eth1) Stage 2 (Device Configure) scheduled... Mar 14 22:26:28 localhost NetworkManager: <information>^IActivation (eth1) Stage 1 (Device Prepare) complete. Mar 14 22:26:28 localhost NetworkManager: <information>^IActivation (eth1) Stage 2 (Device Configure) starting... Mar 14 22:26:28 localhost NetworkManager: <information>^IActivation (eth1/wireless) Stage 2 (Device Configure) will connect to access point 'Stanford'. Mar 14 22:26:28 localhost NetworkManager: <information>^IActivation (eth1/wireless): access point 'Stanford' is unencrypted, no key needed. Mar 14 22:26:28 localhost kernel: ADDRCONF(NETDEV_UP): eth1: link is not ready Mar 14 22:26:30 localhost NetworkManager: <information>^IActivation (eth1/wireless): using essid 'Stanford', with no authentication. Mar 14 22:26:47 localhost NetworkManager: <debug info>^I[1142404007.150074] (): Activation (eth1/wireless): no hardware link to 'Stanford' in non-encrypted mode. Mar 14 22:26:47 localhost NetworkManager: <information>^IActivation (eth1) failure scheduled... Mar 14 22:26:47 localhost NetworkManager: <information>^IActivation (eth1) failure scheduled... Mar 14 22:26:47 localhost NetworkManager: <information>^IActivation (eth1) Stage 2 (Device Configure) complete. Mar 14 22:26:47 localhost NetworkManager: <information>^IActivation (eth1) failed for access point (Stanford) Mar 14 22:26:47 localhost NetworkManager: <information>^IDeactivating device eth1. Mar 14 22:26:49 localhost NetworkManager: <information>^IActivation (eth1) failed for access point (Stanford) Mar 14 22:26:49 localhost NetworkManager: <information>^IDeactivating device eth1. Mar 14 22:26:51 localhost NetworkManager: process_scan_results: assertion `res_buf_len > 0' failed Mar 14 22:26:51 localhost NetworkManager: <WARNING>^I (): nm_device_wireless_process_scan_results(eth1): process_scan_results() returned an error. The last 2 lines are repeated 5 times. 4) If I click on nm-applet, there is nothing in the list of wireless networks. 5) I reenable my wireless card with the switch. 6) The card is detected and eth1 returns a carrier equal to 1 after 2 seconds. If I click on the nm-applet, I see my network in the list of networks, but NM doesn't reconnect automatically to it. There is nothing in the system logs. 7) I manually select my network in the list. 8) NM connects me to the networks, but redeconnects me just after. Here is the log: Mar 14 22:33:38 localhost NetworkManager: <information>^IFORCE: device '/org/freedesktop/NetworkManager/Devices/eth1', network 'Stanford' Mar 14 22:33:38 localhost NetworkManager: <information>^IDeactivating device eth1. Mar 14 22:33:39 localhost kernel: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready Mar 14 22:33:40 localhost NetworkManager: <debug info>^I[1142404420.638269] (): Forcing AP 'Stanford' Mar 14 22:33:40 localhost NetworkManager: <information>^IDevice eth1 activation scheduled... Mar 14 22:33:40 localhost NetworkManager: <information>^IActivation (eth1) started... Mar 14 22:33:40 localhost NetworkManager: <information>^IActivation (eth1) Stage 1 (Device Prepare) scheduled... Mar 14 22:33:40 localhost NetworkManager: <information>^IActivation (eth1) Stage 1 (Device Prepare) started... Mar 14 22:33:40 localhost NetworkManager: <information>^IActivation (eth1) Stage 2 (Device Configure) scheduled... Mar 14 22:33:40 localhost NetworkManager: <information>^IActivation (eth1) Stage 1 (Device Prepare) complete. Mar 14 22:33:40 localhost NetworkManager: <information>^IActivation (eth1) Stage 2 (Device Configure) starting... Mar 14 22:33:40 localhost NetworkManager: <information>^IActivation (eth1/wireless) Stage 2 (Device Configure) will connect to access point 'Stanford'. Mar 14 22:33:40 localhost NetworkManager: <information>^IActivation (eth1/wireless): access point 'Stanford' is unencrypted, no key needed. Mar 14 22:33:42 localhost NetworkManager: <information>^IActivation (eth1/wireless): using essid 'Stanford', with no authentication. Mar 14 22:33:43 localhost NetworkManager: <information>^IActivation (eth1/wireless) Stage 2 (Device Configure) successful. Connected to access point 'Stanford'. Mar 14 22:33:43 localhost NetworkManager: <information>^IActivation (eth1) Stage 3 (IP Configure Start) scheduled. Mar 14 22:33:43 localhost NetworkManager: <information>^IActivation (eth1) Stage 2 (Device Configure) complete. Mar 14 22:33:43 localhost NetworkManager: <information>^IActivation (eth1) Stage 3 (IP Configure Start) started... Mar 14 22:33:44 localhost NetworkManager: <information>^IActivation (eth1) Beginning DHCP transaction. Mar 14 22:33:44 localhost NetworkManager: <information>^IActivation (eth1) Stage 3 (IP Configure Start) complete. Mar 14 22:33:44 localhost NetworkManager: <information>^IDHCP daemon state now 12 for interface eth1 Mar 14 22:33:45 localhost NetworkManager: <information>^IDHCP daemon state now 1 for interface eth1 Mar 14 22:33:47 localhost dhclient: DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 7 Mar 14 22:33:47 localhost dhclient: DHCPOFFER from 128.12.168.1 Mar 14 22:33:47 localhost dhclient: DHCPREQUEST on eth1 to 255.255.255.255 port 67 Mar 14 22:33:47 localhost dhclient: DHCPACK from 128.12.168.1 Mar 14 22:33:47 localhost NetworkManager: <information>^IDHCP daemon state now 2 for interface eth1 Mar 14 22:33:47 localhost NetworkManager: <information>^IActivation (eth1) Stage 4 (IP Configure Get) scheduled... Mar 14 22:33:47 localhost NetworkManager: <information>^IActivation (eth1) Stage 4 (IP Configure Get) started... Mar 14 22:33:47 localhost NetworkManager: <information>^IRetrieved the following IP4 configuration from the DHCP daemon: Mar 14 22:33:47 localhost NetworkManager: <information>^I address 128.12.168.13 Mar 14 22:33:47 localhost NetworkManager: <information>^I netmask 255.255.0.0 Mar 14 22:33:47 localhost NetworkManager: <information>^I broadcast 128.12.255.255 Mar 14 22:33:47 localhost NetworkManager: <information>^I gateway 128.12.1.1 Mar 14 22:33:47 localhost NetworkManager: <information>^I nameserver 171.64.7.55 Mar 14 22:33:47 localhost NetworkManager: <information>^I nameserver 171.64.7.77 Mar 14 22:33:47 localhost NetworkManager: <information>^I nameserver 171.64.7.99 Mar 14 22:33:47 localhost NetworkManager: <information>^I domain name 'Stanford.EDU' Mar 14 22:33:47 localhost NetworkManager: <information>^IActivation (eth1) Stage 5 (IP Configure Commit) scheduled... Mar 14 22:33:47 localhost NetworkManager: <information>^IActivation (eth1) Stage 4 (IP Configure Get) complete. Mar 14 22:33:47 localhost NetworkManager: <information>^IActivation (eth1) Stage 5 (IP Configure Commit) started... Mar 14 22:33:47 localhost NetworkManager: <information>^IYour IP address = 128.12.168.13 Mar 14 22:33:47 localhost dhclient: bound to 128.12.168.13 -- renewal in 77838 seconds. Mar 14 22:33:48 localhost NetworkManager: <information>^IActivation (eth1) Finish handler scheduled. Mar 14 22:33:48 localhost NetworkManager: <information>^IActivation (eth1) Stage 5 (IP Configure Commit) complete. Mar 14 22:33:48 localhost NetworkManager: <information>^IActivation (eth1) successful, device activated. Mar 14 22:33:48 localhost NetworkManager: <debug info>^I[1142404428.126965] (): NetworkManagerInfo triggered update of wireless network 'Stanford' Mar 14 22:33:48 localhost NetworkManager: <information>^ISWITCH: terminating current connection 'eth1' because it's no longer valid. Mar 14 22:33:48 localhost NetworkManager: <information>^IDeactivating device eth1. Mar 14 22:33:48 localhost dhclient: DHCPRELEASE on eth1 to 171.64.7.111 port 67 Mar 14 22:33:49 localhost NetworkManager: compute_nameservers: assertion `config != NULL' failed Mar 14 22:33:50 localhost kernel: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready Mar 14 22:33:51 localhost NetworkManager: nm_device_is_wired: assertion `dev != NULL' failed Mar 14 22:33:51 localhost NetworkManager: nm_device_is_wireless: assertion `dev != NULL' failed Mar 14 22:34:00 localhost kernel: eth1: no IPv6 routers present 9) Nothing happens automatically. 10) I reselect my network in the list. 11) NM connects me to the network, and this time it works, even if 2 dhcp request are made by NM... Here is the log: Mar 14 22:37:17 localhost NetworkManager: <information>^IFORCE: device '/org/freedesktop/NetworkManager/Devices/eth1', network 'Stanford' Mar 14 22:37:17 localhost NetworkManager: <information>^IDeactivating device eth1. Mar 14 22:37:18 localhost kernel: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready Mar 14 22:37:19 localhost NetworkManager: <debug info>^I[1142404639.210048] (): Forcing AP 'Stanford' Mar 14 22:37:19 localhost NetworkManager: <information>^IDevice eth1 activation scheduled... Mar 14 22:37:19 localhost NetworkManager: <information>^IActivation (eth1) started... Mar 14 22:37:19 localhost NetworkManager: <information>^IActivation (eth1) Stage 1 (Device Prepare) scheduled... Mar 14 22:37:19 localhost NetworkManager: <information>^IActivation (eth1) Stage 1 (Device Prepare) started... Mar 14 22:37:19 localhost NetworkManager: <information>^IActivation (eth1) Stage 2 (Device Configure) scheduled... Mar 14 22:37:19 localhost NetworkManager: <information>^IActivation (eth1) Stage 1 (Device Prepare) complete. Mar 14 22:37:19 localhost NetworkManager: <information>^IActivation (eth1) Stage 2 (Device Configure) starting... Mar 14 22:37:19 localhost NetworkManager: <information>^IActivation (eth1/wireless) Stage 2 (Device Configure) will connect to access point 'Stanford'. Mar 14 22:37:19 localhost NetworkManager: <information>^IActivation (eth1/wireless): access point 'Stanford' is unencrypted, no key needed. Mar 14 22:37:21 localhost NetworkManager: <information>^IActivation (eth1/wireless): using essid 'Stanford', with no authentication. Mar 14 22:37:21 localhost NetworkManager: <information>^IActivation (eth1/wireless) Stage 2 (Device Configure) successful. Connected to access point 'Stanford'. Mar 14 22:37:21 localhost NetworkManager: <information>^IActivation (eth1) Stage 3 (IP Configure Start) scheduled. Mar 14 22:37:21 localhost NetworkManager: <information>^IActivation (eth1) Stage 2 (Device Configure) complete. Mar 14 22:37:21 localhost NetworkManager: <information>^IActivation (eth1) Stage 3 (IP Configure Start) started... Mar 14 22:37:22 localhost NetworkManager: <information>^IActivation (eth1) Beginning DHCP transaction. Mar 14 22:37:22 localhost NetworkManager: <information>^IActivation (eth1) Stage 3 (IP Configure Start) complete. Mar 14 22:37:22 localhost NetworkManager: <information>^IDHCP daemon state now 12 for interface eth1 Mar 14 22:37:23 localhost NetworkManager: <information>^IDHCP daemon state now 1 for interface eth1 Mar 14 22:37:27 localhost dhclient: DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 4 Mar 14 22:37:27 localhost dhclient: DHCPOFFER from 128.12.168.1 Mar 14 22:37:27 localhost dhclient: DHCPREQUEST on eth1 to 255.255.255.255 port 67 Mar 14 22:37:27 localhost dhclient: DHCPACK from 128.12.168.1 Mar 14 22:37:27 localhost NetworkManager: <information>^IDHCP daemon state now 2 for interface eth1 Mar 14 22:37:27 localhost NetworkManager: <information>^IActivation (eth1) Stage 4 (IP Configure Get) scheduled... Mar 14 22:37:27 localhost NetworkManager: <information>^IActivation (eth1) Stage 4 (IP Configure Get) started... Mar 14 22:37:27 localhost NetworkManager: <information>^IRetrieved the following IP4 configuration from the DHCP daemon: Mar 14 22:37:27 localhost NetworkManager: <information>^I address 128.12.168.13 Mar 14 22:37:27 localhost NetworkManager: <information>^I netmask 255.255.0.0 Mar 14 22:37:27 localhost NetworkManager: <information>^I broadcast 128.12.255.255 Mar 14 22:37:27 localhost NetworkManager: <information>^I gateway 128.12.1.1 Mar 14 22:37:27 localhost NetworkManager: <information>^I nameserver 171.64.7.55 Mar 14 22:37:27 localhost NetworkManager: <information>^I nameserver 171.64.7.77 Mar 14 22:37:27 localhost NetworkManager: <information>^I nameserver 171.64.7.99 Mar 14 22:37:27 localhost NetworkManager: <information>^I domain name 'Stanford.EDU' Mar 14 22:37:27 localhost NetworkManager: <information>^IActivation (eth1) Stage 5 (IP Configure Commit) scheduled... Mar 14 22:37:27 localhost NetworkManager: <information>^IActivation (eth1) Stage 4 (IP Configure Get) complete. Mar 14 22:37:27 localhost NetworkManager: <information>^IActivation (eth1) Stage 5 (IP Configure Commit) started... Mar 14 22:37:27 localhost NetworkManager: <information>^IYour IP address = 128.12.168.13 Mar 14 22:37:27 localhost dhclient: bound to 128.12.168.13 -- renewal in 80045 seconds. Mar 14 22:37:28 localhost NetworkManager: <information>^IActivation (eth1) successful, device activated. Mar 14 22:37:28 localhost NetworkManager: <debug info>^I[1142404648.121915] (): NetworkManagerInfo triggered update of wireless network 'Stanford' Mar 14 22:37:28 localhost NetworkManager: <information>^ISWITCH: found better connection 'eth1/Stanford' than current connection 'eth1/Stanford'. essid=0, link=0 Mar 14 22:37:28 localhost NetworkManager: <information>^IWill activate connection 'eth1/Stanford'. Mar 14 22:37:28 localhost NetworkManager: <information>^IDevice eth1 activation scheduled... Mar 14 22:37:28 localhost NetworkManager: <information>^IDeactivating device eth1. Mar 14 22:37:28 localhost NetworkManager: <information>^IActivation (eth1) Finish handler scheduled. Mar 14 22:37:28 localhost NetworkManager: <information>^IActivation (eth1) Stage 5 (IP Configure Commit) complete. Mar 14 22:37:28 localhost dhclient: DHCPRELEASE on eth1 to 171.64.7.111 port 67 Mar 14 22:37:29 localhost NetworkManager: compute_nameservers: assertion `config != NULL' failed Mar 14 22:37:30 localhost kernel: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready Mar 14 22:37:31 localhost NetworkManager: <information>^IActivation (eth1) started... Mar 14 22:37:31 localhost NetworkManager: <information>^IActivation (eth1) Stage 1 (Device Prepare) scheduled... Mar 14 22:37:31 localhost NetworkManager: <information>^IDHCP daemon state now 14 for interface eth1 Mar 14 22:37:31 localhost NetworkManager: <information>^IDHCP daemon state now 11 for interface eth1 Mar 14 22:37:31 localhost NetworkManager: <information>^IDHCP daemon state now 14 for interface eth1 Mar 14 22:37:31 localhost NetworkManager: <information>^IActivation (eth1) Stage 1 (Device Prepare) started... Mar 14 22:37:31 localhost NetworkManager: <information>^IActivation (eth1) Stage 2 (Device Configure) scheduled... Mar 14 22:37:31 localhost NetworkManager: <information>^IActivation (eth1) Stage 1 (Device Prepare) complete. Mar 14 22:37:31 localhost NetworkManager: <information>^IActivation (eth1) Stage 2 (Device Configure) starting... Mar 14 22:37:31 localhost NetworkManager: <information>^IActivation (eth1/wireless) Stage 2 (Device Configure) will connect to access point 'Stanford'. Mar 14 22:37:31 localhost NetworkManager: <information>^IActivation (eth1/wireless): access point 'Stanford' is unencrypted, no key needed. Mar 14 22:37:33 localhost NetworkManager: <information>^IActivation (eth1/wireless): using essid 'Stanford', with no authentication. Mar 14 22:37:33 localhost NetworkManager: <information>^IActivation (eth1/wireless) Stage 2 (Device Configure) successful. Connected to access point 'Stanford'. Mar 14 22:37:33 localhost NetworkManager: <information>^IActivation (eth1) Stage 3 (IP Configure Start) scheduled. Mar 14 22:37:33 localhost NetworkManager: <information>^IActivation (eth1) Stage 2 (Device Configure) complete. Mar 14 22:37:33 localhost NetworkManager: <information>^IActivation (eth1) Stage 3 (IP Configure Start) started... Mar 14 22:37:34 localhost NetworkManager: <information>^IActivation (eth1) Beginning DHCP transaction. Mar 14 22:37:34 localhost NetworkManager: <information>^IActivation (eth1) Stage 3 (IP Configure Start) complete. Mar 14 22:37:34 localhost NetworkManager: <information>^IDHCP daemon state now 12 for interface eth1 Mar 14 22:37:35 localhost NetworkManager: <information>^IDHCP daemon state now 1 for interface eth1 Mar 14 22:37:38 localhost dhclient: DHCPDISCOVER on eth1 to 255.255.255.255 port 67 interval 4 Mar 14 22:37:38 localhost dhclient: DHCPOFFER from 128.12.168.1 Mar 14 22:37:38 localhost dhclient: DHCPREQUEST on eth1 to 255.255.255.255 port 67 Mar 14 22:37:38 localhost dhclient: DHCPACK from 128.12.168.1 Mar 14 22:37:38 localhost NetworkManager: <information>^IDHCP daemon state now 2 for interface eth1 Mar 14 22:37:38 localhost NetworkManager: <information>^IActivation (eth1) Stage 4 (IP Configure Get) scheduled... Mar 14 22:37:38 localhost NetworkManager: <information>^IActivation (eth1) Stage 4 (IP Configure Get) started... Mar 14 22:37:38 localhost NetworkManager: <information>^IRetrieved the following IP4 configuration from the DHCP daemon: Mar 14 22:37:38 localhost NetworkManager: <information>^I address 128.12.168.13 Mar 14 22:37:38 localhost NetworkManager: <information>^I netmask 255.255.0.0 Mar 14 22:37:38 localhost NetworkManager: <information>^I broadcast 128.12.255.255 Mar 14 22:37:38 localhost NetworkManager: <information>^I gateway 128.12.1.1 Mar 14 22:37:38 localhost NetworkManager: <information>^I nameserver 171.64.7.55 Mar 14 22:37:38 localhost NetworkManager: <information>^I nameserver 171.64.7.77 Mar 14 22:37:38 localhost NetworkManager: <information>^I nameserver 171.64.7.99 Mar 14 22:37:38 localhost NetworkManager: <information>^I domain name 'Stanford.EDU' Mar 14 22:37:38 localhost NetworkManager: <information>^IActivation (eth1) Stage 5 (IP Configure Commit) scheduled... Mar 14 22:37:38 localhost NetworkManager: <information>^IActivation (eth1) Stage 4 (IP Configure Get) complete. Mar 14 22:37:38 localhost NetworkManager: <information>^IActivation (eth1) Stage 5 (IP Configure Commit) started... Mar 14 22:37:38 localhost NetworkManager: <information>^IYour IP address = 128.12.168.13 Mar 14 22:37:38 localhost dhclient: bound to 128.12.168.13 -- renewal in 83943 seconds. Mar 14 22:37:39 localhost NetworkManager: <information>^IActivation (eth1) Finish handler scheduled. Mar 14 22:37:39 localhost NetworkManager: <information>^IActivation (eth1) Stage 5 (IP Configure Commit) complete. Mar 14 22:37:39 localhost NetworkManager: <information>^IActivation (eth1) successful, device activated. Actual results: As described above Expected results: NM working as if the wireless was software disabled. Does this happen every time? Yes. Other information: It seems that Network Manager doesn't support an hardware disabling/enabling of the card. Ask me if you need more information, logs or testing! Cheers, -- Steph
Confirming.
Also see: https://launchpad.net/distros/ubuntu/+source/network-manager/+bug/20667
*** This bug has been marked as a duplicate of 343679 ***