#989415 network-manager: Intel WiFi on Thinkpad X280 cannot connect: reason=CONN_FAILED

Package:
network-manager
Source:
network-manager
Description:
network management framework (daemon and userspace tools)
Submitter:
Tommaso Gagliardoni
Date:
2021-06-03 09:45:04 UTC
Severity:
important
#989415#5
Date:
2021-06-03 09:42:35 UTC
From:
To:
Dear Maintainer,

not sure exactly when it started because I use regularly my laptop connected
via ethernet. This is a Lenovo Thinkpad X280 with Debian testing and Gnome.
Connection to any WiFi network fails with no apparent reason. The card is
turned on, in fact I can scan existing WiFi's but whenever I try to connect it
fails. I am dual-booting with Windows and the problem does not appear there, so
I guess it's not a hardware problem. Also, I'm 100% sure the wifi key is
correct.

This is my first bug report here, apologies if I mess up.

Here is what I see:

    # lspci |grep -i wireless
-------8<-----------8<-----------8<-----------8<-----------8<----
3b:00.0 Network controller: Intel Corporation Wireless 8265 / 8275 (rev 78)
-------8<-----------8<-----------8<-----------8<-----------8<----

    # journalctl -f
-------8<-----------8<-----------8<-----------8<-----------8<----
May 21 18:01:30 myhostname NetworkManager[639]: <info>  [1621591290.4929]
device (wlp59s0): Activation: starting connection 'WiFi_mySSID' (0123abcd-
just-00an-uuid-0123456789ab)
May 21 18:01:30 myhostname NetworkManager[639]: <info>  [1621591290.4930]
audit: op="connection-add-activate" uuid="0123abcd-just-00an-uuid-0123456789ab"
name="WiFi_mySSID" pid=957 uid=1000 result="success"
May 21 18:01:30 myhostname NetworkManager[639]: <info>  [1621591290.4954]
device (wlp59s0): state change: disconnected -> prepare (reason 'none', sys-
iface-state: 'managed')
May 21 18:01:30 myhostname NetworkManager[639]: <info>  [1621591290.4958]
manager: NetworkManager state is now CONNECTING
May 21 18:01:30 myhostname NetworkManager[639]: <info>  [1621591290.4962]
device (wlp59s0): state change: prepare -> config (reason 'none', sys-iface-
state: 'managed')
May 21 18:01:30 myhostname NetworkManager[639]: <info>  [1621591290.4966]
device (wlp59s0): Activation: (wifi) access point 'WiFi_mySSID' has security,
but secrets are required.
May 21 18:01:30 myhostname NetworkManager[639]: <info>  [1621591290.4966]
device (wlp59s0): state change: config -> need-auth (reason 'none', sys-iface-
state: 'managed')
May 21 18:01:30 myhostname NetworkManager[639]: <info>  [1621591290.4968] sup-
iface[0123456789abcdef,0,wlp59s0]: wps: type pbc start...
May 21 18:01:30 myhostname wpa_supplicant[655]: wlp59s0: WPS-PBC-ACTIVE
May 21 18:01:30 myhostname NetworkManager[639]: <info>  [1621591290.5077]
device (wlp59s0): supplicant interface state: disconnected -> scanning
May 21 18:01:30 myhostname NetworkManager[639]: <info>  [1621591290.5077]
device (p2p-dev-wlp59s0): supplicant management interface state: disconnected
-> scanning
May 21 18:01:55 myhostname NetworkManager[639]: <info>  [1621591315.7216]
device (wlp59s0): state change: need-auth -> prepare (reason 'none', sys-iface-
state: 'managed')
May 21 18:01:55 myhostname NetworkManager[639]: <info>  [1621591315.7221]
device (wlp59s0): state change: prepare -> config (reason 'none', sys-iface-
state: 'managed')
May 21 18:01:55 myhostname NetworkManager[639]: <info>  [1621591315.7223]
device (wlp59s0): Activation: (wifi) connection 'WiFi_mySSID' has security, and
secrets exist.  No new secrets needed.
May 21 18:01:55 myhostname NetworkManager[639]: <info>  [1621591315.7224]
Config: added 'ssid' value 'WiFi_mySSID'
May 21 18:01:55 myhostname NetworkManager[639]: <info>  [1621591315.7224]
Config: added 'scan_ssid' value '1'
May 21 18:01:55 myhostname NetworkManager[639]: <info>  [1621591315.7224]
Config: added 'bgscan' value 'simple:30:-70:86400'
May 21 18:01:55 myhostname NetworkManager[639]: <info>  [1621591315.7224]
Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK'
May 21 18:01:55 myhostname NetworkManager[639]: <info>  [1621591315.7224]
Config: added 'auth_alg' value 'OPEN'
May 21 18:01:55 myhostname NetworkManager[639]: <info>  [1621591315.7224]
Config: added 'psk' value '<hidden>'
May 21 18:01:55 myhostname wpa_supplicant[655]: wlp59s0: SME: Trying to
authenticate with 00:ff:aa:55:00:ff (SSID='WiFi_mySSID' freq=2412 MHz)
May 21 18:01:55 myhostname kernel: wlp59s0: authenticate with 00:ff:aa:55:00:ff
May 21 18:01:55 myhostname NetworkManager[639]: <info>  [1621591315.7474]
device (wlp59s0): supplicant interface state: scanning -> authenticating
May 21 18:01:55 myhostname NetworkManager[639]: <info>  [1621591315.7475]
device (p2p-dev-wlp59s0): supplicant management interface state: scanning ->
authenticating
May 21 18:01:55 myhostname kernel: wlp59s0: send auth to 00:ff:aa:55:00:ff (try
1/3)
May 21 18:01:55 myhostname wpa_supplicant[655]: wlp59s0: Trying to associate
with 00:ff:aa:55:00:ff (SSID='WiFi_mySSID' freq=2412 MHz)
May 21 18:01:55 myhostname NetworkManager[639]: <info>  [1621591315.7629]
device (wlp59s0): supplicant interface state: authenticating -> associating
May 21 18:01:55 myhostname NetworkManager[639]: <info>  [1621591315.7630]
device (p2p-dev-wlp59s0): supplicant management interface state: authenticating
-> associating
May 21 18:01:55 myhostname kernel: wlp59s0: authenticated
May 21 18:01:55 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 1/3)
May 21 18:01:55 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 2/3)
May 21 18:01:55 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 3/3)
May 21 18:01:56 myhostname kernel: wlp59s0: association with 00:ff:aa:55:00:ff
timed out
May 21 18:01:56 myhostname NetworkManager[639]: <info>  [1621591316.1218]
device (wlp59s0): supplicant interface state: associating -> disconnected
May 21 18:01:56 myhostname NetworkManager[639]: <info>  [1621591316.1219]
device (p2p-dev-wlp59s0): supplicant management interface state: associating ->
disconnected
May 21 18:01:56 myhostname NetworkManager[639]: <info>  [1621591316.2220]
device (wlp59s0): supplicant interface state: disconnected -> scanning
May 21 18:01:56 myhostname NetworkManager[639]: <info>  [1621591316.2222]
device (p2p-dev-wlp59s0): supplicant management interface state: disconnected
-> scanning
May 21 18:01:57 myhostname wpa_supplicant[655]: wlp59s0: SME: Trying to
authenticate with 00:ff:aa:55:00:ff (SSID='WiFi_mySSID' freq=2412 MHz)
May 21 18:01:57 myhostname kernel: wlp59s0: authenticate with 00:ff:aa:55:00:ff
May 21 18:01:57 myhostname kernel: wlp59s0: send auth to 00:ff:aa:55:00:ff (try
1/3)
May 21 18:01:57 myhostname NetworkManager[639]: <info>  [1621591317.0932]
device (wlp59s0): supplicant interface state: scanning -> authenticating
May 21 18:01:57 myhostname NetworkManager[639]: <info>  [1621591317.0933]
device (p2p-dev-wlp59s0): supplicant management interface state: scanning ->
authenticating
May 21 18:01:57 myhostname kernel: wlp59s0: send auth to 00:ff:aa:55:00:ff (try
2/3)
May 21 18:01:57 myhostname wpa_supplicant[655]: wlp59s0: Trying to associate
with 00:ff:aa:55:00:ff (SSID='WiFi_mySSID' freq=2412 MHz)
May 21 18:01:57 myhostname kernel: wlp59s0: authenticated
May 21 18:01:57 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 1/3)
May 21 18:01:57 myhostname NetworkManager[639]: <info>  [1621591317.2081]
device (wlp59s0): supplicant interface state: authenticating -> associating
May 21 18:01:57 myhostname NetworkManager[639]: <info>  [1621591317.2082]
device (p2p-dev-wlp59s0): supplicant management interface state: authenticating
-> associating
May 21 18:01:57 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 2/3)
May 21 18:01:57 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 3/3)
May 21 18:01:57 myhostname kernel: wlp59s0: association with 00:ff:aa:55:00:ff
timed out
May 21 18:01:57 myhostname NetworkManager[639]: <info>  [1621591317.5597]
device (wlp59s0): supplicant interface state: associating -> disconnected
May 21 18:01:57 myhostname NetworkManager[639]: <info>  [1621591317.5598]
device (p2p-dev-wlp59s0): supplicant management interface state: associating ->
disconnected
May 21 18:01:58 myhostname NetworkManager[639]: <info>  [1621591318.0601]
device (wlp59s0): supplicant interface state: disconnected -> scanning
May 21 18:01:58 myhostname NetworkManager[639]: <info>  [1621591318.0602]
device (p2p-dev-wlp59s0): supplicant management interface state: disconnected
-> scanning
May 21 18:02:00 myhostname wpa_supplicant[655]: wlp59s0: SME: Trying to
authenticate with 00:ff:aa:55:00:ff (SSID='WiFi_mySSID' freq=2412 MHz)
May 21 18:02:00 myhostname kernel: wlp59s0: authenticate with 00:ff:aa:55:00:ff
May 21 18:02:00 myhostname NetworkManager[639]: <info>  [1621591320.3059]
device (wlp59s0): supplicant interface state: scanning -> authenticating
May 21 18:02:00 myhostname NetworkManager[639]: <info>  [1621591320.3060]
device (p2p-dev-wlp59s0): supplicant management interface state: scanning ->
authenticating
May 21 18:02:00 myhostname kernel: wlp59s0: send auth to 00:ff:aa:55:00:ff (try
1/3)
May 21 18:02:00 myhostname wpa_supplicant[655]: wlp59s0: Trying to associate
with 00:ff:aa:55:00:ff (SSID='WiFi_mySSID' freq=2412 MHz)
May 21 18:02:00 myhostname NetworkManager[639]: <info>  [1621591320.3382]
device (wlp59s0): supplicant interface state: authenticating -> associating
May 21 18:02:00 myhostname NetworkManager[639]: <info>  [1621591320.3383]
device (p2p-dev-wlp59s0): supplicant management interface state: authenticating
-> associating
May 21 18:02:00 myhostname kernel: wlp59s0: authenticated
May 21 18:02:00 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 1/3)
May 21 18:02:00 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 2/3)
May 21 18:02:00 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 3/3)
May 21 18:02:00 myhostname kernel: wlp59s0: association with 00:ff:aa:55:00:ff
timed out
May 21 18:02:00 myhostname NetworkManager[639]: <info>  [1621591320.6867]
device (wlp59s0): supplicant interface state: associating -> disconnected
May 21 18:02:00 myhostname NetworkManager[639]: <info>  [1621591320.6868]
device (p2p-dev-wlp59s0): supplicant management interface state: associating ->
disconnected
May 21 18:02:01 myhostname NetworkManager[639]: <info>  [1621591321.6878]
device (wlp59s0): supplicant interface state: disconnected -> scanning
May 21 18:02:01 myhostname NetworkManager[639]: <info>  [1621591321.6879]
device (p2p-dev-wlp59s0): supplicant management interface state: disconnected
-> scanning
May 21 18:02:02 myhostname wpa_supplicant[655]: wlp59s0: SME: Trying to
authenticate with 00:ff:aa:55:00:ff (SSID='WiFi_mySSID' freq=2412 MHz)
May 21 18:02:02 myhostname kernel: wlp59s0: authenticate with 00:ff:aa:55:00:ff
May 21 18:02:02 myhostname NetworkManager[639]: <info>  [1621591322.5432]
device (wlp59s0): supplicant interface state: scanning -> authenticating
May 21 18:02:02 myhostname kernel: wlp59s0: send auth to 00:ff:aa:55:00:ff (try
1/3)
May 21 18:02:02 myhostname NetworkManager[639]: <info>  [1621591322.5434]
device (p2p-dev-wlp59s0): supplicant management interface state: scanning ->
authenticating
May 21 18:02:02 myhostname kernel: wlp59s0: send auth to 00:ff:aa:55:00:ff (try
2/3)
May 21 18:02:02 myhostname wpa_supplicant[655]: wlp59s0: Trying to associate
with 00:ff:aa:55:00:ff (SSID='WiFi_mySSID' freq=2412 MHz)
May 21 18:02:02 myhostname kernel: wlp59s0: authenticated
May 21 18:02:02 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 1/3)
May 21 18:02:02 myhostname NetworkManager[639]: <info>  [1621591322.6565]
device (wlp59s0): supplicant interface state: authenticating -> associating
May 21 18:02:02 myhostname NetworkManager[639]: <info>  [1621591322.6566]
device (p2p-dev-wlp59s0): supplicant management interface state: authenticating
-> associating
May 21 18:02:02 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 2/3)
May 21 18:02:02 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 3/3)
May 21 18:02:02 myhostname kernel: wlp59s0: association with 00:ff:aa:55:00:ff
timed out
May 21 18:02:03 myhostname wpa_supplicant[655]: wlp59s0: CTRL-EVENT-SSID-TEMP-
DISABLED id=0 ssid="WiFi_mySSID" auth_failures=1 duration=10 reason=CONN_FAILED
May 21 18:02:03 myhostname NetworkManager[639]: <info>  [1621591323.0070]
device (wlp59s0): supplicant interface state: associating -> disconnected
May 21 18:02:03 myhostname NetworkManager[639]: <info>  [1621591323.0071]
device (p2p-dev-wlp59s0): supplicant management interface state: associating ->
disconnected
May 21 18:02:08 myhostname NetworkManager[639]: <info>  [1621591328.0109]
device (wlp59s0): supplicant interface state: disconnected -> scanning
May 21 18:02:08 myhostname NetworkManager[639]: <info>  [1621591328.0110]
device (p2p-dev-wlp59s0): supplicant management interface state: disconnected
-> scanning
May 21 18:02:12 myhostname wpa_supplicant[655]: wlp59s0: CTRL-EVENT-SSID-
REENABLED id=0 ssid="WiFi_mySSID"
May 21 18:02:12 myhostname wpa_supplicant[655]: wlp59s0: SME: Trying to
authenticate with 00:ff:aa:55:00:ff (SSID='WiFi_mySSID' freq=2412 MHz)
May 21 18:02:12 myhostname kernel: wlp59s0: authenticate with 00:ff:aa:55:00:ff
May 21 18:02:12 myhostname NetworkManager[639]: <info>  [1621591332.8656]
device (wlp59s0): supplicant interface state: scanning -> authenticating
May 21 18:02:12 myhostname NetworkManager[639]: <info>  [1621591332.8657]
device (p2p-dev-wlp59s0): supplicant management interface state: scanning ->
authenticating
May 21 18:02:12 myhostname kernel: wlp59s0: send auth to 00:ff:aa:55:00:ff (try
1/3)
May 21 18:02:12 myhostname wpa_supplicant[655]: wlp59s0: Trying to associate
with 00:ff:aa:55:00:ff (SSID='WiFi_mySSID' freq=2412 MHz)
May 21 18:02:12 myhostname kernel: wlp59s0: authenticated
May 21 18:02:12 myhostname NetworkManager[639]: <info>  [1621591332.8776]
device (wlp59s0): supplicant interface state: authenticating -> associating
May 21 18:02:12 myhostname NetworkManager[639]: <info>  [1621591332.8777]
device (p2p-dev-wlp59s0): supplicant management interface state: authenticating
-> associating
May 21 18:02:12 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 1/3)
May 21 18:02:12 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 2/3)
May 21 18:02:13 myhostname kernel: wlp59s0: associate with 00:ff:aa:55:00:ff
(try 3/3)
May 21 18:02:13 myhostname kernel: wlp59s0: association with 00:ff:aa:55:00:ff
timed out
May 21 18:02:13 myhostname wpa_supplicant[655]: wlp59s0: CTRL-EVENT-SSID-TEMP-
DISABLED id=0 ssid="WiFi_mySSID" auth_failures=2 duration=20 reason=CONN_FAILED
May 21 18:02:13 myhostname NetworkManager[639]: <info>  [1621591333.2213]
device (wlp59s0): supplicant interface state: associating -> disconnected
May 21 18:02:13 myhostname NetworkManager[639]: <info>  [1621591333.2213]
device (p2p-dev-wlp59s0): supplicant management interface state: associating ->
disconnected
May 21 18:02:20 myhostname NetworkManager[639]: <warn>  [1621591340.5697]
device (wlp59s0): Activation: (wifi) association took too long
May 21 18:02:20 myhostname NetworkManager[639]: <info>  [1621591340.5698]
device (wlp59s0): state change: config -> need-auth (reason 'none', sys-iface-
state: 'managed')
May 21 18:02:20 myhostname NetworkManager[639]: <info>  [1621591340.5707] sup-
iface[0123456789abcdef,0,wlp59s0]: wps: type pbc start...
May 21 18:02:20 myhostname NetworkManager[639]: <warn>  [1621591340.5734]
device (wlp59s0): Activation: (wifi) asking for new secrets
May 21 18:02:20 myhostname wpa_supplicant[655]: wlp59s0: WPS-PBC-ACTIVE
May 21 18:02:20 myhostname NetworkManager[639]: <info>  [1621591340.5854]
device (wlp59s0): supplicant interface state: disconnected -> scanning
May 21 18:02:20 myhostname NetworkManager[639]: <info>  [1621591340.5855]
device (p2p-dev-wlp59s0): supplicant management interface state: disconnected
-> scanning
May 21 18:02:22 myhostname NetworkManager[639]: <warn>  [1621591342.7709]
device (wlp59s0): no secrets: User canceled the secrets request.
May 21 18:02:22 myhostname NetworkManager[639]: <info>  [1621591342.7710]
device (wlp59s0): state change: need-auth -> failed (reason 'no-secrets', sys-
iface-state: 'managed')
May 21 18:02:22 myhostname NetworkManager[639]: <info>  [1621591342.7726]
manager: NetworkManager state is now DISCONNECTED
May 21 18:02:22 myhostname NetworkManager[639]: <warn>  [1621591342.7751]
device (wlp59s0): Activation: failed for connection 'WiFi_mySSID'
May 21 18:02:22 myhostname NetworkManager[639]: <info>  [1621591342.7756]
device (wlp59s0): state change: failed -> disconnected (reason 'none', sys-
iface-state: 'managed')
May 21 18:02:22 myhostname wpa_supplicant[655]: wlp59s0: Reject scan trigger
since one is already pending
-------8<-----------8<-----------8<-----------8<-----------8<----