Cpu hotplugging problem 3.3 kernel
Milan Oravec <[email protected]> Fri, 23 Mar 2012 00:19:29 +0100
| Newsgroups | gmane.linux.swsusp.devel |
|---|---|
| Organization | CIKT UKF Nitra |
| Message-ID | <[email protected]> |
Hi, is anybody experiencing following problems? After 3 successful
hibernation cycles computer is unwilling to suspend with CPU Hotplugging
failed error.
Thanx.
Milan
TuxOnIce 3.2.1, with support for usm, compression, block i/o, swap
storage, userui.
Initiating a hibernation cycle.
Starting other threads.Freezing user space processes ... (elapsed 0.01
seconds) done.
Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Restarting kernel threads ... done.
Freezing remaining freezable tasks ... (elapsed 0.02 seconds) done.
Waited for i/o due to synchronous I/O 2 times.
uhci_hcd 0000:00:1a.0: setting latency timer to 64
uhci_hcd 0000:00:1a.1: setting latency timer to 64
uhci_hcd 0000:00:1a.2: setting latency timer to 64
ehci_hcd 0000:00:1a.7: setting latency timer to 64
uhci_hcd 0000:00:1d.0: setting latency timer to 64
uhci_hcd 0000:00:1d.1: setting latency timer to 64
uhci_hcd 0000:00:1d.2: setting latency timer to 64
ehci_hcd 0000:00:1d.7: setting latency timer to 64
Suspending console(s) (use no_console_suspend to debug)
sd 0:0:0:0: [sda] Synchronizing SCSI cache
kthreadd: page allocation failure: order:1, mode:0x10
Pid: 2, comm: kthreadd Tainted: G O 3.3.0-06305-gb13601d #1
Call Trace:
[<ffffffff810d8d6d>] warn_alloc_failed+0xbd/0x150
[<ffffffff810db8e7>] ? __alloc_pages_direct_compact+0xa7/0x1d0
[<ffffffff810dbf71>] __alloc_pages_nodemask+0x561/0x7a0
[<ffffffff8106964d>] ? cpuacct_charge+0x6d/0xb0
[<ffffffff8103c5b9>] copy_process+0xe9/0x1360
[<ffffffff8106666d>] ? sub_preempt_count+0x9d/0xd0
[<ffffffff8103d970>] do_fork+0x100/0x2b0
[<ffffffff8106b445>] ? set_next_entity+0xa5/0xc0
[<ffffffff8100b16c>] kernel_thread+0x6c/0x70
[<ffffffff8105a360>] ? kthread_freezable_should_stop+0x60/0x60
[<ffffffff8158c530>] ? gs_change+0xb/0xb
[<ffffffff8105a8d5>] kthreadd+0xf5/0x130
[<ffffffff8158c534>] kernel_thread_helper+0x4/0x10
[<ffffffff8105a7e0>] ? tsk_fork_get_node+0x10/0x10
[<ffffffff8158c530>] ? gs_change+0xb/0xb
Mem-Info:
DMA per-cpu:
CPU 0: hi: 0, btch: 1 usd: 0
CPU 1: hi: 0, btch: 1 usd: 0
DMA32 per-cpu:
CPU 0: hi: 186, btch: 31 usd: 183
CPU 1: hi: 186, btch: 31 usd: 170
Normal per-cpu:
CPU 0: hi: 186, btch: 31 usd: 191
CPU 1: hi: 186, btch: 31 usd: 90
active_anon:234053 inactive_anon:154552 isolated_anon:0
active_file:11505 inactive_file:20597 isolated_file:0
unevictable:243 dirty:4 writeback:37 unstable:0
free:22726 slab_reclaimable:22864 slab_unreclaimable:4500
mapped:33084 shmem:54786 pagetables:5363 bounce:0
DMA free:32kB min:260kB low:324kB high:388kB active_anon:0kB
inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB
isolated(anon):0kB isolated(file):0kB present:15656kB mlocked:0kB
dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:0kB
slab_unreclaimable:8kB kernel_stack:0kB pagetables:0kB unstable:0kB
bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
lowmem_reserve[]: 0 2921 3929 3929
DMA32 free:53212kB min:50052kB low:62564kB high:75076kB
active_anon:457896kB inactive_anon:452564kB active_file:992kB
inactive_file:17668kB unevictable:924kB isolated(anon):0kB
isolated(file):0kB present:2991644kB mlocked:924kB dirty:8kB
writeback:148kB mapped:20964kB shmem:72616kB slab_reclaimable:75540kB
slab_unreclaimable:1924kB kernel_stack:336kB pagetables:6748kB
unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0
all_unreclaimable? no
lowmem_reserve[]: 0 0 1008 1008
Normal free:37660kB min:17268kB low:21584kB high:25900kB
active_anon:478316kB inactive_anon:165644kB active_file:45028kB
inactive_file:64720kB unevictable:48kB isolated(anon):0kB
isolated(file):0kB present:1032192kB mlocked:48kB dirty:8kB
writeback:0kB mapped:111372kB shmem:146528kB slab_reclaimable:15916kB
slab_unreclaimable:16068kB kernel_stack:2264kB pagetables:14704kB
unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0
all_unreclaimable? no
lowmem_reserve[]: 0 0 0 0
DMA: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB
0*2048kB 0*4096kB = 0kB
DMA32: 9221*4kB 827*8kB 109*16kB 47*32kB 27*64kB 9*128kB 6*256kB 2*512kB
1*1024kB 0*2048kB 0*4096kB = 53212kB
Normal: 9389*4kB 13*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB
0*1024kB 0*2048kB 0*4096kB = 37660kB
89272 total pagecache pages
2242 pages in swap cache
Swap cache stats: add 9363, delete 7121, find 19/19
Free swap = 3787264kB
Total swap = 5854204kB
1048560 pages RAM
54869 pages reserved
146936 pages shared
407390 pages non-shared
ata6: SATA link down (SStatus 0 SControl 300)
ata5: SATA link down (SStatus 0 SControl 300)
ACPI handle has no context!
PM: freeze of devices complete after 563.094 msecs
PM: late freeze of devices complete after 0.820 msecs
ACPI: Preparing to enter system sleep state S4
PM: Saving platform NVS memory
Disabling non-boot CPUs ...
kthreadd: page allocation failure: order:1, mode:0x10
Pid: 2, comm: kthreadd Tainted: G O 3.3.0-06305-gb13601d #1
Call Trace:
[<ffffffff810d8d6d>] warn_alloc_failed+0xbd/0x150
[<ffffffff810db8e7>] ? __alloc_pages_direct_compact+0xa7/0x1d0
[<ffffffff810dbf71>] __alloc_pages_nodemask+0x561/0x7a0
[<ffffffff8103c5b9>] copy_process+0xe9/0x1360
[<ffffffff8103d970>] do_fork+0x100/0x2b0
[<ffffffff8100b16c>] kernel_thread+0x6c/0x70
[<ffffffff8105a360>] ? kthread_freezable_should_stop+0x60/0x60
[<ffffffff8158c530>] ? gs_change+0xb/0xb
[<ffffffff8105a8d5>] kthreadd+0xf5/0x130
[<ffffffff8158c534>] kernel_thread_helper+0x4/0x10
[<ffffffff8105a7e0>] ? tsk_fork_get_node+0x10/0x10
[<ffffffff8158c530>] ? gs_change+0xb/0xb
Mem-Info:
DMA per-cpu:
CPU 0: hi: 0, btch: 1 usd: 0
CPU 1: hi: 0, btch: 1 usd: 0
DMA32 per-cpu:
CPU 0: hi: 186, btch: 31 usd: 156
CPU 1: hi: 186, btch: 31 usd: 170
Normal per-cpu:
CPU 0: hi: 186, btch: 31 usd: 191
CPU 1: hi: 186, btch: 31 usd: 90
active_anon:234053 inactive_anon:154502 isolated_anon:0
active_file:11505 inactive_file:20597 isolated_file:0
unevictable:243 dirty:4 writeback:37 unstable:0
free:22819 slab_reclaimable:22864 slab_unreclaimable:4500
mapped:33084 shmem:54712 pagetables:5363 bounce:0
DMA free:32kB min:260kB low:324kB high:388kB active_anon:0kB
inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB
isolated(anon):0kB isolated(file):0kB present:15656kB mlocked:0kB
dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:0kB
slab_unreclaimable:8kB kernel_stack:0kB pagetables:0kB unstable:0kB
bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
lowmem_reserve[]: 0 2921 3929 3929
DMA32 free:53584kB min:50052kB low:62564kB high:75076kB
active_anon:457896kB inactive_anon:452364kB active_file:992kB
inactive_file:17668kB unevictable:924kB isolated(anon):0kB
isolated(file):0kB present:2991644kB mlocked:924kB dirty:8kB
writeback:148kB mapped:20964kB shmem:72320kB slab_reclaimable:75540kB
slab_unreclaimable:1924kB kernel_stack:336kB pagetables:6748kB
unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0
all_unreclaimable? no
lowmem_reserve[]: 0 0 1008 1008
Normal free:37660kB min:17268kB low:21584kB high:25900kB
active_anon:478316kB inactive_anon:165644kB active_file:45028kB
inactive_file:64720kB unevictable:48kB isolated(anon):0kB
isolated(file):0kB present:1032192kB mlocked:48kB dirty:8kB
writeback:0kB mapped:111372kB shmem:146528kB slab_reclaimable:15916kB
slab_unreclaimable:16068kB kernel_stack:2264kB pagetables:14704kB
unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0
all_unreclaimable? no
lowmem_reserve[]: 0 0 0 0
DMA: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB
0*2048kB 0*4096kB = 0kB
DMA32: 9252*4kB 838*8kB 115*16kB 49*32kB 27*64kB 9*128kB 6*256kB 2*512kB
1*1024kB 0*2048kB 0*4096kB = 53584kB
Normal: 9389*4kB 14*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB
0*1024kB 0*2048kB 0*4096kB = 37668kB
89198 total pagecache pages
2242 pages in swap cache
Swap cache stats: add 9363, delete 7121, find 19/19
Free swap = 3787264kB
Total swap = 5854204kB
1048560 pages RAM
54869 pages reserved
146866 pages shared
407391 pages non-shared
_cpu_down: attempt to take down CPU 1 failed
Error taking CPU1 down: -12
Non-boot CPUs are not disabled
ACPI: Waking up from system sleep state S4
PM: early recover of devices complete after 0.222 msecs
snd_hda_intel 0000:00:1b.0: irq 45 for MSI/MSI-X
pci 0000:00:1e.0: setting latency timer to 64
ahci 0000:00:1f.2: setting latency timer to 64
kthreadd: page allocation failure: order:1, mode:0x10
Pid: 2, comm: kthreadd Tainted: G O 3.3.0-06305-gb13601d #1
Call Trace:
[<ffffffff810d8d6d>] warn_alloc_failed+0xbd/0x150
[<ffffffff810db8e7>] ? __alloc_pages_direct_compact+0xa7/0x1d0
[<ffffffff810dbf71>] __alloc_pages_nodemask+0x561/0x7a0
[<ffffffff8103c5b9>] copy_process+0xe9/0x1360
[<ffffffff8103d970>] do_fork+0x100/0x2b0
[<ffffffff8100b16c>] kernel_thread+0x6c/0x70
[<ffffffff8105a360>] ? kthread_freezable_should_stop+0x60/0x60
[<ffffffff8158c530>] ? gs_change+0xb/0xb
[<ffffffff8105a8d5>] kthreadd+0xf5/0x130
[<ffffffff8158c534>] kernel_thread_helper+0x4/0x10
[<ffffffff8105a7e0>] ? tsk_fork_get_node+0x10/0x10
[<ffffffff8158c530>] ? gs_change+0xb/0xb
Mem-Info:
DMA per-cpu:
CPU 0: hi: 0, btch: 1 usd: 0
CPU 1: hi: 0, btch: 1 usd: 0
DMA32 per-cpu:
CPU 0: hi: 186, btch: 31 usd: 157
CPU 1: hi: 186, btch: 31 usd: 170
Normal per-cpu:
CPU 0: hi: 186, btch: 31 usd: 177
CPU 1: hi: 186, btch: 31 usd: 117
active_anon:234053 inactive_anon:154537 isolated_anon:0
active_file:11505 inactive_file:20597 isolated_file:0
unevictable:243 dirty:4 writeback:0 unstable:0
free:22813 slab_reclaimable:22862 slab_unreclaimable:4502
mapped:33084 shmem:54770 pagetables:5363 bounce:0
DMA free:0kB min:260kB low:324kB high:388kB active_anon:0kB
inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB
isolated(anon):0kB isolated(file):0kB present:15656kB mlocked:0kB
dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:0kB
slab_unreclaimable:8kB kernel_stack:0kB pagetables:0kB unstable:0kB
bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
lowmem_reserve[]: 0 2921 3929 3929
DMA32 free:53584kB min:50052kB low:62564kB high:75076kB
active_anon:457896kB inactive_anon:452300kB active_file:992kB
inactive_file:17668kB unevictable:924kB isolated(anon):0kB
isolated(file):0kB present:2991644kB mlocked:924kB dirty:8kB
writeback:0kB mapped:20964kB shmem:72352kB slab_reclaimable:75532kB
slab_unreclaimable:1920kB kernel_stack:336kB pagetables:6748kB
unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0
all_unreclaimable? no
lowmem_reserve[]: 0 0 1008 1008
Normal free:37668kB min:17268kB low:21584kB high:25900kB
active_anon:478316kB inactive_anon:165848kB active_file:45028kB
inactive_file:64720kB unevictable:48kB isolated(anon):0kB
isolated(file):0kB present:1032192kB mlocked:48kB dirty:8kB
writeback:0kB mapped:111372kB shmem:146728kB slab_reclaimable:15916kB
slab_unreclaimable:16080kB kernel_stack:2264kB pagetables:14704kB
unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0
all_unreclaimable? no
lowmem_reserve[]: 0 0 0 0
DMA: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB
0*2048kB 0*4096kB = 0kB
DMA32: 9252*4kB 838*8kB 115*16kB 49*32kB 27*64kB 9*128kB 6*256kB 2*512kB
1*1024kB 0*2048kB 0*4096kB = 53584kB
Normal: 9383*4kB 17*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB
0*1024kB 0*2048kB 0*4096kB = 37668kB
89270 total pagecache pages
2242 pages in swap cache
Swap cache stats: add 9363, delete 7121, find 19/19
Free swap = 3787264kB
Total swap = 5854204kB
1048560 pages RAM
54869 pages reserved
146942 pages shared
407312 pages non-shared
ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
ata1.00: configured for UDMA/133
ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
ata2.00: ACPI cmd 00/00:00:00:00:00:a0 (NOP) rejected by device
(Stat=0x51 Err=0x04)
ata2.00: ACPI cmd 00/00:00:00:00:00:a0 (NOP) rejected by device
(Stat=0x51 Err=0x04)
ata2.00: configured for UDMA/33
ata5: SATA link down (SStatus 0 SControl 300)
ata6: SATA link down (SStatus 0 SControl 300)
sd 0:0:0:0: [sda] Starting disk
kthreadd: page allocation failure: order:1, mode:0x10
Pid: 2, comm: kthreadd Tainted: G O 3.3.0-06305-gb13601d #1
Call Trace:
[<ffffffff810d8d6d>] warn_alloc_failed+0xbd/0x150
[<ffffffff810db8e7>] ? __alloc_pages_direct_compact+0xa7/0x1d0
[<ffffffff810dbf71>] __alloc_pages_nodemask+0x561/0x7a0
[<ffffffff8103c5b9>] copy_process+0xe9/0x1360
[<ffffffff8103d970>] do_fork+0x100/0x2b0
[<ffffffff8100b16c>] kernel_thread+0x6c/0x70
[<ffffffff8105a360>] ? kthread_freezable_should_stop+0x60/0x60
[<ffffffff8158c530>] ? gs_change+0xb/0xb
[<ffffffff8105a8d5>] kthreadd+0xf5/0x130
[<ffffffff8158c534>] kernel_thread_helper+0x4/0x10
[<ffffffff8105a7e0>] ? tsk_fork_get_node+0x10/0x10
[<ffffffff8158c530>] ? gs_change+0xb/0xb
Mem-Info:
DMA per-cpu:
CPU 0: hi: 0, btch: 1 usd: 0
CPU 1: hi: 0, btch: 1 usd: 0
DMA32 per-cpu:
CPU 0: hi: 186, btch: 31 usd: 157
CPU 1: hi: 186, btch: 31 usd: 170
Normal per-cpu:
CPU 0: hi: 186, btch: 31 usd: 177
CPU 1: hi: 186, btch: 31 usd: 119
active_anon:234053 inactive_anon:154542 isolated_anon:0
active_file:11505 inactive_file:20597 isolated_file:0
unevictable:243 dirty:4 writeback:0 unstable:0
free:22813 slab_reclaimable:22862 slab_unreclaimable:4502
mapped:33084 shmem:54786 pagetables:5363 bounce:0
DMA free:0kB min:260kB low:324kB high:388kB active_anon:0kB
inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB
isolated(anon):0kB isolated(file):0kB present:15656kB mlocked:0kB
dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:0kB
slab_unreclaimable:8kB kernel_stack:0kB pagetables:0kB unstable:0kB
bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
lowmem_reserve[]: 0 2921 3929 3929
DMA32 free:53584kB min:50052kB low:62564kB high:75076kB
active_anon:457896kB inactive_anon:452300kB active_file:992kB
inactive_file:17668kB unevictable:924kB isolated(anon):0kB
isolated(file):0kB present:2991644kB mlocked:924kB dirty:8kB
writeback:0kB mapped:20964kB shmem:72352kB slab_reclaimable:75532kB
slab_unreclaimable:1920kB kernel_stack:336kB pagetables:6748kB
unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0
all_unreclaimable? no
lowmem_reserve[]: 0 0 1008 1008
Normal free:37668kB min:17268kB low:21584kB high:25900kB
active_anon:478316kB inactive_anon:165868kB active_file:45028kB
inactive_file:64720kB unevictable:48kB isolated(anon):0kB
isolated(file):0kB present:1032192kB mlocked:48kB dirty:8kB
writeback:0kB mapped:111372kB shmem:146792kB slab_reclaimable:15916kB
slab_unreclaimable:16080kB kernel_stack:2264kB pagetables:14704kB
unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0
all_unreclaimable? no
lowmem_reserve[]: 0 0 0 0
DMA: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB
0*2048kB 0*4096kB = 0kB
DMA32: 9252*4kB 838*8kB 115*16kB 49*32kB 27*64kB 9*128kB 6*256kB 2*512kB
1*1024kB 0*2048kB 0*4096kB = 53584kB
Normal: 9381*4kB 18*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB
0*1024kB 0*2048kB 0*4096kB = 37668kB
89286 total pagecache pages
2242 pages in swap cache
Swap cache stats: add 9363, delete 7121, find 19/19
Free swap = 3787264kB
Total swap = 5854204kB
1048560 pages RAM
54869 pages reserved
146942 pages shared
407310 pages non-shared
PM: recover of devices complete after 1316.845 msecs
Restarting tasks ... Restarting kernel threads ... done.
done.
video LNXVIDEO:00: Restoring backlight state
TuxOnIce debugging info:
- TuxOnIce core : 3.2.1
- Kernel Version : 3.3.0-06305-gb13601d
- Compiler vers. : 4.6
- Attempt number : 6
- Parameters : 524289 667648 0 0 -2 5
- Overall expected compression percentage: 0.
- Compressor is 'lzo'.
- Block I/O active.
Used 0 pages from swap on /dev/sda1.
- Max outstanding reads 1. Max writes 0.
Memory_needed: 1024 x (4096 + 368 + 112) = 4685824 bytes.
Free mem throttle point reached 0.
- Swap Allocator enabled.
Swap available for image: 1454192 pages.
- No I/O speed stats available.
- Extra pages : 0 used/2000.
- Result : Hibernation was aborted.
: CPU Hotplugging failed.
EXT4-fs (sda5): re-mounted. Opts: errors=remount-ro,commit=0
EXT4-fs (sda4): re-mounted. Opts: commit=0
iwlwifi 0000:06:00.0: L1 Enabled; Disabling L0S
iwlwifi 0000:06:00.0: Radio type=0x1-0x2-0x0
ADDRCONF(NETDEV_UP): wlan0: link is not ready