You are not logged in.

#1 2020-08-19 09:06:16

LittleBobbyTables
Member
Registered: 2019-09-15
Posts: 3

Intermittent Wifi Disconnects

This is not a new issue, but I'm finally putting in some real effort to solve it. 

Using NetworkManager, with Qualcomm Atheros QCA6174 802.11ac Wireless Network Adapter and driver=ath10k_pci driverversion=5.7.12-arch1-1 firmware=WLAN.RM.4.4.1-00140-QCARMSWPZ-1

Journalctl log of a disconnection event:

Aug 19 02:46:48 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-56 noise=-112 txrate=1000
Aug 19 02:52:07 electron NetworkManager[373]: <info>  [1597823527.7436] policy: set 'Danzeiser' (wlp58s0) as default for IPv6 routing and DNS
Aug 19 02:52:42 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=-75 noise=-112 txrate=1000
Aug 19 02:52:49 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-66 noise=-112 txrate=1000
Aug 19 02:53:10 electron NetworkManager[373]: <info>  [1597823590.7184] policy: set 'Danzeiser' (wlp58s0) as default for IPv6 routing and DNS
Aug 19 02:58:43 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-67 noise=-112 txrate=1000
Aug 19 03:01:48 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-BEACON-LOSS
Aug 19 03:01:48 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-DISCONNECTED bssid=7c:d9:5c:06:df:e0 reason=4 locally_generated=1
Aug 19 03:01:48 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Aug 19 03:01:48 electron NetworkManager[373]: <info>  [1597824108.8145] device (wlp58s0): supplicant interface state: completed -> disconnected
Aug 19 03:01:48 electron NetworkManager[373]: <info>  [1597824108.8146] device (p2p-dev-wlp58s0): supplicant management interface state: completed -> disconnected
Aug 19 03:01:48 electron NetworkManager[373]: <info>  [1597824108.8944] device (wlp58s0): supplicant interface state: disconnected -> scanning
Aug 19 03:01:48 electron NetworkManager[373]: <info>  [1597824108.8945] device (p2p-dev-wlp58s0): supplicant management interface state: disconnected -> scanning
Aug 19 03:01:49 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Aug 19 03:01:50 electron ntpd[403]: Deleting interface #4 wlp58s0, 192.168.1.173#123, interface stats: received=138, sent=144, dropped=0, active_time=6157 secs
Aug 19 03:01:50 electron ntpd[403]: 194.25.134.196 local addr 192.168.1.173 -> <null>
Aug 19 03:01:50 electron ntpd[403]: 156.38.1.43 local addr 192.168.1.173 -> <null>
Aug 19 03:01:50 electron ntpd[403]: 212.25.15.129 local addr 192.168.1.173 -> <null>
Aug 19 03:01:50 electron ntpd[403]: Deleting interface #5 wlp58s0, 2605:a601:ab58:8900:6261:8fb0:159a:9c9b#123, interface stats: received=46, sent=48, dropped=0, active_time=6157 secs
Aug 19 03:01:50 electron ntpd[403]: 2001:4998:58:183a::1000 local addr 2605:a601:ab58:8900:6261:8fb0:159a:9c9b -> <null>
Aug 19 03:01:50 electron ntpd[403]: Deleting interface #6 wlp58s0, fe80::cf51:2cd2:353:a210%2#123, interface stats: received=0, sent=0, dropped=0, active_time=6157 secs
Aug 19 03:01:53 electron wpa_supplicant[654]: wlp58s0: SME: Trying to authenticate with 7c:d9:5c:06:df:e0 (SSID='Danzeiser' freq=2462 MHz)
Aug 19 03:01:53 electron kernel: wlp58s0: authenticate with 7c:d9:5c:06:df:e0
Aug 19 03:01:53 electron kernel: wlp58s0: send auth to 7c:d9:5c:06:df:e0 (try 1/3)
Aug 19 03:01:53 electron NetworkManager[373]: <info>  [1597824113.4857] device (wlp58s0): supplicant interface state: scanning -> authenticating
Aug 19 03:01:53 electron NetworkManager[373]: <info>  [1597824113.4858] device (p2p-dev-wlp58s0): supplicant management interface state: scanning -> authenticating
Aug 19 03:01:53 electron kernel: wlp58s0: send auth to 7c:d9:5c:06:df:e0 (try 2/3)
Aug 19 03:01:53 electron kernel: wlp58s0: send auth to 7c:d9:5c:06:df:e0 (try 3/3)
Aug 19 03:01:53 electron kernel: wlp58s0: authentication with 7c:d9:5c:06:df:e0 timed out
Aug 19 03:01:53 electron NetworkManager[373]: <info>  [1597824113.5319] device (wlp58s0): supplicant interface state: authenticating -> disconnected
Aug 19 03:01:53 electron NetworkManager[373]: <info>  [1597824113.5320] device (p2p-dev-wlp58s0): supplicant management interface state: authenticating -> disconnected
Aug 19 03:01:54 electron NetworkManager[373]: <info>  [1597824114.0326] device (wlp58s0): supplicant interface state: disconnected -> scanning
Aug 19 03:01:54 electron NetworkManager[373]: <info>  [1597824114.0327] device (p2p-dev-wlp58s0): supplicant management interface state: disconnected -> scanning
Aug 19 03:02:00 electron geoclue[709]: Failed to query location: Error resolving “location.services.mozilla.com”: Temporary failure in name resolution
Aug 19 03:02:03 electron NetworkManager[373]: <warn>  [1597824123.9197] device (wlp58s0): link timed out.
Aug 19 03:02:03 electron NetworkManager[373]: <info>  [1597824123.9219] device (wlp58s0): state change: activated -> failed (reason 'ssid-not-found', sys-iface-state: 'managed')
Aug 19 03:02:03 electron NetworkManager[373]: <info>  [1597824123.9240] manager: NetworkManager state is now DISCONNECTED
Aug 19 03:02:03 electron NetworkManager[373]: <info>  [1597824123.9435] device (wlp58s0): set-hw-addr: set MAC address to D6:52:D1:35:CB:6B (scanning)
Aug 19 03:02:03 electron NetworkManager[373]: <warn>  [1597824123.9556] device (wlp58s0): Activation: failed for connection 'Danzeiser'
Aug 19 03:02:03 electron dbus-daemon[371]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.1' (uid=0 pid=373 comm="/usr/bin/NetworkManager --no-daemon ")
Aug 19 03:02:03 electron systemd[1]: Starting Network Manager Script Dispatcher Service...
Aug 19 03:02:03 electron NetworkManager[373]: <info>  [1597824123.9663] device (wlp58s0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Aug 19 03:02:03 electron dbus-daemon[371]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Aug 19 03:02:03 electron systemd[1]: Started Network Manager Script Dispatcher Service.
Aug 19 03:02:03 electron 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'
Aug 19 03:02:03 electron kernel: audit: type=1130 audit(1597824123.977:168): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 19 03:02:04 electron NetworkManager[373]: <info>  [1597824124.0040] dhcp4 (wlp58s0): canceled DHCP transaction
Aug 19 03:02:04 electron NetworkManager[373]: <info>  [1597824124.0040] dhcp4 (wlp58s0): state changed bound -> done
Aug 19 03:02:04 electron NetworkManager[373]: <info>  [1597824124.0043] dhcp6 (wlp58s0): canceled DHCP transaction
Aug 19 03:02:04 electron NetworkManager[373]: <info>  [1597824124.0043] dhcp6 (wlp58s0): state changed bound -> done
Aug 19 03:02:04 electron NetworkManager[373]: <info>  [1597824124.0090] device (wlp58s0): supplicant interface state: scanning -> disconnected
Aug 19 03:02:04 electron NetworkManager[373]: <info>  [1597824124.0091] device (p2p-dev-wlp58s0): supplicant management interface state: scanning -> disconnected
Aug 19 03:02:04 electron NetworkManager[373]: <info>  [1597824124.0136] device (wlp58s0): supplicant interface state: disconnected -> inactive
Aug 19 03:02:04 electron NetworkManager[373]: <info>  [1597824124.0136] device (p2p-dev-wlp58s0): supplicant management interface state: disconnected -> inactive
Aug 19 03:02:08 electron geoclue[709]: Failed to query location: Error resolving “location.services.mozilla.com”: Temporary failure in name resolution
Aug 19 03:02:13 electron systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Aug 19 03:02:13 electron 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'
Aug 19 03:02:13 electron kernel: audit: type=1131 audit(1597824133.927:169): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 19 03:02:22 electron NetworkManager[373]: <info>  [1597824142.9412] device (wlp58s0): supplicant interface state: inactive -> scanning
Aug 19 03:02:22 electron NetworkManager[373]: <info>  [1597824142.9413] device (p2p-dev-wlp58s0): supplicant management interface state: inactive -> scanning
Aug 19 03:02:24 electron wpa_supplicant[654]: wlp58s0: Reject scan trigger since one is already pending
Aug 19 03:02:27 electron NetworkManager[373]: <info>  [1597824147.4808] device (wlp58s0): supplicant interface state: scanning -> inactive
Aug 19 03:02:27 electron NetworkManager[373]: <info>  [1597824147.4809] device (p2p-dev-wlp58s0): supplicant management interface state: scanning -> inactive
Aug 19 03:02:27 electron NetworkManager[373]: <info>  [1597824147.9312] device (wlp58s0): supplicant interface state: inactive -> scanning
Aug 19 03:02:27 electron NetworkManager[373]: <info>  [1597824147.9313] device (p2p-dev-wlp58s0): supplicant management interface state: inactive -> scanning
Aug 19 03:02:31 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Aug 19 03:02:32 electron NetworkManager[373]: <info>  [1597824152.4726] device (wlp58s0): supplicant interface state: scanning -> inactive
Aug 19 03:02:32 electron NetworkManager[373]: <info>  [1597824152.4727] device (p2p-dev-wlp58s0): supplicant management interface state: scanning -> inactive
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4731] policy: auto-activating connection 'Danzeiser' (f13557ff-23dd-47b7-b650-4c1073bffdef)
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4735] device (wlp58s0): Activation: starting connection 'Danzeiser' (f13557ff-23dd-47b7-b650-4c1073bffdef)
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4736] device (wlp58s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4739] manager: NetworkManager state is now CONNECTING
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4750] device (wlp58s0): set-hw-addr: reset MAC address to 9C:B6:D0:B9:96:3D (preserve)
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4804] device (wlp58s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4807] device (wlp58s0): Activation: (wifi) access point 'Danzeiser' has security, but secrets are required.
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4807] device (wlp58s0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4861] device (wlp58s0): supplicant interface state: inactive -> interface_disabled
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4861] device (p2p-dev-wlp58s0): supplicant management interface state: inactive -> interface_disabled
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4874] device (wlp58s0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4878] device (wlp58s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4881] device (wlp58s0): Activation: (wifi) connection 'Danzeiser' has security, and secrets exist.  No new secrets needed.
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4881] Config: added 'ssid' value 'Danzeiser'
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4881] Config: added 'scan_ssid' value '1'
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4881] Config: added 'bgscan' value 'simple:30:-70:86400'
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4881] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK'
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4881] Config: added 'auth_alg' value 'OPEN'
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.4881] Config: added 'psk' value '<hidden>'
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.5261] device (wlp58s0): supplicant interface state: interface_disabled -> inactive
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.5262] device (p2p-dev-wlp58s0): supplicant management interface state: interface_disabled -> inactive
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.5454] device (wlp58s0): supplicant interface state: inactive -> scanning
Aug 19 03:02:39 electron NetworkManager[373]: <info>  [1597824159.5455] device (p2p-dev-wlp58s0): supplicant management interface state: inactive -> scanning
Aug 19 03:02:44 electron wpa_supplicant[654]: wlp58s0: SME: Trying to authenticate with 7c:d9:5c:06:df:e0 (SSID='Danzeiser' freq=2462 MHz)
Aug 19 03:02:44 electron kernel: wlp58s0: authenticate with 7c:d9:5c:06:df:e0
Aug 19 03:02:44 electron kernel: wlp58s0: send auth to 7c:d9:5c:06:df:e0 (try 1/3)
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.1351] device (wlp58s0): supplicant interface state: scanning -> authenticating
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.1352] device (p2p-dev-wlp58s0): supplicant management interface state: scanning -> authenticating
Aug 19 03:02:44 electron kernel: wlp58s0: authenticated
Aug 19 03:02:44 electron wpa_supplicant[654]: wlp58s0: Trying to associate with 7c:d9:5c:06:df:e0 (SSID='Danzeiser' freq=2462 MHz)
Aug 19 03:02:44 electron kernel: wlp58s0: associate with 7c:d9:5c:06:df:e0 (try 1/3)
Aug 19 03:02:44 electron kernel: wlp58s0: RX AssocResp from 7c:d9:5c:06:df:e0 (capab=0x1411 status=0 aid=3)
Aug 19 03:02:44 electron wpa_supplicant[654]: wlp58s0: Associated with 7c:d9:5c:06:df:e0
Aug 19 03:02:44 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Aug 19 03:02:44 electron kernel: wlp58s0: associated
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.1572] device (wlp58s0): supplicant interface state: authenticating -> associating
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.1573] device (p2p-dev-wlp58s0): supplicant management interface state: authenticating -> associating
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.1576] device (wlp58s0): supplicant interface state: associating -> associated
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.1576] device (p2p-dev-wlp58s0): supplicant management interface state: associating -> associated
Aug 19 03:02:44 electron kernel: wlp58s0: Limiting TX power to 30 (30 - 0) dBm as advertised by 7c:d9:5c:06:df:e0
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.1672] device (wlp58s0): supplicant interface state: associated -> 4way_handshake
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.1672] device (p2p-dev-wlp58s0): supplicant management interface state: associated -> 4way_handshake
Aug 19 03:02:44 electron wpa_supplicant[654]: wlp58s0: WPA: Key negotiation completed with 7c:d9:5c:06:df:e0 [PTK=CCMP GTK=CCMP]
Aug 19 03:02:44 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-CONNECTED - Connection to 7c:d9:5c:06:df:e0 completed [id=0 id_str=]
Aug 19 03:02:44 electron kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp58s0: link becomes ready
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.1818] device (wlp58s0): supplicant interface state: 4way_handshake -> completed
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.1819] device (wlp58s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "Danzeiser"
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.1820] device (p2p-dev-wlp58s0): supplicant management interface state: 4way_handshake -> completed
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.1825] device (wlp58s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.1835] dhcp4 (wlp58s0): activation: beginning transaction (timeout in 45 seconds)
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2097] dhcp4 (wlp58s0): option dhcp_lease_time      => '86400'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2098] dhcp4 (wlp58s0): option domain_name_servers  => '8.8.8.8 1.1.1.1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2098] dhcp4 (wlp58s0): option expiry               => '1597910564'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2098] dhcp4 (wlp58s0): option host_name            => 'electron'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2098] dhcp4 (wlp58s0): option ip_address           => '192.168.1.173'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2098] dhcp4 (wlp58s0): option next_server          => '192.168.1.1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2099] dhcp4 (wlp58s0): option requested_broadcast_address => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2099] dhcp4 (wlp58s0): option requested_domain_name => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2099] dhcp4 (wlp58s0): option requested_domain_name_servers => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2099] dhcp4 (wlp58s0): option requested_domain_search => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2099] dhcp4 (wlp58s0): option requested_host_name  => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2099] dhcp4 (wlp58s0): option requested_interface_mtu => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2100] dhcp4 (wlp58s0): option requested_ms_classless_static_routes => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2100] dhcp4 (wlp58s0): option requested_nis_domain => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2100] dhcp4 (wlp58s0): option requested_nis_servers => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2100] dhcp4 (wlp58s0): option requested_ntp_servers => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2100] dhcp4 (wlp58s0): option requested_rfc3442_classless_static_routes => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2100] dhcp4 (wlp58s0): option requested_root_path  => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2100] dhcp4 (wlp58s0): option requested_routers    => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2101] dhcp4 (wlp58s0): option requested_static_routes => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2101] dhcp4 (wlp58s0): option requested_subnet_mask => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2101] dhcp4 (wlp58s0): option requested_time_offset => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2101] dhcp4 (wlp58s0): option requested_wpad       => '1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2101] dhcp4 (wlp58s0): option routers              => '192.168.1.1'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2101] dhcp4 (wlp58s0): option subnet_mask          => '255.255.255.0'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2102] dhcp4 (wlp58s0): option wpad                 => '
                                              '
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2102] dhcp4 (wlp58s0): state changed unknown -> bound
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2124] device (wlp58s0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Aug 19 03:02:44 electron dbus-daemon[371]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.1' (uid=0 pid=373 comm="/usr/bin/NetworkManager --no-daemon ")
Aug 19 03:02:44 electron systemd[1]: Starting Network Manager Script Dispatcher Service...
Aug 19 03:02:44 electron dbus-daemon[371]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Aug 19 03:02:44 electron systemd[1]: Started Network Manager Script Dispatcher Service.
Aug 19 03:02:44 electron 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'
Aug 19 03:02:44 electron kernel: audit: type=1130 audit(1597824164.217:170): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2249] device (wlp58s0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2252] device (wlp58s0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2256] manager: NetworkManager state is now CONNECTED_LOCAL
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2273] manager: NetworkManager state is now CONNECTED_SITE
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2275] policy: set 'Danzeiser' (wlp58s0) as default for IPv4 routing and DNS
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.2278] device (wlp58s0): Activation: successful, device activated.
Aug 19 03:02:44 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-61 noise=-112 txrate=1000
Aug 19 03:02:44 electron NetworkManager[373]: <info>  [1597824164.6094] manager: NetworkManager state is now CONNECTED_GLOBAL
Aug 19 03:02:46 electron ntpd[403]: Listen normally on 7 wlp58s0 192.168.1.173:123
Aug 19 03:02:46 electron ntpd[403]: bind(24) AF_INET6 fe80::cf51:2cd2:353:a210%2#123 flags 0x11 failed: Cannot assign requested address
Aug 19 03:02:46 electron ntpd[403]: unable to create socket on wlp58s0 (8) for fe80::cf51:2cd2:353:a210%2#123
Aug 19 03:02:46 electron ntpd[403]: failed to init interface for address fe80::cf51:2cd2:353:a210%2
Aug 19 03:02:46 electron ntpd[403]: new interface(s) found: waking up resolver
Aug 19 03:02:46 electron NetworkManager[373]: <info>  [1597824166.2881] dhcp6 (wlp58s0): activation: beginning transaction (timeout in 45 seconds)
Aug 19 03:02:46 electron NetworkManager[373]: <info>  [1597824166.2892] policy: set 'Danzeiser' (wlp58s0) as default for IPv6 routing and DNS
Aug 19 03:02:46 electron NetworkManager[373]: <info>  [1597824166.2933] dhcp6 (wlp58s0): option dhcp6_name_servers   => '2605:a601:ab58:8900::1'
Aug 19 03:02:46 electron NetworkManager[373]: <info>  [1597824166.2934] dhcp6 (wlp58s0): state changed unknown -> bound
Aug 19 03:02:48 electron ntpd[403]: bind(24) AF_INET6 2605:a601:ab58:8900:6261:8fb0:159a:9c9b#123 flags 0x11 failed: Cannot assign requested address
Aug 19 03:02:48 electron ntpd[403]: unable to create socket on wlp58s0 (9) for 2605:a601:ab58:8900:6261:8fb0:159a:9c9b#123
Aug 19 03:02:48 electron ntpd[403]: failed to init interface for address 2605:a601:ab58:8900:6261:8fb0:159a:9c9b
Aug 19 03:02:48 electron ntpd[403]: Listen normally on 10 wlp58s0 [fe80::cf51:2cd2:353:a210%2]:123
Aug 19 03:02:48 electron ntpd[403]: new interface(s) found: waking up resolver
Aug 19 03:02:50 electron ntpd[403]: Listen normally on 11 wlp58s0 [2605:a601:ab58:8900:6261:8fb0:159a:9c9b]:123
Aug 19 03:02:50 electron ntpd[403]: 2001:4998:58:183a::1000 local addr fe80::cf51:2cd2:353:a210%2 -> 2605:a601:ab58:8900:6261:8fb0:159a:9c9b
Aug 19 03:02:50 electron ntpd[403]: new interface(s) found: waking up resolver
Aug 19 03:02:50 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Aug 19 03:02:54 electron systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Aug 19 03:02:54 electron 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'
Aug 19 03:02:54 electron kernel: audit: type=1131 audit(1597824174.931:171): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Aug 19 03:02:57 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Aug 19 03:03:01 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Aug 19 03:03:05 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Aug 19 03:03:09 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Aug 19 03:03:12 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Aug 19 03:03:16 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Aug 19 03:03:19 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Aug 19 03:03:23 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Aug 19 03:03:35 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Aug 19 03:03:50 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=-75 noise=-111 txrate=1000
Aug 19 03:03:51 electron wpa_supplicant[654]: wlp58s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-65 noise=-111 txrate=1000

dmesg log of the event:

[Aug19 03:01] wlp58s0: authenticate with 7c:d9:5c:06:df:e0
[  +0.050903] wlp58s0: send auth to 7c:d9:5c:06:df:e0 (try 1/3)
[  +0.004831] wlp58s0: send auth to 7c:d9:5c:06:df:e0 (try 2/3)
[  +0.004951] wlp58s0: send auth to 7c:d9:5c:06:df:e0 (try 3/3)
[  +0.006152] wlp58s0: authentication with 7c:d9:5c:06:df:e0 timed out
[Aug19 03:02] audit: type=1130 audit(1597824123.977:168): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[  +9.950024] audit: type=1131 audit(1597824133.927:169): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ +30.147840] wlp58s0: authenticate with 7c:d9:5c:06:df:e0
[  +0.051559] wlp58s0: send auth to 7c:d9:5c:06:df:e0 (try 1/3)
[  +0.002894] wlp58s0: authenticated
[  +0.004574] wlp58s0: associate with 7c:d9:5c:06:df:e0 (try 1/3)
[  +0.005973] wlp58s0: RX AssocResp from 7c:d9:5c:06:df:e0 (capab=0x1411 status=0 aid=3)
[  +0.002721] wlp58s0: associated
[  +0.010232] wlp58s0: Limiting TX power to 30 (30 - 0) dBm as advertised by 7c:d9:5c:06:df:e0
[  +0.016696] IPv6: ADDRCONF(NETDEV_CHANGE): wlp58s0: link becomes ready
[  +0.047635] audit: type=1130 audit(1597824164.217:170): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ +10.712245] audit: type=1131 audit(1597824174.931:171): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'

currently enabled systemd units:

UNIT FILE                          STATE   VENDOR PRESET
org.cups.cupsd.path                enabled disabled     
bluetooth.service                  enabled disabled     
getty@.service                     enabled enabled      
NetworkManager-dispatcher.service  enabled disabled     
NetworkManager-wait-online.service enabled disabled     
NetworkManager.service             enabled disabled     
ntpd.service                       enabled disabled     
org.cups.cupsd.service             enabled disabled     
org.cups.cupsd.socket              enabled disabled     
remote-fs.target                   enabled enabled 

Things I've tried that seemed to do nothing but could be affecting behavior:

disabling power saving

/etc/udev/rules.d/81-wifi-powersave.rules
ACTION=="add", SUBSYSTEM=="net", KERNEL=="wl*", RUN+="/usr/bin/iw dev $name set power_save off"

using the nohwcrypt=1 option because it solved similar-looking issues for others, even though they were using ath9k instead

Any help would be greatly appreciated!

Offline

#2 2020-08-19 11:07:30

V1del
Forum Moderator
Registered: 2012-10-16
Posts: 21,738

Re: Intermittent Wifi Disconnects

Any specifically ath10k related messages in dmesg? The other information so far looks quite interfacey and not necessarily related ot the underlying driver/nic (FWIW I have the same card and don't have any driver level issues since a few years now (... it used to crash firmware at least once a session ~2015-2018ish I've made a post somewhere on the first kernel I could actually use the thing error free...))

So in order to combat those I'd suggest to go to a higher layer rather than the kernel module. One thing that often barfs is NetworkManager's internal dhcp client and/or MAC address randomisation so try ruling those out:

https://wiki.archlinux.org/index.php/Ne … HCP_client
https://wiki.archlinux.org/index.php/Ne … domization

Also maybe some details about the router connection itself, 2.4 or 5Ghz band? It looks like it connects to 2.4, maybe if you have the ability switching to 5Ghz might prove more stable. But I'd suggest this as one additional thing to try granted the networkmanager tweaks don't work

Offline

#3 2020-08-19 11:12:35

seth
Member
Registered: 2012-09-03
Posts: 51,229

Re: Intermittent Wifi Disconnects

https://wiki.archlinux.org/index.php/Ne … ets_(WiFi)
https://wiki.archlinux.org/index.php/Ne … domization

Try to stop/disable NM first and see whether a connection w/ eg. wifi-menu is stable.

Edit: grrrr… ;-)

Last edited by seth (2020-08-19 11:13:06)

Offline

Board footer

Powered by FluxBB