You are not logged in.

#1 2017-03-14 02:25:06

Glaeqen
Member
Registered: 2016-08-10
Posts: 26

[SOLVED] Freeze after resuming from suspend

Hi all,
I've got a pretty serious issue. After suspending my laptop (Acer Aspire One D257), when I try to resume it whole interface freezes completely (getting to tty impossible as well). It started happening like a half of month ago (28 II). What is more awkward I can't find valid reason why because no new software was installed at that time nor updated - completely. When system breaks down journalctl outputs (first issue's appearance):

Feb 28 14:29:32 glaeqen-arch-one systemd-logind[390]: Lid closed.
Feb 28 14:29:32 glaeqen-arch-one systemd-logind[390]: Suspending...
Feb 28 14:29:32 glaeqen-arch-one NetworkManager[396]: <info>  [1488288572.4914] manager: sleep requested (sleeping: no  enabled: yes)
Feb 28 14:29:32 glaeqen-arch-one dbus[392]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Feb 28 14:29:32 glaeqen-arch-one NetworkManager[396]: <info>  [1488288572.4915] manager: sleeping...
Feb 28 14:29:32 glaeqen-arch-one NetworkManager[396]: <info>  [1488288572.4919] manager: NetworkManager state is now ASLEEP
Feb 28 14:29:32 glaeqen-arch-one NetworkManager[396]: <info>  [1488288572.4942] device (wlp2s0b1): state change: activated -> deactivating (reason 'sleeping') [100 110 37]
Feb 28 14:29:32 glaeqen-arch-one systemd[1]: Starting Network Manager Script Dispatcher Service...
Feb 28 14:29:32 glaeqen-arch-one dbus[392]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Feb 28 14:29:32 glaeqen-arch-one systemd[1]: Started Network Manager Script Dispatcher Service.
Feb 28 14:29:32 glaeqen-arch-one nm-dispatcher[22587]: req:1 'connectivity-change': new request (0 scripts)
Feb 28 14:29:32 glaeqen-arch-one nm-dispatcher[22587]: req:1 'connectivity-change': completed: no scripts
Feb 28 14:29:32 glaeqen-arch-one NetworkManager[396]: <info>  [1488288572.6064] device (wlp2s0b1): state change: deactivating -> disconnected (reason 'sleeping') [110 30 37]
Feb 28 14:29:32 glaeqen-arch-one NetworkManager[396]: <info>  [1488288572.6079] dhcp4 (wlp2s0b1): canceled DHCP transaction
Feb 28 14:29:32 glaeqen-arch-one NetworkManager[396]: <info>  [1488288572.6080] dhcp4 (wlp2s0b1): state changed bound -> done
Feb 28 14:29:32 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 0 addresses (implement)
Feb 28 14:29:32 glaeqen-arch-one kernel: wlp2s0b1: deauthenticating from 18:3a:2d:69:30:c8 by local choice (Reason: 3=DEAUTH_LEAVING)
Feb 28 14:29:32 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: disassociated
Feb 28 14:29:32 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Feb 28 14:29:32 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: CTRL-EVENT-DISCONNECTED bssid=18:3a:2d:69:30:c8 reason=3 locally_generated=1
Feb 28 14:29:32 glaeqen-arch-one NetworkManager[396]: <info>  [1488288572.6492] device (wlp2s0b1): set-hw-addr: set MAC address to E6:A1:4F:07:A1:A4 (scanning)
Feb 28 14:29:32 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Feb 28 14:29:32 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement)
Feb 28 14:29:32 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Feb 28 14:29:32 glaeqen-arch-one NetworkManager[396]: <info>  [1488288572.8142] dns-mgr: Removing DNS information from /usr/bin/resolvconf
Feb 28 14:29:33 glaeqen-arch-one nm-dispatcher[22587]: req:2 'down' [wlp2s0b1]: new request (0 scripts)
Feb 28 14:29:33 glaeqen-arch-one nm-dispatcher[22587]: req:2 'down' [wlp2s0b1]: completed: no scripts
Feb 28 14:29:33 glaeqen-arch-one NetworkManager[396]: <warn>  [1488288573.0556] sup-iface[0x254b660,wlp2s0b1]: connection disconnected (reason -3)
Feb 28 14:29:33 glaeqen-arch-one NetworkManager[396]: <info>  [1488288573.0563] device (wlp2s0b1): supplicant interface state: completed -> disconnected
Feb 28 14:29:33 glaeqen-arch-one NetworkManager[396]: <info>  [1488288573.0937] device (wlp2s0b1): supplicant interface state: disconnected -> disabled
Feb 28 14:29:33 glaeqen-arch-one NetworkManager[396]: <info>  [1488288573.0942] device (wlp2s0b1): supplicant interface state: disabled -> disconnected
Feb 28 14:29:33 glaeqen-arch-one NetworkManager[396]: <info>  [1488288573.0988] device (wlp2s0b1): state change: disconnected -> unmanaged (reason 'sleeping') [30 10 37]
Feb 28 14:29:33 glaeqen-arch-one NetworkManager[396]: <info>  [1488288573.1233] device (wlp2s0b1): set-hw-addr: reset MAC address to E4:D5:3D:4B:96:1E (unmanage)
Feb 28 14:29:33 glaeqen-arch-one systemd[1]: Starting TLP suspend/resume...
Feb 28 14:29:33 glaeqen-arch-one wpa_supplicant[465]: nl80211: deinit ifname=wlp2s0b1 disabled_11b_rates=0
Feb 28 14:29:33 glaeqen-arch-one systemd[1]: Started TLP suspend/resume.
Feb 28 14:29:33 glaeqen-arch-one systemd[1]: Reached target Sleep.
Feb 28 14:29:34 glaeqen-arch-one systemd[1]: Starting Suspend...
Feb 28 14:29:34 glaeqen-arch-one systemd-sleep[22719]: Suspending system...
Feb 28 16:21:14 glaeqen-arch-one kernel: PM: Syncing filesystems ... done.
Feb 28 16:21:14 glaeqen-arch-one kernel: PM: Preparing system for sleep (mem)
Feb 28 16:21:14 glaeqen-arch-one kernel: Freezing user space processes ... (elapsed 0.002 seconds) done.
Feb 28 16:21:14 glaeqen-arch-one kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Feb 28 16:21:14 glaeqen-arch-one kernel: PM: Suspending system (mem)
Feb 28 16:21:14 glaeqen-arch-one kernel: Suspending console(s) (use no_console_suspend to debug)
Feb 28 16:21:14 glaeqen-arch-one kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Feb 28 16:21:14 glaeqen-arch-one kernel: ACPI : EC: event blocked
Feb 28 16:21:14 glaeqen-arch-one kernel: sd 0:0:0:0: [sda] Stopping disk
Feb 28 16:21:14 glaeqen-arch-one kernel: PM: suspend of devices complete after 487.168 msecs
Feb 28 16:21:14 glaeqen-arch-one kernel: PM: late suspend of devices complete after 15.710 msecs
Feb 28 16:21:14 glaeqen-arch-one kernel: ACPI : EC: interrupt blocked
Feb 28 16:21:14 glaeqen-arch-one kernel: pcieport 0000:00:1c.0: System wakeup enabled by ACPI
Feb 28 16:21:14 glaeqen-arch-one kernel: ehci-pci 0000:00:1d.7: System wakeup enabled by ACPI
Feb 28 16:21:14 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.3: System wakeup enabled by ACPI
Feb 28 16:21:14 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.1: System wakeup enabled by ACPI
Feb 28 16:21:14 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.0: System wakeup enabled by ACPI
Feb 28 16:21:14 glaeqen-arch-one kernel: PM: noirq suspend of devices complete after 16.835 msecs
Feb 28 16:21:14 glaeqen-arch-one kernel: ACPI: Preparing to enter system sleep state S3
Feb 28 16:21:14 glaeqen-arch-one kernel: ACPI : EC: EC stopped
Feb 28 16:21:14 glaeqen-arch-one kernel: PM: Saving platform NVS memory
Feb 28 16:21:14 glaeqen-arch-one kernel: Disabling non-boot CPUs ...
Feb 28 16:21:14 glaeqen-arch-one kernel: smpboot: CPU 1 is now offline
Feb 28 16:21:14 glaeqen-arch-one kernel: smpboot: CPU 2 is now offline
Feb 28 16:21:14 glaeqen-arch-one kernel: smpboot: CPU 3 is now offline
Feb 28 16:21:14 glaeqen-arch-one kernel: ACPI: Low-level resume complete
Feb 28 16:21:14 glaeqen-arch-one kernel: ACPI : EC: EC started
Feb 28 16:21:14 glaeqen-arch-one kernel: PM: Restoring platform NVS memory
Feb 28 16:21:14 glaeqen-arch-one kernel: Suspended for 6698.786 seconds
Feb 28 16:21:14 glaeqen-arch-one kernel: Enabling non-boot CPUs ...
Feb 28 16:21:14 glaeqen-arch-one kernel: x86: Booting SMP configuration:
Feb 28 16:21:14 glaeqen-arch-one kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Feb 28 16:21:14 glaeqen-arch-one kernel: Disabled fast string operations
Feb 28 16:21:14 glaeqen-arch-one kernel:  cache: parent cpu1 should not be sleeping
Feb 28 16:21:14 glaeqen-arch-one kernel: CPU1 is up
Feb 28 16:21:14 glaeqen-arch-one kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Feb 28 16:21:14 glaeqen-arch-one kernel: Disabled fast string operations
Feb 28 16:21:14 glaeqen-arch-one kernel:  cache: parent cpu2 should not be sleeping
Feb 28 16:21:14 glaeqen-arch-one kernel: CPU2 is up
Feb 28 16:21:14 glaeqen-arch-one kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Feb 28 16:21:14 glaeqen-arch-one kernel: Disabled fast string operations
Feb 28 16:21:14 glaeqen-arch-one kernel:  cache: parent cpu3 should not be sleeping
Feb 28 16:21:14 glaeqen-arch-one kernel: CPU3 is up
Feb 28 16:21:14 glaeqen-arch-one kernel: ACPI: Waking up from system sleep state S3
Feb 28 16:21:14 glaeqen-arch-one kernel: ACPI : EC: interrupt unblocked
Feb 28 16:21:14 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.0: System wakeup disabled by ACPI
Feb 28 16:21:14 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.1: System wakeup disabled by ACPI
Feb 28 16:21:14 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.3: System wakeup disabled by ACPI
Feb 28 16:21:14 glaeqen-arch-one kernel: ehci-pci 0000:00:1d.7: System wakeup disabled by ACPI
Feb 28 16:21:14 glaeqen-arch-one kernel: PM: noirq resume of devices complete after 16.977 msecs
Feb 28 16:21:14 glaeqen-arch-one kernel: PM: early resume of devices complete after 0.435 msecs
Feb 28 16:21:14 glaeqen-arch-one kernel: ACPI : EC: event unblocked
Feb 28 16:21:14 glaeqen-arch-one kernel: usb usb2: root hub lost power or was reset
Feb 28 16:21:14 glaeqen-arch-one kernel: usb usb3: root hub lost power or was reset
Feb 28 16:21:14 glaeqen-arch-one kernel: usb usb4: root hub lost power or was reset
Feb 28 16:21:14 glaeqen-arch-one kernel: pcieport 0000:00:1c.0: System wakeup disabled by ACPI
Feb 28 16:21:14 glaeqen-arch-one kernel: rtc_cmos 00:01: System wakeup disabled by ACPI
Feb 28 16:21:14 glaeqen-arch-one kernel: sd 0:0:0:0: [sda] Starting disk
Feb 28 16:21:14 glaeqen-arch-one kernel: r8169 0000:01:00.0 enp1s0: link down
Feb 28 16:21:14 glaeqen-arch-one kernel: usb 1-3: reset high-speed USB device number 2 using ehci-pci
Feb 28 16:21:14 glaeqen-arch-one kernel: ata2: SATA link down (SStatus 0 SControl 300)
Feb 28 16:21:14 glaeqen-arch-one kernel: PM: resume of devices complete after 444.599 msecs
Feb 28 16:21:14 glaeqen-arch-one kernel: PM: Finishing wakeup.
Feb 28 16:21:14 glaeqen-arch-one kernel: Restarting tasks ... done.
Feb 28 16:21:14 glaeqen-arch-one kernel: video LNXVIDEO:00: Restoring backlight state
Feb 28 16:21:14 glaeqen-arch-one kernel: BUG: unable to handle kernel NULL pointer dereference at           (null)
Feb 28 16:21:14 glaeqen-arch-one kernel: IP: [<ffffffffa0614c6d>] i915_gem_request_alloc+0x10d/0x260 [i915]
Feb 28 16:21:16 glaeqen-arch-one kernel: PGD 36f09067 
Feb 28 16:21:16 glaeqen-arch-one kernel: PUD 33419067 
Feb 28 16:21:16 glaeqen-arch-one kernel: PMD 0 
Feb 28 16:21:16 glaeqen-arch-one kernel: 
Feb 28 16:21:16 glaeqen-arch-one kernel: Oops: 0002 [#1] PREEMPT SMP
Feb 28 16:21:16 glaeqen-arch-one kernel: Modules linked in: fuse ctr ccm uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_core videodev media arc4 brcmsmac cordic brcmutil b43 mousedev mac80211 cfg80211 ssb joydev rng_core pcmcia pcmcia_core coretemp snd_hda_codec_realtek snd_hda_codec_generic acer_wmi i915 sparse_keymap rfkill kvm irqbypass drm_kms_helper drm snd_hda_intel snd_hda_codec syscopyarea pcspkr snd_hda_core bcma psmouse sysfillrect evdev input_leds led_class mac_hid rtsx_pci_ms sysimgblt snd_hwdep fjes snd_pcm i2c_i801 i2c_smbus r8169 memstick mii snd_timer fb_sys_fops snd wmi shpchp soundcore i2c_algo_bit lpc_ich video thermal ac battery intel_agp intel_gtt button acpi_cpufreq tpm_tis tpm_tis_core tpm sch_fq_codel ip_tables x_tables ext4 crc16 jbd2 mbcache sd_mod rtsx_pci_sdmmc serio_raw mmc_core
Feb 28 16:21:16 glaeqen-arch-one kernel:  atkbd libps2 uhci_hcd ahci libahci libata scsi_mod ehci_pci ehci_hcd usbcore usb_common rtsx_pci i8042 serio libcrc32c crc32c_generic f2fs fscrypto
Feb 28 16:21:16 glaeqen-arch-one kernel: CPU: 2 PID: 533 Comm: Xorg Not tainted 4.9.11-1-ARCH #1
Feb 28 16:21:16 glaeqen-arch-one kernel: Hardware name: Acer AOD257/JE06_PT , BIOS V1.15 10/21/2011
Feb 28 16:21:16 glaeqen-arch-one kernel: task: ffff88003ba79a00 task.stack: ffffc90000478000
Feb 28 16:21:16 glaeqen-arch-one kernel: RIP: 0010:[<ffffffffa0614c6d>]  [<ffffffffa0614c6d>] i915_gem_request_alloc+0x10d/0x260 [i915]
Feb 28 16:21:16 glaeqen-arch-one kernel: RSP: 0018:ffffc9000047bc78  EFLAGS: 00010246
Feb 28 16:21:16 glaeqen-arch-one kernel: RAX: 0000000000000001 RBX: ffff88002ad29a40 RCX: 0000000000000000
Feb 28 16:21:16 glaeqen-arch-one kernel: RDX: ffffffffa06d8ca8 RSI: ffffffffa06a9012 RDI: ffff88002ad29b08
Feb 28 16:21:16 glaeqen-arch-one kernel: RBP: ffffc9000047bca0 R08: 000000000002c8b2 R09: ffff880037494200
Feb 28 16:21:16 glaeqen-arch-one kernel: R10: ffff88002ad29a40 R11: ffff88003ba79a00 R12: ffff88003cbb2968
Feb 28 16:21:16 glaeqen-arch-one kernel: R13: 0000000000000000 R14: ffff88003cbb0000 R15: ffff880024eef9c0
Feb 28 16:21:16 glaeqen-arch-one kernel: FS:  00007f14882fe940(0000) GS:ffff88003f300000(0000) knlGS:0000000000000000
Feb 28 16:21:16 glaeqen-arch-one kernel: CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Feb 28 16:21:16 glaeqen-arch-one kernel: CR2: 0000000000000000 CR3: 0000000035e7f000 CR4: 00000000000006e0
Feb 28 16:21:16 glaeqen-arch-one kernel: Stack:
Feb 28 16:21:16 glaeqen-arch-one kernel:  ffff88003bbc0000 ffff88003b9d3c00 ffff88003cbb0000 ffff88003bb8c000
Feb 28 16:21:16 glaeqen-arch-one kernel:  ffff880024eef9c0 ffffc9000047bd18 ffffffffa0656dcf 000000003d803680
Feb 28 16:21:16 glaeqen-arch-one kernel:  ffff88003cbb2968 0000000000000000 ffff88003cbb01d8 ffffc90000000001
Feb 28 16:21:16 glaeqen-arch-one kernel: Call Trace:
Feb 28 16:21:16 glaeqen-arch-one kernel:  [<ffffffffa0656dcf>] intel_crtc_page_flip+0x42f/0x9c0 [i915]
Feb 28 16:21:16 glaeqen-arch-one kernel:  [<ffffffffa04581e2>] drm_mode_page_flip_ioctl+0x372/0x420 [drm]
Feb 28 16:21:16 glaeqen-arch-one kernel:  [<ffffffffa043bb6b>] drm_ioctl+0x21b/0x4c0 [drm]
Feb 28 16:21:16 glaeqen-arch-one kernel:  [<ffffffffa0457e70>] ? drm_mode_cursor2_ioctl+0x10/0x10 [drm]
Feb 28 16:21:16 glaeqen-arch-one kernel:  [<ffffffff810f1da0>] ? hrtimer_init+0x120/0x120
Feb 28 16:21:16 glaeqen-arch-one kernel:  [<ffffffff816097c5>] ? schedule_hrtimeout_range_clock+0x95/0x120
Feb 28 16:21:16 glaeqen-arch-one kernel:  [<ffffffff81225283>] do_vfs_ioctl+0xa3/0x5f0
Feb 28 16:21:16 glaeqen-arch-one kernel:  [<ffffffff810f364b>] ? do_setitimer+0x12b/0x230
Feb 28 16:21:16 glaeqen-arch-one kernel:  [<ffffffff812306c7>] ? __fget+0x77/0xb0
Feb 28 16:21:16 glaeqen-arch-one kernel:  [<ffffffff81225849>] SyS_ioctl+0x79/0x90
Feb 28 16:21:16 glaeqen-arch-one kernel:  [<ffffffff8160aaf7>] entry_SYSCALL_64_fastpath+0x1a/0xa9
Feb 28 16:21:16 glaeqen-arch-one kernel: Code: 44 36 61 a0 e8 d5 65 fc ff 48 8d 83 40 01 00 00 4c 89 73 68 4c 89 63 78 48 89 83 40 01 00 00 48 89 83 48 01 00 00 b8 01 00 00 00 <f0> 41 0f c1 45 00 83 c0 01 83 f8 01 0f 8e f8 00 00 00 8b 05 a3 
Feb 28 16:21:16 glaeqen-arch-one kernel: RIP  [<ffffffffa0614c6d>] i915_gem_request_alloc+0x10d/0x260 [i915]
Feb 28 16:21:16 glaeqen-arch-one kernel:  RSP <ffffc9000047bc78>
Feb 28 16:21:16 glaeqen-arch-one kernel: CR2: 0000000000000000
Feb 28 16:21:16 glaeqen-arch-one kernel: ---[ end trace fddaac041586b3d2 ]---
Feb 28 16:21:16 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): enp1s0: link is not ready
Feb 28 16:21:16 glaeqen-arch-one kernel: psmouse serio2: synaptics: queried max coordinates: x [..5612], y [..4618]
Feb 28 16:21:16 glaeqen-arch-one kernel: r8169 0000:01:00.0 enp1s0: link down
Feb 28 16:21:16 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): enp1s0: link is not ready
Feb 28 16:21:16 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Feb 28 16:21:16 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Feb 28 16:21:16 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement)
Feb 28 16:21:16 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Feb 28 16:21:16 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Feb 28 16:21:16 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement)
Feb 28 16:21:16 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Feb 28 16:21:16 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Feb 28 16:21:16 glaeqen-arch-one kernel: ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Feb 28 16:21:16 glaeqen-arch-one kernel: ata1.00: unexpected _GTF length (4)
Feb 28 16:21:16 glaeqen-arch-one kernel: ata1.00: unexpected _GTF length (4)
Feb 28 16:21:16 glaeqen-arch-one kernel: ata1.00: configured for UDMA/133
Feb 28 16:21:16 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Feb 28 16:21:16 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement)
Feb 28 16:21:16 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Feb 28 16:21:14 glaeqen-arch-one systemd[481]: Time has been changed
Feb 28 16:21:14 glaeqen-arch-one systemd[1]: Time has been changed
Feb 28 16:21:14 glaeqen-arch-one systemd-logind[390]: Lid opened.
Feb 28 16:21:14 glaeqen-arch-one systemd-sleep[22719]: System resumed.
Feb 28 16:21:14 glaeqen-arch-one systemd[1]: Started Suspend.
Feb 28 16:21:14 glaeqen-arch-one systemd[1]: sleep.target: Unit not needed anymore. Stopping.
Feb 28 16:21:14 glaeqen-arch-one systemd[1]: Stopped target Sleep.
Feb 28 16:21:14 glaeqen-arch-one systemd[1]: tlp-sleep.service: Unit not needed anymore. Stopping.
Feb 28 16:21:14 glaeqen-arch-one systemd[1]: Stopping TLP suspend/resume...
Feb 28 16:21:14 glaeqen-arch-one systemd[1]: Reached target Suspend.
Feb 28 16:21:14 glaeqen-arch-one systemd-logind[390]: Operation 'sleep' finished.
Feb 28 16:21:14 glaeqen-arch-one NetworkManager[396]: <info>  [1488295274.5660] manager: wake requested (sleeping: yes  enabled: yes)
Feb 28 16:21:14 glaeqen-arch-one NetworkManager[396]: <info>  [1488295274.5661] manager: waking up...
Feb 28 16:21:14 glaeqen-arch-one NetworkManager[396]: <info>  [1488295274.5663] device (enp1s0): state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Feb 28 16:21:14 glaeqen-arch-one systemd[1]: suspend.target: Unit is bound to inactive unit systemd-suspend.service. Stopping, too.
Feb 28 16:21:14 glaeqen-arch-one systemd[1]: Stopped target Suspend.
Feb 28 16:21:14 glaeqen-arch-one NetworkManager[396]: <info>  [1488295274.8213] device (enp1s0): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Feb 28 16:21:15 glaeqen-arch-one NetworkManager[396]: <info>  [1488295275.0384] device (wlp2s0b1): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Feb 28 16:21:15 glaeqen-arch-one NetworkManager[396]: <info>  [1488295275.2481] device (wlp2s0b1): set-hw-addr: set MAC address to 02:EE:C1:F1:E5:14 (scanning)
Feb 28 16:21:15 glaeqen-arch-one NetworkManager[396]: <info>  [1488295275.4182] manager: NetworkManager state is now DISCONNECTED
Feb 28 16:21:15 glaeqen-arch-one NetworkManager[396]: <info>  [1488295275.5088] sup-iface[0x25bce30,wlp2s0b1]: supports 4 scan SSIDs
Feb 28 16:21:15 glaeqen-arch-one NetworkManager[396]: <info>  [1488295275.5148] device (wlp2s0b1): supplicant interface state: starting -> ready
Feb 28 16:21:15 glaeqen-arch-one NetworkManager[396]: <info>  [1488295275.5151] device (wlp2s0b1): state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Feb 28 16:21:16 glaeqen-arch-one NetworkManager[396]: <info>  [1488295276.6012] device (wlp2s0b1): supplicant interface state: ready -> inactive
Feb 28 16:21:16 glaeqen-arch-one NetworkManager[396]: <info>  [1488295276.7413] policy: auto-activating connection 'AndroidAP'
Feb 28 16:21:16 glaeqen-arch-one NetworkManager[396]: <info>  [1488295276.7481] device (wlp2s0b1): Activation: starting connection 'AndroidAP' (da6c0c41-14b0-4fb6-8773-a641af5cacca)
Feb 28 16:21:16 glaeqen-arch-one NetworkManager[396]: <info>  [1488295276.7495] device (wlp2s0b1): state change: disconnected -> prepare (reason 'none') [30 40 0]
Feb 28 16:21:16 glaeqen-arch-one NetworkManager[396]: <info>  [1488295276.7503] manager: NetworkManager state is now CONNECTING
Feb 28 16:21:16 glaeqen-arch-one NetworkManager[396]: <info>  [1488295276.7756] device (wlp2s0b1): set-hw-addr: reset MAC address to E4:D5:3D:4B:96:1E (preserve)
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0007] device (wlp2s0b1): supplicant interface state: inactive -> disabled
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0031] device (wlp2s0b1): state change: prepare -> config (reason 'none') [40 50 0]
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0087] device (wlp2s0b1): Activation: (wifi) access point 'AndroidAP' has security, but secrets are required.
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0089] device (wlp2s0b1): state change: config -> need-auth (reason 'none') [50 60 0]
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0772] device (wlp2s0b1): supplicant interface state: disabled -> inactive
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0790] device (wlp2s0b1): state change: need-auth -> prepare (reason 'none') [60 40 0]
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0840] device (wlp2s0b1): state change: prepare -> config (reason 'none') [40 50 0]
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0859] device (wlp2s0b1): Activation: (wifi) connection 'AndroidAP' has security, and secrets exist.  No new secrets needed.
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0860] Config: added 'ssid' value 'AndroidAP'
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0861] Config: added 'scan_ssid' value '1'
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0862] Config: added 'key_mgmt' value 'WPA-PSK'
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0863] Config: added 'auth_alg' value 'OPEN'
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0864] Config: added 'psk' value '<hidden>'
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.0901] sup-iface[0x25bce30,wlp2s0b1]: config: set interface ap_scan to 1
Feb 28 16:21:17 glaeqen-arch-one NetworkManager[396]: <info>  [1488295277.1586] device (wlp2s0b1): supplicant interface state: inactive -> scanning
Feb 28 16:21:17 glaeqen-arch-one systemd[1]: Stopped TLP suspend/resume.
Feb 28 16:21:18 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: SME: Trying to authenticate with 7c:91:22:2e:ac:d2 (SSID='AndroidAP' freq=2462 MHz)
Feb 28 16:21:18 glaeqen-arch-one kernel: wlp2s0b1: authenticate with 7c:91:22:2e:ac:d2
Feb 28 16:21:18 glaeqen-arch-one kernel: wlp2s0b1: send auth to 7c:91:22:2e:ac:d2 (try 1/3)
Feb 28 16:21:18 glaeqen-arch-one NetworkManager[396]: <info>  [1488295278.2068] device (wlp2s0b1): supplicant interface state: scanning -> authenticating
Feb 28 16:21:18 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: Trying to associate with 7c:91:22:2e:ac:d2 (SSID='AndroidAP' freq=2462 MHz)
Feb 28 16:21:18 glaeqen-arch-one kernel: wlp2s0b1: authenticated
Feb 28 16:21:18 glaeqen-arch-one kernel: wlp2s0b1: associate with 7c:91:22:2e:ac:d2 (try 1/3)
Feb 28 16:21:18 glaeqen-arch-one NetworkManager[396]: <info>  [1488295278.2310] device (wlp2s0b1): supplicant interface state: authenticating -> associating
Feb 28 16:21:18 glaeqen-arch-one kernel: wlp2s0b1: RX AssocResp from 7c:91:22:2e:ac:d2 (capab=0x8431 status=0 aid=24)
Feb 28 16:21:18 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: associated
Feb 28 16:21:18 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement)
Feb 28 16:21:18 glaeqen-arch-one kernel: wlp2s0b1: associated
Feb 28 16:21:18 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0b1: link becomes ready
Feb 28 16:21:18 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: Associated with 7c:91:22:2e:ac:d2
Feb 28 16:21:18 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Feb 28 16:21:18 glaeqen-arch-one NetworkManager[396]: <info>  [1488295278.2873] device (wlp2s0b1): supplicant interface state: associating -> associated
Feb 28 16:21:18 glaeqen-arch-one kernel: brcmsmac bcma0:1: wl0: brcms_c_d11hdrs_mac80211:  txop exceeded phylen 159/256 dur 1778/1504
Feb 28 16:21:18 glaeqen-arch-one NetworkManager[396]: <info>  [1488295278.3530] device (wlp2s0b1): supplicant interface state: associated -> 4-way handshake
Feb 28 16:21:19 glaeqen-arch-one kernel: brcmsmac bcma0:1: wl0: brcms_c_d11hdrs_mac80211:  txop exceeded phylen 159/256 dur 1778/1504
Feb 28 16:21:20 glaeqen-arch-one kernel: brcmsmac bcma0:1: wl0: brcms_c_d11hdrs_mac80211:  txop exceeded phylen 159/256 dur 1778/1504
Feb 28 16:21:21 glaeqen-arch-one kernel: brcmsmac bcma0:1: wl0: brcms_c_d11hdrs_mac80211:  txop exceeded phylen 159/256 dur 1778/1504
Feb 28 16:21:22 glaeqen-arch-one kernel: wlp2s0b1: deauthenticated from 7c:91:22:2e:ac:d2 (Reason: 3=DEAUTH_LEAVING)
Feb 28 16:21:22 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: CTRL-EVENT-DISCONNECTED bssid=7c:91:22:2e:ac:d2 reason=3
Feb 28 16:21:22 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: WPA: 4-Way Handshake failed - pre-shared key may be incorrect
Feb 28 16:21:22 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="AndroidAP" auth_failures=1 duration=10 reason=WRONG_KEY
Feb 28 16:21:22 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: disassociated
Feb 28 16:21:22 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Feb 28 16:21:22 glaeqen-arch-one NetworkManager[396]: <warn>  [1488295282.3790] sup-iface[0x25bce30,wlp2s0b1]: connection disconnected (reason 3)
Feb 28 16:21:22 glaeqen-arch-one NetworkManager[396]: <info>  [1488295282.3829] device (wlp2s0b1): supplicant interface state: 4-way handshake -> disconnected
Feb 28 16:21:22 glaeqen-arch-one NetworkManager[396]: <info>  [1488295282.3899] device (wlp2s0b1): Activation: (wifi) disconnected during association, asking for new key
Feb 28 16:21:22 glaeqen-arch-one NetworkManager[396]: <info>  [1488295282.3905] device (wlp2s0b1): state change: config -> need-auth (reason 'supplicant-disconnect') [50 60 8]
Feb 28 16:21:22 glaeqen-arch-one NetworkManager[396]: <warn>  [1488295282.4026] device (wlp2s0b1): No agents were available for this request.
Feb 28 16:21:22 glaeqen-arch-one NetworkManager[396]: <info>  [1488295282.4027] device (wlp2s0b1): state change: need-auth -> failed (reason 'no-secrets') [60 120 7]
Feb 28 16:21:22 glaeqen-arch-one NetworkManager[396]: <info>  [1488295282.4039] manager: NetworkManager state is now DISCONNECTED
Feb 28 16:21:22 glaeqen-arch-one NetworkManager[396]: <warn>  [1488295282.4061] device (wlp2s0b1): Activation: failed for connection 'AndroidAP'
Feb 28 16:21:22 glaeqen-arch-one NetworkManager[396]: <info>  [1488295282.4108] device (wlp2s0b1): state change: failed -> disconnected (reason 'none') [120 30 0]
Feb 28 16:21:22 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Feb 28 16:21:22 glaeqen-arch-one NetworkManager[396]: <info>  [1488295282.4313] device (wlp2s0b1): set-hw-addr: set MAC address to 32:C1:63:33:33:DC (scanning)
Feb 28 16:21:22 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Feb 28 16:21:22 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement)
Feb 28 16:21:22 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Feb 28 16:21:22 glaeqen-arch-one NetworkManager[396]: <info>  [1488295282.6246] device (wlp2s0b1): supplicant interface state: disconnected -> disabled
Feb 28 16:21:22 glaeqen-arch-one NetworkManager[396]: <info>  [1488295282.6453] device (wlp2s0b1): supplicant interface state: disabled -> inactive
Feb 28 16:21:22 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: Reject scan trigger since one is already pending
Feb 28 16:21:23 glaeqen-arch-one NetworkManager[396]: <info>  [1488295283.8163] policy: auto-activating connection 'J3st3_z13mn14k13'
Feb 28 16:21:23 glaeqen-arch-one NetworkManager[396]: <info>  [1488295283.8208] device (wlp2s0b1): Activation: starting connection 'J3st3_z13mn14k13' (a04b2b64-53e9-45b6-92ca-089831bf8f79)
Feb 28 16:21:23 glaeqen-arch-one NetworkManager[396]: <info>  [1488295283.8220] device (wlp2s0b1): state change: disconnected -> prepare (reason 'none') [30 40 0]
Feb 28 16:21:23 glaeqen-arch-one NetworkManager[396]: <info>  [1488295283.8227] manager: NetworkManager state is now CONNECTING
Feb 28 16:21:23 glaeqen-arch-one NetworkManager[396]: <info>  [1488295283.8439] device (wlp2s0b1): set-hw-addr: reset MAC address to E4:D5:3D:4B:96:1E (preserve)
Feb 28 16:21:24 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Feb 28 16:21:24 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement)
Feb 28 16:21:24 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.0711] device (wlp2s0b1): supplicant interface state: inactive -> disabled
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.0725] device (wlp2s0b1): supplicant interface state: disabled -> inactive
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.0741] device (wlp2s0b1): state change: prepare -> config (reason 'none') [40 50 0]
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.0752] device (wlp2s0b1): Activation: (wifi) access point 'J3st3_z13mn14k13' has security, but secrets are required.
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.0754] device (wlp2s0b1): state change: config -> need-auth (reason 'none') [50 60 0]
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.1082] device (wlp2s0b1): state change: need-auth -> prepare (reason 'none') [60 40 0]
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.1105] device (wlp2s0b1): state change: prepare -> config (reason 'none') [40 50 0]
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.1117] device (wlp2s0b1): Activation: (wifi) connection 'J3st3_z13mn14k13' has security, and secrets exist.  No new secrets needed.
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.1119] Config: added 'ssid' value 'J3st3_z13mn14k13'
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.1119] Config: added 'scan_ssid' value '1'
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.1120] Config: added 'key_mgmt' value 'WPA-PSK'
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.1121] Config: added 'auth_alg' value 'OPEN'
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.1122] Config: added 'psk' value '<hidden>'
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.1309] sup-iface[0x25bce30,wlp2s0b1]: config: set interface ap_scan to 1
Feb 28 16:21:24 glaeqen-arch-one NetworkManager[396]: <info>  [1488295284.1919] device (wlp2s0b1): supplicant interface state: inactive -> scanning
Feb 28 16:21:25 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: SME: Trying to authenticate with 54:e6:fc:fd:36:0c (SSID='J3st3_z13mn14k13' freq=2427 MHz)
Feb 28 16:21:25 glaeqen-arch-one kernel: wlp2s0b1: authenticate with 54:e6:fc:fd:36:0c
Feb 28 16:21:25 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: Trying to associate with 54:e6:fc:fd:36:0c (SSID='J3st3_z13mn14k13' freq=2427 MHz)
Feb 28 16:21:25 glaeqen-arch-one kernel: wlp2s0b1: send auth to 54:e6:fc:fd:36:0c (try 1/3)
Feb 28 16:21:25 glaeqen-arch-one kernel: wlp2s0b1: authenticated
Feb 28 16:21:25 glaeqen-arch-one kernel: brcmsmac bcma0:1 wlp2s0b1: disabling HT as WMM/QoS is not supported by the AP
Feb 28 16:21:25 glaeqen-arch-one kernel: brcmsmac bcma0:1 wlp2s0b1: disabling VHT as WMM/QoS is not supported by the AP
Feb 28 16:21:25 glaeqen-arch-one kernel: wlp2s0b1: associating with AP with corrupt probe response
Feb 28 16:21:25 glaeqen-arch-one kernel: wlp2s0b1: associate with 54:e6:fc:fd:36:0c (try 1/3)
Feb 28 16:21:25 glaeqen-arch-one kernel: wlp2s0b1: RX AssocResp from 54:e6:fc:fd:36:0c (capab=0x431 status=0 aid=3)
Feb 28 16:21:25 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: associated
Feb 28 16:21:25 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement)
Feb 28 16:21:25 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0b1: link becomes ready
Feb 28 16:21:25 glaeqen-arch-one kernel: wlp2s0b1: associated
Feb 28 16:21:25 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: Associated with 54:e6:fc:fd:36:0c
Feb 28 16:21:25 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.2515] device (wlp2s0b1): supplicant interface state: scanning -> authenticating
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.2530] device (wlp2s0b1): supplicant interface state: authenticating -> associating
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.2580] device (wlp2s0b1): supplicant interface state: associating -> associated
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.6736] device (wlp2s0b1): supplicant interface state: associated -> 4-way handshake
Feb 28 16:21:25 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: WPA: Key negotiation completed with 54:e6:fc:fd:36:0c [PTK=CCMP GTK=TKIP]
Feb 28 16:21:25 glaeqen-arch-one wpa_supplicant[465]: wlp2s0b1: CTRL-EVENT-CONNECTED - Connection to 54:e6:fc:fd:36:0c completed [id=0 id_str=]
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.6815] device (wlp2s0b1): supplicant interface state: 4-way handshake -> completed
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.6817] device (wlp2s0b1): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'J3st3_z13mn14k13'.
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.6823] device (wlp2s0b1): state change: config -> ip-config (reason 'none') [50 70 0]
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.6858] dhcp4 (wlp2s0b1): activation: beginning transaction (timeout in 45 seconds)
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.8275] dhcp4 (wlp2s0b1):   address 192.168.1.104
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.8277] dhcp4 (wlp2s0b1):   plen 24
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.8278] dhcp4 (wlp2s0b1):   expires in 7200 seconds
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.8280] dhcp4 (wlp2s0b1):   nameserver '192.168.240.2'
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.8281] dhcp4 (wlp2s0b1):   nameserver '192.168.192.2'
Feb 28 16:21:25 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 1 addresses (implement)
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.8282] dhcp4 (wlp2s0b1):   gateway 192.168.1.1
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.8284] dhcp4 (wlp2s0b1):   mtu 576
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.8295] dhcp4 (wlp2s0b1): state changed unknown -> bound
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.8432] device (wlp2s0b1): state change: ip-config -> ip-check (reason 'none') [70 80 0]
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.8501] device (wlp2s0b1): state change: ip-check -> secondaries (reason 'none') [80 90 0]
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.8537] device (wlp2s0b1): state change: secondaries -> activated (reason 'none') [90 100 0]
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.8554] manager: NetworkManager state is now CONNECTED_LOCAL
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.9028] manager: NetworkManager state is now CONNECTED_SITE
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.9034] policy: set 'J3st3_z13mn14k13' (wlp2s0b1) as default for IPv4 routing and DNS
Feb 28 16:21:25 glaeqen-arch-one NetworkManager[396]: <info>  [1488295285.9043] dns-mgr: Writing DNS information to /usr/bin/resolvconf
Feb 28 16:21:26 glaeqen-arch-one NetworkManager[396]: <info>  [1488295286.1882] device (wlp2s0b1): Activation: successful, device activated.
Feb 28 16:21:26 glaeqen-arch-one dbus[392]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Feb 28 16:21:26 glaeqen-arch-one systemd[1]: Starting Network Manager Script Dispatcher Service...
Feb 28 16:21:26 glaeqen-arch-one dbus[392]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Feb 28 16:21:26 glaeqen-arch-one systemd[1]: Started Network Manager Script Dispatcher Service.
Feb 28 16:21:26 glaeqen-arch-one nm-dispatcher[23437]: req:1 'up' [wlp2s0b1]: new request (0 scripts)
Feb 28 16:21:26 glaeqen-arch-one nm-dispatcher[23437]: req:1 'up' [wlp2s0b1]: completed: no scripts
Feb 28 16:21:27 glaeqen-arch-one NetworkManager[396]: <info>  [1488295287.3210] manager: NetworkManager state is now CONNECTED_GLOBAL
Feb 28 16:21:27 glaeqen-arch-one nm-dispatcher[23437]: req:2 'connectivity-change': new request (0 scripts)
Feb 28 16:21:27 glaeqen-arch-one nm-dispatcher[23437]: req:2 'connectivity-change': completed: no scripts
Feb 28 16:21:46 glaeqen-arch-one systemd-logind[390]: Power key pressed.
Feb 28 16:21:46 glaeqen-arch-one systemd-logind[390]: Powering Off...
Feb 28 16:21:46 glaeqen-arch-one systemd-logind[390]: System is powering down.
-- Reboot --

Another, more recent example

Mar 14 01:12:36 glaeqen-arch-one systemd-logind[398]: Lid closed.
Mar 14 01:12:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489450357.9317] manager: NetworkManager state is now CONNECTED_GLOBAL
Mar 14 01:12:37 glaeqen-arch-one nm-dispatcher[23736]: req:4 'connectivity-change': new request (0 scripts)
Mar 14 01:12:37 glaeqen-arch-one nm-dispatcher[23736]: req:4 'connectivity-change': completed: no scripts
Mar 14 01:13:00 glaeqen-arch-one systemd-logind[398]: Suspending...
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.2580] manager: sleep requested (sleeping: no  enabled: yes)
Mar 14 01:13:00 glaeqen-arch-one dbus[399]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.2581] manager: sleeping...
Mar 14 01:13:00 glaeqen-arch-one dbus[399]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.2584] manager: NetworkManager state is now ASLEEP
Mar 14 01:13:00 glaeqen-arch-one nm-dispatcher[25022]: req:1 'connectivity-change': new request (0 scripts)
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.2606] device (wlp2s0b1): state change: activated -> deactivating (reason 'sleeping') [100 110 37]
Mar 14 01:13:00 glaeqen-arch-one nm-dispatcher[25022]: req:1 'connectivity-change': completed: no scripts
Mar 14 01:13:00 glaeqen-arch-one systemd[1]: Starting Network Manager Script Dispatcher Service...
Mar 14 01:13:00 glaeqen-arch-one systemd[1]: Started Network Manager Script Dispatcher Service.
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.4060] device (wlp2s0b1): state change: deactivating -> disconnected (reason 'sleeping') [110 30 37]
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.4075] dhcp4 (wlp2s0b1): canceled DHCP transaction
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.4076] dhcp4 (wlp2s0b1): state changed bound -> done
Mar 14 01:13:00 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 0 addresses (implement)
Mar 14 01:13:00 glaeqen-arch-one kernel: wlp2s0b1: deauthenticating from 54:e6:fc:fd:36:0c by local choice (Reason: 3=DEAUTH_LEAVING)
Mar 14 01:13:00 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: disassociated
Mar 14 01:13:00 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Mar 14 01:13:00 glaeqen-arch-one wpa_supplicant[457]: wlp2s0b1: CTRL-EVENT-DISCONNECTED bssid=54:e6:fc:fd:36:0c reason=3 locally_generated=1
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.4394] device (wlp2s0b1): set-hw-addr: set MAC address to EE:9A:C2:4E:1B:EB (scanning)
Mar 14 01:13:00 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Mar 14 01:13:00 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement)
Mar 14 01:13:00 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.6089] dns-mgr: Removing DNS information from /usr/bin/resolvconf
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <warn>  [1489450380.8453] sup-iface[0x1bb28d0,wlp2s0b1]: connection disconnected (reason -3)
Mar 14 01:13:00 glaeqen-arch-one nm-dispatcher[25022]: req:2 'down' [wlp2s0b1]: new request (0 scripts)
Mar 14 01:13:00 glaeqen-arch-one nm-dispatcher[25022]: req:2 'down' [wlp2s0b1]: completed: no scripts
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.8493] device (wlp2s0b1): supplicant interface state: completed -> disconnected
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.8972] device (wlp2s0b1): supplicant interface state: disconnected -> disabled
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.8980] device (wlp2s0b1): supplicant interface state: disabled -> disconnected
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.9038] device (wlp2s0b1): state change: disconnected -> unmanaged (reason 'sleeping') [30 10 37]
Mar 14 01:13:00 glaeqen-arch-one NetworkManager[403]: <info>  [1489450380.9285] device (wlp2s0b1): set-hw-addr: reset MAC address to E4:D5:3D:4B:96:1E (unmanage)
Mar 14 01:13:00 glaeqen-arch-one systemd[1]: Starting TLP suspend/resume...
Mar 14 01:13:00 glaeqen-arch-one wpa_supplicant[457]: nl80211: deinit ifname=wlp2s0b1 disabled_11b_rates=0
Mar 14 01:13:01 glaeqen-arch-one systemd[1]: Started TLP suspend/resume.
Mar 14 01:13:01 glaeqen-arch-one systemd[1]: Reached target Sleep.
Mar 14 01:13:01 glaeqen-arch-one systemd[1]: Starting Suspend...
Mar 14 01:13:01 glaeqen-arch-one systemd-sleep[25146]: Suspending system...
Mar 14 02:08:09 glaeqen-arch-one kernel: PM: Syncing filesystems ... done.
Mar 14 02:08:09 glaeqen-arch-one kernel: PM: Preparing system for sleep (mem)
Mar 14 02:08:09 glaeqen-arch-one kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
Mar 14 02:08:09 glaeqen-arch-one kernel: Freezing remaining freezable tasks ... (elapsed 0.000 seconds) done.
Mar 14 02:08:09 glaeqen-arch-one kernel: PM: Suspending system (mem)
Mar 14 02:08:09 glaeqen-arch-one kernel: Suspending console(s) (use no_console_suspend to debug)
Mar 14 02:08:09 glaeqen-arch-one kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 14 02:08:09 glaeqen-arch-one kernel: ACPI : EC: event blocked
Mar 14 02:08:09 glaeqen-arch-one kernel: sd 0:0:0:0: [sda] Stopping disk
Mar 14 02:08:09 glaeqen-arch-one kernel: PM: suspend of devices complete after 469.541 msecs
Mar 14 02:08:09 glaeqen-arch-one kernel: PM: late suspend of devices complete after 16.501 msecs
Mar 14 02:08:09 glaeqen-arch-one kernel: ACPI : EC: interrupt blocked
Mar 14 02:08:09 glaeqen-arch-one kernel: pcieport 0000:00:1c.0: System wakeup enabled by ACPI
Mar 14 02:08:09 glaeqen-arch-one kernel: ehci-pci 0000:00:1d.7: System wakeup enabled by ACPI
Mar 14 02:08:09 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.3: System wakeup enabled by ACPI
Mar 14 02:08:09 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.1: System wakeup enabled by ACPI
Mar 14 02:08:09 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.0: System wakeup enabled by ACPI
Mar 14 02:08:09 glaeqen-arch-one kernel: PM: noirq suspend of devices complete after 16.883 msecs
Mar 14 02:08:09 glaeqen-arch-one kernel: ACPI: Preparing to enter system sleep state S3
Mar 14 02:08:09 glaeqen-arch-one kernel: ACPI : EC: EC stopped
Mar 14 02:08:09 glaeqen-arch-one kernel: PM: Saving platform NVS memory
Mar 14 02:08:09 glaeqen-arch-one kernel: Disabling non-boot CPUs ...
Mar 14 02:08:09 glaeqen-arch-one kernel: smpboot: CPU 1 is now offline
Mar 14 02:08:09 glaeqen-arch-one kernel: smpboot: CPU 2 is now offline
Mar 14 02:08:09 glaeqen-arch-one kernel: smpboot: CPU 3 is now offline
Mar 14 02:08:09 glaeqen-arch-one kernel: ACPI: Low-level resume complete
Mar 14 02:08:09 glaeqen-arch-one kernel: ACPI : EC: EC started
Mar 14 02:08:09 glaeqen-arch-one kernel: PM: Restoring platform NVS memory
Mar 14 02:08:09 glaeqen-arch-one kernel: Suspended for 3306.176 seconds
Mar 14 02:08:09 glaeqen-arch-one kernel: Enabling non-boot CPUs ...
Mar 14 02:08:09 glaeqen-arch-one kernel: x86: Booting SMP configuration:
Mar 14 02:08:09 glaeqen-arch-one kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Mar 14 02:08:09 glaeqen-arch-one kernel: Disabled fast string operations
Mar 14 02:08:09 glaeqen-arch-one kernel:  cache: parent cpu1 should not be sleeping
Mar 14 02:08:09 glaeqen-arch-one kernel: CPU1 is up
Mar 14 02:08:09 glaeqen-arch-one kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Mar 14 02:08:09 glaeqen-arch-one kernel: Disabled fast string operations
Mar 14 02:08:09 glaeqen-arch-one kernel:  cache: parent cpu2 should not be sleeping
Mar 14 02:08:09 glaeqen-arch-one kernel: CPU2 is up
Mar 14 02:08:09 glaeqen-arch-one kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Mar 14 02:08:09 glaeqen-arch-one kernel: Disabled fast string operations
Mar 14 02:08:09 glaeqen-arch-one kernel:  cache: parent cpu3 should not be sleeping
Mar 14 02:08:09 glaeqen-arch-one kernel: CPU3 is up
Mar 14 02:08:09 glaeqen-arch-one kernel: ACPI: Waking up from system sleep state S3
Mar 14 02:08:09 glaeqen-arch-one kernel: ACPI : EC: interrupt unblocked
Mar 14 02:08:09 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.0: System wakeup disabled by ACPI
Mar 14 02:08:09 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.3: System wakeup disabled by ACPI
Mar 14 02:08:09 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.1: System wakeup disabled by ACPI
Mar 14 02:08:09 glaeqen-arch-one kernel: ehci-pci 0000:00:1d.7: System wakeup disabled by ACPI
Mar 14 02:08:09 glaeqen-arch-one kernel: PM: noirq resume of devices complete after 20.448 msecs
Mar 14 02:08:09 glaeqen-arch-one kernel: PM: early resume of devices complete after 3.153 msecs
Mar 14 02:08:09 glaeqen-arch-one kernel: usb usb2: root hub lost power or was reset
Mar 14 02:08:09 glaeqen-arch-one kernel: usb usb3: root hub lost power or was reset
Mar 14 02:08:09 glaeqen-arch-one kernel: usb usb4: root hub lost power or was reset
Mar 14 02:08:09 glaeqen-arch-one kernel: sd 0:0:0:0: [sda] Starting disk
Mar 14 02:08:09 glaeqen-arch-one kernel: pcieport 0000:00:1c.0: System wakeup disabled by ACPI
Mar 14 02:08:09 glaeqen-arch-one kernel: ACPI : EC: event unblocked
Mar 14 02:08:09 glaeqen-arch-one kernel: rtc_cmos 00:01: System wakeup disabled by ACPI
Mar 14 02:08:09 glaeqen-arch-one kernel: r8169 0000:01:00.0 enp1s0: link down
Mar 14 02:08:09 glaeqen-arch-one kernel: usb 1-3: reset high-speed USB device number 2 using ehci-pci
Mar 14 02:08:09 glaeqen-arch-one kernel: ata2: SATA link down (SStatus 0 SControl 300)
Mar 14 02:08:09 glaeqen-arch-one kernel: PM: resume of devices complete after 445.431 msecs
Mar 14 02:08:09 glaeqen-arch-one kernel: PM: Finishing wakeup.
Mar 14 02:08:09 glaeqen-arch-one kernel: Restarting tasks ... 
Mar 14 02:08:09 glaeqen-arch-one kernel: BUG: unable to handle kernel NULL pointer dereference at 0000000000000018
Mar 14 02:08:09 glaeqen-arch-one kernel: IP: i915_gem_request_alloc+0xc5/0x3d0 [i915]
Mar 14 02:08:09 glaeqen-arch-one kernel: PGD 348e7067 
Mar 14 02:08:11 glaeqen-arch-one kernel: PUD 363e6067 
Mar 14 02:08:11 glaeqen-arch-one kernel: PMD 0 
Mar 14 02:08:11 glaeqen-arch-one kernel: 
Mar 14 02:08:11 glaeqen-arch-one kernel: Oops: 0000 [#1] PREEMPT SMP
Mar 14 02:08:11 glaeqen-arch-one kernel: Modules linked in: ctr ccm uvcvideo videobuf2_vmalloc videobuf2_memops videobuf2_v4l2 videobuf2_core videodev media arc4 brcmsmac cordic brcmutil b43 mac80211 cfg80211 joydev ssb mousedev rng_core pcmcia pcmcia_core snd_hda_codec_realtek snd_hda_codec_generic acer_wmi sparse_keymap snd_hda_intel rfkill i915 snd_hda_codec snd_hda_core snd_hwdep coretemp drm_kms_helper bcma rtsx_pci_ms snd_pcm r8169 evdev drm memstick mii snd_timer input_leds led_class mac_hid kvm syscopyarea pcspkr sysfillrect sysimgblt irqbypass fb_sys_fops psmouse snd i2c_algo_bit thermal shpchp i2c_i801 fjes wmi soundcore lpc_ich intel_agp video intel_gtt ac battery button acpi_cpufreq tpm_tis tpm_tis_core tpm sch_fq_codel ip_tables x_tables ext4 crc16 jbd2 mbcache sd_mod rtsx_pci_sdmmc mmc_core serio_raw atkbd libps2
Mar 14 02:08:11 glaeqen-arch-one kernel: done.
Mar 14 02:08:11 glaeqen-arch-one kernel:  ahci libahci uhci_hcd libata scsi_mod ehci_pci ehci_hcd usbcore usb_common rtsx_pci i8042 serio libcrc32c crc32c_generic f2fs fscrypto
Mar 14 02:08:11 glaeqen-arch-one kernel: CPU: 0 PID: 502 Comm: Xorg Not tainted 4.10.1-1-ARCH #1
Mar 14 02:08:11 glaeqen-arch-one kernel: Hardware name: Acer AOD257/JE06_PT , BIOS V1.15 10/21/2011
Mar 14 02:08:11 glaeqen-arch-one kernel: task: ffff88003b829a00 task.stack: ffffc900006e8000
Mar 14 02:08:11 glaeqen-arch-one kernel: RIP: 0010:i915_gem_request_alloc+0xc5/0x3d0 [i915]
Mar 14 02:08:11 glaeqen-arch-one kernel: RSP: 0018:ffffc900006ebc60 EFLAGS: 00010282
Mar 14 02:08:11 glaeqen-arch-one kernel: RAX: ffff88002ee75680 RBX: ffff88002ee75680 RCX: 0000000000358000
Mar 14 02:08:11 glaeqen-arch-one kernel: RDX: 0000000000357f80 RSI: 0000000000357f80 RDI: 0000000000020e40
Mar 14 02:08:11 glaeqen-arch-one kernel: RBP: ffffc900006ebc90 R08: ffff88002ee75680 R09: 0000000000000004
Mar 14 02:08:11 glaeqen-arch-one kernel: R10: 0000000000001001 R11: ffff88003b829a00 R12: ffff88003bea0000
Mar 14 02:08:11 glaeqen-arch-one kernel: R13: 0000000000000000 R14: ffff88003d07e000 R15: ffff880022868540
Mar 14 02:08:11 glaeqen-arch-one kernel: FS:  00007fbcf537c940(0000) GS:ffff88003f200000(0000) knlGS:0000000000000000
Mar 14 02:08:11 glaeqen-arch-one kernel: CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
Mar 14 02:08:11 glaeqen-arch-one kernel: CR2: 0000000000000018 CR3: 0000000037376000 CR4: 00000000000006f0
Mar 14 02:08:11 glaeqen-arch-one kernel: Call Trace:
Mar 14 02:08:11 glaeqen-arch-one kernel:  intel_crtc_page_flip+0x3ea/0xc30 [i915]
Mar 14 02:08:11 glaeqen-arch-one kernel:  drm_mode_page_flip_ioctl+0x36c/0x420 [drm]
Mar 14 02:08:11 glaeqen-arch-one kernel:  drm_ioctl+0x21b/0x4c0 [drm]
Mar 14 02:08:11 glaeqen-arch-one kernel:  ? drm_mode_cursor2_ioctl+0x10/0x10 [drm]
Mar 14 02:08:11 glaeqen-arch-one kernel:  ? do_signal+0x37/0x690
Mar 14 02:08:11 glaeqen-arch-one kernel:  ? __hrtimer_run_queues+0x2b0/0x2b0
Mar 14 02:08:11 glaeqen-arch-one kernel:  do_vfs_ioctl+0xa3/0x5f0
Mar 14 02:08:11 glaeqen-arch-one kernel:  ? __fget+0x77/0xb0
Mar 14 02:08:11 glaeqen-arch-one kernel:  SyS_ioctl+0x79/0x90
Mar 14 02:08:11 glaeqen-arch-one kernel:  entry_SYSCALL_64_fastpath+0x1a/0xa9
Mar 14 02:08:11 glaeqen-arch-one kernel: RIP: 0033:0x7fbcf31c60d7
Mar 14 02:08:11 glaeqen-arch-one kernel: RSP: 002b:00007ffdacaf1e38 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
Mar 14 02:08:11 glaeqen-arch-one kernel: RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007fbcf31c60d7
Mar 14 02:08:11 glaeqen-arch-one kernel: RDX: 00007ffdacaf1f00 RSI: 00000000c01864b0 RDI: 000000000000000b
Mar 14 02:08:11 glaeqen-arch-one kernel: RBP: 000000000082c044 R08: 00000000000007d0 R09: 0000000000000000
Mar 14 02:08:11 glaeqen-arch-one kernel: R10: 0068c67186a2d5aa R11: 0000000000000246 R12: 00000000009addf0
Mar 14 02:08:11 glaeqen-arch-one kernel: R13: 00000000008422c0 R14: 000000000084c388 R15: 00007ffdacaf2034
Mar 14 02:08:11 glaeqen-arch-one kernel: Code: 00 00 00 3b 42 b8 78 05 e8 b9 f3 ff ff 49 8b bc 24 70 06 00 00 be c0 00 40 01 e8 67 54 cb e0 48 85 c0 48 89 c3 0f 84 e9 02 00 00 <49> 8b 45 18 48 85 c0 0f 84 0e 02 00 00 41 8b 56 10 c7 43 60 00 
Mar 14 02:08:11 glaeqen-arch-one kernel: RIP: i915_gem_request_alloc+0xc5/0x3d0 [i915] RSP: ffffc900006ebc60
Mar 14 02:08:11 glaeqen-arch-one kernel: CR2: 0000000000000018
Mar 14 02:08:11 glaeqen-arch-one kernel: psmouse serio2: synaptics: Unable to query device.
Mar 14 02:08:11 glaeqen-arch-one kernel: ---[ end trace 0773d727968589a8 ]---
Mar 14 02:08:11 glaeqen-arch-one kernel: video LNXVIDEO:00: Restoring backlight state
Mar 14 02:08:11 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): enp1s0: link is not ready
Mar 14 02:08:11 glaeqen-arch-one kernel: r8169 0000:01:00.0 enp1s0: link down
Mar 14 02:08:11 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): enp1s0: link is not ready
Mar 14 02:08:11 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Mar 14 02:08:11 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Mar 14 02:08:11 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement)
Mar 14 02:08:11 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Mar 14 02:08:11 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Mar 14 02:08:11 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement)
Mar 14 02:08:11 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Mar 14 02:08:11 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Mar 14 02:08:11 glaeqen-arch-one kernel: ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 14 02:08:11 glaeqen-arch-one kernel: psmouse serio2: synaptics: queried max coordinates: x [..5612], y [..4618]
Mar 14 02:08:11 glaeqen-arch-one kernel: psmouse serio2: synaptics: Touchpad model: 1, fw: 7.2, id: 0x1c0b1, caps: 0xd04733/0xa40000/0xa0000/0x0, board id: 0, fw id: 528321
Mar 14 02:08:11 glaeqen-arch-one kernel: input: SynPS/2 Synaptics TouchPad as /devices/platform/i8042/serio2/input/input64
Mar 14 02:08:11 glaeqen-arch-one kernel: ata1.00: unexpected _GTF length (4)
Mar 14 02:08:11 glaeqen-arch-one kernel: ata1.00: unexpected _GTF length (4)
Mar 14 02:08:11 glaeqen-arch-one kernel: ata1.00: configured for UDMA/133
Mar 14 02:08:09 glaeqen-arch-one systemd-logind[398]: Lid opened.
Mar 14 02:08:09 glaeqen-arch-one systemd[1]: Time has been changed
Mar 14 02:08:09 glaeqen-arch-one systemd[465]: Time has been changed
Mar 14 02:08:09 glaeqen-arch-one systemd-sleep[25146]: System resumed.
Mar 14 02:08:09 glaeqen-arch-one systemd[1]: Started Suspend.
Mar 14 02:08:09 glaeqen-arch-one systemd[1]: sleep.target: Unit not needed anymore. Stopping.
Mar 14 02:08:09 glaeqen-arch-one systemd[1]: Stopped target Sleep.
Mar 14 02:08:09 glaeqen-arch-one systemd[1]: tlp-sleep.service: Unit not needed anymore. Stopping.
Mar 14 02:08:09 glaeqen-arch-one systemd[1]: Stopping TLP suspend/resume...
Mar 14 02:08:09 glaeqen-arch-one systemd[1]: Reached target Suspend.
Mar 14 02:08:09 glaeqen-arch-one systemd-logind[398]: Operation 'sleep' finished.
Mar 14 02:08:09 glaeqen-arch-one systemd[1]: suspend.target: Unit is bound to inactive unit systemd-suspend.service. Stopping, too.
Mar 14 02:08:09 glaeqen-arch-one systemd[1]: Stopped target Suspend.
Mar 14 02:08:09 glaeqen-arch-one NetworkManager[403]: <info>  [1489453689.7454] manager: wake requested (sleeping: yes  enabled: yes)
Mar 14 02:08:09 glaeqen-arch-one NetworkManager[403]: <info>  [1489453689.7455] manager: waking up...
Mar 14 02:08:09 glaeqen-arch-one NetworkManager[403]: <info>  [1489453689.7458] device (enp1s0): state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Mar 14 02:08:09 glaeqen-arch-one NetworkManager[403]: <info>  [1489453689.7612] device (enp1s0): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 14 02:08:09 glaeqen-arch-one NetworkManager[403]: <info>  [1489453689.9782] device (wlp2s0b1): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Mar 14 02:08:10 glaeqen-arch-one NetworkManager[403]: <info>  [1489453690.2300] device (wlp2s0b1): set-hw-addr: set MAC address to F6:A4:63:D9:88:91 (scanning)
Mar 14 02:08:10 glaeqen-arch-one NetworkManager[403]: <info>  [1489453690.4565] manager: NetworkManager state is now DISCONNECTED
Mar 14 02:08:10 glaeqen-arch-one NetworkManager[403]: <info>  [1489453690.5355] sup-iface[0x1bc7820,wlp2s0b1]: supports 4 scan SSIDs
Mar 14 02:08:10 glaeqen-arch-one NetworkManager[403]: <info>  [1489453690.5429] device (wlp2s0b1): supplicant interface state: starting -> ready
Mar 14 02:08:10 glaeqen-arch-one NetworkManager[403]: <info>  [1489453690.5434] device (wlp2s0b1): state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Mar 14 02:08:11 glaeqen-arch-one NetworkManager[403]: <info>  [1489453691.6376] device (wlp2s0b1): supplicant interface state: ready -> inactive
Mar 14 02:08:11 glaeqen-arch-one NetworkManager[403]: <info>  [1489453691.8020] policy: auto-activating connection 'J3st3_z13mn14k13'
Mar 14 02:08:11 glaeqen-arch-one NetworkManager[403]: <info>  [1489453691.8080] device (wlp2s0b1): Activation: starting connection 'J3st3_z13mn14k13' (a04b2b64-53e9-45b6-92ca-089831bf8f79)
Mar 14 02:08:11 glaeqen-arch-one NetworkManager[403]: <info>  [1489453691.8093] device (wlp2s0b1): state change: disconnected -> prepare (reason 'none') [30 40 0]
Mar 14 02:08:11 glaeqen-arch-one NetworkManager[403]: <info>  [1489453691.8101] manager: NetworkManager state is now CONNECTING
Mar 14 02:08:11 glaeqen-arch-one systemd[1]: Stopped TLP suspend/resume.
Mar 14 02:08:11 glaeqen-arch-one NetworkManager[403]: <info>  [1489453691.8404] device (wlp2s0b1): set-hw-addr: reset MAC address to E4:D5:3D:4B:96:1E (preserve)
Mar 14 02:08:12 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Mar 14 02:08:12 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement)
Mar 14 02:08:12 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.0903] device (wlp2s0b1): supplicant interface state: inactive -> disabled
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.0928] device (wlp2s0b1): state change: prepare -> config (reason 'none') [40 50 0]
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.0938] device (wlp2s0b1): Activation: (wifi) access point 'J3st3_z13mn14k13' has security, but secrets are required.
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.0939] device (wlp2s0b1): state change: config -> need-auth (reason 'none') [50 60 0]
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.1028] device (wlp2s0b1): supplicant interface state: disabled -> inactive
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.1173] device (wlp2s0b1): state change: need-auth -> prepare (reason 'none') [60 40 0]
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.1195] device (wlp2s0b1): state change: prepare -> config (reason 'none') [40 50 0]
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.1207] device (wlp2s0b1): Activation: (wifi) connection 'J3st3_z13mn14k13' has security, and secrets exist.  No new secrets needed.
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.1209] Config: added 'ssid' value 'J3st3_z13mn14k13'
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.1210] Config: added 'scan_ssid' value '1'
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.1212] Config: added 'key_mgmt' value 'WPA-PSK'
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.1213] Config: added 'auth_alg' value 'OPEN'
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.1214] Config: added 'psk' value '<hidden>'
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.1303] sup-iface[0x1bc7820,wlp2s0b1]: config: set interface ap_scan to 1
Mar 14 02:08:12 glaeqen-arch-one NetworkManager[403]: <info>  [1489453692.2163] device (wlp2s0b1): supplicant interface state: inactive -> scanning
Mar 14 02:08:13 glaeqen-arch-one wpa_supplicant[457]: wlp2s0b1: SME: Trying to authenticate with 54:e6:fc:fd:36:0c (SSID='J3st3_z13mn14k13' freq=2427 MHz)
Mar 14 02:08:13 glaeqen-arch-one kernel: wlp2s0b1: authenticate with 54:e6:fc:fd:36:0c
Mar 14 02:08:13 glaeqen-arch-one kernel: wlp2s0b1: send auth to 54:e6:fc:fd:36:0c (try 1/3)
Mar 14 02:08:13 glaeqen-arch-one kernel: wlp2s0b1: authenticated
Mar 14 02:08:13 glaeqen-arch-one wpa_supplicant[457]: wlp2s0b1: Trying to associate with 54:e6:fc:fd:36:0c (SSID='J3st3_z13mn14k13' freq=2427 MHz)
Mar 14 02:08:13 glaeqen-arch-one kernel: brcmsmac bcma0:1 wlp2s0b1: disabling HT as WMM/QoS is not supported by the AP
Mar 14 02:08:13 glaeqen-arch-one kernel: brcmsmac bcma0:1 wlp2s0b1: disabling VHT as WMM/QoS is not supported by the AP
Mar 14 02:08:13 glaeqen-arch-one kernel: wlp2s0b1: associating with AP with corrupt probe response
Mar 14 02:08:13 glaeqen-arch-one kernel: wlp2s0b1: associate with 54:e6:fc:fd:36:0c (try 1/3)
Mar 14 02:08:13 glaeqen-arch-one kernel: wlp2s0b1: RX AssocResp from 54:e6:fc:fd:36:0c (capab=0x431 status=0 aid=1)
Mar 14 02:08:13 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: associated
Mar 14 02:08:13 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: true (implement)
Mar 14 02:08:13 glaeqen-arch-one kernel: wlp2s0b1: associated
Mar 14 02:08:13 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0b1: link becomes ready
Mar 14 02:08:13 glaeqen-arch-one wpa_supplicant[457]: wlp2s0b1: Associated with 54:e6:fc:fd:36:0c
Mar 14 02:08:13 glaeqen-arch-one wpa_supplicant[457]: wlp2s0b1: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.2450] device (wlp2s0b1): supplicant interface state: scanning -> associating
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.2494] device (wlp2s0b1): supplicant interface state: associating -> associated
Mar 14 02:08:13 glaeqen-arch-one wpa_supplicant[457]: wlp2s0b1: WPA: Key negotiation completed with 54:e6:fc:fd:36:0c [PTK=CCMP GTK=TKIP]
Mar 14 02:08:13 glaeqen-arch-one wpa_supplicant[457]: wlp2s0b1: CTRL-EVENT-CONNECTED - Connection to 54:e6:fc:fd:36:0c completed [id=0 id_str=]
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.3668] device (wlp2s0b1): supplicant interface state: associated -> 4-way handshake
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.3672] device (wlp2s0b1): supplicant interface state: 4-way handshake -> completed
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.3674] device (wlp2s0b1): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'J3st3_z13mn14k13'.
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.3695] device (wlp2s0b1): state change: config -> ip-config (reason 'none') [50 70 0]
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.3712] dhcp4 (wlp2s0b1): activation: beginning transaction (timeout in 45 seconds)
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.4645] dhcp4 (wlp2s0b1):   address 192.168.1.104
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.4646] dhcp4 (wlp2s0b1):   plen 24
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.4647] dhcp4 (wlp2s0b1):   expires in 7200 seconds
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.4649] dhcp4 (wlp2s0b1):   nameserver '192.168.240.2'
Mar 14 02:08:13 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 1 addresses (implement)
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.4650] dhcp4 (wlp2s0b1):   nameserver '192.168.192.2'
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.4651] dhcp4 (wlp2s0b1):   gateway 192.168.1.1
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.4652] dhcp4 (wlp2s0b1):   mtu 576
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.4660] dhcp4 (wlp2s0b1): state changed unknown -> bound
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.4747] device (wlp2s0b1): state change: ip-config -> ip-check (reason 'none') [70 80 0]
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.4792] device (wlp2s0b1): state change: ip-check -> secondaries (reason 'none') [80 90 0]
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.4814] device (wlp2s0b1): state change: secondaries -> activated (reason 'none') [90 100 0]
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.4822] manager: NetworkManager state is now CONNECTED_LOCAL
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.6055] manager: NetworkManager state is now CONNECTED_SITE
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.6061] policy: set 'J3st3_z13mn14k13' (wlp2s0b1) as default for IPv4 routing and DNS
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.6065] dns-mgr: Writing DNS information to /usr/bin/resolvconf
Mar 14 02:08:13 glaeqen-arch-one NetworkManager[403]: <info>  [1489453693.8506] device (wlp2s0b1): Activation: successful, device activated.
Mar 14 02:08:13 glaeqen-arch-one nm-dispatcher[25022]: req:3 'up' [wlp2s0b1]: new request (0 scripts)
Mar 14 02:08:13 glaeqen-arch-one nm-dispatcher[25022]: req:3 'up' [wlp2s0b1]: completed: no scripts
Mar 14 02:08:14 glaeqen-arch-one NetworkManager[403]: <info>  [1489453694.9287] manager: NetworkManager state is now CONNECTED_GLOBAL
Mar 14 02:08:14 glaeqen-arch-one nm-dispatcher[25022]: req:4 'connectivity-change': new request (0 scripts)
Mar 14 02:08:14 glaeqen-arch-one nm-dispatcher[25022]: req:4 'connectivity-change': completed: no scripts
Mar 14 02:08:26 glaeqen-arch-one systemd-logind[398]: Lid closed.
Mar 14 02:08:37 glaeqen-arch-one systemd-logind[398]: Suspending...
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.1794] manager: sleep requested (sleeping: no  enabled: yes)
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.1795] manager: sleeping...
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.1798] manager: NetworkManager state is now ASLEEP
Mar 14 02:08:37 glaeqen-arch-one dbus[399]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.1837] device (wlp2s0b1): state change: activated -> deactivating (reason 'sleeping') [100 110 37]
Mar 14 02:08:37 glaeqen-arch-one systemd[1]: Starting Network Manager Script Dispatcher Service...
Mar 14 02:08:37 glaeqen-arch-one dbus[399]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mar 14 02:08:37 glaeqen-arch-one systemd[1]: Started Network Manager Script Dispatcher Service.
Mar 14 02:08:37 glaeqen-arch-one nm-dispatcher[25674]: req:1 'connectivity-change': new request (0 scripts)
Mar 14 02:08:37 glaeqen-arch-one nm-dispatcher[25674]: req:1 'connectivity-change': completed: no scripts
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.3061] device (wlp2s0b1): state change: deactivating -> disconnected (reason 'sleeping') [110 30 37]
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.3093] dhcp4 (wlp2s0b1): canceled DHCP transaction
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.3095] dhcp4 (wlp2s0b1): state changed bound -> done
Mar 14 02:08:37 glaeqen-arch-one kernel: wlp2s0b1: deauthenticating from 54:e6:fc:fd:36:0c by local choice (Reason: 3=DEAUTH_LEAVING)
Mar 14 02:08:37 glaeqen-arch-one wpa_supplicant[457]: wlp2s0b1: CTRL-EVENT-DISCONNECTED bssid=54:e6:fc:fd:36:0c reason=3 locally_generated=1
Mar 14 02:08:37 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: disassociated
Mar 14 02:08:37 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 1 addresses (implement)
Mar 14 02:08:37 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.3428] device (wlp2s0b1): set-hw-addr: set MAC address to 26:49:C1:B9:24:49 (scanning)
Mar 14 02:08:37 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Mar 14 02:08:37 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement)
Mar 14 02:08:37 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.5085] dns-mgr: Removing DNS information from /usr/bin/resolvconf
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <warn>  [1489453717.7429] sup-iface[0x1bc7820,wlp2s0b1]: connection disconnected (reason -3)
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.7440] device (wlp2s0b1): supplicant interface state: completed -> disconnected
Mar 14 02:08:37 glaeqen-arch-one nm-dispatcher[25674]: req:2 'down' [wlp2s0b1]: new request (0 scripts)
Mar 14 02:08:37 glaeqen-arch-one nm-dispatcher[25674]: req:2 'down' [wlp2s0b1]: completed: no scripts
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.7941] device (wlp2s0b1): supplicant interface state: disconnected -> disabled
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.7946] device (wlp2s0b1): supplicant interface state: disabled -> disconnected
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.7997] device (wlp2s0b1): state change: disconnected -> unmanaged (reason 'sleeping') [30 10 37]
Mar 14 02:08:37 glaeqen-arch-one NetworkManager[403]: <info>  [1489453717.8209] device (wlp2s0b1): set-hw-addr: reset MAC address to E4:D5:3D:4B:96:1E (unmanage)
Mar 14 02:08:37 glaeqen-arch-one systemd[1]: Starting TLP suspend/resume...
Mar 14 02:08:37 glaeqen-arch-one wpa_supplicant[457]: nl80211: deinit ifname=wlp2s0b1 disabled_11b_rates=0
Mar 14 02:08:38 glaeqen-arch-one systemd[1]: Started TLP suspend/resume.
Mar 14 02:08:38 glaeqen-arch-one systemd[1]: Reached target Sleep.
Mar 14 02:08:38 glaeqen-arch-one systemd[1]: Starting Suspend...
Mar 14 02:08:38 glaeqen-arch-one systemd-sleep[25773]: Suspending system...
-- Reboot --

At the end I manually force the shutdown by holding power key for a few secs.

Journal reports an error when it (I guess?) tries to reinitialise Intel video card driver? What's important, it's hardly reproducible because freeze is not always the case eg. when I suspend system only for few minutes (I guess it's because kernel/systemd re-initialisation schedules, I don't know how does that work). I'm not sure but it seems that freezes happen more rarely when charger is plugged in (but i have no idea if it's relevant or not).

For a comparison, situation when freeze DIDN'T happen

Mar 14 00:16:40 glaeqen-arch-one systemd-logind[398]: Lid closed.
Mar 14 00:16:41 glaeqen-arch-one systemd-udevd[23053]: Process '/usr/bin/tlp auto' failed with exit code 4.
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.6312] dhcp4 (wlp2s0b1):   address 192.168.1.104
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.6314] dhcp4 (wlp2s0b1):   plen 24
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.6315] dhcp4 (wlp2s0b1):   expires in 7200 seconds
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.6317] dhcp4 (wlp2s0b1):   nameserver '192.168.240.2'
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.6319] dhcp4 (wlp2s0b1):   nameserver '192.168.192.2'
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.6320] dhcp4 (wlp2s0b1):   gateway 192.168.1.1
Mar 14 00:16:41 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 1 addresses (implement)
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.6321] dhcp4 (wlp2s0b1):   mtu 576
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.6332] dhcp4 (wlp2s0b1): state changed unknown -> bound
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.6442] device (wlp2s0b1): state change: ip-config -> ip-check (reason 'none') [70 80 0]
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.6499] device (wlp2s0b1): state change: ip-check -> secondaries (reason 'none') [80 90 0]
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.6527] device (wlp2s0b1): state change: secondaries -> activated (reason 'none') [90 100 0]
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.6538] manager: NetworkManager state is now CONNECTED_LOCAL
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.7874] manager: NetworkManager state is now CONNECTED_SITE
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.7880] policy: set 'J3st3_z13mn14k13' (wlp2s0b1) as default for IPv4 routing and DNS
Mar 14 00:16:41 glaeqen-arch-one NetworkManager[403]: <info>  [1489447001.7886] dns-mgr: Writing DNS information to /usr/bin/resolvconf
Mar 14 00:16:42 glaeqen-arch-one NetworkManager[403]: <info>  [1489447002.0858] device (wlp2s0b1): Activation: successful, device activated.
Mar 14 00:16:42 glaeqen-arch-one nm-dispatcher[22375]: req:3 'up' [wlp2s0b1]: new request (0 scripts)
Mar 14 00:16:42 glaeqen-arch-one nm-dispatcher[22375]: req:3 'up' [wlp2s0b1]: completed: no scripts
Mar 14 00:16:43 glaeqen-arch-one NetworkManager[403]: <info>  [1489447003.2066] manager: NetworkManager state is now CONNECTED_GLOBAL
Mar 14 00:16:43 glaeqen-arch-one dbus[399]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Mar 14 00:16:43 glaeqen-arch-one systemd[1]: Starting Network Manager Script Dispatcher Service...
Mar 14 00:16:43 glaeqen-arch-one dbus[399]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mar 14 00:16:43 glaeqen-arch-one systemd[1]: Started Network Manager Script Dispatcher Service.
Mar 14 00:16:43 glaeqen-arch-one nm-dispatcher[23224]: req:1 'connectivity-change': new request (0 scripts)
Mar 14 00:16:43 glaeqen-arch-one nm-dispatcher[23224]: req:1 'connectivity-change': completed: no scripts
Mar 14 00:17:02 glaeqen-arch-one systemd-logind[398]: Suspending...
Mar 14 00:17:02 glaeqen-arch-one NetworkManager[403]: <info>  [1489447022.5714] manager: sleep requested (sleeping: no  enabled: yes)
Mar 14 00:17:02 glaeqen-arch-one NetworkManager[403]: <info>  [1489447022.5715] manager: sleeping...
Mar 14 00:17:02 glaeqen-arch-one dbus[399]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Mar 14 00:17:02 glaeqen-arch-one NetworkManager[403]: <info>  [1489447022.5718] manager: NetworkManager state is now ASLEEP
Mar 14 00:17:02 glaeqen-arch-one NetworkManager[403]: <info>  [1489447022.5744] device (wlp2s0b1): state change: activated -> deactivating (reason 'sleeping') [100 110 37]
Mar 14 00:17:02 glaeqen-arch-one systemd[1]: Starting Network Manager Script Dispatcher Service...
Mar 14 00:17:02 glaeqen-arch-one dbus[399]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mar 14 00:17:02 glaeqen-arch-one systemd[1]: Started Network Manager Script Dispatcher Service.
Mar 14 00:17:02 glaeqen-arch-one nm-dispatcher[23736]: req:1 'connectivity-change': new request (0 scripts)
Mar 14 00:17:02 glaeqen-arch-one nm-dispatcher[23736]: req:1 'connectivity-change': completed: no scripts
Mar 14 00:17:02 glaeqen-arch-one NetworkManager[403]: <info>  [1489447022.6972] device (wlp2s0b1): state change: deactivating -> disconnected (reason 'sleeping') [110 30 37]
Mar 14 00:17:02 glaeqen-arch-one NetworkManager[403]: <info>  [1489447022.6993] dhcp4 (wlp2s0b1): canceled DHCP transaction
Mar 14 00:17:02 glaeqen-arch-one NetworkManager[403]: <info>  [1489447022.6994] dhcp4 (wlp2s0b1): state changed bound -> done
Mar 14 00:17:02 glaeqen-arch-one kernel: wlp2s0b1: deauthenticating from 54:e6:fc:fd:36:0c by local choice (Reason: 3=DEAUTH_LEAVING)
Mar 14 00:17:02 glaeqen-arch-one wpa_supplicant[457]: wlp2s0b1: CTRL-EVENT-DISCONNECTED bssid=54:e6:fc:fd:36:0c reason=3 locally_generated=1
Mar 14 00:17:02 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcmsmac: brcms_ops_bss_info_changed: disassociated
Mar 14 00:17:02 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: arp filtering: 1 addresses (implement)
Mar 14 00:17:02 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Mar 14 00:17:02 glaeqen-arch-one NetworkManager[403]: <info>  [1489447022.7264] device (wlp2s0b1): set-hw-addr: set MAC address to 2A:BA:09:60:F9:E6 (scanning)
Mar 14 00:17:02 glaeqen-arch-one NetworkManager[403]: <info>  [1489447022.8927] dns-mgr: Removing DNS information from /usr/bin/resolvconf
Mar 14 00:17:02 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_bss_info_changed: qos enabled: false (implement)
Mar 14 00:17:02 glaeqen-arch-one kernel: brcmsmac bcma0:1: brcms_ops_config: change power-save mode: false (implement)
Mar 14 00:17:02 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0b1: link is not ready
Mar 14 00:17:03 glaeqen-arch-one nm-dispatcher[23736]: req:2 'down' [wlp2s0b1]: new request (0 scripts)
Mar 14 00:17:03 glaeqen-arch-one nm-dispatcher[23736]: req:2 'down' [wlp2s0b1]: completed: no scripts
Mar 14 00:17:03 glaeqen-arch-one NetworkManager[403]: <warn>  [1489447023.1491] sup-iface[0x1bb2980,wlp2s0b1]: connection disconnected (reason -3)
Mar 14 00:17:03 glaeqen-arch-one NetworkManager[403]: <info>  [1489447023.1496] device (wlp2s0b1): supplicant interface state: completed -> disconnected
Mar 14 00:17:03 glaeqen-arch-one NetworkManager[403]: <info>  [1489447023.2138] device (wlp2s0b1): supplicant interface state: disconnected -> disabled
Mar 14 00:17:03 glaeqen-arch-one NetworkManager[403]: <info>  [1489447023.2144] device (wlp2s0b1): supplicant interface state: disabled -> disconnected
Mar 14 00:17:03 glaeqen-arch-one NetworkManager[403]: <info>  [1489447023.2199] device (wlp2s0b1): state change: disconnected -> unmanaged (reason 'sleeping') [30 10 37]
Mar 14 00:17:03 glaeqen-arch-one NetworkManager[403]: <info>  [1489447023.2438] device (wlp2s0b1): set-hw-addr: reset MAC address to E4:D5:3D:4B:96:1E (unmanage)
Mar 14 00:17:03 glaeqen-arch-one systemd[1]: Starting TLP suspend/resume...
Mar 14 00:17:03 glaeqen-arch-one wpa_supplicant[457]: nl80211: deinit ifname=wlp2s0b1 disabled_11b_rates=0
Mar 14 00:17:04 glaeqen-arch-one systemd[1]: Started TLP suspend/resume.
Mar 14 00:17:04 glaeqen-arch-one systemd[1]: Reached target Sleep.
Mar 14 00:17:04 glaeqen-arch-one systemd[1]: Starting Suspend...
Mar 14 00:17:04 glaeqen-arch-one systemd-sleep[23868]: Suspending system...
Mar 14 01:12:32 glaeqen-arch-one kernel: PM: Syncing filesystems ... done.
Mar 14 01:12:32 glaeqen-arch-one kernel: PM: Preparing system for sleep (mem)
Mar 14 01:12:32 glaeqen-arch-one kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
Mar 14 01:12:32 glaeqen-arch-one kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Mar 14 01:12:32 glaeqen-arch-one kernel: PM: Suspending system (mem)
Mar 14 01:12:32 glaeqen-arch-one kernel: Suspending console(s) (use no_console_suspend to debug)
Mar 14 01:12:32 glaeqen-arch-one kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 14 01:12:32 glaeqen-arch-one kernel: ACPI : EC: event blocked
Mar 14 01:12:32 glaeqen-arch-one kernel: sd 0:0:0:0: [sda] Stopping disk
Mar 14 01:12:32 glaeqen-arch-one kernel: PM: suspend of devices complete after 476.043 msecs
Mar 14 01:12:32 glaeqen-arch-one kernel: PM: late suspend of devices complete after 14.510 msecs
Mar 14 01:12:32 glaeqen-arch-one kernel: ACPI : EC: interrupt blocked
Mar 14 01:12:32 glaeqen-arch-one kernel: pcieport 0000:00:1c.0: System wakeup enabled by ACPI
Mar 14 01:12:32 glaeqen-arch-one kernel: ehci-pci 0000:00:1d.7: System wakeup enabled by ACPI
Mar 14 01:12:32 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.3: System wakeup enabled by ACPI
Mar 14 01:12:32 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.1: System wakeup enabled by ACPI
Mar 14 01:12:32 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.0: System wakeup enabled by ACPI
Mar 14 01:12:32 glaeqen-arch-one kernel: PM: noirq suspend of devices complete after 16.848 msecs
Mar 14 01:12:32 glaeqen-arch-one kernel: ACPI: Preparing to enter system sleep state S3
Mar 14 01:12:32 glaeqen-arch-one kernel: ACPI : EC: EC stopped
Mar 14 01:12:32 glaeqen-arch-one kernel: PM: Saving platform NVS memory
Mar 14 01:12:32 glaeqen-arch-one kernel: Disabling non-boot CPUs ...
Mar 14 01:12:32 glaeqen-arch-one kernel: smpboot: CPU 1 is now offline
Mar 14 01:12:32 glaeqen-arch-one kernel: smpboot: CPU 2 is now offline
Mar 14 01:12:32 glaeqen-arch-one kernel: smpboot: CPU 3 is now offline
Mar 14 01:12:32 glaeqen-arch-one kernel: ACPI: Low-level resume complete
Mar 14 01:12:32 glaeqen-arch-one kernel: ACPI : EC: EC started
Mar 14 01:12:32 glaeqen-arch-one kernel: PM: Restoring platform NVS memory
Mar 14 01:12:32 glaeqen-arch-one kernel: Suspended for 3326.933 seconds
Mar 14 01:12:32 glaeqen-arch-one kernel: Enabling non-boot CPUs ...
Mar 14 01:12:32 glaeqen-arch-one kernel: x86: Booting SMP configuration:
Mar 14 01:12:32 glaeqen-arch-one kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Mar 14 01:12:32 glaeqen-arch-one kernel: Disabled fast string operations
Mar 14 01:12:32 glaeqen-arch-one kernel:  cache: parent cpu1 should not be sleeping
Mar 14 01:12:32 glaeqen-arch-one kernel: CPU1 is up
Mar 14 01:12:32 glaeqen-arch-one kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Mar 14 01:12:32 glaeqen-arch-one kernel: Disabled fast string operations
Mar 14 01:12:32 glaeqen-arch-one kernel:  cache: parent cpu2 should not be sleeping
Mar 14 01:12:32 glaeqen-arch-one kernel: CPU2 is up
Mar 14 01:12:32 glaeqen-arch-one kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Mar 14 01:12:32 glaeqen-arch-one kernel: Disabled fast string operations
Mar 14 01:12:32 glaeqen-arch-one kernel:  cache: parent cpu3 should not be sleeping
Mar 14 01:12:32 glaeqen-arch-one kernel: CPU3 is up
Mar 14 01:12:32 glaeqen-arch-one kernel: ACPI: Waking up from system sleep state S3
Mar 14 01:12:32 glaeqen-arch-one kernel: ACPI : EC: interrupt unblocked
Mar 14 01:12:32 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.0: System wakeup disabled by ACPI
Mar 14 01:12:32 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.1: System wakeup disabled by ACPI
Mar 14 01:12:32 glaeqen-arch-one kernel: uhci_hcd 0000:00:1d.3: System wakeup disabled by ACPI
Mar 14 01:12:32 glaeqen-arch-one kernel: ehci-pci 0000:00:1d.7: System wakeup disabled by ACPI
Mar 14 01:12:32 glaeqen-arch-one kernel: PM: noirq resume of devices complete after 17.950 msecs
Mar 14 01:12:32 glaeqen-arch-one kernel: PM: early resume of devices complete after 4.052 msecs
Mar 14 01:12:32 glaeqen-arch-one kernel: usb usb2: root hub lost power or was reset
Mar 14 01:12:32 glaeqen-arch-one kernel: usb usb3: root hub lost power or was reset
Mar 14 01:12:32 glaeqen-arch-one kernel: usb usb4: root hub lost power or was reset
Mar 14 01:12:32 glaeqen-arch-one kernel: pcieport 0000:00:1c.0: System wakeup disabled by ACPI
Mar 14 01:12:32 glaeqen-arch-one kernel: sd 0:0:0:0: [sda] Starting disk
Mar 14 01:12:32 glaeqen-arch-one kernel: ACPI : EC: event unblocked
Mar 14 01:12:32 glaeqen-arch-one kernel: rtc_cmos 00:01: System wakeup disabled by ACPI
Mar 14 01:12:32 glaeqen-arch-one kernel: r8169 0000:01:00.0 enp1s0: link down
Mar 14 01:12:32 glaeqen-arch-one kernel: usb 1-3: reset high-speed USB device number 2 using ehci-pci
Mar 14 01:12:32 glaeqen-arch-one kernel: ata2: SATA link down (SStatus 0 SControl 300)
Mar 14 01:12:32 glaeqen-arch-one kernel: PM: resume of devices complete after 447.444 msecs
Mar 14 01:12:32 glaeqen-arch-one kernel: PM: Finishing wakeup.
Mar 14 01:12:32 glaeqen-arch-one kernel: Restarting tasks ... done.
Mar 14 01:12:32 glaeqen-arch-one kernel: video LNXVIDEO:00: Restoring backlight state
Mar 14 01:12:32 glaeqen-arch-one systemd-logind[398]: Lid opened.
Mar 14 01:12:32 glaeqen-arch-one systemd[465]: Time has been changed
Mar 14 01:12:32 glaeqen-arch-one kernel: IPv6: ADDRCONF(NETDEV_UP): enp1s0: link is not ready
Mar 14 01:12:32 glaeqen-arch-one systemd[1]: Time has been changed
Mar 14 01:12:32 glaeqen-arch-one systemd-sleep[23868]: System resumed.

These are records when was driver and kernel updated (systemd latest update 01.02.17, I think it's irrelevant)

[2016-12-14 21:26] [ALPM] upgraded xf86-video-intel (1:2.99.917+740+g9ac7a33-1 -> 1:2.99.917+746+g169c74f-1)
[2017-01-13 23:14] [ALPM] upgraded xf86-video-intel (1:2.99.917+746+g169c74f-1 -> 1:2.99.917+747+g028c946d-1)
[2017-02-05 11:59] [ALPM] upgraded xf86-video-intel (1:2.99.917+747+g028c946d-1 -> 1:2.99.917+753+g9fe04af4-1)
[2017-02-15 17:48] [ALPM] upgraded xf86-video-intel (1:2.99.917+753+g9fe04af4-1 -> 1:2.99.917+757+g93942b7d-1)
[2017-03-05 19:38] [ALPM] upgraded xf86-video-intel (1:2.99.917+757+g93942b7d-1 -> 1:2.99.917+760+ge9cd8c21-1)
...
[2016-12-07 02:09] [ALPM] upgraded linux (4.8.11-1 -> 4.8.12-2)
[2016-12-14 21:26] [ALPM] upgraded linux (4.8.12-2 -> 4.8.13-1)
[2017-01-27 15:37] [ALPM] upgraded linux (4.8.13-1 -> 4.9.6-1)
[2017-02-07 21:48] [ALPM] upgraded linux (4.9.6-1 -> 4.9.7-1)
[2017-02-09 14:18] [ALPM] upgraded linux (4.9.7-1 -> 4.9.8-1)
[2017-02-22 20:21] [ALPM] upgraded linux (4.9.8-1 -> 4.9.11-1)
[2017-03-10 22:56] [ALPM] upgraded linux (4.9.11-1 -> 4.10.1-1)

I'll try to provide as much info as I'm able to. Thank you greatly for your effort in advance. I hope it's possible to figure out asap what is wrong.

Last edited by Glaeqen (2017-05-14 19:49:21)

Offline

#2 2017-03-21 10:29:29

frcl
Member
Registered: 2017-03-21
Posts: 1

Re: [SOLVED] Freeze after resuming from suspend

I experienced similar behavior the last few days. It seems that some systemd service is not shutting down properly, and systemd is waiting for it, hence the freeze. In my case the unit was systemd-udevd, because of the tlp udev rule.

Your problem doesn't seem to be exacly the same, but in the the case not freezing there is the line

Mar 14 00:16:41 glaeqen-arch-one systemd-udevd[23053]: Process '/usr/bin/tlp auto' failed with exit code 4.

wich suggests that in this case tlp is simply failing and returning an error code instead of waiting and causing the freeze.

I suggest uninstalling tlp and rebooting. If you can suspend after that, you now that tlp is causing the problem.

Offline

#3 2017-03-21 11:35:58

Glaeqen
Member
Registered: 2016-08-10
Posts: 26

Re: [SOLVED] Freeze after resuming from suspend

Thanks for the reply. I'll try out your suggestion. If it's the TLP fault, I'll send proper ticket to its maintainer. I find it awkward that it started happening out of nowhere. I have TLP installed from the very beginning, so.. hmm I hope I'll get system working with TLP ASAP - I don't want to lose that battery life optimisation coming from it. I'll post results of the test here.
UPDATE:
I appears that nothing has changed since uninstallation. Again - after resuming system from standby - OS freezes and the same error in journal is visible. sad

Last edited by Glaeqen (2017-03-21 17:19:33)

Offline

#4 2017-04-30 12:52:56

Glaeqen
Member
Registered: 2016-08-10
Posts: 26

Re: [SOLVED] Freeze after resuming from suspend

Problem still occurs. Is anyone able to help me? sad Thank you in advance

Offline

#5 2017-04-30 23:43:34

zganus
Member
Registered: 2017-03-26
Posts: 5

Re: [SOLVED] Freeze after resuming from suspend

I have the same problem, and many others have been facing it too on their laptops.

To my knowledge, it's a kernel bug introduced in 4.10. I just use linux-lts and linux-lts-headers now. I tried to bisect the kernel (see https://bbs.archlinux.org/viewtopic.php?id=224521) but I finally gave up when I couldn't boot some kernel builds.

Offline

#6 2017-05-01 09:10:22

Glaeqen
Member
Registered: 2016-08-10
Posts: 26

Re: [SOLVED] Freeze after resuming from suspend

Thank you for the reply. I'd like to point out (just in case) that the first time when the problem appeared was 28 II 2017 when I got kernel 4.9.11. I updated it to 4.10.1 on March 10.

Offline

#7 2017-05-03 17:28:54

Glaeqen
Member
Registered: 2016-08-10
Posts: 26

Re: [SOLVED] Freeze after resuming from suspend

UPDATE: Moving to kernel-lts didn't help.

Offline

#8 2017-05-14 19:24:20

jamdox
Member
Registered: 2015-05-02
Posts: 46

Re: [SOLVED] Freeze after resuming from suspend

I think I have something similar.  Sleep/wake works ok on some versions of gnome but then breaks on others.  I wiped my dconf settings and it was working seemingly reliably but then it wasn't.  I'm considering the possibility it may be a networkmanager problem rather than a kernel problem.  I was happy that cleaning up the settings seemed to fix it, since it does seem to vary with the software installed not the kernel, but this most recent wake failure is depressing. 

I'm curious about no_console_suspend, does this mean it'll print messages to the systemd journal or do I literally need to have a second console attached to the machine somehow?

Offline

#9 2017-05-14 19:48:51

Glaeqen
Member
Registered: 2016-08-10
Posts: 26

Re: [SOLVED] Freeze after resuming from suspend

My problem seems to be fixed. Update to kernel 4.11 (Currently I'm using linux-hardened package from Arch Linux Repository) solved the issue (FOR NOW).
Links:
https://bugs.freedesktop.org/show_bug.cgi?id=101010
https://patchwork.kernel.org/patch/9479343/

Offline

Board footer

Powered by FluxBB