You are not logged in.

#1 2017-11-06 01:30:37

someguynamedmatt
Member
From: Los Angeles, CA
Registered: 2017-11-06
Posts: 11

WiFi slow to connect (sometimes fails)

Lenovo P50
Linux 4.13.11-1-ARCH
Gnome 3.26.2
Network controller: Intel Corporation Wireless 8260 (rev 3a)
Subsystem: Intel Corporation Wireless 8260


I started noticing (about two updates ago, not that _that_ means much) that when I wake my machine from sleep the WiFi doesn't always reconnect. It will attempt connecting to my AP for a minute or so, then just give up with "?" where the WiFi icon usually sits on my Gnome desktop.

ping -c2 google.com

// unknown host issue

journalctl | grep wlp4s0 | grep fail

// gives "failed to remove key (1, ff:ff:ff:ff:ff:ff) from hardware (-22)"

This issue hits me about 40% of the time I reopen my laptop, so it's not completely blocking me. But, still, I'd like to figure out what's going on.

Last edited by someguynamedmatt (2017-11-06 01:32:10)


...mostly ignorant

Offline

#2 2017-11-06 01:43:52

cfr
Member
From: Cymru
Registered: 2011-11-27
Posts: 7,130

Re: WiFi slow to connect (sometimes fails)

I doubt that error is responsible for the problem. What else is in the journal? How is your connection configured?


CLI Paste | How To Ask Questions

Arch Linux | x86_64 | GPT | EFI boot | refind | stub loader | systemd | LVM2 on LUKS
Lenovo x270 | Intel(R) Core(TM) i5-7200U CPU @ 2.50GHz | Intel Wireless 8265/8275 | US keyboard w/ Euro | 512G NVMe INTEL SSDPEKKF512G7L

Offline

#3 2017-11-06 01:57:07

someguynamedmatt
Member
From: Los Angeles, CA
Registered: 2017-11-06
Posts: 11

Re: WiFi slow to connect (sometimes fails)

cfr wrote:

I doubt that error is responsible for the problem. What else is in the journal? How is your connection configured?

Yeah, from my quick research that error shouldn't have created this problem, but I dunno. Checking more into my journalctl output I see this popping up frequently and it does seem to be correlated with the WiFi issue:

arch gsd-power[610]: Error setting property 'PowerSaveMode' on interface org.gnome.Mutter.DisplayConfig: Timeout was reached (g-io-error-quark, 24)

...but then again, maybe not. I see other errors in the output as well, but they all seem to be related to conky, so I'll fight that battle another day.

As far as network configuration goes, I think it's just the standard, out-of-the-box config that I got with Gnome (Network Manager?). What else should I be looking for?

Thanks


...mostly ignorant

Offline

#4 2017-11-06 04:07:41

cfr
Member
From: Cymru
Registered: 2011-11-27
Posts: 7,130

Re: WiFi slow to connect (sometimes fails)

I don't use Gnome, but that message seems to concern your display / window manager.

Is this a laptop? Is there a pattern? Does it matter whether you wake on the same LAN or a different one? What do you have to do to get it working again? Do you restart NetworkManager.service or what?

What's in the journal when it happens? Looking when the problem occurs may make it more obvious what's relevant.

There's no out-of-the-box network configuration. When installing, netctl is available. You've presumably installed and configured NetworkManager. So what config are you using? Does NetworkManager have a debug or verbose mode? If so, that may give you more information.

Can you ping an ip address e.g. 8.8.8.8?  Can you ping an ip address on your LAN? E.g. can you ping your router, if you have one?

What does ip addr tell you? What does systemctl status tell you?


CLI Paste | How To Ask Questions

Arch Linux | x86_64 | GPT | EFI boot | refind | stub loader | systemd | LVM2 on LUKS
Lenovo x270 | Intel(R) Core(TM) i5-7200U CPU @ 2.50GHz | Intel Wireless 8265/8275 | US keyboard w/ Euro | 512G NVMe INTEL SSDPEKKF512G7L

Offline

#5 2017-11-06 13:14:08

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

Re: WiFi slow to connect (sometimes fails)

In addition please dump a complete journal after such incident and paste it somewhere. An "?" icon is pretty meaningless ;-)
Also see https://bbs.archlinux.org/viewtopic.php?id=231501 - though different HW, it looks like the same pattern.

Offline

#6 2017-11-06 21:03:04

mcloaked
Member
From: Yorkshire, UK
Registered: 2012-02-02
Posts: 1,222

Re: WiFi slow to connect (sometimes fails)

Intel AC-8260 seems to have problems with firmware and possibly the driver in the kernel - there are multiple bug reports concerning different problems with that wireless chip in the kernel wireless bugzilla - I opened an arch bug about 8260 connection issues at:

https://bugs.archlinux.org/task/56248

which contains a few links to upstream bugs. If you search the kernel bugzilla you will find more - one or other may relate to the specific problem you have.


Mike C

Offline

#7 2017-11-07 05:53:59

someguynamedmatt
Member
From: Los Angeles, CA
Registered: 2017-11-06
Posts: 11

Re: WiFi slow to connect (sometimes fails)

First off, thanks for the help everyone.

I looked at the link that @seth pointed me towards and here's the output from some of the things that poster tried:

lscpi | grep Network

(outputs)

04:00.0 Network controller: Intel Corporation Wireless 8260 (rev 3a)
modinfo ath10k_pci

filename:       /lib/modules/4.13.11-1-ARCH/kernel/drivers/net/wireless/ath/ath10k/ath10k_pci.ko.gz
firmware:       ath10k/QCA9377/hw1.0/board.bin
firmware:       ath10k/QCA9377/hw1.0/firmware-5.bin
firmware:       ath10k/QCA6174/hw3.0/board-2.bin
firmware:       ath10k/QCA6174/hw3.0/board.bin
firmware:       ath10k/QCA6174/hw3.0/firmware-6.bin
firmware:       ath10k/QCA6174/hw3.0/firmware-5.bin
firmware:       ath10k/QCA6174/hw3.0/firmware-4.bin
firmware:       ath10k/QCA6174/hw2.1/board-2.bin
firmware:       ath10k/QCA6174/hw2.1/board.bin
firmware:       ath10k/QCA6174/hw2.1/firmware-5.bin
firmware:       ath10k/QCA6174/hw2.1/firmware-4.bin
firmware:       ath10k/QCA9887/hw1.0/board-2.bin
firmware:       ath10k/QCA9887/hw1.0/board.bin
firmware:       ath10k/QCA9887/hw1.0/firmware-5.bin
firmware:       ath10k/QCA988X/hw2.0/board-2.bin
firmware:       ath10k/QCA988X/hw2.0/board.bin
firmware:       ath10k/QCA988X/hw2.0/firmware-5.bin
firmware:       ath10k/QCA988X/hw2.0/firmware-4.bin
firmware:       ath10k/QCA988X/hw2.0/firmware-3.bin
firmware:       ath10k/QCA988X/hw2.0/firmware-2.bin
license:        Dual BSD/GPL
description:    Driver support for Qualcomm Atheros 802.11ac WLAN PCIe/AHB devices
author:         Qualcomm Atheros
alias:          pci:v0000168Cd00000050sv*sd*bc*sc*i*
alias:          pci:v0000168Cd00000042sv*sd*bc*sc*i*
alias:          pci:v0000168Cd00000046sv*sd*bc*sc*i*
alias:          pci:v0000168Cd00000056sv*sd*bc*sc*i*
alias:          pci:v0000168Cd00000040sv*sd*bc*sc*i*
alias:          pci:v0000168Cd0000003Esv*sd*bc*sc*i*
alias:          pci:v0000168Cd00000041sv*sd*bc*sc*i*
alias:          pci:v0000168Cd0000003Csv*sd*bc*sc*i*
depends:        ath10k_core
intree:         Y
name:           ath10k_pci
vermagic:       4.13.11-1-ARCH SMP preempt mod_unload modversions 
parm:           irq_mode:0: auto, 1: legacy, 2: msi (default: 0) (uint)
parm:           reset_mode:0: auto, 1: warm only (default: 0) (uint)

In regards to @cfr's comments: No, I can't reach my router or 8.8.8.8. All attempts (for everything) return "Network unreachable"

My

journalctl | grep Network

output is as follows:

Nov 06 21:36:54 arch NetworkManager[333]: <info>  [1510033014.5324] manager: wake requested (sleeping: yes  enabled: yes)
Nov 06 21:36:54 arch NetworkManager[333]: <info>  [1510033014.5324] manager: waking up...
Nov 06 21:36:54 arch NetworkManager[333]: <info>  [1510033014.5324] device (enp0s31f6): state change: unavailable -> unmanaged (reason 'sleeping', internal state 'managed')
Nov 06 21:36:54 arch NetworkManager[333]: <info>  [1510033014.6362] device (enp0s31f6): state change: unmanaged -> unavailable (reason 'managed', internal state 'managed')
Nov 06 21:36:54 arch NetworkManager[333]: <info>  [1510033014.8488] device (wlp4s0): state change: unmanaged -> unavailable (reason 'managed', internal state 'managed')
Nov 06 21:36:55 arch NetworkManager[333]: <info>  [1510033015.1333] device (wlp4s0): set-hw-addr: set MAC address to B2:12:13:F2:95:B4 (scanning)
Nov 06 21:36:55 arch NetworkManager[333]: <info>  [1510033015.3848] manager: NetworkManager state is now DISCONNECTED
Nov 06 21:36:55 arch NetworkManager[333]: <info>  [1510033015.4469] sup-iface[0x55d6020738c0,wlp4s0]: supports 5 scan SSIDs
Nov 06 21:36:55 arch NetworkManager[333]: <info>  [1510033015.4475] device (wlp4s0): supplicant interface state: starting -> ready
Nov 06 21:36:55 arch NetworkManager[333]: <info>  [1510033015.4475] device (wlp4s0): state change: unavailable -> disconnected (reason 'supplicant-available', internal state 'managed')
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.5831] device (wlp4s0): supplicant interface state: ready -> inactive
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.5992] policy: auto-activating connection 'MOTOF140-5G'
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.5998] device (wlp4s0): Activation: starting connection 'MOTOF140-5G' (bfd93323-69bf-4190-891b-c7ec911c5bd8)
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.6000] device (wlp4s0): state change: disconnected -> prepare (reason 'none', internal state 'managed')
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.6001] manager: NetworkManager state is now CONNECTING
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7005] device (wlp4s0): set-hw-addr: reset MAC address to E4:A7:A0:0E:A7:86 (preserve)
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7386] device (wlp4s0): supplicant interface state: inactive -> disabled
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7445] device (wlp4s0): state change: prepare -> config (reason 'none', internal state 'managed')
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7446] device (wlp4s0): Activation: (wifi) access point 'MOTOF140-5G' has security, but secrets are required.
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7447] device (wlp4s0): state change: config -> need-auth (reason 'none', internal state 'managed')
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7564] device (wlp4s0): state change: need-auth -> prepare (reason 'none', internal state 'managed')
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7566] device (wlp4s0): state change: prepare -> config (reason 'none', internal state 'managed')
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7568] device (wlp4s0): Activation: (wifi) connection 'MOTOF140-5G' has security, and secrets exist.  No new secrets needed.
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7568] Config: added 'ssid' value 'MOTOF140-5G'
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7568] Config: added 'scan_ssid' value '1'
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7568] Config: added 'key_mgmt' value 'WPA-PSK'
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7568] Config: added 'psk' value '<hidden>'
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7752] device (wlp4s0): supplicant interface state: disabled -> inactive
Nov 06 21:36:58 arch NetworkManager[333]: <info>  [1510033018.7852] device (wlp4s0): supplicant interface state: inactive -> scanning
Nov 06 21:36:59 arch NetworkManager[333]: <info>  [1510033019.9771] device (wlp4s0): supplicant interface state: scanning -> authenticating
Nov 06 21:36:59 arch NetworkManager[333]: <info>  [1510033019.9826] device (wlp4s0): supplicant interface state: authenticating -> associating
Nov 06 21:36:59 arch NetworkManager[333]: <info>  [1510033019.9993] device (wlp4s0): supplicant interface state: associating -> associated
Nov 06 21:36:59 arch NetworkManager[333]: <info>  [1510033019.9997] device (wlp4s0): supplicant interface state: associated -> 4-way handshake
Nov 06 21:37:00 arch NetworkManager[333]: <info>  [1510033020.0149] device (wlp4s0): supplicant interface state: 4-way handshake -> completed
Nov 06 21:37:00 arch NetworkManager[333]: <info>  [1510033020.0149] device (wlp4s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'MOTOF140-5G'.
Nov 06 21:37:00 arch NetworkManager[333]: <info>  [1510033020.0150] device (wlp4s0): state change: config -> ip-config (reason 'none', internal state 'managed')
Nov 06 21:37:00 arch NetworkManager[333]: <info>  [1510033020.0152] dhcp4 (wlp4s0): activation: beginning transaction (timeout in 45 seconds)
Nov 06 21:37:03 arch NetworkManager[333]: <info>  [1510033023.8328] dhcp6 (wlp4s0): activation: beginning transaction (timeout in 45 seconds)
Nov 06 21:37:45 arch NetworkManager[333]: <warn>  [1510033065.2672] dhcp4 (wlp4s0): request timed out
Nov 06 21:37:45 arch NetworkManager[333]: <info>  [1510033065.2672] dhcp4 (wlp4s0): state changed unknown -> timeout
Nov 06 21:37:45 arch NetworkManager[333]: <info>  [1510033065.2675] dhcp4 (wlp4s0): canceled DHCP transaction
Nov 06 21:37:45 arch NetworkManager[333]: <info>  [1510033065.2675] dhcp4 (wlp4s0): state changed timeout -> done
Nov 06 21:37:49 arch NetworkManager[333]: <warn>  [1510033069.2627] dhcp6 (wlp4s0): request timed out
Nov 06 21:37:49 arch NetworkManager[333]: <info>  [1510033069.2627] dhcp6 (wlp4s0): state changed unknown -> timeout
Nov 06 21:37:49 arch NetworkManager[333]: <info>  [1510033069.2628] dhcp6 (wlp4s0): canceled DHCP transaction
Nov 06 21:37:49 arch NetworkManager[333]: <info>  [1510033069.2629] dhcp6 (wlp4s0): state changed timeout -> done
Nov 06 21:37:49 arch NetworkManager[333]: <info>  [1510033069.2649] device (wlp4s0): state change: ip-config -> ip-check (reason 'none', internal state 'managed')
Nov 06 21:37:49 arch NetworkManager[333]: <info>  [1510033069.2683] device (wlp4s0): state change: ip-check -> secondaries (reason 'none', internal state 'managed')
Nov 06 21:37:49 arch NetworkManager[333]: <info>  [1510033069.2692] device (wlp4s0): state change: secondaries -> activated (reason 'none', internal state 'managed')
Nov 06 21:37:49 arch NetworkManager[333]: <info>  [1510033069.2727] manager: NetworkManager state is now CONNECTED_LOCAL
Nov 06 21:37:49 arch NetworkManager[333]: <info>  [1510033069.2841] manager: NetworkManager state is now CONNECTED_GLOBAL
Nov 06 21:37:49 arch NetworkManager[333]: <info>  [1510033069.2842] policy: set 'MOTOF140-5G' (wlp4s0) as default for IPv6 routing and DNS
Nov 06 21:37:49 arch NetworkManager[333]: <info>  [1510033069.2842] device (wlp4s0): Activation: successful, device activated.
Nov 06 21:37:49 arch dbus-daemon[312]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.2' (uid=0 pid=333 comm="/usr/bin/NetworkManager --no-daemon ")
Nov 06 21:37:49 arch systemd[1]: Starting Network Manager Script Dispatcher Service...
Nov 06 21:37:49 arch systemd[1]: Started Network Manager Script Dispatcher Service.

Despite the fact that the above output says that it has succesfully connected to my WiFi, I still get nothin'. Maybe I'm misinterpreting that output though.

Last edited by someguynamedmatt (2017-11-07 05:59:34)


...mostly ignorant

Offline

#8 2017-11-07 07:46:07

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

Re: WiFi slow to connect (sometimes fails)

You don't have an atheros chip and we all can modinfo it ;-)

Please provide a *complete* journal - as you just figured, NM logging is worthless.
In addition, check "ip addr" and "ip route" when in this condition.

Offline

#9 2017-11-07 16:53:56

cfr
Member
From: Cymru
Registered: 2011-11-27
Posts: 7,130

Re: WiFi slow to connect (sometimes fails)

Also, try the standard diagnostic for all networking problems: disable NetworkManager and try to connect manually.


CLI Paste | How To Ask Questions

Arch Linux | x86_64 | GPT | EFI boot | refind | stub loader | systemd | LVM2 on LUKS
Lenovo x270 | Intel(R) Core(TM) i5-7200U CPU @ 2.50GHz | Intel Wireless 8265/8275 | US keyboard w/ Euro | 512G NVMe INTEL SSDPEKKF512G7L

Offline

#10 2017-11-19 19:47:27

someguynamedmatt
Member
From: Los Angeles, CA
Registered: 2017-11-06
Posts: 11

Re: WiFi slow to connect (sometimes fails)

Okay, sorry for the delay. Here's output from the _entire_ `journalctl` when I last closed the lid and re-opened it _and_ WiFi wouldn't connect:

Nov 19 01:32:44 arch systemd-logind[395]: Lid closed.
Nov 19 01:32:44 arch systemd-logind[395]: Suspending...
Nov 19 01:32:44 arch NetworkManager[398]: <info>  [1511083964.4715] manager: sleep requested (sleeping: no  enabled: yes)
Nov 19 01:32:44 arch NetworkManager[398]: <info>  [1511083964.4715] manager: sleeping...
Nov 19 01:32:44 arch NetworkManager[398]: <info>  [1511083964.4716] manager: NetworkManager state is now ASLEEP
Nov 19 01:32:44 arch NetworkManager[398]: <info>  [1511083964.4718] device (wlp4s0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Nov 19 01:32:44 arch dbus-daemon[396]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.2' (uid=0 pid=398 comm="/usr/bin/NetworkManager --no-daemon ")
Nov 19 01:32:44 arch NetworkManager[398]: <info>  [1511083964.4777] device (wlp4s0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Nov 19 01:32:44 arch NetworkManager[398]: <info>  [1511083964.4780] dhcp4 (wlp4s0): canceled DHCP transaction
Nov 19 01:32:44 arch NetworkManager[398]: <info>  [1511083964.4780] dhcp4 (wlp4s0): state changed bound -> done
Nov 19 01:32:44 arch systemd[1]: Starting Network Manager Script Dispatcher Service...
Nov 19 01:32:44 arch kernel: wlp4s0: deauthenticating from f8:cf:c5:f8:da:9f by local choice (Reason: 3=DEAUTH_LEAVING)
Nov 19 01:32:44 arch dbus-daemon[396]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Nov 19 01:32:44 arch systemd[1]: Started Network Manager Script Dispatcher Service.
Nov 19 01:32:44 arch nm-dispatcher[1661]: req:1 'connectivity-change': new request (0 scripts)
Nov 19 01:32:44 arch nm-dispatcher[1661]: req:1 'connectivity-change': completed: no scripts
Nov 19 01:32:44 arch wpa_supplicant[451]: wlp4s0: CTRL-EVENT-DISCONNECTED bssid=f8:cf:c5:f8:da:9f reason=3 locally_generated=1
Nov 19 01:32:44 arch kernel: wlp4s0: failed to remove key (1, ff:ff:ff:ff:ff:ff) from hardware (-22)
Nov 19 01:32:44 arch NetworkManager[398]: <info>  [1511083964.4957] device (wlp4s0): set-hw-addr: set MAC address to 7E:19:25:67:E9:48 (scanning)
Nov 19 01:32:44 arch NetworkManager[398]: <info>  [1511083964.5003] dns-mgr: Removing DNS information from /usr/bin/resolvconf
Nov 19 01:32:44 arch kernel: IPv6: ADDRCONF(NETDEV_UP): wlp4s0: link is not ready
Nov 19 01:32:44 arch NetworkManager[398]: <warn>  [1511083964.5307] sup-iface[0x562dbccfbe50,wlp4s0]: connection disconnected (reason -3)
Nov 19 01:32:44 arch NetworkManager[398]: <info>  [1511083964.5308] device (wlp4s0): supplicant interface state: completed -> disconnected
Nov 19 01:32:44 arch nm-dispatcher[1661]: req:2 'down' [wlp4s0]: new request (0 scripts)
Nov 19 01:32:44 arch nm-dispatcher[1661]: req:2 'down' [wlp4s0]: completed: no scripts
Nov 19 01:32:44 arch wpa_supplicant[451]: wlp4s0: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=0 noise=9999 txrate=0
Nov 19 01:32:44 arch NetworkManager[398]: <info>  [1511083964.5317] device (wlp4s0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Nov 19 01:32:44 arch gsd-sharing[1051]: Failed to StopUnit service: GDBus.Error:org.freedesktop.systemd1.NoSuchUnit: Unit gnome-remote-desktop.service not loaded.
Nov 19 01:32:44 arch NetworkManager[398]: <info>  [1511083964.5382] device (wlp4s0): set-hw-addr: reset MAC address to E4:A7:A0:0E:A7:86 (unmanage)
Nov 19 01:32:44 arch wpa_supplicant[451]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlp4s0/drop_unicast_in_l2_multicast: No such file or directory
Nov 19 01:32:44 arch wpa_supplicant[451]: nl80211: Failed to set IPv4 unicast in multicast filter
Nov 19 01:32:44 arch wpa_supplicant[451]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlp4s0/drop_unicast_in_l2_multicast: No such file or directory
Nov 19 01:32:44 arch wpa_supplicant[451]: nl80211: Failed to set IPv4 unicast in multicast filter
Nov 19 01:32:44 arch wpa_supplicant[451]: nl80211: deinit ifname=p2p-dev-wlp4s0 disabled_11b_rates=0
Nov 19 01:32:44 arch wpa_supplicant[451]: nl80211: deinit ifname=wlp4s0 disabled_11b_rates=0
Nov 19 01:32:45 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 01:32:45 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 01:32:45 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 01:32:45 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 01:32:47 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 01:32:47 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 01:32:47 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 01:32:47 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 01:32:49 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 01:32:49 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 01:32:49 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 01:32:49 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 01:32:49 arch systemd-logind[395]: Delay lock is active (UID 120/gdm, PID 742/gsd-power) but inhibitor timeout is reached.
Nov 19 01:32:49 arch systemd[1]: Reached target Sleep.
Nov 19 01:32:49 arch systemd[1]: Starting Suspend...
Nov 19 01:32:49 arch systemd-sleep[1777]: Suspending system...
Nov 19 11:23:26 arch kernel: PM: Syncing filesystems ... done.
Nov 19 11:23:26 arch kernel: PM: Preparing system for sleep (mem)
Nov 19 11:23:26 arch kernel: Freezing user space processes ... (elapsed 0.003 seconds) done.
Nov 19 11:23:26 arch kernel: OOM killer disabled.
Nov 19 11:23:26 arch kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Nov 19 11:23:26 arch kernel: PM: Suspending system (mem)
Nov 19 11:23:26 arch kernel: Suspending console(s) (use no_console_suspend to debug)
Nov 19 11:23:26 arch kernel: psmouse serio2: Failed to disable mouse on synaptics-pt/serio0
Nov 19 11:23:26 arch kernel: sd 3:0:0:0: [sda] Synchronizing SCSI cache
Nov 19 11:23:26 arch kernel: sd 3:0:0:0: [sda] Stopping disk
Nov 19 11:23:26 arch kernel: e1000e: EEE TX LPI TIMER: 00000011
Nov 19 11:23:26 arch kernel: PM: suspend of devices complete after 736.666 msecs
Nov 19 11:23:26 arch kernel: PM: late suspend of devices complete after 16.419 msecs
Nov 19 11:23:26 arch kernel: PM: noirq suspend of devices complete after 70.068 msecs
Nov 19 11:23:26 arch kernel: ACPI: Preparing to enter system sleep state S3
Nov 19 11:23:26 arch kernel: ACPI: EC: event blocked
Nov 19 11:23:26 arch kernel: ACPI: EC: EC stopped
Nov 19 11:23:26 arch kernel: PM: Saving platform NVS memory
Nov 19 11:23:26 arch kernel: Disabling non-boot CPUs ...
Nov 19 11:23:26 arch kernel: smpboot: CPU 1 is now offline
Nov 19 11:23:26 arch kernel: smpboot: CPU 2 is now offline
Nov 19 11:23:26 arch kernel: smpboot: CPU 3 is now offline
Nov 19 11:23:26 arch kernel: smpboot: CPU 4 is now offline
Nov 19 11:23:26 arch kernel: smpboot: CPU 5 is now offline
Nov 19 11:23:26 arch kernel: smpboot: CPU 6 is now offline
Nov 19 11:23:26 arch kernel: smpboot: CPU 7 is now offline
Nov 19 11:23:26 arch kernel: ACPI: Low-level resume complete
Nov 19 11:23:26 arch kernel: ACPI: EC: EC started
Nov 19 11:23:26 arch kernel: PM: Restoring platform NVS memory
Nov 19 11:23:26 arch kernel: Suspended for 35434.027 seconds
Nov 19 11:23:26 arch kernel: Enabling non-boot CPUs ...
Nov 19 11:23:26 arch kernel: x86: Booting SMP configuration:
Nov 19 11:23:26 arch kernel: smpboot: Booting Node 0 Processor 1 APIC 0x2
Nov 19 11:23:26 arch kernel:  cache: parent cpu1 should not be sleeping
Nov 19 11:23:26 arch kernel: CPU1 is up
Nov 19 11:23:26 arch kernel: smpboot: Booting Node 0 Processor 2 APIC 0x4
Nov 19 11:23:26 arch kernel:  cache: parent cpu2 should not be sleeping
Nov 19 11:23:26 arch kernel: CPU2 is up
Nov 19 11:23:26 arch kernel: smpboot: Booting Node 0 Processor 3 APIC 0x6
Nov 19 11:23:26 arch kernel:  cache: parent cpu3 should not be sleeping
Nov 19 11:23:26 arch kernel: CPU3 is up
Nov 19 11:23:26 arch kernel: smpboot: Booting Node 0 Processor 4 APIC 0x1
Nov 19 11:23:26 arch kernel:  cache: parent cpu4 should not be sleeping
Nov 19 11:23:26 arch kernel: CPU4 is up
Nov 19 11:23:26 arch kernel: smpboot: Booting Node 0 Processor 5 APIC 0x3
Nov 19 11:23:26 arch kernel:  cache: parent cpu5 should not be sleeping
Nov 19 11:23:26 arch kernel: CPU5 is up
Nov 19 11:23:26 arch kernel: smpboot: Booting Node 0 Processor 6 APIC 0x5
Nov 19 11:23:26 arch kernel:  cache: parent cpu6 should not be sleeping
Nov 19 11:23:26 arch kernel: CPU6 is up
Nov 19 11:23:26 arch kernel: smpboot: Booting Node 0 Processor 7 APIC 0x7
Nov 19 11:23:26 arch kernel:  cache: parent cpu7 should not be sleeping
Nov 19 11:23:26 arch kernel: CPU7 is up
Nov 19 11:23:26 arch kernel: ACPI: Waking up from system sleep state S3
Nov 19 11:23:26 arch kernel: PM: noirq resume of devices complete after 36.953 msecs
Nov 19 11:23:26 arch kernel: PM: early resume of devices complete after 20.853 msecs
Nov 19 11:23:26 arch kernel: sd 3:0:0:0: [sda] Starting disk
Nov 19 11:23:26 arch kernel: ACPI: EC: event unblocked
Nov 19 11:23:26 arch kernel: xhci_hcd 0000:00:14.0: port 13 resume PLC timeout
Nov 19 11:23:26 arch kernel: xhci_hcd 0000:00:14.0: port 8 resume PLC timeout
Nov 19 11:23:26 arch kernel: usb 1-8: reset high-speed USB device number 2 using xhci_hcd
Nov 19 11:23:26 arch kernel: ata4: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Nov 19 11:23:26 arch kernel: ata4.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Nov 19 11:23:26 arch kernel: ata4.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Nov 19 11:23:26 arch kernel: ata4.00: supports DRM functions and may not be fully accessible
Nov 19 11:23:26 arch kernel: ata4.00: disabling queued TRIM support
Nov 19 11:23:26 arch kernel: ata4.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Nov 19 11:23:26 arch kernel: ata4.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Nov 19 11:23:26 arch kernel: ata4.00: supports DRM functions and may not be fully accessible
Nov 19 11:23:26 arch kernel: ata4.00: disabling queued TRIM support
Nov 19 11:23:26 arch kernel: ata4.00: configured for UDMA/133
Nov 19 11:23:26 arch kernel: usb 1-14: reset full-speed USB device number 4 using xhci_hcd
Nov 19 11:23:26 arch kernel: usb 1-9: reset full-speed USB device number 3 using xhci_hcd
Nov 19 11:23:26 arch kernel: psmouse serio1: synaptics: queried max coordinates: x [..5676], y [..4754]
Nov 19 11:23:26 arch kernel: psmouse serio1: synaptics: queried min coordinates: x [1266..], y [1100..]
Nov 19 11:23:26 arch kernel: PM: resume of devices complete after 947.930 msecs
Nov 19 11:23:26 arch kernel: usb 1-14:1.0: rebind failed: -517
Nov 19 11:23:26 arch kernel: usb 1-14:1.1: rebind failed: -517
Nov 19 11:23:26 arch kernel: PM: Finishing wakeup.
Nov 19 11:23:26 arch kernel: OOM killer enabled.
Nov 19 11:23:26 arch kernel: Restarting tasks ...
Nov 19 11:23:26 arch kernel: nouveau 0000:01:00.0: DRM: resuming object tree...
Nov 19 11:23:26 arch systemd-logind[395]: Lid opened.
Nov 19 11:23:26 arch systemd[1]: Starting Load/Save RF Kill Switch Status...
Nov 19 11:23:26 arch systemd-rfkill[1844]: Failed to open device: No such device
Nov 19 11:23:26 arch kernel: Bluetooth: hci0: Bootloader revision 0.0 build 2 week 52 2014
Nov 19 11:23:26 arch systemd[1]: bluetooth.target: Unit not needed anymore. Stopping.
Nov 19 11:23:26 arch systemd[1]: Stopped target Bluetooth.
Nov 19 11:23:26 arch systemd[1]: Reached target Bluetooth.
Nov 19 11:23:26 arch systemd[1]: Started Load/Save RF Kill Switch Status.
Nov 19 11:23:26 arch kernel: done.
Nov 19 11:23:26 arch kernel: thermal thermal_zone3: failed to read out thermal zone (-5)
Nov 19 11:23:26 arch kernel: Bluetooth: hci0: Device revision is 5
Nov 19 11:23:26 arch kernel: Bluetooth: hci0: Secure boot is enabled
Nov 19 11:23:26 arch kernel: Bluetooth: hci0: OTP lock is enabled
Nov 19 11:23:26 arch kernel: Bluetooth: hci0: API lock is enabled
Nov 19 11:23:26 arch kernel: Bluetooth: hci0: Debug lock is disabled
Nov 19 11:23:26 arch kernel: Bluetooth: hci0: Minimum firmware build 1 week 10 2014
Nov 19 11:23:26 arch kernel: Bluetooth: hci0: Found device firmware: intel/ibt-11-5.sfi
Nov 19 11:23:26 arch kernel: [drm] RC6 on
Nov 19 11:23:26 arch systemd-sleep[1777]: System resumed.
Nov 19 11:23:26 arch systemd[1]: Started Suspend.
Nov 19 11:23:26 arch systemd[1]: sleep.target: Unit not needed anymore. Stopping.
Nov 19 11:23:26 arch systemd[1]: Stopped target Sleep.
Nov 19 11:23:26 arch systemd[1]: Reached target Suspend.
Nov 19 11:23:26 arch systemd-logind[395]: Operation 'sleep' finished.
Nov 19 11:23:26 arch systemd[1]: suspend.target: Unit not needed anymore. Stopping.
Nov 19 11:23:26 arch systemd[1]: Stopped target Suspend.
Nov 19 11:23:26 arch gnome-calendar[1808]: gtk_widget_queue_draw: assertion 'GTK_IS_WIDGET (widget)' failed
Nov 19 11:23:26 arch NetworkManager[398]: <info>  [1511119406.5351] manager: wake requested (sleeping: yes  enabled: yes)
Nov 19 11:23:26 arch NetworkManager[398]: <info>  [1511119406.5351] manager: waking up...
Nov 19 11:23:26 arch NetworkManager[398]: <info>  [1511119406.5352] device (enp0s31f6): state change: unavailable -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Nov 19 11:23:26 arch NetworkManager[398]: <info>  [1511119406.6399] device (enp0s31f6): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'managed')
Nov 19 11:23:26 arch kernel: e1000e: enp0s31f6 NIC Link is Down
Nov 19 11:23:26 arch kernel: IPv6: ADDRCONF(NETDEV_UP): enp0s31f6: link is not ready
Nov 19 11:23:26 arch kernel: nouveau 0000:01:00.0: DRM: resuming fence...
Nov 19 11:23:26 arch kernel: nouveau 0000:01:00.0: DRM: resuming display...
Nov 19 11:23:26 arch kernel: nouveau 0000:01:00.0: DRM: resuming console...
Nov 19 11:23:26 arch NetworkManager[398]: <info>  [1511119406.8513] device (wlp4s0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'managed')
Nov 19 11:23:26 arch kernel: IPv6: ADDRCONF(NETDEV_UP): enp0s31f6: link is not ready
Nov 19 11:23:26 arch kernel: IPv6: ADDRCONF(NETDEV_UP): wlp4s0: link is not ready
Nov 19 11:23:27 arch kernel: IPv6: ADDRCONF(NETDEV_UP): wlp4s0: link is not ready
Nov 19 11:23:27 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 11:23:27 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 11:23:27 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 11:23:27 arch conky.desktop[1160]: ERROR: Can't get value of subfeature temp1_input: I/O error
Nov 19 11:23:27 arch NetworkManager[398]: <info>  [1511119407.1258] device (wlp4s0): set-hw-addr: set MAC address to E2:3F:6C:E2:70:C9 (scanning)
Nov 19 11:23:27 arch NetworkManager[398]: <info>  [1511119407.3766] manager: NetworkManager state is now CONNECTED_LOCAL
Nov 19 11:23:27 arch kernel: IPv6: ADDRCONF(NETDEV_UP): wlp4s0: link is not ready
Nov 19 11:23:27 arch NetworkManager[398]: <info>  [1511119407.4362] sup-iface[0x562dbccfbda0,wlp4s0]: supports 5 scan SSIDs
Nov 19 11:23:27 arch kernel: IPv6: ADDRCONF(NETDEV_UP): wlp4s0: link is not ready
Nov 19 11:23:27 arch NetworkManager[398]: <info>  [1511119407.4367] device (wlp4s0): supplicant interface state: starting -> ready
Nov 19 11:23:27 arch NetworkManager[398]: <info>  [1511119407.4368] device (wlp4s0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
Nov 19 11:23:28 arch kernel: Bluetooth: hci0: Waiting for firmware download to complete
Nov 19 11:23:28 arch kernel: Bluetooth: hci0: Firmware loaded in 1547326 usecs
Nov 19 11:23:28 arch kernel: Bluetooth: hci0: Waiting for device to boot
Nov 19 11:23:28 arch kernel: Bluetooth: hci0: Device booted in 10803 usecs
Nov 19 11:23:28 arch kernel: Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.ddc
Nov 19 11:23:28 arch kernel: Bluetooth: hci0: Applying Intel DDC parameters completed
Nov 19 11:23:29 arch gdm-password][1951]: gkr-pam: unlocked login keyring
Nov 19 11:23:29 arch gnome-shell[871]: loading default theme (Adwaita)
Nov 19 11:23:30 arch pkexec[1999]: pam_unix(polkit-1:session): session opened for user root by (uid=1000)
Nov 19 11:23:30 arch pkexec[1999]: my: Executing command [USER=root] [TTY=unknown] [CWD=/home/my] [COMMAND=/usr/lib/gnome-settings-daemon/gsd-backlight-helper --set-brightness 127]
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.5926] policy: auto-activating connection 'MOTOF140-5G'
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.5935] device (wlp4s0): Activation: starting connection 'MOTOF140-5G' (bfd93323-69bf-4190-891b-c7ec911c5bd8)
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.5937] device (wlp4s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.5938] manager: NetworkManager state is now CONNECTING
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.5974] device (wlp4s0): set-hw-addr: reset MAC address to E4:A7:A0:0E:A7:86 (preserve)
Nov 19 11:23:30 arch kernel: IPv6: ADDRCONF(NETDEV_UP): wlp4s0: link is not ready
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6158] device (wlp4s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6160] device (wlp4s0): Activation: (wifi) access point 'MOTOF140-5G' has security, but secrets are required.
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6160] device (wlp4s0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6161] sup-iface[0x562dbccfbda0,wlp4s0]: wps: type pbc start...
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6236] device (wlp4s0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6239] device (wlp4s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6241] device (wlp4s0): Activation: (wifi) connection 'MOTOF140-5G' has security, and secrets exist.  No new secrets needed.
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6242] Config: added 'ssid' value 'MOTOF140-5G'
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6242] Config: added 'scan_ssid' value '1'
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6242] Config: added 'bgscan' value 'simple:30:-80:86400'
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6242] Config: added 'key_mgmt' value 'WPA-PSK'
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6242] Config: added 'psk' value '<hidden>'
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6545] device (wlp4s0): supplicant interface state: ready -> disconnected
Nov 19 11:23:30 arch NetworkManager[398]: <info>  [1511119410.6651] device (wlp4s0): supplicant interface state: disconnected -> scanning
Nov 19 11:23:30 arch pkexec[2006]: pam_unix(polkit-1:session): session opened for user root by (uid=1000)
Nov 19 11:23:30 arch pkexec[2006]: my: Executing command [USER=root] [TTY=unknown] [CWD=/home/my] [COMMAND=/usr/lib/gnome-settings-daemon/gsd-backlight-helper --set-brightness 169]
Nov 19 11:23:30 arch pkexec[2012]: pam_unix(polkit-1:session): session opened for user root by (uid=1000)
Nov 19 11:23:30 arch pkexec[2012]: my: Executing command [USER=root] [TTY=unknown] [CWD=/home/my] [COMMAND=/usr/lib/gnome-settings-daemon/gsd-backlight-helper --set-brightness 211]
Nov 19 11:23:31 arch pkexec[2018]: pam_unix(polkit-1:session): session opened for user root by (uid=1000)
Nov 19 11:23:31 arch pkexec[2018]: my: Executing command [USER=root] [TTY=unknown] [CWD=/home/my] [COMMAND=/usr/lib/gnome-settings-daemon/gsd-backlight-helper --set-brightness 253]
Nov 19 11:23:31 arch pkexec[2024]: pam_unix(polkit-1:session): session opened for user root by (uid=1000)
Nov 19 11:23:31 arch pkexec[2024]: my: Executing command [USER=root] [TTY=unknown] [CWD=/home/my] [COMMAND=/usr/lib/gnome-settings-daemon/gsd-backlight-helper --set-brightness 295]
Nov 19 11:23:31 arch pkexec[2030]: pam_unix(polkit-1:session): session opened for user root by (uid=1000)
Nov 19 11:23:31 arch pkexec[2030]: my: Executing command [USER=root] [TTY=unknown] [CWD=/home/my] [COMMAND=/usr/lib/gnome-settings-daemon/gsd-backlight-helper --set-brightness 337]
Nov 19 11:23:31 arch pkexec[2059]: pam_unix(polkit-1:session): session opened for user root by (uid=1000)
Nov 19 11:23:31 arch pkexec[2059]: my: Executing command [USER=root] [TTY=unknown] [CWD=/home/my] [COMMAND=/usr/lib/gnome-settings-daemon/gsd-backlight-helper --set-brightness 379]
Nov 19 11:23:31 arch pkexec[2065]: pam_unix(polkit-1:session): session opened for user root by (uid=1000)
Nov 19 11:23:31 arch pkexec[2065]: my: Executing command [USER=root] [TTY=unknown] [CWD=/home/my] [COMMAND=/usr/lib/gnome-settings-daemon/gsd-backlight-helper --set-brightness 421]
Nov 19 11:23:31 arch kernel: nouveau 0000:01:00.0: DRM: suspending console...
Nov 19 11:23:31 arch kernel: nouveau 0000:01:00.0: DRM: suspending display...
Nov 19 11:23:31 arch kernel: nouveau 0000:01:00.0: DRM: evicting buffers...
Nov 19 11:23:31 arch kernel: nouveau 0000:01:00.0: DRM: waiting for kernel channels to go idle...
Nov 19 11:23:31 arch kernel: nouveau 0000:01:00.0: DRM: suspending fence...
Nov 19 11:23:31 arch kernel: nouveau 0000:01:00.0: DRM: suspending object tree...
Nov 19 11:23:32 arch wpa_supplicant[451]: wlp4s0: SME: Trying to authenticate with f8:cf:c5:f8:da:9f (SSID='MOTOF140-5G' freq=5765 MHz)
Nov 19 11:23:32 arch kernel: wlp4s0: authenticate with f8:cf:c5:f8:da:9f
Nov 19 11:23:32 arch NetworkManager[398]: <info>  [1511119412.4989] device (wlp4s0): supplicant interface state: scanning -> authenticating
Nov 19 11:23:32 arch kernel: wlp4s0: send auth to f8:cf:c5:f8:da:9f (try 1/3)
Nov 19 11:23:32 arch wpa_supplicant[451]: wlp4s0: Trying to associate with f8:cf:c5:f8:da:9f (SSID='MOTOF140-5G' freq=5765 MHz)
Nov 19 11:23:32 arch kernel: wlp4s0: authenticated
Nov 19 11:23:32 arch kernel: wlp4s0: associate with f8:cf:c5:f8:da:9f (try 1/3)
Nov 19 11:23:32 arch NetworkManager[398]: <info>  [1511119412.5131] device (wlp4s0): supplicant interface state: authenticating -> associating
Nov 19 11:23:32 arch kernel: wlp4s0: RX AssocResp from f8:cf:c5:f8:da:9f (capab=0x1011 status=0 aid=2)
Nov 19 11:23:32 arch wpa_supplicant[451]: wlp4s0: Associated with f8:cf:c5:f8:da:9f
Nov 19 11:23:32 arch wpa_supplicant[451]: wlp4s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Nov 19 11:23:32 arch kernel: wlp4s0: associated
Nov 19 11:23:32 arch kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp4s0: link becomes ready
Nov 19 11:23:32 arch NetworkManager[398]: <info>  [1511119412.5281] device (wlp4s0): supplicant interface state: associating -> associated
Nov 19 11:23:32 arch NetworkManager[398]: <info>  [1511119412.5385] device (wlp4s0): supplicant interface state: associated -> 4-way handshake
Nov 19 11:23:32 arch wpa_supplicant[451]: wlp4s0: WPA: Key negotiation completed with f8:cf:c5:f8:da:9f [PTK=CCMP GTK=CCMP]
Nov 19 11:23:32 arch wpa_supplicant[451]: wlp4s0: CTRL-EVENT-CONNECTED - Connection to f8:cf:c5:f8:da:9f completed [id=0 id_str=]
Nov 19 11:23:32 arch wpa_supplicant[451]: wlp4s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-38 noise=9999 txrate=6000
Nov 19 11:23:32 arch NetworkManager[398]: <info>  [1511119412.5615] device (wlp4s0): supplicant interface state: 4-way handshake -> completed
Nov 19 11:23:32 arch NetworkManager[398]: <info>  [1511119412.5615] device (wlp4s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'MOTOF140-5G'.
Nov 19 11:23:32 arch NetworkManager[398]: <info>  [1511119412.5616] device (wlp4s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Nov 19 11:23:32 arch NetworkManager[398]: <info>  [1511119412.5618] dhcp4 (wlp4s0): activation: beginning transaction (timeout in 45 seconds)
Nov 19 11:23:33 arch wpa_supplicant[451]: wlp4s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-38 noise=9999 txrate=866700
Nov 19 11:23:42 arch NetworkManager[398]: <info>  [1511119422.9854] dhcp6 (wlp4s0): activation: beginning transaction (timeout in 45 seconds)
Nov 19 11:24:01 arch gnome-calendar[1808]: gtk_widget_queue_draw: assertion 'GTK_IS_WIDGET (widget)' failed
Nov 19 11:24:08 arch gsd-power[742]: Error setting property 'PowerSaveMode' on interface org.gnome.Mutter.DisplayConfig: Timeout was reached (g-io-error-quark, 24)
Nov 19 11:24:08 arch gsd-power[742]: Error setting property 'PowerSaveMode' on interface org.gnome.Mutter.DisplayConfig: Timeout was reached (g-io-error-quark, 24)
Nov 19 11:24:17 arch NetworkManager[398]: <warn>  [1511119457.4258] dhcp4 (wlp4s0): request timed out
Nov 19 11:24:17 arch NetworkManager[398]: <info>  [1511119457.4258] dhcp4 (wlp4s0): state changed unknown -> timeout
Nov 19 11:24:17 arch NetworkManager[398]: <info>  [1511119457.4441] dhcp4 (wlp4s0): canceled DHCP transaction
Nov 19 11:24:17 arch NetworkManager[398]: <info>  [1511119457.4445] dhcp4 (wlp4s0): state changed timeout -> done
Nov 19 11:24:28 arch NetworkManager[398]: <warn>  [1511119468.4270] dhcp6 (wlp4s0): request timed out
Nov 19 11:24:28 arch NetworkManager[398]: <info>  [1511119468.4270] dhcp6 (wlp4s0): state changed unknown -> timeout
Nov 19 11:24:28 arch NetworkManager[398]: <info>  [1511119468.4271] dhcp6 (wlp4s0): canceled DHCP transaction
Nov 19 11:24:28 arch NetworkManager[398]: <info>  [1511119468.4271] dhcp6 (wlp4s0): state changed timeout -> done
Nov 19 11:24:28 arch NetworkManager[398]: <info>  [1511119468.4281] device (wlp4s0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Nov 19 11:24:28 arch NetworkManager[398]: <info>  [1511119468.4295] device (wlp4s0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Nov 19 11:24:28 arch NetworkManager[398]: <info>  [1511119468.4301] device (wlp4s0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Nov 19 11:24:28 arch NetworkManager[398]: <info>  [1511119468.4305] manager: NetworkManager state is now CONNECTED_LOCAL
Nov 19 11:24:28 arch NetworkManager[398]: <info>  [1511119468.4403] manager: NetworkManager state is now CONNECTED_SITE
Nov 19 11:24:28 arch NetworkManager[398]: <info>  [1511119468.4403] policy: set 'MOTOF140-5G' (wlp4s0) as default for IPv6 routing and DNS
Nov 19 11:24:28 arch NetworkManager[398]: <info>  [1511119468.4404] device (wlp4s0): Activation: successful, device activated.
Nov 19 11:24:28 arch dbus-daemon[396]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.2' (uid=0 pid=398 comm="/usr/bin/NetworkManager --no-daemon ")
Nov 19 11:24:28 arch gsd-sharing[1051]: Failed to StopUnit service: GDBus.Error:org.freedesktop.systemd1.NoSuchUnit: Unit gnome-remote-desktop.service not loaded.
Nov 19 11:24:28 arch systemd[1]: Starting Network Manager Script Dispatcher Service...
Nov 19 11:24:28 arch dbus-daemon[396]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Nov 19 11:24:28 arch systemd[1]: Started Network Manager Script Dispatcher Service.
Nov 19 11:24:28 arch nm-dispatcher[2784]: req:1 'up' [wlp4s0]: new request (0 scripts)
Nov 19 11:24:28 arch nm-dispatcher[2784]: req:1 'up' [wlp4s0]: completed: no scripts
Nov 19 11:24:29 arch nm-dispatcher[2784]: req:2 'connectivity-change': new request (0 scripts)
Nov 19 11:24:29 arch nm-dispatcher[2784]: req:2 'connectivity-change': completed: no scripts
Nov 19 11:24:35 arch dbus-daemon[832]: [session uid=1000 pid=832] Activating service name='org.gnome.ControlCenter.SearchProvider' requested by ':1.14' (uid=1000 pid=871 comm="/usr/bin/gnome-shell ")
Nov 19 11:24:35 arch dbus-daemon[832]: [session uid=1000 pid=832] Activating service name='org.gnome.Contacts.SearchProvider' requested by ':1.14' (uid=1000 pid=871 comm="/usr/bin/gnome-shell ")
Nov 19 11:24:35 arch dbus-daemon[832]: [session uid=1000 pid=832] Activating service name='org.gnome.Documents' requested by ':1.14' (uid=1000 pid=871 comm="/usr/bin/gnome-shell ")
Nov 19 11:24:35 arch dbus-daemon[832]: [session uid=1000 pid=832] Activating service name='org.gnome.Nautilus' requested by ':1.14' (uid=1000 pid=871 comm="/usr/bin/gnome-shell ")
Nov 19 11:24:35 arch dbus-daemon[832]: [session uid=1000 pid=832] Activating service name='org.gnome.Calculator.SearchProvider' requested by ':1.14' (uid=1000 pid=871 comm="/usr/bin/gnome-shell ")
Nov 19 11:24:35 arch dbus-daemon[832]: [session uid=1000 pid=832] Activating service name='org.gnome.clocks' requested by ':1.14' (uid=1000 pid=871 comm="/usr/bin/gnome-shell ")
Nov 19 11:24:35 arch dbus-daemon[832]: [session uid=1000 pid=832] Activating service name='org.gnome.bijiben.SearchProvider' requested by ':1.14' (uid=1000 pid=871 comm="/usr/bin/gnome-shell ")
Nov 19 11:24:35 arch dbus-daemon[832]: [session uid=1000 pid=832] Activating service name='org.gnome.seahorse.Application' requested by ':1.14' (uid=1000 pid=871 comm="/usr/bin/gnome-shell ")
Nov 19 11:24:35 arch dbus-daemon[832]: [session uid=1000 pid=832] Activating service name='org.gnome.Photos' requested by ':1.14' (uid=1000 pid=871 comm="/usr/bin/gnome-shell ")
Nov 19 11:24:35 arch dbus-daemon[832]: [session uid=1000 pid=832] Activating via systemd: service name='org.gnome.Terminal' unit='gnome-terminal-server.service' requested by ':1.14' (uid=1000 pid=871 comm="/usr/bin/gnome-shell ")
Nov 19 11:24:35 arch systemd[812]: Starting GNOME Terminal Server...
Nov 19 11:24:35 arch dbus-daemon[832]: [session uid=1000 pid=832] Successfully activated service 'org.gnome.Calculator.SearchProvider'
Nov 19 11:24:36 arch dbus-daemon[832]: [session uid=1000 pid=832] Successfully activated service 'org.gnome.ControlCenter.SearchProvider'
Nov 19 11:24:36 arch dbus-daemon[832]: [session uid=1000 pid=832] Successfully activated service 'org.gnome.Nautilus'
Nov 19 11:24:36 arch gnome-shell[871]: Received error from DBus search provider org.gnome.Epiphany.desktop: Gio.DBusError: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.gnome.EpiphanySearchProvider was not provided by any .service files
Nov 19 11:24:36 arch dbus-daemon[832]: [session uid=1000 pid=832] Successfully activated service 'org.gnome.clocks'
Nov 19 11:24:36 arch dbus-daemon[832]: [session uid=1000 pid=832] Successfully activated service 'org.gnome.Terminal'
Nov 19 11:24:36 arch systemd[812]: Started GNOME Terminal Server.
Nov 19 11:24:36 arch dbus-daemon[832]: [session uid=1000 pid=832] Successfully activated service 'org.gnome.seahorse.Application'
Nov 19 11:24:36 arch dbus-daemon[832]: [session uid=1000 pid=832] Successfully activated service 'org.gnome.Photos'
Nov 19 11:24:36 arch dbus-daemon[832]: [session uid=1000 pid=832] Successfully activated service 'org.gnome.Documents'
Nov 19 11:24:36 arch dbus-daemon[832]: [session uid=1000 pid=832] Successfully activated service 'org.gnome.Contacts.SearchProvider'
Nov 19 11:24:36 arch dbus-daemon[396]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.2209' (uid=1000 pid=2918 comm="/usr/lib/gnome-contacts/gnome-contacts-search-prov")
Nov 19 11:24:36 arch dbus-daemon[396]: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service not found.
Nov 19 11:24:36 arch dbus-daemon[832]: [session uid=1000 pid=832] Successfully activated service 'org.gnome.bijiben.SearchProvider'
Nov 19 11:24:36 arch gnome-photos[2932]: Module '/usr/lib/gegl-0.3/lens-correct.so' load error: liblensfun.so.1: cannot open shared object file: No such file or directory
Nov 19 11:24:36 arch gnome-photos[2932]: Module '/usr/lib/gegl-0.3/matting-levin.so' load error: libumfpack.so.5: cannot open shared object file: No such file or directory
Nov 19 11:24:36 arch bijiben-shell-s[2933]: g_object_unref: assertion 'G_IS_OBJECT (object)' failed
Nov 19 11:24:36 arch gnome-documents[2920]: JS LOG: Can't find a valid getting started PDF document
Nov 19 11:24:36 arch dbus-daemon[832]: [session uid=1000 pid=832] Activating service name='com.intel.dleyna-renderer' requested by ':1.464' (uid=1000 pid=2932 comm="/usr/bin/gnome-photos --gapplication-service ")
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: dLeyna core version 0.6.0
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: dleyna-renderer-service version 0.6.0
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: Type[0] Level[0x13] Mask[0x4C] Flags[0x4F]
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: Load file [/home/my/.config/dleyna-renderer-service.conf]
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: [General settings]
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: Never Quit: F
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: Connector Name: dbus
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: Port: 0
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: Push host port: 0
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: [Logging settings]
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: Log Type : 0
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: Log Level: 0x13
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: [Network filtering settings]
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: Enabled : F
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: Entries: (null)
Nov 19 11:24:36 arch dbus-daemon[832]: [session uid=1000 pid=832] Successfully activated service 'com.intel.dleyna-renderer'
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: Calling GetRenderers method
Nov 19 11:24:36 arch dleyna-renderer-service[3034]: Client :1.464 lost
Nov 19 11:24:36 arch gnome-shell[871]: Received error from DBus search provider org.gnome.Photos.desktop during GetResultMetas: Gio.DBusError: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.464 was not provided by any .service files
Nov 19 11:24:36 arch gnome-shell[871]: Wrong number of result metas returned by search provider org.gnome.Photos.desktop: expected 5 but got 0
Nov 19 11:24:37 arch dleyna-renderer-service[3034]: dLeyna: Exit
Nov 19 11:24:51 arch conky.desktop[1160]:   % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
Nov 19 11:24:51 arch conky.desktop[1160]:                                  Dload  Upload   Total   Spent    Left  Speed
Nov 19 11:24:51 arch conky.desktop[1160]: [124B blob data]
Nov 19 11:25:00 arch gnome-calendar[1808]: gtk_widget_queue_draw: assertion 'GTK_IS_WIDGET (widget)' failed
Nov 19 11:26:00 arch gnome-calendar[1808]: gtk_widget_queue_draw: assertion 'GTK_IS_WIDGET (widget)' failed
Nov 19 11:27:00 arch gnome-calendar[1808]: gtk_widget_queue_draw: assertion 'GTK_IS_WIDGET (widget)' failed

...mostly ignorant

Offline

#11 2017-11-19 22:33:05

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

Re: WiFi slow to connect (sometimes fails)

wpa_supplicant[451]: wlp4s0: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-38 noise=9999 txrate=866700

This looks fishy. Can you reproduce this if you manually stop the network before the S3 and after the wakeup wait eg. ~60 seconds before connecting again?
Also, for a wild guess, check rfkill ...

Offline

#12 2017-11-20 15:33:31

LinG
Member
Registered: 2017-07-23
Posts: 35

Re: WiFi slow to connect (sometimes fails)

On a side note, I experience the same behavior of wifi not connecting properly etc... since ~2 upgrades ago. (Running, KDE with NetworkManager, to manually fix it I have to disconnect and reconnect but speeds have become slower as well...)

Last edited by LinG (2017-11-20 15:34:24)

Offline

#13 2017-12-12 19:26:45

Troels
Member
From: Denmark
Registered: 2009-03-01
Posts: 16

Re: WiFi slow to connect (sometimes fails)

I have the same issue. I'm running Gnome on a 2015 Macbook Air (Broadcom chipset with the wl driver).

Offline

Board footer

Powered by FluxBB