Package: wpasupplicant
Version: 2:2.9.0-12
Severity: important

Hello,

I have got a problem with my Dell Inspiron 3585 wifi adapter:
03:00.0 Network controller: Qualcomm Atheros QCA9377 802.11ac Wireless
Network Adapter (rev 31)

[    3.438605] ath10k_pci 0000:03:00.0 wlp3s0: renamed from wlan0
[   32.826116] wlp3s0: authenticate with 00:1a:8c:c7:63:a9
[   32.859709] wlp3s0: send auth to 00:1a:8c:c7:63:a9 (try 1/3)
[   32.862641] wlp3s0: authenticated
[   32.866234] wlp3s0: associate with 00:1a:8c:c7:63:a9 (try 1/3)
[   32.871392] wlp3s0: RX AssocResp from 00:1a:8c:c7:63:a9 (capab=0x11
status=0 aid=3)
[   32.874132] wlp3s0: associated
[   33.056432] IPv6: ADDRCONF(NETDEV_CHANGE): wlp3s0: link becomes ready
[ 2577.801473] wlp3s0: disconnect from AP 00:1a:8c:c7:63:a9 for new auth
to 00:1a:8c:c7:5f:7a
[ 2577.808020] wlp3s0: authenticate with 00:1a:8c:c7:5f:7a
[ 2577.840862] wlp3s0: send auth to 00:1a:8c:c7:5f:7a (try 1/3)
[ 2577.848092] wlp3s0: authenticated
[ 2577.851599] wlp3s0: associate with 00:1a:8c:c7:5f:7a (try 1/3)
[ 2577.862711] wlp3s0: RX ReassocResp from 00:1a:8c:c7:5f:7a (capab=0x11
status=0 aid=2)
[ 2577.865073] wlp3s0: associated
[ 2835.321270] wlp3s0: disconnect from AP 00:1a:8c:c7:5f:7a for new auth
to 00:1a:8c:c7:63:a9
[ 2835.327712] wlp3s0: authenticate with 00:1a:8c:c7:63:a9
[ 2835.360231] wlp3s0: send auth to 00:1a:8c:c7:63:a9 (try 1/3)
[ 2835.368132] wlp3s0: authenticated
[ 2835.368710] wlp3s0: associate with 00:1a:8c:c7:63:a9 (try 1/3)
[ 2835.380752] wlp3s0: RX ReassocResp from 00:1a:8c:c7:63:a9 (capab=0x11
status=0 aid=3)
[ 2835.383635] wlp3s0: associated
[ 2955.568567] wlp3s0: deauthenticating from 00:1a:8c:c7:63:a9 by local
choice (Reason: 3=DEAUTH_LEAVING)
[ 2969.407112] wlp3s0: authenticate with 00:1a:8c:c7:63:a9
[ 2969.440429] wlp3s0: send auth to 00:1a:8c:c7:63:a9 (try 1/3)
[ 2969.443737] wlp3s0: authenticated
[ 2969.445585] wlp3s0: associate with 00:1a:8c:c7:63:a9 (try 1/3)
[ 2969.450928] wlp3s0: RX AssocResp from 00:1a:8c:c7:63:a9 (capab=0x11
status=0 aid=1)
[ 2969.453427] wlp3s0: associated
[ 2969.611188] IPv6: ADDRCONF(NETDEV_CHANGE): wlp3s0: link becomes ready

It happens with Linux 5.4 and 5.5. After some time (ping test is enough)
like 10 - 40 minutes networkmanager (kde) shows the Wifi as connected,
but networking at all is not working anymore, until I completly
reconnect the wifi.
On our wireless hardware I have checked the logs, if the problem occurs
on my laptop, the Sophos UTM logs this:

2020:03:31-14:39:43 A4004CCF656D273 hostapd: wlan10: STA
e8:6f:38:92:ea:f1 IEEE 802.11: associated (aid 2)
2020:03:31-14:39:43 A4004CCF656D273 awelogger[2009]: id="4104"
severity="info" sys="System" sub="WiFi" name="STA association"
ssid="LEO-WLAN-5G" ssid_id="WLAN3.1" bssid="00:1a:8c:c7:5f:7a"
sta="e8:6f:38:92:ea:f1" status_code="0"
2020:03:31-14:39:43 A4004CCF656D273 awelogger[2009]: id="4101"
severity="info" sys="System" sub="WiFi" name="STA connected"
ssid="LEO-WLAN-5G" ssid_id="WLAN3.1" bssid="00:1a:8c:c7:5f:7a"
sta="e8:6f:38:92:ea:f1"


This is logged on my laptop:

==> /var/log/daemon.log <==
Mar 31 14:39:43 gnu wpa_supplicant[719]: wlp3s0: SME: Trying to
authenticate with 00:1a:8c:c7:5f:7a (SSID='LEO-WLAN-5G' freq=5180 MHz)

==> /var/log/kern.log <==
Mar 31 14:39:43 gnu kernel: [ 2577.801473] wlp3s0: disconnect from AP
00:1a:8c:c7:63:a9 for new auth to 00:1a:8c:c7:5f:7a
Mar 31 14:39:43 gnu kernel: [ 2577.808020] wlp3s0: authenticate with
00:1a:8c:c7:5f:7a
Mar 31 14:39:43 gnu kernel: [ 2577.840862] wlp3s0: send auth to
00:1a:8c:c7:5f:7a (try 1/3)

==> /var/log/daemon.log <==
Mar 31 14:39:43 gnu NetworkManager[708]: <info>  [1585658383.5890]
device (wlp3s0): supplicant interface state: completed -> authenticating
Mar 31 14:39:43 gnu NetworkManager[708]: <info>  [1585658383.5890]
device (p2p-dev-wlp3s0): supplicant management interface state:
completed -> authenticating
Mar 31 14:39:43 gnu wpa_supplicant[719]: wlp3s0:
CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Mar 31 14:39:43 gnu wpa_supplicant[719]: FT: Failed to set PTK to the driver
Mar 31 14:39:43 gnu wpa_supplicant[719]: wlp3s0: Trying to associate
with 00:1a:8c:c7:5f:7a (SSID='LEO-WLAN-5G' freq=5180 MHz)

==> /var/log/kern.log <==
Mar 31 14:39:43 gnu kernel: [ 2577.848092] wlp3s0: authenticated
Mar 31 14:39:43 gnu kernel: [ 2577.851599] wlp3s0: associate with
00:1a:8c:c7:5f:7a (try 1/3)

==> /var/log/daemon.log <==
Mar 31 14:39:43 gnu NetworkManager[708]: <info>  [1585658383.5990]
device (wlp3s0): supplicant interface state: authenticating -> associating
Mar 31 14:39:43 gnu NetworkManager[708]: <info>  [1585658383.5990]
device (p2p-dev-wlp3s0): supplicant management interface state:
authenticating -> associating

==> /var/log/kern.log <==
Mar 31 14:39:43 gnu kernel: [ 2577.862711] wlp3s0: RX ReassocResp from
00:1a:8c:c7:5f:7a (capab=0x11 status=0 aid=2)

==> /var/log/daemon.log <==
Mar 31 14:39:43 gnu wpa_supplicant[719]: dbus:
wpa_dbus_property_changed: no property SessionLength in object
/fi/w1/wpa_supplicant1/Interfaces/0
Mar 31 14:39:43 gnu wpa_supplicant[719]: wlp3s0: Associated with
00:1a:8c:c7:5f:7a
Mar 31 14:39:43 gnu wpa_supplicant[719]: wlp3s0: WPA: Key negotiation
completed with 00:1a:8c:c7:5f:7a [PTK=CCMP GTK=CCMP]
Mar 31 14:39:43 gnu wpa_supplicant[719]: dbus:
wpa_dbus_property_changed: no property RoamTime in object
/fi/w1/wpa_supplicant1/Interfaces/0
Mar 31 14:39:43 gnu wpa_supplicant[719]: dbus:
wpa_dbus_property_changed: no property RoamComplete in object
/fi/w1/wpa_supplicant1/Interfaces/0
Mar 31 14:39:43 gnu wpa_supplicant[719]: wlp3s0: CTRL-EVENT-CONNECTED -
Connection to 00:1a:8c:c7:5f:7a completed [id=0 id_str=]

==> /var/log/kern.log <==
Mar 31 14:39:43 gnu kernel: [ 2577.865073] wlp3s0: associated
Mar 31 14:39:43 gnu kernel: [ 2577.865206] ath: EEPROM regdomain: 0x8114
Mar 31 14:39:43 gnu kernel: [ 2577.865207] ath: EEPROM indicates we
should expect a country code
Mar 31 14:39:43 gnu kernel: [ 2577.865208] ath: doing EEPROM
country->regdmn map search
Mar 31 14:39:43 gnu kernel: [ 2577.865209] ath: country maps to regdmn
code: 0x37
Mar 31 14:39:43 gnu kernel: [ 2577.865210] ath: Country alpha2 being
used: DE
Mar 31 14:39:43 gnu kernel: [ 2577.865211] ath: Regpair used: 0x37
Mar 31 14:39:43 gnu kernel: [ 2577.865212] ath: regdomain 0x8114
dynamically updated by country element

==> /var/log/daemon.log <==
Mar 31 14:39:43 gnu wpa_supplicant[719]: wlp3s0:
CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Mar 31 14:39:43 gnu wpa_supplicant[719]: wlp3s0:
CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE
Mar 31 14:39:43 gnu NetworkManager[708]: <info>  [1585658383.6180]
device (wlp3s0): supplicant interface state: associating -> completed
Mar 31 14:39:43 gnu NetworkManager[708]: <info>  [1585658383.6186]
device (p2p-dev-wlp3s0): supplicant management interface state:
associating -> completed
Mar 31 14:39:43 gnu wpa_supplicant[719]: wlp3s0:
CTRL-EVENT-SIGNAL-CHANGE above=0 signal=-70 noise=-98 txrate=6000


It only happens with wpa2 enterprise wifi, wpa2 personal is stable.

I also see, that it may happen if the adapter changes the association to
another access point (here are multiple access points for the same network).

Before:

wlp3s0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet 192.168.222.119  netmask 255.255.255.0  broadcast
192.168.222.255
        inet6 fe80::78e1:65f9:5060:d27d  prefixlen 64  scopeid 0x20<link>
        inet6 XXXXXXXXXXXXXXXXXXXXXXXXXX  prefixlen 128  scopeid 0x0<global>
        ether e8:6f:38:92:ea:f1  txqueuelen 1000  (Ethernet)
        RX packets 8144  bytes 1167968 (1.1 MiB)
        RX errors 0  dropped 501  overruns 0  frame 0
        TX packets 2840  bytes 451973 (441.3 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

wlp3s0    IEEE 802.11  ESSID:"LEO-WLAN-5G" 
          Mode:Managed  Frequency:5.18 GHz  Access Point:
00:1A:8C:C7:63:A9  
          Bit Rate=6 Mb/s   Tx-Power=30 dBm  
          Retry short limit:7   RTS thr:off   Fragment thr:off
          Encryption key:off
          Power Management:on
          Link Quality=37/70  Signal level=-73 dBm 
          Rx invalid nwid:0  Rx invalid crypt:0  Rx invalid frag:0
          Tx excessive retries:0  Invalid misc:110   Missed beacon:0



After:

wlp3s0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet 192.168.222.119  netmask 255.255.255.0  broadcast
192.168.222.255
        inet6 fe80::78e1:65f9:5060:d27d  prefixlen 64  scopeid 0x20<link>
        inet6 XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX  prefixlen 128  scopeid
0x0<global>
        ether e8:6f:38:92:ea:f1  txqueuelen 1000  (Ethernet)
        RX packets 15139  bytes 2149327 (2.0 MiB)
        RX errors 0  dropped 836  overruns 0  frame 0
        TX packets 6116  bytes 920251 (898.6 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

wlp3s0    IEEE 802.11  ESSID:"LEO-WLAN-5G" 
          Mode:Managed  Frequency:5.18 GHz  Access Point:
00:1A:8C:C7:5F:7A  
          Bit Rate=6 Mb/s   Tx-Power=23 dBm  
          Retry short limit:7   RTS thr:off   Fragment thr:off
          Encryption key:off
          Power Management:on
          Link Quality=38/70  Signal level=-72 dBm 
          Rx invalid nwid:0  Rx invalid crypt:0  Rx invalid frag:0
          Tx excessive retries:52  Invalid misc:0   Missed beacon:0

-- 
/*
Mit freundlichem Gruß / With kind regards,
 Patrick Matthäi
 GNU/Linux Debian Developer

  Blog: https://www.linux-dev.org/
E-Mail: pmatth...@debian.org
        patr...@linux-dev.org
*/

Reply via email to