You are not logged in.

#1 2020-10-12 23:15:16

warl0ck
Member
Registered: 2020-04-29
Posts: 47

Intermittent wifi issues with NetworkManager

I built a new system a while back, and it's always had a bit of an issue with the wifi. I'm trying to narrow down the cause.

Basically, my network connection is absolutely fine most of the time. I can play online games, watch videos, etc without any fuss.

However, randomly throughout the day (1 to 2 times), my connection will be haywire for about 30 mins.

The connection does not drop completely, NetworkManager is still connected to the correct AP. But when I run a speedtest, (if it loads at all), gives me a speed below 1mbps.

After a few minutes, it will "fix itself", and the speedtest rockets back up to my usual 40-60 mbps range. Sometimes the test will start at 1mbps, and be back at 50 by the time it finishes.

But this only lasts for a minute, because before I know it it's down to 1mbps again. This continues for roughly 30 mins before the issue is "permanently fixed" and speed goes back to normal for the rest of the day.

Some possible causes:

  • I am on WifI. This is not ideal, but I don't have a long ass ethernet cable

  • I moved my PC farther from the router. Not my choice, but it happened nonetheless.

  • I am using a broadcom wifi chipset which is known to have issues

Here are some lines in journalctl which seemed relevant:

Oct 12 15:34:12 reaper wpa_supplicant[466]: wlp40s0: CTRL-EVENT-DISCONNECTED bssid=10:05:01:17:18:d8 reason=0
Oct 12 15:34:12 reaper wpa_supplicant[466]: wlp40s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Oct 12 15:34:12 reaper NetworkManager[2348016]: <info>  [1602542052.0457] device (wlp40s0): supplicant interface state: completed -> disconnected
Oct 12 15:34:12 reaper NetworkManager[2348016]: <info>  [1602542052.1455] device (wlp40s0): supplicant interface state: disconnected -> scanning
Oct 12 15:34:14 reaper wpa_supplicant[466]: wlp40s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Oct 12 15:34:14 reaper wpa_supplicant[466]: wlp40s0: Trying to associate with 10:05:01:17:18:d8 (SSID='CBCI-F9B3-2.4' freq=2462 MHz)
Oct 12 15:34:14 reaper NetworkManager[2348016]: <info>  [1602542054.2725] device (wlp40s0): supplicant interface state: scanning -> associating
Oct 12 15:34:14 reaper wpa_supplicant[466]: wlp40s0: Associated with 10:05:01:17:18:d8
Oct 12 15:34:14 reaper wpa_supplicant[466]: wlp40s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Oct 12 15:34:14 reaper NetworkManager[2348016]: <info>  [1602542054.3503] device (wlp40s0): supplicant interface state: associating -> associated
Oct 12 15:34:14 reaper NetworkManager[2348016]: <info>  [1602542054.3688] device (wlp40s0): supplicant interface state: associated -> 4way_handshake
Oct 12 15:34:14 reaper wpa_supplicant[466]: wlp40s0: WPA: Key negotiation completed with 10:05:01:17:18:d8 [PTK=CCMP GTK=TKIP]
Oct 12 15:34:14 reaper wpa_supplicant[466]: wlp40s0: CTRL-EVENT-CONNECTED - Connection to 10:05:01:17:18:d8 completed [id=0 id_str=]
Oct 12 15:34:14 reaper wpa_supplicant[466]: bgscan simple: Failed to enable signal strength monitoring
Oct 12 15:34:14 reaper NetworkManager[2348016]: <info>  [1602542054.3859] device (wlp40s0): supplicant interface state: 4way_handshake -> completed
Oct 12 15:45:27 reaper systemd[1]: Starting Network Manager Script Dispatcher Service...
Oct 12 15:45:27 reaper dbus-daemon[462]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.4102' (uid=0 pid=2348016 comm="/usr/bin/NetworkManager --no-dae>
Oct 12 15:45:27 reaper dbus-daemon[462]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Oct 12 15:45:27 reaper systemd[1]: Started Network Manager Script Dispatcher Service.
Oct 12 15:45:27 reaper 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'
Oct 12 15:45:27 reaper kernel: audit: type=1130 audit(1602542727.704:541): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 12 15:45:38 reaper systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Oct 12 15:45:38 reaper kernel: audit: type=1131 audit(1602542738.051:542): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 12 15:45:38 reaper 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'

when I run sudo systemctl restart NetworkManager, I get

Oct 12 15:49:52 reaper dbus-daemon[462]: [system] Activating via systemd: service name='org.freedesktop.resolve1' unit='dbus-org.freedesktop.resolve1.service' requested by ':1.23428' (uid=0 pid=643065 comm="/usr/bin/NetworkManager --no-daemon ")
Oct 12 15:49:52 reaper dbus-daemon[462]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.resolve1.service': Unit dbus-org.freedesktop.resolve1.service not found.
Oct 12 15:49:52 reaper dbus-daemon[462]: [system] Activating via systemd: service name='org.freedesktop.resolve1' unit='dbus-org.freedesktop.resolve1.service' requested by ':1.23428' (uid=0 pid=643065 comm="/usr/bin/NetworkManager --no-daemon ")
Oct 12 15:49:52 reaper dbus-daemon[462]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.resolve1.service': Unit dbus-org.freedesktop.resolve1.service not found.
Oct 12 15:49:52 reaper dbus-daemon[462]: [system] Activating via systemd: service name='org.freedesktop.resolve1' unit='dbus-org.freedesktop.resolve1.service' requested by ':1.23428' (uid=0 pid=643065 comm="/usr/bin/NetworkManager --no-daemon ")
Oct 12 15:49:52 reaper dbus-daemon[462]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.resolve1.service': Unit dbus-org.freedesktop.resolve1.service not found.
Oct 12 15:49:52 reaper dbus-daemon[462]: [system] Activating via systemd: service name='org.freedesktop.resolve1' unit='dbus-org.freedesktop.resolve1.service' requested by ':1.23428' (uid=0 pid=643065 comm="/usr/bin/NetworkManager --no-daemon ")
Oct 12 15:49:52 reaper dbus-daemon[462]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.resolve1.service': Unit dbus-org.freedesktop.resolve1.service not found.
Oct 12 15:49:52 reaper dbus-daemon[462]: [system] Activating via systemd: service name='org.freedesktop.resolve1' unit='dbus-org.freedesktop.resolve1.service' requested by ':1.23428' (uid=0 pid=643065 comm="/usr/bin/NetworkManager --no-daemon ")
Oct 12 15:49:52 reaper dbus-daemon[462]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.resolve1.service': Unit dbus-org.freedesktop.resolve1.service not found.
Oct 12 15:49:52 reaper dbus-daemon[462]: [system] Activating via systemd: service name='org.freedesktop.resolve1' unit='dbus-org.freedesktop.resolve1.service' requested by ':1.23428' (uid=0 pid=643065 comm="/usr/bin/NetworkManager --no-daemon ")
Oct 12 15:49:52 reaper dbus-daemon[462]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.resolve1.service': Unit dbus-org.freedesktop.resolve1.service not found.

as it fails to reconnect to the wifi network. But lo and behold, just try enough times and it just fixes itself. Very annoying.

I think this is the first message upon reconnect

Oct 12 15:53:13 reaper wpa_supplicant[466]: wlp40s0: CTRL-EVENT-SSID-REENABLED id=0 ssid="CBCI-F9B3-2.4"
Oct 12 15:53:13 reaper wpa_supplicant[466]: wlp40s0: Trying to associate with 10:05:01:17:18:d8 (SSID='CBCI-F9B3-2.4' freq=2462 MHz)
Oct 12 15:53:13 reaper NetworkManager[643065]: <info>  [1602543193.7099] device (wlp40s0): supplicant interface state: scanning -> associating
Oct 12 15:53:13 reaper wpa_supplicant[466]: wlp40s0: Associated with 10:05:01:17:18:d8
Oct 12 15:53:13 reaper wpa_supplicant[466]: wlp40s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Oct 12 15:53:13 reaper NetworkManager[643065]: <info>  [1602543193.8088] device (wlp40s0): supplicant interface state: associating -> associated
Oct 12 15:53:13 reaper NetworkManager[643065]: <info>  [1602543193.8302] device (wlp40s0): supplicant interface state: associated -> 4way_handshake
Oct 12 15:53:13 reaper wpa_supplicant[466]: wlp40s0: WPA: Key negotiation completed with 10:05:01:17:18:d8 [PTK=CCMP GTK=TKIP]
Oct 12 15:53:13 reaper wpa_supplicant[466]: wlp40s0: CTRL-EVENT-CONNECTED - Connection to 10:05:01:17:18:d8 completed [id=0 id_str=]

When I run

 lspci 

I get

Network controller: Broadcom Inc. and subsidiaries BCM4360 802.11ac Wireless Network Adapter (rev 03)

Which is my wifi card

Last edited by warl0ck (2020-10-19 19:39:58)

Offline

#2 2020-10-13 06:44:36

seth
Member
Registered: 2012-09-03
Posts: 49,951

Re: Intermittent wifi issues with NetworkManager

Sanity check:

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

Offline

#3 2020-10-13 16:01:27

warl0ck
Member
Registered: 2020-04-29
Posts: 47

Re: Intermittent wifi issues with NetworkManager

bluetooth.service                        | bluetooth.target.wants
dbus-fi.w1.wpa_supplicant1.service       | system
dbus-org.bluez.service                   | system
dbus-org.freedesktop.nm-dispatcher.service | system
dirmngr.socket                           | sockets.target.wants
display-manager.service                  | system
docker.service                           | multi-user.target.wants
getty@tty1.service                       | getty.target.wants
gpg-agent-browser.socket                 | sockets.target.wants
gpg-agent-extra.socket                   | sockets.target.wants
gpg-agent.socket                         | sockets.target.wants
gpg-agent-ssh.socket                     | sockets.target.wants
logmein-hamachi.service                  | multi-user.target.wants
NetworkManager.service                   | multi-user.target.wants
NetworkManager-wait-online.service       | network-online.target.wants
p11-kit-server.socket                    | sockets.target.wants
pulseaudio.socket                        | sockets.target.wants
remote-fs.target                         | multi-user.target.wants
sshd.service                             | multi-user.target.wants
wpa_supplicant.service                   | multi-user.target.wants
xdg-user-dirs-update.service             | default.target.wants

Offline

#4 2020-10-13 18:11:56

seth
Member
Registered: 2012-09-03
Posts: 49,951

Re: Intermittent wifi issues with NetworkManager

Disable/stop the wpa_supplicant service (NM will run wap_supplicant/iwd itself and the two will get in a race over the NIC, knocking each other out)

Offline

#5 2020-10-13 18:45:40

warl0ck
Member
Registered: 2020-04-29
Posts: 47

Re: Intermittent wifi issues with NetworkManager

I see, I'll give that a shot!

Offline

#6 2020-10-19 19:09:13

warl0ck
Member
Registered: 2020-04-29
Posts: 47

Re: Intermittent wifi issues with NetworkManager

Update: Everything seemed to work ok, but after going out of town for a few days and returning, the behavior is back with a vengeance.

I ran

sudo systemctl disable wpa_supplicant

and

sudo systemctl stop wpa_supplicant

But when I run


sudo systemctl status wpa_supplicant

I get

● wpa_supplicant.service - WPA supplicant
     Loaded: loaded (/usr/lib/systemd/system/wpa_supplicant.service; disabled; vendor preset: disabled)
     Active: active (running) since Mon 2020-10-19 11:51:50 PDT; 10min ago
   Main PID: 581 (wpa_supplicant)
      Tasks: 1 (limit: 38451)
     Memory: 5.7M
     CGroup: /system.slice/wpa_supplicant.service
             └─581 /usr/bin/wpa_supplicant -u -s -O /run/wpa_supplicant

Oct 19 11:51:50 reaper systemd[1]: Started WPA supplicant.
Oct 19 11:51:50 reaper wpa_supplicant[581]: Successfully initialized wpa_supplicant
Oct 19 11:51:50 reaper wpa_supplicant[581]: dbus: fill_dict_with_properties dbus_interface=fi.w1.wpa_supplicant1.Interface.P2PDevice dbus_property=P2PDeviceConfig getter failed
Oct 19 11:52:03 reaper wpa_supplicant[581]: wlp40s0: Trying to associate with 10:05:01:17:18:d8 (SSID='CBCI-F9B3-2.4' freq=2462 MHz)
Oct 19 11:52:03 reaper wpa_supplicant[581]: wlp40s0: Associated with 10:05:01:17:18:d8
Oct 19 11:52:03 reaper wpa_supplicant[581]: wlp40s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Oct 19 11:52:03 reaper wpa_supplicant[581]: wlp40s0: WPA: Key negotiation completed with 10:05:01:17:18:d8 [PTK=CCMP GTK=TKIP]
Oct 19 11:52:03 reaper wpa_supplicant[581]: wlp40s0: CTRL-EVENT-CONNECTED - Connection to 10:05:01:17:18:d8 completed [id=0 id_str=]
Oct 19 11:52:03 reaper wpa_supplicant[581]: bgscan simple: Failed to enable signal strength monitoring
Oct 19 11:54:02 reaper wpa_supplicant[581]: wlp40s0: WPA: Group rekeying completed with 10:05:01:17:18:d8 [GTK=TKIP]

I have no idea how or why it is being run, or what the main issue is really.



sudo systemctl status NetworkManager

gives

● NetworkManager.service - Network Manager
     Loaded: loaded (/usr/lib/systemd/system/NetworkManager.service; enabled; vendor preset: disabled)
    Drop-In: /usr/lib/systemd/system/NetworkManager.service.d
             └─NetworkManager-ovs.conf
     Active: active (running) since Mon 2020-10-19 11:51:50 PDT; 11min ago
       Docs: man:NetworkManager(8)
   Main PID: 499 (NetworkManager)
      Tasks: 11 (limit: 38451)
     Memory: 24.5M
     CGroup: /system.slice/NetworkManager.service
             └─499 /usr/bin/NetworkManager --no-daemon

Oct 19 11:52:06 reaper NetworkManager[499]: <info>  [1603133526.1175] policy: set 'CBCI-F9B3-2.4' (wlp40s0) as default for IPv6 routing and DNS
Oct 19 11:52:06 reaper NetworkManager[499]: <info>  [1603133526.3099] dhcp6 (wlp40s0): option dhcp6_name_servers   => '2001:558:feed::1 2001:558:feed::2'
Oct 19 11:52:06 reaper NetworkManager[499]: <info>  [1603133526.3100] dhcp6 (wlp40s0): option ip6_address          => '2603:3023:708:9c00::3337'
Oct 19 11:52:06 reaper NetworkManager[499]: <info>  [1603133526.3100] dhcp6 (wlp40s0): state changed unknown -> bound
Oct 19 12:02:56 reaper NetworkManager[499]: <info>  [1603134176.9385] manager: NetworkManager state is now CONNECTED_SITE
Oct 19 12:02:56 reaper NetworkManager[499]: <info>  [1603134176.9429] device (vetha2460b4): released from master device br-c8aeef2e6a49
Oct 19 12:02:57 reaper NetworkManager[499]: <info>  [1603134177.5592] manager: (veth8728c00): new Veth device (/org/freedesktop/NetworkManager/Devices/22)
Oct 19 12:02:57 reaper NetworkManager[499]: <info>  [1603134177.5604] manager: (veth247e0b5): new Veth device (/org/freedesktop/NetworkManager/Devices/23)
Oct 19 12:02:57 reaper NetworkManager[499]: <info>  [1603134177.7791] device (veth247e0b5): carrier: link connected
Oct 19 12:02:57 reaper NetworkManager[499]: <info>  [1603134177.7795] device (br-c8aeef2e6a49): carrier: link connected

Offline

#7 2020-10-19 19:28:53

seth
Member
Registered: 2012-09-03
Posts: 49,951

Re: Intermittent wifi issues with NetworkManager

NM uses wpa_supplicant to establish the WIFI carrier and I assume it starts it through the service (notice that the wpa_supplicant PID is > the NM PID)
=> updated journal?

Offline

#8 2020-10-20 02:03:59

warl0ck
Member
Registered: 2020-04-29
Posts: 47

Re: Intermittent wifi issues with NetworkManager

So this is the second time today this is happening, around 6:50 local time. Here is all the journal for that time frame. Some things seem interesting...

Oct 19 18:49:59 reaper NetworkManager[499]: <info>  [1603158599.4357] device (vethc565352): released from master device br-c8aeef2e6a49
Oct 19 18:50:00 reaper dockerd[832]: time="2020-10-19T18:50:00.776705379-07:00" level=warning msg="[resolver] connect failed: dial udp 75.75.76.76:53: connect: network is unreachable"
Oct 19 18:50:00 reaper dockerd[832]: time="2020-10-19T18:50:00.776727459-07:00" level=warning msg="[resolver] connect failed: dial udp 75.75.76.76:53: connect: network is unreachable"
Oct 19 18:50:02 reaper systemd[2033]: run-docker-netns-12bf9a551fba.mount: Succeeded.
Oct 19 18:50:02 reaper systemd[1]: run-docker-netns-12bf9a551fba.mount: Succeeded.
Oct 19 18:50:02 reaper systemd[1]: var-lib-docker-containers-9544914b54f94f0d9385f46a30306bba5e5afef9d079025dc643449e297182b1-mounts-shm.mount: Succeeded.
Oct 19 18:50:02 reaper systemd[2033]: var-lib-docker-containers-9544914b54f94f0d9385f46a30306bba5e5afef9d079025dc643449e297182b1-mounts-shm.mount: Succeeded.
Oct 19 18:50:02 reaper systemd[1]: var-lib-docker-overlay2-e92b6c824d40204ff9e4ce4b3cf4f7e58657485b970c71e485809be88a6b96a9-merged.mount: Succeeded.
Oct 19 18:50:02 reaper systemd[2033]: var-lib-docker-overlay2-e92b6c824d40204ff9e4ce4b3cf4f7e58657485b970c71e485809be88a6b96a9-merged.mount: Succeeded.
Oct 19 18:50:02 reaper systemd-udevd[1929480]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Oct 19 18:50:02 reaper NetworkManager[499]: <info>  [1603158602.3156] manager: (veth0b97982): new Veth device (/org/freedesktop/NetworkManager/Devices/50)
Oct 19 18:50:02 reaper systemd-udevd[1929483]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Oct 19 18:50:02 reaper audit: ANOM_PROMISCUOUS dev=veth189a79c prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
Oct 19 18:50:02 reaper systemd-udevd[1929483]: Using default interface naming scheme 'v245'.
Oct 19 18:50:02 reaper NetworkManager[499]: <info>  [1603158602.3166] manager: (veth189a79c): new Veth device (/org/freedesktop/NetworkManager/Devices/51)
Oct 19 18:50:02 reaper kernel: br-c8aeef2e6a49: port 1(veth189a79c) entered blocking state
Oct 19 18:50:02 reaper kernel: br-c8aeef2e6a49: port 1(veth189a79c) entered disabled state
Oct 19 18:50:02 reaper kernel: device veth189a79c entered promiscuous mode
Oct 19 18:50:02 reaper kernel: audit: type=1700 audit(1603158602.313:465): dev=veth189a79c prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
Oct 19 18:50:02 reaper containerd[528]: time="2020-10-19T18:50:02.343460793-07:00" level=warning msg="runtime v1 is deprecated since containerd v1.4, consider using runtime v2"
Oct 19 18:50:02 reaper containerd[528]: time="2020-10-19T18:50:02.344058653-07:00" level=info msg="shim containerd-shim started" address=/containerd-shim/a337f9912cc92ad81f611c694d73d7a>
Oct 19 18:50:02 reaper audit[1929841]: NETFILTER_CFG table=nat family=2 entries=0 op=register pid=1929841 comm="iptables"
Oct 19 18:50:02 reaper audit[1929842]: NETFILTER_CFG table=filter family=2 entries=0 op=register pid=1929842 comm="iptables"
Oct 19 18:50:02 reaper kernel: audit: type=1325 audit(1603158602.486:466): table=nat family=2 entries=0 op=register pid=1929841 comm="iptables"
Oct 19 18:50:02 reaper kernel: audit: type=1325 audit(1603158602.486:467): table=filter family=2 entries=0 op=register pid=1929842 comm="iptables"
Oct 19 18:50:02 reaper audit[1929845]: NETFILTER_CFG table=nat family=2 entries=5 op=replace pid=1929845 comm="iptables"
Oct 19 18:50:02 reaper audit[1929846]: NETFILTER_CFG table=nat family=2 entries=7 op=replace pid=1929846 comm="iptables"
Oct 19 18:50:02 reaper kernel: audit: type=1325 audit(1603158602.489:468): table=nat family=2 entries=5 op=replace pid=1929845 comm="iptables"
Oct 19 18:50:02 reaper kernel: audit: type=1325 audit(1603158602.489:469): table=nat family=2 entries=7 op=replace pid=1929846 comm="iptables"
Oct 19 18:50:02 reaper audit[1929848]: NETFILTER_CFG table=nat family=2 entries=8 op=replace pid=1929848 comm="iptables"
Oct 19 18:50:02 reaper audit[1929849]: NETFILTER_CFG table=nat family=2 entries=10 op=replace pid=1929849 comm="iptables"
Oct 19 18:50:02 reaper audit[1929850]: NETFILTER_CFG table=nat family=2 entries=11 op=replace pid=1929850 comm="iptables"
Oct 19 18:50:02 reaper audit[1929851]: NETFILTER_CFG table=nat family=2 entries=12 op=replace pid=1929851 comm="iptables"
Oct 19 18:50:02 reaper audit[1929852]: NETFILTER_CFG table=nat family=2 entries=13 op=replace pid=1929852 comm="iptables"
Oct 19 18:50:02 reaper kernel: audit: type=1325 audit(1603158602.493:470): table=nat family=2 entries=8 op=replace pid=1929848 comm="iptables"
Oct 19 18:50:02 reaper kernel: audit: type=1325 audit(1603158602.493:471): table=nat family=2 entries=10 op=replace pid=1929849 comm="iptables"
Oct 19 18:50:02 reaper kernel: audit: type=1325 audit(1603158602.493:472): table=nat family=2 entries=11 op=replace pid=1929850 comm="iptables"
Oct 19 18:50:02 reaper kernel: audit: type=1325 audit(1603158602.493:473): table=nat family=2 entries=12 op=replace pid=1929851 comm="iptables"
Oct 19 18:50:02 reaper audit[1929853]: NETFILTER_CFG table=nat family=2 entries=14 op=replace pid=1929853 comm="iptables"
Oct 19 18:50:02 reaper kernel: eth0: renamed from veth0b97982
Oct 19 18:50:02 reaper NetworkManager[499]: <info>  [1603158602.5556] device (veth189a79c): carrier: link connected
Oct 19 18:50:02 reaper NetworkManager[499]: <info>  [1603158602.5559] device (br-c8aeef2e6a49): carrier: link connected
Oct 19 18:50:02 reaper kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth189a79c: link becomes ready
Oct 19 18:50:02 reaper kernel: br-c8aeef2e6a49: port 1(veth189a79c) entered blocking state
Oct 19 18:50:02 reaper kernel: br-c8aeef2e6a49: port 1(veth189a79c) entered forwarding state
Oct 19 18:51:37 reaper audit[1657313]: NETFILTER_CFG table=filter family=2 entries=4 op=unregister pid=1657313 comm="kworker/u64:2"
Oct 19 18:51:37 reaper audit[1657313]: NETFILTER_CFG table=nat family=2 entries=15 op=unregister pid=1657313 comm="kworker/u64:2"
Oct 19 18:51:37 reaper kernel: kauditd_printk_skb: 2 callbacks suppressed
Oct 19 18:51:37 reaper kernel: audit: type=1325 audit(1603158697.333:476): table=filter family=2 entries=4 op=unregister pid=1657313 comm="kworker/u64:2"
Oct 19 18:51:37 reaper kernel: audit: type=1325 audit(1603158697.333:477): table=nat family=2 entries=15 op=unregister pid=1657313 comm="kworker/u64:2"

Last edited by warl0ck (2020-10-20 02:05:27)

Offline

#9 2020-10-20 02:09:37

warl0ck
Member
Registered: 2020-04-29
Posts: 47

Re: Intermittent wifi issues with NetworkManager

Upon further research, it seems like my issue might be related to docker-compose?

https://bbs.archlinux.org/viewtopic.php?id=247020
https://unix.stackexchange.com/question … k-on-linux

Here is the output of docker ps

CONTAINER ID        IMAGE                   COMMAND                  CREATED             STATUS              PORTS                                                                        NAMES
90dd39c3bd12        nginx                   "/docker-entrypoint.…"   8 weeks ago         Up 7 hours          0.0.0.0:9876->80/tcp                                                         docker_StartTree_1
0b9df8180519        telegraf                "/entrypoint.sh tele…"   2 months ago        Up 7 hours          8092/udp, 8125/udp, 8094/tcp, 0.0.0.0:8888->8888/tcp                         telegraf
9544914b54f9        sopelirc/sopel:latest   "/entrypoint.sh sopel"   2 months ago        Up 6 minutes                                                                                     sopel
5ec341fbfd57        linuxserver/syncthing   "/init"                  2 months ago        Up 7 hours          0.0.0.0:8384->8384/tcp, 0.0.0.0:22000->22000/tcp, 0.0.0.0:21027->21027/udp   syncthing

Could it be the sopel container? It seems to be the newest, it could be restarting itself?

Offline

#10 2020-10-20 13:14:22

seth
Member
Registered: 2012-09-03
Posts: 49,951

Re: Intermittent wifi issues with NetworkManager

Could also be the other way round (the container restarts for the network loss)
The journal starts w/ a virtual device being detached from the bridge device, then a lot of docker stuff, then a new  virtual device is setup.

The most interesting bits should be slightly ahead of the first posted line (ie. *why* all of this is happening)

But if everytime you encounter a network issue the same container just restarted, I'd say the container is at the root of this.

Offline

#11 2020-10-20 18:30:41

warl0ck
Member
Registered: 2020-04-29
Posts: 47

Re: Intermittent wifi issues with NetworkManager

Hmm, I've killed a few of the containers I no longer need, but I'm still getting the issue!

Here is the latest journal.

Oct 20 11:22:36 reaper nextcloud[2122]: 2020-10-20 11:22:36:789 [ debug default ]        [ OCC::PropagateUploadFileCommon::slotComputeContentChecksum ]:        Still trying to understand if this is the local file or the uploaded one
Oct 20 11:22:36 reaper nextcloud[2122]: 2020-10-20 11:22:36:806 [ debug default ]        [ OCC::PropagateUploadFileV1::startNextChunk ]:        Trying to upload "/home/me/School/CS/405/programs/assignment1/skyline.java"
Oct 20 11:22:36 reaper nextcloud[2122]: 2020-10-20 11:22:36:807 [ debug default ]        [ OCC::PropagateUploadFileV1::startNextChunk ]:        Trying to upload "/home/me/School/CS/405/homeworks/hw1/document/main.synctex.gz"
Oct 20 11:26:45 reaper NetworkManager[499]: <info>  [1603218405.8813] manager: NetworkManager state is now CONNECTED_SITE
Oct 20 11:26:45 reaper systemd[1]: Starting Network Manager Script Dispatcher Service...
Oct 20 11:26:45 reaper dbus-daemon[498]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.1' (uid=0 pid=499 comm="/usr/bin/NetworkManager --no-daemon ")
Oct 20 11:26:45 reaper dbus-daemon[498]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Oct 20 11:26:45 reaper systemd[1]: Started Network Manager Script Dispatcher Service.
Oct 20 11:26:45 reaper 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'
Oct 20 11:26:45 reaper kernel: audit: type=1130 audit(1603218405.899:595): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 20 11:26:56 reaper systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Oct 20 11:26:56 reaper 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'
Oct 20 11:26:56 reaper kernel: audit: type=1131 audit(1603218416.049:596): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 20 11:27:46 reaper NetworkManager[499]: <info>  [1603218466.8348] manager: NetworkManager state is now CONNECTED_GLOBAL
Oct 20 11:27:46 reaper dbus-daemon[498]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.1' (uid=0 pid=499 comm="/usr/bin/NetworkManager --no-daemon ")
Oct 20 11:27:46 reaper systemd[1]: Starting Network Manager Script Dispatcher Service...
Oct 20 11:27:46 reaper dbus-daemon[498]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Oct 20 11:27:46 reaper systemd[1]: Started Network Manager Script Dispatcher Service.
Oct 20 11:27:46 reaper 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'
Oct 20 11:27:46 reaper kernel: audit: type=1130 audit(1603218466.839:597): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 20 11:27:57 reaper systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Oct 20 11:27:57 reaper 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'
Oct 20 11:27:57 reaper kernel: audit: type=1131 audit(1603218477.046:598): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 20 11:28:01 reaper nextcloud[2122]: 2020-10-20 11:28:01:863 [ debug default ]        [ OCC::PropagateUploadFileCommon::startUploadFile ]:        Running the compute checksum
Oct 20 11:28:01 reaper nextcloud[2122]: 2020-10-20 11:28:01:863 [ debug default ]        [ OCC::PropagateUploadFileCommon::slotComputeContentChecksum ]:        Tryint to compute the checksum of the file

This output is sandwiched on either side by LOTS of nextcloud logs, which are basically always there as I am constantly editing synced files. This is the only activity during the error I could see

Last edited by warl0ck (2020-10-20 18:36:26)

Offline

#12 2020-10-20 18:35:32

seth
Member
Registered: 2012-09-03
Posts: 49,951

Re: Intermittent wifi issues with NetworkManager

The excerpt seems to show NM in the middle of a re-connection. It does not fully explain what's going on, let alone why.
Please post the entire journal, not some random fractions. You can use a pastebin service (the first link in my signature explains how to directly pipe the output of a command into one)

Offline

#13 2020-10-20 18:47:12

warl0ck
Member
Registered: 2020-04-29
Posts: 47

Re: Intermittent wifi issues with NetworkManager

Even my journal from this boot alone is too big to paste to ix.io, codepad, and xclip complains when I try and copy the whole thing... Let my see if I can get something working

Offline

#14 2020-10-20 18:49:35

seth
Member
Registered: 2012-09-03
Posts: 49,951

Re: Intermittent wifi issues with NetworkManager

You'll at least have to widen the timeframe significantly (an hour around the incident should certainly suffice)

Offline

#15 2020-10-20 19:00:55

warl0ck
Member
Registered: 2020-04-29
Posts: 47

Re: Intermittent wifi issues with NetworkManager

Ok, here is the entire journal starting from 10AM Local to right now (11:57 AM). The issues are happening at roughly 11:26

http://ix.io/2BoU

Offline

#16 2020-10-20 19:26:30

seth
Member
Registered: 2012-09-03
Posts: 49,951

Re: Intermittent wifi issues with NetworkManager

So, what's obvious is that nextcloud is going nuts over sync'ing a couple of files.
NM only logs the loss of the global connection ("no internet") which is resolved after a minute, but could be outside the system (eg. on the router)

I'd first fix the nextcloud situation.

Offline

#17 2020-10-20 21:38:23

warl0ck
Member
Registered: 2020-04-29
Posts: 47

Re: Intermittent wifi issues with NetworkManager

Interesting. I have absolutely no clue what is going on with NextCloud, it seems to be working as far as I can tell...

Looking through my logs shows it's failing to upload/sync a single file. I am getting the 423 LOCKED server response when I try to delete the file... Obviously this is outside the scope of this original thread but this keeps getting more and more frustrating!

I have no idea why/if this failure is causing my network outages, but I might as well clean the issue up anyways and clear out my journal...

Offline

#18 2020-10-20 21:52:42

warl0ck
Member
Registered: 2020-04-29
Posts: 47

Re: Intermittent wifi issues with NetworkManager

I disabled file locking serverside, in case anyone else comes across this issue, just add

'filelocking.enabled' => false,

to your config.php file. This allowed me to delete the file that was locked for some reason. I will closely monitor my logs, and next time I experience network issues I will paste the journal, hopefully free of NC messages.

Offline

#19 2020-10-22 02:23:03

warl0ck
Member
Registered: 2020-04-29
Posts: 47

Re: Intermittent wifi issues with NetworkManager

Still getting the issue today unfortunately. Both of them, though i'm not sure they're related. Nextcloud seems to have issues syncing files that are auto-generated as I am writing LaTeX on my machine. But the last log about that is at least an hour before my issues kicked in, which was roughly 19:10. It "fixed" at 19:19.

Oct 21 16:55:04 reaper kernel: hid-generic 0005:05AC:024F.0006: unknown main item tag 0x0
Oct 21 16:55:04 reaper kernel: input: RK-Bluetooth Keyboard as /devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0/bluetooth/hci0/hci0:70/0005:05AC:024F.0006/input/input23
Oct 21 16:55:04 reaper kernel: input: RK-Bluetooth Keyboard Consumer Control as /devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0/bluetooth/hci0/hci0:70/0005:05AC:024F.0006/input/input24
Oct 21 16:55:04 reaper kernel: input: RK-Bluetooth Keyboard System Control as /devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0/bluetooth/hci0/hci0:70/0005:05AC:024F.0006/input/input25
Oct 21 16:55:04 reaper kernel: hid-generic 0005:05AC:024F.0006: input,hidraw4: BLUETOOTH HID v1.1b Keyboard [RK-Bluetooth Keyboard] on 00:1a:7d:da:71:13
Oct 21 16:55:04 reaper systemd-logind[500]: Watching system buttons on /dev/input/event20 (RK-Bluetooth Keyboard)
Oct 21 16:55:04 reaper systemd-logind[500]: Watching system buttons on /dev/input/event22 (RK-Bluetooth Keyboard System Control)
Oct 21 16:55:04 reaper systemd-logind[500]: Watching system buttons on /dev/input/event21 (RK-Bluetooth Keyboard Consumer Control)
Oct 21 16:55:04 reaper upowerd[779]: treating change event as add on /sys/devices/pci0000:00/0000:00:01.2/0000:02:00.0/usb1/1-6/1-6:1.0/bluetooth/hci0/hci0:70/0005:05AC:024F.0006/power_supply/hid-dc:2c:26:db:ca:bb-battery
Oct 21 17:38:06 reaper wpa_supplicant[576]: wlp40s0: WPA: Group rekeying completed with 10:05:01:17:18:d8 [GTK=TKIP]
Oct 21 17:45:06 reaper kdeconnectd[2551]: QMetaObject::invokeMethod: No such method KIO::StoredTransferJob::slotDataReqFromDevice()
Oct 21 17:45:06 reaper kdeconnectd[2551]: QMetaObject::invokeMethod: No such method KIO::StoredTransferJob::slotDataReqFromDevice()
Oct 21 17:45:06 reaper kdeconnectd[2551]: QMetaObject::invokeMethod: No such method KIO::StoredTransferJob::slotDataReqFromDevice()
Oct 21 18:38:06 reaper wpa_supplicant[576]: wlp40s0: WPA: Group rekeying completed with 10:05:01:17:18:d8 [GTK=TKIP]
Oct 21 18:47:22 reaper kdeconnectd[2551]: QMetaObject::invokeMethod: No such method KIO::StoredTransferJob::slotDataReqFromDevice()
Oct 21 18:54:06 reaper kdeconnectd[2551]: QMetaObject::invokeMethod: No such method KIO::StoredTransferJob::slotDataReqFromDevice()
Oct 21 18:56:42 reaper wpa_supplicant[576]: wlp40s0: CTRL-EVENT-DISCONNECTED bssid=10:05:01:17:18:d8 reason=0
Oct 21 18:56:42 reaper wpa_supplicant[576]: wlp40s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Oct 21 18:56:42 reaper NetworkManager[499]: <info>  [1603331802.4210] device (wlp40s0): supplicant interface state: completed -> disconnected
Oct 21 18:56:42 reaper NetworkManager[499]: <info>  [1603331802.5197] device (wlp40s0): supplicant interface state: disconnected -> scanning
Oct 21 18:56:44 reaper wpa_supplicant[576]: wlp40s0: Trying to associate with 10:05:01:17:18:d8 (SSID='CBCI-F9B3-2.4' freq=2462 MHz)
Oct 21 18:56:44 reaper NetworkManager[499]: <info>  [1603331804.6357] device (wlp40s0): supplicant interface state: scanning -> associating
Oct 21 18:56:44 reaper wpa_supplicant[576]: wlp40s0: Associated with 10:05:01:17:18:d8
Oct 21 18:56:44 reaper wpa_supplicant[576]: wlp40s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Oct 21 18:56:44 reaper NetworkManager[499]: <info>  [1603331804.7229] device (wlp40s0): supplicant interface state: associating -> associated
Oct 21 18:56:44 reaper NetworkManager[499]: <info>  [1603331804.7414] device (wlp40s0): supplicant interface state: associated -> 4way_handshake
Oct 21 18:56:44 reaper wpa_supplicant[576]: wlp40s0: WPA: Key negotiation completed with 10:05:01:17:18:d8 [PTK=CCMP GTK=TKIP]
Oct 21 18:56:44 reaper wpa_supplicant[576]: wlp40s0: CTRL-EVENT-CONNECTED - Connection to 10:05:01:17:18:d8 completed [id=0 id_str=]
Oct 21 18:56:44 reaper wpa_supplicant[576]: bgscan simple: Failed to enable signal strength monitoring
Oct 21 18:56:44 reaper NetworkManager[499]: <info>  [1603331804.7592] device (wlp40s0): supplicant interface state: 4way_handshake -> completed
Oct 21 18:57:09 reaper wpa_supplicant[576]: wlp40s0: CTRL-EVENT-DISCONNECTED bssid=10:05:01:17:18:d8 reason=0
Oct 21 18:57:09 reaper wpa_supplicant[576]: wlp40s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Oct 21 18:57:09 reaper NetworkManager[499]: <info>  [1603331829.5932] device (wlp40s0): supplicant interface state: completed -> disconnected
Oct 21 18:57:09 reaper NetworkManager[499]: <info>  [1603331829.6930] device (wlp40s0): supplicant interface state: disconnected -> scanning
Oct 21 18:57:11 reaper wpa_supplicant[576]: wlp40s0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Oct 21 18:57:11 reaper wpa_supplicant[576]: wlp40s0: Trying to associate with 10:05:01:17:18:d8 (SSID='CBCI-F9B3-2.4' freq=2462 MHz)
Oct 21 18:57:11 reaper NetworkManager[499]: <info>  [1603331831.8098] device (wlp40s0): supplicant interface state: scanning -> associating
Oct 21 18:57:11 reaper wpa_supplicant[576]: wlp40s0: CTRL-EVENT-ASSOC-REJECT bssid=10:05:01:17:18:d8 status_code=16
Oct 21 18:57:11 reaper NetworkManager[499]: <info>  [1603331831.8527] device (wlp40s0): supplicant interface state: associating -> disconnected
Oct 21 18:57:12 reaper NetworkManager[499]: <info>  [1603331832.3534] device (wlp40s0): supplicant interface state: disconnected -> scanning
Oct 21 18:57:14 reaper wpa_supplicant[576]: wlp40s0: Trying to associate with 10:05:01:17:18:d8 (SSID='CBCI-F9B3-2.4' freq=2462 MHz)
Oct 21 18:57:14 reaper NetworkManager[499]: <info>  [1603331834.4961] device (wlp40s0): supplicant interface state: scanning -> associating
Oct 21 18:57:14 reaper wpa_supplicant[576]: wlp40s0: Associated with 10:05:01:17:18:d8
Oct 21 18:57:14 reaper wpa_supplicant[576]: wlp40s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Oct 21 18:57:14 reaper NetworkManager[499]: <info>  [1603331834.5683] device (wlp40s0): supplicant interface state: associating -> associated
Oct 21 18:57:14 reaper NetworkManager[499]: <info>  [1603331834.5888] device (wlp40s0): supplicant interface state: associated -> 4way_handshake
Oct 21 18:57:14 reaper wpa_supplicant[576]: wlp40s0: WPA: Key negotiation completed with 10:05:01:17:18:d8 [PTK=CCMP GTK=TKIP]
Oct 21 18:57:14 reaper wpa_supplicant[576]: wlp40s0: CTRL-EVENT-CONNECTED - Connection to 10:05:01:17:18:d8 completed [id=0 id_str=]
Oct 21 18:57:14 reaper wpa_supplicant[576]: bgscan simple: Failed to enable signal strength monitoring
Oct 21 18:57:14 reaper NetworkManager[499]: <info>  [1603331834.6056] device (wlp40s0): supplicant interface state: 4way_handshake -> completed
Oct 21 18:57:16 reaper kdeconnectd[2551]: kdeconnect.plugin.battery: No Primary Battery detected on this system. This may be a bug.
Oct 21 18:57:16 reaper kdeconnectd[2551]: kdeconnect.plugin.battery: Total quantity of batteries found:  1
Oct 21 18:57:27 reaper kdeconnectd[2551]: QMetaObject::invokeMethod: No such method KIO::StoredTransferJob::slotDataReqFromDevice()
Oct 21 19:09:34 reaper NetworkManager[499]: <info>  [1603332574.0141] manager: NetworkManager state is now CONNECTED_SITE
Oct 21 19:09:34 reaper systemd[1]: Starting Network Manager Script Dispatcher Service...
Oct 21 19:09:34 reaper dbus-daemon[494]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.1' (uid=0 pid=499 comm="/usr/bin/NetworkManager --no-daemon ")
Oct 21 19:09:34 reaper dbus-daemon[494]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Oct 21 19:09:34 reaper systemd[1]: Started Network Manager Script Dispatcher Service.
Oct 21 19:09:34 reaper 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'
Oct 21 19:09:34 reaper kernel: audit: type=1130 audit(1603332574.018:282): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 21 19:09:44 reaper systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Oct 21 19:09:44 reaper 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'
Oct 21 19:09:44 reaper kernel: audit: type=1131 audit(1603332584.052:283): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 21 19:14:58 reaper nextcloud[2062]: 2020-10-21 19:14:58:161 [ debug nextcloud.gui.systray ]        [ OCC::Systray::computeWindowReferencePoint ]:        screenRect: QRect(4480,0 1080x1920)
Oct 21 19:14:58 reaper nextcloud[2062]: 2020-10-21 19:14:58:161 [ debug nextcloud.gui.systray ]        [ OCC::Systray::computeWindowReferencePoint ]:        taskbarRect: QRect(0,0 1080x32)
Oct 21 19:14:58 reaper nextcloud[2062]: 2020-10-21 19:14:58:161 [ debug nextcloud.gui.systray ]        [ OCC::Systray::computeWindowReferencePoint ]:        taskbarScreenEdge: OCC::Systray::TaskBarPosition::Top
Oct 21 19:14:58 reaper nextcloud[2062]: 2020-10-21 19:14:58:161 [ debug nextcloud.gui.systray ]        [ OCC::Systray::computeWindowReferencePoint ]:        trayIconCenter: QPoint(5473,16)
Oct 21 19:14:58 reaper nextcloud[2062]: 2020-10-21 19:14:58:161 [ debug nextcloud.gui.systray ]        [ OCC::Systray::computeWindowPosition ]:        taskbarScreenEdge: OCC::Systray::TaskBarPosition::Top
Oct 21 19:14:58 reaper nextcloud[2062]: 2020-10-21 19:14:58:161 [ debug nextcloud.gui.systray ]        [ OCC::Systray::computeWindowPosition ]:        screenRect: QRect(4480,0 1080x1920)
Oct 21 19:14:58 reaper nextcloud[2062]: 2020-10-21 19:14:58:161 [ debug nextcloud.gui.systray ]        [ OCC::Systray::computeWindowPosition ]:        windowRect (reference) QRect(5273,36 401x511)
Oct 21 19:14:58 reaper nextcloud[2062]: 2020-10-21 19:14:58:161 [ debug nextcloud.gui.systray ]        [ OCC::Systray::computeWindowPosition ]:        windowRect (adjusted ) QRect(5155,36 401x511)
Oct 21 19:18:04 reaper NetworkManager[499]: <info>  [1603333084.4093] manager: NetworkManager state is now CONNECTED_GLOBAL
Oct 21 19:18:04 reaper kernel: audit: type=1130 audit(1603333084.412:284): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 21 19:18:04 reaper 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'
Oct 21 19:18:04 reaper dbus-daemon[494]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.1' (uid=0 pid=499 comm="/usr/bin/NetworkManager --no-daemon ")
Oct 21 19:18:04 reaper systemd[1]: Starting Network Manager Script Dispatcher Service...
Oct 21 19:18:04 reaper dbus-daemon[494]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Oct 21 19:18:04 reaper systemd[1]: Started Network Manager Script Dispatcher Service.
Oct 21 19:18:15 reaper systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Oct 21 19:18:15 reaper 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'

Offline

#20 2020-10-22 20:34:08

seth
Member
Registered: 2012-09-03
Posts: 49,951

Re: Intermittent wifi issues with NetworkManager

NM just pretty much suddenly moves to CONNECTED_SITE.
Can you test whether this also happens w/ a wired connection? (Just temporarily to figure whether this is WIFI related at all)
Do you have a bluetooth device? Or other wifi devices nearby that could interfere?
Can you access the router/AP logs to check whether it cut the connection?
Can you test the speed on the LAN (eg. wget a file from another device in the subnet)?

Offline

#21 2020-10-22 20:49:44

warl0ck
Member
Registered: 2020-04-29
Posts: 47

Re: Intermittent wifi issues with NetworkManager

To answer your questions:

* I will try to move to a wired connection soon, i'll probably have time this weekend
* I do have a bluetooth device, an RK 61 bluetooth keyboard. It is annoying as it refuses to stay connected to the bluetooth if I leave it alone for 20+ minutes.
* I also have the Broadcom BCM4360 chipset, which is known to have issues. I might try tearing the wifi card out of my old build, which didn't have these issues. (it had other network issues but i digress)
* I'll poke around the awful comcast router interface and see if I can find logs. Although I have no reason to see why this machine in particular (out of lots of devices, a few of which are running Arch as well) would be blocked.
* I have run multiple speedtests on other devices and haven't seen any slowdowns.

Lines like this makes me think it's something to do with the card

Oct 22 13:32:08 reaper NetworkManager[499]: <warn>  [1603398728.8780] dhcp6 (wlp40s0): request timed out                                                                                │
Oct 22 13:32:08 reaper NetworkManager[499]: <info>  [1603398728.8781] dhcp6 (wlp40s0): state changed unknown -> timeout                                                                 │

Offline

Board footer

Powered by FluxBB