It seems I am seeing the same bug too, on linux-image-3.2.0-0.bpo.1-686-pae, 3.2.4-1~bpo60+1. However, I'm on an nVidia nForce 570 chipset (AMD A64-x2), esp. using the sata_nv driver. About half of the hibernate-disk cycles do work, the other half starts writing the image to some point just normally, but after a few seconds the disk writer gets extremely slow (around 1% per minute), and after some more minutes, the kernel throws page allocation failures: > [16095.943147] echo: page allocation failure: order:0, mode:0x20 > [16095.945176] kthreadd: page allocation failure: order:0, mode:0x20 > [16095.945180] Pid: 2, comm: kthreadd Not tainted 3.2.0-0.bpo.1-686-pae #1 > [16095.945182] Call Trace: > [16095.945189] [<c10a1284>] ? warn_alloc_failed+0xc0/0xd1 > [16095.945192] [<c10a236b>] ? __alloc_pages_nodemask+0x55e/0x5cb > [16095.945195] [<c10a9959>] ? shrink_zone+0x440/0x44c > [16095.945199] [<c10ca0aa>] ? ____cache_alloc+0x25d/0x406 > [16095.945201] [<c10ca343>] ? kmem_cache_alloc_trace+0x3c/0x7b > [16095.945204] [<c10f019d>] ? __bdi_start_writeback+0x23/0x90 > [16095.945206] [<c10f0257>] ? wakeup_flusher_threads+0x4d/0x64 > [16095.945208] [<c10a9f52>] ? do_try_to_free_pages+0x2d4/0x403 > [16095.945210] [<c10aa28a>] ? try_to_free_pages+0x9f/0xcd > [16095.945213] [<c10a21d0>] ? __alloc_pages_nodemask+0x3c3/0x5cb > [16095.945217] [<c103a29b>] ? copy_process+0xc5/0xdf1 > [16095.945220] [<c100baa9>] ? __switch_to+0x6f/0xe2 > [16095.945223] [<c103b157>] ? do_fork+0xbb/0x1d0 > [16095.945226] [<c10514fc>] ? kthread_worker_fn+0x114/0x114 > [16095.945229] [<c101186f>] ? kernel_thread+0x7b/0x83 > [16095.945231] [<c10514fc>] ? kthread_worker_fn+0x114/0x114 > [16095.945234] [<c12d2c78>] ? common_interrupt+0x38/0x38 > [16095.945237] [<c105160e>] ? kthreadd+0xaa/0xdc > [16095.945239] [<c1051564>] ? kthread+0x68/0x68 > [16095.945241] [<c12d2c7e>] ? kernel_thread_helper+0x6/0x10 > [16095.945242] Mem-Info: > [16095.945243] DMA per-cpu: > [16095.945245] CPU 0: hi: 0, btch: 1 usd: 0 > [16095.945246] CPU 1: hi: 0, btch: 1 usd: 0 > [16095.945247] Normal per-cpu: > [16095.945249] CPU 0: hi: 186, btch: 31 usd: 21 > [16095.945250] CPU 1: hi: 186, btch: 31 usd: 1 > [16095.945251] HighMem per-cpu: > [16095.945253] CPU 0: hi: 186, btch: 31 usd: 0 > [16095.945254] CPU 1: hi: 186, btch: 31 usd: 0 > [16095.945258] active_anon:30380 inactive_anon:864 isolated_anon:0 > [16095.945259] active_file:112988 inactive_file:128220 isolated_file:0 > [16095.945259] unevictable:0 dirty:0 writeback:0 unstable:0 > [16095.945260] free:226695 slab_reclaimable:11024 slab_unreclaimable:15456 > [16095.945261] mapped:13381 shmem:931 pagetables:959 bounce:0 > [16095.945266] DMA free:16kB min:784kB low:980kB high:1176kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15792kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:0kB slab_unreclaimable:4220kB kernel_stack:0kB pagetables:0kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no > [16095.945270] lowmem_reserve[]: 0 867 4039 4039 > [16095.945275] Normal free:0kB min:44112kB low:55140kB high:66168kB active_anon:0kB inactive_anon:0kB active_file:1148kB inactive_file:1148kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:887976kB mlocked:0kB dirty:0kB writeback:0kB mapped:4kB shmem:0kB slab_reclaimable:44096kB slab_unreclaimable:57604kB kernel_stack:2000kB pagetables:0kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:220064075 all_unreclaimable? no > [16095.945279] lowmem_reserve[]: 0 0 25378 25378 > [16095.945285] HighMem free:906764kB min:512kB low:40856kB high:81204kB active_anon:121520kB inactive_anon:3456kB active_file:450804kB inactive_file:511732kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:3248468kB mlocked:0kB dirty:0kB writeback:0kB mapped:53520kB shmem:3724kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:3836kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no > [16095.945288] lowmem_reserve[]: 0 0 0 0 > [16095.945290] DMA: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 0kB > [16095.945295] Normal: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 0kB > [16095.945299] HighMem: 22355*4kB 16232*8kB 6812*16kB 2330*32kB 612*64kB 405*128kB 133*256kB 24*512kB 12*1024kB 7*2048kB 83*4096kB = 906764kB > [16095.945305] 242139 total pagecache pages > [16095.945306] 0 pages in swap cache > [16095.945307] Swap cache stats: add 0, delete 0, find 0/0 > [16095.945308] Free swap = 3715540kB > [16095.945309] Total swap = 4194300kB > [16095.948007] 1318896 pages RAM > [16095.948007] 1091074 pages HighMem > [16095.948007] 282321 pages reserved > [16095.948007] 156789 pages shared > [16095.948007] 702774 pages non-shared More netconsole output attached. The output matches the screenshot that was posted to the redhat bug tracker on 2012-03-28, so it looks like the same issue: Running out of memory, maybe because hibernate buffers eated it. Cheers, Udo
Attachment:
Netconsole.txt.gz
Description: GNU Zip compressed data