* 3.5-rc7 - can no longer wake up from suspend to RAM
@ 2012-07-19 4:46 Tomasz Chmielewski
2012-07-21 22:11 ` Roland Dreier
0 siblings, 1 reply; 5+ messages in thread
From: Tomasz Chmielewski @ 2012-07-19 4:46 UTC (permalink / raw)
To: linux-kernel
After upgrading to 3.5-rc7, my laptop no longer wakes up reliable from suspend to RAM. 3.4.x worked fine.
It did wake up once after a several minute pause; there were a couple of:
BUG: soft lockup - CPU#0 stuck for 23s! [pm-suspend:5522]
in dmesg.
See below for more details.
Jul 18 01:30:19 s9 kernel: [57626.175125] NMI watchdog: enabled on all CPUs, permanently consumes one hw-PMU counter.
Jul 18 01:32:04 s9 kernel: [57731.634693] wlan0: deauthenticating from 00:27:22:44:ee:4f by local choice (reason=3)
Jul 18 01:32:04 s9 kernel: [57731.657099] cfg80211: All devices are disconnected, going to restore regulatory settings
Jul 18 01:32:04 s9 kernel: [57731.657113] cfg80211: Restoring regulatory settings
Jul 18 01:32:04 s9 kernel: [57731.657125] cfg80211: Calling CRDA to update world regulatory domain
Jul 18 01:32:04 s9 kernel: [57731.681986] cfg80211: Ignoring regulatory request Set by core since the driver uses its own custom regulatory domain
Jul 18 01:32:04 s9 kernel: [57731.681999] cfg80211: World regulatory domain updated:
Jul 18 01:32:04 s9 kernel: [57731.682004] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Jul 18 01:32:04 s9 kernel: [57731.682010] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Jul 18 01:32:04 s9 kernel: [57731.682015] cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Jul 18 01:32:04 s9 kernel: [57731.682020] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Jul 18 01:32:04 s9 kernel: [57731.682025] cfg80211: (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Jul 18 01:32:04 s9 kernel: [57731.682030] cfg80211: (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Jul 18 01:32:08 s9 kernel: [57734.569995] PM: Syncing filesystems ... done.
Jul 18 01:32:08 s9 kernel: [57734.775422] PM: Preparing system for mem sleep
Jul 18 01:34:47 s9 kernel: [57734.826480] Freezing user space processes ... (elapsed 0.01 seconds) done.
Jul 18 01:34:47 s9 kernel: [57734.842461] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Jul 18 01:34:47 s9 kernel: [57734.858455] PM: Entering mem sleep
Jul 18 01:34:47 s9 kernel: [57734.858529] Suspending console(s) (use no_console_suspend to debug)
Jul 18 01:34:47 s9 kernel: [57734.874842] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Jul 18 01:34:47 s9 kernel: [57734.876600] sd 0:0:0:0: [sda] Stopping disk
Jul 18 01:34:47 s9 kernel: [57735.413906] PM: suspend of devices complete after 539.681 msecs
Jul 18 01:34:47 s9 kernel: [57735.413911] PM: suspend devices took 0.556 seconds
Jul 18 01:34:47 s9 kernel: [57735.414084] PM: late suspend of devices complete after 0.170 msecs
Jul 18 01:34:47 s9 kernel: [57735.414337] xhci_hcd 0000:03:00.0: wake-up capability enabled by ACPI
Jul 18 01:34:47 s9 kernel: [57735.429990] r8169 0000:02:00.0: wake-up capability enabled by ACPI
Jul 18 01:34:47 s9 kernel: [57735.509829] PM: noirq suspend of devices complete after 95.815 msecs
Jul 18 01:34:47 s9 kernel: [57735.510162] ACPI: Preparing to enter system sleep state S3
Jul 18 01:34:47 s9 kernel: [57735.529924] PM: Saving platform NVS memory
Jul 18 01:34:47 s9 kernel: [57735.533712] Disabling non-boot CPUs ...
Jul 18 01:34:47 s9 kernel: [57735.534399] Broke affinity for irq 49
Jul 18 01:34:47 s9 kernel: [57735.637654] CPU 1 is now offline
Jul 18 01:34:47 s9 kernel: [57735.741579] CPU 2 is now offline
Jul 18 01:34:47 s9 kernel: [57735.742343] Broke affinity for irq 9
Jul 18 01:34:47 s9 kernel: [57735.742362] Broke affinity for irq 16
Jul 18 01:34:47 s9 kernel: [57735.845486] CPU 3 is now offline
Jul 18 01:34:47 s9 kernel: [57735.845973] Extended CMOS year: 2000
Jul 18 01:34:47 s9 kernel: [57735.847433] ACPI: Low-level resume complete
Jul 18 01:34:47 s9 kernel: [57735.847480] PM: Restoring platform NVS memory
Jul 18 01:34:47 s9 kernel: [57735.848340] Extended CMOS year: 2000
Jul 18 01:34:47 s9 kernel: [57735.848387] Enabling non-boot CPUs ...
Jul 18 01:34:47 s9 kernel: [57735.848486] Booting Node 0 Processor 1 APIC 0x1
Jul 18 01:34:47 s9 kernel: [57762.066656] BUG: soft lockup - CPU#0 stuck for 23s! [pm-suspend:5522]
Jul 18 01:34:47 s9 kernel: [57762.066656] Modules linked in: msr uas usb_storage easy_slow_down_manager(O) ip6table_filter ip6_tables ebtable_nat ebtables dm_crypt ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT snd_hda_codec_hdmi snd_hda_codec_realtek xt_CHECKSUM iptable_mangle xt_tcpudp iptable_filter ip_tables x_tables bridge stp llc arc4 bnep rfcomm snd_hda_intel parport_pc snd_hda_codec ppdev snd_hwdep snd_pcm samsung_laptop kvm_intel kvm snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq microcode snd_timer snd_seq_device iwlwifi psmouse serio_raw mac80211 snd lpc_ich cfg80211 btusb bluetooth soundcore snd_page_alloc binfmt_misc mei mac_hid coretemp lp parport ghash_clmulni_intel aesni_intel cryptd aes_x86_64 i915 drm_kms_helper r8169 drm i2c_algo_bit wmi video [last unloaded: videobuf2_memops]
Jul 18 01:34:47 s9 kernel: [57762.066701] CPU 0 Modules linked in: msr uas usb_storage easy_slow_down_manager(O) ip6table_filter ip6_tables ebtable_nat ebtables dm_crypt ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT snd_hda_codec_hdmi snd_hda_codec_realtek xt_CHECKSUM iptable_mangle xt_tcpudp iptable_filter ip_tables x_tables bridge stp llc arc4 bnep rfcomm snd_hda_intel parport_pc snd_hda_codec ppdev snd_hwdep snd_pcm samsung_laptop kvm_intel kvm snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq microcode snd_timer snd_seq_device iwlwifi psmouse serio_raw mac80211 snd lpc_ich cfg80211 btusb bluetooth soundcore snd_page_alloc binfmt_misc mei mac_hid coretemp lp parport ghash_clmulni_intel aesni_intel cryptd aes_x86_64 i915 drm_kms_helper r8169 drm i2c_algo_bit wmi video [last unloaded: videobuf2_memops]
Jul 18 01:34:47 s9 kernel: [57762.066738]
Jul 18 01:34:47 s9 kernel: [57762.066741] Pid: 5522, comm: pm-suspend Tainted: G O 3.5.0-030500rc7-generic #201207142035 SAMSUNG ELECTRONICS CO., LTD. 90X3A/90X3A
Jul 18 01:34:47 s9 kernel: [57762.066744] RIP: 0010:[<ffffffff8101c2a6>] [<ffffffff8101c2a6>] native_read_tsc+0x6/0x20
Jul 18 01:34:47 s9 kernel: [57762.066752] RSP: 0018:ffff88010085fc48 EFLAGS: 00000246
Jul 18 01:34:47 s9 kernel: [57762.066753] RAX: 00000000172b9518 RBX: ffffffff81053dbe RCX: 00000000172b94dc
Jul 18 01:34:47 s9 kernel: [57762.066754] RDX: 0000000000000000 RSI: 000000000000c500 RDI: 00000000001829e6
Jul 18 01:34:47 s9 kernel: [57762.066755] RBP: ffff88010085fc48 R08: 0000000000000078 R09: 0000000000000007
Jul 18 01:34:47 s9 kernel: [57762.066756] R10: ffffffff81e213df R11: 2043495041203120 R12: 0000000000000000
Jul 18 01:34:47 s9 kernel: [57762.066757] R13: 0000000000000000 R14: ffffffff81053d0c R15: ffff88010085fbb8
Jul 18 01:34:47 s9 kernel: [57762.066758] FS: 00007f7aad3bb700(0000) GS:ffff88024fa00000(0000) knlGS:0000000000000000
Jul 18 01:34:47 s9 kernel: [57762.066759] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Jul 18 01:34:47 s9 kernel: [57762.066760] CR2: 00007fcaa3db0fb0 CR3: 0000000134b90000 CR4: 00000000000407f0
Jul 18 01:34:47 s9 kernel: [57762.066761] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jul 18 01:34:47 s9 kernel: [57762.066762] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jul 18 01:34:47 s9 kernel: [57762.066764] Process pm-suspend (pid: 5522, threadinfo ffff88010085e000, task ffff880162b81700)
Jul 18 01:34:47 s9 kernel: [57762.066765] Stack:
Jul 18 01:34:47 s9 kernel: [57762.066766] ffff88010085fc78 ffffffff8132da0a 0000000000000001 0000000000000006
Jul 18 01:34:47 s9 kernel: [57762.066769] 0000000000098000 0000000000000007 ffff88010085fc88 ffffffff8132d93c
Jul 18 01:34:47 s9 kernel: [57762.066771] ffff88010085fcd8 ffffffff8166781d 0000000000000001 0000000000000001
Jul 18 01:34:47 s9 kernel: [57762.066774] Call Trace:
Jul 18 01:34:47 s9 kernel: [57762.066775] [<ffffffff8132da0a>] delay_tsc+0x4a/0x80
Jul 18 01:34:47 s9 kernel: [57762.066780] [<ffffffff8132d93c>] __const_udelay+0x2c/0x30
Jul 18 01:34:47 s9 kernel: [57762.066783] [<ffffffff8166781d>] wakeup_secondary_cpu_via_init+0x7c/0x1cb
Jul 18 01:34:47 s9 kernel: [57762.066788] [<ffffffff81667b05>] do_boot_cpu+0x199/0x2fa
Jul 18 01:34:47 s9 kernel: [57762.066791] [<ffffffff816684d8>] native_cpu_up+0xc7/0x119
Jul 18 01:34:47 s9 kernel: [57762.066793] [<ffffffff816699a7>] _cpu_up+0xb1/0x125
Jul 18 01:34:47 s9 kernel: [57762.066797] [<ffffffff8165004a>] enable_nonboot_cpus+0xaa/0xe0
Jul 18 01:34:47 s9 kernel: [57762.066801] [<ffffffff8109b485>] suspend_enter+0x115/0x170
Jul 18 01:34:47 s9 kernel: [57762.066804] [<ffffffff8109b59b>] suspend_devices_and_enter+0xbb/0x1f0
Jul 18 01:34:47 s9 kernel: [57762.066807] [<ffffffff8109b7a1>] enter_state+0xd1/0x100
Jul 18 01:34:47 s9 kernel: [57762.066809] [<ffffffff8109b7eb>] pm_suspend+0x1b/0x60
Jul 18 01:34:47 s9 kernel: [57762.066811] [<ffffffff8109a855>] state_store+0x45/0x70
Jul 18 01:34:47 s9 kernel: [57762.066813] [<ffffffff8132313f>] kobj_attr_store+0xf/0x30
Jul 18 01:34:47 s9 kernel: [57762.066816] [<ffffffff811f682f>] sysfs_write_file+0xef/0x170
Jul 18 01:34:47 s9 kernel: [57762.066821] [<ffffffff811872f3>] vfs_write+0xb3/0x180
Jul 18 01:34:47 s9 kernel: [57762.066825] [<ffffffff8118761a>] sys_write+0x4a/0x90
Jul 18 01:34:47 s9 kernel: [57762.066827] [<ffffffff81693ce9>] system_call_fastpath+0x16/0x1b
Jul 18 01:34:47 s9 kernel: [57762.066832] Code: c3 90 90 90 90 55 89 f8 48 89 e5 e6 70 e4 71 5d c3 0f 1f 40 00 55 89 f0 48 89 e5 e6 70 89 f8 e6 71 5d c3 66 90 55 48 89 e5 0f 31 <89> c1 48 89 d0 48 c1 e0 20 48 09 c8 5d c3 66 66 66 2e 0f 1f 84
Jul 18 01:34:47 s9 kernel: [57786.047357] BUG: soft lockup - CPU#0 stuck for 22s! [pm-suspend:5522]
Jul 18 01:34:47 s9 kernel: [57786.047357] Modules linked in: msr uas usb_storage easy_slow_down_manager(O) ip6table_filter ip6_tables ebtable_nat ebtables dm_crypt ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT snd_hda_codec_hdmi snd_hda_codec_realtek xt_CHECKSUM iptable_mangle xt_tcpudp iptable_filter ip_tables x_tables bridge stp llc arc4 bnep rfcomm snd_hda_intel parport_pc snd_hda_codec ppdev snd_hwdep snd_pcm samsung_laptop kvm_intel kvm snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq microcode snd_timer snd_seq_device iwlwifi psmouse serio_raw mac80211 snd lpc_ich cfg80211 btusb bluetooth soundcore snd_page_alloc binfmt_misc mei mac_hid coretemp lp parport ghash_clmulni_intel aesni_intel cryptd aes_x86_64 i915 drm_kms_helper r8169 drm i2c_algo_bit wmi video [last unloaded: videobuf2_memops]
Jul 18 01:34:47 s9 kernel: [57786.047393] CPU 0 Modules linked in: msr uas usb_storage easy_slow_down_manager(O) ip6table_filter ip6_tables ebtable_nat ebtables dm_crypt ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT snd_hda_codec_hdmi snd_hda_codec_realtek xt_CHECKSUM iptable_mangle xt_tcpudp iptable_filter ip_tables x_tables bridge stp llc arc4 bnep rfcomm snd_hda_intel parport_pc snd_hda_codec ppdev snd_hwdep snd_pcm samsung_laptop kvm_intel kvm snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq microcode snd_timer snd_seq_device iwlwifi psmouse serio_raw mac80211 snd lpc_ich cfg80211 btusb bluetooth soundcore snd_page_alloc binfmt_misc mei mac_hid coretemp lp parport ghash_clmulni_intel aesni_intel cryptd aes_x86_64 i915 drm_kms_helper r8169 drm i2c_algo_bit wmi video [last unloaded: videobuf2_memops]
Jul 18 01:34:47 s9 kernel: [57786.047428]
Jul 18 01:34:47 s9 kernel: [57786.047430] Pid: 5522, comm: pm-suspend Tainted: G O 3.5.0-030500rc7-generic #201207142035 SAMSUNG ELECTRONICS CO., LTD. 90X3A/90X3A
Jul 18 01:34:47 s9 kernel: [57786.047432] RIP: 0010:[<ffffffff8101c2a6>] [<ffffffff8101c2a6>] native_read_tsc+0x6/0x20
Jul 18 01:34:47 s9 kernel: [57786.047435] RSP: 0018:ffff88010085fc48 EFLAGS: 00000246
Jul 18 01:34:47 s9 kernel: [57786.047436] RAX: 00000000172b9518 RBX: ffffffff81053dbe RCX: 00000000172b94dc
Jul 18 01:34:47 s9 kernel: [57786.047437] RDX: 0000000000000000 RSI: 000000000000c500 RDI: 00000000001829e6
Jul 18 01:34:47 s9 kernel: [57786.047438] RBP: ffff88010085fc48 R08: 0000000000000078 R09: 0000000000000007
Jul 18 01:34:47 s9 kernel: [57786.047439] R10: ffffffff81e213df R11: 2043495041203120 R12: 0000000000000000
Jul 18 01:34:47 s9 kernel: [57786.047440] R13: 0000000000000000 R14: ffffffff81053d0c R15: ffff88010085fbb8
Jul 18 01:34:47 s9 kernel: [57786.047441] FS: 00007f7aad3bb700(0000) GS:ffff88024fa00000(0000) knlGS:0000000000000000
Jul 18 01:34:47 s9 kernel: [57786.047442] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Jul 18 01:34:47 s9 kernel: [57786.047443] CR2: 00007fcaa3db0fb0 CR3: 0000000134b90000 CR4: 00000000000407f0
Jul 18 01:34:47 s9 kernel: [57786.047444] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jul 18 01:34:47 s9 kernel: [57786.047445] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jul 18 01:34:47 s9 kernel: [57786.047446] Process pm-suspend (pid: 5522, threadinfo ffff88010085e000, task ffff880162b81700)
Jul 18 01:34:47 s9 kernel: [57786.047446] Stack:
Jul 18 01:34:47 s9 kernel: [57786.047447] ffff88010085fc78 ffffffff8132da0a 0000000000000001 0000000000000006
Jul 18 01:34:47 s9 kernel: [57786.047449] 0000000000098000 0000000000000007 ffff88010085fc88 ffffffff8132d93c
Jul 18 01:34:47 s9 kernel: [57786.047452] ffff88010085fcd8 ffffffff8166781d 0000000000000001 0000000000000001
Jul 18 01:34:47 s9 kernel: [57786.047454] Call Trace:
Jul 18 01:34:47 s9 kernel: [57786.047455] [<ffffffff8132da0a>] delay_tsc+0x4a/0x80
Jul 18 01:34:47 s9 kernel: [57786.047457] [<ffffffff8132d93c>] __const_udelay+0x2c/0x30
Jul 18 01:34:47 s9 kernel: [57786.047459] [<ffffffff8166781d>] wakeup_secondary_cpu_via_init+0x7c/0x1cb
Jul 18 01:34:47 s9 kernel: [57786.047462] [<ffffffff81667b05>] do_boot_cpu+0x199/0x2fa
Jul 18 01:34:47 s9 kernel: [57786.047465] [<ffffffff816684d8>] native_cpu_up+0xc7/0x119
Jul 18 01:34:47 s9 kernel: [57786.047467] [<ffffffff816699a7>] _cpu_up+0xb1/0x125
Jul 18 01:34:47 s9 kernel: [57786.047470] [<ffffffff8165004a>] enable_nonboot_cpus+0xaa/0xe0
Jul 18 01:34:47 s9 kernel: [57786.047473] [<ffffffff8109b485>] suspend_enter+0x115/0x170
Jul 18 01:34:47 s9 kernel: [57786.047475] [<ffffffff8109b59b>] suspend_devices_and_enter+0xbb/0x1f0
Jul 18 01:34:47 s9 kernel: [57786.047478] [<ffffffff8109b7a1>] enter_state+0xd1/0x100
Jul 18 01:34:47 s9 kernel: [57786.047479] [<ffffffff8109b7eb>] pm_suspend+0x1b/0x60
Jul 18 01:34:47 s9 kernel: [57786.047481] [<ffffffff8109a855>] state_store+0x45/0x70
Jul 18 01:34:47 s9 kernel: [57786.047483] [<ffffffff8132313f>] kobj_attr_store+0xf/0x30
Jul 18 01:34:47 s9 kernel: [57786.047486] [<ffffffff811f682f>] sysfs_write_file+0xef/0x170
Jul 18 01:34:47 s9 kernel: [57786.047489] [<ffffffff811872f3>] vfs_write+0xb3/0x180
Jul 18 01:34:47 s9 kernel: [57786.047491] [<ffffffff8118761a>] sys_write+0x4a/0x90
Jul 18 01:34:47 s9 kernel: [57786.047494] [<ffffffff81693ce9>] system_call_fastpath+0x16/0x1b
Jul 18 01:34:47 s9 kernel: [57786.047497] Code: c3 90 90 90 90 55 89 f8 48 89 e5 e6 70 e4 71 5d c3 0f 1f 40 00 55 89 f0 48 89 e5 e6 70 89 f8 e6 71 5d c3 66 90 55 48 89 e5 0f 31 <89> c1 48 89 d0 48 c1 e0 20 48 09 c8 5d c3 66 66 66 2e 0f 1f 84
Jul 18 01:34:47 s9 kernel: [57795.799508] INFO: rcu_sched detected stalls on CPUs/tasks: { 3} (detected by 0, t=15002 jiffies)
Jul 18 01:34:47 s9 kernel: [57795.799513] INFO: Stall ended before state dump start
Jul 18 01:34:47 s9 kernel: [57810.028058] BUG: soft lockup - CPU#0 stuck for 22s! [pm-suspend:5522]
Jul 18 01:34:47 s9 kernel: [57810.028059] Modules linked in: msr uas usb_storage easy_slow_down_manager(O) ip6table_filter ip6_tables ebtable_nat ebtables dm_crypt ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT snd_hda_codec_hdmi snd_hda_codec_realtek xt_CHECKSUM iptable_mangle xt_tcpudp iptable_filter ip_tables x_tables bridge stp llc arc4 bnep rfcomm snd_hda_intel parport_pc snd_hda_codec ppdev snd_hwdep snd_pcm samsung_laptop kvm_intel kvm snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq microcode snd_timer snd_seq_device iwlwifi psmouse serio_raw mac80211 snd lpc_ich cfg80211 btusb bluetooth soundcore snd_page_alloc binfmt_misc mei mac_hid coretemp lp parport ghash_clmulni_intel aesni_intel cryptd aes_x86_64 i915 drm_kms_helper r8169 drm i2c_algo_bit wmi video [last unloaded: videobuf2_memops]
Jul 18 01:34:47 s9 kernel: [57810.028094] CPU 0 Modules linked in: msr uas usb_storage easy_slow_down_manager(O) ip6table_filter ip6_tables ebtable_nat ebtables dm_crypt ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_defrag_ipv4 xt_state nf_conntrack ipt_REJECT snd_hda_codec_hdmi snd_hda_codec_realtek xt_CHECKSUM iptable_mangle xt_tcpudp iptable_filter ip_tables x_tables bridge stp llc arc4 bnep rfcomm snd_hda_intel parport_pc snd_hda_codec ppdev snd_hwdep snd_pcm samsung_laptop kvm_intel kvm snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq microcode snd_timer snd_seq_device iwlwifi psmouse serio_raw mac80211 snd lpc_ich cfg80211 btusb bluetooth soundcore snd_page_alloc binfmt_misc mei mac_hid coretemp lp parport ghash_clmulni_intel aesni_intel cryptd aes_x86_64 i915 drm_kms_helper r8169 drm i2c_algo_bit wmi video [last unloaded: videobuf2_memops]
Jul 18 01:34:47 s9 kernel: [57810.028129]
Jul 18 01:34:47 s9 kernel: [57810.028130] Pid: 5522, comm: pm-suspend Tainted: G O 3.5.0-030500rc7-generic #201207142035 SAMSUNG ELECTRONICS CO., LTD. 90X3A/90X3A
Jul 18 01:34:47 s9 kernel: [57810.028132] RIP: 0010:[<ffffffff8101c2a6>] [<ffffffff8101c2a6>] native_read_tsc+0x6/0x20
Jul 18 01:34:47 s9 kernel: [57810.028135] RSP: 0018:ffff88010085fc48 EFLAGS: 00000246
Jul 18 01:34:47 s9 kernel: [57810.028136] RAX: 00000000172b9518 RBX: ffffffff81053dbe RCX: 00000000172b94dc
Jul 18 01:34:47 s9 kernel: [57810.028137] RDX: 0000000000000000 RSI: 000000000000c500 RDI: 00000000001829e6
Jul 18 01:34:47 s9 kernel: [57810.028138] RBP: ffff88010085fc48 R08: 0000000000000078 R09: 0000000000000007
Jul 18 01:34:47 s9 kernel: [57810.028138] R10: ffffffff81e213df R11: 2043495041203120 R12: 0000000000000000
Jul 18 01:34:47 s9 kernel: [57810.028139] R13: 0000000000000000 R14: ffffffff81053d0c R15: ffff88010085fbb8
Jul 18 01:34:47 s9 kernel: [57810.028140] FS: 00007f7aad3bb700(0000) GS:ffff88024fa00000(0000) knlGS:0000000000000000
Jul 18 01:34:47 s9 kernel: [57810.028141] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b
Jul 18 01:34:47 s9 kernel: [57810.028142] CR2: 00007fcaa3db0fb0 CR3: 0000000134b90000 CR4: 00000000000407f0
Jul 18 01:34:47 s9 kernel: [57810.028143] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
Jul 18 01:34:47 s9 kernel: [57810.028144] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400
Jul 18 01:34:47 s9 kernel: [57810.028145] Process pm-suspend (pid: 5522, threadinfo ffff88010085e000, task ffff880162b81700)
Jul 18 01:34:47 s9 kernel: [57810.028145] Stack:
Jul 18 01:34:47 s9 kernel: [57810.028146] ffff88010085fc78 ffffffff8132da0a 0000000000000001 0000000000000006
Jul 18 01:34:47 s9 kernel: [57810.028148] 0000000000098000 0000000000000007 ffff88010085fc88 ffffffff8132d93c
Jul 18 01:34:47 s9 kernel: [57810.028151] ffff88010085fcd8 ffffffff8166781d 0000000000000001 0000000000000001
Jul 18 01:34:47 s9 kernel: [57810.028153] Call Trace:
Jul 18 01:34:47 s9 kernel: [57810.028154] [<ffffffff8132da0a>] delay_tsc+0x4a/0x80
Jul 18 01:34:47 s9 kernel: [57810.028156] [<ffffffff8132d93c>] __const_udelay+0x2c/0x30
Jul 18 01:34:47 s9 kernel: [57810.028158] [<ffffffff8166781d>] wakeup_secondary_cpu_via_init+0x7c/0x1cb
Jul 18 01:34:47 s9 kernel: [57810.028161] [<ffffffff81667b05>] do_boot_cpu+0x199/0x2fa
Jul 18 01:34:47 s9 kernel: [57810.028164] [<ffffffff816684d8>] native_cpu_up+0xc7/0x119
Jul 18 01:34:47 s9 kernel: [57810.028166] [<ffffffff816699a7>] _cpu_up+0xb1/0x125
Jul 18 01:34:47 s9 kernel: [57810.028169] [<ffffffff8165004a>] enable_nonboot_cpus+0xaa/0xe0
Jul 18 01:34:47 s9 kernel: [57810.028172] [<ffffffff8109b485>] suspend_enter+0x115/0x170
Jul 18 01:34:47 s9 kernel: [57810.028174] [<ffffffff8109b59b>] suspend_devices_and_enter+0xbb/0x1f0
Jul 18 01:34:47 s9 kernel: [57810.028176] [<ffffffff8109b7a1>] enter_state+0xd1/0x100
Jul 18 01:34:47 s9 kernel: [57810.028178] [<ffffffff8109b7eb>] pm_suspend+0x1b/0x60
Jul 18 01:34:47 s9 kernel: [57810.028180] [<ffffffff8109a855>] state_store+0x45/0x70
Jul 18 01:34:47 s9 kernel: [57810.028182] [<ffffffff8132313f>] kobj_attr_store+0xf/0x30
Jul 18 01:34:47 s9 kernel: [57810.028184] [<ffffffff811f682f>] sysfs_write_file+0xef/0x170
Jul 18 01:34:47 s9 kernel: [57810.028187] [<ffffffff811872f3>] vfs_write+0xb3/0x180
Jul 18 01:34:47 s9 kernel: [57810.028189] [<ffffffff8118761a>] sys_write+0x4a/0x90
Jul 18 01:34:47 s9 kernel: [57810.028192] [<ffffffff81693ce9>] system_call_fastpath+0x16/0x1b
Jul 18 01:34:47 s9 kernel: [57810.028195] Code: c3 90 90 90 90 55 89 f8 48 89 e5 e6 70 e4 71 5d c3 0f 1f 40 00 55 89 f0 48 89 e5 e6 70 89 f8 e6 71 5d c3 66 90 55 48 89 e5 0f 31 <89> c1 48 89 d0 48 c1 e0 20 48 09 c8 5d c3 66 66 66 2e 0f 1f 84
Jul 18 01:34:47 s9 kernel: [57814.643955] Disabled fast string operations
Jul 18 01:34:47 s9 kernel: [57814.644349] [sched_delayed] sched: RT throttling activated
Jul 18 01:34:47 s9 kernel: [57814.647282] CPU1 is up
Jul 18 01:34:47 s9 kernel: [57814.647382] Booting Node 0 Processor 2 APIC 0x2
Jul 18 01:34:47 s9 kernel: [57814.658426] Disabled fast string operations
Jul 18 01:34:47 s9 kernel: [57814.661061] CPU2 is up
Jul 18 01:34:47 s9 kernel: [57814.661216] Booting Node 0 Processor 3 APIC 0x3
Jul 18 01:34:47 s9 kernel: [57814.672251] Disabled fast string operations
Jul 18 01:34:47 s9 kernel: [57814.675095] CPU3 is up
Jul 18 01:34:47 s9 kernel: [57814.679342] ACPI: Waking up from system sleep state S3
Jul 18 01:34:47 s9 kernel: [57814.769664] xhci_hcd 0000:03:00.0: wake-up capability disabled by ACPI
Jul 18 01:34:47 s9 kernel: [57814.769730] PM: noirq resume of devices complete after 0.996 msecs
Jul 18 01:34:47 s9 kernel: [57814.769826] PM: early resume of devices complete after 0.054 msecs
Jul 18 01:34:47 s9 kernel: [57814.769857] i915 0000:00:02.0: setting latency timer to 64
Jul 18 01:34:47 s9 kernel: [57814.769877] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Jul 18 01:34:47 s9 kernel: [57814.769923] mei 0000:00:16.0: irq 48 for MSI/MSI-X
Jul 18 01:34:47 s9 kernel: [57814.769985] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Jul 18 01:34:47 s9 kernel: [57814.769990] ahci 0000:00:1f.2: setting latency timer to 64
Jul 18 01:34:47 s9 kernel: [57814.769991] snd_hda_intel 0000:00:1b.0: irq 50 for MSI/MSI-X
Jul 18 01:34:47 s9 kernel: [57814.770027] iwlwifi 0000:01:00.0: RF_KILL bit toggled to enable radio.
Jul 18 01:34:47 s9 kernel: [57814.770081] usb usb3: root hub lost power or was reset
Jul 18 01:34:47 s9 kernel: [57814.770083] usb usb4: root hub lost power or was reset
Jul 18 01:34:47 s9 kernel: [57814.770086] r8169 0000:02:00.0: wake-up capability disabled by ACPI
Jul 18 01:34:47 s9 kernel: [57814.770601] xhci_hcd 0000:03:00.0: irq 41 for MSI/MSI-X
Jul 18 01:34:47 s9 kernel: [57814.770609] xhci_hcd 0000:03:00.0: irq 42 for MSI/MSI-X
Jul 18 01:34:47 s9 kernel: [57814.770617] xhci_hcd 0000:03:00.0: irq 43 for MSI/MSI-X
Jul 18 01:34:47 s9 kernel: [57814.770624] xhci_hcd 0000:03:00.0: irq 44 for MSI/MSI-X
Jul 18 01:34:47 s9 kernel: [57814.770632] xhci_hcd 0000:03:00.0: irq 45 for MSI/MSI-X
Jul 18 01:34:47 s9 kernel: [57814.777969] mei 0000:00:16.0: wd: failed to find the client
Jul 18 01:34:47 s9 kernel: [57814.812835] [drm] Enabling RC6 states: RC6 on, RC6p off, RC6pp off
Jul 18 01:34:47 s9 kernel: [57815.020325] usb 1-1.4: reset high-speed USB device number 4 using ehci_hcd
Jul 18 01:34:47 s9 kernel: [57815.088094] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Jul 18 01:34:47 s9 kernel: [57815.096043] ata1.00: configured for UDMA/133
Jul 18 01:34:47 s9 kernel: [57815.096070] ata4: SATA link down (SStatus 0 SControl 300)
Jul 18 01:34:47 s9 kernel: [57815.104093] ata5: SATA link down (SStatus 0 SControl 300)
Jul 18 01:34:47 s9 kernel: [57815.112177] sd 0:0:0:0: [sda] Starting disk
Jul 18 01:34:47 s9 kernel: [57815.124402] Extended CMOS year: 2000
Jul 18 01:34:47 s9 kernel: [57815.671994] PM: resume of devices complete after 902.890 msecs
Jul 18 01:34:47 s9 kernel: [57815.672089] PM: resume devices took 0.904 seconds
Jul 18 01:34:47 s9 kernel: [57815.672368] PM: Finishing wakeup.
Jul 18 01:34:47 s9 kernel: [57815.672370] Restarting tasks ... done.
Jul 18 01:34:47 s9 kernel: [57815.723496] video LNXVIDEO:00: Restoring backlight state
Jul 18 01:34:50 s9 kernel: [57817.926244] iwlwifi 0000:01:00.0: L1 Disabled; Enabling L0S
Jul 18 01:34:50 s9 kernel: [57817.933344] iwlwifi 0000:01:00.0: Radio type=0x0-0x3-0x1
Jul 18 01:34:50 s9 kernel: [57818.057158] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 18 01:34:50 s9 kernel: [57818.162539] r8169 0000:02:00.0: eth0: link down
Jul 18 01:34:50 s9 kernel: [57818.164080] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Jul 18 01:34:59 s9 kernel: [57826.890665] wlan0: authenticate with 00:27:22:44:ee:4f
Jul 18 01:34:59 s9 kernel: [57826.914877] wlan0: send auth to 00:27:22:44:ee:4f (try 1/3)
Jul 18 01:34:59 s9 kernel: [57826.916329] wlan0: authenticated
Jul 18 01:34:59 s9 kernel: [57826.918532] wlan0: associate with 00:27:22:44:ee:4f (try 1/3)
Jul 18 01:34:59 s9 kernel: [57826.921970] wlan0: RX AssocResp from 00:27:22:44:ee:4f (capab=0x431 status=0 aid=5)
Jul 18 01:34:59 s9 kernel: [57826.921982] wlan0: associated
Jul 18 01:34:59 s9 kernel: [57826.929627] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
--
Tomasz Chmielewski
http://www.ptraveler.com
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: 3.5-rc7 - can no longer wake up from suspend to RAM
2012-07-19 4:46 3.5-rc7 - can no longer wake up from suspend to RAM Tomasz Chmielewski
@ 2012-07-21 22:11 ` Roland Dreier
2012-07-22 2:07 ` Hugh Dickins
0 siblings, 1 reply; 5+ messages in thread
From: Roland Dreier @ 2012-07-21 22:11 UTC (permalink / raw)
To: Tomasz Chmielewski; +Cc: linux-kernel
On Wed, Jul 18, 2012 at 9:46 PM, Tomasz Chmielewski <tch@wpkg.org> wrote:
> After upgrading to 3.5-rc7, my laptop no longer wakes up reliable from suspend to RAM. 3.4.x worked fine.
FWIW, I've been having similar problems with 3.5-rc7. With 3.5-rc6 my
laptop resumed fine, but since updating to -rc7, it often seems to just
sit there after opening the lid -- the moon/sleep LED stays on, and the
power LED smoothly cycles on and off, as if it's fast asleep. Even pressing
the power button doesn't have any effect (until I hold down the power
button long enough to turn off).
I guess I'll start a bisection, but it's slow going because it takes multiple
tries to know for sure if a kernel is bad.
The only commit between rc6 and rc7 that looks like it might be related
is dc332fdf9f373a87b1e2f423b5b004b2a3c37e1a ("ACPI / PM: Leave
Bus Master Arbitration enabled for suspend/resume"), which apparently
fixes some other laptops. But perhaps I'll try reverting that and see how
it goes.
- R.
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: 3.5-rc7 - can no longer wake up from suspend to RAM
2012-07-21 22:11 ` Roland Dreier
@ 2012-07-22 2:07 ` Hugh Dickins
2012-07-22 5:48 ` Roland Dreier
0 siblings, 1 reply; 5+ messages in thread
From: Hugh Dickins @ 2012-07-22 2:07 UTC (permalink / raw)
To: Roland Dreier; +Cc: Tomasz Chmielewski, linux-kernel
On Sat, 21 Jul 2012, Roland Dreier wrote:
> On Wed, Jul 18, 2012 at 9:46 PM, Tomasz Chmielewski <tch@wpkg.org> wrote:
> > After upgrading to 3.5-rc7, my laptop no longer wakes up reliable from suspend to RAM. 3.4.x worked fine.
>
> FWIW, I've been having similar problems with 3.5-rc7. With 3.5-rc6 my
> laptop resumed fine, but since updating to -rc7, it often seems to just
> sit there after opening the lid -- the moon/sleep LED stays on, and the
> power LED smoothly cycles on and off, as if it's fast asleep. Even pressing
> the power button doesn't have any effect (until I hold down the power
> button long enough to turn off).
>
> I guess I'll start a bisection, but it's slow going because it takes multiple
> tries to know for sure if a kernel is bad.
>
> The only commit between rc6 and rc7 that looks like it might be related
> is dc332fdf9f373a87b1e2f423b5b004b2a3c37e1a ("ACPI / PM: Leave
> Bus Master Arbitration enabled for suspend/resume"), which apparently
> fixes some other laptops. But perhaps I'll try reverting that and see how
> it goes.
Interrupt your bisection to try this from 3.5 final:
commit 3e997130bd2e8c6f5aaa49d6e3161d4d29b43ab0
Author: Thomas Gleixner <tglx@linutronix.de>
Date: Mon Jul 16 12:50:42 2012 -0400
timekeeping: Add missing update call in timekeeping_resume()
The leap second rework unearthed another issue of inconsistent data.
On timekeeping_resume() the timekeeper data is updated, but nothing
calls timekeeping_update(), so now the update code in the timer
interrupt sees stale values.
This has been the case before those changes, but then the timer
interrupt was using stale data as well so this went unnoticed for quite
some time.
Add the missing update call, so all the data is consistent everywhere.
Reported-by: Andreas Schwab <schwab@linux-m68k.org>
Reported-and-tested-by: "Rafael J. Wysocki" <rjw@sisk.pl>
Reported-and-tested-by: Martin Steigerwald <Martin@lichtvoll.de>
Cc: LKML <linux-kernel@vger.kernel.org>
Cc: Linux PM list <linux-pm@vger.kernel.org>
Cc: John Stultz <johnstul@us.ibm.com>
Cc: Ingo Molnar <mingo@kernel.org>
Cc: Peter Zijlstra <a.p.zijlstra@chello.nl>,
Cc: Prarit Bhargava <prarit@redhat.com>
Cc: stable@vger.kernel.org
Signed-off-by: Thomas Gleixner <tglx@linutronix.de>
Signed-off-by: John Stultz <johnstul@us.ibm.com>
Signed-off-by: Linus Torvalds <torvalds@linux-foundation.org>
diff --git a/kernel/time/timekeeping.c b/kernel/time/timekeeping.c
index 269b1fe..3447cfa 100644
--- a/kernel/time/timekeeping.c
+++ b/kernel/time/timekeeping.c
@@ -717,6 +717,7 @@ static void timekeeping_resume(void)
timekeeper.clock->cycle_last = timekeeper.clock->read(timekeeper.clock);
timekeeper.ntp_error = 0;
timekeeping_suspended = 0;
+ timekeeping_update(false);
write_sequnlock_irqrestore(&timekeeper.lock, flags);
touch_softlockup_watchdog();
^ permalink raw reply related [flat|nested] 5+ messages in thread
* Re: 3.5-rc7 - can no longer wake up from suspend to RAM
2012-07-22 2:07 ` Hugh Dickins
@ 2012-07-22 5:48 ` Roland Dreier
2012-07-22 5:51 ` Tomasz Chmielewski
0 siblings, 1 reply; 5+ messages in thread
From: Roland Dreier @ 2012-07-22 5:48 UTC (permalink / raw)
To: Hugh Dickins; +Cc: Tomasz Chmielewski, linux-kernel
Thanks Hugh. I just went ahead and built 3.5 final, and suspend/resume
look to be working again.
I'm not even going to try to understand how a timekeeping bug broke resume...
- R.
^ permalink raw reply [flat|nested] 5+ messages in thread
* Re: 3.5-rc7 - can no longer wake up from suspend to RAM
2012-07-22 5:48 ` Roland Dreier
@ 2012-07-22 5:51 ` Tomasz Chmielewski
0 siblings, 0 replies; 5+ messages in thread
From: Tomasz Chmielewski @ 2012-07-22 5:51 UTC (permalink / raw)
To: Roland Dreier; +Cc: Hugh Dickins, linux-kernel
On 07/22/2012 12:48 PM, Roland Dreier wrote:
> Thanks Hugh. I just went ahead and built 3.5 final, and suspend/resume
> look to be working again.
>
> I'm not even going to try to understand how a timekeeping bug broke resume...
Yep, seems to be working fine here, too.
--
Tomasz Chmielewski
http://www.ptraveler.com
^ permalink raw reply [flat|nested] 5+ messages in thread
end of thread, other threads:[~2012-07-22 5:52 UTC | newest]
Thread overview: 5+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2012-07-19 4:46 3.5-rc7 - can no longer wake up from suspend to RAM Tomasz Chmielewski
2012-07-21 22:11 ` Roland Dreier
2012-07-22 2:07 ` Hugh Dickins
2012-07-22 5:48 ` Roland Dreier
2012-07-22 5:51 ` Tomasz Chmielewski
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).