Wifi keeps disconnecting randomly; no useful logs are shown

ArchArael · 2023-04-11 07:37:10

On my Asus Zephyrus G14, all AMD hardware, the wifi keeps crashing at random times.

There are no logs in the system whatsoever. When it happens I cannot ping anything.

Any hints on how to fix/debug this?

Here is the lsusb output, will provide other info if needed.

00:00.0 Host bridge: Advanced Micro Devices, Inc. [AMD] Family 17h-19h PCIe Root Complex (rev 01)
00:00.2 IOMMU: Advanced Micro Devices, Inc. [AMD] Family 17h-19h IOMMU
00:01.0 Host bridge: Advanced Micro Devices, Inc. [AMD] Family 17h-19h PCIe Dummy Host Bridge (rev 01)
00:01.1 PCI bridge: Advanced Micro Devices, Inc. [AMD] Device 14b8
00:02.0 Host bridge: Advanced Micro Devices, Inc. [AMD] Family 17h-19h PCIe Dummy Host Bridge (rev 01)
00:02.1 PCI bridge: Advanced Micro Devices, Inc. [AMD] Family 17h-19h PCIe GPP Bridge
00:02.2 PCI bridge: Advanced Micro Devices, Inc. [AMD] Family 17h-19h PCIe GPP Bridge
00:02.4 PCI bridge: Advanced Micro Devices, Inc. [AMD] Family 17h-19h PCIe GPP Bridge
00:03.0 Host bridge: Advanced Micro Devices, Inc. [AMD] Family 17h-19h PCIe Dummy Host Bridge (rev 01)
00:04.0 Host bridge: Advanced Micro Devices, Inc. [AMD] Family 17h-19h PCIe Dummy Host Bridge (rev 01)
00:08.0 Host bridge: Advanced Micro Devices, Inc. [AMD] Family 17h-19h PCIe Dummy Host Bridge (rev 01)
00:08.1 PCI bridge: Advanced Micro Devices, Inc. [AMD] Family 17h-19h Internal PCIe GPP Bridge (rev 10)
00:08.3 PCI bridge: Advanced Micro Devices, Inc. [AMD] Family 17h-19h Internal PCIe GPP Bridge (rev 10)
00:14.0 SMBus: Advanced Micro Devices, Inc. [AMD] FCH SMBus Controller (rev 71)
00:14.3 ISA bridge: Advanced Micro Devices, Inc. [AMD] FCH LPC Bridge (rev 51)
00:18.0 Host bridge: Advanced Micro Devices, Inc. [AMD] Rembrandt Data Fabric: Device 18h; Function 0
00:18.1 Host bridge: Advanced Micro Devices, Inc. [AMD] Rembrandt Data Fabric: Device 18h; Function 1
00:18.2 Host bridge: Advanced Micro Devices, Inc. [AMD] Rembrandt Data Fabric: Device 18h; Function 2
00:18.3 Host bridge: Advanced Micro Devices, Inc. [AMD] Rembrandt Data Fabric: Device 18h; Function 3
00:18.4 Host bridge: Advanced Micro Devices, Inc. [AMD] Rembrandt Data Fabric: Device 18h; Function 4
00:18.5 Host bridge: Advanced Micro Devices, Inc. [AMD] Rembrandt Data Fabric: Device 18h; Function 5
00:18.6 Host bridge: Advanced Micro Devices, Inc. [AMD] Rembrandt Data Fabric: Device 18h; Function 6
00:18.7 Host bridge: Advanced Micro Devices, Inc. [AMD] Rembrandt Data Fabric: Device 18h; Function 7
01:00.0 PCI bridge: Advanced Micro Devices, Inc. [AMD/ATI] Navi 10 XL Upstream Port of PCI Express Switch (rev c0)
02:00.0 PCI bridge: Advanced Micro Devices, Inc. [AMD/ATI] Navi 10 XL Downstream Port of PCI Express Switch
03:00.0 VGA compatible controller: Advanced Micro Devices, Inc. [AMD/ATI] Navi 23 [Radeon RX 6650 XT / 6700S / 6800S] (rev c0)
03:00.1 Audio device: Advanced Micro Devices, Inc. [AMD/ATI] Navi 21/23 HDMI/DP Audio Controller
04:00.0 SD Host controller: O2 Micro, Inc. SD/MMC Card Reader Controller (rev 01)
05:00.0 Network controller: MEDIATEK Corp. Device 7922
06:00.0 Non-Volatile memory controller: Micron Technology Inc Device 5411 (rev 01)
07:00.0 VGA compatible controller: Advanced Micro Devices, Inc. [AMD/ATI] Rembrandt [Radeon 680M] (rev c8)
07:00.1 Audio device: Advanced Micro Devices, Inc. [AMD/ATI] Rembrandt Radeon High Definition Audio Controller
07:00.2 Encryption controller: Advanced Micro Devices, Inc. [AMD] VanGogh PSP/CCP
07:00.3 USB controller: Advanced Micro Devices, Inc. [AMD] Rembrandt USB4 XHCI controller #3
07:00.4 USB controller: Advanced Micro Devices, Inc. [AMD] Rembrandt USB4 XHCI controller #4
07:00.5 Multimedia controller: Advanced Micro Devices, Inc. [AMD] ACP/ACP3X/ACP6x Audio Coprocessor (rev 60)
07:00.6 Audio device: Advanced Micro Devices, Inc. [AMD] Family 17h/19h HD Audio Controller
08:00.0 USB controller: Advanced Micro Devices, Inc. [AMD] Rembrandt USB4 XHCI controller #8
08:00.3 USB controller: Advanced Micro Devices, Inc. [AMD] Rembrandt USB4 XHCI controller #5
08:00.4 USB controller: Advanced Micro Devices, Inc. [AMD] Rembrandt USB4 XHCI controller #6

twelveeighty · 2023-04-11 14:38:30

dmesg and/or Journal doesn't have anything around the time this happens?

Head_on_a_Stick · 2023-04-11 15:43:52

Try disabling the power management by applying the disable_aspm=Y parameter to the mt7921e driver.

For example:

# /etc/modprobe.d/mt7921.conf
options mt7921e disable_aspm=Y

Reference: https://wiki.archlinux.org/title/Kernel … le_options

ArchArael · 2023-04-12 09:10:26

Thanks. Nothing in dmesg. Usually when I check the messages they are all old and there are no error reports.

Will disable power management and report here if the issues stops.

ArchArael · 2023-04-13 01:44:17

Unfortunately, the issue is not solved. This morning I had to restart NetworkManager again because I could not reach anything. Ping just did not output anything.

Here is the log of the whole morning:

 Apr 13 10:20:24 asustop kernel: Freezing user space processes
Apr 13 10:20:24 asustop kernel: Freezing user space processes completed (elapsed 0.001 seconds)
Apr 13 10:20:24 asustop kernel: OOM killer disabled.
Apr 13 10:20:24 asustop kernel: Freezing remaining freezable tasks
Apr 13 10:20:24 asustop kernel: Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
Apr 13 10:20:24 asustop kernel: printk: Suspending console(s) (use no_console_suspend to debug)
Apr 13 10:20:24 asustop kernel: ACPI: EC: interrupt blocked
Apr 13 10:20:24 asustop kernel: ACPI: EC: interrupt unblocked
Apr 13 10:20:24 asustop kernel: [drm] PCIE GART of 1024M enabled (table at 0x000000F41FC00000).
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: SMU is resuming...
Apr 13 10:20:24 asustop kernel: [drm] PCIE GART of 512M enabled (table at 0x00000081FEB00000).
Apr 13 10:20:24 asustop kernel: [drm] PSP is resuming...
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: SMU is resumed successfully!
Apr 13 10:20:24 asustop kernel: [drm] Watermarks table not configured properly by SMU
Apr 13 10:20:24 asustop kernel: [drm] reserve 0xa00000 from 0x81fd000000 for PSP TMR
Apr 13 10:20:24 asustop kernel: nvme nvme0: 8/0/0 default/read/poll queues
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: RAS: optional ras ta ucode is not available
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: SECUREDISPLAY: securedisplay ta ucode is not available
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: SMU is resuming...
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: smu driver if version = 0x0000000f, smu fw if version = 0x00000013, smu fw program = 0, version = 0x003b2a00 (59.42.0)
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: SMU driver if version not matched
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: use vbios provided pptable
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: SMU is resumed successfully!
Apr 13 10:20:24 asustop kernel: [drm] DMUB hardware initialized: version=0x02020017
Apr 13 10:20:24 asustop kernel: [drm] kiq ring mec 2 pipe 1 q 0
Apr 13 10:20:24 asustop kernel: [drm] VCN decode and encode initialized successfully(under DPG Mode).
Apr 13 10:20:24 asustop kernel: [drm] JPEG decode initialized successfully.
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring gfx_0.0.0 uses VM inv eng 0 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring comp_1.0.0 uses VM inv eng 1 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring comp_1.1.0 uses VM inv eng 4 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring comp_1.2.0 uses VM inv eng 5 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring comp_1.3.0 uses VM inv eng 6 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring comp_1.0.1 uses VM inv eng 7 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring comp_1.1.1 uses VM inv eng 8 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring comp_1.2.1 uses VM inv eng 9 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring comp_1.3.1 uses VM inv eng 10 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring kiq_2.1.0 uses VM inv eng 11 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring sdma0 uses VM inv eng 12 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring sdma1 uses VM inv eng 13 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring vcn_dec_0 uses VM inv eng 0 on hub 1
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring vcn_enc_0.0 uses VM inv eng 1 on hub 1
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring vcn_enc_0.1 uses VM inv eng 4 on hub 1
Apr 13 10:20:24 asustop kernel: amdgpu 0000:03:00.0: amdgpu: ring jpeg_dec uses VM inv eng 5 on hub 1
Apr 13 10:20:24 asustop kernel: [drm] dce110_edp_wait_for_hpd_ready: wait timed out!
Apr 13 10:20:24 asustop kernel: [drm] dce110_edp_wait_for_hpd_ready: wait timed out!
Apr 13 10:20:24 asustop kernel: [drm] VCN decode and encode initialized successfully(under DPG Mode).
Apr 13 10:20:24 asustop kernel: [drm] JPEG decode initialized successfully.
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring gfx_0.0.0 uses VM inv eng 0 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring comp_1.0.0 uses VM inv eng 1 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring comp_1.1.0 uses VM inv eng 4 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring comp_1.2.0 uses VM inv eng 5 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring comp_1.3.0 uses VM inv eng 6 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring comp_1.0.1 uses VM inv eng 7 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring comp_1.1.1 uses VM inv eng 8 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring comp_1.2.1 uses VM inv eng 9 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring comp_1.3.1 uses VM inv eng 10 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring kiq_2.1.0 uses VM inv eng 11 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring sdma0 uses VM inv eng 12 on hub 0
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring vcn_dec_0 uses VM inv eng 0 on hub 1
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring vcn_enc_0.0 uses VM inv eng 1 on hub 1
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring vcn_enc_0.1 uses VM inv eng 4 on hub 1
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: amdgpu: ring jpeg_dec uses VM inv eng 5 on hub 1
Apr 13 10:20:24 asustop kernel: amdgpu 0000:07:00.0: [drm] Cannot find any crtc or sizes
Apr 13 10:20:24 asustop kernel: OOM killer enabled.
Apr 13 10:20:24 asustop kernel: Restarting tasks ... done.
Apr 13 10:20:24 asustop kernel: random: crng reseeded on system resumption
Apr 13 10:20:24 asustop systemd-sleep[20236]: System returned from sleep state.
Apr 13 10:20:24 asustop bluetoothd[535]: Controller resume with wake event 0x0
Apr 13 10:20:24 asustop systemd[1]: systemd-suspend.service: Deactivated successfully.
Apr 13 10:20:24 asustop kernel: PM: suspend exit
Apr 13 10:20:24 asustop systemd[1]: Finished System Suspend.
Apr 13 10:20:24 asustop systemd[1]: Stopped target Sleep.
Apr 13 10:20:24 asustop systemd[1]: Reached target Suspend.
Apr 13 10:20:24 asustop systemd[1]: Stopped target Suspend.
Apr 13 10:20:24 asustop systemd-logind[537]: Operation 'sleep' finished.
Apr 13 10:20:24 asustop asusd[1039]: INFO: CtrlAnimeTask running sleep animation
Apr 13 10:20:24 asustop asusd[1039]: INFO: CtrlRogBios reloading panel_od
Apr 13 10:20:24 asustop asusd[1039]: INFO: CtrlCharge reloading charge limit
Apr 13 10:20:24 asustop asusd[1039]: INFO: CtrlKbdLedTask reloading brightness and modes
Apr 13 10:20:24 asustop asusd[1039]: INFO: AniMe system thread: JoinHandle { .. }
Apr 13 10:20:24 asustop asusd[1039]: INFO: AniMe new system thread started
Apr 13 10:20:24 asustop NetworkManager[548]: <info>  [1681348824.7305] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Apr 13 10:20:24 asustop NetworkManager[548]: <info>  [1681348824.7306] device (wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Apr 13 10:20:24 asustop asusd[1039]: INFO: Battery charge limit: 60
Apr 13 10:20:24 asustop asusd[1039]: INFO: rog-anime: frame-loop was asked to exit
Apr 13 10:20:24 asustop asusd[1039]: INFO: rog-anime: frame-loop callback asked to exit early
Apr 13 10:20:24 asustop NetworkManager[548]: <info>  [1681348824.7735] device (wlan0): set-hw-addr: set MAC address to ... (scanning)
Apr 13 10:20:24 asustop asusd[1039]: INFO: AniMe system thread exited
Apr 13 10:20:24 asustop asusd[1039]: INFO: AniMe no previous system thread running (now)
Apr 13 10:20:24 asustop NetworkManager[548]: <info>  [1681348824.8236] manager: NetworkManager state is now DISCONNECTED
Apr 13 10:20:24 asustop wpa_supplicant[666]: dbus: fill_dict_with_properties dbus_interface=fi.w1.wpa_supplicant1.Interface.P2PDevice dbus_property=P2PDeviceConfig getter failed
Apr 13 10:20:24 asustop NetworkManager[548]: <info>  [1681348824.8613] device (wlan0): supplicant interface state: internal-starting -> disconnected
Apr 13 10:20:24 asustop NetworkManager[548]: <info>  [1681348824.8613] device (wlan0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.2640] policy: auto-activating connection 'WIFI' (...)
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.2644] device (wlan0): Activation: starting connection 'WIFI' (...)
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.2645] device (wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.2647] manager: NetworkManager state is now CONNECTING
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.3252] device (wlan0): set-hw-addr: reset MAC address to ... (preserve)
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.3864] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.3869] device (wlan0): Activation: (wifi) access point 'WIFI' has security, but secrets are required.
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.3869] device (wlan0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.3874] device (wlan0): supplicant interface state: disconnected -> interface_disabled
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.3877] device (wlan0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.3878] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.3879] device (wlan0): Activation: (wifi) connection 'WIFI' has security, and secrets exist.  No new secrets needed.
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.3880] Config: added 'ssid' value 'WIFI'
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.3880] Config: added 'scan_ssid' value '1'
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.3880] Config: added 'bgscan' value 'simple:30:-70:86400'
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.3880] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK SAE FT-SAE'
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.3880] Config: added 'psk' value '<hidden>'
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.4132] device (wlan0): supplicant interface state: interface_disabled -> inactive
Apr 13 10:20:27 asustop wpa_supplicant[666]: wlan0: SME: Trying to authenticate with ... (SSID='WIFI' freq=2427 MHz)
Apr 13 10:20:27 asustop kernel: wlan0: authenticate with ...
Apr 13 10:20:27 asustop kernel: wlan0: send auth to ...
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.5993] device (wlan0): supplicant interface state: inactive -> authenticating
Apr 13 10:20:27 asustop wpa_supplicant[666]: wlan0: Trying to associate with  ... (SSID='WIFI' freq=2427 MHz)
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.6066] device (wlan0): supplicant interface state: authenticating -> associating
Apr 13 10:20:27 asustop kernel: wlan0: authenticated
Apr 13 10:20:27 asustop kernel: wlan0: associate with ...(try 1/3)
Apr 13 10:20:27 asustop kernel: wlan0: RX AssocResp from ... (capab=0x1411 status=0 aid=17)
Apr 13 10:20:27 asustop wpa_supplicant[666]: wlan0: Associated with ...
Apr 13 10:20:27 asustop wpa_supplicant[666]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Apr 13 10:20:27 asustop kernel: wlan0: associated
Apr 13 10:20:27 asustop kernel: wlan0: Limiting TX power to 20 (20 - 0) dBm as advertised by ...
Apr 13 10:20:27 asustop wpa_supplicant[666]: wlan0: WPA: Key negotiation completed with ... [PTK=CCMP GTK=CCMP]
Apr 13 10:20:27 asustop wpa_supplicant[666]: wlan0: CTRL-EVENT-CONNECTED - Connection to ... completed [id=0 id_str=]
Apr 13 10:20:27 asustop wpa_supplicant[666]: bgscan simple: Failed to enable signal strength monitoring
Apr 13 10:20:27 asustop kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.7714] device (wlan0): supplicant interface state: associating -> completed
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.7715] device (wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "WIFI"
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.7717] device (wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.7723] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.8120] dhcp4 (wlan0): state changed new lease, address=...
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.8126] policy: set 'WIFI' (wlan0) as default for IPv4 routing and DNS
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.8350] device (wlan0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.8369] device (wlan0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.8370] device (wlan0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.8372] manager: NetworkManager state is now CONNECTED_SITE
Apr 13 10:20:27 asustop NetworkManager[548]: <info>  [1681348827.8408] device (wlan0): Activation: successful, device activated.
Apr 13 10:20:28 asustop NetworkManager[548]: <info>  [1681348828.7188] manager: NetworkManager state is now CONNECTED_GLOBAL
Apr 13 10:20:30 asustop systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Apr 13 10:20:55 asustop rtkit-daemon[720]: Supervising 8 threads of 6 processes of 1 users.
Apr 13 10:20:55 asustop rtkit-daemon[720]: Supervising 8 threads of 6 processes of 1 users.
Apr 13 10:21:03 asustop kernel: input: HHKB-Hybrid_2 Keyboard as /devices/virtual/misc/uhid/0005:04FE:0021.000D/input/input45
Apr 13 10:21:03 asustop kernel: hid-generic 0005:04FE:0021.000D: input,hidraw6: BLUETOOTH HID v0.01 Keyboard [HHKB-Hybrid_2] on ...
Apr 13 10:21:03 asustop systemd-logind[537]: Watching system buttons on /dev/input/event22 (HHKB-Hybrid_2 Keyboard)
Apr 13 10:21:03 asustop kernel: input: HHKB-Hybrid_2 Keyboard as /devices/virtual/input/input46
Apr 13 10:21:03 asustop systemd-logind[537]: Watching system buttons on /dev/input/event23 (HHKB-Hybrid_2 Keyboard)
Apr 13 10:21:08 asustop /usr/lib/gdm-wayland-session[20442]: Set Windows Application User Model ID (AUMID) { appUserModelId: 'org.whispersystems.signal-desktop' }
Apr 13 10:21:08 asustop /usr/lib/gdm-wayland-session[20442]: NODE_ENV production
Apr 13 10:21:08 asustop /usr/lib/gdm-wayland-session[20442]: NODE_CONFIG_DIR /usr/lib/signal-desktop/resources/app.asar/config
Apr 13 10:21:08 asustop /usr/lib/gdm-wayland-session[20442]: NODE_CONFIG {}
Apr 13 10:21:08 asustop /usr/lib/gdm-wayland-session[20442]: ALLOW_CONFIG_MUTATIONS undefined
Apr 13 10:21:08 asustop /usr/lib/gdm-wayland-session[20442]: HOSTNAME undefined
Apr 13 10:21:08 asustop /usr/lib/gdm-wayland-session[20442]: NODE_APP_INSTANCE undefined
Apr 13 10:21:08 asustop /usr/lib/gdm-wayland-session[20442]: SUPPRESS_NO_CONFIG_WARNING undefined
Apr 13 10:21:08 asustop /usr/lib/gdm-wayland-session[20442]: SIGNAL_ENABLE_HTTP undefined
Apr 13 10:21:08 asustop /usr/lib/gdm-wayland-session[20442]: userData: /home/arael/.config/Signal
Apr 13 10:21:08 asustop /usr/lib/gdm-wayland-session[20442]: config/get: Successfully read user config file
Apr 13 10:21:08 asustop /usr/lib/gdm-wayland-session[20442]: config/get: Successfully read ephemeral config file
Apr 13 10:21:09 asustop /usr/lib/gdm-wayland-session[20547]: (node:20547) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead.
Apr 13 10:21:09 asustop /usr/lib/gdm-wayland-session[20547]: (Use `exe --trace-deprecation ...` to show where the warning was created)
Apr 13 10:21:15 asustop signal-desktop[20442]: signal-desktop has no capability of surrounding-text feature
Apr 13 10:21:19 asustop /usr/lib/gdm-wayland-session[20154]: #<buffer *vterm*>
Apr 13 10:21:19 asustop /usr/lib/gdm-wayland-session[1151]: 05:19:21.649 [ERROR] [wlr] [xwayland/xwm.c:1522] xcb error: op ChangeProperty (no minor), code Window (no extension), sequence 41308, value 23069916
Apr 13 10:21:19 asustop /usr/lib/gdm-wayland-session[1151]: 05:19:21.649 [ERROR] [wlr] [xwayland/xwm.c:1522] xcb error: op ConfigureWindow (no minor), code Window (no extension), sequence 41310, value 23069916
Apr 13 10:21:19 asustop /usr/lib/gdm-wayland-session[1151]: 05:19:21.649 [ERROR] [wlr] [xwayland/xwm.c:1522] xcb error: op ChangeProperty (no minor), code Window (no extension), sequence 41321, value 23069916
Apr 13 10:21:19 asustop /usr/lib/gdm-wayland-session[1151]: 05:19:21.649 [ERROR] [wlr] [xwayland/xwm.c:1522] xcb error: op ChangeProperty (no minor), code Window (no extension), sequence 41322, value 23069916
Apr 13 10:21:26 asustop /usr/lib/gdm-wayland-session[20610]: 1
Apr 13 10:21:26 asustop /usr/lib/gdm-wayland-session[20614]: 1
Apr 13 10:21:27 asustop /usr/lib/gdm-wayland-session[20618]: 1
Apr 13 10:21:28 asustop /usr/lib/gdm-wayland-session[20622]: 1
Apr 13 10:21:31 asustop /usr/lib/gdm-wayland-session[20627]: 1
Apr 13 10:21:33 asustop /usr/lib/gdm-wayland-session[1497]: [Parent 1497, Main Thread] WARNING: firefox has no capability of surrounding-text feature: 'glib warning', file /build/firefox/src/firefox-112.0/toolkit/xre/nsSigHandlers.cpp:167
Apr 13 10:21:33 asustop firefox[1497]: firefox has no capability of surrounding-text feature
Apr 13 10:21:33 asustop /usr/lib/gdm-wayland-session[1497]: [Parent 1497, Main Thread] WARNING: firefox has no capability of surrounding-text feature: 'glib warning', file /build/firefox/src/firefox-112.0/toolkit/xre/nsSigHandlers.cpp:167
Apr 13 10:21:33 asustop firefox[1497]: firefox has no capability of surrounding-text feature
Apr 13 10:21:33 asustop /usr/lib/gdm-wayland-session[1151]: 05:19:35.601 [ERROR] [wlr] [xwayland/xwm.c:1522] xcb error: op ChangeWindowAttributes (no minor), code Window (no extension), sequence 41598, value 10487628
Apr 13 10:21:36 asustop rtkit-daemon[720]: Supervising 8 threads of 6 processes of 1 users.
Apr 13 10:21:36 asustop rtkit-daemon[720]: Supervising 8 threads of 6 processes of 1 users.
Apr 13 10:21:38 asustop rtkit-daemon[720]: Supervising 8 threads of 6 processes of 1 users.
Apr 13 10:21:38 asustop rtkit-daemon[720]: Supervising 8 threads of 6 processes of 1 users.
Apr 13 10:22:03 asustop rtkit-daemon[720]: Supervising 8 threads of 6 processes of 1 users.
Apr 13 10:22:03 asustop rtkit-daemon[720]: Supervising 8 threads of 6 processes of 1 users.
Apr 13 10:22:23 asustop rtkit-daemon[720]: Supervising 8 threads of 6 processes of 1 users.
Apr 13 10:22:23 asustop rtkit-daemon[720]: Supervising 8 threads of 6 processes of 1 users.
Apr 13 10:22:23 asustop rtkit-daemon[720]: Supervising 8 threads of 6 processes of 1 users.
Apr 13 10:22:23 asustop rtkit-daemon[720]: Supervising 8 threads of 6 processes of 1 users.
Apr 13 10:22:23 asustop rtkit-daemon[720]: Successfully made thread 20851 of process 18330 owned by '1000' RT at priority 10.
Apr 13 10:22:23 asustop rtkit-daemon[720]: Supervising 9 threads of 7 processes of 1 users.
Apr 13 10:22:55 asustop rtkit-daemon[720]: Supervising 9 threads of 7 processes of 1 users.
Apr 13 10:22:55 asustop rtkit-daemon[720]: Supervising 9 threads of 7 processes of 1 users.
Apr 13 10:23:19 asustop rtkit-daemon[720]: Supervising 9 threads of 7 processes of 1 users.
Apr 13 10:23:19 asustop rtkit-daemon[720]: Supervising 9 threads of 7 processes of 1 users.
Apr 13 10:23:19 asustop rtkit-daemon[720]: Supervising 9 threads of 7 processes of 1 users.
Apr 13 10:23:19 asustop rtkit-daemon[720]: Supervising 9 threads of 7 processes of 1 users.
Apr 13 10:23:19 asustop rtkit-daemon[720]: Successfully made thread 20917 of process 19684 owned by '1000' RT at priority 10.
Apr 13 10:23:19 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:23:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:23:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:25:25 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:25:25 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:25:46 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:25:46 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:26:25 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:26:25 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:27:25 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:27:25 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:28:14 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:28:14 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:28:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:28:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:29:39 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:29:39 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:29:42 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:29:42 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:30:03 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:30:03 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:30:21 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:30:21 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:31:11 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:31:11 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:31:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:31:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:33:10 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:33:10 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:33:10 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:33:10 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:33:16 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:33:16 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:33:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:33:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:34:01 asustop /usr/lib/gdm-wayland-session[1299]: I2023-04-13 10:34:01.998926 instance.cpp:1136] Running autosave...
Apr 13 10:34:02 asustop /usr/lib/gdm-wayland-session[1299]: I2023-04-13 10:34:02.006387 instance.cpp:1138] End autosave
Apr 13 10:34:33 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:34:33 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:34:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:34:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:35:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:35:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:36:19 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:36:19 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:36:40 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:36:40 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:36:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:36:55 asustop rtkit-daemon[720]: Supervising 10 threads of 8 processes of 1 users.
Apr 13 10:37:10 asustop /usr/lib/gdm-wayland-session[1497]: [Parent 1497, Main Thread] WARNING: firefox has no capability of surrounding-text feature: 'glib warning', file /build/firefox/src/firefox-112.0/toolkit/xre/nsSigHandlers.cpp:167
Apr 13 10:37:10 asustop /usr/lib/gdm-wayland-session[1497]: [Parent 1497, Main Thread] WARNING: firefox has no capability of surrounding-text feature: 'glib warning', file /build/firefox/src/firefox-112.0/toolkit/xre/nsSigHandlers.cpp:167
Apr 13 10:37:10 asustop firefox[1497]: firefox has no capability of surrounding-text feature
Apr 13 10:37:10 asustop firefox[1497]: firefox has no capability of surrounding-text feature
Apr 13 10:37:37 asustop dbus-daemon[536]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.455' (uid=0 pid=22207 comm="sudo systemctl restart NetworkManager")
Apr 13 10:37:37 asustop dbus-daemon[536]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.home1.service': Unit dbus-org.freedesktop.home1.service not found.
Apr 13 10:37:37 asustop sudo[22207]: pam_systemd_home(sudo:auth): systemd-homed is not available: Unit dbus-org.freedesktop.home1.service not found.
Apr 13 10:37:39 asustop sudo[22207]: pam_unix(sudo:auth): authentication failure; logname= uid=1000 euid=0 tty=/dev/pts/2 ruser=arael rhost=  user=arael
Apr 13 10:37:43 asustop sudo[22207]: pam_unix(sudo:auth): conversation failed
Apr 13 10:37:43 asustop sudo[22207]: pam_unix(sudo:auth): auth could not identify password for [arael]

Last edited by ArchArael (2023-04-13 01:58:39)

seth · 2023-04-13 06:21:44

Apr 13 10:20:24 asustop systemd[1]: Stopped target Suspend.
…
Apr 13 10:20:24 asustop NetworkManager[548]: <info>  [1681348824.8236] manager: NetworkManager state is now DISCONNECTED
…
Apr 13 10:20:28 asustop NetworkManager[548]: <info>  [1681348828.7188] manager: NetworkManager state is now CONNECTED_GLOBAL
…
Apr 13 10:37:37 asustop dbus-daemon[536]: [system] Activating via systemd: service name='org.freedesktop.home1' unit='dbus-org.freedesktop.home1.service' requested by ':1.455' (uid=0 pid=22207 comm="sudo systemctl restart NetworkManager")

You woke up at 10:20:24, got re-connected at 10:20:28 and then restart NM out of the blue at 10:37:37
Did the network at first still work?
How's the signal strength? (in dBm, not "good" or "ok")

Ping just did not output anything.

I cannot ping anything.

Please don't paraphrase, https://bbs.archlinux.org/viewtopic.php?id=57855

At any time

ip a
resolvectl status

And when you cannot ping anything™

ping 8.8.8.8
ping _gateway

ArchArael · 2023-04-13 07:19:04

The strength of the signal is 91% and the network was operative.

I tried the following:

ping google.com

The result was a timeout.

Yes, I have to restart the NetworkManager when this happens. It is the only way to get back the connection.

This the content of /proc/net/wireless:

wlan0: 0000   61.  -49.  -256        0      0      0      0      4        0

I have tried to monitor it while browsing. The numbers do not vary much.

Thank you for helping.

Last edited by ArchArael (2023-04-13 07:28:21)

seth · 2023-04-13 07:23:46

Before the edit you wrote:

Next time it happens I will post the output of
ip a
resolvectl status

No, post it now and try the IP/_gateway ping when "ping google.com" times out, this could very well be a mere DNS issue.

ArchArael · 2023-04-13 07:33:15

Here is more info:

❯ ping 8.8.8.8
PING 8.8.8.8 (8.8.8.8) 56(84) bytes of data.
64 bytes from 8.8.8.8: icmp_seq=1 ttl=115 time=35.6 ms
64 bytes from 8.8.8.8: icmp_seq=2 ttl=115 time=36.9 ms
64 bytes from 8.8.8.8: icmp_seq=3 ttl=115 time=37.0 ms
64 bytes from 8.8.8.8: icmp_seq=4 ttl=115 time=37.0 ms
64 bytes from 8.8.8.8: icmp_seq=5 ttl=115 time=15.3 ms
64 bytes from 8.8.8.8: icmp_seq=6 ttl=115 time=35.9 ms

❯ ping _gateway
PING _gateway (192.168.50.1) 56(84) bytes of data.
64 bytes from _gateway (192.168.50.1): icmp_seq=1 ttl=64 time=27.4 ms
64 bytes from _gateway (192.168.50.1): icmp_seq=2 ttl=64 time=28.4 ms
64 bytes from _gateway (192.168.50.1): icmp_seq=3 ttl=64 time=27.9 ms
64 bytes from _gateway (192.168.50.1): icmp_seq=4 ttl=64 time=1.11 ms
64 bytes from _gateway (192.168.50.1): icmp_seq=5 ttl=64 time=27.7 ms
64 bytes from _gateway (192.168.50.1): icmp_seq=6 ttl=64 time=29.5 ms
64 bytes from _gateway (192.168.50.1): icmp_seq=7 ttl=64 time=24.6 ms

❯ resolvectl status
Failed to get global data: Unit dbus-org.freedesktop.resolve1.service not found.

❯ host google.com 8.8.8.8
Using domain server:
Name: 8.8.8.8
Address: 8.8.8.8#53
Aliases: 

google.com has address 142.251.222.46
google.com has IPv6 address 2404:6800:4004:818::200e
google.com mail is handled by 10 smtp.google.com.

Next time, when the networks becomes unreachable, will try to ping the default gateway. Thank you for suggesting that.

Last edited by ArchArael (2023-04-13 07:37:05)

seth · 2023-04-13 07:37:37

The most interesting test is ping 8.8.8.8 when ping google.com fails, but for now post

ip a

and "nslookup google.com" or "drill google.com" to record the default resolver.

ArchArael · 2023-04-13 07:42:16

Next time the connection fails I will post the information that you have requested.

For now, here is the info with working connection:

❯ ip a 
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1000
    link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
    inet 127.0.0.1/8 scope host lo
       valid_lft forever preferred_lft forever
    inet6 ::1/128 scope host 
       valid_lft forever preferred_lft forever
2: wlan0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue state UP group default qlen 1000
    link/ether XX:XX:XX:XX:XX:XX brd ff:ff:ff:ff:ff:ff
    inet 192.168.50.204/24 brd 192.168.50.255 scope global dynamic noprefixroute wlan0
       valid_lft 83516sec preferred_lft 83516sec
    inet6 fe80::783f:db8b:ae24:b4a2/64 scope link noprefixroute 
       valid_lft forever preferred_lft forever

~
❯ nslookup google.com
Server:         192.168.50.1
Address:        192.168.50.1#53

Non-authoritative answer:
Name:   google.com
Address: 142.250.207.46
Name:   google.com
Address: 2404:6800:4004:818::200e

~
❯ drill google.com
;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 26672
;; flags: qr rd ra ; QUERY: 1, ANSWER: 1, AUTHORITY: 0, ADDITIONAL: 0 
;; QUESTION SECTION:
;; google.com.  IN      A

;; ANSWER SECTION:
google.com.     187     IN      A       142.251.222.14

;; AUTHORITY SECTION:

;; ADDITIONAL SECTION:

;; Query time: 140 msec
;; EDNS: version 0; flags: ; udp: 1232
;; SERVER: 192.168.50.1
;; WHEN: Thu Apr 13 16:42:51 2023
;; MSG SIZE  rcvd: 55

Last edited by ArchArael (2023-04-13 07:45:41)

Arch Linux

#1 2023-04-11 07:37:10

Wifi keeps disconnecting randomly; no useful logs are shown

#2 2023-04-11 14:38:30

Re: Wifi keeps disconnecting randomly; no useful logs are shown

#3 2023-04-11 15:43:52

Re: Wifi keeps disconnecting randomly; no useful logs are shown

#4 2023-04-12 09:10:26

Re: Wifi keeps disconnecting randomly; no useful logs are shown

#5 2023-04-13 01:44:17

Re: Wifi keeps disconnecting randomly; no useful logs are shown

#6 2023-04-13 06:21:44

Re: Wifi keeps disconnecting randomly; no useful logs are shown

#7 2023-04-13 07:19:04

Re: Wifi keeps disconnecting randomly; no useful logs are shown

#8 2023-04-13 07:23:46

Re: Wifi keeps disconnecting randomly; no useful logs are shown

#9 2023-04-13 07:33:15

Re: Wifi keeps disconnecting randomly; no useful logs are shown

#10 2023-04-13 07:37:37

Re: Wifi keeps disconnecting randomly; no useful logs are shown

#11 2023-04-13 07:42:16

Re: Wifi keeps disconnecting randomly; no useful logs are shown

Board footer