Re: s2disk hang update

[Alan Jenkins <sourcejedi.lkml@googlemail.com>]

[-- Attachment #1: Type: text/plain, Size: 1414 bytes --]

On 2/16/10, Alan Jenkins <sourcejedi.lkml@googlemail.com> wrote:
> On 2/15/10, Rafael J. Wysocki <rjw@sisk.pl> wrote:
>> On Tuesday 09 February 2010, Alan Jenkins wrote:
>>> Perhaps I spoke too soon.  I see the same hang if I run too many
>>> applications.  The first hibernation fails with "not enough swap" as
>>> expected, but the second or third attempt hangs (with the same backtrace
>>> as before).
>>>
>>> The patch definitely helps though.  Without the patch, I see a hang the
>>> first time I try to hibernate with too many applications running.
>>
>> Well, I have an idea.
>>
>> Can you try to apply the appended patch in addition and see if that
>> helps?
>>
>> Rafael
>
> It doesn't seem to help.

To be clear: It doesn't stop the hang when I hibernate with too many
applications.

It does stop the same hang in a different case though.

1. boot with init=/bin/bash
2. run s2disk
3. cancel the s2disk
4. repeat steps 2&3

With the patch, I can run 10s of iterations, with no hang.
Without the patch, it soon hangs, (in disable_nonboot_cpus(), as always).

That's what happens on 2.6.33-rc7.  On 2.6.30, there is no problem.
On 2.6.31 and 2.6.32 I don't get a hang, but dmesg shows an allocation
failure after a couple of iterations ("kthreadd: page allocation
failure. order:1, mode:0xd0").  It looks like it might be the same
stop_machine thread allocation failure that causes the hang.

Regards
Alan

[-- Attachment #2: graceful-allocation-failure.txt --]
[-- Type: text/plain, Size: 30282 bytes --]

[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 2.6.31eeepc (alan@alan-desktop-karmic) (gcc version 4.4.1 (Ubuntu 4.4.1-4ubuntu8) ) #48 SMP Tue Dec 1 14:37:15 GMT 2009
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   NSC Geode by NSC
[    0.000000]   Cyrix CyrixInstead
[    0.000000]   Centaur CentaurHauls
[    0.000000]   Transmeta GenuineTMx86
[    0.000000]   Transmeta TransmetaCPU
[    0.000000]   UMC UMC UMC UMC
[    0.000000] BIOS-provided physical RAM map:
[    0.000000]  BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
[    0.000000]  BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
[    0.000000]  BIOS-e820: 00000000000e4000 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 000000001f780000 (usable)
[    0.000000]  BIOS-e820: 000000001f780000 - 000000001f790000 (ACPI data)
[    0.000000]  BIOS-e820: 000000001f790000 - 000000001f7d0000 (ACPI NVS)
[    0.000000]  BIOS-e820: 000000001f7d0000 - 000000001f7de000 (reserved)
[    0.000000]  BIOS-e820: 000000001f7e0000 - 000000001f800000 (reserved)
[    0.000000]  BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
[    0.000000]  BIOS-e820: 00000000fff80000 - 0000000100000000 (reserved)
[    0.000000] DMI present.
[    0.000000] AMI BIOS detected: BIOS may corrupt low RAM, working around it.
[    0.000000] e820 update range: 0000000000000000 - 0000000000010000 (usable) ==> (reserved)
[    0.000000] last_pfn = 0x1f780 max_arch_pfn = 0x100000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-DFFFF uncachable
[    0.000000]   E0000-EFFFF write-through
[    0.000000]   F0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 000000000 mask FE0000000 write-back
[    0.000000]   1 base 01F800000 mask FFF800000 uncachable
[    0.000000]   2 disabled
[    0.000000]   3 disabled
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] PAT not supported by CPU.
[    0.000000] initial memory mapped : 0 - 01800000
[    0.000000] init_memory_mapping: 0000000000000000-000000001f780000
[    0.000000]  0000000000 - 0000400000 page 4k
[    0.000000]  0000400000 - 001f400000 page 2M
[    0.000000]  001f400000 - 001f780000 page 4k
[    0.000000] kernel direct mapping tables up to 1f780000 @ 10000-16000
[    0.000000] RAMDISK: 176f6000 - 179df1f6
[    0.000000] ACPI: RSDP 000fbe50 00014 (v00 ACPIAM)
[    0.000000] ACPI: RSDT 1f780000 00034 (v01 A M I  OEMRSDT  03000911 MSFT 00000097)
[    0.000000] ACPI: FACP 1f780200 00081 (v01 A M I  OEMFACP  03000911 MSFT 00000097)
[    0.000000] ACPI: DSDT 1f780400 06069 (v01  A0797 A0797000 00000000 INTL 20060113)
[    0.000000] ACPI: FACS 1f790000 00040
[    0.000000] ACPI: APIC 1f780390 00068 (v01 A M I  OEMAPIC  03000911 MSFT 00000097)
[    0.000000] ACPI: OEMB 1f790040 00046 (v01 A M I  AMI_OEM  03000911 MSFT 00000097)
[    0.000000] ACPI: MCFG 1f786470 0003C (v01 A M I  OEMMCFG  03000911 MSFT 00000097)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] 0MB HIGHMEM available.
[    0.000000] 503MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 1f780000
[    0.000000]   low ram: 0 - 1f780000
[    0.000000]   node 0 low ram: 00000000 - 1f780000
[    0.000000]   node 0 bootmap 00012000 - 00015ef0
[    0.000000] (9 early reservations) ==> bootmem [0000000000 - 001f780000]
[    0.000000]   #0 [0000000000 - 0000001000]   BIOS data page ==> [0000000000 - 0000001000]
[    0.000000]   #1 [0000001000 - 0000002000]    EX TRAMPOLINE ==> [0000001000 - 0000002000]
[    0.000000]   #2 [0000006000 - 0000007000]       TRAMPOLINE ==> [0000006000 - 0000007000]
[    0.000000]   #3 [0001000000 - 00014a5f90]    TEXT DATA BSS ==> [0001000000 - 00014a5f90]
[    0.000000]   #4 [00176f6000 - 00179df1f6]          RAMDISK ==> [00176f6000 - 00179df1f6]
[    0.000000]   #5 [000009fc00 - 0000100000]    BIOS reserved ==> [000009fc00 - 0000100000]
[    0.000000]   #6 [00014a6000 - 00014ac1f4]              BRK ==> [00014a6000 - 00014ac1f4]
[    0.000000]   #7 [0000010000 - 0000012000]          PGTABLE ==> [0000010000 - 0000012000]
[    0.000000]   #8 [0000012000 - 0000016000]          BOOTMAP ==> [0000012000 - 0000016000]
[    0.000000] found SMP MP-table at [c00ff780] ff780
[    0.000000] Zone PFN ranges:
[    0.000000]   DMA      0x00000010 -> 0x00001000
[    0.000000]   Normal   0x00001000 -> 0x0001f780
[    0.000000]   HighMem  0x0001f780 -> 0x0001f780
[    0.000000] Movable zone start PFN for each node
[    0.000000] early_node_map[2] active PFN ranges
[    0.000000]     0: 0x00000010 -> 0x0000009f
[    0.000000]     0: 0x00000100 -> 0x0001f780
[    0.000000] On node 0 totalpages: 128783
[    0.000000] free_area_init_node: node 0, pgdat c1396960, node_mem_map c14ae200
[    0.000000]   DMA zone: 32 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 3951 pages, LIFO batch:0
[    0.000000]   Normal zone: 975 pages used for memmap
[    0.000000]   Normal zone: 123825 pages, LIFO batch:31
[    0.000000] Using APIC driver default
[    0.000000] ACPI: PM-Timer IO Port: 0x808
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x00] enabled)
[    0.000000] ACPI: IOAPIC (id[0x01] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 1, version 32, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ2 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] SMP: Allowing 1 CPUs, 0 hotplug CPUs
[    0.000000] nr_irqs_gsi: 24
[    0.000000] PM: Registered nosave memory: 000000000009f000 - 00000000000a0000
[    0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000e4000
[    0.000000] PM: Registered nosave memory: 00000000000e4000 - 0000000000100000
[    0.000000] Allocating PCI resources starting at 1f800000 (gap: 1f800000:df600000)
[    0.000000] Booting paravirtualized kernel on bare hardware
[    0.000000] NR_CPUS:32 nr_cpumask_bits:32 nr_cpu_ids:1 nr_node_ids:1
[    0.000000] PERCPU: Embedded 13 pages/cpu @c1c00000 s32088 r0 d21160 u4194304
[    0.000000] pcpu-alloc: s32088 r0 d21160 u4194304 alloc=1*4194304
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 127776
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-2.6.31eeepc root=/dev/sda2 ro rootfstype=ext4 no_console_suspend i915.modeset=1 resume=/dev/sda2 resume_offset=188416 init=/bin/bash
[    0.000000] Unknown boot option `i915.modeset=1': ignoring
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Enabling fast FPU save and restore... done.
[    0.000000] Enabling unmasked SIMD FPU exception support... done.
[    0.000000] Initializing CPU#0
[    0.000000] Initializing HighMem for node 0 (00000000:00000000)
[    0.000000] Memory: 502436k/515584k available (2390k kernel code, 12296k reserved, 1319k data, 356k init, 0k highmem)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xffd56000 - 0xfffff000   (2724 kB)
[    0.000000]     pkmap   : 0xff400000 - 0xff800000   (4096 kB)
[    0.000000]     vmalloc : 0xdff80000 - 0xff3fe000   ( 500 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xdf780000   ( 503 MB)
[    0.000000]       .init : 0xc13a0000 - 0xc13f9000   ( 356 kB)
[    0.000000]       .data : 0xc1255afe - 0xc139fa68   (1319 kB)
[    0.000000]       .text : 0xc1000000 - 0xc1255afe   (2390 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS:1280
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty0] enabled
[    0.000000] Fast TSC calibration using PIT
[    0.000000] Detected 630.015 MHz processor.
[    0.004010] Calibrating delay loop (skipped), value calculated using timer frequency.. 1260.03 BogoMIPS (lpj=2520060)
[    0.008114] Security Framework initialized
[    0.008220] SELinux:  Disabled at boot.
[    0.008348] Mount-cache hash table entries: 512
[    0.008760] Initializing cgroup subsys ns
[    0.008860] Initializing cgroup subsys cpuacct
[    0.008961] Initializing cgroup subsys devices
[    0.009058] Initializing cgroup subsys freezer
[    0.009153] Initializing cgroup subsys net_cls
[    0.009303] CPU: L1 I cache: 32K, L1 D cache: 32K
[    0.009446] CPU: L2 cache: 512K
[    0.009542] mce: CPU supports 5 MCE banks
[    0.009651] CPU0: Thermal monitoring enabled (TM1)
[    0.009764] Performance Events: p6 PMU driver.
[    0.009931] ... version:                0
[    0.010024] ... bit width:              32
[    0.010118] ... generic registers:      2
[    0.010211] ... value mask:             00000000ffffffff
[    0.010310] ... max period:             000000007fffffff
[    0.010408] ... fixed-purpose events:   0
[    0.010502] ... event mask:             0000000000000003
[    0.010608] Checking 'hlt' instruction... OK.
[    0.024948] SMP alternatives: switching to UP code
[    0.035538] Freeing SMP alternatives: 12k freed
[    0.035655] ACPI: Core revision 20090521
[    0.060707] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.102319] CPU0: Intel(R) Celeron(R) M processor          900MHz stepping 08
[    0.104001] APIC calibration not consistent with PM-Timer: 136ms instead of 100ms
[    0.104001] APIC delta adjusted to PM-Timer: 437545 (595095)
[    0.104001] Brought up 1 CPUs
[    0.104001] Total of 1 processors activated (1260.03 BogoMIPS).
[    0.104001] CPU0 attaching NULL sched-domain.
[    0.104001] regulator: core version 0.5
[    0.104001] NET: Registered protocol family 16
[    0.104001] ACPI: bus type pci registered
[    0.104001] PCI: MCFG configuration 0: base e0000000 segment 0 buses 0 - 255
[    0.104001] PCI: Not using MMCONFIG.
[    0.104001] PCI: PCI BIOS revision 3.00 entry at 0xf0031, last bus=5
[    0.104001] PCI: Using configuration type 1 for base access
[    0.105290] bio: create slab <bio-0> at 0
[    0.107289] ACPI: EC: Look up EC in DSDT
[    0.128761] ACPI: Interpreter enabled
[    0.128881] ACPI: (supports S0 S1 S3 S4 S5)
[    0.129262] ACPI: Using IOAPIC for interrupt routing
[    0.129500] PCI: MCFG configuration 0: base e0000000 segment 0 buses 0 - 255
[    0.135742] PCI: MCFG area at e0000000 reserved in ACPI motherboard resources
[    0.135852] PCI: Using MMCONFIG for extended config space
[    0.149580] ACPI: EC: GPE = 0x18, I/O: command/status = 0x66, data = 0x62
[    0.149689] ACPI: EC: driver started in poll mode
[    0.150277] ACPI: No dock devices found.
[    0.150782] ACPI: PCI Root Bridge [PCI0] (0000:00)
[    0.151088] pci 0000:00:02.0: reg 10 32bit mmio: [0xf7f00000-0xf7f7ffff]
[    0.151103] pci 0000:00:02.0: reg 14 io port: [0xec00-0xec07]
[    0.151117] pci 0000:00:02.0: reg 18 32bit mmio pref: [0xd0000000-0xdfffffff]
[    0.151131] pci 0000:00:02.0: reg 1c 32bit mmio: [0xf7ec0000-0xf7efffff]
[    0.151206] pci 0000:00:02.1: reg 10 32bit mmio: [0xf7f80000-0xf7ffffff]
[    0.151357] pci 0000:00:1b.0: reg 10 64bit mmio: [0xf7eb8000-0xf7ebbfff]
[    0.151428] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold
[    0.151536] pci 0000:00:1b.0: PME# disabled
[    0.151732] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
[    0.151837] pci 0000:00:1c.0: PME# disabled
[    0.152049] pci 0000:00:1c.1: PME# supported from D0 D3hot D3cold
[    0.152156] pci 0000:00:1c.1: PME# disabled
[    0.152355] pci 0000:00:1c.2: PME# supported from D0 D3hot D3cold
[    0.152460] pci 0000:00:1c.2: PME# disabled
[    0.152630] pci 0000:00:1d.0: reg 20 io port: [0xe400-0xe41f]
[    0.152714] pci 0000:00:1d.1: reg 20 io port: [0xe480-0xe49f]
[    0.152797] pci 0000:00:1d.2: reg 20 io port: [0xe800-0xe81f]
[    0.152880] pci 0000:00:1d.3: reg 20 io port: [0xe880-0xe89f]
[    0.152968] pci 0000:00:1d.7: reg 10 32bit mmio: [0xf7eb7c00-0xf7eb7fff]
[    0.153048] pci 0000:00:1d.7: PME# supported from D0 D3hot D3cold
[    0.153154] pci 0000:00:1d.7: PME# disabled
[    0.153427] pci 0000:00:1f.0: Force enabled HPET at 0xfed00000
[    0.153448] pci 0000:00:1f.0: quirk: region 0800-087f claimed by ICH6 ACPI/GPIO/TCO
[    0.153589] pci 0000:00:1f.0: quirk: region 0480-04bf claimed by ICH6 GPIO
[    0.153698] pci 0000:00:1f.0: LPC Generic IO decode 1 PIO at 0380-03ff
[    0.153856] pci 0000:00:1f.2: reg 10 io port: [0x00-0x07]
[    0.153870] pci 0000:00:1f.2: reg 14 io port: [0x00-0x03]
[    0.153884] pci 0000:00:1f.2: reg 18 io port: [0x00-0x07]
[    0.153898] pci 0000:00:1f.2: reg 1c io port: [0x00-0x03]
[    0.153913] pci 0000:00:1f.2: reg 20 io port: [0xffa0-0xffaf]
[    0.153955] pci 0000:00:1f.2: PME# supported from D3hot
[    0.154057] pci 0000:00:1f.2: PME# disabled
[    0.154217] pci 0000:00:1f.3: reg 20 io port: [0x400-0x41f]
[    0.154409] pci 0000:03:00.0: reg 10 64bit mmio: [0xfbfc0000-0xfbffffff]
[    0.154459] pci 0000:03:00.0: reg 30 32bit mmio pref: [0xfbfa0000-0xfbfbffff]
[    0.154516] pci 0000:03:00.0: PME# supported from D3hot D3cold
[    0.156011] pci 0000:03:00.0: PME# disabled
[    0.156180] pci 0000:00:1c.1: bridge 32bit mmio: [0xfbf00000-0xfbffffff]
[    0.156271] pci 0000:01:00.0: reg 10 64bit mmio: [0xfbef0000-0xfbefffff]
[    0.156446] pci 0000:00:1c.2: bridge 32bit mmio: [0xf8000000-0xfbefffff]
[    0.156461] pci 0000:00:1c.2: bridge 64bit mmio pref: [0xf0000000-0xf6ffffff]
[    0.156536] pci 0000:00:1e.0: transparent bridge
[    0.156670] pci_bus 0000:00: on NUMA node 0
[    0.156692] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[    0.157130] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P3._PRT]
[    0.157287] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P5._PRT]
[    0.157427] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P6._PRT]
[    0.169807] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 *5 6 7 10 11 12 14 15)
[    0.170792] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 10 *11 12 14 15)
[    0.171762] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 *10 11 12 14 15)
[    0.172727] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 *7 10 11 12 14 15)
[    0.173695] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
[    0.174791] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
[    0.175889] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 7 10 11 12 14 15) *0, disabled.
[    0.176994] ACPI: PCI Interrupt Link [LNKH] (IRQs *3 4 5 6 7 10 11 12 14 15)
[    0.177907] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    0.178069] vgaarb: loaded
[    0.178497] SCSI subsystem initialized
[    0.178735] libata version 3.00 loaded.
[    0.178908] PCI: Using ACPI for IRQ routing
[    0.179757] hpet clockevent registered
[    0.179767] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[    0.179882] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.180181] hpet0: 3 comparators, 64-bit 14.318180 MHz counter
[    0.184027] Switching to clocksource tsc
[    0.187552] pnp: PnP ACPI init
[    0.187701] ACPI: bus type pnp registered
[    0.192924] pnp: PnP ACPI: found 13 devices
[    0.193021] ACPI: ACPI bus type pnp unregistered
[    0.193123] PnPBIOS: Disabled by ACPI PNP
[    0.193278] system 00:01: iomem range 0xfed13000-0xfed19fff has been reserved
[    0.193407] system 00:08: ioport range 0x380-0x383 has been reserved
[    0.193514] system 00:08: ioport range 0x4d0-0x4d1 has been reserved
[    0.193620] system 00:08: ioport range 0x800-0x87f has been reserved
[    0.193727] system 00:08: ioport range 0x480-0x4bf has been reserved
[    0.193835] system 00:08: iomem range 0xfed1c000-0xfed1ffff has been reserved
[    0.193945] system 00:08: iomem range 0xfed20000-0xfed8ffff has been reserved
[    0.194056] system 00:08: iomem range 0xfff00000-0xffffffff could not be reserved
[    0.194203] system 00:09: iomem range 0xfec00000-0xfec00fff could not be reserved
[    0.194343] system 00:09: iomem range 0xfee00000-0xfee00fff has been reserved
[    0.194462] system 00:0a: iomem range 0xe0000000-0xefffffff has been reserved
[    0.194579] system 00:0b: iomem range 0xe0000000-0xefffffff has been reserved
[    0.194695] system 00:0c: iomem range 0x0-0x9ffff could not be reserved
[    0.194803] system 00:0c: iomem range 0xc0000-0xcffff could not be reserved
[    0.194914] system 00:0c: iomem range 0xe0000-0xfffff could not be reserved
[    0.195024] system 00:0c: iomem range 0x100000-0x1f7fffff could not be reserved
[    0.231261] pci 0000:00:1c.0: PCI bridge, secondary bus 0000:04
[    0.231369] pci 0000:00:1c.0:   IO window: 0x1000-0x1fff
[    0.231474] pci 0000:00:1c.0:   MEM window: 0x20000000-0x201fffff
[    0.231581] pci 0000:00:1c.0:   PREFETCH window: 0x00000020200000-0x000000203fffff
[    0.231726] pci 0000:00:1c.1: PCI bridge, secondary bus 0000:03
[    0.231842] pci 0000:00:1c.1:   IO window: 0x2000-0x2fff
[    0.231947] pci 0000:00:1c.1:   MEM window: 0xfbf00000-0xfbffffff
[    0.232054] pci 0000:00:1c.1:   PREFETCH window: 0x00000020400000-0x000000205fffff
[    0.232198] pci 0000:00:1c.2: PCI bridge, secondary bus 0000:01
[    0.232303] pci 0000:00:1c.2:   IO window: 0x3000-0x3fff
[    0.232408] pci 0000:00:1c.2:   MEM window: 0xf8000000-0xfbefffff
[    0.232515] pci 0000:00:1c.2:   PREFETCH window: 0x000000f0000000-0x000000f6ffffff
[    0.232659] pci 0000:00:1e.0: PCI bridge, secondary bus 0000:05
[    0.232761] pci 0000:00:1e.0:   IO window: disabled
[    0.232864] pci 0000:00:1e.0:   MEM window: disabled
[    0.232964] pci 0000:00:1e.0:   PREFETCH window: disabled
[    0.233082] pci 0000:00:1c.0: enabling device (0104 -> 0107)
[    0.233201] pci 0000:00:1c.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[    0.233311] pci 0000:00:1c.0: setting latency timer to 64
[    0.233327] pci 0000:00:1c.1: enabling device (0106 -> 0107)
[    0.233434] pci 0000:00:1c.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17
[    0.233542] pci 0000:00:1c.1: setting latency timer to 64
[    0.233557] pci 0000:00:1c.2: enabling device (0106 -> 0107)
[    0.233665] pci 0000:00:1c.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
[    0.233774] pci 0000:00:1c.2: setting latency timer to 64
[    0.233788] pci 0000:00:1e.0: setting latency timer to 64
[    0.233801] pci_bus 0000:00: resource 0 io:  [0x00-0xffff]
[    0.233810] pci_bus 0000:00: resource 1 mem: [0x000000-0xffffffff]
[    0.233820] pci_bus 0000:04: resource 0 io:  [0x1000-0x1fff]
[    0.233829] pci_bus 0000:04: resource 1 mem: [0x20000000-0x201fffff]
[    0.233838] pci_bus 0000:04: resource 2 pref mem [0x20200000-0x203fffff]
[    0.233848] pci_bus 0000:03: resource 0 io:  [0x2000-0x2fff]
[    0.233857] pci_bus 0000:03: resource 1 mem: [0xfbf00000-0xfbffffff]
[    0.233866] pci_bus 0000:03: resource 2 pref mem [0x20400000-0x205fffff]
[    0.233876] pci_bus 0000:01: resource 0 io:  [0x3000-0x3fff]
[    0.233885] pci_bus 0000:01: resource 1 mem: [0xf8000000-0xfbefffff]
[    0.233894] pci_bus 0000:01: resource 2 pref mem [0xf0000000-0xf6ffffff]
[    0.233904] pci_bus 0000:05: resource 3 io:  [0x00-0xffff]
[    0.233913] pci_bus 0000:05: resource 4 mem: [0x000000-0xffffffff]
[    0.234068] NET: Registered protocol family 2
[    0.234411] IP route cache hash table entries: 4096 (order: 2, 16384 bytes)
[    0.235326] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[    0.235726] TCP bind hash table entries: 16384 (order: 5, 131072 bytes)
[    0.236111] TCP: Hash tables configured (established 16384 bind 16384)
[    0.236217] TCP reno registered
[    0.236581] NET: Registered protocol family 1
[    0.236831] Unpacking initramfs...
[    0.487945] Freeing initrd memory: 2980k freed
[    0.493850] audit: initializing netlink socket (disabled)
[    0.494001] type=2000 audit(1266331887.491:1): initialized
[    0.494513] HugeTLB registered 4 MB page size, pre-allocated 0 pages
[    0.494853] VFS: Disk quotas dquot_6.5.2
[    0.495040] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.495352] msgmni has been set to 987
[    0.495949] alg: No test for stdrng (krng)
[    0.496140] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[    0.496280] io scheduler noop registered
[    0.496374] io scheduler anticipatory registered
[    0.496470] io scheduler deadline registered
[    0.496601] io scheduler cfq registered (default)
[    0.496733] pci 0000:00:02.0: Boot video device
[    0.497135] pcieport-driver 0000:00:1c.0: irq 24 for MSI/MSI-X
[    0.497155] pcieport-driver 0000:00:1c.0: setting latency timer to 64
[    0.497400] pcieport-driver 0000:00:1c.1: irq 25 for MSI/MSI-X
[    0.497417] pcieport-driver 0000:00:1c.1: setting latency timer to 64
[    0.497651] pcieport-driver 0000:00:1c.2: irq 26 for MSI/MSI-X
[    0.497667] pcieport-driver 0000:00:1c.2: setting latency timer to 64
[    0.498101] isapnp: Scanning for PnP cards...
[    0.851963] isapnp: No Plug & Play device found
[    0.855293] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.856555] PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12
[    0.877617] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.877727] serio: i8042 AUX port at 0x60,0x64 irq 12
[    0.878071] mice: PS/2 mouse device common for all mice
[    0.878930] rtc_cmos 00:03: RTC can wake from S4
[    0.879121] rtc_cmos 00:03: rtc core: registered rtc_cmos as rtc0
[    0.879260] rtc0: alarms up to one month, 114 bytes nvram, hpet irqs
[    0.879396] cpuidle: using governor ladder
[    0.879490] cpuidle: using governor menu
[    0.879589] No iBFT detected.
[    0.880694] TCP cubic registered
[    0.880872] NET: Registered protocol family 10
[    0.882240] lo: Disabled Privacy Extensions
[    0.883269] Mobile IPv6
[    0.883360] NET: Registered protocol family 17
[    0.883500] Using IPI No-Shortcut mode
[    0.898223] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[    0.917824] registered taskstats version 1
[    0.918510] rtc_cmos 00:03: setting system clock to 2010-02-16 14:51:28 UTC (1266331888)
[    0.918806] Freeing unused kernel memory: 356k freed
[    0.919389] Write protecting the kernel text: 2392k
[    0.919536] Write protecting the kernel read-only data: 928k
[    1.003758] ata_piix 0000:00:1f.2: version 2.13
[    1.003815] ata_piix 0000:00:1f.2: PCI INT B -> GSI 19 (level, low) -> IRQ 19
[    1.003986] ata_piix 0000:00:1f.2: MAP [ P0 P2 IDE IDE ]
[    1.004358] ata_piix 0000:00:1f.2: setting latency timer to 64
[    1.004540] scsi0 : ata_piix
[    1.004886] scsi1 : ata_piix
[    1.009412] ata1: SATA max UDMA/133 cmd 0x1f0 ctl 0x3f6 bmdma 0xffa0 irq 14
[    1.009523] ata2: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0xffa8 irq 15
[    1.103840] Switched to high resolution mode on CPU 0
[    1.180377] ata2.00: CFA: SILICONMOTION SM223AC, , max UDMA/66
[    1.180483] ata2.00: 7815024 sectors, multi 0: LBA 
[    1.196318] ata2.00: configured for UDMA/66
[    1.196698] scsi 1:0:0:0: Direct-Access     ATA      SILICONMOTION SM n/a  PQ: 0 ANSI: 5
[    1.249334] ACPI: EC: non-query interrupt received, switching to interrupt mode
[    1.269516] thermal LNXTHERM:01: registered as thermal_zone0
[    1.269638] ACPI: Thermal Zone [TZ00] (52 C)
[    1.624225] sd 1:0:0:0: [sda] 7815024 512-byte logical blocks: (4.00 GB/3.72 GiB)
[    1.624505] sd 1:0:0:0: [sda] Write Protect is off
[    1.624606] sd 1:0:0:0: [sda] Mode Sense: 00 3a 00 00
[    1.624682] sd 1:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
[    1.625201]  sda: sda1 sda2
[    1.627406] sd 1:0:0:0: [sda] Attached SCSI disk
[    1.726773] PM: Starting manual resume from disk
[    1.830772] EXT4-fs (sda2): delayed allocation enabled
[    1.830974] EXT4-fs: file extents enabled
[    1.831247] EXT4-fs: mballoc enabled
[    1.831377] EXT4-fs (sda2): mounted filesystem without journal
[   33.244766] EXT4-fs (sda2): warning: mounting unchecked fs, running e2fsck is recommended
[   33.246093] EXT4-fs (sda2): no journal
[   66.496623] Adding 358392k swap on /swapfile.  Priority:-1 extents:48 across:1567744k 
[   68.432097] Syncing filesystems ... done.
[   68.436096] Freezing user space processes ... (elapsed 0.00 seconds) done.
[   68.436299] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[   68.436696] PM: Preallocating image memory... done (allocated 122032 pages)
[   68.693244] PM: Allocated 488128 kbytes in 0.25 seconds (1952.51 MB/s)
[   68.693835] ata_piix 0000:00:1f.2: PCI INT B disabled
[   68.695106] Disabling non-boot CPUs ...
[   68.695379] PM: Creating hibernation image: 
[   68.696008] PM: Need to copy 5137 pages
[   68.696008] PM: Hibernation image created (5137 pages copied)
[   68.696008] CPU0: Thermal LVT vector (0xfa) already installed
[   68.696008] Force enabled HPET at resume
[   68.703403] pci 0000:00:1e.0: setting latency timer to 64
[   68.703526] ata_piix 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00001, writing 0x2b00005)
[   68.703638] ata_piix 0000:00:1f.2: PCI INT B -> GSI 19 (level, low) -> IRQ 19
[   68.703721] ata_piix 0000:00:1f.2: setting latency timer to 64
[   68.710309] sd 1:0:0:0: [sda] Starting disk
[   68.876379] ata2.00: ACPI cmd ef/03:44:00:00:00:a0 (SET FEATURES) filtered out
[   68.876465] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out
[   68.892319] ata2.00: configured for UDMA/66
[   68.978249] Restarting tasks ... done.
[   70.404093] Syncing filesystems ... done.
[   70.408090] Freezing user space processes ... (elapsed 0.00 seconds) done.
[   70.408300] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done.
[   70.408689] PM: Preallocating image memory... done (allocated 123273 pages)
[   71.325465] PM: Allocated 493092 kbytes in 0.91 seconds (541.85 MB/s)
[   71.326053] ata_piix 0000:00:1f.2: PCI INT B disabled
[   71.327436] kthreadd: page allocation failure. order:1, mode:0xd0
[   71.327519] Pid: 2, comm: kthreadd Not tainted 2.6.31eeepc #48
[   71.327592] Call Trace:
[   71.327671]  [<c10848f4>] ? __alloc_pages_nodemask+0x423/0x490
[   71.327755]  [<c103fb65>] ? kthread+0x0/0x64
[   71.327826]  [<c108496d>] ? __get_free_pages+0xc/0x17
[   71.327904]  [<c102d74f>] ? copy_process+0x99/0xf35
[   71.327978]  [<c103fb65>] ? kthread+0x0/0x64
[   71.328084]  [<c102e71f>] ? do_fork+0x134/0x297
[   71.328158]  [<c1001e00>] ? __switch_to+0x6f/0xe2
[   71.328231]  [<c103fb65>] ? kthread+0x0/0x64
[   71.328301]  [<c1001d89>] ? kernel_thread+0x7a/0x82
[   71.328374]  [<c103fb65>] ? kthread+0x0/0x64
[   71.328447]  [<c1003be0>] ? kernel_thread_helper+0x0/0x10
[   71.328523]  [<c103fb36>] ? kthreadd+0x8a/0xb9
[   71.328593]  [<c103faac>] ? kthreadd+0x0/0xb9
[   71.328665]  [<c1003be7>] ? kernel_thread_helper+0x7/0x10
[   71.328765] Mem-Info:
[   71.328851] DMA per-cpu:
[   71.328939] CPU    0: hi:    0, btch:   1 usd:   0
[   71.329036] Normal per-cpu:
[   71.329127] CPU    0: hi:  186, btch:  31 usd:   0
[   71.329236] active_anon:0 inactive_anon:0 isolated_anon:0
[   71.329240]  active_file:0 inactive_file:10 isolated_file:0
[   71.329244]  unevictable:1058 dirty:0 writeback:0 unstable:0 buffer:10
[   71.329249]  free:1267 slab_reclaimable:189 slab_unreclaimable:430
[   71.329253]  mapped:931 shmem:0 pagetables:9 bounce:0
[   71.329745] DMA free:2012kB min:88kB low:108kB high:132kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15804kB mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[   71.330118] lowmem_reserve[]: 0 483 483 483
[   71.330454] Normal free:3056kB min:2764kB low:3452kB high:4144kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:40kB unevictable:4232kB isolated(anon):0kB isolated(file):0kB present:495300kB mlocked:4232kB dirty:0kB writeback:0kB mapped:3724kB shmem:0kB slab_reclaimable:756kB slab_unreclaimable:1720kB kernel_stack:232kB pagetables:36kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[   71.330835] lowmem_reserve[]: 0 0 0 0
[   71.331154] DMA: 1*4kB 1*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 2012kB
[   71.331854] Normal: 26*4kB 9*8kB 4*16kB 0*32kB 0*64kB 0*128kB 1*256kB 1*512kB 0*1024kB 1*2048kB 0*4096kB = 3056kB
[   71.332569] 940 total pagecache pages
[   71.332661] 0 pages in swap cache
[   71.332752] Swap cache stats: add 91, delete 91, find 0/0
[   71.332851] Free swap  = 358028kB
[   71.332941] Total swap = 358392kB
[   71.338499] 128880 pages RAM
[   71.338591] 0 pages HighMem
[   71.338679] 2332 pages reserved
[   71.338767] 1079 pages shared
[   71.338855] 124261 pages non-shared
[   71.433571] pci 0000:00:1e.0: setting latency timer to 64
[   71.433731] ata_piix 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00001, writing 0x2b00005)
[   71.433903] ata_piix 0000:00:1f.2: PCI INT B -> GSI 19 (level, low) -> IRQ 19
[   71.434013] ata_piix 0000:00:1f.2: setting latency timer to 64
[   71.440327] sd 1:0:0:0: [sda] Starting disk
[   71.612382] ata2.00: ACPI cmd ef/03:44:00:00:00:a0 (SET FEATURES) filtered out
[   71.612526] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out
[   71.628319] ata2.00: configured for UDMA/66
[   71.629042] Restarting tasks ... done.

[-- Attachment #3: Type: text/plain, Size: 0 bytes --]