NetworkManager disconnect

Soph · 2023-04-18 04:02:36

I am using NetworkManager with a Quectel EC25-AF wireless network card. Sometimes, it will turn off wlan0, which cannot be turned back on and will not unless restarted or put into sleep mode.
Running systemctl status NetworkManager produces this output

● NetworkManager.service - Network Manager
     Loaded: loaded (/usr/lib/systemd/system/NetworkManager.service; enabled; preset: disabled)
     Active: active (running) since Mon 2023-04-17 20:29:18 PDT; 26min ago
       Docs: man:NetworkManager(8)
   Main PID: 288 (NetworkManager)
      Tasks: 4 (limit: 4474)
     Memory: 3.7M
        CPU: 5.628s
     CGroup: /system.slice/NetworkManager.service
             └─288 /usr/bin/NetworkManager --no-daemon

Apr 17 20:54:37 RALEI NetworkManager[288]: <info>  [1681790077.2182] device (wlan0): set-hw-addr: set MAC address to 9E:04:B0:29:37:D8 (scanning)
Apr 17 20:54:37 RALEI NetworkManager[288]: <warn>  [1681790077.8432] device (wlan0): Activation: failed for connection 'WIFI-HOME'
Apr 17 20:54:37 RALEI NetworkManager[288]: <info>  [1681790077.8568] device (wlan0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Apr 17 20:54:37 RALEI NetworkManager[288]: <info>  [1681790077.9106] dhcp4 (wlan0): canceled DHCP transaction
Apr 17 20:54:37 RALEI NetworkManager[288]: <info>  [1681790077.9116] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Apr 17 20:54:37 RALEI NetworkManager[288]: <info>  [1681790077.9118] dhcp4 (wlan0): state changed no lease
Apr 17 20:54:38 RALEI NetworkManager[288]: <info>  [1681790078.6436] device (wlan0): supplicant interface state: scanning -> disconnected
Apr 17 20:54:38 RALEI NetworkManager[288]: <info>  [1681790078.6441] device (wlan0): supplicant interface state: disconnected -> scanning
Apr 17 20:54:40 RALEI NetworkManager[288]: <info>  [1681790080.4429] device (wlan0): supplicant interface state: scanning -> disconnected
Apr 17 20:54:41 RALEI NetworkManager[288]: <info>  [1681790081.6708] device (wlan0): supplicant interface state: disconnected -> inactive

Last edited by Soph (2023-04-18 14:27:56)

seth · 2023-04-18 07:13:49

Please use [code][/code] tags, not "quote" tags. Edit your post in this regard.

Please post the output of

find /etc/systemd -type l -exec test -f {} \; -print | awk -F'/' '{ printf ("%-40s | %s\n", $(NF-0), $(NF-1)) }' | sort -f

Please post your complete system journal for the boot:

sudo journalctl -b | curl -F 'file=@-' 0x0.st

Soph · 2023-04-18 14:47:32

Please post the output of

find /etc/systemd -type l -exec test -f {} \; -print | awk -F'/' '{ printf ("%-40s | %s\n", $(NF-0), $(NF-1)) }' | sort -f

bluetooth.service                        | bluetooth.target.wants
dbus-org.bluez.service                   | system
dbus-org.freedesktop.nm-dispatcher.service | system
display-manager.service                  | system
gcr-ssh-agent.socket                     | sockets.target.wants
getty@tty1.service                       | getty.target.wants
NetworkManager-wait-online.service       | network-online.target.wants
NetworkManager.service                   | multi-user.target.wants
p11-kit-server.socket                    | sockets.target.wants
pipewire-session-manager.service         | user
pipewire.socket                          | sockets.target.wants
pulseaudio.socket                        | sockets.target.wants
remote-fs.target                         | multi-user.target.wants
wireplumber.service                      | pipewire.service.wants
xdg-user-dirs-update.service             | default.target.wants

Please post your complete system journal for the boot:
sudo journalctl -b | curl -F 'file=@-' 0x0.st

http://0x0.st/H8y8.txt

Last edited by Soph (2023-04-18 14:47:53)

seth · 2023-04-18 15:46:27

There're no colliding services, but the journal covers only ~1m, so I guess it's not supposed to cover a connection loss?

This chip?
https://aur.archlinux.org/packages/rtl8723du-dkms-git

Make sure to disable https://wiki.archlinux.org/title/Power_ … utosuspend ("usbcore.autosuspend=-1", but stuff like TLP will override that at runtime) and also try to disable https://wiki.archlinux.org/title/Networ … domization

Soph · 2023-04-18 20:13:47

This chip?
https://aur.archlinux.org/packages/rtl8723du-dkms-git

I believe it is that chip.

Make sure to disable https://wiki.archlinux.org/title/Power_ … utosuspend ("usbcore.autosuspend=-1", but stuff like TLP will override that at runtime) and also try to disable https://wiki.archlinux.org/title/Networ … domization

lsusb creates this output:

Bus 002 Device 001: ID 1d6b:0003 Linux Foundation 3.0 root hub
Bus 001 Device 004: ID 058f:3822 Alcor Micro Corp. USB 2.0 Camera 
Bus 001 Device 003: ID 0bda:d723 Realtek Semiconductor Corp. 802.11n WLAN Adapter
Bus 001 Device 002: ID 0bda:0129 Realtek Semiconductor Corp. RTS5129 Card Reader Controller
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub

Therefore, I created a file /etc/udev/rules.d/50-usb_power_save.rules and put this text in it:

ACTION=="add", SUBSYSTEM=="usb", ATTR{idVendor}=="0bda", ATTR{idProduct}=="d723", GOTO="power_usb_rules_end"
ACTION=="add", SUBSYSTEM=="usb", ATTR{idVendor}=="0bda", ATTR{idProduct}=="0129", GOTO="power_usb_rules_end"

ACTION=="add", SUBSYSTEM=="usb", TEST=="power/control", ATTR{power/control}="auto"
LABEL="power_usb_rules_end"

For the second part, I created a file /etc/NetworkManager/conf.d/wifi_rand_mac.conf and put this text in it:

[device]
wifi.scan-rand-mac-address=no

Despite all this, wlan0 still goes down with this status of NetworkManager:

● NetworkManager.service - Network Manager
     Loaded: loaded (/usr/lib/systemd/system/NetworkManager.service; enabled; preset: disabled)
     Active: active (running) since Tue 2023-04-18 11:22:23 PDT; 1h 42min ago
       Docs: man:NetworkManager(8)
   Main PID: 290 (NetworkManager)
      Tasks: 4 (limit: 4474)
     Memory: 4.3M
        CPU: 54.178s
     CGroup: /system.slice/NetworkManager.service
             └─290 /usr/bin/NetworkManager --no-daemon

Apr 18 13:04:22 RALEI NetworkManager[290]: <warn>  [1681848262.7315] device (wlan0): link timed out.
Apr 18 13:04:22 RALEI NetworkManager[290]: <info>  [1681848262.7593] device (wlan0): state change: activated -> failed (reason 'ssid-not-found', sys-iface-state: 'managed')
Apr 18 13:04:22 RALEI NetworkManager[290]: <info>  [1681848262.7618] manager: NetworkManager state is now CONNECTED_LOCAL
Apr 18 13:04:22 RALEI NetworkManager[290]: <info>  [1681848262.7659] manager: NetworkManager state is now DISCONNECTED
Apr 18 13:04:22 RALEI NetworkManager[290]: <warn>  [1681848262.7715] device (wlan0): Activation: failed for connection '0436-WIFI'
Apr 18 13:04:22 RALEI NetworkManager[290]: <info>  [1681848262.8078] device (wlan0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Apr 18 13:04:22 RALEI NetworkManager[290]: <info>  [1681848262.8342] dhcp4 (wlan0): canceled DHCP transaction
Apr 18 13:04:22 RALEI NetworkManager[290]: <info>  [1681848262.8359] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Apr 18 13:04:22 RALEI NetworkManager[290]: <info>  [1681848262.8368] dhcp4 (wlan0): state changed no lease
Apr 18 13:04:27 RALEI NetworkManager[290]: <info>  [1681848267.5369] device (wlan0): supplicant interface state: scanning -> inactive

seth · 2023-04-18 20:50:51

seth wrote:

the journal covers only ~1m, so I guess it's not supposed to cover a connection loss?

A journal actually covering the connection loss would be helpful.

I believe it is that chip.

Did you not install the package? Afaict there's no in-tree driver?

Soph · 2023-04-20 14:54:03

This problem is quite random, it did not happen for a day.

A journal actually covering the connection loss would be helpful.

Here is a journal of the computer losing connection several times, with the laptop being shut and opened back up afterwards:

https://0x0.st/H8GU.txt

Did you not install the package? Afaict there's no in-tree driver?

I do have this package installed, apologies for the confusion.

seth · 2023-04-20 15:26:24

It's not technically "losing" it since it never gets connected.
Your best stint is

Apr 20 07:31:58 RALEI wpa_supplicant[363]: wlan0: Trying to associate with 06:00:c1:16:5f:75 (SSID='0436-WIFI' freq=2412 MHz)
Apr 20 07:31:58 RALEI kernel: wlan0: authenticated
Apr 20 07:31:58 RALEI kernel: wlan0: associate with 06:00:c1:16:5f:75 (try 1/3)
Apr 20 07:31:58 RALEI NetworkManager[277]: <info>  [1682001118.1138] device (wlan0): supplicant interface state: authenticating -> associating
Apr 20 07:31:58 RALEI kernel: wlan0: RX AssocResp from 06:00:c1:16:5f:75 (capab=0x411 status=0 aid=10)
Apr 20 07:31:58 RALEI wpa_supplicant[363]: wlan0: Associated with 06:00:c1:16:5f:75
…
Apr 20 07:31:58 RALEI kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Apr 20 07:31:58 RALEI wpa_supplicant[363]: wlan0: WPA: Key negotiation completed with 06:00:c1:16:5f:75 [PTK=CCMP GTK=CCMP]
Apr 20 07:31:58 RALEI wpa_supplicant[363]: wlan0: CTRL-EVENT-CONNECTED - Connection to 06:00:c1:16:5f:75 completed [id=0 id_str=]
…
Apr 20 07:31:58 RALEI NetworkManager[277]: <info>  [1682001118.3386] manager: NetworkManager state is now CONNECTED_SITE
…
Apr 20 07:31:58 RALEI wpa_supplicant[363]: wlan0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-51 noise=9999 txrate=19500
…
Apr 20 07:32:07 RALEI wpa_supplicant[363]: wlan0: CTRL-EVENT-BEACON-LOSS
Apr 20 07:32:08 RALEI kernel: rtw_8723du 1-7:1.2: failed to get tx report from firmware
…
Apr 20 07:32:08 RALEI kernel: rtw_8723du 1-7:1.2: timed out to flush queue 3
…
Apr 20 07:32:08 RALEI wpa_supplicant[363]: wlan0: CTRL-EVENT-DISCONNECTED bssid=06:00:c1:16:5f:75 reason=4 locally_generated=1

And the journal segment already starts w/

Apr 20 07:30:47 RALEI kernel: rtw_8723du 1-7:1.2: timed out to flush queue 3

after which you immediately

Apr 20 07:31:51 RALEI systemd-sleep[5254]: Entering sleep state 'suspend'...

ie. the build-up to the failure isn't covered in this journal segment.
=> Please post the entire journal of the boot, from beginning on. Not random excerpts.

Soph · 2023-04-20 20:19:21

Please post the entire journal of the boot, from beginning on. Not random excerpts.

I didn't know how to upload the entire journal from only one boot, so here's my entire journal (0x0 couldn't store the entire file):
https://drive.google.com/file/d/1oCPe_R … sp=sharing

seth · 2023-04-20 20:25:06

sudo journalctl -b | curl -F 'file=@-' 0x0.st

Arch Linux

#1 2023-04-18 04:02:36

NetworkManager disconnect

#2 2023-04-18 07:13:49

Re: NetworkManager disconnect

#3 2023-04-18 14:47:32

Re: NetworkManager disconnect

#4 2023-04-18 15:46:27

Re: NetworkManager disconnect

#5 2023-04-18 20:13:47

Re: NetworkManager disconnect

#6 2023-04-18 20:50:51

Re: NetworkManager disconnect

#7 2023-04-20 14:54:03

Re: NetworkManager disconnect

#8 2023-04-20 15:26:24

Re: NetworkManager disconnect

#9 2023-04-20 20:19:21

Re: NetworkManager disconnect

#10 2023-04-20 20:25:06

Re: NetworkManager disconnect

Board footer