Unable to connect to mobile hotspot, R8188EU: indicate disassoc

Sourav · 2021-01-15 03:40:49

I was a happy Arch Linux user, but with the release of 5.10 kernels, I am not able to connect to my phone or tablet's hotspot but can connect to my wifi.

I am using TP-Link WN725N wifi adapter. Whenever I try to connect to my phone's hotspot, on my phone, I can see one device (my laptop) is connected, but on my laptop network-manager-applet on the xfce4 panel just keeps spinning and then it disconnects, then the process continues for very long time.

Whenever I see it fails to connect, and I run dmesg, the output from dmesg is:

[    6.015657] R8188EU: indicate disassoc
[    7.649837] R8188EU: assoc success
[    7.686170] IPv6: ADDRCONF(NETDEV_CHANGE): wlp0s20f0u2: link becomes ready
[   11.126415] kauditd_printk_skb: 21 callbacks suppressed
[   11.126417] audit: type=1334 audit(1610681364.046:54): prog-id=20 op=LOAD
[   11.127691] audit: type=1334 audit(1610681364.046:55): prog-id=21 op=LOAD
[   11.320201] audit: type=1130 audit(1610681364.242:56): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=udisks2 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   11.659859] audit: type=1130 audit(1610681364.579:57): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=upower comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   11.935090] audit: type=1130 audit(1610681364.856:58): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=accounts-daemon comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   16.014327] audit: type=1131 audit(1610681368.936:59): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   16.643480] logitech-hidpp-device 0003:046D:4054.0006: HID++ 4.5 device connected.
[   25.937608] audit: type=1111 audit(1610681378.859:60): pid=458 uid=0 auid=4294967295 ses=4294967295 msg='op=connection-activate uuid=34a8aaab-0ed7-4670-882e-478d61d29172 name="OpenSSL::SSL::SSLError" pid=684 uid=1000 result=success exe="/usr/bin/NetworkManager" hostname=? addr=? terminal=? res=success'
[   25.959784] audit: type=1130 audit(1610681378.879:61): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   25.961888] R8188EU: indicate disassoc
[   25.989406] R8188EU: indicate disassoc
[   27.413362] R8188EU: assoc success
[   27.459351] IPv6: ADDRCONF(NETDEV_CHANGE): wlp0s20f0u2: link becomes ready
[   35.551349] audit: type=1131 audit(1610681388.472:62): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-hostnamed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   35.703531] audit: type=1334 audit(1610681388.626:63): prog-id=18 op=UNLOAD
[   35.703546] audit: type=1334 audit(1610681388.626:64): prog-id=17 op=UNLOAD
[   36.013431] audit: type=1131 audit(1610681388.936:65): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   73.009633] R8188EU: indicate disassoc
[   73.162111] audit: type=1130 audit(1610681426.082:66): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[   76.373105] R8188EU: assoc success

The hotspot of my phone has ESSID of "OpenSSL::SSL::SSLError".

If I disable the wifi security on the hotspot, my laptop w/ 5.10+ can connect to it. If I then enable the security I can connect to it.

The NetworkManager version is 1.28.1dev+7+g3f5df3cdc6-1, but I have also downgraded network manager to 1.28.0-1 and 1.28.0-2, but that didn't help either.

I am using the 5.9.9 (Xanmod) kernel because I have that compiled. Getting anything from 5.10.1 to 5.10.7 (stock ones from arch repo) doesn't fix this issue at all.

Because it's 5.10.7 now and it doesn't seem to be working which makes me worry if it's going to get fixed at all in the later kernels. Does anyone having similar issue know how to fix this problem without downgrading the kernel?

Edit After Trying wifi-menu:
The wifi-menu also has similar effect. It kind of connects to my phone, and when I run dhcpcd manually, I get:

$ sudo dhcpcd
dhcpcd-9.4.0 starting
dev: loaded udev
DUID 00:04:4c:4c:45:44:00:53:4e:10:80:48:c8:c0:4f:58:33:32
wlp0s20f0u2: IAID 45:30:38:75
wlp0s20f0u2: soliciting an IPv6 router
wlp0s20f0u2: soliciting a DHCP lease
wlp0s20f0u2: no IPv6 Routers available
wlp0s20f0u2: carrier lost
forked to background, child pid 2139
timed out

Last edited by Sourav (2021-01-15 03:59:57)

jasonwryan · 2021-01-15 03:42:57

You really need to post journal/dmesg information to debug this.

Sourav · 2021-01-15 03:44:05

jasonwryan wrote:

You really need to post journal/dmesg information to debug this.

Sorry I forgot that part, and I immediately edited my post and added the info... Anyway, I have journaling disabled because I am using an SSD and journaling writes a lot... So I can only go with dmesg...

jasonwryan · 2021-01-15 04:19:44

Sourav wrote:

[Anyway, I have journaling disabled because I am using an SSD and journaling writes a lot..

That is a terrible idea. One it is what an SSD is designed to do, and two it completely hamstrings you when you want to determine what is going on with your system.

Also, use code, not quote, tags: your dmesg is truncated so it is not possible to read the actual messages.

Sourav · 2021-01-15 04:57:06

Ok so I have enabled journald, and then ran

sudo journalctl -u NetworkManager

Heres' the output:

sudo journalctl -u NetworkManager
-- Journal begins at Fri 2021-01-15 10:07:24 IST, ends at Fri 2021-01-15 10:24:40 IST. --
Jan 15 10:07:26 archlinux systemd[1]: Starting Network Manager...
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.6443] NetworkManager (version 1.28.1dev+7+g3f5df3cdc6-1) is starting... (for the fi>
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.6444] Read config: /etc/NetworkManager/NetworkManager.conf (lib: 20-connectivity.co>
Jan 15 10:07:26 archlinux NetworkManager[438]: <warn>  [1610685446.6444] config: unknown key 'main.systemd-resolved' in section [main] of file '/etc/N>
Jan 15 10:07:26 archlinux systemd[1]: Started Network Manager.
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.6494] bus-manager: acquired D-Bus service "org.freedesktop.NetworkManager"
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.6565] wifi-wext: (wlp0s20f0u2): driver supports SSID scans (scan_capa 0x3F).
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.6565] wifi-wext: (wlp0s20f0u2): using WEXT for Wi-Fi device control
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.6595] manager[0x556f15f22040]: monitoring kernel firmware directory '/lib/firmware'.
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.7669] hostname: hostname: using hostnamed
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.7669] hostname: hostname changed from (none) to "archlinux"
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.7679] dns-mgr[0x556f15efc220]: init: dns=none rc-manager=unmanaged
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.7685] manager[0x556f15f22040]: rfkill: Wi-Fi hardware radio set enabled
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.7686] manager[0x556f15f22040]: rfkill: WWAN hardware radio set enabled
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.7799] Loaded device plugin: NMAtmManager (/usr/lib/NetworkManager/1.28.1dev+7+g3f5d>
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.7921] Loaded device plugin: NMBluezManager (/usr/lib/NetworkManager/1.28.1dev+7+g3f>
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.7956] Loaded device plugin: NMOvsFactory (/usr/lib/NetworkManager/1.28.1dev+7+g3f5d>
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.8316] Loaded device plugin: NMTeamFactory (/usr/lib/NetworkManager/1.28.1dev+7+g3f5>
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.8353] Loaded device plugin: NMWifiFactory (/usr/lib/NetworkManager/1.28.1dev+7+g3f5>
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.8376] Loaded device plugin: NMWwanFactory (/usr/lib/NetworkManager/1.28.1dev+7+g3f5>
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.8399] manager: rfkill: Wi-Fi enabled by radio killswitch; enabled by state file
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.8407] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.8419] manager: Networking is enabled by state file
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.8438] dhcp-init: Using DHCP client 'internal'
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.8463] settings: Loaded settings plugin: keyfile (internal)
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.8885] device (lo): carrier: link connected
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.8896] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1)
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.8910] manager: (wlp0s20f0u2): new 802.11 Wi-Fi device (/org/freedesktop/NetworkMana>
Jan 15 10:07:26 archlinux NetworkManager[438]: <info>  [1610685446.8925] device (wlp0s20f0u2): state change: unmanaged -> unavailable (reason 'managed>
Jan 15 10:07:27 archlinux NetworkManager[438]: <warn>  [1610685447.3747] device (wlp0s20f0u2): set-hw-addr: new MAC address DA:7A:B1:DA:D8:D3 not succ>
Jan 15 10:07:27 archlinux NetworkManager[438]: <info>  [1610685447.3842] ovsdb: Could not connect: No such file or directory
Jan 15 10:07:27 archlinux NetworkManager[438]: <info>  [1610685447.4899] device (wlp0s20f0u2): supplicant interface state: internal-starting -> discon>
Jan 15 10:07:27 archlinux NetworkManager[438]: <info>  [1610685447.4900] device (wlp0s20f0u2): state change: unavailable -> disconnected (reason 'supp>
Jan 15 10:07:27 archlinux NetworkManager[438]: <info>  [1610685447.9935] manager: startup complete
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.7965] device (wlp0s20f0u2): supplicant interface state: disconnected -> inactive
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8018] policy: auto-activating connection 'OpenSSL::SSL::SSLError' (34a8aaab-0ed7-46>
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8034] device (wlp0s20f0u2): Activation: starting connection 'OpenSSL::SSL::SSLError>
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8038] device (wlp0s20f0u2): state change: disconnected -> prepare (reason 'none', s>
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8050] manager: NetworkManager state is now CONNECTING
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8059] device (wlp0s20f0u2): state change: prepare -> config (reason 'none', sys-ifa>
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8068] device (wlp0s20f0u2): Activation: (wifi) access point 'OpenSSL::SSL::SSLError>
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8068] device (wlp0s20f0u2): state change: config -> need-auth (reason 'none', sys-i>
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8141] device (wlp0s20f0u2): state change: need-auth -> prepare (reason 'none', sys->
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8152] device (wlp0s20f0u2): state change: prepare -> config (reason 'none', sys-ifa>
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8162] device (wlp0s20f0u2): Activation: (wifi) connection 'OpenSSL::SSL::SSLError' >
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8163] Config: added 'ssid' value 'OpenSSL::SSL::SSLError'
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8163] Config: added 'scan_ssid' value '1'
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8163] Config: added 'bgscan' value 'simple:30:-70:86400'
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8163] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256'
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8164] Config: added 'auth_alg' value 'OPEN'
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8164] Config: added 'psk' value '<hidden>'
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.8321] device (wlp0s20f0u2): supplicant interface state: inactive -> associating
Jan 15 10:07:28 archlinux NetworkManager[438]: <info>  [1610685448.9261] device (wlp0s20f0u2): supplicant interface state: associating -> associated
Jan 15 10:07:29 archlinux NetworkManager[438]: <info>  [1610685449.9376] device (wlp0s20f0u2): supplicant interface state: associated -> 4way_handshake
Jan 15 10:07:29 archlinux NetworkManager[438]: <info>  [1610685449.9435] device (wlp0s20f0u2): supplicant interface state: 4way_handshake -> completed
Jan 15 10:07:29 archlinux NetworkManager[438]: <info>  [1610685449.9436] device (wlp0s20f0u2): Activation: (wifi) Stage 2 of 5 (Device Configure) succ>
Jan 15 10:07:29 archlinux NetworkManager[438]: <info>  [1610685449.9443] device (wlp0s20f0u2): state change: config -> ip-config (reason 'none', sys-i>
Jan 15 10:07:29 archlinux NetworkManager[438]: <info>  [1610685449.9448] dhcp4 (wlp0s20f0u2): activation: beginning transaction (timeout in 45 seconds)
Jan 15 10:07:32 archlinux NetworkManager[438]: <info>  [1610685452.7883] agent-manager: agent[69057e6a05b0d327,:1.18/org.freedesktop.nm-applet/1000]: >
Jan 15 10:08:14 archlinux NetworkManager[438]: <warn>  [1610685494.9421] dhcp4 (wlp0s20f0u2): request timed out
Jan 15 10:08:14 archlinux NetworkManager[438]: <info>  [1610685494.9422] dhcp4 (wlp0s20f0u2): state changed unknown -> timeout
Jan 15 10:08:14 archlinux NetworkManager[438]: <info>  [1610685494.9424] device (wlp0s20f0u2): state change: ip-config -> failed (reason 'ip-config-un>
Jan 15 10:08:14 archlinux NetworkManager[438]: <info>  [1610685494.9456] manager: NetworkManager state is now DISCONNECTED
Jan 15 10:08:15 archlinux NetworkManager[438]: <warn>  [1610685495.0525] device (wlp0s20f0u2): set-hw-addr: new MAC address B6:CD:62:50:AF:35 not succ>
Jan 15 10:08:15 archlinux NetworkManager[438]: <warn>  [1610685495.0550] device (wlp0s20f0u2): Activation: failed for connection 'OpenSSL::SSL::SSLErr>
Jan 15 10:08:15 archlinux NetworkManager[438]: <info>  [1610685495.0576] device (wlp0s20f0u2): supplicant interface state: completed -> interface_disa>
Jan 15 10:08:15 archlinux NetworkManager[438]: <info>  [1610685495.0613] device (wlp0s20f0u2): state change: failed -> disconnected (reason 'none', sy>
Jan 15 10:08:15 archlinux NetworkManager[438]: <info>  [1610685495.0755] dhcp4 (wlp0s20f0u2): canceled DHCP transaction
Jan 15 10:08:15 archlinux NetworkManager[438]: <info>  [1610685495.0755] dhcp4 (wlp0s20f0u2): state changed timeout -> done
Jan 15 10:08:15 archlinux NetworkManager[438]: <info>  [1610685495.0802] device (wlp0s20f0u2): supplicant interface state: interface_disabled -> scann>
Jan 15 10:08:16 archlinux NetworkManager[438]: <info>  [1610685496.4483] device (wlp0s20f0u2): supplicant interface state: scanning -> disconnected
Jan 15 10:08:17 archlinux NetworkManager[438]: <info>  [1610685497.8120] device (wlp0s20f0u2): supplicant interface state: disconnected -> inactive
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1728] policy: auto-activating connection 'OpenSSL::SSL::SSLError' (34a8aaab-0ed7-46>
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1769] device (wlp0s20f0u2): Activation: starting connection 'OpenSSL::SSL::SSLError>
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1776] device (wlp0s20f0u2): state change: disconnected -> prepare (reason 'none', s>
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1803] manager: NetworkManager state is now CONNECTING
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1823] device (wlp0s20f0u2): state change: prepare -> config (reason 'none', sys-ifa>
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1835] device (wlp0s20f0u2): Activation: (wifi) access point 'OpenSSL::SSL::SSLError>
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1836] device (wlp0s20f0u2): state change: config -> need-auth (reason 'none', sys-i>
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1970] device (wlp0s20f0u2): state change: need-auth -> prepare (reason 'none', sys->
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1978] device (wlp0s20f0u2): state change: prepare -> config (reason 'none', sys-ifa>
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1985] device (wlp0s20f0u2): Activation: (wifi) connection 'OpenSSL::SSL::SSLError' >
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1989] Config: added 'ssid' value 'OpenSSL::SSL::SSLError'
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1989] Config: added 'scan_ssid' value '1'
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1990] Config: added 'bgscan' value 'simple:30:-70:86400'
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1990] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256'
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1990] Config: added 'auth_alg' value 'OPEN'
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.1990] Config: added 'psk' value '<hidden>'
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.2159] device (wlp0s20f0u2): supplicant interface state: inactive -> associating
Jan 15 10:08:19 archlinux NetworkManager[438]: <info>  [1610685499.3100] device (wlp0s20f0u2): supplicant interface state: associating -> associated
Jan 15 10:08:20 archlinux NetworkManager[438]: <info>  [1610685500.3255] device (wlp0s20f0u2): supplicant interface state: associated -> 4way_handshake
Jan 15 10:08:20 archlinux NetworkManager[438]: <info>  [1610685500.3329] device (wlp0s20f0u2): supplicant interface state: 4way_handshake -> completed
Jan 15 10:08:20 archlinux NetworkManager[438]: <info>  [1610685500.3330] device (wlp0s20f0u2): Activation: (wifi) Stage 2 of 5 (Device Configure) succ>
Jan 15 10:08:20 archlinux NetworkManager[438]: <info>  [1610685500.3332] device (wlp0s20f0u2): state change: config -> ip-config (reason 'none', sys-i>
Jan 15 10:08:20 archlinux NetworkManager[438]: <info>  [1610685500.3336] dhcp4 (wlp0s20f0u2): activation: beginning transaction (timeout in 45 seconds)
Jan 15 10:09:05 archlinux NetworkManager[438]: <warn>  [1610685545.9421] dhcp4 (wlp0s20f0u2): request timed out
Jan 15 10:09:05 archlinux NetworkManager[438]: <info>  [1610685545.9422] dhcp4 (wlp0s20f0u2): state changed unknown -> timeout
Jan 15 10:09:05 archlinux NetworkManager[438]: <info>  [1610685545.9424] device (wlp0s20f0u2): state change: ip-config -> failed (reason 'ip-config-un>
Jan 15 10:09:05 archlinux NetworkManager[438]: <info>  [1610685545.9448] manager: NetworkManager state is now DISCONNECTED
Jan 15 10:09:06 archlinux NetworkManager[438]: <warn>  [1610685546.0536] device (wlp0s20f0u2): set-hw-addr: new MAC address 86:B1:D5:7F:56:B7 not succ>
Jan 15 10:09:06 archlinux NetworkManager[438]: <warn>  [1610685546.0578] device (wlp0s20f0u2): Activation: failed for connection 'OpenSSL::SSL::SSLErr>
Jan 15 10:09:06 archlinux NetworkManager[438]: <info>  [1610685546.0600] device (wlp0s20f0u2): supplicant interface state: completed -> interface_disa>
Jan 15 10:09:06 archlinux NetworkManager[438]: <info>  [1610685546.0641] device (wlp0s20f0u2): state change: failed -> disconnected (reason 'none', sy>
Jan 15 10:09:06 archlinux NetworkManager[438]: <info>  [1610685546.0832] dhcp4 (wlp0s20f0u2): canceled DHCP transaction
Jan 15 10:09:06 archlinux NetworkManager[438]: <info>  [1610685546.0832] dhcp4 (wlp0s20f0u2): state changed timeout -> done
Jan 15 10:09:06 archlinux NetworkManager[438]: <info>  [1610685546.0902] device (wlp0s20f0u2): supplicant interface state: interface_disabled -> scann>
Jan 15 10:09:07 archlinux NetworkManager[438]: <info>  [1610685547.4475] device (wlp0s20f0u2): supplicant interface state: scanning -> disconnected
Jan 15 10:09:08 archlinux NetworkManager[438]: <info>  [1610685548.8074] device (wlp0s20f0u2): supplicant interface state: disconnected -> inactive
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1709] policy: auto-activating connection 'OpenSSL::SSL::SSLError' (34a8aaab-0ed7-46>
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1720] device (wlp0s20f0u2): Activation: starting connection 'OpenSSL::SSL::SSLError>
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1723] device (wlp0s20f0u2): state change: disconnected -> prepare (reason 'none', s>
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1729] manager: NetworkManager state is now CONNECTING
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1734] device (wlp0s20f0u2): state change: prepare -> config (reason 'none', sys-ifa>
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1739] device (wlp0s20f0u2): Activation: (wifi) access point 'OpenSSL::SSL::SSLError>
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1739] device (wlp0s20f0u2): state change: config -> need-auth (reason 'none', sys-i>
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1788] device (wlp0s20f0u2): state change: need-auth -> prepare (reason 'none', sys->
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1799] device (wlp0s20f0u2): state change: prepare -> config (reason 'none', sys-ifa>
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1811] device (wlp0s20f0u2): Activation: (wifi) connection 'OpenSSL::SSL::SSLError' >
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1812] Config: added 'ssid' value 'OpenSSL::SSL::SSLError'
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1812] Config: added 'scan_ssid' value '1'
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1813] Config: added 'bgscan' value 'simple:30:-70:86400'
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1813] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256'
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1813] Config: added 'auth_alg' value 'OPEN'
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.1813] Config: added 'psk' value '<hidden>'
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.2029] device (wlp0s20f0u2): supplicant interface state: inactive -> associating
Jan 15 10:09:10 archlinux NetworkManager[438]: <info>  [1610685550.2982] device (wlp0s20f0u2): supplicant interface state: associating -> associated
Jan 15 10:09:11 archlinux NetworkManager[438]: <info>  [1610685551.3067] device (wlp0s20f0u2): supplicant interface state: associated -> 4way_handshake
Jan 15 10:09:11 archlinux NetworkManager[438]: <info>  [1610685551.3149] device (wlp0s20f0u2): supplicant interface state: 4way_handshake -> completed
Jan 15 10:09:11 archlinux NetworkManager[438]: <info>  [1610685551.3150] device (wlp0s20f0u2): Activation: (wifi) Stage 2 of 5 (Device Configure) succ>
Jan 15 10:09:11 archlinux NetworkManager[438]: <info>  [1610685551.3159] device (wlp0s20f0u2): state change: config -> ip-config (reason 'none', sys-i>
Jan 15 10:09:11 archlinux NetworkManager[438]: <info>  [1610685551.3170] dhcp4 (wlp0s20f0u2): activation: beginning transaction (timeout in 45 seconds)
Jan 15 10:09:56 archlinux NetworkManager[438]: <warn>  [1610685596.9454] dhcp4 (wlp0s20f0u2): request timed out
Jan 15 10:09:56 archlinux NetworkManager[438]: <info>  [1610685596.9455] dhcp4 (wlp0s20f0u2): state changed unknown -> timeout
Jan 15 10:09:56 archlinux NetworkManager[438]: <info>  [1610685596.9457] device (wlp0s20f0u2): state change: ip-config -> failed (reason 'ip-config-un>
Jan 15 10:09:56 archlinux NetworkManager[438]: <info>  [1610685596.9488] manager: NetworkManager state is now DISCONNECTED
Jan 15 10:09:57 archlinux NetworkManager[438]: <warn>  [1610685597.0562] device (wlp0s20f0u2): set-hw-addr: new MAC address AA:5D:00:E4:87:25 not succ>
Jan 15 10:09:57 archlinux NetworkManager[438]: <warn>  [1610685597.0602] device (wlp0s20f0u2): Activation: failed for connection 'OpenSSL::SSL::SSLErr>
Jan 15 10:09:57 archlinux NetworkManager[438]: <info>  [1610685597.0648] device (wlp0s20f0u2): supplicant interface state: completed -> interface_disa>
Jan 15 10:09:57 archlinux NetworkManager[438]: <info>  [1610685597.0713] device (wlp0s20f0u2): state change: failed -> disconnected (reason 'none', sy>
Jan 15 10:09:57 archlinux NetworkManager[438]: <info>  [1610685597.0888] dhcp4 (wlp0s20f0u2): canceled DHCP transaction
Jan 15 10:09:57 archlinux NetworkManager[438]: <info>  [1610685597.0889] dhcp4 (wlp0s20f0u2): state changed timeout -> done
Jan 15 10:09:57 archlinux NetworkManager[438]: <info>  [1610685597.0939] device (wlp0s20f0u2): supplicant interface state: interface_disabled -> scann>
Jan 15 10:09:58 archlinux NetworkManager[438]: <info>  [1610685598.4454] device (wlp0s20f0u2): supplicant interface state: scanning -> disconnected
Jan 15 10:09:59 archlinux NetworkManager[438]: <info>  [1610685599.8151] device (wlp0s20f0u2): supplicant interface state: disconnected -> inactive
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.1897] policy: auto-activating connection 'OpenSSL::SSL::SSLError' (34a8aaab-0ed7-46>
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.1922] device (wlp0s20f0u2): Activation: starting connection 'OpenSSL::SSL::SSLError>
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.1929] device (wlp0s20f0u2): state change: disconnected -> prepare (reason 'none', s>
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.1957] manager: NetworkManager state is now CONNECTING
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.1978] device (wlp0s20f0u2): state change: prepare -> config (reason 'none', sys-ifa>
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.1996] device (wlp0s20f0u2): Activation: (wifi) access point 'OpenSSL::SSL::SSLError>
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.1997] device (wlp0s20f0u2): state change: config -> need-auth (reason 'none', sys-i>
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.2123] device (wlp0s20f0u2): state change: need-auth -> prepare (reason 'none', sys->
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.2131] device (wlp0s20f0u2): state change: prepare -> config (reason 'none', sys-ifa>
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.2137] device (wlp0s20f0u2): Activation: (wifi) connection 'OpenSSL::SSL::SSLError' >
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.2138] Config: added 'ssid' value 'OpenSSL::SSL::SSLError'
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.2138] Config: added 'scan_ssid' value '1'
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.2138] Config: added 'bgscan' value 'simple:30:-70:86400'
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.2138] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256'
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.2139] Config: added 'auth_alg' value 'OPEN'
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.2139] Config: added 'psk' value '<hidden>'
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.2276] device (wlp0s20f0u2): supplicant interface state: inactive -> associating
Jan 15 10:10:01 archlinux NetworkManager[438]: <info>  [1610685601.2933] device (wlp0s20f0u2): supplicant interface state: associating -> associated
Jan 15 10:10:02 archlinux NetworkManager[438]: <info>  [1610685602.3015] device (wlp0s20f0u2): supplicant interface state: associated -> 4way_handshake
Jan 15 10:10:02 archlinux NetworkManager[438]: <info>  [1610685602.3104] device (wlp0s20f0u2): supplicant interface state: 4way_handshake -> completed
Jan 15 10:10:02 archlinux NetworkManager[438]: <info>  [1610685602.3106] device (wlp0s20f0u2): Activation: (wifi) Stage 2 of 5 (Device Configure) succ>
Jan 15 10:10:02 archlinux NetworkManager[438]: <info>  [1610685602.3146] device (wlp0s20f0u2): state change: config -> ip-config (reason 'none', sys-i>
Jan 15 10:10:02 archlinux NetworkManager[438]: <info>  [1610685602.3164] dhcp4 (wlp0s20f0u2): activation: beginning transaction (timeout in 45 seconds)
Jan 15 10:10:47 archlinux NetworkManager[438]: <warn>  [1610685647.9420] dhcp4 (wlp0s20f0u2): request timed out
Jan 15 10:10:47 archlinux NetworkManager[438]: <info>  [1610685647.9421] dhcp4 (wlp0s20f0u2): state changed unknown -> timeout
Jan 15 10:10:47 archlinux NetworkManager[438]: <info>  [1610685647.9424] device (wlp0s20f0u2): state change: ip-config -> failed (reason 'ip-config-un>
Jan 15 10:10:47 archlinux NetworkManager[438]: <info>  [1610685647.9467] manager: NetworkManager state is now DISCONNECTED
Jan 15 10:10:48 archlinux NetworkManager[438]: <warn>  [1610685648.0542] device (wlp0s20f0u2): set-hw-addr: new MAC address 92:DD:02:2F:F4:13 not succ>
Jan 15 10:10:48 archlinux NetworkManager[438]: <warn>  [1610685648.0580] device (wlp0s20f0u2): Activation: failed for connection 'OpenSSL::SSL::SSLErr>
Jan 15 10:10:48 archlinux NetworkManager[438]: <info>  [1610685648.0614] device (wlp0s20f0u2): supplicant interface state: completed -> interface_disa>
Jan 15 10:10:48 archlinux NetworkManager[438]: <info>  [1610685648.0668] device (wlp0s20f0u2): state change: failed -> disconnected (reason 'none', sy>
Jan 15 10:10:48 archlinux NetworkManager[438]: <info>  [1610685648.0923] dhcp4 (wlp0s20f0u2): canceled DHCP transaction
Jan 15 10:10:48 archlinux NetworkManager[438]: <info>  [1610685648.0924] dhcp4 (wlp0s20f0u2): state changed timeout -> done
Jan 15 10:10:48 archlinux NetworkManager[438]: <info>  [1610685648.1022] device (wlp0s20f0u2): supplicant interface state: interface_disabled -> scann>
Jan 15 10:10:49 archlinux NetworkManager[438]: <info>  [1610685649.4628] device (wlp0s20f0u2): supplicant interface state: scanning -> disconnected
Jan 15 10:10:50 archlinux NetworkManager[438]: <info>  [1610685650.8220] device (wlp0s20f0u2): supplicant interface state: disconnected -> inactive
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.1915] policy: auto-activating connection 'ArchLinux' (b3eb9f6d-53f9-4b3b-b07c-1b93b>
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.1974] device (wlp0s20f0u2): Activation: starting connection 'ArchLinux' (b3eb9f6d-5>
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.1980] device (wlp0s20f0u2): state change: disconnected -> prepare (reason 'none', s>
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.2006] manager: NetworkManager state is now CONNECTING
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.2040] device (wlp0s20f0u2): state change: prepare -> config (reason 'none', sys-ifa>
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.2056] device (wlp0s20f0u2): Activation: (wifi) access point 'ArchLinux' has securit>
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.2057] device (wlp0s20f0u2): state change: config -> need-auth (reason 'none', sys-i>
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.2172] device (wlp0s20f0u2): state change: need-auth -> prepare (reason 'none', sys->
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.2180] device (wlp0s20f0u2): state change: prepare -> config (reason 'none', sys-ifa>
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.2185] device (wlp0s20f0u2): Activation: (wifi) connection 'ArchLinux' has security,>
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.2185] Config: added 'ssid' value 'ArchLinux'
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.2186] Config: added 'scan_ssid' value '1'
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.2186] Config: added 'bgscan' value 'simple:30:-70:86400'
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.2186] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256'
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.2187] Config: added 'psk' value '<hidden>'
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.2325] device (wlp0s20f0u2): supplicant interface state: inactive -> associating
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.3388] device (wlp0s20f0u2): supplicant interface state: associating -> 4way_handsha>
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.3745] device (wlp0s20f0u2): supplicant interface state: 4way_handshake -> completed
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.3747] device (wlp0s20f0u2): Activation: (wifi) Stage 2 of 5 (Device Configure) succ>
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.3756] device (wlp0s20f0u2): state change: config -> ip-config (reason 'none', sys-i>
Jan 15 10:10:52 archlinux NetworkManager[438]: <info>  [1610685652.3769] dhcp4 (wlp0s20f0u2): activation: beginning transaction (timeout in 45 seconds)
Jan 15 10:11:37 archlinux NetworkManager[438]: <warn>  [1610685697.9421] dhcp4 (wlp0s20f0u2): request timed out
Jan 15 10:11:37 archlinux NetworkManager[438]: <info>  [1610685697.9422] dhcp4 (wlp0s20f0u2): state changed unknown -> timeout
Jan 15 10:11:37 archlinux NetworkManager[438]: <info>  [1610685697.9424] device (wlp0s20f0u2): state change: ip-config -> failed (reason 'ip-config-un>
Jan 15 10:11:37 archlinux NetworkManager[438]: <info>  [1610685697.9444] manager: NetworkManager state is now DISCONNECTED
Jan 15 10:11:38 archlinux NetworkManager[438]: <warn>  [1610685698.0498] device (wlp0s20f0u2): set-hw-addr: new MAC address 16:61:3F:C6:C7:2B not succ>
Jan 15 10:11:38 archlinux NetworkManager[438]: <warn>  [1610685698.0546] device (wlp0s20f0u2): Activation: failed for connection 'ArchLinux'
Jan 15 10:11:38 archlinux NetworkManager[438]: <info>  [1610685698.0595] device (wlp0s20f0u2): supplicant interface state: completed -> interface_disa>
Jan 15 10:11:38 archlinux NetworkManager[438]: <info>  [1610685698.0600] device (wlp0s20f0u2): supplicant interface state: interface_disabled -> scann>
Jan 15 10:11:38 archlinux NetworkManager[438]: <info>  [1610685698.0619] device (wlp0s20f0u2): state change: failed -> disconnected (reason 'none', sy>
Jan 15 10:11:38 archlinux NetworkManager[438]: <info>  [1610685698.0888] dhcp4 (wlp0s20f0u2): canceled DHCP transaction
Jan 15 10:11:38 archlinux NetworkManager[438]: <info>  [1610685698.0889] dhcp4 (wlp0s20f0u2): state changed timeout -> done
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4034] device (wlp0s20f0u2): supplicant interface state: scanning -> disconnected
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4053] policy: auto-activating connection 'ArchLinux' (b3eb9f6d-53f9-4b3b-b07c-1b93b>
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4059] device (wlp0s20f0u2): Activation: starting connection 'ArchLinux' (b3eb9f6d-5>
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4060] device (wlp0s20f0u2): state change: disconnected -> prepare (reason 'none', s>
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4065] manager: NetworkManager state is now CONNECTING
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4070] device (wlp0s20f0u2): state change: prepare -> config (reason 'none', sys-ifa>
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4075] device (wlp0s20f0u2): Activation: (wifi) access point 'ArchLinux' has securit>
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4076] device (wlp0s20f0u2): state change: config -> need-auth (reason 'none', sys-i>
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4122] device (wlp0s20f0u2): state change: need-auth -> prepare (reason 'none', sys->
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4133] device (wlp0s20f0u2): state change: prepare -> config (reason 'none', sys-ifa>
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4136] device (wlp0s20f0u2): Activation: (wifi) connection 'ArchLinux' has security,>
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4137] Config: added 'ssid' value 'ArchLinux'
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4137] Config: added 'scan_ssid' value '1'
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4137] Config: added 'bgscan' value 'simple:30:-70:86400'
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4137] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256'
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4137] Config: added 'psk' value '<hidden>'
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4267] device (wlp0s20f0u2): supplicant interface state: disconnected -> associating
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4408] device (wlp0s20f0u2): supplicant interface state: associating -> associated
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4761] device (wlp0s20f0u2): supplicant interface state: associated -> 4way_handshake
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4798] device (wlp0s20f0u2): supplicant interface state: 4way_handshake -> completed
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4800] device (wlp0s20f0u2): Activation: (wifi) Stage 2 of 5 (Device Configure) succ>
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4835] device (wlp0s20f0u2): state change: config -> ip-config (reason 'none', sys-i>
Jan 15 10:11:39 archlinux NetworkManager[438]: <info>  [1610685699.4851] dhcp4 (wlp0s20f0u2): activation: beginning transaction (timeout in 45 seconds)
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1754] dhcp4 (wlp0s20f0u2): option dhcp_lease_time      => '7200'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1755] dhcp4 (wlp0s20f0u2): option domain_name_servers  => '192.168.0.1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1756] dhcp4 (wlp0s20f0u2): option expiry               => '1610692917'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1756] dhcp4 (wlp0s20f0u2): option ip_address           => '192.168.0.103'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1756] dhcp4 (wlp0s20f0u2): option requested_broadcast_address => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1757] dhcp4 (wlp0s20f0u2): option requested_domain_name => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1757] dhcp4 (wlp0s20f0u2): option requested_domain_name_servers => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1757] dhcp4 (wlp0s20f0u2): option requested_domain_search => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1758] dhcp4 (wlp0s20f0u2): option requested_host_name  => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1758] dhcp4 (wlp0s20f0u2): option requested_interface_mtu => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1758] dhcp4 (wlp0s20f0u2): option requested_ms_classless_static_routes => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1759] dhcp4 (wlp0s20f0u2): option requested_nis_domain => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1759] dhcp4 (wlp0s20f0u2): option requested_nis_servers => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1760] dhcp4 (wlp0s20f0u2): option requested_ntp_servers => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1760] dhcp4 (wlp0s20f0u2): option requested_rfc3442_classless_static_routes => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1761] dhcp4 (wlp0s20f0u2): option requested_root_path  => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1761] dhcp4 (wlp0s20f0u2): option requested_routers    => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1762] dhcp4 (wlp0s20f0u2): option requested_static_routes => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1762] dhcp4 (wlp0s20f0u2): option requested_subnet_mask => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1762] dhcp4 (wlp0s20f0u2): option requested_time_offset => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1763] dhcp4 (wlp0s20f0u2): option requested_wpad       => '1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1763] dhcp4 (wlp0s20f0u2): option routers              => '192.168.0.1'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1763] dhcp4 (wlp0s20f0u2): option subnet_mask          => '255.255.255.0'
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1763] dhcp4 (wlp0s20f0u2): state changed unknown -> bound
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1809] device (wlp0s20f0u2): state change: ip-config -> ip-check (reason 'none', sys>
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1917] device (wlp0s20f0u2): state change: ip-check -> secondaries (reason 'none', s>
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1921] device (wlp0s20f0u2): state change: secondaries -> activated (reason 'none', >
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1927] manager: NetworkManager state is now CONNECTED_LOCAL
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1948] manager: NetworkManager state is now CONNECTED_SITE
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1950] policy: set 'ArchLinux' (wlp0s20f0u2) as default for IPv4 routing and DNS
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.1956] device (wlp0s20f0u2): Activation: successful, device activated.
Jan 15 10:11:57 archlinux NetworkManager[438]: <info>  [1610685717.6726] manager: NetworkManager state is now CONNECTED_GLOBAL
Jan 15 10:15:55 archlinux NetworkManager[438]: <info>  [1610685955.8419] dhcp6 (wlp0s20f0u2): activation: beginning transaction (timeout in 45 seconds)
Jan 15 10:15:55 archlinux NetworkManager[438]: <info>  [1610685955.8445] policy: set 'ArchLinux' (wlp0s20f0u2) as default for IPv6 routing and DNS
Jan 15 10:16:40 archlinux NetworkManager[438]: <warn>  [1610686000.9406] dhcp6 (wlp0s20f0u2): request timed out
Jan 15 10:16:40 archlinux NetworkManager[438]: <info>  [1610686000.9408] dhcp6 (wlp0s20f0u2): state changed unknown -> timeout
Jan 15 10:16:40 archlinux NetworkManager[438]: <info>  [1610686000.9410] dhcp6 (wlp0s20f0u2): canceled DHCP transaction
Jan 15 10:16:40 archlinux NetworkManager[438]: <info>  [1610686000.9410] dhcp6 (wlp0s20f0u2): state changed timeout -> done

I have pasted the full output, and it's now connected to my wifi router which has ESSID of ArchLinux, on my phone hostpot with ESSID "OpenSSL::SSL:ERROR", I get the error, same problem with my tablet as well. After a few retries it connects to my wifi router successfully. That's the problem in the first place, can't connect to hotspot. As I have said I have tried 5.4 to 5.9.9 after purchasing this adapter, none had the problem...

Last edited by Sourav (2021-01-15 04:59:16)

p37 · 2021-01-16 16:22:18

Hello,
(Excuse my English)
i think i have the same problem,
since kernel 5.10 no internet via R8188EU

no problem without WPA/WPA2, or with LTS Kernel

with https://aur.archlinux.org/packages/8188eu-dkms-git/ no problem
unfortunately my CPU is at 100% with every kernel update

[root@H2OdiArch2 ~]# modprobe --show-depends r8188eu
insmod /lib/modules/5.10.7-arch1-1/kernel/net/rfkill/rfkill.ko.xz 
insmod /lib/modules/5.10.7-arch1-1/kernel/net/wireless/cfg80211.ko.xz 
insmod /lib/modules/5.10.7-arch1-1/kernel/net/wireless/lib80211.ko.xz 
insmod /lib/modules/5.10.7-arch1-1/kernel/drivers/staging/rtl8188eu/r8188eu.ko.xz 
[root@H2OdiArch2 ~]#

[root@H2OdiArch2 ~]# pacman -Qo /usr/lib/firmware/rtlwifi/rtl8188eufw.bin
/usr/lib/firmware/rtlwifi/rtl8188eufw.bin ist in linux-firmware 20201218.646f159-1 enthalten
[root@H2OdiArch2 ~]#

[root@H2OdiArch2 ~]# lsusb -v | grep RTL8188EUS
can't get debug descriptor: Resource temporarily unavailable
Bus 001 Device 003: ID 0bda:8179 Realtek Semiconductor Corp. RTL8188EUS 802.11n Wireless Network Adapter
  idProduct          0x8179 RTL8188EUS 802.11n Wireless Network Adapter

[root@H2OdiArch2 ~]# journalctl -f --no-pager | grep NetworkManager
Jan 16 16:13:06 H2OdiArch2 kernel: audit: type=1130 audit(1610809986.053:79): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 16 16:13:06 H2OdiArch2 NetworkManager[325]: <info>  [1610809986.0947] device (wlp0s21f0u3): supplicant interface state: completed -> disconnected
Jan 16 16:13:06 H2OdiArch2 NetworkManager[325]: <info>  [1610809986.0949] device (wlp0s21f0u3): state change: deactivating -> disconnected (reason 'user-requested', sys-iface-state: 'managed')
Jan 16 16:13:06 H2OdiArch2 NetworkManager[325]: <info>  [1610809986.1338] dhcp4 (wlp0s21f0u3): canceled DHCP transaction
Jan 16 16:13:06 H2OdiArch2 NetworkManager[325]: <info>  [1610809986.1339] dhcp4 (wlp0s21f0u3): state changed unknown -> done
Jan 16 16:13:06 H2OdiArch2 NetworkManager[325]: <info>  [1610809986.1365] manager: NetworkManager state is now DISCONNECTED
Jan 16 16:13:16 H2OdiArch2 systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Jan 16 16:13:16 H2OdiArch2 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 16 16:13:16 H2OdiArch2 kernel: audit: type=1131 audit(1610809996.740:80): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 16 16:13:22 H2OdiArch2 kernel: audit: type=1111 audit(1610810002.370:81): pid=325 uid=0 auid=4294967295 ses=4294967295 msg='op=connection-activate uuid=354ddbab-5725-4d20-962a-126ed9d6e4f6 name=447265616D5765622032 pid=493 uid=1000 result=success exe="/usr/bin/NetworkManager" hostname=? addr=? terminal=? res=success'
Jan 16 16:13:22 H2OdiArch2 audit[325]: USYS_CONFIG pid=325 uid=0 auid=4294967295 ses=4294967295 msg='op=connection-activate uuid=354ddbab-5725-4d20-962a-126ed9d6e4f6 name=447265616D5765622032 pid=493 uid=1000 result=success exe="/usr/bin/NetworkManager" hostname=? addr=? terminal=? res=success'
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3765] device (wlp0s21f0u3): Activation: starting connection 'DreamWeb 2' (354ddbab-5725-4d20-962a-126ed9d6e4f6)
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3767] audit: op="connection-activate" uuid="354ddbab-5725-4d20-962a-126ed9d6e4f6" name="DreamWeb 2" pid=493 uid=1000 result="success"
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3768] device (wlp0s21f0u3): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3775] manager: NetworkManager state is now CONNECTING
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3779] device (wlp0s21f0u3): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3783] device (wlp0s21f0u3): Activation: (wifi) access point 'DreamWeb 2' has security, but secrets are required.
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3783] device (wlp0s21f0u3): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3817] device (wlp0s21f0u3): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3822] device (wlp0s21f0u3): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3825] device (wlp0s21f0u3): Activation: (wifi) connection 'DreamWeb 2' has security, and secrets exist.  No new secrets needed.
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3826] Config: added 'ssid' value 'DreamWeb'
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3826] Config: added 'scan_ssid' value '1'
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3826] Config: added 'bgscan' value 'simple:30:-70:86400'
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3826] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256'
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3827] Config: added 'auth_alg' value 'OPEN'
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.3827] Config: added 'psk' value '<hidden>'
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.4746] device (wlp0s21f0u3): supplicant interface state: disconnected -> associating
Jan 16 16:13:22 H2OdiArch2 NetworkManager[325]: <info>  [1610810002.5437] device (wlp0s21f0u3): supplicant interface state: associating -> associated
Jan 16 16:13:23 H2OdiArch2 NetworkManager[325]: <info>  [1610810003.8315] device (wlp0s21f0u3): supplicant interface state: associated -> 4way_handshake
Jan 16 16:13:23 H2OdiArch2 NetworkManager[325]: <info>  [1610810003.8407] device (wlp0s21f0u3): supplicant interface state: 4way_handshake -> completed
Jan 16 16:13:23 H2OdiArch2 NetworkManager[325]: <info>  [1610810003.8408] device (wlp0s21f0u3): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "DreamWeb"
Jan 16 16:13:23 H2OdiArch2 NetworkManager[325]: <info>  [1610810003.8410] device (wlp0s21f0u3): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Jan 16 16:13:23 H2OdiArch2 NetworkManager[325]: <info>  [1610810003.8415] dhcp4 (wlp0s21f0u3): activation: beginning transaction (timeout in 45 seconds)
Jan 16 16:13:36 H2OdiArch2 dbus-daemon[324]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.4' (uid=0 pid=325 comm="/usr/bin/NetworkManager --no-daemon ")
Jan 16 16:13:36 H2OdiArch2 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 16 16:13:36 H2OdiArch2 kernel: audit: type=1130 audit(1610810016.153:82): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 16 16:13:46 H2OdiArch2 systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Jan 16 16:13:46 H2OdiArch2 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 16 16:13:46 H2OdiArch2 kernel: audit: type=1131 audit(1610810026.736:83): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 16 16:14:08 H2OdiArch2 NetworkManager[325]: <warn>  [1610810048.7311] dhcp4 (wlp0s21f0u3): request timed out
Jan 16 16:14:08 H2OdiArch2 NetworkManager[325]: <info>  [1610810048.7312] dhcp4 (wlp0s21f0u3): state changed unknown -> timeout
Jan 16 16:14:08 H2OdiArch2 NetworkManager[325]: <info>  [1610810048.7313] device (wlp0s21f0u3): state change: ip-config -> failed (reason 'ip-config-unavailable', sys-iface-state: 'managed')
Jan 16 16:14:08 H2OdiArch2 NetworkManager[325]: <info>  [1610810048.7319] manager: NetworkManager state is now DISCONNECTED
Jan 16 16:14:08 H2OdiArch2 NetworkManager[325]: <warn>  [1610810048.7338] device (wlp0s21f0u3): Activation: failed for connection 'DreamWeb 2'
Jan 16 16:14:08 H2OdiArch2 NetworkManager[325]: <info>  [1610810048.7347] device (wlp0s21f0u3): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Jan 16 16:14:08 H2OdiArch2 NetworkManager[325]: <info>  [1610810048.7501] dhcp4 (wlp0s21f0u3): canceled DHCP transaction
Jan 16 16:14:08 H2OdiArch2 NetworkManager[325]: <info>  [1610810048.7502] dhcp4 (wlp0s21f0u3): state changed timeout -> done
Jan 16 16:14:08 H2OdiArch2 NetworkManager[325]: <info>  [1610810048.7548] device (wlp0s21f0u3): supplicant interface state: completed -> disconnected
Jan 16 16:14:08 H2OdiArch2 dbus-daemon[324]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.4' (uid=0 pid=325 comm="/usr/bin/NetworkManager --no-daemon ")
Jan 16 16:14:08 H2OdiArch2 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 16 16:14:08 H2OdiArch2 kernel: audit: type=1130 audit(1610810048.763:84): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7572] policy: auto-activating connection 'DreamWeb 2' (354ddbab-5725-4d20-962a-126ed9d6e4f6)
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7590] device (wlp0s21f0u3): Activation: starting connection 'DreamWeb 2' (354ddbab-5725-4d20-962a-126ed9d6e4f6)
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7594] device (wlp0s21f0u3): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7612] manager: NetworkManager state is now CONNECTING
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7628] device (wlp0s21f0u3): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7638] device (wlp0s21f0u3): Activation: (wifi) access point 'DreamWeb 2' has security, but secrets are required.
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7639] device (wlp0s21f0u3): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7669] device (wlp0s21f0u3): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7675] device (wlp0s21f0u3): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7680] device (wlp0s21f0u3): Activation: (wifi) connection 'DreamWeb 2' has security, and secrets exist.  No new secrets needed.
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7680] Config: added 'ssid' value 'DreamWeb'
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7680] Config: added 'scan_ssid' value '1'
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7681] Config: added 'bgscan' value 'simple:30:-70:86400'
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7681] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256'
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7681] Config: added 'auth_alg' value 'OPEN'
Jan 16 16:14:09 H2OdiArch2 NetworkManager[325]: <info>  [1610810049.7681] Config: added 'psk' value '<hidden>'
Jan 16 16:14:10 H2OdiArch2 NetworkManager[325]: <info>  [1610810050.7820] device (wlp0s21f0u3): supplicant interface state: disconnected -> scanning
Jan 16 16:14:12 H2OdiArch2 NetworkManager[325]: <info>  [1610810052.2319] device (wlp0s21f0u3): supplicant interface state: scanning -> associating
Jan 16 16:14:12 H2OdiArch2 NetworkManager[325]: <info>  [1610810052.3099] device (wlp0s21f0u3): supplicant interface state: associating -> associated
Jan 16 16:14:13 H2OdiArch2 NetworkManager[325]: <info>  [1610810053.6000] device (wlp0s21f0u3): supplicant interface state: associated -> 4way_handshake
Jan 16 16:14:13 H2OdiArch2 NetworkManager[325]: <info>  [1610810053.6056] device (wlp0s21f0u3): supplicant interface state: 4way_handshake -> completed
Jan 16 16:14:13 H2OdiArch2 NetworkManager[325]: <info>  [1610810053.6056] device (wlp0s21f0u3): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "DreamWeb"
Jan 16 16:14:13 H2OdiArch2 NetworkManager[325]: <info>  [1610810053.6058] device (wlp0s21f0u3): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Jan 16 16:14:13 H2OdiArch2 NetworkManager[325]: <info>  [1610810053.6064] dhcp4 (wlp0s21f0u3): activation: beginning transaction (timeout in 45 seconds)
Jan 16 16:14:18 H2OdiArch2 systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Jan 16 16:14:18 H2OdiArch2 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 16 16:14:18 H2OdiArch2 kernel: audit: type=1131 audit(1610810058.733:85): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'

Greetings
p37

Sourav · 2021-01-18 15:26:27

Just an update, if you follow the older problems, the solution is to add

wifi.scan-rand-mac-address=no

under the [device] section, in NetworkManager.conf. But this problem doesn't go away with this. Only downgrading the kernel is viable solution!

progandy · 2021-01-18 16:39:49

It seems like you should bisect the kernel:

seth @ https://bbs.archlinux.org/viewtopic.php?pid=1948932#p1948932 wrote:

https://wiki.archlinux.org/index.php/Bi … s_with_Git
https://wiki.gentoo.org/wiki/Kernel_git-bisect
Edit:
https://bbs.archlinux.org/viewtopic.php … 2#p1912502
https://bbs.archlinux.org/viewtopic.php … 2#p1912642
Use the proper kernel versions.

You could also try to do the bisection only with the wifi module:

git clone https://github.com/torvalds/linux
--- update, reboot to kernel v5.10 ---

cd /path/to/your/cloned/linux

git checkout v5.9
cd drivers/staging/rtl8188eu
make -C /lib/modules/`uname -r`/build M=$PWD ccflags-y+=-I'$(PWD)/include'
sudo rmmod r8188eu
sudo insmod r8188eu
# verify that the old driver works with the new kernel

# start the bisection
cd /path/to/your/cloned/linux
git bisect start v5.10 v5.9 -- drivers/staging/rtl8188eu

# repeat the following
make -C /lib/modules/`uname -r`/build M="$PWD/drivers/staging/rtl8188eu" ccflags-y+=-I'$(PWD)/drivers/staging/rtl8188eu/include'
sudo rmmod r8188eu
sudo insmod drivers/staging/rtl8188eu/r8188eu.ko
# test wifi
# then choose either:
git bisect good 
# or
git bisect bad
# repeat make ...

p37 · 2021-01-20 12:58:32

I'm afraid I have to give up here,
my head is already smoking from reading, but thank you for this exciting topic.

Kind regards
p37

Sourav · 2021-01-29 08:54:07

The problem isn't yet fixed. I am afraid of doing the git bisect, my PC has i3 4th gen processor and compiling kernel takes 1 hour 20 minutes (yes, I measured with the time command). I have to just keep using the older 5.9.9 I have so far.

progandy · 2021-01-29 09:05:18

That is why I suggested you try to bisect and compile only the realtek module and not the whole kernel. That may not work if the bug is somewhere else in the kernel, but it should be fast. You will know if you can get a result when you compile the 5.9 module for the 5.10 kernel once at the beginning and it restores your internet connection.

Last edited by progandy (2021-01-29 09:06:10)

Anon11 · 2021-07-11 07:55:18

progandy wrote:

It seems like you should bisect the kernel:

Hello,
I also use TP-Link WN725N and it does not connect to android hotspot. Very rarely after multiple attempts it might connect but usually does not.

It seems that below commit has caused the issue :
"f53ae8193fdb8cdd671dcf122dec0e9cca5ed2ba is the first bad commit"

Here is what i did. Just writing it down as i do not know much and i deviated a bit (using google) from your instructions.

1) Full linux download is a bit too much as i have limited quota. So i did incremental fetch until 75 which seemed to have covered beyond 5.9

git clone --depth=1 https://github.com/torvalds/linux	
git fetch --depth=75

2) module built on v5.9 was good

3) Bisect gave me problem

git bisect start v5.10 v5.9 -- drivers/staging/rtl8188eu

Output

Updating files: 100% (14756/14756), done.
Previous HEAD position was be1dd6692a Merge tag 'perf-tools-fixes-for-v5.10-2020-11-17' of git://git.kernel.org/pub/scm/linux/kernel/git/acme/linux
HEAD is now at bbf5c97901 Linux 5.9
Bisecting: -1 revisions left to test after this (roughly 0 steps)
[be1dd6692adbdb1d70da47da124ac8376bba5ad5] Merge tag 'perf-tools-fixes-for-v5.10-2020-11-17' of git://git.kernel.org/pub/scm/linux/kernel/git/acme/linux

4) I tested on latest commit and problem was there. So i started bisect in this way

git bisect start -- drivers/staging/rtl8188eu
git bisect bad
git bisect good v5.9

5) This worked and here is the full bisect log

git bisect start '--' 'drivers/staging/rtl8188eu'
# bad: [de5540965853e514a85d3b775e9049deb85a2ff3] Merge tag 'rtc-5.14' of git://git.kernel.org/pub/scm/linux/kernel/git/abelloni/linux
git bisect bad de5540965853e514a85d3b775e9049deb85a2ff3
# good: [bbf5c979011a099af5dc76498918ed7df445635b] Linux 5.9
git bisect good bbf5c979011a099af5dc76498918ed7df445635b
# bad: [5e28bb5ad6520966b8e8fcb05bee36af978bb2cd] staging: rtl8188eu: remove all RT_TRACE calls from core/rtw_ieee80211.c
git bisect bad 5e28bb5ad6520966b8e8fcb05bee36af978bb2cd
# bad: [9eb8004c87c0fe7395ba00811d1cf7e023085713] staging: rtl8188eu: remove all RT_TRACE calls from core/rtw_sta_mgt.c
git bisect bad 9eb8004c87c0fe7395ba00811d1cf7e023085713
# bad: [0299b6df594b4a325d1c09057e82458192d9b0ca] staging: rtl8188eu: remove all RT_TRACE calls from os_dep/mlme_linux.c
git bisect bad 0299b6df594b4a325d1c09057e82458192d9b0ca
# bad: [f53ae8193fdb8cdd671dcf122dec0e9cca5ed2ba] staging: rtl8188eu: remove all RT_TRACE calls from os_dep/os_intfs.c
git bisect bad f53ae8193fdb8cdd671dcf122dec0e9cca5ed2ba
# first bad commit: [f53ae8193fdb8cdd671dcf122dec0e9cca5ed2ba] staging: rtl8188eu: remove all RT_TRACE calls from os_dep/os_intfs.c

Thank you for instructions. Long long time ago i did bisect on kernel on a slow laptop and that was very tedious. This was much simpler.
What next ? I guess we need to report bug somewhere.

Last edited by Anon11 (2021-07-11 08:05:12)

progandy · 2021-07-11 08:53:01

That can't be the problem, that commit is not in a released kernel yet. Without a full kernel download (at least everything between v5.9 and v5.10) bisecting is not really possible. (I think you currently have 75 commits before master and 75 before v5.9. You are missing everything in between).

Last edited by progandy (2021-07-11 08:57:41)

Anon11 · 2021-07-12 14:22:42

I did full kernel download, below is the result of bisect

eca1d82ea858044a27fa95c498cc0835a0b2e17a is the first bad commit
commit eca1d82ea858044a27fa95c498cc0835a0b2e17a
Author: Michael Straube <straube.linux@gmail.com>
Date:   Sun Sep 27 10:35:35 2020 +0200

    staging: rtl8188eu: Reapply "staging:r8188eu: Use lib80211 to encrypt (CCMP) tx frames"
    
    Commit 515ce733e86e ("staging:r8188eu: Use lib80211 to encrypt (CCMP) tx frames")
    was reverted because it caused scheduling while atomic bugs and hard
    freezes. Experimentation showed that there were no freezes and no BUG
    messages logged when lib80211_get_crypto_ops() was called directly
    rather than indirectly through try_then_request_module().
    
    Reapply "staging:r8188eu: Use lib80211 to encrypt (CCMP) tx frames"
    with resolved revert conflicts and replace try_then_request_module()
    with direct call to lib80211_get_crypto_ops().
    
    Original commit message:
    Put data to skb, decrypt with lib80211_crypt_ccmp, and place back to tx buffer.
    
    Cc: Ivan Safonov <insafonov@gmail.com>
    Signed-off-by: Michael Straube <straube.linux@gmail.com>
    Link: https://lore.kernel.org/r/20200927083535.2895-1-straube.linux@gmail.com
    Signed-off-by: Greg Kroah-Hartman <gregkh@linuxfoundation.org>

 drivers/staging/rtl8188eu/core/rtw_security.c | 778 +++-----------------------
 1 file changed, 72 insertions(+), 706 deletions(-)

% git bisect log                                                                                                                  

# bad: [2c85ebc57b3e1817b6ce1a6b703928e113a90442] Linux 5.10
# good: [bbf5c979011a099af5dc76498918ed7df445635b] Linux 5.9
git bisect start 'v5.10' 'v5.9' '--' 'drivers/staging/rtl8188eu'
# good: [9079371e947b350e4a8ffd6a6d00847c7c50908e] staging: rtl8188eu: clean up alignment style issues
git bisect good 9079371e947b350e4a8ffd6a6d00847c7c50908e
# bad: [a89b10ad9195139e9afd2e0d00b736bd073c04e4] staging: rtl8188eu: use ETH_ALEN
git bisect bad a89b10ad9195139e9afd2e0d00b736bd073c04e4
# bad: [a1070fee729a7e660f684e24481496070a1c3d08] staging: rtl8188eu: remove unused macros and definitions
git bisect bad a1070fee729a7e660f684e24481496070a1c3d08
# good: [69fea2b4e59c52844cf5196c9c81157792d194fb] staging: r8188eu: replace WIFI_REASON_CODE enum with native ieee80211_reasoncode
git bisect good 69fea2b4e59c52844cf5196c9c81157792d194fb
# bad: [eca1d82ea858044a27fa95c498cc0835a0b2e17a] staging: rtl8188eu: Reapply "staging:r8188eu: Use lib80211 to encrypt (CCMP) tx frames"
git bisect bad eca1d82ea858044a27fa95c498cc0835a0b2e17a
# first bad commit: [eca1d82ea858044a27fa95c498cc0835a0b2e17a] staging: rtl8188eu: Reapply "staging:r8188eu: Use lib80211 to encrypt (CCMP) tx frames"

Please note that the bad drivers sometimes do work (after multiple tries) and once they do, they all generally continue to work even after loading known bad version. Very irritating.
Usually i had to reset android device and reconnect usb device to get it to stop working again.

So bisect bad is accurate but bisect good may be faulty.
Just fyi, i think result should be ok as i tested good multiple times and also made sure 'modprobe r8188eu' fails as installed driver should fail.
Last bad result for instance was working initially and only after resetting everything i started seeing failure ( and then it continued to fail).

Last edited by Anon11 (2021-07-12 14:28:05)

Anon11 · 2021-07-31 05:05:03

progandy wrote:

That can't be the problem, that commit is not in a released kernel yet. Without a full kernel download (at least everything between v5.9 and v5.10) bisecting is not really possible. (I think you currently have 75 commits before master and 75 before v5.9. You are missing everything in between).

Hello, does the new bisect look ok ?
As of now i am loading module from 5.9 which works without issue.

Arch Linux

#1 2021-01-15 03:40:49

Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#2 2021-01-15 03:42:57

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#3 2021-01-15 03:44:05

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#4 2021-01-15 04:19:44

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#5 2021-01-15 04:57:06

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#6 2021-01-16 16:22:18

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#7 2021-01-18 15:26:27

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#8 2021-01-18 16:39:49

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#9 2021-01-20 12:58:32

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#10 2021-01-29 08:54:07

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#11 2021-01-29 09:05:18

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#12 2021-07-11 07:55:18

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#13 2021-07-11 08:53:01

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#14 2021-07-12 14:22:42

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

#15 2021-07-31 05:05:03

Re: Unable to connect to mobile hotspot, R8188EU: indicate disassoc

Board footer