| Summary: | [abrt] kernel: WARNING: at kernel/power/suspend_test.c:53 suspend_test_finish+0x8c/0x90() | ||
|---|---|---|---|
| Product: | [Fedora] Fedora | Reporter: | Jaroslaw Gorny <jaroslaw.gorny> |
| Component: | kernel | Assignee: | Kernel Maintainer List <kernel-maint> |
| Status: | CLOSED INSUFFICIENT_DATA | QA Contact: | Fedora Extras Quality Assurance <extras-qa> |
| Severity: | unspecified | Docs Contact: | |
| Priority: | unspecified | ||
| Version: | 17 | CC: | andrea.calzavacca, gansalmon, itamar, jforbes, jonathan, jreiser, kernel-maint, madhu.chinakonda, mikko.tiihonen |
| Target Milestone: | --- | ||
| Target Release: | --- | ||
| Hardware: | i686 | ||
| OS: | Unspecified | ||
| Whiteboard: | abrt_hash:e849d300b1ae9b8508dc771fd8314a9af033cb85 first=3.3.0-rc3 tested=3.4.0 suspend | ||
| Fixed In Version: | Doc Type: | Bug Fix | |
| Doc Text: | Story Points: | --- | |
| Clone Of: | Environment: | ||
| Last Closed: | 2013-01-02 13:11:46 UTC | Type: | --- |
| Regression: | --- | Mount Type: | --- |
| Documentation: | --- | CRM: | |
| Verified Versions: | Category: | --- | |
| oVirt Team: | --- | RHEL 7.3 requirements from Atomic Host: | |
| Cloudforms Team: | --- | Target Upstream Version: | |
|
Description
Jaroslaw Gorny
2012-02-21 20:44:05 UTC
This translates to:
/* Warning on suspend means the RTC alarm period needs to be
* larger -- the system was sooo slooowwww to suspend that the
* alarm (should have) fired before the system went to sleep!
*
* Warning on either suspend or resume also means the system
* has some performance issues. The stack dump of a WARN_ON
* is more likely to get the right attention than a printk...
*/
WARN(msec > (TEST_SUSPEND_SECONDS * 1000),
"Component: %s, time: %u\n", label, msec);
which means some device took a really long time. Do you have anything in
/var/log/pm-suspend.log? What were you doing at the time the machine suspend?
Nothing interesting in pm-suspend.log Unfortunately it doesn't have timestamps per hook, so I don't know what was actually so sloooow. But you are right.... suspend took ~1 minute to finish. Generally on RC3 kernel, because of debugging (I guess) system is hardly usable for me. What was I doing? Actually, after first boot of the system (install from boot.iso, f17 + updates + updates-testing), I just opened gnome-terminal, installed couple of packages, and decided to try suspend :) I wonder if this was related to the other rcu related slowdowns that people saw. does it happen with the latest builds ? Resume from suspend: move the keyboard (PS/2), which may have triggered a key by static electricity. Package: kernel OS Release: Fedora release 17 (Beefy Miracle) is this still happening on the update kernel ? (there's an rc6 build in testing today) Still happens in 3.3.0-0.rc5.git3.1.fc17.x86_64, I'll go looking for rc6 [grrr Fedora mirror system, which does not yet show rc6 to my "yum update"which includes updates-testing.] [ cut here ]------------ [ 136.948129] WARNING: at kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [ 136.948131] Hardware name: P5Q-PRO [ 136.948132] Component: suspend devices, time: 12811 [ 136.948133] Modules linked in: fuse lockd be2iscsi iscsi_boot_sysfs sunrpc rfcomm bnx2i cnic uio bnep cxgb4i cxgb4 cxgb3i cxgb3 mdio libcxgbi ib_iser rdma_cm ib_addr iw_cm ib_cm ib_sa ib_mad ib_core iscsi_tcp libiscsi_tcp libiscsi scsi_transport_iscsi snd_hda_codec_hdmi snd_hda_codec_realtek btusb snd_hda_intel bluetooth snd_hda_codec snd_hwdep snd_pcm iTCO_wdt rfkill iTCO_vendor_support microcode i2c_i801 serio_raw snd_page_alloc snd_timer snd e1000e atl1e asus_atk0110 soundcore uinput firewire_ohci firewire_core sata_sil24 crc_itu_t nouveau mxm_wmi wmi video drm_kms_helper ttm drm i2c_core [last unloaded: scsi_wait_scan] [ 136.948172] Pid: 1508, comm: pm-suspend Not tainted 3.3.0-0.rc5.git3.1.fc17.x86_64 #1 [ 136.948174] Call Trace: [<ffffffff81060d6f>] warn_slowpath_common+0x7f/0xc0 [<ffffffff81060e66>] warn_slowpath_fmt+0x46/0x50 [<ffffffff810b5176>] suspend_test_finish+0x86/0x90 [<ffffffff810b4a92>] suspend_devices_and_enter+0x92/0x4a0 [<ffffffff810b5036>] enter_state+0x196/0x1d0 [<ffffffff810b356a>] state_store+0xba/0x130 [<ffffffff8131f17f>] kobj_attr_store+0xf/0x20 [<ffffffff81234986>] sysfs_write_file+0xe6/0x160 [<ffffffff811bb33f>] vfs_write+0xaf/0x190 [<ffffffff811bb67d>] sys_write+0x4d/0x90 [<ffffffff816a68e9>] system_call_fastpath+0x16/0x1b [ end trace 9b33e13f457aa219 ]--- Exactly same situation here. On rc5 I've just had the same issue. RC6 not available for me atm. Will test ASAP. you can get the builds directly from koji http://koji.fedoraproject.org/koji/buildinfo?buildID=304743 the process of getting builds into updates is kinda slow right now. kernel-3.3.0-0.rc6.git0.2.fc17.x86_64 fails to get completely into Suspend state. The harddrives do not power down, the DVI monitor does not power down, the fans do not stop. The display has changed to text mode and shows something like "Cannot open font file True." kernel-3.3.0-0.rc5.git3.1.fc17.x86_64 works for Suspend + Resume. See also bug 736839. I go the same warning an a newer kernel. The pm-suspend.log has nothing but success messages. PM: suspend of devices complete after 32979.009 msecs PM: suspend devices took 32.980 seconds WARNING: at kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() Hardware name: 42844DG Component: suspend devices, time: 32980 Modules linked in: usb_storage uas fuse nf_conntrack_netbios_ns nf_conntrack_broadcast ipt_MASQUERADE ip6table_mangle ip6t_REJECT nf_conntrack_ipv6 n f_defrag_ipv6 iptable_nat nf_nat iptable_mangle lockd sunrpc nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack ip6table_filter ip6_tables ebtable_nat ebtables arc4 uvcvideo snd_hda_codec_hd mi videobuf2_vmalloc videobuf2_memops videobuf2_core videodev cdc_acm media cdc_ncm cdc_wdm usbnet mii snd_hda_codec_conexant coretemp microcode i2c_i801 iwlwifi mac80211 iTCO_wdt iTCO_vendor_suppor t snd_hda_intel cfg80211 snd_hda_codec snd_hwdep snd_pcm snd_page_alloc snd_timer e1000e thinkpad_acpi snd soundcore rfkill binfmt_misc vhost_net tun macvtap macvlan kvm_intel kvm uinput btrfs libcr c32c zlib_deflate crc32c_intel ghash_clmulni_intel sdhci_pci firewire_ohci sdhci firewire_core mmc_core crc_itu_t nouveau mxm_wmi wmi video drm_kms_helper ttm drm i2c_core [last unloaded: scsi_wait_ scan] Pid: 16433, comm: pm-suspend Not tainted 3.4.0-0.rc3.git2.1.fc18.x86_64 #1 Call Trace: [<ffffffff8106183f>] warn_slowpath_common+0x7f/0xc0 [<ffffffff81061936>] warn_slowpath_fmt+0x46/0x50 [<ffffffff810b7216>] suspend_test_finish+0x86/0x90 [<ffffffff810b6af2>] suspend_devices_and_enter+0x92/0x4e0 [<ffffffff810b70d4>] pm_suspend+0x194/0x230 [<ffffffff810b5c3a>] state_store+0xba/0xf0 [<ffffffff8132a70f>] kobj_attr_store+0xf/0x20 [<ffffffff8123ad00>] sysfs_write_file+0xe0/0x150 [<ffffffff811bf64f>] vfs_write+0xaf/0x190 [<ffffffff811bf98d>] sys_write+0x4d/0x90 [<ffffffff816c11a9>] system_call_fastpath+0x16/0x1b ---[ end trace e52cecb0e54f33d6 ]--- PM: late suspend of devices complete after 0.931 msecs Is this still happening in 3.6.9? |