Public bug reported:
Binary package hint: pm-utils
Suspend and resume usually works OK after a fresh boot, but after the
computer is used for a a few hours or a program that consumes a lot of
memory was used and closed (such as a virtual machine), resuming starts
taking several minutes. The first time it happens it might "only" take
1-2 minutes to resume. The next time it will be 2-4 minutes. During this
time the hard drive is thrashing constantly. Before suspending, I have
no swap used, and after resuming there is usually at least 1GB of swap
used. It appears that the system swaps everything out to disk upon
resume. Thinkpad T400 with 4GB of RAM. It's been doing this since I
installed Karmic.
I found the following in dmesg from during the suspend process:
[109727.259644] PM: Entering mem sleep
[109727.259656] Suspending console(s) (use no_console_suspend to debug)
[109727.533577] hdaps: setting ec_rate=0, filter_order=1
[109727.550106] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[109727.550225] sd 0:0:0:0: [sda] Stopping disk
[109728.941560] ACPI handle has no context!
[109728.941565] sdhci-pci 0000:15:00.2: PME# disabled
[109728.941572] sdhci-pci 0000:15:00.2: PCI INT C disabled
[109728.941578] ACPI handle has no context!
[109728.965149] ACPI handle has no context!
[109729.000102] [fglrx] Power down the ASIC .
[109729.000199] [fglrx] Preparing suspend fglrx in kernel.
[109729.001665] pm-suspend: page allocation failure. order:10, mode:0x4020
[109729.001667] Pid: 6236, comm: pm-suspend Tainted: P WC
2.6.31-17-generic #54-Ubuntu
[109729.001669] Call Trace:
[109729.001676] [<ffffffff810e091c>] __alloc_pages_slowpath+0x4cc/0x4e0
[109729.001678] [<ffffffff810e0a7e>] __alloc_pages_nodemask+0x14e/0x150
[109729.001681] [<ffffffff8110cf82>] alloc_pages_current+0x82/0xd0
[109729.001683] [<ffffffff810dfa69>] __get_free_pages+0x9/0x50
[109729.001686] [<ffffffff811163b5>] __kmalloc+0x125/0x1d0
[109729.001736] [<ffffffffa00c1cf8>] ? KCL_MEM_AllocAtomic+0x18/0x20 [fglrx]
[109729.001757] [<ffffffffa00c254e>] KCL_MEM_SmallBufferAllocAtomic+0xe/0x10
[fglrx]
[109729.001780] [<ffffffffa00d1f89>] firegl_save_fb+0x49/0x180 [fglrx]
[109729.001804] [<ffffffffa00d1330>] ? firegl_pm_save_framebuffer+0x1f0/0x270
[fglrx]
[109729.001828] [<ffffffffa00d3bd3>] ? firegl_cail_powerdown+0xb3/0x1c0 [fglrx]
[109729.001847] [<ffffffffa00be0f2>] ? fglrx_pci_suspend+0x82/0x140 [fglrx]
[109729.001851] [<ffffffff8128e9b5>] ? pci_legacy_suspend+0x45/0xe0
[109729.001853] [<ffffffff8128f325>] ? pci_pm_suspend+0xd5/0x130
[109729.001856] [<ffffffff8132550a>] ? pm_op+0x13a/0x180
[109729.001858] [<ffffffff81325b4a>] ? device_suspend+0xda/0x140
[109729.001860] [<ffffffff81325c76>] ? dpm_suspend+0xc6/0x140
[109729.001862] [<ffffffff81325d12>] ? dpm_suspend_start+0x22/0x30
[109729.001865] [<ffffffff8109331c>] ? suspend_devices_and_enter+0x5c/0xe0
[109729.001867] [<ffffffff81093478>] ? enter_state+0xd8/0x110
[109729.001869] [<ffffffff81092a32>] ? state_store+0x92/0x100
[109729.001872] [<ffffffff812747c7>] ? kobj_attr_store+0x17/0x20
[109729.001875] [<ffffffff81184dc0>] ? sysfs_write_file+0xe0/0x160
[109729.001877] [<ffffffff8111f3b8>] ? vfs_write+0xb8/0x1a0
[109729.001880] [<ffffffff8152c7b4>] ? do_page_fault+0x194/0x370
[109729.001882] [<ffffffff8111fe6c>] ? sys_write+0x4c/0x80
[109729.001885] [<ffffffff81012002>] ? system_call_fastpath+0x16/0x1b
[109729.001886] Mem-Info:
[109729.001887] Node 0 DMA per-cpu:
[109729.001889] CPU 0: hi: 0, btch: 1 usd: 0
[109729.001890] CPU 1: hi: 0, btch: 1 usd: 0
[109729.001891] Node 0 DMA32 per-cpu:
[109729.001893] CPU 0: hi: 186, btch: 31 usd: 65
[109729.001894] CPU 1: hi: 186, btch: 31 usd: 98
[109729.001895] Node 0 Normal per-cpu:
[109729.001896] CPU 0: hi: 186, btch: 31 usd: 100
[109729.001898] CPU 1: hi: 186, btch: 31 usd: 158
[109729.001900] Active_anon:190818 active_file:208996 inactive_anon:131055
[109729.001901] inactive_file:193625 unevictable:0 dirty:2 writeback:0
unstable:0
[109729.001901] free:95157 slab:59243 mapped:26824 pagetables:9369 bounce:0
[109729.001902] Node 0 DMA free:15900kB min:28kB low:32kB high:40kB
active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB
unevictable:0kB present:15332kB pages_scanned:0 all_unreclaimable? no
[109729.001906] lowmem_reserve[]: 0 2991 3938 3938
[109729.001908] Node 0 DMA32 free:362408kB min:6088kB low:7608kB high:9132kB
active_anon:661908kB inactive_anon:392280kB active_file:685024kB
inactive_file:620668kB unevictable:0kB present:3063428kB pages_scanned:0
all_unreclaimable? no
[109729.001911] lowmem_reserve[]: 0 0 946 946
[109729.001913] Node 0 Normal free:2320kB min:1924kB low:2404kB high:2884kB
active_anon:101364kB inactive_anon:131940kB active_file:150960kB
inactive_file:153832kB unevictable:0kB present:969600kB pages_scanned:0
all_unreclaimable? no
[109729.001916] lowmem_reserve[]: 0 0 0 0
[109729.001918] Node 0 DMA: 3*4kB 4*8kB 3*16kB 4*32kB 3*64kB 1*128kB 2*256kB
1*512kB 2*1024kB 2*2048kB 2*4096kB = 15900kB
[109729.001922] Node 0 DMA32: 22*4kB 4684*8kB 2965*16kB 1493*32kB 1092*64kB
552*128kB 288*256kB 24*512kB 1*1024kB 1*2048kB 0*4096kB = 362408kB
[109729.001927] Node 0 Normal: 240*4kB 60*8kB 3*16kB 2*32kB 0*64kB 6*128kB
0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 2320kB
[109729.001931] 449415 total pagecache pages
[109729.001932] 44278 pages in swap cache
[109729.001934] Swap cache stats: add 363129, delete 318851, find 769483/788866
[109729.001935] Free swap = 3872652kB
[109729.001936] Total swap = 4105072kB
[109729.014226] 1032192 pages RAM
[109729.014228] 44560 pages reserved
[109729.014229] 425977 pages shared
[109729.014229] 583440 pages non-shared
[109729.369279] [fglrx] Suspending fglrx in kernel completed.
During resume, the only thing that looked suspicious was:
[109736.106634] ata1.00: configured for UDMA/100
[109736.121372] ata1: exception Emask 0x10 SAct 0x0 SErr 0x0 action 0xf t4
[109736.121375] ata1: irq_stat 0x00000040, connection status changed
[109736.121379] ata1: hard resetting link
[109736.871379] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
I'm not sure of this matters or not, but I'm using hdaps, and it's the last
line before the system supposedly finishes waking up:
[109737.732556] hdaps: initial mode latch is 0x05
[109737.732670] hdaps: setting ec_rate=250, filter_order=2
[109737.733059] PM: resume devices took 2.220 seconds
[109737.733177] PM: Finishing wakeup.
[109737.733178] Restarting tasks ... done.
ProblemType: Bug
Architecture: amd64
Date: Sun Dec 20 13:33:12 2009
DistroRelease: Ubuntu 9.10
NonfreeKernelModules: fglrx
Package: pm-utils 1.2.5-2ubuntu7
PackageArchitecture: all
ProcEnviron:
PATH=(custom, user)
LANG=en_US.UTF-8
SHELL=/bin/bash
ProcVersionSignature: Ubuntu 2.6.31-17.54-generic
SourcePackage: pm-utils
Uname: Linux 2.6.31-17-generic x86_64
** Affects: pm-utils (Ubuntu)
Importance: Undecided
Status: New
** Tags: amd64 apport-bug
--
resume takes a long time (several minutes)
https://bugs.launchpad.net/bugs/498845
You received this bug notification because you are a member of Ubuntu
Bugs, which is subscribed to Ubuntu.
--
ubuntu-bugs mailing list
ubuntu-bugs@lists.ubuntu.com
https://lists.ubuntu.com/mailman/listinfo/ubuntu-bugs
