Bug#722001: linux-image-3.10-2-amd64: suspend failed with -16 in nouveau
FYI, a few hours before, there were no problems with suspend.
AFAIK, there were 2 changes in the context: I had been using
wifi a few minutes before (but was no longer connected) and
the AC power was connected.
Here's the log of this earlier (successful) suspend:
Sep 6 11:53:24 xvii acpid: received input layer event "button/sleep SBTN 00000080 00000000"
Sep 6 11:53:24 xvii acpid: rule from 14328[0:0] matched
Sep 6 11:53:24 xvii acpid: notifying client 14328[0:0]
Sep 6 11:53:24 xvii acpid: rule from /etc/acpi/events/sleepbtn matched
Sep 6 11:53:24 xvii acpid: executing action "/etc/acpi/sleep_suspend.sh sleep"
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: Session added: /org/freedesktop/ConsoleKit/Session207
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: Found uid of session '/org/freedesktop/ConsoleKit/Session207': 106
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: Found x11 display of session '/org/freedesktop/ConsoleKit/Session207':
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): AcUserManager: (mostly) ignoring session '/org/freedesktop/ConsoleKit/Session207' since it's not graphical
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: trying to track new user with uid 106
Sep 6 11:53:24 xvii gnome-session[14357]: DEBUG(+): GsmDBusClient: obj_path=/org/freedesktop/DBus interface=org.freedesktop.DBus method=NameOwnerChanged
Sep 6 11:53:24 xvii gnome-session[14357]: DEBUG(+): GsmDBusClient: obj_path=/org/freedesktop/DBus interface=org.freedesktop.DBus method=NameOwnerChanged
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: finding user with id 106 state 1
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: finding user with id 106 state 2
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: Looking for user with id 106 in accounts service
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUser: adding session /org/freedesktop/ConsoleKit/Session207
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: added session for user with id 106
Sep 6 11:53:24 xvii gdm-welcome][14347]: AccountsService-DEBUG(+): ActUserManager: new user in accounts service with object path /org/freedesktop/Accounts/User106
Sep 6 11:53:24 xvii gdm3][14402]: AccountsService-DEBUG(+): ActUserManager: new user in accounts service with object path /org/freedesktop/Accounts/User106
Sep 6 11:53:24 xvii gdm-welcome][14347]: AccountsService-DEBUG(+): ActUserManager: tracking new user with object path /org/freedesktop/Accounts/User106
Sep 6 11:53:24 xvii gdm3][14402]: AccountsService-DEBUG(+): ActUserManager: tracking new user with object path /org/freedesktop/Accounts/User106
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: new user in accounts service with object path /org/freedesktop/Accounts/User106
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: tracking new user with object path /org/freedesktop/Accounts/User106
Sep 6 11:53:24 xvii gnome-session[14357]: DEBUG(+): GsmDBusClient: obj_path=/org/freedesktop/DBus interface=org.freedesktop.DBus method=NameOwnerChanged
Sep 6 11:53:24 xvii gnome-session[14357]: DEBUG(+): GsmDBusClient: obj_path=/org/freedesktop/DBus interface=org.freedesktop.DBus method=NameOwnerChanged
Sep 6 11:53:24 xvii acpid: action exited with status 0
Sep 6 11:53:24 xvii acpid: 2 total rules matched
Sep 6 11:53:24 xvii acpid: completed input layer event "button/sleep SBTN 00000080 00000000"
Sep 6 11:53:24 xvii gdm3][14402]: AccountsService-DEBUG(+): ActUserManager: user Debian-gdm is now loaded
Sep 6 11:53:24 xvii gdm3][14402]: AccountsService-DEBUG(+): ActUserManager: user Debian-gdm was not yet known, adding it
Sep 6 11:53:24 xvii gdm3][14402]: AccountsService-DEBUG(+): ActUserManager: tracking user 'Debian-gdm'
Sep 6 11:53:24 xvii gdm3][14402]: AccountsService-DEBUG(+): ActUserManager: loaded, so emitting user-added signal
Sep 6 11:53:24 xvii gdm3][14402]: AccountsService-DEBUG(+): ActUserManager: no pending users, trying to set loaded property
Sep 6 11:53:24 xvii gdm3][14402]: AccountsService-DEBUG(+): ActUserManager: already loaded, so not setting loaded property
Sep 6 11:53:24 xvii gdm3][14402]: AccountsService-DEBUG(+): ActUserManager: user Debian-gdm changed
Sep 6 11:53:24 xvii gdm-welcome][14347]: AccountsService-DEBUG(+): ActUserManager: user Debian-gdm is now loaded
Sep 6 11:53:24 xvii gdm-welcome][14347]: AccountsService-DEBUG(+): ActUserManager: user Debian-gdm was not yet known, adding it
Sep 6 11:53:24 xvii gdm-welcome][14347]: AccountsService-DEBUG(+): ActUserManager: tracking user 'Debian-gdm'
Sep 6 11:53:24 xvii gdm-welcome][14347]: AccountsService-DEBUG(+): ActUserManager: loaded, so emitting user-added signal
Sep 6 11:53:24 xvii gdm-welcome][14347]: AccountsService-DEBUG(+): ActUserManager: no pending users, trying to set loaded property
Sep 6 11:53:24 xvii gdm-welcome][14347]: AccountsService-DEBUG(+): ActUserManager: already loaded, so not setting loaded property
Sep 6 11:53:24 xvii gdm-welcome][14347]: AccountsService-DEBUG(+): ActUserManager: user Debian-gdm changed
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: Found object path of user with id 106: /org/freedesktop/Accounts/User106
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: finding user with id 106 state 3
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: user with id 106 fetched
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: finished handling request for user with id 106
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: Session removed: /org/freedesktop/ConsoleKit/Session207
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: New session for uid 106 on x11 display removed before fully loading
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: user Debian-gdm is now loaded
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: user Debian-gdm was not yet known, adding it
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: tracking user 'Debian-gdm'
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: loaded, so emitting user-added signal
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: no pending users, trying to set loaded property
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: already loaded, so not setting loaded property
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: user Debian-gdm changed
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: DEBUG(+): GdmUserChooserWidget: font height 18; using icon size 54
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: DEBUG(+): GdmUserChooserWidget: User added name:Debian-gdm logged-in:0 pixbuf:0xef4940
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: user Debian-gdm is now loaded
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: sessions changed (user Debian-gdm) num=0
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: DEBUG(+): GdmUserChooserWidget: User logged in changed: Debian-gdm
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: no pending users, trying to set loaded property
Sep 6 11:53:24 xvii gdm-simple-greeter[14391]: AccountsService-DEBUG(+): ActUserManager: already loaded, so not setting loaded property
Sep 6 11:53:25 xvii gnome-session[14357]: DEBUG(+): GsmDBusClient: obj_path=/org/freedesktop/DBus interface=org.freedesktop.DBus method=NameOwnerChanged
Sep 6 11:53:25 xvii gnome-session[14357]: DEBUG(+): GsmDBusClient: obj_path=/org/freedesktop/DBus interface=org.freedesktop.DBus method=NameOwnerChanged
Sep 6 11:53:26 xvii kernel: [1051708.604214] e1000e 0000:00:19.0: setting latency timer to 64
Sep 6 11:53:26 xvii kernel: [1051708.604270] e1000e 0000:00:19.0: irq 44 for MSI/MSI-X
Sep 6 11:53:26 xvii named[25277]: received control channel command 'reconfig'
Sep 6 11:53:26 xvii named[25277]: loading configuration from '/etc/bind/named.conf'
Sep 6 11:53:26 xvii named[25277]: reading built-in trusted keys from file '/etc/bind/bind.keys'
Sep 6 11:53:26 xvii named[25277]: using default UDP/IPv4 port range: [1024, 65535]
Sep 6 11:53:26 xvii named[25277]: using default UDP/IPv6 port range: [1024, 65535]
Sep 6 11:53:26 xvii named[25277]: sizing zone task pool based on 5 zones
Sep 6 11:53:26 xvii named[25277]: using built-in root key for view _default
Sep 6 11:53:26 xvii named[25277]: reloading configuration succeeded
Sep 6 11:53:26 xvii named[25277]: any newly configured zones are now loaded
Sep 6 11:53:26 xvii dhclient: Internet Systems Consortium DHCP Client 4.2.4
Sep 6 11:53:26 xvii dhclient: Copyright 2004-2012 Internet Systems Consortium.
Sep 6 11:53:26 xvii dhclient: All rights reserved.
Sep 6 11:53:26 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/
Sep 6 11:53:26 xvii dhclient:
Sep 6 11:53:26 xvii dhclient: Listening on LPF/wlan0/00:21:6a:47:b3:80
Sep 6 11:53:26 xvii dhclient: Sending on LPF/wlan0/00:21:6a:47:b3:80
Sep 6 11:53:26 xvii dhclient: Sending on Socket/fallback
Sep 6 11:53:26 xvii dhclient: DHCPRELEASE on wlan0 to 192.168.0.1 port 67
Sep 6 11:53:26 xvii dhclient: send_packet: Network is unreachable
Sep 6 11:53:26 xvii dhclient: send_packet: please consult README file regarding broadcast address.
Sep 6 11:53:26 xvii dhclient: dhclient.c:2270: Failed to send 300 byte long packet over fallback interface.
Sep 6 11:53:26 xvii named[25277]: received control channel command 'reconfig'
Sep 6 11:53:26 xvii named[25277]: loading configuration from '/etc/bind/named.conf'
Sep 6 11:53:26 xvii named[25277]: reading built-in trusted keys from file '/etc/bind/bind.keys'
Sep 6 11:53:26 xvii named[25277]: using default UDP/IPv4 port range: [1024, 65535]
Sep 6 11:53:26 xvii named[25277]: using default UDP/IPv6 port range: [1024, 65535]
Sep 6 11:53:26 xvii named[25277]: sizing zone task pool based on 5 zones
Sep 6 11:53:26 xvii named[25277]: using built-in root key for view _default
Sep 6 11:53:26 xvii named[25277]: reloading configuration succeeded
Sep 6 11:53:26 xvii named[25277]: any newly configured zones are now loaded
Sep 6 11:53:26 xvii named[25277]: received control channel command 'reconfig'
Sep 6 11:53:26 xvii named[25277]: loading configuration from '/etc/bind/named.conf'
Sep 6 11:53:26 xvii named[25277]: reading built-in trusted keys from file '/etc/bind/bind.keys'
Sep 6 11:53:26 xvii named[25277]: using default UDP/IPv4 port range: [1024, 65535]
Sep 6 11:53:26 xvii named[25277]: using default UDP/IPv6 port range: [1024, 65535]
Sep 6 11:53:26 xvii named[25277]: sizing zone task pool based on 5 zones
Sep 6 11:53:26 xvii named[25277]: using built-in root key for view _default
Sep 6 11:53:26 xvii named[25277]: reloading configuration succeeded
Sep 6 11:53:26 xvii named[25277]: any newly configured zones are now loaded
Sep 6 11:53:26 xvii dhclient: Internet Systems Consortium DHCP Client 4.2.4
Sep 6 11:53:26 xvii dhclient: Copyright 2004-2012 Internet Systems Consortium.
Sep 6 11:53:26 xvii dhclient: All rights reserved.
Sep 6 11:53:26 xvii dhclient: For info, please visit https://www.isc.org/software/dhcp/
Sep 6 11:53:26 xvii dhclient:
Sep 6 11:53:26 xvii dhclient: Listening on LPF/wlan0/00:21:6a:47:b3:80
Sep 6 11:53:26 xvii dhclient: Sending on LPF/wlan0/00:21:6a:47:b3:80
Sep 6 11:53:26 xvii dhclient: Sending on Socket/fallback
Sep 6 11:53:26 xvii dhclient: DHCPRELEASE on wlan0 to 192.168.0.1 port 67
Sep 6 11:53:26 xvii dhclient: send_packet: Network is unreachable
Sep 6 11:53:26 xvii dhclient: send_packet: please consult README file regarding broadcast address.
Sep 6 11:53:26 xvii dhclient: dhclient.c:2270: Failed to send 300 byte long packet over fallback interface.
Sep 6 11:53:27 xvii named[25277]: received control channel command 'reconfig'
Sep 6 11:53:27 xvii named[25277]: loading configuration from '/etc/bind/named.conf'
Sep 6 11:53:27 xvii named[25277]: reading built-in trusted keys from file '/etc/bind/bind.keys'
Sep 6 11:53:27 xvii named[25277]: using default UDP/IPv4 port range: [1024, 65535]
Sep 6 11:53:27 xvii named[25277]: using default UDP/IPv6 port range: [1024, 65535]
Sep 6 11:53:27 xvii named[25277]: sizing zone task pool based on 5 zones
Sep 6 11:53:27 xvii named[25277]: using built-in root key for view _default
Sep 6 11:53:27 xvii named[25277]: reloading configuration succeeded
Sep 6 11:53:27 xvii named[25277]: any newly configured zones are now loaded
Sep 6 11:53:28 xvii kernel: [1051710.866848] PM: Syncing filesystems ... done.
Sep 6 11:53:28 xvii kernel: [1051710.881952] PM: Preparing system for mem sleep
Sep 6 15:28:36 xvii kernel: [1051711.688315] (NULL device *): firmware: agent loaded intel-ucode/06-17-0a into memory
Sep 6 15:28:36 xvii kernel: [1051711.688316] (NULL device *): firmware: agent loaded iwlwifi-5000-5.ucode into memory
Sep 6 15:28:36 xvii kernel: [1051711.688325] Freezing user space processes ... (elapsed 0.01 seconds) done.
Sep 6 15:28:36 xvii kernel: [1051711.704074] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Sep 6 15:28:36 xvii kernel: [1051711.720251] PM: Entering mem sleep
Sep 6 15:28:36 xvii kernel: [1051711.720267] Suspending console(s) (use no_console_suspend to debug)
Sep 6 15:28:36 xvii kernel: [1051711.720796] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Sep 6 15:28:36 xvii kernel: [1051711.720917] sd 0:0:0:0: [sda] Stopping disk
Sep 6 15:28:36 xvii kernel: [1051711.745390] serial 00:08: disabled
Sep 6 15:28:36 xvii kernel: [1051711.745554] nouveau [ DRM] suspending fbcon...
Sep 6 15:28:36 xvii kernel: [1051711.745558] nouveau [ DRM] suspending display...
Sep 6 15:28:36 xvii kernel: [1051711.745573] nouveau [ DRM] unpinning framebuffer(s)...
Sep 6 15:28:36 xvii kernel: [1051711.745647] nouveau [ DRM] evicting buffers...
Sep 6 15:28:36 xvii kernel: [1051711.854942] nouveau [ DRM] waiting for kernel channels to go idle...
Sep 6 15:28:36 xvii kernel: [1051711.854957] nouveau [ DRM] suspending client object trees...
Sep 6 15:28:36 xvii kernel: [1051711.855784] nouveau [ DRM] suspending kernel object tree...
Sep 6 15:28:36 xvii kernel: [1051711.926231] dell_wmi: Received unknown WMI event (0x11)
Sep 6 15:28:36 xvii kernel: [1051715.472310] PM: suspend of devices complete after 3751.673 msecs
Sep 6 15:28:36 xvii kernel: [1051715.472691] PM: late suspend of devices complete after 0.377 msecs
Sep 6 15:28:36 xvii kernel: [1051715.492777] ehci-pci 0000:00:1d.7: System wakeup enabled by ACPI
Sep 6 15:28:36 xvii kernel: [1051715.512589] uhci_hcd 0000:00:1d.2: System wakeup enabled by ACPI
Sep 6 15:28:36 xvii kernel: [1051715.517140] uhci_hcd 0000:00:1d.1: System wakeup enabled by ACPI
Sep 6 15:28:36 xvii kernel: [1051715.521712] uhci_hcd 0000:00:1d.0: System wakeup enabled by ACPI
Sep 6 15:28:36 xvii kernel: [1051715.527454] ehci-pci 0000:00:1a.7: System wakeup enabled by ACPI
Sep 6 15:28:36 xvii kernel: [1051715.544576] uhci_hcd 0000:00:1a.2: System wakeup enabled by ACPI
Sep 6 15:28:36 xvii kernel: [1051715.549179] uhci_hcd 0000:00:1a.1: System wakeup enabled by ACPI
Sep 6 15:28:36 xvii kernel: [1051715.555015] uhci_hcd 0000:00:1a.0: System wakeup enabled by ACPI
Sep 6 15:28:36 xvii kernel: [1051715.568068] PM: noirq suspend of devices complete after 95.374 msecs
Sep 6 15:28:36 xvii kernel: [1051715.568583] ACPI: Preparing to enter system sleep state S3
Sep 6 15:28:36 xvii kernel: [1051715.579572] PM: Saving platform NVS memory
Sep 6 15:28:36 xvii kernel: [1051715.579573] Disabling non-boot CPUs ...
Sep 6 15:28:36 xvii kernel: [1051715.580883] smpboot: CPU 1 is now offline
--
Vincent Lefèvre <vincent@vinc17.net> - Web: <http://www.vinc17.net/>
100% accessible validated (X)HTML - Blog: <http://www.vinc17.net/blog/>
Work: CR INRIA - computer arithmetic / AriC project (LIP, ENS-Lyon)
Reply to: