Attachment 129244 Details for Bug 99545 – dmesg

dmesg

20170131_hang_after_hibernate_dmesg.txt (text/plain), 9.99 KB, created by unki on 2017-01-31 06:01:34 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: unki

Created: 2017-01-31 06:01:34 UTC

Size: 9.99 KB

patch

obsolete

>[407643.295421] PM: Hibernation mode set to 'platform'
>[407643.296603] (NULL device *): firmware: direct-loading firmware i915/skl_dmc_ver1_26.bin
>[407643.297450] (NULL device *): firmware: direct-loading firmware intel/ibt-11-5.ddc
>[407643.299977] (NULL device *): firmware: direct-loading firmware intel/ibt-11-5.sfi
>[407643.303009] (NULL device *): firmware: direct-loading firmware iwlwifi-8000C-22.ucode
>[407643.303095] PM: Syncing filesystems ... 
>[407643.311704] done.
>[407643.311709] Freezing user space processes ... (elapsed 0.003 seconds) done.
>[407643.315758] PM: Marking nosave pages: [mem 0x00000000-0x00000fff]
>[407643.315762] PM: Marking nosave pages: [mem 0x00058000-0x00058fff]
>[407643.315765] PM: Marking nosave pages: [mem 0x0009d000-0x000fffff]
>[407643.315776] PM: Marking nosave pages: [mem 0xab4ca000-0xab4f4fff]
>[407643.315782] PM: Marking nosave pages: [mem 0xb63c1000-0xb7ffefff]
>[407643.316319] PM: Marking nosave pages: [mem 0xb8000000-0xffffffff]
>[407643.318686] PM: Basic memory bitmaps created
>[407643.318840] PM: Preallocating image memory... done (allocated 809922 pages)
>[407644.466827] PM: Allocated 3239688 kbytes in 1.14 seconds (2841.83 MB/s)
>[407644.466828] Freezing remaining freezable tasks ... (elapsed 0.004 seconds) done.
>[407644.471938] Suspending console(s) (use no_console_suspend to debug)
>[407644.552272] ACPI : EC: event blocked
>[407644.918447] PM: freeze of devices complete after 446.503 msecs
>[407644.939496] PM: late freeze of devices complete after 21.020 msecs
>[407644.940626] ACPI : EC: interrupt blocked
>[407644.963188] PM: noirq freeze of devices complete after 23.688 msecs
>[407644.963585] ACPI: Preparing to enter system sleep state S4
>[407644.967988] ACPI : EC: EC stopped
>[407644.967988] PM: Saving platform NVS memory
>[407644.968206] Disabling non-boot CPUs ...
>[407644.968446] Broke affinity for irq 125
>[407644.969932] smpboot: CPU 1 is now offline
>[407644.970625] Broke affinity for irq 124
>[407644.970628] Broke affinity for irq 125
>[407644.970630] Broke affinity for irq 126
>[407644.970633] Broke affinity for irq 128
>[407644.971657] smpboot: CPU 2 is now offline
>[407644.972272] Broke affinity for irq 1
>[407644.972275] Broke affinity for irq 8
>[407644.972277] Broke affinity for irq 9
>[407644.972280] Broke affinity for irq 12
>[407644.972283] Broke affinity for irq 16
>[407644.972285] Broke affinity for irq 20
>[407644.972293] Broke affinity for irq 124
>[407644.972295] Broke affinity for irq 125
>[407644.972298] Broke affinity for irq 126
>[407644.972300] Broke affinity for irq 128
>[407644.973319] smpboot: CPU 3 is now offline
>[407644.975218] PM: Creating hibernation image:
>[407645.081154] PM: Need to copy 802394 pages
>[407645.081155] PM: Normal pages needed: 802394 + 1024, available pages: 1264694
>[407644.976336] PM: Restoring platform NVS memory
>[407644.976383] ACPI : EC: EC started
>[407644.977136] Suspended for 27428.098 seconds
>[407644.977195] Enabling non-boot CPUs ...
>[407644.977254] x86: Booting SMP configuration:
>[407644.977254] smpboot: Booting Node 0 Processor 1 APIC 0x2
>[407644.979940]  cache: parent cpu1 should not be sleeping
>[407644.980161] CPU1 is up
>[407644.980203] smpboot: Booting Node 0 Processor 2 APIC 0x1
>[407644.982941]  cache: parent cpu2 should not be sleeping
>[407644.983175] CPU2 is up
>[407644.983222] smpboot: Booting Node 0 Processor 3 APIC 0x3
>[407644.985912]  cache: parent cpu3 should not be sleeping
>[407644.986173] CPU3 is up
>[407644.990359] ACPI: Waking up from system sleep state S4
>[407644.995951] thinkpad_acpi: EC reports that Thermal Table has changed
>[407645.002642] ACPI : EC: interrupt unblocked
>[407645.022507] PM: noirq restore of devices complete after 20.016 msecs
>[407645.022908] [drm:gen9_set_dc_state [i915]] *ERROR* DC state mismatch (0x0 -> 0x2)
>[407645.034137] PM: early restore of devices complete after 11.607 msecs
>[407645.115841] usb usb1: root hub lost power or was reset
>[407645.115843] usb usb2: root hub lost power or was reset
>[407645.116863] ACPI : EC: event unblocked
>[407645.117395] rtc_cmos 00:02: System wakeup disabled by ACPI
>[407645.117649] sd 1:0:0:0: [sda] Starting disk
>[407645.123858] [drm] GuC firmware load skipped
>[407645.226505] tpm tpm0: A TPM error (6) occurred attempting to read a pcr value
>[407645.226508] tpm tpm0: TPM is disabled/deactivated (0x6)
>[407645.430195] ata2: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
>[407645.430706] ata2.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
>[407645.430709] ata2.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>[407645.430867] ata2.00: ACPI cmd ef/10:09:00:00:00:a0 (SET FEATURES) succeeded
>[407645.431270] ata2.00: supports DRM functions and may not be fully accessible
>[407645.433297] ata2.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
>[407645.433302] ata2.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
>[407645.433457] ata2.00: ACPI cmd ef/10:09:00:00:00:a0 (SET FEATURES) succeeded
>[407645.433846] ata2.00: supports DRM functions and may not be fully accessible
>[407645.434940] ata2.00: configured for UDMA/133
>[407645.462480] usb 1-2: reset high-speed USB device number 2 using xhci_hcd
>[407645.669711] qcserial ttyUSB0: Qualcomm USB modem converter now disconnected from ttyUSB0
>[407645.669733] qcserial 1-2:1.0: device disconnected
>[407645.670055] qcserial ttyUSB1: Qualcomm USB modem converter now disconnected from ttyUSB1
>[407645.670077] qcserial 1-2:1.2: device disconnected
>[407645.670342] qcserial ttyUSB2: Qualcomm USB modem converter now disconnected from ttyUSB2
>[407645.670365] qcserial 1-2:1.3: device disconnected
>[407645.842464] usb 1-8: reset high-speed USB device number 4 using xhci_hcd
>[407645.915185] psmouse serio1: synaptics: queried max coordinates: x [..5678], y [..4754]
>[407645.946763] psmouse serio1: synaptics: queried min coordinates: x [1262..], y [1098..]
>[407646.102493] usb 1-7: reset full-speed USB device number 3 using xhci_hcd
>[407646.362489] usb 1-9: reset full-speed USB device number 5 using xhci_hcd
>[407646.504422] PM: restore of devices complete after 1388.706 msecs
>[407646.504644] usb 1-7:1.0: rebind failed: -517
>[407646.504651] usb 1-7:1.1: rebind failed: -517
>[407646.504663] usb 1-2:1.0: rebind failed: -517
>[407646.504670] usb 1-2:1.2: rebind failed: -517
>[407646.504676] usb 1-2:1.3: rebind failed: -517
>[407646.505051] PM: Image restored successfully.
>[407646.505130] PM: Basic memory bitmaps freed
>[407646.505132] Restarting tasks ... done.
>[407646.514728] qcserial 1-2:1.0: Qualcomm USB modem converter detected
>[407646.514822] usb 1-2: Qualcomm USB modem converter now attached to ttyUSB0
>[407646.515508] thermal thermal_zone3: failed to read out thermal zone (-5)
>[407646.515556] qcserial 1-2:1.2: Qualcomm USB modem converter detected
>[407646.515636] usb 1-2: Qualcomm USB modem converter now attached to ttyUSB1
>[407646.515961] Bluetooth: hci0: Bootloader revision 0.0 build 2 week 52 2014
>[407646.516124] qcserial 1-2:1.3: Qualcomm USB modem converter detected
>[407646.516229] usb 1-2: Qualcomm USB modem converter now attached to ttyUSB2
>[407646.522952] Bluetooth: hci0: Device revision is 5
>[407646.522954] Bluetooth: hci0: Secure boot is enabled
>[407646.522954] Bluetooth: hci0: OTP lock is enabled
>[407646.522955] Bluetooth: hci0: API lock is enabled
>[407646.522955] Bluetooth: hci0: Debug lock is disabled
>[407646.522956] Bluetooth: hci0: Minimum firmware build 1 week 10 2014
>[407646.522958] Bluetooth: hci0: Found device firmware: intel/ibt-11-5.sfi
>[407646.594861] [drm] RC6 on
>[407646.682321] usb 1-6: new full-speed USB device number 8 using xhci_hcd
>[407646.684226] e1000e: eth0 NIC Link is Down
>[407646.685681] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
>[407646.828201] usb 1-6: New USB device found, idVendor=1bcf, idProduct=0824
>[407646.828202] usb 1-6: New USB device strings: Mfr=1, Product=2, SerialNumber=0
>[407646.828203] usb 1-6: Product: SmartMouse
>[407646.828204] usb 1-6: Manufacturer: SunplusIT
>[407646.829569] input: SunplusIT SmartMouse as /devices/pci0000:00/0000:00:14.0/usb1/1-6/1-6:1.0/0003:1BCF:0824.0007/input/input22
>[407646.886578] hid-generic 0003:1BCF:0824.0007: input,hidraw0: USB HID v1.00 Keyboard [SunplusIT SmartMouse] on usb-0000:00:14.0-6/input0
>[407646.888051] input: SunplusIT SmartMouse as /devices/pci0000:00/0000:00:14.0/usb1/1-6/1-6:1.1/0003:1BCF:0824.0008/input/input23
>[407646.898529] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
>[407646.899965] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
>[407646.902731] iwlwifi 0000:04:00.0: L1 Enabled - LTR Enabled
>[407646.903692] iwlwifi 0000:04:00.0: L1 Enabled - LTR Enabled
>[407646.946453] hid-generic 0003:1BCF:0824.0008: input,hidraw1: USB HID v1.00 Mouse [SunplusIT SmartMouse] on usb-0000:00:14.0-6/input1
>[407647.032021] iwlwifi 0000:04:00.0: L1 Enabled - LTR Enabled
>[407647.032447] iwlwifi 0000:04:00.0: L1 Enabled - LTR Enabled
>[407647.118994] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
>[407647.127623] iwlwifi 0000:04:00.0: L1 Enabled - LTR Enabled
>[407647.128578] iwlwifi 0000:04:00.0: L1 Enabled - LTR Enabled
>[407647.256412] iwlwifi 0000:04:00.0: L1 Enabled - LTR Enabled
>[407647.256792] iwlwifi 0000:04:00.0: L1 Enabled - LTR Enabled
>[407647.344057] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
>[407647.404909] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
>[407647.885682] Bluetooth: hci0: Waiting for firmware download to complete
>[407647.885949] Bluetooth: hci0: Firmware loaded in 1346269 usecs
>[407647.886041] Bluetooth: hci0: Waiting for device to boot
>[407647.897019] Bluetooth: hci0: Device booted in 10801 usecs
>[407647.897029] Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.ddc
>[407647.898047] Bluetooth: hci0: Failed to send Intel_Write_DDC (-22)
>[407651.025416] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
>[407658.134521] drm/i915: Resetting chip after gpu hang
>[407658.134623] [drm] RC6 on
>[407658.153123] [drm] GuC firmware load skipped
>[407669.114180] drm/i915: Resetting chip after gpu hang
>[407669.114225] [drm] RC6 on
>[407669.130184] [drm] GuC firmware load skipped
>[407681.115581] drm/i915: Resetting chip after gpu hang
>[407681.115688] [drm] RC6 on
>[407681.131603] [drm] GuC firmware load skipped

Actions: View

Attachments on bug 99545: 129159 | 129160 | 129244 | 129245

Use of freedesktop.org services, including Bugzilla, is subject to our Code of Conduct. How we collect and use information is described in our Privacy Policy.