[pkg-wpa-devel] Debian testing wpa_supplicant 1.03 cannot roam between wifi APs without me killing wpa_supplicant (intel 6300 AGN)
Marc MERLIN
marc_hostap at merlins.org
Fri Feb 15 07:34:09 UTC 2013
[resent with correct Email]
Howdy
First, the basics:
kernel: 3.7.4-amd64-preempt-20121226-fixwd
debian-testing
wpasupplicant-1.0-3+b2
iwlwifi 0000:03:00.0: loaded firmware version 9.221.4.1 build 25532
iwldvm: Intel(R) Wireless WiFi Link AGN driver for Linux, in-tree:d
iwldvm: Copyright(c) 2003-2012 Intel Corporation
iwlwifi 0000:03:00.0: CONFIG_IWLWIFI_DEBUG enabled
iwlwifi 0000:03:00.0: CONFIG_IWLWIFI_DEBUGFS disabled
iwlwifi 0000:03:00.0: CONFIG_IWLWIFI_DEVICE_TRACING disabled
iwlwifi 0000:03:00.0: CONFIG_IWLWIFI_DEVICE_TESTMODE enabled
iwlwifi 0000:03:00.0: CONFIG_IWLWIFI_P2P enabled
iwlwifi 0000:03:00.0: Detected Intel(R) Centrino(R) Ultimate-N 6300 AGN, REV=0x74
iwlwifi 0000:03:00.0: L1 Enabled; Disabling L0S
iwlwifi 0000:03:00.0: RF_KILL bit toggled to enable radio.
iwlwifi 0000:03:00.0: device EEPROM VER=0x43a, CALIB=0x6
iwlwifi 0000:03:00.0: Device SKU: 0x1F0
iwlwifi 0000:03:00.0: Valid Tx ant: 0x7, Valid Rx ant: 0x7
I started with being connected ok to 2C:B0:5D:3C:7D:F1, and walked mostly out of range and close to 2c:b0:5d:a0:c8:a6
wpa_supplicant actually did connect to 2c:b0:5d:a0:c8:a6, then I had a few pings that went through the
new AP, and then the pings stopped.
Networking never recovered until after I ran kilall wpa_supplicant.
This basically happens every time I roam :(
Debian runs it like so:
root 14862 0.0 0.0 31024 2828 ? S 20:59 0:00 /sbin/wpa_supplicant -u -s -O /var/run/wpa_supplicant
Should I be overriding what debian does and run it like so instead?
/sbin/wpa_supplicant -u -onl80211 -O/var/run/wpa_supplicant -f /var/log/wpa_supplicant.log
Here are the full logs if that helps, with annotations:
> Feb 14 20:29:43 gandalfthegreat wpa_supplicant[2501]: wlan0: SME: Trying to authenticate with 2c:b0:5d:a0:c8:a6 (SSID='magicnet-a' freq=5785 MHz)
> Feb 14 20:29:45 gandalfthegreat kernel: iwlwifi 0000:03:00.0: fail to flush all tx fifo queues
> Feb 14 20:29:45 gandalfthegreat kernel: wlan0: authenticate with 2c:b0:5d:a0:c8:a6
> Feb 14 20:29:45 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: completed -> authenticating
> Feb 14 20:29:45 gandalfthegreat kernel: wlan0: send auth to 2c:b0:5d:a0:c8:a6 (try 1/3)
> Feb 14 20:29:45 gandalfthegreat wpa_supplicant[2501]: wlan0: Trying to associate with 2c:b0:5d:a0:c8:a6 (SSID='magicnet-a' freq=5785 MHz)
> Feb 14 20:29:45 gandalfthegreat kernel: wlan0: send auth to 2c:b0:5d:a0:c8:a6 (try 2/3)
> Feb 14 20:29:45 gandalfthegreat kernel: wlan0: authenticated
> Feb 14 20:29:45 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: authenticating -> associating
> Feb 14 20:29:45 gandalfthegreat kernel: wlan0: associate with 2c:b0:5d:a0:c8:a6 (try 1/3)
> Feb 14 20:29:45 gandalfthegreat kernel: wlan0: RX AssocResp from 2c:b0:5d:a0:c8:a6 (capab=0x11 status=0 aid=3)
> Feb 14 20:29:45 gandalfthegreat wpa_supplicant[2501]: wlan0: Associated with 2c:b0:5d:a0:c8:a6
> Feb 14 20:29:45 gandalfthegreat kernel: wlan0: associated
> Feb 14 20:29:45 gandalfthegreat kernel: cfg80211: Calling CRDA for country: US
> Feb 14 20:29:45 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: associating -> associated
> Feb 14 20:29:45 gandalfthegreat kernel: cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule:
> Feb 14 20:29:45 gandalfthegreat kernel: cfg80211: 2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
(...)
> Feb 14 20:29:45 gandalfthegreat kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
> Feb 14 20:29:45 gandalfthegreat kernel: cfg80211: Found new beacon on frequency: 5785 MHz (Ch 157) on phy0
> Feb 14 20:29:45 gandalfthegreat wpa_supplicant[2501]: wlan0: WPA: Key negotiation completed with 2c:b0:5d:a0:c8:a6 [PTK=CCMP GTK=CCMP]
> Feb 14 20:29:45 gandalfthegreat wpa_supplicant[2501]: wlan0: CTRL-EVENT-CONNECTED - Connection to 2c:b0:5d:a0:c8:a6 completed (reauth) [id=0 id_str=]
> Feb 14 20:29:45 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: associated -> completed
> Feb 14 20:29:50 gandalfthegreat NetworkManager[1926]: <info> (wlan0): roamed from BSSID 2C:B0:5D:3C:7D:F1 (magicnet-a) to 2C:B0:5D:A0:C8:A6 (magicnet-a)
I had nothing in the logs for a while, and 1mn later, wpa_supplicant disconnected from the close ap and reconnected to the far away one:
> Feb 14 20:30:55 gandalfthegreat wpa_supplicant[2501]: wlan0: WPA: Group rekeying completed with 2c:b0:5d:a0:c8:a6 [GTK=CCMP]
> Feb 14 20:30:55 gandalfthegreat wpa_supplicant[2501]: wlan0: WPA: Group rekeying completed with 2c:b0:5d:a0:c8:a6 [GTK=CCMP]
> Feb 14 20:30:56 gandalfthegreat wpa_supplicant[2501]: wlan0: WPA: Group rekeying completed with 2c:b0:5d:a0:c8:a6 [GTK=CCMP]
> Feb 14 20:30:57 gandalfthegreat wpa_supplicant[2501]: wlan0: WPA: Group rekeying completed with 2c:b0:5d:a0:c8:a6 [GTK=CCMP]
> Feb 14 20:31:01 gandalfthegreat kernel: cfg80211: Found new beacon on frequency: 5745 MHz (Ch 149) on phy0
> Feb 14 20:31:04 gandalfthegreat kernel: iwlwifi 0000:03:00.0: fail to flush all tx fifo queues
> Feb 14 20:31:04 gandalfthegreat kernel: wlan0: deauthenticated from 2c:b0:5d:a0:c8:a6 (Reason: 2)
disconnection was here:
> Feb 14 20:31:06 gandalfthegreat wpa_supplicant[2501]: wlan0: CTRL-EVENT-DISCONNECTED bssid=2c:b0:5d:a0:c8:a6 reason=2
> Feb 14 20:31:06 gandalfthegreat kernel: iwlwifi 0000:03:00.0: fail to flush all tx fifo queues
> Feb 14 20:31:06 gandalfthegreat kernel: cfg80211: All devices are disconnected, going to restore regulatory settings
> Feb 14 20:31:06 gandalfthegreat kernel: cfg80211: Restoring regulatory settings
> Feb 14 20:31:06 gandalfthegreat kernel: cfg80211: Calling CRDA to update world regulatory domain
> Feb 14 20:31:06 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: completed -> disconnected
> Feb 14 20:31:06 gandalfthegreat kernel: cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
> Feb 14 20:31:06 gandalfthegreat kernel: cfg80211: World regulatory domain updated:
> Feb 14 20:31:06 gandalfthegreat kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
> Feb 14 20:31:06 gandalfthegreat kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> Feb 14 20:31:06 gandalfthegreat kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
> Feb 14 20:31:06 gandalfthegreat kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
> Feb 14 20:31:06 gandalfthegreat kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> Feb 14 20:31:06 gandalfthegreat kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> Feb 14 20:31:06 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: disconnected -> scanning
> Feb 14 20:31:06 gandalfthegreat kernel: cfg80211: Found new beacon on frequency: 5745 MHz (Ch 149) on phy0
> Feb 14 20:31:06 gandalfthegreat wpa_supplicant[2501]: wlan0: SME: Trying to authenticate with 2c:b0:5d:3c:7d:f1 (SSID='magicnet-a' freq=5745 MHz)
> Feb 14 20:31:06 gandalfthegreat kernel: wlan0: authenticate with 2c:b0:5d:3c:7d:f1
> Feb 14 20:31:06 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: scanning -> authenticating
> Feb 14 20:31:06 gandalfthegreat kernel: wlan0: send auth to 2c:b0:5d:3c:7d:f1 (try 1/3)
> Feb 14 20:31:06 gandalfthegreat kernel: wlan0: send auth to 2c:b0:5d:3c:7d:f1 (try 2/3)
> Feb 14 20:31:07 gandalfthegreat wpa_supplicant[2501]: wlan0: Trying to associate with 2c:b0:5d:3c:7d:f1 (SSID='magicnet-a' freq=5745 MHz)
> Feb 14 20:31:07 gandalfthegreat kernel: wlan0: send auth to 2c:b0:5d:3c:7d:f1 (try 3/3)
> Feb 14 20:31:07 gandalfthegreat kernel: wlan0: authenticated
> Feb 14 20:31:07 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: authenticating -> associating
> Feb 14 20:31:07 gandalfthegreat kernel: wlan0: associate with 2c:b0:5d:3c:7d:f1 (try 1/3)
> Feb 14 20:31:07 gandalfthegreat kernel: wlan0: associate with 2c:b0:5d:3c:7d:f1 (try 2/3)
> Feb 14 20:31:07 gandalfthegreat kernel: wlan0: associate with 2c:b0:5d:3c:7d:f1 (try 3/3)
> Feb 14 20:31:07 gandalfthegreat kernel: wlan0: RX AssocResp from 2c:b0:5d:3c:7d:f1 (capab=0x11 status=0 aid=1)
> Feb 14 20:31:07 gandalfthegreat wpa_supplicant[2501]: wlan0: Associated with 2c:b0:5d:3c:7d:f1
> Feb 14 20:31:07 gandalfthegreat kernel: wlan0: associated
> Feb 14 20:31:07 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: associating -> associated
> Feb 14 20:31:08 gandalfthegreat NetworkManager[1926]: <info> (wlan0): roamed from BSSID 2C:B0:5D:A0:C8:A6 (magicnet-a) to 2C:B0:5D:3C:7D:F1 (magicnet-a)
> Feb 14 20:31:08 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: associated -> 4-way handshake
> Feb 14 20:31:08 gandalfthegreat wpa_supplicant[2501]: wlan0: WPA: Key negotiation completed with 2c:b0:5d:3c:7d:f1 [PTK=CCMP GTK=CCMP]
> Feb 14 20:31:08 gandalfthegreat wpa_supplicant[2501]: wlan0: CTRL-EVENT-CONNECTED - Connection to 2c:b0:5d:3c:7d:f1 completed (reauth) [id=0 id_str=]
> Feb 14 20:31:08 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
> Feb 14 20:32:43 gandalfthegreat kernel: cfg80211: Found new beacon on frequency: 5785 MHz (Ch 157) on phy0
> Feb 14 20:32:45 gandalfthegreat kernel: iwlwifi 0000:03:00.0: fail to flush all tx fifo queues
Ok, so now I got impatient and ran killall wpa_supplicant. After that NetworkManager restarted it, and it reconnected ok to the close AP.
>From there, netowrking ran fine.
> Feb 14 20:33:49 gandalfthegreat wpa_supplicant[2501]: wlan0: CTRL-EVENT-TERMINATING - signal 15 received
> Feb 14 20:33:49 gandalfthegreat kernel: wlan0: deauthenticating from 2c:b0:5d:3c:7d:f1 by local choice (reason=3)
> Feb 14 20:33:51 gandalfthegreat kernel: iwlwifi 0000:03:00.0: fail to flush all tx fifo queues
> Feb 14 20:33:53 gandalfthegreat kernel: iwlwifi 0000:03:00.0: fail to flush all tx fifo queues
> Feb 14 20:33:55 gandalfthegreat NetworkManager[1926]: <info> (wlan0): roamed from BSSID 2C:B0:5D:3C:7D:F1 (magicnet-a) to (none) ((none))
> Feb 14 20:33:55 gandalfthegreat kernel: iwlwifi 0000:03:00.0: fail to flush all tx fifo queues
> Feb 14 20:33:55 gandalfthegreat kernel: cfg80211: All devices are disconnected, going to restore regulatory settings
> Feb 14 20:33:55 gandalfthegreat kernel: cfg80211: Restoring regulatory settings
> Feb 14 20:33:55 gandalfthegreat kernel: cfg80211: Calling CRDA to update world regulatory domain
> Feb 14 20:33:55 gandalfthegreat kernel: cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
> Feb 14 20:33:55 gandalfthegreat kernel: cfg80211: World regulatory domain updated:
> Feb 14 20:33:55 gandalfthegreat kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
> Feb 14 20:33:55 gandalfthegreat kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> Feb 14 20:33:55 gandalfthegreat kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
> Feb 14 20:33:55 gandalfthegreat kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
> Feb 14 20:33:55 gandalfthegreat kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> Feb 14 20:33:55 gandalfthegreat kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
> Feb 14 20:33:55 gandalfthegreat avahi-daemon[3891]: Interface wlan0.IPv6 no longer relevant for mDNS.
> Feb 14 20:33:55 gandalfthegreat avahi-daemon[3891]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::2677:3ff:fe7c:d0b0.
> Feb 14 20:33:55 gandalfthegreat avahi-daemon[3891]: Interface wlan0.IPv4 no longer relevant for mDNS.
> Feb 14 20:33:55 gandalfthegreat avahi-daemon[3891]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.205.7.
> Feb 14 20:33:55 gandalfthegreat avahi-daemon[3891]: Withdrawing address record for fe80::2677:3ff:fe7c:d0b0 on wlan0.
> Feb 14 20:33:55 gandalfthegreat avahi-daemon[3891]: Withdrawing address record for 192.168.205.7 on wlan0.
> Feb 14 20:33:55 gandalfthegreat NetworkManager[1926]: <info> wpa_supplicant stopped
> Feb 14 20:33:55 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: completed -> down
> Feb 14 20:33:55 gandalfthegreat NetworkManager[1926]: <info> (wlan0): device state change: activated -> unavailable (reason 'supplicant-failed') [100 20 10]
> Feb 14 20:33:55 gandalfthegreat NetworkManager[1926]: <info> (wlan0): deactivating device (reason 'supplicant-failed') [10]
> Feb 14 20:33:55 gandalfthegreat dbus[3526]: [system] Activating service name='fi.w1.wpa_supplicant1' (using servicehelper)
> Feb 14 20:33:55 gandalfthegreat dbus[3526]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
> Feb 14 20:33:55 gandalfthegreat dbus[3526]: [system] Successfully activated service 'fi.w1.wpa_supplicant1'
> Feb 14 20:33:55 gandalfthegreat NetworkManager[1926]: <info> wpa_supplicant started
> Feb 14 20:33:55 gandalfthegreat dbus[3526]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
> Feb 14 20:33:55 gandalfthegreat kernel: iwlwifi 0000:03:00.0: L1 Enabled; Disabling L0S
> Feb 14 20:33:55 gandalfthegreat kernel: iwlwifi 0000:03:00.0: Radio type=0x0-0x3-0x1
> Feb 14 20:33:55 gandalfthegreat kernel: IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
> Feb 14 20:33:55 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: starting -> ready
> Feb 14 20:33:55 gandalfthegreat NetworkManager[1926]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
> Feb 14 20:33:55 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: ready -> inactive
> Feb 14 20:33:58 gandalfthegreat kernel: cfg80211: Found new beacon on frequency: 5745 MHz (Ch 149) on phy0
> Feb 14 20:33:58 gandalfthegreat kernel: cfg80211: Found new beacon on frequency: 5785 MHz (Ch 157) on phy0
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Auto-activating connection 'Auto magicnet-a'.
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) starting connection 'Auto magicnet-a'
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> (wlan0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0/wireless): access point 'Auto magicnet-a' has security, but secrets are required.
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> (wlan0): device state change: config -> need-auth (reason 'none') [50 60 0]
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> (wlan0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> (wlan0): device state change: prepare -> config (reason 'none') [40 50 0]
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0/wireless): connection 'Auto magicnet-a' has security, and secrets exist. No new secrets needed.
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Config: added 'ssid' value 'magicnet-a'
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Config: added 'scan_ssid' value '1'
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Config: added 'psk' value '<omitted>'
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> Config: set interface ap_scan to 1
> Feb 14 20:33:58 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: inactive -> scanning
> Feb 14 20:34:02 gandalfthegreat wpa_supplicant[7672]: wlan0: SME: Trying to authenticate with 2c:b0:5d:a0:c8:a6 (SSID='magicnet-a' freq=5785 MHz)
> Feb 14 20:34:02 gandalfthegreat kernel: wlan0: authenticate with 2c:b0:5d:a0:c8:a6
> Feb 14 20:34:02 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: scanning -> authenticating
> Feb 14 20:34:02 gandalfthegreat kernel: wlan0: send auth to 2c:b0:5d:a0:c8:a6 (try 1/3)
> Feb 14 20:34:02 gandalfthegreat wpa_supplicant[7672]: wlan0: Trying to associate with 2c:b0:5d:a0:c8:a6 (SSID='magicnet-a' freq=5785 MHz)
> Feb 14 20:34:02 gandalfthegreat kernel: wlan0: send auth to 2c:b0:5d:a0:c8:a6 (try 2/3)
> Feb 14 20:34:02 gandalfthegreat kernel: wlan0: authenticated
> Feb 14 20:34:02 gandalfthegreat kernel: wlan0: associate with 2c:b0:5d:a0:c8:a6 (try 1/3)
> Feb 14 20:34:02 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: authenticating -> associating
> Feb 14 20:34:02 gandalfthegreat kernel: wlan0: RX AssocResp from 2c:b0:5d:a0:c8:a6 (capab=0x11 status=0 aid=3)
> Feb 14 20:34:02 gandalfthegreat wpa_supplicant[7672]: wlan0: Associated with 2c:b0:5d:a0:c8:a6
> Feb 14 20:34:02 gandalfthegreat kernel: wlan0: associated
> Feb 14 20:34:02 gandalfthegreat kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
> Feb 14 20:34:02 gandalfthegreat kernel: cfg80211: Calling CRDA for country: US
> Feb 14 20:34:02 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: associating -> 4-way handshake
> Feb 14 20:34:02 gandalfthegreat wpa_supplicant[7672]: wlan0: WPA: Key negotiation completed with 2c:b0:5d:a0:c8:a6 [PTK=CCMP GTK=CCMP]
> Feb 14 20:34:02 gandalfthegreat wpa_supplicant[7672]: wlan0: CTRL-EVENT-CONNECTED - Connection to 2c:b0:5d:a0:c8:a6 completed (auth) [id=0 id_str=]
> Feb 14 20:34:02 gandalfthegreat NetworkManager[1926]: <info> (wlan0): supplicant interface state: 4-way handshake -> completed
> Feb 14 20:34:02 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'magicnet-a'.
> Feb 14 20:34:02 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) scheduled.
> Feb 14 20:34:02 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) started...
> Feb 14 20:34:02 gandalfthegreat NetworkManager[1926]: <info> (wlan0): device state change: config -> ip-config (reason 'none') [50 70 0]
> Feb 14 20:34:02 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) scheduled...
> Feb 14 20:34:02 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Beginning IP6 addrconf.
> Feb 14 20:34:02 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 3 of 5 (IP Configure Start) complete.
> Feb 14 20:34:02 gandalfthegreat kernel: cfg80211: Updating information on frequency 2412 MHz for a 20 MHz width channel with regulatory rule:
> Feb 14 20:34:02 gandalfthegreat kernel: cfg80211: 2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
(...)
> Feb 14 20:34:02 gandalfthegreat kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
> Feb 14 20:34:02 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) started...
> Feb 14 20:34:02 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 4 of 5 (IP4 Configure Get) complete.
> Feb 14 20:34:02 gandalfthegreat kernel: cfg80211: Found new beacon on frequency: 5785 MHz (Ch 157) on phy0
> Feb 14 20:34:04 gandalfthegreat avahi-daemon[3891]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::2677:3ff:fe7c:d0b0.
> Feb 14 20:34:04 gandalfthegreat avahi-daemon[3891]: New relevant interface wlan0.IPv6 for mDNS.
> Feb 14 20:34:04 gandalfthegreat avahi-daemon[3891]: Registering new address record for fe80::2677:3ff:fe7c:d0b0 on wlan0.*.
> Feb 14 20:34:05 gandalfthegreat NetworkManager[1926]: <info> wpa_supplicant die count reset
>
> Feb 14 20:34:22 gandalfthegreat NetworkManager[1926]: <info> (wlan0): IP6 addrconf timed out or failed.
> Feb 14 20:34:22 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) scheduled...
> Feb 14 20:34:22 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) started...
> Feb 14 20:34:22 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) scheduled...
> Feb 14 20:34:22 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 4 of 5 (IP6 Configure Timeout) complete.
> Feb 14 20:34:22 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) started...
> Feb 14 20:34:22 gandalfthegreat avahi-daemon[3891]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.205.7.
> Feb 14 20:34:22 gandalfthegreat avahi-daemon[3891]: New relevant interface wlan0.IPv4 for mDNS.
> Feb 14 20:34:22 gandalfthegreat avahi-daemon[3891]: Registering new address record for 192.168.205.7 on wlan0.IPv4.
> Feb 14 20:34:23 gandalfthegreat NetworkManager[1926]: <info> (wlan0): device state change: ip-config -> activated (reason 'none') [70 100 0]
> Feb 14 20:34:23 gandalfthegreat NetworkManager[1926]: <info> (wlan0): roamed from BSSID 2C:B0:5D:3C:7D:F1 (magicnet-a) to 2C:B0:5D:A0:C8:A6 (magicnet-a)
> Feb 14 20:34:23 gandalfthegreat NetworkManager[1926]: <info> Policy set 'Auto magicnet-a' (wlan0) as default for IPv4 routing and DNS.
> Feb 14 20:34:23 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) successful, device activated.
> Feb 14 20:34:23 gandalfthegreat NetworkManager[1926]: <info> Activation (wlan0) Stage 5 of 5 (IP Configure Commit) complete.
> Feb 14 20:34:23 gandalfthegreat dbus[3526]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
> Feb 14 20:34:23 gandalfthegreat dbus[3526]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
> Feb 14 20:34:23 gandalfthegreat kernel: Loading kernel module for a network device with CAP_SYS_MODULE (deprecated). Use CAP_NET_ADMIN and alias netdev-tun instead.
> Feb 14 20:34:23 gandalfthegreat kernel: Loading kernel module for a network device with CAP_SYS_MODULE (deprecated). Use CAP_NET_ADMIN and alias netdev-tun instead.
> Feb 14 20:34:23 gandalfthegreat kernel: Loading kernel module for a network device with CAP_SYS_MODULE (deprecated). Use CAP_NET_ADMIN and alias netdev-tun instead.
> Feb 14 20:34:23 gandalfthegreat kernel: Loading kernel module for a network device with CAP_SYS_MODULE (deprecated). Use CAP_NET_ADMIN and alias netdev-tun instead.
> Feb 14 20:34:49 gandalfthegreat kernel: cfg80211: Found new beacon on frequency: 5745 MHz (Ch 149) on phy0
>From here, networking was back up and life was good.
But I shouldn't have to kill wpa_supplicant each time I roam.
Suggestions welcome.
Thanks,
Marc
--
"A mouse is a device used to point at the xterm you want to type in" - A.S.R.
Microsoft is to operating systems ....
.... what McDonalds is to gourmet cooking
Home page: http://marc.merlins.org/
More information about the Pkg-wpa-devel
mailing list