You are not logged in.

#1 2019-01-14 15:59:46

kingelilol
Member
Registered: 2019-01-11
Posts: 2

Wifi connection drops

My wifi connection at different networks drops in non periodic time steps and won't recontact automatically. "iwconfig" shows the Network as connected, but i can't ping 8.8.8.8 .
I use a "Intel Corporation Wireless 3165", NetworkManager and dhcpcd I also tried using dhclient.
I have no clue what the problem is. Do You have any ideas?

In this example I needed to reconnect manually at approximately 17:30 because my ping failed since 17:27
The journal:

Jan 12 17:09:44 elias-hp wpa_supplicant[532]: wlo1: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-54 noise=9999 txrate=150000
Jan 12 17:22:50 elias-hp dbus-daemon[451]: [system] Activating via systemd: service name='org.freedesktop.UPower' unit='upower.service' requested by ':1.6379' (uid=1000 pid=18587 comm="/usr/lib/firefox-developer-edition/firefox ")
Jan 12 17:22:50 elias-hp systemd[1]: Starting Daemon for power management...
Jan 12 17:22:50 elias-hp dbus-daemon[451]: [system] Successfully activated service 'org.freedesktop.UPower'
Jan 12 17:22:50 elias-hp systemd[1]: Started Daemon for power management.
Jan 12 17:22:50 elias-hp audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=upower comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 12 17:22:50 elias-hp kernel: audit: type=1130 audit(1547310170.216:108): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=upower comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 12 17:31:30 elias-hp NetworkManager[452]: <info>  [1547310690.1140] manager: NetworkManager state is now CONNECTED_SITE
Jan 12 17:31:30 elias-hp dbus-daemon[451]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.1' (uid=0 pid=452 comm="/usr/bin/NetworkManager --no-daemon ")
Jan 12 17:31:30 elias-hp systemd[1]: Starting Network Manager Script Dispatcher Service...
Jan 12 17:31:30 elias-hp dbus-daemon[451]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jan 12 17:31:30 elias-hp systemd[1]: Started Network Manager Script Dispatcher Service.
Jan 12 17:31:30 elias-hp audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 12 17:31:30 elias-hp nm-dispatcher[9755]: req:1 'connectivity-change': new request (0 scripts)
Jan 12 17:31:30 elias-hp kernel: audit: type=1130 audit(1547310690.139:109): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 12 17:31:30 elias-hp nm-dispatcher[9755]: req:1 'connectivity-change': completed: no scripts
Jan 12 17:31:40 elias-hp audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 12 17:31:40 elias-hp kernel: audit: type=1131 audit(1547310700.066:110): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 12 17:32:42 elias-hp dbus-daemon[599]: [session uid=1000 pid=599] Activating via systemd: service name='org.gtk.vfs.Metadata' unit='gvfs-metadata.service' requested by ':1.67' (uid=1000 pid=10159 comm="gedit ")
Jan 12 17:32:42 elias-hp systemd[582]: Starting Virtual filesystem metadata service...
Jan 12 17:32:42 elias-hp dbus-daemon[599]: [session uid=1000 pid=599] Successfully activated service 'org.gtk.vfs.Metadata'
Jan 12 17:32:42 elias-hp systemd[582]: Started Virtual filesystem metadata service.
Jan 12 17:32:48 elias-hp NetworkManager[452]: <info>  [1547310768.7234] device (wlo1): state change: activated -> deactivating (reason 'user-requested', sys-iface-state: 'managed')
Jan 12 17:32:48 elias-hp NetworkManager[452]: <info>  [1547310768.7242] manager: NetworkManager state is now DISCONNECTING
Jan 12 17:32:48 elias-hp audit[452]: USYS_CONFIG pid=452 uid=0 auid=4294967295 ses=4294967295 msg='op=connection-deactivate uuid=16026217-4a7d-4bb7-b93d-7452d15c4dad name="H1a6rr3r" pid=10372 uid=1000 result=success exe="/usr/bin/NetworkManager" hostname=? addr=? terminal=? res=success'
Jan 12 17:32:48 elias-hp NetworkManager[452]: <info>  [1547310768.7776] audit: op="connection-deactivate" uuid="16026217-4a7d-4bb7-b93d-7452d15c4dad" name="H1a6rr3r" pid=10372 uid=1000 result="success"
Jan 12 17:32:48 elias-hp NetworkManager[452]: <info>  [1547310768.7779] device (wlo1): state change: deactivating -> disconnected (reason 'user-requested', sys-iface-state: 'managed')
Jan 12 17:32:48 elias-hp dhcpcd[2343]: received SIGTERM, stopping
Jan 12 17:32:48 elias-hp kernel: audit: type=1111 audit(1547310768.773:111): pid=452 uid=0 auid=4294967295 ses=4294967295 msg='op=connection-deactivate uuid=16026217-4a7d-4bb7-b93d-7452d15c4dad name="H1a6rr3r" pid=10372 uid=1000 result=success exe="/usr/bin/NetworkManager" hostname=? addr=? terminal=? res=success'
Jan 12 17:32:48 elias-hp dhcpcd[2343]: wlo1: removing interface
Jan 12 17:32:49 elias-hp NetworkManager[452]: <info>  [1547310769.3230] dhcp4 (wlo1): canceled DHCP transaction, DHCP client pid 2343
Jan 12 17:32:49 elias-hp NetworkManager[452]: <info>  [1547310769.3230] dhcp4 (wlo1): state changed bound -> done
Jan 12 17:32:49 elias-hp kernel: wlo1: deauthenticating from f4:f2:6d:a5:52:a2 by local choice (Reason: 3=DEAUTH_LEAVING)
Jan 12 17:32:49 elias-hp wpa_supplicant[532]: wlo1: CTRL-EVENT-DISCONNECTED bssid=f4:f2:6d:a5:52:a2 reason=3 locally_generated=1
Jan 12 17:32:49 elias-hp wpa_supplicant[532]: wlo1: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=0 noise=9999 txrate=0
Jan 12 17:32:49 elias-hp NetworkManager[452]: <info>  [1547310769.3441] device (wlo1): set-hw-addr: set MAC address to 62:34:5D:AE:69:52 (scanning)
Jan 12 17:32:49 elias-hp kernel: IPv6: ADDRCONF(NETDEV_UP): wlo1: link is not ready
Jan 12 17:32:49 elias-hp NetworkManager[452]: <info>  [1547310769.3561] manager: NetworkManager state is now DISCONNECTED
Jan 12 17:32:49 elias-hp NetworkManager[452]: <warn>  [1547310769.3608] sup-iface[0x55c1608608d0,wlo1]: connection disconnected (reason -3)
Jan 12 17:32:49 elias-hp NetworkManager[452]: <info>  [1547310769.3611] device (wlo1): supplicant interface state: completed -> disconnected
Jan 12 17:32:49 elias-hp NetworkManager[452]: <warn>  [1547310769.3627] dhcp-listener: dhcp-event: (pid 2343) unhandled DHCP event for interface wlo1
Jan 12 17:32:49 elias-hp dbus-daemon[451]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.1' (uid=0 pid=452 comm="/usr/bin/NetworkManager --no-daemon ")
Jan 12 17:32:49 elias-hp systemd[1]: Starting Network Manager Script Dispatcher Service...
Jan 12 17:32:49 elias-hp dbus-daemon[451]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jan 12 17:32:49 elias-hp systemd[1]: Started Network Manager Script Dispatcher Service.
Jan 12 17:32:49 elias-hp audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 12 17:32:49 elias-hp nm-dispatcher[10408]: req:1 'down' [wlo1]: new request (0 scripts)
Jan 12 17:32:49 elias-hp nm-dispatcher[10408]: req:1 'down' [wlo1]: completed: no scripts
Jan 12 17:32:49 elias-hp kernel: audit: type=1130 audit(1547310769.383:112): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 12 17:32:50 elias-hp wpa_supplicant[532]: wlo1: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=WORLD
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8572] agent-manager: req[0x55c16093c390, :1.6733/nmtui/1000]: agent registered
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8695] device (wlo1): Activation: starting connection 'H1a6rr3r' (16026217-4a7d-4bb7-b93d-7452d15c4dad)
Jan 12 17:32:51 elias-hp audit[452]: USYS_CONFIG pid=452 uid=0 auid=4294967295 ses=4294967295 msg='op=connection-activate uuid=16026217-4a7d-4bb7-b93d-7452d15c4dad name="H1a6rr3r" pid=10372 uid=1000 result=success exe="/usr/bin/NetworkManager" hostname=? addr=? terminal=? res=success'
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8698] audit: op="connection-activate" uuid="16026217-4a7d-4bb7-b93d-7452d15c4dad" name="H1a6rr3r" pid=10372 uid=1000 result="success"
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8701] device (wlo1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8710] manager: NetworkManager state is now CONNECTING
Jan 12 17:32:51 elias-hp kernel: audit: type=1111 audit(1547310771.866:113): pid=452 uid=0 auid=4294967295 ses=4294967295 msg='op=connection-activate uuid=16026217-4a7d-4bb7-b93d-7452d15c4dad name="H1a6rr3r" pid=10372 uid=1000 result=success exe="/usr/bin/NetworkManager" hostname=? addr=? terminal=? res=success'
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8742] device (wlo1): set-hw-addr: set-cloned MAC address to 16:73:FB:73:0F:C5 (random)
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8784] device (wlo1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jan 12 17:32:51 elias-hp kernel: IPv6: ADDRCONF(NETDEV_UP): wlo1: link is not ready
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8793] device (wlo1): Activation: (wifi) access point 'H1a6rr3r' has security, but secrets are required.
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8793] device (wlo1): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8861] device (wlo1): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8873] device (wlo1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8885] device (wlo1): Activation: (wifi) connection 'H1a6rr3r' has security, and secrets exist.  No new secrets needed.
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8886] Config: added 'ssid' value 'H1a6rr3r'
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8886] Config: added 'scan_ssid' value '1'
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8887] Config: added 'bgscan' value 'simple:30:-80:86400'
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8887] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256'
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8888] Config: added 'psk' value '<hidden>'
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.8888] Config: added 'ieee80211w' value '1'
Jan 12 17:32:51 elias-hp NetworkManager[452]: <info>  [1547310771.9604] device (wlo1): supplicant interface state: disconnected -> scanning
Jan 12 17:32:55 elias-hp wpa_supplicant[532]: wlo1: CTRL-EVENT-REGDOM-CHANGE init=DRIVER type=COUNTRY alpha2=DE
Jan 12 17:32:55 elias-hp wpa_supplicant[532]: wlo1: SME: Trying to authenticate with f4:f2:6d:a5:52:a2 (SSID='H1a6rr3r' freq=2412 MHz)
Jan 12 17:32:55 elias-hp kernel: wlo1: authenticate with f4:f2:6d:a5:52:a2
Jan 12 17:32:55 elias-hp kernel: wlo1: send auth to f4:f2:6d:a5:52:a2 (try 1/3)
Jan 12 17:32:55 elias-hp wpa_supplicant[532]: wlo1: Trying to associate with f4:f2:6d:a5:52:a2 (SSID='H1a6rr3r' freq=2412 MHz)
Jan 12 17:32:55 elias-hp NetworkManager[452]: <info>  [1547310775.4681] device (wlo1): supplicant interface state: scanning -> authenticating
Jan 12 17:32:55 elias-hp kernel: wlo1: authenticated
Jan 12 17:32:55 elias-hp kernel: wlo1: associate with f4:f2:6d:a5:52:a2 (try 1/3)
Jan 12 17:32:55 elias-hp NetworkManager[452]: <info>  [1547310775.4729] device (wlo1): supplicant interface state: authenticating -> associating
Jan 12 17:32:55 elias-hp kernel: wlo1: RX AssocResp from f4:f2:6d:a5:52:a2 (capab=0x31 status=0 aid=9)
Jan 12 17:32:55 elias-hp wpa_supplicant[532]: wlo1: Associated with f4:f2:6d:a5:52:a2
Jan 12 17:32:55 elias-hp wpa_supplicant[532]: wlo1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jan 12 17:32:55 elias-hp kernel: wlo1: associated
Jan 12 17:32:55 elias-hp NetworkManager[452]: <info>  [1547310775.4914] device (wlo1): supplicant interface state: associating -> 4-way handshake
Jan 12 17:32:55 elias-hp wpa_supplicant[532]: wlo1: RSC 0000000000770000 is likely bogus, using 0
Jan 12 17:32:55 elias-hp wpa_supplicant[532]: wlo1: WPA: Key negotiation completed with f4:f2:6d:a5:52:a2 [PTK=CCMP GTK=TKIP]
Jan 12 17:32:55 elias-hp wpa_supplicant[532]: wlo1: CTRL-EVENT-CONNECTED - Connection to f4:f2:6d:a5:52:a2 completed [id=0 id_str=]
Jan 12 17:32:55 elias-hp kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlo1: link becomes ready
Jan 12 17:32:55 elias-hp wpa_supplicant[532]: wlo1: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=-57 noise=9999 txrate=1000
Jan 12 17:32:55 elias-hp NetworkManager[452]: <info>  [1547310775.4989] device (wlo1): supplicant interface state: 4-way handshake -> completed
Jan 12 17:32:55 elias-hp NetworkManager[452]: <info>  [1547310775.4989] device (wlo1): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "H1a6rr3r"
Jan 12 17:32:55 elias-hp NetworkManager[452]: <info>  [1547310775.4992] device (wlo1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Jan 12 17:32:55 elias-hp NetworkManager[452]: <info>  [1547310775.4998] dhcp4 (wlo1): activation: beginning transaction (timeout in 45 seconds)
Jan 12 17:32:55 elias-hp NetworkManager[452]: <info>  [1547310775.5015] dhcp4 (wlo1): dhcpcd started with pid 10449
Jan 12 17:32:55 elias-hp dhcpcd[10449]: DUID 00:04:00:93:8c:34:99:c6:11:e8:9c:7e:74:40:bb:e2:c3:55
Jan 12 17:32:55 elias-hp dhcpcd[10449]: wlo1: IAID fb:73:0f:c5
Jan 12 17:32:55 elias-hp wpa_supplicant[532]: wlo1: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-58 noise=9999 txrate=150000
Jan 12 17:32:55 elias-hp dhcpcd[10449]: wlo1: rebinding lease of 192.168.1.216
Jan 12 17:32:56 elias-hp dhcpcd[10449]: wlo1: NAK: address in use from 192.168.1.1
Jan 12 17:32:56 elias-hp dhcpcd[10449]: wlo1: message: address in use
Jan 12 17:32:56 elias-hp NetworkManager[452]: <info>  [1547310776.0565] dhcp4 (wlo1): state changed unknown -> expire
Jan 12 17:32:56 elias-hp dhcpcd[10449]: wlo1: soliciting a DHCP lease
Jan 12 17:32:58 elias-hp dhcpcd[10449]: wlo1: offered 192.168.1.109 from 192.168.1.1
Jan 12 17:32:58 elias-hp dhcpcd[10449]: wlo1: leased 192.168.1.109 for 86400 seconds
Jan 12 17:32:58 elias-hp dhcpcd[10449]: wlo1: adding route to 192.168.1.0/24
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.8783] dhcp4 (wlo1):   address 192.168.1.109
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.8783] dhcp4 (wlo1):   plen 24 (255.255.255.0)
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.8784] dhcp4 (wlo1):   gateway 192.168.1.1
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.8785] dhcp4 (wlo1):   lease time 86400
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.8785] dhcp4 (wlo1):   hostname 'elias-hp'
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.8786] dhcp4 (wlo1):   nameserver '192.168.1.1'
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.8786] dhcp4 (wlo1): state changed expire -> bound
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.8814] device (wlo1): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.8836] device (wlo1): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.8842] device (wlo1): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.8853] manager: NetworkManager state is now CONNECTED_LOCAL
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.8970] manager: NetworkManager state is now CONNECTED_SITE
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.8974] policy: set 'H1a6rr3r' (wlo1) as default for IPv4 routing and DNS
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.9010] device (wlo1): Activation: successful, device activated.
Jan 12 17:32:58 elias-hp nm-dispatcher[10408]: req:2 'up' [wlo1]: new request (0 scripts)
Jan 12 17:32:58 elias-hp nm-dispatcher[10408]: req:2 'up' [wlo1]: completed: no scripts
Jan 12 17:32:58 elias-hp NetworkManager[452]: <info>  [1547310778.9906] manager: NetworkManager state is now CONNECTED_GLOBAL
Jan 12 17:32:58 elias-hp nm-dispatcher[10408]: req:3 'connectivity-change': new request (0 scripts)
Jan 12 17:32:58 elias-hp nm-dispatcher[10408]: req:3 'connectivity-change': completed: no scripts
Jan 12 17:33:00 elias-hp audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jan 12 17:33:00 elias-hp kernel: audit: type=1131 audit(1547310780.059:114): 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

#2 2019-01-14 16:04:34

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

Re: Wifi connection drops

NetworkManager and dhcpcd

Yeah, that's the problem.
dhcpcd & dhcp4  from NM try to operate on wlo1 and get into a race.
Pick ONE network managing service per interface.

For a sanity check:

systemctl list-unit-files --state=enabled

Online

#3 2019-01-15 10:39:56

kingelilol
Member
Registered: 2019-01-11
Posts: 2

Re: Wifi connection drops

I'm sorry this was a bit unclear I have dhcpcd in my NetworkManager config

# Configuration file for NetworkManager.
# See "man 5 NetworkManager.conf" for details.

[main]
dhcp=dhcpcd

And "systemctl list-unit-files --state=enabled" outputs

UNIT FILE                                   STATE  
autovt@.service                             enabled
dbus-org.freedesktop.NetworkManager.service enabled
dbus-org.freedesktop.nm-dispatcher.service  enabled
display-manager.service                     enabled
getty@.service                              enabled
lightdm.service                             enabled
NetworkManager-dispatcher.service           enabled
NetworkManager-wait-online.service          enabled
NetworkManager.service                      enabled
remote-fs.target                            enabled

Offline

Board footer

Powered by FluxBB