Attachment 599846 Details for Bug 842406 – /var/log/messages for suspend/resume

/var/log/messages for suspend/resume

sleep-syslog.txt (text/plain), 12.71 KB, created by Robert Story on 2012-07-23 19:12:44 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Robert Story

Created: 2012-07-23 19:12:44 UTC

Size: 12.71 KB

patch

obsolete

>Jul 23 14:35:30 tp NetworkManager[1495]: <info> sleep requested (sleeping: no  enabled: yes)
>Jul 23 14:35:30 tp NetworkManager[1495]: <info> sleeping or disabling...
>Jul 23 14:35:30 tp NetworkManager[1495]: <info> (em1): now unmanaged
>Jul 23 14:35:30 tp NetworkManager[1495]: <info> (em1): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
>Jul 23 14:35:30 tp NetworkManager[1495]: <info> (em1): cleaning up...
>Jul 23 14:35:30 tp NetworkManager[1495]: <info> (em1): taking down device.
>Jul 23 14:35:30 tp NetworkManager[1495]: <info> (wlan0): now unmanaged
>Jul 23 14:35:30 tp NetworkManager[1495]: <info> (wlan0): device state change: disconnected -> unmanaged (reason 'sleeping') [30 10 37]
>Jul 23 14:35:30 tp NetworkManager[1495]: <info> (wlan0): cleaning up...
>Jul 23 14:35:30 tp NetworkManager[1495]: <info> (wlan0): taking down device.
>Jul 23 14:35:31 tp bluetoothd[1413]: HCI dev 0 unregistered
>Jul 23 14:35:31 tp bluetoothd[1413]: Stopping hci0 event socket
>Jul 23 14:35:31 tp bluetoothd[1413]: bluetoothd[1413]: HCI dev 0 unregistered
>Jul 23 14:35:31 tp bluetoothd[1413]: bluetoothd[1413]: Stopping hci0 event socket
>Jul 23 14:35:31 tp bluetoothd[1413]: Unregister path: /org/bluez/1413/hci0
>Jul 23 14:35:31 tp bluetoothd[1413]: bluetoothd[1413]: Unregister path: /org/bluez/1413/hci0
>Jul 23 14:35:31 tp kernel: [  271.806768] usb 1-1.4: USB disconnect, device number 4
>Jul 23 14:35:31 tp systemd[1]: Service bluetooth.target is not needed anymore. Stopping.
>Jul 23 14:35:33 tp pulseaudio[1707]: module-alsa-card.c: Failed to find a working profile.
>Jul 23 14:35:33 tp pulseaudio[1707]: module.c: Failed to load  module "module-alsa-card" (argument: "device_id="29" name="platform-thinkpad_acpi" card_name="alsa_card.platform-thinkpad_acpi" tsched=yes ignore_dB=no card_properties="module-udev-detect.discovered=1""): initialization failed.
>Jul 23 14:45:28 tp acpid: client 1837[0:0] has disconnected
>Jul 23 14:45:28 tp kernel: [  273.787401] PM: Syncing filesystems ... done.
>Jul 23 14:45:28 tp kernel: [  273.789432] Freezing user space processes ... (elapsed 0.01 seconds) done.
>Jul 23 14:45:28 tp kernel: [  273.801044] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
>Jul 23 14:45:28 tp kernel: [  273.812189] Suspending console(s) (use no_console_suspend to debug)
>Jul 23 14:45:28 tp kernel: [  274.031816] sd 1:0:0:0: [sdb] Synchronizing SCSI cache
>Jul 23 14:45:28 tp kernel: [  274.031894] sd 0:0:0:0: [sda] Synchronizing SCSI cache
>Jul 23 14:45:28 tp kernel: [  274.031996] sd 1:0:0:0: [sdb] Stopping disk
>Jul 23 14:45:28 tp kernel: [  274.032113] sd 0:0:0:0: [sda] Stopping disk
>Jul 23 14:45:28 tp kernel: [  274.330901] e1000e 0000:00:19.0: wake-up capability enabled by ACPI
>Jul 23 14:45:28 tp kernel: [  274.701815] PM: suspend of devices complete after 889.566 msecs
>Jul 23 14:45:28 tp kernel: [  274.701899] PM: late suspend of devices complete after 0.081 msecs
>Jul 23 14:45:28 tp kernel: [  274.702004] pcieport 0000:00:1c.6: wake-up capability enabled by ACPI
>Jul 23 14:45:28 tp kernel: [  274.723824] ehci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI
>Jul 23 14:45:28 tp kernel: [  274.734657] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3
>Jul 23 14:45:28 tp kernel: [  274.734758] ehci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI
>Jul 23 14:45:28 tp kernel: [  274.745655] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3
>Jul 23 14:45:28 tp kernel: [  274.745765] PM: noirq suspend of devices complete after 43.866 msecs
>Jul 23 14:45:28 tp kernel: [  274.745964] ACPI: Preparing to enter system sleep state S3
>Jul 23 14:45:28 tp kernel: [  274.795702] PM: Saving platform NVS memory
>Jul 23 14:45:28 tp kernel: [  274.797656] Disabling non-boot CPUs ...
>Jul 23 14:45:28 tp kernel: [  274.799623] CPU 1 is now offline
>Jul 23 14:45:28 tp kernel: [  274.799881] CPU 1 offline: Remove Rx thread
>Jul 23 14:45:28 tp kernel: [  274.801732] CPU 2 is now offline
>Jul 23 14:45:28 tp kernel: [  274.802089] CPU 2 offline: Remove Rx thread
>Jul 23 14:45:28 tp kernel: [  274.802438] Broke affinity for irq 16
>Jul 23 14:45:28 tp kernel: [  274.802448] Broke affinity for irq 23
>Jul 23 14:45:28 tp kernel: [  274.803730] CPU 3 is now offline
>Jul 23 14:45:28 tp kernel: [  274.803999] CPU 3 offline: Remove Rx thread
>Jul 23 14:45:28 tp kernel: [  274.805496] CPU 4 is now offline
>Jul 23 14:45:28 tp kernel: [  274.805866] CPU 4 offline: Remove Rx thread
>Jul 23 14:45:28 tp kernel: [  274.806248] Broke affinity for irq 17
>Jul 23 14:45:28 tp kernel: [  274.806260] Broke affinity for irq 41
>Jul 23 14:45:28 tp kernel: [  274.807438] CPU 5 is now offline
>Jul 23 14:45:28 tp kernel: [  274.807659] CPU 5 offline: Remove Rx thread
>Jul 23 14:45:28 tp kernel: [  274.809216] CPU 6 is now offline
>Jul 23 14:45:28 tp kernel: [  274.809490] CPU 6 offline: Remove Rx thread
>Jul 23 14:45:28 tp kernel: [  274.810990] CPU 7 is now offline
>Jul 23 14:45:28 tp kernel: [  274.811231] CPU 7 offline: Remove Rx thread
>Jul 23 14:45:28 tp kernel: [  274.811338] Extended CMOS year: 2000
>Jul 23 14:45:28 tp kernel: [  274.812410] ACPI: Low-level resume complete
>Jul 23 14:45:28 tp kernel: [  274.812450] PM: Restoring platform NVS memory
>Jul 23 14:45:28 tp kernel: [  274.813306] Extended CMOS year: 2000
>Jul 23 14:45:28 tp kernel: [  274.813895] microcode: CPU0 updated to revision 0x28, date = 2012-04-24
>Jul 23 14:45:28 tp kernel: [  274.813926] Enabling non-boot CPUs ...
>Jul 23 14:45:28 tp kernel: [  274.814034] Booting Node 0 Processor 1 APIC 0x1
>Jul 23 14:45:28 tp kernel: [  274.825182] Disabled fast string operations
>Jul 23 14:45:28 tp kernel: [  274.827479] NMI watchdog: enabled, takes one hw-pmu counter.
>Jul 23 14:45:28 tp kernel: [  274.854016] microcode: CPU1 updated to revision 0x28, date = 2012-04-24
>Jul 23 14:45:28 tp kernel: [  274.854022] bnx2i: CPU 1 online: Create Rx thread
>Jul 23 14:45:28 tp kernel: [  274.854050] CPU1 is up
>Jul 23 14:45:28 tp kernel: [  274.854172] Booting Node 0 Processor 2 APIC 0x2
>Jul 23 14:45:28 tp kernel: [  274.865198] Disabled fast string operations
>Jul 23 14:45:28 tp kernel: [  274.867553] NMI watchdog: enabled, takes one hw-pmu counter.
>Jul 23 14:45:28 tp kernel: [  274.893985] microcode: CPU2 updated to revision 0x28, date = 2012-04-24
>Jul 23 14:45:28 tp kernel: [  274.893989] bnx2i: CPU 2 online: Create Rx thread
>Jul 23 14:45:28 tp kernel: [  274.894068] CPU2 is up
>Jul 23 14:45:28 tp kernel: [  274.894152] Booting Node 0 Processor 3 APIC 0x3
>Jul 23 14:45:28 tp kernel: [  274.905179] Disabled fast string operations
>Jul 23 14:45:28 tp kernel: [  274.907523] NMI watchdog: enabled, takes one hw-pmu counter.
>Jul 23 14:45:28 tp kernel: [  274.934002] microcode: CPU3 updated to revision 0x28, date = 2012-04-24
>Jul 23 14:45:28 tp kernel: [  274.934013] bnx2i: CPU 3 online: Create Rx thread
>Jul 23 14:45:28 tp kernel: [  274.934094] CPU3 is up
>Jul 23 14:45:28 tp kernel: [  274.934371] Booting Node 0 Processor 4 APIC 0x4
>Jul 23 14:45:28 tp kernel: [  274.945397] Disabled fast string operations
>Jul 23 14:45:28 tp kernel: [  274.947812] NMI watchdog: enabled, takes one hw-pmu counter.
>Jul 23 14:45:28 tp kernel: [  274.973983] microcode: CPU4 updated to revision 0x28, date = 2012-04-24
>Jul 23 14:45:28 tp kernel: [  274.973988] bnx2i: CPU 4 online: Create Rx thread
>Jul 23 14:45:28 tp kernel: [  274.974072] CPU4 is up
>Jul 23 14:45:28 tp kernel: [  274.974350] Booting Node 0 Processor 5 APIC 0x5
>Jul 23 14:45:28 tp kernel: [  274.985375] Disabled fast string operations
>Jul 23 14:45:28 tp kernel: [  274.987851] NMI watchdog: enabled, takes one hw-pmu counter.
>Jul 23 14:45:28 tp kernel: [  275.014013] microcode: CPU5 updated to revision 0x28, date = 2012-04-24
>Jul 23 14:45:28 tp kernel: [  275.014019] bnx2i: CPU 5 online: Create Rx thread
>Jul 23 14:45:28 tp kernel: [  275.014096] CPU5 is up
>Jul 23 14:45:28 tp kernel: [  275.014369] Booting Node 0 Processor 6 APIC 0x6
>Jul 23 14:45:28 tp kernel: [  275.025394] Disabled fast string operations
>Jul 23 14:45:28 tp kernel: [  275.027829] NMI watchdog: enabled, takes one hw-pmu counter.
>Jul 23 14:45:28 tp kernel: [  275.053964] microcode: CPU6 updated to revision 0x28, date = 2012-04-24
>Jul 23 14:45:28 tp kernel: [  275.053968] bnx2i: CPU 6 online: Create Rx thread
>Jul 23 14:45:28 tp kernel: [  275.054082] CPU6 is up
>Jul 23 14:45:28 tp kernel: [  275.054352] Booting Node 0 Processor 7 APIC 0x7
>Jul 23 14:45:28 tp kernel: [  275.065376] Disabled fast string operations
>Jul 23 14:45:28 tp kernel: [  275.067860] NMI watchdog: enabled, takes one hw-pmu counter.
>Jul 23 14:45:28 tp kernel: [  275.094007] microcode: CPU7 updated to revision 0x28, date = 2012-04-24
>Jul 23 14:45:28 tp kernel: [  275.094016] bnx2i: CPU 7 online: Create Rx thread
>Jul 23 14:45:28 tp kernel: [  275.094091] CPU7 is up
>Jul 23 14:45:28 tp kernel: [  275.100856] ACPI: Waking up from system sleep state S3
>Jul 23 14:45:28 tp kernel: [  275.290802] ehci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI
>Jul 23 14:45:28 tp kernel: [  275.290810] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
>Jul 23 14:45:28 tp kernel: [  275.290813] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
>Jul 23 14:45:28 tp kernel: [  275.291113] ehci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI
>Jul 23 14:45:28 tp kernel: [  275.291118] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
>Jul 23 14:45:28 tp kernel: [  275.291121] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
>Jul 23 14:45:28 tp kernel: [  275.291552] sdhci-pci 0000:0d:00.0: MMC controller base frequency changed to 50Mhz.
>Jul 23 14:45:28 tp kernel: [  275.291743] pcieport 0000:00:1c.6: wake-up capability disabled by ACPI
>Jul 23 14:45:28 tp kernel: [  275.291813] PM: noirq resume of devices complete after 1.206 msecs
>Jul 23 14:45:28 tp kernel: [  275.291891] PM: early resume of devices complete after 0.036 msecs
>Jul 23 14:45:28 tp kernel: [  275.291928] e1000e 0000:00:19.0: wake-up capability disabled by ACPI
>Jul 23 14:45:28 tp kernel: [  275.291945] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
>Jul 23 14:45:28 tp kernel: [  275.291962] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
>Jul 23 14:45:28 tp kernel: [  275.292109] rtlwifi: wireless switch is on
>Jul 23 14:45:28 tp kernel: [  275.292225] usb usb3: root hub lost power or was reset
>Jul 23 14:45:28 tp kernel: [  275.292227] usb usb4: root hub lost power or was reset
>Jul 23 14:45:28 tp kernel: [  275.293545] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
>Jul 23 14:45:28 tp kernel: [  275.293587] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
>Jul 23 14:45:28 tp kernel: [  275.337549] tpm_tis 00:0a: A TPM error (6) occurred attempting to read a pcr value
>Jul 23 14:45:28 tp kernel: [  275.337552] tpm_tis 00:0a: TPM is disabled/deactivated (0x6)
>Jul 23 14:45:28 tp kernel: [  275.524675] usb 1-1.3: reset full-speed USB device number 3 using ehci_hcd
>Jul 23 14:45:28 tp kernel: [  275.609515] ata5: SATA link down (SStatus 0 SControl 300)
>Jul 23 14:45:28 tp kernel: [  275.611493] ata4: SATA link down (SStatus 0 SControl 300)
>Jul 23 14:45:28 tp kernel: [  275.682660] usb 1-1.6: reset high-speed USB device number 5 using ehci_hcd
>Jul 23 14:45:28 tp kernel: [  275.843578] firewire_core 0000:0d:00.3: rediscovered device fw0
>Jul 23 14:45:28 tp kernel: [  277.296384] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>Jul 23 14:45:28 tp kernel: [  277.502353] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
>Jul 23 14:45:28 tp kernel: [  277.512721] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>Jul 23 14:45:28 tp kernel: [  277.512728] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
>Jul 23 14:45:28 tp kernel: [  277.513806] ata2.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>Jul 23 14:45:28 tp kernel: [  277.513813] ata2.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
>Jul 23 14:45:28 tp kernel: [  277.517284] ata2.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>Jul 23 14:45:28 tp kernel: [  277.517291] ata2.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
>Jul 23 14:45:28 tp kernel: [  277.518716] ata2.00: configured for UDMA/133
>Jul 23 14:45:28 tp kernel: [  277.519045] sd 1:0:0:0: [sdb] Starting disk
>Jul 23 14:45:28 tp kernel: [  277.547761] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>Jul 23 14:45:28 tp kernel: [  277.547768] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
>Jul 23 14:45:28 tp kernel: [  277.551282] ata1.00: configured for UDMA/133
>Jul 23 14:45:28 tp kernel: [  277.551435] sd 0:0:0:0: [sda] Starting disk
>Jul 23 14:45:28 tp kernel: [  277.553155] Extended CMOS year: 2000
>Jul 23 14:45:28 tp kernel: [  277.562419] PM: resume of devices complete after 2270.785 msecs
>Jul 23 14:45:28 tp kernel: [  277.574176] Restarting tasks ... done.
>Jul 23 14:45:28 tp kernel: [  277.579531] video LNXVIDEO:01: Restoring backlight state

Actions: View

Attachments on bug 842406: 599844 | 599845 | 599846