wpa_supplicant wifi connection gets disconnected with tls_connection_handshake

[Danny van Heumen]

Binary package hint: wpasupplicant

I'm running (K)Ubuntu Intrepid, fully updated. Since a few days I have gotten disconnected far more than normal from an existing, connected wifi connection. (I haven't had any wifi troubles for months.) When I look in /var/log/wpa_supplicant.log, I get the following messages:

--- /var/log/wpa_supplicant.log ---
...
CTRL-EVENT-SCAN-RESULTS
CTRL-EVENT-SCAN-RESULTS
Trying to associate with xx:xx:xx:xx:xx:xx (SSID='wifi' freq=5580 MHz)
Associated with xx:xx:xx:xx:xx:xx
CTRL-EVENT-EAP-STARTED EAP authentication started
CTRL-EVENT-EAP-METHOD EAP vendor 0 method 25 (PEAP) selected
OpenSSL: tls_connection_handshake - Failed to read possible Application Data error:00000000:lib(0):func(0):reason(0)
EAP-MSCHAPV2: Authentication succeeded
EAP-TLV: TLV Result - Success - EAP-TLV/Phase2 Completed
CTRL-EVENT-EAP-SUCCESS EAP authentication completed successfully
CTRL-EVENT-CONNECTED - Connection to xx:xx:xx:xx:xx:xx completed (auth) [id=0 id_str=]
CTRL-EVENT-SCAN-RESULTS
CTRL-EVENT-SCAN-RESULTS
---

It happens at quite a regular frequency and I can't help but think that it might have something to do with the recent TLS updates.
I also have a friend who remarked that he also hadn't had anymore wifi problems since his reinstallation (about a week ago) and he now also has problems. We get the same error messages.

[Pirouette Cacahuète (lissyx)]

Not sure it could help you, but I found this : http://intellinuxwireless.org/bugzilla/show_bug.cgi?id=1909#c5 :
##############
  ------- Comment #5 From Abhijeet Kolekar 2009-02-20 14:18:29 -------

Clayton , What version is your wpa_supplicant?
This line may be the cause of the problem, This has been fixed in
wpa_supplicant-0.6.7.
"OpenSSL: tls_connection_handshake - Failed to read possible Application Data
error:00000000:lib(0):func(0):reason(0)."

Please try with new wpa_supplicant if you have not already updated.
##############

[Witold Baryluk (baryluk)]

Hi,

mayby this is irrelevant, but i'm on Debian unstable, with wpasupplicant 0.6.9-3, and have exactly the same problem on Intel 2915ABG. Friend of mine, also have the same problem on Debian Unstable, with Intel 2200BG. Other persons (ie. running Windows, or Debian Lenny Stable) have everything ok.

Configuration is WPA with Radius and TTLS + PAP.

firmware-ipw2x00 version 0.16.

Logs here: http://smp.if.uj.edu.pl/~baryluk/bledy_wpa.txt

[hoarak (f-weik)]

Same here on Karmic, with wpasupplicant 0.6.9-3 and Intel WiFi Link 5100

[Witold Baryluk (baryluk)]

I also wanted to say that I still have this problem. Debian Unstable installed few days ago, with another kernel, another network-manager and another gnome tan in #2, still wpasupplicant 0.6.9-3 and from time to time wifi disconnects with this configuration (WPA + Ratius + TTLS + PAP).

[ougu (ana-gallardo-77)]

Hello, I'm using Ubuntu Karmic Koala 9.10

  $ sudo uname -a
  Linux Portatil 2.6.31-15-generic #50-Ubuntu SMP Tue Nov 10 14:54:29 UTC 2009 i686 GNU/Linux

with Network Manager 0.8

  $ sudo dpkg -l network-manager
  ii network-manager 0.8~a~git.20091013t193206.679d548-0ubu network management framework daemon

wpa_supplicant 0.6.9-3

  $ sudo dpkg -l wpasupplicant
  ii wpasupplicant 0.6.9-3ubuntu1 client support for WPA and WPA2 (IEEE 802.11i)

and OpenSSL0.9.8g

  $ sudo dpkg -l openssl
  ii openssl 0.9.8g-16ubuntu3 Secure Socket Layer (SSL) binary and related cryptographic tools

My wireless card is Intel Corporation PRO/Wireless 3945ABG

  $ lspci | grep Wireless
  10:00.0 Network controller: Intel Corporation PRO/Wireless 3945ABG [Golan] Network Connection (rev 02)

I can connect to an SSID with WPA EAP-TTLS + PAP, but if I disconnect and then I try to connect again I can't.

Here is my syslog:

Dec 15 14:56:22 Portatil NetworkManager: <info> Activation (eth2) starting connection 'Auto eduroam'
Dec 15 14:56:22 Portatil NetworkManager: <info> (eth2): device state change: 3 -> 4 (reason 0)
Dec 15 14:56:22 Portatil NetworkManager: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Dec 15 14:56:22 Portatil NetworkManager: <info> Activation (eth2) Stage 1 of 5 (Device Prepare) scheduled...
Dec 15 14:56:22 Portatil NetworkManager: <info> Activation (eth2) Stage 1 of 5 (Device Prepare) started...
Dec 15 14:56:22 Portatil NetworkManager: <info> Activation (eth2) Stage 2 of 5 (Device Configure) scheduled...
Dec 15 14:56:22 Portatil NetworkManager: <info> Activation (eth2) Stage 1 of 5 (Device Prepare) complete.
Dec 15 14:56:22 Portatil NetworkManager: <info> Activation (eth2) Stage 2 of 5 (Device Configure) starting...
Dec 15 14:56:22 Portatil NetworkManager: <info> (eth2): device state change: 4 -> 5 (reason 0)
Dec 15 14:56:22 Portatil NetworkManager: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Dec 15 14:56:22 Portatil NetworkManager: <info> Activation (eth2/wireless): access point 'Auto eduroam' has security, but secrets are required.
Dec 15 14:56:22 Portatil NetworkManager: <info> (eth2): device state change: 5 -> 6 (reason 0)
Dec 15 14:56:22 Portatil NetworkManager: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Dec 15 14:56:22 Portatil NetworkManager: <info> Activation (eth2) Stage 2 of 5 (Device Configure) complete.
Dec 15 14:56:22 Portatil NetworkManager: <info> Activation (eth2) Stage 1 of 5 (Device Prepare) scheduled...
Dec 15 14:56:22 Portatil NetworkManager: <info> Activation (eth2) Stage 1 of 5 (Device Prepare) started...
Dec 15 14:56:22 Portatil NetworkManager: <info> (eth2): device state change: 6 -> 4 (reason 0)
Dec 15 14:56:22 Portatil NetworkManager: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Dec 15 14:56:22 Portatil NetworkManager: <info> Activation (eth2) Stage 2 of 5 (Device Configure) s...

[Miguel (muchochini)]

Well, I just got a Dell Latitude e6500 with Intel PRO/Wireless 5300 AGN. Having the same issue on Ubuntu Karmic 9.10. Here is some input:

# lshw -short
/0/100/1c.1/0 wmaster0 network PRO/Wireless 5300 AGN [Shiloh] Network Connection

# uname -a
Linux andromeda 2.6.31-17-generic #54-Ubuntu SMP Thu Dec 10 17:01:44 UTC 2009 x86_64 GNU/Linux

# lspci | grep -i wireless
0c:00.0 Network controller: Intel Corporation PRO/Wireless 5300 AGN [Shiloh] Network Connection

# dpkg -l wpasupplicant
ii wpasupplicant 0.6.9-3ubuntu1 client support for WPA and WPA2 (IEEE 802.11i

# dpkg -l network-manager
ii network-manager 0.8~a~git.20091013t193206.679d548-0ubu network management framework daemon

If you need anything else, let me know.

Thanks

[Zaphod (vilppu777)]

Same problem on Lucid with intel card.

[Jhase (teleco)]

I think the problem is network-manager, i have changed to WICD and the problem has gone (and wpa_supplicant is the same).

On LG X110:
Ubuntu 9.10 (karmic)
kernel 2.6.31-19-generic
gnome 2.28.1
Intel Atom N270

[Alan (awoodland)]

The odd thing is when it fails disabling NM, using wpa_supplicant manually and then re-enabling NM causes NM to work again (reliably too)

[John Martin (john-d-martin-iii)]

I have this presently with an Inspiron 1525 with Intel Pro Wireless 4965 installed. 2.6.31-20-generic #58-Ubuntu 9.10 x86_64 is the kernel/system. I've just tried to replace NM with WICD to see if this helps.

[Fernando D. (fdbozzo)]

I have the same problem in Ubuntu 10.04 and DHCP, *but* I find that if I don't use DHCP and use a manual IP address then problems dissapear.

[gnd (gnd)]

Hi, i have the same problem on Ubuntu 10.04 with 2.6.32-25-generic i686. Im using a Lenovo T400 with a Intel Wifi Link 5300. Setting a manual IP address doesnt help though.

[Zaphod (vilppu777)]

You may try using a wireless G connection if you are using N. Or it may be a crummy router. I added an extra G access point and now everything works great. My routers wireless N though is really unreliable I will never buy another Buffalo router.

[gnd (gnd)]

I dont think this is possible, is a university network.
Here is the log output from daemon.log:

Oct 1 16:44:02 mai NetworkManager: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 1 16:44:02 mai NetworkManager: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) started...
Oct 1 16:44:02 mai NetworkManager: <info> (wlan0): device state change: 6 -> 4 (reason 0)
Oct 1 16:44:02 mai NetworkManager: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) scheduled...
Oct 1 16:44:02 mai NetworkManager: <info> Activation (wlan0) Stage 1 of 5 (Device Prepare) complete.
Oct 1 16:44:02 mai NetworkManager: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) starting...
Oct 1 16:44:02 mai NetworkManager: <info> (wlan0): device state change: 4 -> 5 (reason 0)
Oct 1 16:44:02 mai NetworkManager: <info> Activation (wlan0/wireless): connection 'Auto eduroam' has security, and secrets exist. No new secrets needed.
Oct 1 16:44:02 mai NetworkManager: <info> Config: added 'ssid' value 'eduroam'
Oct 1 16:44:02 mai NetworkManager: <info> Config: added 'scan_ssid' value '1'
Oct 1 16:44:02 mai NetworkManager: <info> Config: added 'key_mgmt' value 'WPA-EAP'
Oct 1 16:44:02 mai NetworkManager: <info> Config: added 'password' value '<omitted>'
Oct 1 16:44:02 mai NetworkManager: <info> Config: added 'eap' value 'PEAP'
Oct 1 16:44:02 mai NetworkManager: <info> Config: added 'fragment_size' value '1300'
Oct 1 16:44:02 mai NetworkManager: <info> Config: added 'phase1' value 'peapver=0'
Oct 1 16:44:02 mai NetworkManager: <info> Config: added 'phase2' value 'auth=MSCHAPV2'
Oct 1 16:44:02 mai NetworkManager: <info> Config: added 'ca_cert' value '/home/gnd/data/cert.pem'
Oct 1 16:44:02 mai NetworkManager: <info> Config: added 'identity' value '<email address hidden>'
Oct 1 16:44:02 mai NetworkManager: <info> Activation (wlan0) Stage 2 of 5 (Device Configure) complete.
Oct 1 16:44:02 mai NetworkManager: <info> Config: set interface ap_scan to 1
Oct 1 16:44:02 mai NetworkManager: <info> (wlan0): supplicant connection state: disconnected -> scanning
Oct 1 16:44:05 mai wpa_supplicant[1055]: Trying to associate with 00:17:df:94:88:50 (SSID='eduroam' freq=2412 MHz)
Oct 1 16:44:05 mai NetworkManager: <info> (wlan0): supplicant connection state: scanning -> associating
Oct 1 16:44:05 mai wpa_supplicant[1055]: Associated with 00:17:df:94:88:50
Oct 1 16:44:05 mai wpa_supplicant[1055]: CTRL-EVENT-EAP-STARTED EAP authentication started
Oct 1 16:44:05 mai wpa_supplicant[1055]: CTRL-EVENT-EAP-METHOD EAP vendor 0 method 25 (PEAP) selected
Oct 1 16:44:05 mai NetworkManager: <info> (wlan0): supplicant connection state: associating -> associated
Oct 1 16:44:06 mai wpa_supplicant[1055]: OpenSSL: tls_connection_handshake - Failed to read possible Application Data error:00000000:lib(0):func(0):reason(0)
Oct 1 16:44:06 mai wpa_supplicant[1055]: EAP-TLV: TLV Result - Failure
Oct 1 16:44:07 mai wpa_supplicant[1055]: CTRL-EVENT-EAP-FAILURE EAP authentication failed
Oct 1 16:44:09 mai wpa_supplicant[1055]: Authentication with 00:17:df:94:88:50 timed out.
Oct 1 16:44:09 mai wpa_supplicant[1055]: Failed to initiate AP s...