406231 – Wifi is very slow to connect when opening session

Bug 406231 - Wifi is very slow to connect when opening session

Summary: Wifi is very slow to connect when opening session

Status:	RESOLVED WORKSFORME

Alias:	None

Product:	plasma-nm
Classification:	Plasma
Component:	general (show other bugs)
Version:	master
Platform:	Fedora RPMs Linux

Importance:	NOR normal
Target Milestone:	---
Assignee:	Jan Grulich

URL:
Keywords:

Depends on:
Blocks:

Reported:	2019-04-04 20:33 UTC by Edouard Duliège
Modified:	2021-01-05 11:26 UTC (History)
CC List:	3 users (show)

See Also:
Latest Commit:
Version Fixed In:

Attachments
Add an attachment

Note You need to log in before you can comment on or make changes to this bug.

Description Edouard Duliège 2019-04-04 20:33:44 UTC

Description of problem:
Wifi is very slow to connect when I open a session. after a good 10 seconds the system says "wrong password" then immediately connects to the wifi.
Right after opening the session, if I disable wifi and enable it again, it connects to the wifi rapidly and flawlessly.

How reproducible:
everytime

Steps to Reproduce:
1. open session
2. wait for wifi to connect (10 sec)

Actual results:
error message then connects flawlessly

Expected results:
connects to wifi within 3 sec

Additional info:
Wifi card:
02:00.0 Network controller: Intel Corporation Wireless 8265 / 8275 (rev 78)

rfkill list
0: hci0: Bluetooth
        Soft blocked: yes
        Hard blocked: no
1: phy0: Wireless LAN
        Soft blocked: no
        Hard blocked: no

nmcli con
NAME  UUID                                  TYPE  DEVICE 
AKED  adbfb94b-0c7b-4078-9253-715f12988ede  wifi  wlp2s0

journal:
janv. 26 08:56:39 localhost.localdomain NetworkManager[940]: <info>  [1548489399.2501] audit: op="statistics" arg="refresh-rate-ms" pid=1413 uid=1000 result="success"
janv. 26 08:56:38 localhost.localdomain audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
janv. 26 08:56:32 localhost.localdomain chronyd[843]: Selected source 192.168.1.1
janv. 26 08:56:28 localhost.localdomain NetworkManager[940]: <info>  [1548489388.4548] audit: op="statistics" arg="refresh-rate-ms" pid=1413 uid=1000 result="success"
janv. 26 08:56:28 localhost.localdomain nm-dispatcher[4358]: req:6 'connectivity-change': start running ordered scripts...
janv. 26 08:56:28 localhost.localdomain nm-dispatcher[4358]: req:6 'connectivity-change': new request (3 scripts)
janv. 26 08:56:28 localhost.localdomain NetworkManager[940]: <info>  [1548489388.1945] manager: NetworkManager state is now CONNECTED_GLOBAL
janv. 26 08:56:28 localhost.localdomain audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
janv. 26 08:56:28 localhost.localdomain chronyd[843]: Source 37.187.18.4 online
janv. 26 08:56:28 localhost.localdomain chronyd[843]: Source 188.165.207.160 online
janv. 26 08:56:28 localhost.localdomain chronyd[843]: Source 212.51.181.242 online
janv. 26 08:56:28 localhost.localdomain chronyd[843]: Source 92.243.6.5 online
janv. 26 08:56:27 localhost.localdomain systemd[1]: iscsi.service: Unit cannot be reloaded because it is inactive.
janv. 26 08:56:27 localhost.localdomain nm-dispatcher[4358]: req:5 'up' [wlp2s0]: start running ordered scripts...
janv. 26 08:56:27 localhost.localdomain nm-dispatcher[4358]: req:5 'up' [wlp2s0]: new request (3 scripts)
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.9733] device (wlp2s0): Activation: successful, device activated.
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.9699] policy: set 'AKED' (wlp2s0) as default for IPv4 routing and DNS
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.9696] manager: NetworkManager state is now CONNECTED_SITE
janv. 26 08:56:27 localhost.localdomain dhclient[4507]: bound to 192.168.1.41 -- renewal in 32850 seconds.
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.9603] manager: NetworkManager state is now CONNECTED_LOCAL
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.9600] device (wlp2s0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.9597] device (wlp2s0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.9586] device (wlp2s0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:27 localhost.localdomain avahi-daemon[804]: Registering new address record for 192.168.1.41 on wlp2s0.IPv4.
janv. 26 08:56:27 localhost.localdomain avahi-daemon[804]: New relevant interface wlp2s0.IPv4 for mDNS.
janv. 26 08:56:27 localhost.localdomain avahi-daemon[804]: Joining mDNS multicast group on interface wlp2s0.IPv4 with address 192.168.1.41.
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.9563] dhcp4 (wlp2s0): state changed unknown -> bound
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.9563] dhcp4 (wlp2s0):   nameserver '192.168.1.1'
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.9563] dhcp4 (wlp2s0):   lease time 86400
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.9563] dhcp4 (wlp2s0):   gateway 192.168.1.1
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.9563] dhcp4 (wlp2s0):   plen 24 (255.255.255.0)
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.9562] dhcp4 (wlp2s0):   address 192.168.1.41
janv. 26 08:56:27 localhost.localdomain dhclient[4507]: DHCPACK from 192.168.1.1 (xid=0x20bdc704)
janv. 26 08:56:27 localhost.localdomain dhclient[4507]: DHCPREQUEST on wlp2s0 to 255.255.255.255 port 67 (xid=0x20bdc704)
janv. 26 08:56:27 localhost.localdomain avahi-daemon[804]: Registering new address record for fe80::4e96:a5eb:c0c0:12d1 on wlp2s0.*.
janv. 26 08:56:27 localhost.localdomain avahi-daemon[804]: New relevant interface wlp2s0.IPv6 for mDNS.
janv. 26 08:56:27 localhost.localdomain avahi-daemon[804]: Joining mDNS multicast group on interface wlp2s0.IPv6 with address fe80::4e96:a5eb:c0c0:12d1.
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.8953] dhcp4 (wlp2s0): dhclient started with pid 4507
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.8926] dhcp4 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.8922] device (wlp2s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:27 localhost.localdomain audit: NETFILTER_CFG table=filter family=10 entries=82
janv. 26 08:56:27 localhost.localdomain audit: NETFILTER_CFG table=nat family=10 entries=50
janv. 26 08:56:27 localhost.localdomain audit: NETFILTER_CFG table=mangle family=10 entries=39
janv. 26 08:56:27 localhost.localdomain audit: NETFILTER_CFG table=raw family=10 entries=30
janv. 26 08:56:27 localhost.localdomain audit: NETFILTER_CFG table=filter family=2 entries=81
janv. 26 08:56:27 localhost.localdomain audit: NETFILTER_CFG table=nat family=2 entries=50
janv. 26 08:56:27 localhost.localdomain audit: NETFILTER_CFG table=mangle family=2 entries=39
janv. 26 08:56:27 localhost.localdomain audit: NETFILTER_CFG table=raw family=2 entries=27
janv. 26 08:56:27 localhost.localdomain wpa_supplicant[1092]: wlp2s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-54 noise=9999 txrate=1000
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.8688] device (wlp2s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'AKED'.
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.8688] device (wlp2s0): supplicant interface state: 4-way handshake -> completed
janv. 26 08:56:27 localhost.localdomain wpa_supplicant[1092]: wlp2s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-54 noise=9999 txrate=1000
janv. 26 08:56:27 localhost.localdomain wpa_supplicant[1092]: wlp2s0: CTRL-EVENT-CONNECTED - Connection to 24:95:04:c3:87:8c completed [id=0 id_str=]
janv. 26 08:56:27 localhost.localdomain kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready
janv. 26 08:56:27 localhost.localdomain wpa_supplicant[1092]: wlp2s0: WPA: Key negotiation completed with 24:95:04:c3:87:8c [PTK=CCMP GTK=TKIP]
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.8506] device (wlp2s0): supplicant interface state: associated -> 4-way handshake
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.8062] device (wlp2s0): supplicant interface state: associating -> associated
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.8014] device (wlp2s0): supplicant interface state: authenticating -> associating
janv. 26 08:56:27 localhost.localdomain wpa_supplicant[1092]: wlp2s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
janv. 26 08:56:27 localhost.localdomain kernel: wlp2s0: associated
janv. 26 08:56:27 localhost.localdomain wpa_supplicant[1092]: wlp2s0: Associated with 24:95:04:c3:87:8c
janv. 26 08:56:27 localhost.localdomain kernel: wlp2s0: RX AssocResp from 24:95:04:c3:87:8c (capab=0x411 status=0 aid=4)
janv. 26 08:56:27 localhost.localdomain NetworkManager[940]: <info>  [1548489387.7945] device (wlp2s0): supplicant interface state: scanning -> authenticating
janv. 26 08:56:27 localhost.localdomain kernel: wlp2s0: associate with 24:95:04:c3:87:8c (try 1/3)
janv. 26 08:56:27 localhost.localdomain kernel: wlp2s0: authenticated
janv. 26 08:56:27 localhost.localdomain wpa_supplicant[1092]: wlp2s0: Trying to associate with 24:95:04:c3:87:8c (SSID='AKED' freq=2437 MHz)
janv. 26 08:56:27 localhost.localdomain kernel: wlp2s0: send auth to 24:95:04:c3:87:8c (try 1/3)
janv. 26 08:56:27 localhost.localdomain kernel: wlp2s0: authenticate with 24:95:04:c3:87:8c
janv. 26 08:56:27 localhost.localdomain wpa_supplicant[1092]: wlp2s0: SME: Trying to authenticate with 24:95:04:c3:87:8c (SSID='AKED' freq=2437 MHz)
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9440] Config: added 'psk' value '<hidden>'
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9440] Config: added 'auth_alg' value 'OPEN'
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9439] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256'
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9439] Config: added 'bgscan' value 'simple:30:-80:86400'
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9439] Config: added 'scan_ssid' value '1'
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9439] Config: added 'ssid' value 'AKED'
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9439] device (wlp2s0): Activation: (wifi) connection 'AKED' has security, and secrets exist.  No new secrets needed.
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9436] device (wlp2s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9432] device (wlp2s0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:26 localhost.localdomain kdeinit5[1378]: plasma-nm: Unhandled active connection state change:  1
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9173] device (wlp2s0): supplicant interface state: inactive -> scanning
janv. 26 08:56:26 localhost.localdomain wpa_supplicant[1092]: wlp2s0: WPS-PBC-ACTIVE
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9114] device (wlp2s0): supplicant interface state: disabled -> inactive
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9055] sup-iface[0x5570cdf8c230,wlp2s0]: wps: type pbc start...
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9053] device (wlp2s0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9053] device (wlp2s0): Activation: (wifi) access point 'AKED' has security, but secrets are required.
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9051] device (wlp2s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.9048] device (wlp2s0): supplicant interface state: ready -> disabled
janv. 26 08:56:26 localhost.localdomain kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.8896] device (wlp2s0): set-hw-addr: reset MAC address to 18:1D:EA:33:E7:FD (preserve)
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.8875] manager: NetworkManager state is now CONNECTING
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.8872] device (wlp2s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.8871] device (wlp2s0): Activation: starting connection 'AKED' (adbfb94b-0c7b-4078-9253-715f12988ede)
janv. 26 08:56:26 localhost.localdomain NetworkManager[940]: <info>  [1548489386.8865] policy: auto-activating connection 'AKED' (adbfb94b-0c7b-4078-9253-715f12988ede)
janv. 26 08:56:26 localhost.localdomain wpa_supplicant[1092]: wlp2s0: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=FR
janv. 26 08:56:23 localhost.localdomain plasmashell[1413]: kf5idletime_kwayland: This plugin does not support polling idle time
janv. 26 08:56:23 localhost.localdomain kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
janv. 26 08:56:23 localhost.localdomain NetworkManager[940]: <info>  [1548489383.4177] device (wlp2s0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
janv. 26 08:56:23 localhost.localdomain NetworkManager[940]: <info>  [1548489383.4175] device (wlp2s0): supplicant interface state: starting -> ready
janv. 26 08:56:23 localhost.localdomain NetworkManager[940]: <info>  [1548489383.4140] sup-iface[0x5570cdf8c230,wlp2s0]: supports 5 scan SSIDs
janv. 26 08:56:23 localhost.localdomain NetworkManager[940]: <info>  [1548489383.3583] manager: rfkill: WiFi now enabled by radio killswitch
janv. 26 08:56:23 localhost.localdomain NetworkManager[940]: <info>  [1548489383.3572] audit: op="radio-control" arg="wireless-enabled" pid=1413 uid=1000 result="success"
janv. 26 08:56:23 localhost.localdomain kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
janv. 26 08:56:23 localhost.localdomain NetworkManager[940]: <info>  [1548489383.1218] manager: rfkill: WiFi hardware radio set enabled
janv. 26 08:56:21 localhost.localdomain nm-dispatcher[4358]: req:4 'connectivity-change': start running ordered scripts...
janv. 26 08:56:21 localhost.localdomain nm-dispatcher[4358]: req:4 'connectivity-change': new request (3 scripts)
janv. 26 08:56:20 localhost.localdomain chronyd[843]: Source 92.243.6.5 offline
janv. 26 08:56:20 localhost.localdomain chronyd[843]: Source 37.187.18.4 offline
janv. 26 08:56:20 localhost.localdomain chronyd[843]: Source 188.165.207.160 offline
janv. 26 08:56:20 localhost.localdomain chronyd[843]: Source 212.51.181.242 offline
janv. 26 08:56:20 localhost.localdomain wpa_supplicant[1092]: nl80211: deinit ifname=wlp2s0 disabled_11b_rates=0
janv. 26 08:56:20 localhost.localdomain nm-dispatcher[4358]: req:3 'down' [wlp2s0]: start running ordered scripts...
janv. 26 08:56:20 localhost.localdomain nm-dispatcher[4358]: req:3 'down' [wlp2s0]: new request (3 scripts)
janv. 26 08:56:20 localhost.localdomain NetworkManager[940]: <info>  [1548489380.7007] manager: rfkill: WiFi now disabled by radio killswitch
janv. 26 08:56:20 localhost.localdomain NetworkManager[940]: <info>  [1548489380.7000] audit: op="radio-control" arg="wireless-enabled" pid=1413 uid=1000 result="success"
janv. 26 08:56:20 localhost.localdomain audit: NETFILTER_CFG table=filter family=10 entries=85
janv. 26 08:56:20 localhost.localdomain audit: NETFILTER_CFG table=nat family=10 entries=52
janv. 26 08:56:20 localhost.localdomain audit: NETFILTER_CFG table=mangle family=10 entries=40
janv. 26 08:56:20 localhost.localdomain audit: NETFILTER_CFG table=raw family=10 entries=31
janv. 26 08:56:20 localhost.localdomain wpa_supplicant[1092]: nl80211: deinit ifname=p2p-dev-wlp2s0 disabled_11b_rates=0
janv. 26 08:56:20 localhost.localdomain audit: NETFILTER_CFG table=filter family=2 entries=84
janv. 26 08:56:20 localhost.localdomain audit: NETFILTER_CFG table=nat family=2 entries=52
janv. 26 08:56:20 localhost.localdomain audit: NETFILTER_CFG table=mangle family=2 entries=40
janv. 26 08:56:20 localhost.localdomain audit: NETFILTER_CFG table=raw family=2 entries=28
janv. 26 08:56:20 localhost.localdomain audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
janv. 26 08:56:20 localhost.localdomain systemd[1]: Started Load/Save RF Kill Switch Status.
janv. 26 08:56:20 localhost.localdomain NetworkManager[940]: <info>  [1548489380.6855] manager: NetworkManager state is now DISCONNECTED
janv. 26 08:56:20 localhost.localdomain NetworkManager[940]: <info>  [1548489380.6805] device (wlp2s0): set-hw-addr: set MAC address to 4E:48:E3:1E:A4:7E (scanning)
janv. 26 08:56:20 localhost.localdomain NetworkManager[940]: <info>  [1548489380.6764] dhcp4 (wlp2s0): state changed bound -> done
janv. 26 08:56:20 localhost.localdomain NetworkManager[940]: <info>  [1548489380.6758] dhcp4 (wlp2s0): canceled DHCP transaction, DHCP client pid 4402
janv. 26 08:56:20 localhost.localdomain systemd[1]: Starting Load/Save RF Kill Switch Status...
janv. 26 08:56:20 localhost.localdomain wpa_supplicant[1092]: rfkill: WLAN soft blocked
janv. 26 08:56:20 localhost.localdomain wpa_supplicant[1092]: rfkill: WLAN soft blocked
janv. 26 08:56:20 localhost.localdomain wpa_supplicant[1092]: wlp2s0: CTRL-EVENT-DISCONNECTED bssid=24:95:04:c3:87:8c reason=3 locally_generated=1
janv. 26 08:56:20 localhost.localdomain dhclient[4402]: receive_packet failed on wlp2s0: Network is down
janv. 26 08:56:20 localhost.localdomain avahi-daemon[804]: Withdrawing address record for 192.168.1.41 on wlp2s0.
janv. 26 08:56:20 localhost.localdomain avahi-daemon[804]: Withdrawing address record for fe80::4e96:a5eb:c0c0:12d1 on wlp2s0.
janv. 26 08:56:20 localhost.localdomain wpa_supplicant[1092]: wlp2s0: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=0 noise=9999 txrate=0
janv. 26 08:56:20 localhost.localdomain avahi-daemon[804]: Leaving mDNS multicast group on interface wlp2s0.IPv4 with address 192.168.1.41.
janv. 26 08:56:20 localhost.localdomain NetworkManager[940]: <info>  [1548489380.6580] device (wlp2s0): state change: activated -> unavailable (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:20 localhost.localdomain avahi-daemon[804]: Interface wlp2s0.IPv4 no longer relevant for mDNS.
janv. 26 08:56:20 localhost.localdomain NetworkManager[940]: <info>  [1548489380.6579] manager: rfkill: WiFi hardware radio set disabled
janv. 26 08:56:20 localhost.localdomain avahi-daemon[804]: Leaving mDNS multicast group on interface wlp2s0.IPv6 with address fe80::4e96:a5eb:c0c0:12d1.
janv. 26 08:56:20 localhost.localdomain avahi-daemon[804]: Interface wlp2s0.IPv6 no longer relevant for mDNS.
janv. 26 08:56:20 localhost.localdomain kernel: wlp2s0: deauthenticating from 24:95:04:c3:87:8c by local choice (Reason: 3=DEAUTH_LEAVING)
janv. 26 08:56:18 localhost.localdomain wpa_supplicant[1092]: wlp2s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-51 noise=9999 txrate=144400
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.5431] audit: op="statistics" arg="refresh-rate-ms" pid=1413 uid=1000 result="success"
janv. 26 08:56:15 localhost.localdomain chronyd[843]: Source 37.187.18.4 online
janv. 26 08:56:15 localhost.localdomain chronyd[843]: Source 188.165.207.160 online
janv. 26 08:56:15 localhost.localdomain chronyd[843]: Source 212.51.181.242 online
janv. 26 08:56:15 localhost.localdomain chronyd[843]: Source 92.243.6.5 online
janv. 26 08:56:15 localhost.localdomain systemd[1]: iscsi.service: Unit cannot be reloaded because it is inactive.
janv. 26 08:56:15 localhost.localdomain nm-dispatcher[4358]: req:2 'up' [wlp2s0]: start running ordered scripts...
janv. 26 08:56:15 localhost.localdomain nm-dispatcher[4358]: req:2 'up' [wlp2s0]: new request (3 scripts)
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.1510] device (wlp2s0): Activation: successful, device activated.
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.1480] policy: set 'AKED' (wlp2s0) as default for IPv4 routing and DNS
janv. 26 08:56:15 localhost.localdomain dhclient[4402]: bound to 192.168.1.41 -- renewal in 39319 seconds.
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.1419] manager: NetworkManager state is now CONNECTED_GLOBAL
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.1415] device (wlp2s0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.1412] device (wlp2s0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.1400] device (wlp2s0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:15 localhost.localdomain avahi-daemon[804]: Registering new address record for 192.168.1.41 on wlp2s0.IPv4.
janv. 26 08:56:15 localhost.localdomain avahi-daemon[804]: New relevant interface wlp2s0.IPv4 for mDNS.
janv. 26 08:56:15 localhost.localdomain avahi-daemon[804]: Joining mDNS multicast group on interface wlp2s0.IPv4 with address 192.168.1.41.
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.1382] dhcp4 (wlp2s0): state changed unknown -> bound
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.1382] dhcp4 (wlp2s0):   nameserver '192.168.1.1'
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.1382] dhcp4 (wlp2s0):   lease time 86400
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.1382] dhcp4 (wlp2s0):   gateway 192.168.1.1
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.1381] dhcp4 (wlp2s0):   plen 24 (255.255.255.0)
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.1381] dhcp4 (wlp2s0):   address 192.168.1.41
janv. 26 08:56:15 localhost.localdomain dhclient[4402]: DHCPACK from 192.168.1.1 (xid=0x80c6d64b)
janv. 26 08:56:15 localhost.localdomain dhclient[4402]: DHCPREQUEST on wlp2s0 to 255.255.255.255 port 67 (xid=0x80c6d64b)
janv. 26 08:56:15 localhost.localdomain avahi-daemon[804]: Registering new address record for fe80::4e96:a5eb:c0c0:12d1 on wlp2s0.*.
janv. 26 08:56:15 localhost.localdomain avahi-daemon[804]: New relevant interface wlp2s0.IPv6 for mDNS.
janv. 26 08:56:15 localhost.localdomain avahi-daemon[804]: Joining mDNS multicast group on interface wlp2s0.IPv6 with address fe80::4e96:a5eb:c0c0:12d1.
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.0807] dhcp4 (wlp2s0): dhclient started with pid 4402
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.0783] dhcp4 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.0780] device (wlp2s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:15 localhost.localdomain audit: NETFILTER_CFG table=filter family=10 entries=82
janv. 26 08:56:15 localhost.localdomain audit: NETFILTER_CFG table=nat family=10 entries=50
janv. 26 08:56:15 localhost.localdomain audit: NETFILTER_CFG table=mangle family=10 entries=39
janv. 26 08:56:15 localhost.localdomain audit: NETFILTER_CFG table=raw family=10 entries=30
janv. 26 08:56:15 localhost.localdomain audit: NETFILTER_CFG table=filter family=2 entries=81
janv. 26 08:56:15 localhost.localdomain audit: NETFILTER_CFG table=nat family=2 entries=50
janv. 26 08:56:15 localhost.localdomain audit: NETFILTER_CFG table=mangle family=2 entries=39
janv. 26 08:56:15 localhost.localdomain audit: NETFILTER_CFG table=raw family=2 entries=27
janv. 26 08:56:15 localhost.localdomain wpa_supplicant[1092]: wlp2s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-58 noise=9999 txrate=1000
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.0653] device (wlp2s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'AKED'.
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.0653] device (wlp2s0): supplicant interface state: 4-way handshake -> completed
janv. 26 08:56:15 localhost.localdomain wpa_supplicant[1092]: wlp2s0: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=-58 noise=9999 txrate=1000
janv. 26 08:56:15 localhost.localdomain kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready
janv. 26 08:56:15 localhost.localdomain wpa_supplicant[1092]: wlp2s0: CTRL-EVENT-CONNECTED - Connection to 24:95:04:c3:87:8c completed [id=0 id_str=]
janv. 26 08:56:15 localhost.localdomain wpa_supplicant[1092]: wlp2s0: WPA: Key negotiation completed with 24:95:04:c3:87:8c [PTK=CCMP GTK=TKIP]
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.0382] device (wlp2s0): supplicant interface state: associated -> 4-way handshake
janv. 26 08:56:15 localhost.localdomain NetworkManager[940]: <info>  [1548489375.0001] device (wlp2s0): supplicant interface state: associating -> associated
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.9947] device (wlp2s0): supplicant interface state: authenticating -> associating
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.9937] device (wlp2s0): supplicant interface state: scanning -> authenticating
janv. 26 08:56:14 localhost.localdomain kernel: wlp2s0: associated
janv. 26 08:56:14 localhost.localdomain wpa_supplicant[1092]: wlp2s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
janv. 26 08:56:14 localhost.localdomain wpa_supplicant[1092]: wlp2s0: Associated with 24:95:04:c3:87:8c
janv. 26 08:56:14 localhost.localdomain kernel: wlp2s0: RX AssocResp from 24:95:04:c3:87:8c (capab=0x411 status=0 aid=4)
janv. 26 08:56:14 localhost.localdomain kernel: wlp2s0: associate with 24:95:04:c3:87:8c (try 1/3)
janv. 26 08:56:14 localhost.localdomain kernel: wlp2s0: authenticated
janv. 26 08:56:14 localhost.localdomain wpa_supplicant[1092]: wlp2s0: Trying to associate with 24:95:04:c3:87:8c (SSID='AKED' freq=2437 MHz)
janv. 26 08:56:14 localhost.localdomain kernel: wlp2s0: send auth to 24:95:04:c3:87:8c (try 1/3)
janv. 26 08:56:14 localhost.localdomain kernel: wlp2s0: authenticate with 24:95:04:c3:87:8c
janv. 26 08:56:14 localhost.localdomain wpa_supplicant[1092]: wlp2s0: SME: Trying to authenticate with 24:95:04:c3:87:8c (SSID='AKED' freq=2437 MHz)
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1742] Config: added 'psk' value '<hidden>'
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1742] Config: added 'auth_alg' value 'OPEN'
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1741] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256'
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1741] Config: added 'bgscan' value 'simple:30:-80:86400'
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1741] Config: added 'scan_ssid' value '1'
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1741] Config: added 'ssid' value 'AKED'
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1741] device (wlp2s0): Activation: (wifi) connection 'AKED' has security, and secrets exist.  No new secrets needed.
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1737] device (wlp2s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1733] device (wlp2s0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1611] device (wlp2s0): supplicant interface state: disconnected -> scanning
janv. 26 08:56:14 localhost.localdomain kdeinit5[1378]: plasma-nm: Unhandled active connection state change:  1
janv. 26 08:56:14 localhost.localdomain wpa_supplicant[1092]: wlp2s0: WPS-PBC-ACTIVE
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1500] device (wlp2s0): supplicant interface state: disabled -> disconnected
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1462] device (wlp2s0): supplicant interface state: disconnected -> disabled
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1271] sup-iface[0x5570cdf8c0d0,wlp2s0]: wps: type pbc start...
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1265] device (wlp2s0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1265] device (wlp2s0): Activation: (wifi) access point 'AKED' has security, but secrets are required.
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1256] device (wlp2s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:14 localhost.localdomain kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1181] device (wlp2s0): set-hw-addr: reset MAC address to 18:1D:EA:33:E7:FD (preserve)
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1130] manager: NetworkManager state is now CONNECTING
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1112] device (wlp2s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1106] audit: op="connection-activate" uuid="adbfb94b-0c7b-4078-9253-715f12988ede" name="AKED" pid=1413 uid=1000 result="success"
janv. 26 08:56:14 localhost.localdomain NetworkManager[940]: <info>  [1548489374.1103] device (wlp2s0): Activation: starting connection 'AKED' (adbfb94b-0c7b-4078-9253-715f12988ede)

Comment 1 Michael 2020-12-26 23:12:43 UTC

I'm having a similar situation but slightly different. This didn't happen in KDE neon 18.04, but is regular since 20.04. 

When I log in to a session, the Plasma Networks applet will try to connect to my default wifi network, but it will keep failing. The spinner will spin around, it will fail to connect, it will retry but fail again. I have to click the Disconnect button to get it out of its loop. Then I have to go to the command line and issue a direct command to nmcli, like:

$ nmcli device wifi con  "my-network" password "the-password"

...then it will connect right away and then all is good. I may have to file a separate bug for this but thought I'd see if this relates.

Comment 2 Edouard Duliège 2020-12-27 11:23:58 UTC

I don't have this anymore
I currently run KDE 5.20.4 ; Wayland ; Qt 5.12.2, kernel 5.9.15-200 Fedora 33 x86_64

Comment 3 Michael 2020-12-30 00:16:36 UTC

I have just recently fixed my slowness/inability to connect to wifi. From System Settings -> Network Connections, I clicked on the saved connection and removed it from the saved connections list. Then I reconnected anew, saving the password, and now my connection is immediately done every time I log into my session.

Comment 4 Jan Grulich 2021-01-05 11:26:58 UTC

Looks that our secret agent had some issues with initialization or there was some miscommunication with NetworkManager. I will close it now. Feel free to reopen if it's still happening after you re-create your faulty connection.