Arch Linux

mervinb

Member

Registered: 2011-11-19

Posts: 25

ASUS UX305 suspend not working after kernel 4.2.1 update

Hi,

I have a standard configuration (stock kernel, systemd) and suspend/resume was working fine throughout the 4.1.x kernel series. The laptop suspends correctly on lid close, and suspend button (fn-Zz).

After the recent update to 4.2.1 kernel, the FIRST occasion I suspend, I see the laptop goig to sleep (power button flashes slowly), and all works normally on resume. Subsequently the laptop will NOT resume, either from lid close or hitting the suspend key. The power light remains on, the laptop remains warm, and re-opening lid shows the power indicator still on, although screen is not lit. Hitting the power button brings xscreensaver login back.

I've verified this with a downgrade back to 4.1.6, which works fine. For now I'll stay with the 4.1.x kernel, but I'd like to keep current. I'll be happy to run some diagnostics to help narrow down the problem.

Thanks!
Mervin

hydracone

Member

Registered: 2015-06-25

Posts: 24

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

I didn't have any such problems in 4.2.1. Are you using any kernel parameters for i915?? 4.2.2 had been released yesterday. Why don't you try upgrading to that??

mervinb

Member

Registered: 2011-11-19

Posts: 25

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

Thanks hydracone. No I'm not using at kernel parameters at boot. I'll try 4.2.2 which has just arrived on my local mirror.

Update: same behaviour on 4.2.2, and came across this, which may/may not be the same issue - https://bugzilla.redhat.com/show_bug.cgi?id=1249341

Last edited by mervinb (2015-10-02 06:27:42)

hydracone

Member

Registered: 2015-06-25

Posts: 24

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

Try viewing a live log of the suspend process using :

# journalctl --all -f

and in another terminal, suspend your system using :

# systemctl suspend

Also, are you using a window manager, or a desktop environment??

EDIT : Please make sure that your system is up to date as well... (specifically the xf86-video-intel and the mesa packages)

Last edited by hydracone (2015-10-02 07:27:08)

mervinb

Member

Registered: 2011-11-19

Posts: 25

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

Thanks again hydracone.

My system is as up to date as pacman allows. In fact I even tried some fresher iwlwifi ucode, thinking that it might be wifi suspend related.

WORKAROUND:

I've isolated the problem by looking at the journal log after resume. The issue is on wakeup problems for the webcam *after* first suspend (so the system won't suspend a second time). The workaround is to blacklist the uvcvideo module. With this the problem in suspending on second and subsequent occasions disappears, and the logs reflect this too.

This seems to confirm a kernel regression for the uvcvideo driver. I'll post the log when time allows. I won't mark as solved yet, as what I have is a workaround with a disabled webcam.

hydracone

Member

Registered: 2015-06-25

Posts: 24

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

Oh.... so were you using your webcam before suspending? I didn't expect that! Anyways, glad you found a way to work around this issue..

mervinb

Member

Registered: 2011-11-19

Posts: 25

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

No, I was not using the webcam. The webcam driver is initiated on boot (the device ID and description can be traced back on dmesg), and is put to sleep on a suspend, like other USB devices. On the 4.2 kernels (both 4.2.1 and 4.2.2) there appears to be a problem on wake up, so that after one suspend the device cannot be waken up, but this issue is only noticed when I next try to suspend.

mervinb

Member

Registered: 2011-11-19

Posts: 25

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

This is the journctl log for when the webcam driver is *not* blacklisted. The first suspend/resume works fine. On the second suspend attempt, the system attempts to suspend screensaver activates, screen shuts, but system is not really asleep. At this point I need to hit the power button to get the screensaver login.

Oct 04 05:46:15 evo-305 systemd-sleep[1054]: xscreensaver-command: activating and locking.
Oct 04 05:46:15 evo-305 sudo[1062]: pam_unix(sudo:session): session closed for user mervin
Oct 04 05:46:16 evo-305 systemd-sleep[1054]: Suspending system...
Oct 04 05:46:16 evo-305 kernel: PM: Syncing filesystems ... done.
Oct 04 05:46:16 evo-305 kernel: PM: Preparing system for sleep (mem)
Oct 04 05:46:16 evo-305 kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
Oct 04 05:46:16 evo-305 kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Oct 04 05:46:16 evo-305 kernel: PM: Suspending system (mem)
Oct 04 05:46:16 evo-305 kernel: Suspending console(s) (use no_console_suspend to debug)
Oct 04 05:46:16 evo-305 kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Oct 04 05:46:16 evo-305 kernel: sd 0:0:0:0: [sda] Stopping disk
Oct 04 05:46:16 evo-305 kernel: pci_pm_suspend(): hcd_pci_suspend+0x0/0x30 [usbcore] returns -16       *** Only on 4.2.x kernels
Oct 04 05:46:16 evo-305 kernel: dpm_run_callback(): pci_pm_suspend+0x0/0x150 returns -16
Oct 04 05:46:16 evo-305 kernel: PM: Device 0000:00:14.0 failed to suspend async: error -16             *** 0000:00:14.0 is the webcam
Oct 04 05:46:16 evo-305 kernel: PM: Some devices failed to suspend, or early wake event detected       *** System never gets to sleep
Oct 04 05:46:16 evo-305 kernel: rtc_cmos 00:04: System wakeup disabled by ACPI
Oct 04 05:46:16 evo-305 kernel: sd 0:0:0:0: [sda] Starting disk
Oct 04 05:46:16 evo-305 kernel: PM: resume of devices complete after 89.449 msecs
Oct 04 05:46:16 evo-305 kernel: PM: Finishing wakeup.

If the webcam driver blacklisted, all suspends are correct, leading to power light blinking (indicating sleep status), and system awakes on lid re-open.

hydracone

Member

Registered: 2015-06-25

Posts: 24

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

On my system, device 0000:00:14:0 points to the entire USB controller, not the webcam per se. It is one of the devices connected to the USB bus. Could it be that you're using some faulty USB device which is preventing the system from suspending? I'm just asking because I feel I should also be experiencing the same issue, if it were a kernel regression.

mervinb

Member

Registered: 2011-11-19

Posts: 25

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

I have no external USB devices at all. I traced it to the webcam via dmesg:

[    2.702166] input: USB2.0 UVC HD Webcam as /devices/pci0000:00/0000:00:14.0/usb1/1-5/1-5:1.0/input/input21

cabellicar123

Member

Registered: 2015-08-03

Posts: 56

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

Getting the same behavior here. Is there a bug report filed?

mervinb

Member

Registered: 2011-11-19

Posts: 25

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

It's good to know that the bug seems to be repeatable. No report has been filed, and I've not done so for a kernel bug.

If someone can help with this, or provide pointers on how to do so, it will be appreciated. I take it this would be considered an upstream (linux kernel) bug report?

mervinb

Member

Registered: 2011-11-19

Posts: 25

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

Getting the same behavior here. Is there a bug report filed?

A bug report has been filed at https://bugzilla.kernel.org/show_bug.cgi?id=105641

carmenbbakker

Member

Registered: 2016-05-10

Posts: 2

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

I have the exact issue described in the thread - second suspend does not work - but the workaround doesn't work for me.

I have a ux305ca-fb073t and I'm running openSUSE Tumbleweed, Linux 4.5.3-1. This is the only place I found discussing the topic, however, so I hope you'll forgive me.

I have recorded the output of journalctl --all -f to a file. The uvcvideo module is ENABLED, though it doesn't make a difference whether I've blacklisted it or not.

-- Logs begin at Mon 2016-05-09 20:15:41 CEST. --
May 11 00:12:31 carmen-laptop dns-resolver[3226]: ATTENTION: You have modified /etc/resolv.conf. Leaving it untouched...
May 11 00:12:31 carmen-laptop dns-resolver[3228]: You can find my version in /etc/resolv.conf.netconfig
May 11 00:12:31 carmen-laptop NetworkManager[1529]: <13>May 11 00:12:31 dns-resolver: ATTENTION: You have modified /etc/resolv.conf. Leaving it untouched...
May 11 00:12:31 carmen-laptop NetworkManager[1529]: <13>May 11 00:12:31 dns-resolver: You can find my version in /etc/resolv.conf.netconfig
May 11 00:12:31 carmen-laptop NetworkManager[1529]: ATTENTION: You have modified /etc/resolv.conf.  Leaving it untouched...
May 11 00:12:31 carmen-laptop NetworkManager[1529]: You can find my version in /etc/resolv.conf.netconfig ...
May 11 00:12:31 carmen-laptop nscd[1499]: 1499 monitored file `/etc/resolv.conf` was moved into place, adding watch
May 11 00:12:31 carmen-laptop NetworkManager[1529]: <info>  NetworkManager state is now ASLEEP
May 11 00:12:31 carmen-laptop NetworkManager[1529]: <warn>  Failed to GDBus.Error:fi.w1.wpa_supplicant1.NotConnected: This interface is not connected: disconnect.
May 11 00:12:31 carmen-laptop dbus[1517]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
May 11 00:12:31 carmen-laptop systemd[1]: Starting Network Manager Script Dispatcher Service...
May 11 00:12:31 carmen-laptop dbus[1517]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
May 11 00:12:31 carmen-laptop systemd[1]: Started Network Manager Script Dispatcher Service.
May 11 00:12:31 carmen-laptop nm-dispatcher[3287]: Dispatching action 'down' for wlp1s0
May 11 00:12:31 carmen-laptop audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 11 00:12:31 carmen-laptop systemd[1]: Reached target Sleep.
May 11 00:12:31 carmen-laptop systemd[1]: Starting Suspend...
May 11 00:12:31 carmen-laptop systemd-sleep[3315]: INFO: Skip running /usr/lib/systemd/system-sleep/grub2.sleep for suspend
May 11 00:12:31 carmen-laptop systemd-sleep[3315]: Suspending system...
May 11 00:12:32 carmen-laptop kernel: PM: Syncing filesystems ... done.
May 11 00:12:32 carmen-laptop kernel: PM: Preparing system for sleep (mem)
May 11 00:12:38 carmen-laptop kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
May 11 00:12:38 carmen-laptop kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
May 11 00:12:38 carmen-laptop kernel: PM: Suspending system (mem)
May 11 00:12:38 carmen-laptop kernel: Suspending console(s) (use no_console_suspend to debug)
May 11 00:12:38 carmen-laptop kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
May 11 00:12:38 carmen-laptop kernel: sd 0:0:0:0: [sda] Stopping disk
May 11 00:12:38 carmen-laptop kernel: PM: suspend of devices complete after 408.277 msecs
May 11 00:12:38 carmen-laptop kernel: PM: late suspend of devices complete after 15.817 msecs
May 11 00:12:38 carmen-laptop kernel: xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
May 11 00:12:38 carmen-laptop kernel: PM: noirq suspend of devices complete after 35.823 msecs
May 11 00:12:38 carmen-laptop kernel: ACPI: Preparing to enter system sleep state S3
May 11 00:12:38 carmen-laptop kernel: ACPI : EC: EC stopped
May 11 00:12:38 carmen-laptop kernel: PM: Saving platform NVS memory
May 11 00:12:38 carmen-laptop kernel: Disabling non-boot CPUs ...
May 11 00:12:38 carmen-laptop kernel: smpboot: CPU 1 is now offline
May 11 00:12:38 carmen-laptop kernel: Broke affinity for irq 123
May 11 00:12:38 carmen-laptop kernel: smpboot: CPU 2 is now offline
May 11 00:12:38 carmen-laptop kernel: Broke affinity for irq 122
May 11 00:12:38 carmen-laptop kernel: Broke affinity for irq 123
May 11 00:12:38 carmen-laptop kernel: Broke affinity for irq 124
May 11 00:12:38 carmen-laptop kernel: Broke affinity for irq 128
May 11 00:12:38 carmen-laptop kernel: smpboot: CPU 3 is now offline
May 11 00:12:38 carmen-laptop kernel: ACPI: Low-level resume complete
May 11 00:12:38 carmen-laptop kernel: ACPI : EC: EC started
May 11 00:12:38 carmen-laptop kernel: PM: Restoring platform NVS memory
May 11 00:12:38 carmen-laptop kernel: Enabling non-boot CPUs ...
May 11 00:12:38 carmen-laptop kernel: x86: Booting SMP configuration:
May 11 00:12:38 carmen-laptop kernel: smpboot: Booting Node 0 Processor 1 APIC 0x2
May 11 00:12:38 carmen-laptop kernel:  cache: parent cpu1 should not be sleeping
May 11 00:12:38 carmen-laptop kernel: CPU1 is up
May 11 00:12:38 carmen-laptop kernel: smpboot: Booting Node 0 Processor 2 APIC 0x1
May 11 00:12:38 carmen-laptop kernel:  cache: parent cpu2 should not be sleeping
May 11 00:12:38 carmen-laptop kernel: CPU2 is up
May 11 00:12:38 carmen-laptop kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
May 11 00:12:38 carmen-laptop kernel:  cache: parent cpu3 should not be sleeping
May 11 00:12:38 carmen-laptop kernel: CPU3 is up
May 11 00:12:38 carmen-laptop kernel: ACPI: Waking up from system sleep state S3
May 11 00:12:38 carmen-laptop kernel: xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
May 11 00:12:38 carmen-laptop kernel: PM: noirq resume of devices complete after 31.061 msecs
May 11 00:12:38 carmen-laptop kernel: PM: early resume of devices complete after 7.210 msecs
May 11 00:12:38 carmen-laptop kernel: usb usb1: root hub lost power or was reset
May 11 00:12:38 carmen-laptop kernel: usb usb2: root hub lost power or was reset
May 11 00:12:38 carmen-laptop kernel: rtc_cmos 00:01: System wakeup disabled by ACPI
May 11 00:12:38 carmen-laptop kernel: sd 0:0:0:0: [sda] Starting disk
May 11 00:12:38 carmen-laptop kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
May 11 00:12:38 carmen-laptop kernel: ata2: SATA link down (SStatus 4 SControl 300)
May 11 00:12:38 carmen-laptop kernel: usb 1-5: reset high-speed USB device number 2 using xhci_hcd
May 11 00:12:38 carmen-laptop kernel: ata1.00: configured for UDMA/133
May 11 00:12:38 carmen-laptop kernel: ahci 0000:00:17.0: port does not support device sleep
May 11 00:12:38 carmen-laptop kernel: ata1.00: Enabling discard_zeroes_data
May 11 00:12:38 carmen-laptop kernel: usb 1-9: reset full-speed USB device number 3 using xhci_hcd
May 11 00:12:38 carmen-laptop kernel: PM: resume of devices complete after 916.210 msecs
May 11 00:12:38 carmen-laptop kernel: usb 1-9:1.0: rebind failed: -517
May 11 00:12:38 carmen-laptop kernel: usb 1-9:1.1: rebind failed: -517
May 11 00:12:38 carmen-laptop kernel: PM: Finishing wakeup.
May 11 00:12:38 carmen-laptop systemd[1]: Time has been changed
May 11 00:12:38 carmen-laptop systemd[1904]: Time has been changed
May 11 00:12:38 carmen-laptop systemd[1947]: Time has been changed
May 11 00:12:38 carmen-laptop systemd[1]: Starting Load/Save RF Kill Switch Status...
May 11 00:12:39 carmen-laptop kernel: Restarting tasks ... done.
May 11 00:12:39 carmen-laptop kernel: Bluetooth: hci0: read Intel version: 370810011003110e00
May 11 00:12:39 carmen-laptop kernel: Bluetooth: hci0: Intel Bluetooth firmware file: intel/ibt-hw-37.8.10-fw-1.10.3.11.e.bseq
May 11 00:12:39 carmen-laptop bluetoothd[1477]: Endpoint unregistered: sender=:1.33 path=/MediaEndpoint/A2DPSource
May 11 00:12:39 carmen-laptop bluetoothd[1477]: Endpoint unregistered: sender=:1.33 path=/MediaEndpoint/A2DPSink
May 11 00:12:39 carmen-laptop dbus[1517]: [system] Rejected send message, 10 matched rules; type="error", sender=":1.33" (uid=1000 pid=2123 comm="/usr/bin/pulseaudio --start --log-target=syslog ") interface="(unset)" member="(unset)" error name="org.bluez.MediaEndpoint1.Error.NotImplemented" requested_reply="0" destination=":1.1" (uid=0 pid=1477 comm="/usr/lib/bluetooth/bluetoothd ")
May 11 00:12:39 carmen-laptop dbus[1517]: [system] Rejected send message, 10 matched rules; type="error", sender=":1.33" (uid=1000 pid=2123 comm="/usr/bin/pulseaudio --start --log-target=syslog ") interface="(unset)" member="(unset)" error name="org.bluez.MediaEndpoint1.Error.NotImplemented" requested_reply="0" destination=":1.1" (uid=0 pid=1477 comm="/usr/lib/bluetooth/bluetoothd ")
May 11 00:12:39 carmen-laptop dbus[1517]: [system] Rejected send message, 10 matched rules; type="error", sender=":1.33" (uid=1000 pid=2123 comm="/usr/bin/pulseaudio --start --log-target=syslog ") interface="(unset)" member="(unset)" error name="org.bluez.MediaEndpoint1.Error.NotImplemented" requested_reply="0" destination=":1.1" (uid=0 pid=1477 comm="/usr/lib/bluetooth/bluetoothd ")
May 11 00:12:39 carmen-laptop dbus[1517]: [system] Rejected send message, 10 matched rules; type="error", sender=":1.33" (uid=1000 pid=2123 comm="/usr/bin/pulseaudio --start --log-target=syslog ") interface="(unset)" member="(unset)" error name="org.bluez.MediaEndpoint1.Error.NotImplemented" requested_reply="0" destination=":1.1" (uid=0 pid=1477 comm="/usr/lib/bluetooth/bluetoothd ")
May 11 00:12:39 carmen-laptop systemd[1]: bluetooth.target: Unit not needed anymore. Stopping.
May 11 00:12:39 carmen-laptop systemd[1]: Stopped target Bluetooth.
May 11 00:12:39 carmen-laptop systemd[1]: Started Load/Save RF Kill Switch Status.
May 11 00:12:39 carmen-laptop audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 11 00:12:39 carmen-laptop systemd[1]: Reached target Bluetooth.
May 11 00:12:39 carmen-laptop systemd-sleep[3315]: System resumed.
May 11 00:12:39 carmen-laptop systemd-sleep[3315]: INFO: Skip running /usr/lib/systemd/system-sleep/grub2.sleep for suspend
May 11 00:12:39 carmen-laptop systemd[1]: Started Suspend.
May 11 00:12:39 carmen-laptop audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 11 00:12:39 carmen-laptop systemd[1]: sleep.target: Unit not needed anymore. Stopping.
May 11 00:12:39 carmen-laptop audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 11 00:12:39 carmen-laptop systemd[1]: Stopped target Sleep.
May 11 00:12:39 carmen-laptop systemd[1]: Reached target Suspend.
May 11 00:12:39 carmen-laptop systemd-logind[1540]: Operation 'sleep' finished.
May 11 00:12:39 carmen-laptop kernel: IPv6: ADDRCONF(NETDEV_UP): wlp1s0: link is not ready
May 11 00:12:39 carmen-laptop kernel: iwlwifi 0000:01:00.0: L1 Enabled - LTR Enabled
May 11 00:12:39 carmen-laptop kernel: iwlwifi 0000:01:00.0: L1 Enabled - LTR Enabled
May 11 00:12:39 carmen-laptop systemd[1]: suspend.target: Unit is bound to inactive unit systemd-suspend.service. Stopping, too.
May 11 00:12:39 carmen-laptop NetworkManager[1529]: <info>  wake requested (sleeping: yes  enabled: yes)
May 11 00:12:39 carmen-laptop systemd[1]: Stopped target Suspend.
May 11 00:12:39 carmen-laptop NetworkManager[1529]: <info>  waking up...
May 11 00:12:39 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
May 11 00:12:39 carmen-laptop dbus[1517]: [system] Activating via systemd: service name='org.freedesktop.PackageKit' unit='packagekit.service'
May 11 00:12:39 carmen-laptop systemd[1]: Starting PackageKit Daemon...
May 11 00:12:39 carmen-laptop PackageKit[3397]: daemon start
May 11 00:12:39 carmen-laptop kernel: iwlwifi 0000:01:00.0: L1 Enabled - LTR Enabled
May 11 00:12:39 carmen-laptop kernel: iwlwifi 0000:01:00.0: L1 Enabled - LTR Enabled
May 11 00:12:39 carmen-laptop kernel: IPv6: ADDRCONF(NETDEV_UP): wlp1s0: link is not ready
May 11 00:12:39 carmen-laptop NetworkManager[1529]: <info>  NetworkManager state is now DISCONNECTED
May 11 00:12:39 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0) supports 5 scan SSIDs
May 11 00:12:39 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): supplicant interface state: starting -> ready
May 11 00:12:39 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
May 11 00:12:39 carmen-laptop kernel: IPv6: ADDRCONF(NETDEV_UP): wlp1s0: link is not ready
May 11 00:12:39 carmen-laptop dbus[1517]: [system] Successfully activated service 'org.freedesktop.PackageKit'
May 11 00:12:39 carmen-laptop systemd[1]: Started PackageKit Daemon.
May 11 00:12:39 carmen-laptop audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=packagekit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 11 00:12:39 carmen-laptop kernel: Bluetooth: hci0: Intel Bluetooth firmware patch completed and activated
May 11 00:12:39 carmen-laptop bluetoothd[1477]: Failed to obtain handles for "Service Changed" characteristic
May 11 00:12:39 carmen-laptop bluetoothd[1477]: Sap driver initialization failed.
May 11 00:12:39 carmen-laptop bluetoothd[1477]: sap-server: Operation not permitted (1)
May 11 00:12:39 carmen-laptop bluetoothd[1477]: Endpoint registered: sender=:1.33 path=/MediaEndpoint/A2DPSource
May 11 00:12:39 carmen-laptop bluetoothd[1477]: Endpoint registered: sender=:1.33 path=/MediaEndpoint/A2DPSink
May 11 00:12:39 carmen-laptop kernel: [drm] RC6 on
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): supplicant interface state: ready -> inactive
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  Auto-activating connection 'WiFi-Repeater(5G)'.
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): Activation: starting connection 'WiFi-Repeater(5G)' (5f12b2f5-5868-4012-bb3c-05693b9de6d1)
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  NetworkManager state is now CONNECTING
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): device state change: prepare -> config (reason 'none') [40 50 0]
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): Activation: (wifi) access point 'WiFi-Repeater(5G)' has security, but secrets are required.
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): device state change: config -> need-auth (reason 'none') [50 60 0]
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): device state change: prepare -> config (reason 'none') [40 50 0]
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): Activation: (wifi) connection 'WiFi-Repeater(5G)' has security, and secrets exist.  No new secrets needed.
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  Config: added 'ssid' value 'WiFi-Repeater(5G)'
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  Config: added 'scan_ssid' value '1'
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  Config: added 'key_mgmt' value 'WPA-PSK'
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  Config: added 'auth_alg' value 'OPEN'
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  Config: added 'psk' value '<omitted>'
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  Config: set interface ap_scan to 1
May 11 00:12:42 carmen-laptop kernel: wlp1s0: authenticate with 80:3f:5d:fd:9f:0a
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): supplicant interface state: inactive -> authenticating
May 11 00:12:42 carmen-laptop kernel: wlp1s0: send auth to 80:3f:5d:fd:9f:0a (try 1/3)
May 11 00:12:42 carmen-laptop kernel: wlp1s0: authenticated
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): supplicant interface state: authenticating -> associating
May 11 00:12:42 carmen-laptop kernel: wlp1s0: associate with 80:3f:5d:fd:9f:0a (try 1/3)
May 11 00:12:42 carmen-laptop kernel: wlp1s0: RX AssocResp from 80:3f:5d:fd:9f:0a (capab=0x411 status=0 aid=1)
May 11 00:12:42 carmen-laptop kernel: wlp1s0: associated
May 11 00:12:42 carmen-laptop kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp1s0: link becomes ready
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): supplicant interface state: associating -> completed
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'WiFi-Repeater(5G)'.
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): device state change: config -> ip-config (reason 'none') [50 70 0]
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  Activation (wlp1s0) Beginning DHCPv4 transaction (timeout in 45 seconds)
May 11 00:12:42 carmen-laptop NetworkManager[1529]: <info>  dhclient started with pid 3450
May 11 00:12:43 carmen-laptop audit[3456]: USER_AUTH pid=3456 uid=1000 auid=1000 ses=2 msg='op=PAM:unix_chkpwd acct="carmen" exe="/sbin/unix_chkpwd" hostname=? addr=? terminal=? res=success'
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>    address 192.168.0.117
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>    plen 24 (255.255.255.0)
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>    gateway 192.168.0.1
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>    server identifier 192.168.0.1
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>    lease time 3600
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>    nameserver '192.168.0.101'
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>    domain name 'dynamic.ziggo.nl'
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): DHCPv4 state changed unknown -> bound
May 11 00:12:43 carmen-laptop avahi-daemon[1516]: Joining mDNS multicast group on interface wlp1s0.IPv4 with address 192.168.0.117.
May 11 00:12:43 carmen-laptop avahi-daemon[1516]: New relevant interface wlp1s0.IPv4 for mDNS.
May 11 00:12:43 carmen-laptop avahi-daemon[1516]: Registering new address record for 192.168.0.117 on wlp1s0.IPv4.
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): device state change: ip-config -> ip-check (reason 'none') [70 80 0]
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): device state change: ip-check -> secondaries (reason 'none') [80 90 0]
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): device state change: secondaries -> activated (reason 'none') [90 100 0]
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>  NetworkManager state is now CONNECTED_LOCAL
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>  NetworkManager state is now CONNECTED_SITE
May 11 00:12:43 carmen-laptop NetworkManager[1529]: <info>  Policy set 'WiFi-Repeater(5G)' (wlp1s0) as default for IPv4 routing and DNS.
May 11 00:12:43 carmen-laptop dns-resolver[3504]: ATTENTION: You have modified /etc/resolv.conf. Leaving it untouched...
May 11 00:12:43 carmen-laptop dns-resolver[3506]: You can find my version in /etc/resolv.conf.netconfig
May 11 00:12:44 carmen-laptop audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 11 00:12:44 carmen-laptop NetworkManager[1529]: <13>May 11 00:12:43 dns-resolver: ATTENTION: You have modified /etc/resolv.conf. Leaving it untouched...
May 11 00:12:44 carmen-laptop NetworkManager[1529]: <13>May 11 00:12:43 dns-resolver: You can find my version in /etc/resolv.conf.netconfig
May 11 00:12:44 carmen-laptop NetworkManager[1529]: ATTENTION: You have modified /etc/resolv.conf.  Leaving it untouched...
May 11 00:12:44 carmen-laptop NetworkManager[1529]: You can find my version in /etc/resolv.conf.netconfig ...
May 11 00:12:44 carmen-laptop avahi-daemon[1516]: Joining mDNS multicast group on interface wlp1s0.IPv6 with address fe80::de53:60ff:feda:5ad9.
May 11 00:12:44 carmen-laptop avahi-daemon[1516]: New relevant interface wlp1s0.IPv6 for mDNS.
May 11 00:12:44 carmen-laptop avahi-daemon[1516]: Registering new address record for fe80::de53:60ff:feda:5ad9 on wlp1s0.*.
May 11 00:12:44 carmen-laptop nscd[1499]: 1499 monitored file `/etc/resolv.conf` was moved into place, adding watch
May 11 00:12:44 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): Activation: successful, device activated.
May 11 00:12:44 carmen-laptop nm-dispatcher[3287]: Dispatching action 'up' for wlp1s0
May 11 00:12:44 carmen-laptop kernel: SFW2-INext-DROP-DEFLT IN=wlp1s0 OUT= MAC= SRC=fe80:0000:0000:0000:de53:60ff:feda:5ad9 DST=ff02:0000:0000:0000:0000:0000:0000:00fb LEN=267 TC=0 HOPLIMIT=255 FLOWLBL=565129 PROTO=UDP SPT=5353 DPT=5353 LEN=227 
May 11 00:12:44 carmen-laptop kernel: SFW2-INext-DROP-DEFLT IN=wlp1s0 OUT= MAC= SRC=fe80:0000:0000:0000:de53:60ff:feda:5ad9 DST=ff02:0000:0000:0000:0000:0000:0000:00fb LEN=442 TC=0 HOPLIMIT=255 FLOWLBL=565129 PROTO=UDP SPT=5353 DPT=5353 LEN=402 
May 11 00:12:44 carmen-laptop NetworkManager[1529]: <info>  NetworkManager state is now CONNECTED_GLOBAL
May 11 00:12:44 carmen-laptop kernel: SFW2-INext-DROP-DEFLT IN=wlp1s0 OUT= MAC= SRC=fe80:0000:0000:0000:de53:60ff:feda:5ad9 DST=ff02:0000:0000:0000:0000:0000:0000:00fb LEN=442 TC=0 HOPLIMIT=255 FLOWLBL=565129 PROTO=UDP SPT=5353 DPT=5353 LEN=402 
May 11 00:12:44 carmen-laptop kernel: SFW2-INext-DROP-DEFLT IN=wlp1s0 OUT= MAC= SRC=fe80:0000:0000:0000:de53:60ff:feda:5ad9 DST=ff02:0000:0000:0000:0000:0000:0000:00fb LEN=442 TC=0 HOPLIMIT=255 FLOWLBL=565129 PROTO=UDP SPT=5353 DPT=5353 LEN=402 
May 11 00:12:49 carmen-laptop systemd-logind[1540]: Suspend key pressed.
May 11 00:12:49 carmen-laptop NetworkManager[1529]: <info>  sleep requested (sleeping: no  enabled: yes)
May 11 00:12:49 carmen-laptop NetworkManager[1529]: <info>  sleeping...
May 11 00:12:49 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
May 11 00:12:49 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): canceled DHCP transaction, DHCP client pid 3450
May 11 00:12:49 carmen-laptop NetworkManager[1529]: <info>  (wlp1s0): DHCPv4 state changed bound -> done
May 11 00:12:49 carmen-laptop avahi-daemon[1516]: Withdrawing address record for 192.168.0.117 on wlp1s0.
May 11 00:12:49 carmen-laptop kernel: wlp1s0: deauthenticating from 80:3f:5d:fd:9f:0a by local choice (Reason: 3=DEAUTH_LEAVING)
May 11 00:12:49 carmen-laptop avahi-daemon[1516]: Leaving mDNS multicast group on interface wlp1s0.IPv4 with address 192.168.0.117.
May 11 00:12:49 carmen-laptop avahi-daemon[1516]: Interface wlp1s0.IPv4 no longer relevant for mDNS.
May 11 00:12:49 carmen-laptop avahi-daemon[1516]: Withdrawing address record for fe80::de53:60ff:feda:5ad9 on wlp1s0.
May 11 00:12:49 carmen-laptop avahi-daemon[1516]: Leaving mDNS multicast group on interface wlp1s0.IPv6 with address fe80::de53:60ff:feda:5ad9.
May 11 00:12:49 carmen-laptop avahi-daemon[1516]: Interface wlp1s0.IPv6 no longer relevant for mDNS.
May 11 00:12:49 carmen-laptop dns-resolver[3662]: ATTENTION: You have modified /etc/resolv.conf. Leaving it untouched...
May 11 00:12:49 carmen-laptop dns-resolver[3664]: You can find my version in /etc/resolv.conf.netconfig
May 11 00:12:49 carmen-laptop NetworkManager[1529]: <13>May 11 00:12:49 dns-resolver: ATTENTION: You have modified /etc/resolv.conf. Leaving it untouched...
May 11 00:12:49 carmen-laptop NetworkManager[1529]: <13>May 11 00:12:49 dns-resolver: You can find my version in /etc/resolv.conf.netconfig
May 11 00:12:49 carmen-laptop NetworkManager[1529]: ATTENTION: You have modified /etc/resolv.conf.  Leaving it untouched...
May 11 00:12:49 carmen-laptop NetworkManager[1529]: You can find my version in /etc/resolv.conf.netconfig ...
May 11 00:12:49 carmen-laptop nscd[1499]: 1499 monitored file `/etc/resolv.conf` was moved into place, adding watch
May 11 00:12:49 carmen-laptop NetworkManager[1529]: <info>  NetworkManager state is now ASLEEP
May 11 00:12:49 carmen-laptop NetworkManager[1529]: <warn>  Failed to GDBus.Error:fi.w1.wpa_supplicant1.NotConnected: This interface is not connected: disconnect.
May 11 00:12:49 carmen-laptop nm-dispatcher[3287]: Dispatching action 'down' for wlp1s0
May 11 00:12:50 carmen-laptop systemd[1]: Reached target Sleep.
May 11 00:12:50 carmen-laptop systemd[1]: Starting Suspend...
May 11 00:12:50 carmen-laptop systemd-sleep[3744]: Suspending system...
May 11 00:12:50 carmen-laptop systemd-sleep[3744]: INFO: Skip running /usr/lib/systemd/system-sleep/grub2.sleep for suspend

This is the output of suspending once, resuming once, and attempting to suspend again (at which point the entire system becomes unresponsive and the screen is off). The only lines that I think could be the culprits were:

May 11 00:12:38 carmen-laptop kernel: ahci 0000:00:17.0: port does not support device sleep

--- and ---

May 11 00:12:38 carmen-laptop kernel: usb 1-9:1.0: rebind failed: -517
May 11 00:12:38 carmen-laptop kernel: usb 1-9:1.1: rebind failed: -517

The output of `dmesg | grep 'usb 1-9'` is:

[    3.519046] usb 1-9: new full-speed USB device number 3 using xhci_hcd
[    3.704845] usb 1-9: New USB device found, idVendor=8087, idProduct=0a2a
[    3.704846] usb 1-9: New USB device strings: Mfr=0, Product=0, SerialNumber=0

Which isn't very clear to me.

If someone could help me decypher this, I would be very grateful.

Last edited by carmenbbakker (2016-05-10 22:34:49)

carmenbbakker

Member

Registered: 2016-05-10

Posts: 2

Re: ASUS UX305 suspend not working after kernel 4.2.1 update

Forget all that, I guess.

I upgraded to 4.6.0-rc7 and it just works now.

Possibly related Ubuntu bug thread:

https://bugs.launchpad.net/ubuntu/+sour … ug/1566302