You are not logged in.
Hello Arch-Community!
I run a Dell Vostro V131 and a Dell XPS 13 with an up to date Arch Linux with Gnome Shell as desktop enviroment. My internet connection is established by an AVM Fritzbox 7490 over wireless lan. The WLAN connection works with a windows pc and my android devices absolutely stable. As soon I get into the reach of wlan, my smartphone connects without any issues.
With Arch I've got the following problem: After a restart or standby mode, the NetworkManager tries to connect to my wireless lan. After about 30 seconds it fails and the icon vanishes out of the notification bar. After a short moment it tries again, it fails, it tries, it fails... until it magically works and the computer is logged into my wireless network.
To get some logs i killed the NetworkManager task and restarted it by hand. In the following log you see NetworkManager starting and failing a couple of logins with the message "association took too long, failing activation". At the end NetworkManager reports "Activation (wlp18s0) successful, device activated" and wlan works... I didn't change a thing.
I would be very thankful if someone might be able to help me figure out what might go wrong.
Thanks!
Christoph
$ sudo /usr/bin/NetworkManager --no-daemon
NetworkManager[3251]: <info> NetworkManager (version 0.9.8.10) is starting...
NetworkManager[3251]: <info> Read config file /etc/NetworkManager/NetworkManager.conf
NetworkManager[3251]: <info> WEXT support is enabled
NetworkManager[3251]: <info> VPN: loaded org.freedesktop.NetworkManager.openvpn
NetworkManager[3251]: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc. To report bugs please use the NetworkManager mailing list.
NetworkManager[3251]: keyfile: parsing KIT-OPENVPN ...
NetworkManager[3251]: keyfile: read connection 'KIT-OPENVPN'
NetworkManager[3251]: keyfile: parsing Littlehelper ...
NetworkManager[3251]: keyfile: read connection 'Littlehelper'
NetworkManager[3251]: keyfile: parsing Santa ...
NetworkManager[3251]: keyfile: read connection 'Santa'
NetworkManager[3251]: <info> monitoring kernel firmware directory '/lib/firmware'.
NetworkManager[3251]: <info> rfkill0: found WiFi radio killswitch (at /sys/devices/pci0000:00/0000:00:1c.1/0000:12:00.0/ieee80211/phy0/rfkill0) (driver ath9k)
NetworkManager[3251]: <info> WiFi hardware radio set enabled
NetworkManager[3251]: <info> WiFi enabled by radio killswitch; enabled by state file
NetworkManager[3251]: <info> WWAN enabled by radio killswitch; enabled by state file
NetworkManager[3251]: <info> WiMAX enabled by radio killswitch; enabled by state file
NetworkManager[3251]: <info> Networking is enabled by state file
NetworkManager[3251]: <info> (wlp18s0): using nl80211 for WiFi device control
NetworkManager[3251]: <info> (wlp18s0): driver supports Access Point (AP) mode
NetworkManager[3251]: <info> (wlp18s0): new 802.11 WiFi device (driver: 'ath9k' ifindex: 4)
NetworkManager[3251]: <info> (wlp18s0): exported as /org/freedesktop/NetworkManager/Devices/0
NetworkManager[3251]: <info> (wlp18s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[3251]: <info> (wlp18s0): bringing up device.
NetworkManager[3251]: <info> (wlp18s0): preparing device.
NetworkManager[3251]: <info> (wlp18s0): deactivating device (reason 'managed') [2]
NetworkManager[3251]: <warn> failed to allocate link cache: (-26) Protocol mismatch
NetworkManager[3251]: <info> (enp19s0): carrier is OFF
NetworkManager[3251]: <info> (enp19s0): new Ethernet device (driver: 'r8169' ifindex: 2)
NetworkManager[3251]: <info> (enp19s0): exported as /org/freedesktop/NetworkManager/Devices/1
NetworkManager[3251]: <info> (enp19s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[3251]: <info> (enp19s0): bringing up device.
NetworkManager[3251]: <info> (enp19s0): preparing device.
NetworkManager[3251]: <info> (enp19s0): deactivating device (reason 'managed') [2]
NetworkManager[3251]: <info> Added default wired connection 'Kabelgebundene Verbindung 1' for /sys/devices/pci0000:00/0000:00:1c.2/0000:13:00.0/net/enp19s0
NetworkManager[3251]: <warn> /sys/devices/virtual/net/lo: couldn't determine device driver; ignoring...
NetworkManager[3251]: <warn> /sys/devices/virtual/net/lo: couldn't determine device driver; ignoring...
NetworkManager[3251]: <info> (wlp18s0) supports 4 scan SSIDs
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: starting -> ready
NetworkManager[3251]: <info> (wlp18s0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
NetworkManager[3251]: <warn> Trying to remove a non-existant call id.
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: ready -> disconnected
NetworkManager[3251]: <info> (wlp18s0) supports 4 scan SSIDs
NetworkManager[3251]: <info> Auto-activating connection 'Santa'.
NetworkManager[3251]: <info> Activation (wlp18s0) starting connection 'Santa'
NetworkManager[3251]: <info> (wlp18s0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
NetworkManager[3251]: <info> NetworkManager state is now CONNECTING
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) started...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) starting...
NetworkManager[3251]: <info> (wlp18s0): device state change: prepare -> config (reason 'none') [40 50 0]
NetworkManager[3251]: <info> Activation (wlp18s0/wireless): connection 'Santa' has security, and secrets exist. No new secrets needed.
NetworkManager[3251]: <info> Config: added 'ssid' value 'Santa'
NetworkManager[3251]: <info> Config: added 'scan_ssid' value '1'
NetworkManager[3251]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
NetworkManager[3251]: <info> Config: added 'auth_alg' value 'OPEN'
NetworkManager[3251]: <info> Config: added 'psk' value '<omitted>'
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) complete.
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: disconnected -> inactive
NetworkManager[3251]: <info> Config: set interface ap_scan to 1
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: inactive -> authenticating
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: authenticating -> disconnected
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: disconnected -> scanning
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: scanning -> authenticating
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: authenticating -> disconnected
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: disconnected -> scanning
NetworkManager[3251]: <warn> Activation (wlp18s0/wireless): association took too long, failing activation.
NetworkManager[3251]: <info> (wlp18s0): device state change: config -> failed (reason 'SSID not found') [50 120 53]
NetworkManager[3251]: <info> NetworkManager state is now DISCONNECTED
NetworkManager[3251]: <warn> Activation (wlp18s0) failed for connection 'Santa'
NetworkManager[3251]: <info> (wlp18s0): device state change: failed -> disconnected (reason 'none') [120 30 0]
NetworkManager[3251]: <info> (wlp18s0): deactivating device (reason 'none') [0]
NetworkManager[3251]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
NetworkManager[3251]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: scanning -> inactive
NetworkManager[3251]: <info> Auto-activating connection 'Santa'.
NetworkManager[3251]: <info> Activation (wlp18s0) starting connection 'Santa'
NetworkManager[3251]: <info> (wlp18s0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
NetworkManager[3251]: <info> NetworkManager state is now CONNECTING
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) started...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) starting...
NetworkManager[3251]: <info> (wlp18s0): device state change: prepare -> config (reason 'none') [40 50 0]
NetworkManager[3251]: <info> Activation (wlp18s0/wireless): access point 'Santa' has security, but secrets are required.
NetworkManager[3251]: <info> (wlp18s0): device state change: config -> need-auth (reason 'none') [50 60 0]
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) complete.
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) started...
NetworkManager[3251]: <info> (wlp18s0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) starting...
NetworkManager[3251]: <info> (wlp18s0): device state change: prepare -> config (reason 'none') [40 50 0]
NetworkManager[3251]: <info> Activation (wlp18s0/wireless): connection 'Santa' has security, and secrets exist. No new secrets needed.
NetworkManager[3251]: <info> Config: added 'ssid' value 'Santa'
NetworkManager[3251]: <info> Config: added 'scan_ssid' value '1'
NetworkManager[3251]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
NetworkManager[3251]: <info> Config: added 'auth_alg' value 'OPEN'
NetworkManager[3251]: <info> Config: added 'psk' value '<omitted>'
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) complete.
NetworkManager[3251]: <info> Config: set interface ap_scan to 1
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: inactive -> authenticating
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: authenticating -> disconnected
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: disconnected -> scanning
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: scanning -> authenticating
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: authenticating -> disconnected
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: disconnected -> scanning
NetworkManager[3251]: <warn> Activation (wlp18s0/wireless): association took too long, failing activation.
NetworkManager[3251]: <info> (wlp18s0): device state change: config -> failed (reason 'SSID not found') [50 120 53]
NetworkManager[3251]: <info> NetworkManager state is now DISCONNECTED
NetworkManager[3251]: <warn> Activation (wlp18s0) failed for connection 'Santa'
NetworkManager[3251]: <info> (wlp18s0): device state change: failed -> disconnected (reason 'none') [120 30 0]
NetworkManager[3251]: <info> (wlp18s0): deactivating device (reason 'none') [0]
NetworkManager[3251]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
NetworkManager[3251]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: scanning -> inactive
NetworkManager[3251]: <info> Auto-activating connection 'Santa'.
NetworkManager[3251]: <info> Activation (wlp18s0) starting connection 'Santa'
NetworkManager[3251]: <info> (wlp18s0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
NetworkManager[3251]: <info> NetworkManager state is now CONNECTING
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) started...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) starting...
NetworkManager[3251]: <info> (wlp18s0): device state change: prepare -> config (reason 'none') [40 50 0]
NetworkManager[3251]: <info> Activation (wlp18s0/wireless): access point 'Santa' has security, but secrets are required.
NetworkManager[3251]: <info> (wlp18s0): device state change: config -> need-auth (reason 'none') [50 60 0]
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) complete.
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) started...
NetworkManager[3251]: <info> (wlp18s0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) starting...
NetworkManager[3251]: <info> (wlp18s0): device state change: prepare -> config (reason 'none') [40 50 0]
NetworkManager[3251]: <info> Activation (wlp18s0/wireless): connection 'Santa' has security, and secrets exist. No new secrets needed.
NetworkManager[3251]: <info> Config: added 'ssid' value 'Santa'
NetworkManager[3251]: <info> Config: added 'scan_ssid' value '1'
NetworkManager[3251]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
NetworkManager[3251]: <info> Config: added 'auth_alg' value 'OPEN'
NetworkManager[3251]: <info> Config: added 'psk' value '<omitted>'
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) complete.
NetworkManager[3251]: <info> Config: set interface ap_scan to 1
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: inactive -> authenticating
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: authenticating -> disconnected
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: disconnected -> scanning
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: scanning -> authenticating
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: authenticating -> disconnected
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: disconnected -> scanning
NetworkManager[3251]: <warn> Activation (wlp18s0/wireless): association took too long, failing activation.
NetworkManager[3251]: <info> (wlp18s0): device state change: config -> failed (reason 'SSID not found') [50 120 53]
NetworkManager[3251]: <info> NetworkManager state is now DISCONNECTED
NetworkManager[3251]: <warn> Activation (wlp18s0) failed for connection 'Santa'
NetworkManager[3251]: <info> (wlp18s0): device state change: failed -> disconnected (reason 'none') [120 30 0]
NetworkManager[3251]: <info> (wlp18s0): deactivating device (reason 'none') [0]
NetworkManager[3251]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
NetworkManager[3251]: <warn> Couldn't disconnect supplicant interface: This interface is not connected.
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: scanning -> inactive
NetworkManager[3251]: <info> Auto-activating connection 'Santa'.
NetworkManager[3251]: <info> Activation (wlp18s0) starting connection 'Santa'
NetworkManager[3251]: <info> (wlp18s0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
NetworkManager[3251]: <info> NetworkManager state is now CONNECTING
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) started...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) starting...
NetworkManager[3251]: <info> (wlp18s0): device state change: prepare -> config (reason 'none') [40 50 0]
NetworkManager[3251]: <info> Activation (wlp18s0/wireless): access point 'Santa' has security, but secrets are required.
NetworkManager[3251]: <info> (wlp18s0): device state change: config -> need-auth (reason 'none') [50 60 0]
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) complete.
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) started...
NetworkManager[3251]: <info> (wlp18s0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) starting...
NetworkManager[3251]: <info> (wlp18s0): device state change: prepare -> config (reason 'none') [40 50 0]
NetworkManager[3251]: <info> Activation (wlp18s0/wireless): connection 'Santa' has security, and secrets exist. No new secrets needed.
NetworkManager[3251]: <info> Config: added 'ssid' value 'Santa'
NetworkManager[3251]: <info> Config: added 'scan_ssid' value '1'
NetworkManager[3251]: <info> Config: added 'key_mgmt' value 'WPA-PSK'
NetworkManager[3251]: <info> Config: added 'auth_alg' value 'OPEN'
NetworkManager[3251]: <info> Config: added 'psk' value '<omitted>'
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 2 of 5 (Device Configure) complete.
NetworkManager[3251]: <info> Config: set interface ap_scan to 1
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: inactive -> authenticating
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: authenticating -> disconnected
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: disconnected -> scanning
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: scanning -> authenticating
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: authenticating -> associating
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: associating -> associated
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: associated -> 4-way handshake
NetworkManager[3251]: <info> (wlp18s0): supplicant interface state: 4-way handshake -> completed
NetworkManager[3251]: <info> Activation (wlp18s0/wireless) Stage 2 of 5 (Device Configure) successful. Connected to wireless network 'Santa'.
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 3 of 5 (IP Configure Start) scheduled.
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 3 of 5 (IP Configure Start) started...
NetworkManager[3251]: <info> (wlp18s0): device state change: config -> ip-config (reason 'none') [50 70 0]
NetworkManager[3251]: <info> Activation (wlp18s0) Beginning DHCPv4 transaction (timeout in 45 seconds)
NetworkManager[3251]: <info> dhcpcd started with pid 3261
NetworkManager[3251]: <info> Activation (wlp18s0) Beginning IP6 addrconf.
dhcpcd[3261]: version 6.4.0 starting
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 3 of 5 (IP Configure Start) complete.
dhcpcd[3261]: wlp18s0: adding address fe80::e0b1:110:f1ea:b935
dhcpcd[3261]: DUID 00:01:00:01:1b:3e:cd:f2:78:2b:cb:cf:a1:ab
dhcpcd[3261]: wlp18s0: IAID 4e:93:d0:c9
NetworkManager[3251]: <info> (wlp18s0): DHCPv4 state changed nbi -> preinit
dhcpcd[3261]: wlp18s0: rebinding lease of 192.168.178.49
dhcpcd[3261]: wlp18s0: soliciting an IPv6 router
dhcpcd[3261]: wlp18s0: leased 192.168.178.49 for 864000 seconds
dhcpcd[3261]: wlp18s0: adding route to 192.168.178.0/24
NetworkManager[3251]: <info> (wlp18s0): DHCPv4 state changed preinit -> reboot
NetworkManager[3251]: <info> address 192.168.178.49
NetworkManager[3251]: <info> prefix 24 (255.255.255.0)
NetworkManager[3251]: <info> gateway 192.168.178.1
NetworkManager[3251]: <info> nameserver '192.168.178.1'
NetworkManager[3251]: <info> domain name 'fritz.box'
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 5 of 5 (IPv4 Commit) started...
dhcpcd[3261]: wlp18s0: removing route to 192.168.178.0/24
NetworkManager[3251]: <info> (wlp18s0): device state change: ip-config -> secondaries (reason 'none') [70 90 0]
NetworkManager[3251]: <info> Activation (wlp18s0) Stage 5 of 5 (IPv4 Commit) complete.
NetworkManager[3251]: <info> (wlp18s0): device state change: secondaries -> activated (reason 'none') [90 100 0]
NetworkManager[3251]: <info> NetworkManager state is now CONNECTED_GLOBAL
NetworkManager[3251]: <info> Policy set 'Santa' (wlp18s0) as default for IPv4 routing and DNS.
NetworkManager[3251]: <info> Writing DNS information to /usr/bin/resolvconf
NetworkManager[3251]: <info> Activation (wlp18s0) successful, device activated.Offline