FAQ Search Today's Posts Mark Forums Read
» Video Reviews

» Linux Archive

Linux-archive is a website aiming to archive linux email lists and to make them easily accessible for linux users/developers.


» Sponsor

» Partners

» Sponsor

Go Back   Linux Archive > Debian > Debian Kernel

 
 
LinkBack Thread Tools
 
Old 11-17-2010, 05:44 PM
Frederik Himpe
 
Default Bug#603835: linux-image-2.6.32-5-amd64: page allocation failures while transferring data with virtio

Package: linux-2.6
Version: 2.6.32-27
Severity: important

I've got a KVM virtual machine which is using bacula-fd to back-up its
files to another virtual machine.

Regularly, while back-ing up, backtraces start appearing in the kernel
logs and bacula-fd starts eating up a huge amount of memory.

# ps aux | grep bacula-fd
root 1054 0.0 0.0 7544 824 pts/1 S+ 15:44 0:00 grep bacula-fd
root 1295 0.1 31.3 875424 322184 ? Ssl Nov11 14:53 /usr/sbin/bacula-fd -c /etc/bacula/bacula-fd.conf

I am using virtio in the guest, qemu-kvm 0.12.5 and recent Linux 2.6.32
kernel (both in host and guest) from Debian Squeeze.

It has already occurred that networking was totally broken in the guest
after these page allocation failures, and I needed a reboot to bring the
guest back online. This does not happen every time though.

The guest is started by libvirt with this command:
LC_ALL=C PATH=/usr/local/sbin:/usr/local/bin:/sbin:/bin:/usr/sbin:/usr/bin HOME=/root USER=root LOGNAME=root QEMU_AUDIO_DRV=none /usr/bin/kvm -S -M pc-0.12 -enable-kvm -m 1024 -smp 1,sockets=1,cores=1,threads=1 -name ai-file -uuid 82de86ee-f606-5a37-3053-7620567198a3 -nodefaults -chardev socket,id=monitor,path=/var/lib/libvirt/qemu/ai-file.monitor,server,nowait -mon chardev=monitor,mode=readline -rtc base=utc -boot cd -drive file=/dev/linux/ai-file.img,if=none,id=drive-virtio-disk0,boot=on,format=raw -device virtio-blk-pci,bus=pci.0,addr=0x4,drive=drive-virtio-disk0,id=virtio-disk0 -drive file=/var/lib/libvirt/images/systemrescuecd-x86-1.6.3.iso,if=none,media=cdrom,id=drive-ide0-1-0,readonly=on,format=raw -device ide-drive,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -device virtio-ne
t-pci,vlan=0,id=net0,mac=54:52:00:73:05:69,bus=pci.0 ,addr=0x3 -net tap,fd=42,vlan=0,name=hostnet0 -chardev pty,id=serial0 -device isa-serial,chardev=serial0 -usb -vnc 127.0.0.1:1 -k fr-be -vga cirrus -device virtio-balloon-pci,id=balloon0,bus=pci.0,addr=0x5

This might be the same as
http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=576838 , however that
bug was closed as fixed.

Ubuntu has this similar bug:
https://bugs.launchpad.net/ubuntu/+source/linux/+bug/579276
which is supposed to be fixed by
http://git.kernel.org/?p=linux/kernel/git/torvalds/linux-2.6.git;a=commitdiff;h=8eae939f1400326b06d0c9afe53 d2a484a326871

[ 0.000000] Initializing cgroup subsys cpuset
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Linux version 2.6.32-5-amd64 (Debian 2.6.32-27) (maks@debian.org) (gcc version 4.3.5 (Debian 4.3.5-4) ) #1 SMP Sat Oct 30 14:18:21 UTC 2010
[ 0.000000] Command line: BOOT_IMAGE=/vmlinuz-2.6.32-5-amd64 root=/dev/mapper/linux-root ro quiet
[ 0.000000] KERNEL supported cpus:
[ 0.000000] Intel GenuineIntel
[ 0.000000] AMD AuthenticAMD
[ 0.000000] Centaur CentaurHauls
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: 0000000000000000 - 000000000009f000 (usable)
[ 0.000000] BIOS-e820: 000000000009f000 - 00000000000a0000 (reserved)
[ 0.000000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
[ 0.000000] BIOS-e820: 0000000000100000 - 000000003fffb000 (usable)
[ 0.000000] BIOS-e820: 000000003fffb000 - 0000000040000000 (reserved)
[ 0.000000] BIOS-e820: 00000000fffbc000 - 0000000100000000 (reserved)
[ 0.000000] DMI 2.4 present.
[ 0.000000] last_pfn = 0x3fffb max_arch_pfn = 0x400000000
[ 0.000000] MTRR default type: write-back
[ 0.000000] MTRR fixed ranges enabled:
[ 0.000000] 00000-9FFFF write-back
[ 0.000000] A0000-BFFFF uncachable
[ 0.000000] C0000-FFFFF write-protect
[ 0.000000] MTRR variable ranges enabled:
[ 0.000000] 0 base 00E0000000 mask FFE0000000 uncachable
[ 0.000000] 1 disabled
[ 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] x86 PAT enabled: cpu 0, old 0x0, new 0x7010600070106
[ 0.000000] initial memory mapped : 0 - 20000000
[ 0.000000] init_memory_mapping: 0000000000000000-000000003fffb000
[ 0.000000] 0000000000 - 003fe00000 page 2M
[ 0.000000] 003fe00000 - 003fffb000 page 4k
[ 0.000000] kernel direct mapping tables up to 3fffb000 @ 8000-b000
[ 0.000000] RAMDISK: 2f6a9000 - 3003c279
[ 0.000000] ACPI: RSDP 00000000000f8840 00014 (v00 BOCHS )
[ 0.000000] ACPI: RSDT 000000003fffde30 00034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: FACP 000000003ffffe70 00074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001)
[ 0.000000] ACPI: DSDT 000000003fffdfd0 01E22 (v01 BXPC BXDSDT 00000001 INTL 20090123)
[ 0.000000] ACPI: FACS 000000003ffffe00 00040
[ 0.000000] ACPI: SSDT 000000003fffdf90 00037 (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: APIC 000000003fffdeb0 00072 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001)
[ 0.000000] ACPI: HPET 000000003fffde70 00038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001)
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] No NUMA configuration found
[ 0.000000] Faking a node at 0000000000000000-000000003fffb000
[ 0.000000] Bootmem setup node 0 0000000000000000-000000003fffb000
[ 0.000000] NODE_DATA [0000000000009000 - 0000000000010fff]
[ 0.000000] bootmap [0000000000011000 - 0000000000018fff] pages 8
[ 0.000000] (7 early reservations) ==> bootmem [0000000000 - 003fffb000]
[ 0.000000] #0 [0000000000 - 0000001000] BIOS data page ==> [0000000000 - 0000001000]
[ 0.000000] #1 [0000006000 - 0000008000] TRAMPOLINE ==> [0000006000 - 0000008000]
[ 0.000000] #2 [0001000000 - 0001688414] TEXT DATA BSS ==> [0001000000 - 0001688414]
[ 0.000000] #3 [002f6a9000 - 003003c279] RAMDISK ==> [002f6a9000 - 003003c279]
[ 0.000000] #4 [000009f000 - 0000100000] BIOS reserved ==> [000009f000 - 0000100000]
[ 0.000000] #5 [0001689000 - 0001689071] BRK ==> [0001689000 - 0001689071]
[ 0.000000] #6 [0000008000 - 0000009000] PGTABLE ==> [0000008000 - 0000009000]
[ 0.000000] found SMP MP-table at [ffff8800000f8890] f8890
[ 0.000000] kvm-clock: cpu 0, msr 0:14f1701, boot clock
[ 0.000000] [ffffea0000000000-ffffea0000dfffff] PMD -> [ffff880001c00000-ffff8800029fffff] on node 0
[ 0.000000] Zone PFN ranges:
[ 0.000000] DMA 0x00000000 -> 0x00001000
[ 0.000000] DMA32 0x00001000 -> 0x00100000
[ 0.000000] Normal 0x00100000 -> 0x00100000
[ 0.000000] Movable zone start PFN for each node
[ 0.000000] early_node_map[2] active PFN ranges
[ 0.000000] 0: 0x00000000 -> 0x0000009f
[ 0.000000] 0: 0x00000100 -> 0x0003fffb
[ 0.000000] On node 0 totalpages: 262042
[ 0.000000] DMA zone: 56 pages used for memmap
[ 0.000000] DMA zone: 101 pages reserved
[ 0.000000] DMA zone: 3842 pages, LIFO batch:0
[ 0.000000] DMA32 zone: 3528 pages used for memmap
[ 0.000000] DMA32 zone: 254515 pages, LIFO batch:31
[ 0.000000] ACPI: PM-Timer IO Port: 0xb008
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[ 0.000000] ACPI: IOAPIC (id[0x01] address[0xfec00000] gsi_base[0])
[ 0.000000] IOAPIC[0]: apic_id 1, version 17, 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 5 global_irq 5 high level)
[ 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 10 global_irq 10 high level)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[ 0.000000] ACPI: IRQ0 used by override.
[ 0.000000] ACPI: IRQ2 used by override.
[ 0.000000] ACPI: IRQ5 used by override.
[ 0.000000] ACPI: IRQ9 used by override.
[ 0.000000] ACPI: IRQ10 used by override.
[ 0.000000] ACPI: IRQ11 used by override.
[ 0.000000] Using ACPI (MADT) for SMP configuration information
[ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[ 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 - 00000000000f0000
[ 0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000000100000
[ 0.000000] Allocating PCI resources starting at 40000000 (gap: 40000000:bffbc000)
[ 0.000000] Booting paravirtualized kernel on KVM
[ 0.000000] NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:1 nr_node_ids:1
[ 0.000000] PERCPU: Embedded 30 pages/cpu @ffff880001800000 s90328 r8192 d24360 u2097152
[ 0.000000] pcpu-alloc: s90328 r8192 d24360 u2097152 alloc=1*2097152
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] kvm-clock: cpu 0, msr 0:1815701, primary cpu clock
[ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 258357
[ 0.000000] Policy zone: DMA32
[ 0.000000] Kernel command line: BOOT_IMAGE=/vmlinuz-2.6.32-5-amd64 root=/dev/mapper/linux-root ro quiet
[ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[ 0.000000] Initializing CPU#0
[ 0.000000] Checking aperture...
[ 0.000000] No AGP bridge found
[ 0.000000] Calgary: detecting Calgary via BIOS EBDA area
[ 0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing!
[ 0.000000] Memory: 1016884k/1048556k available (3077k kernel code, 388k absent, 31284k reserved, 1893k data, 584k init)
[ 0.000000] SLUB: Genslabs=14, HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] Hierarchical RCU implementation.
[ 0.000000] NR_IRQS:4352 nr_irqs:256
[ 0.000000] Console: colour VGA+ 80x25
[ 0.000000] console [tty0] enabled
[ 0.000000] hpet clockevent registered
[ 0.000000] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[ 0.000000] Detected 2294.188 MHz processor.
[ 0.024001] Calibrating delay loop (skipped) preset value.. 4588.37 BogoMIPS (lpj=9176752)
[ 0.024001] Security Framework initialized
[ 0.024001] SELinux: Disabled at boot.
[ 0.024001] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[ 0.024001] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[ 0.024001] Mount-cache hash table entries: 256
[ 0.024001] Initializing cgroup subsys ns
[ 0.024001] Initializing cgroup subsys cpuacct
[ 0.024001] Initializing cgroup subsys devices
[ 0.024001] Initializing cgroup subsys freezer
[ 0.024001] Initializing cgroup subsys net_cls
[ 0.024001] CPU: L1 I Cache: 64K (64 bytes/line), D cache 64K (64 bytes/line)
[ 0.024001] CPU: L2 Cache: 512K (64 bytes/line)
[ 0.024001] CPU 0/0x0 -> Node 0
[ 0.024001] mce: CPU supports 10 MCE banks
[ 0.024001] Performance Events: AMD PMU driver.
[ 0.024001] ... version: 0
[ 0.024001] ... bit width: 48
[ 0.024001] ... generic registers: 4
[ 0.024001] ... value mask: 0000ffffffffffff
[ 0.024001] ... max period: 00007fffffffffff
[ 0.024001] ... fixed-purpose events: 0
[ 0.024001] ... event mask: 000000000000000f
[ 0.024001] SMP alternatives: switching to UP code
[ 0.044993] Freeing SMP alternatives: 28k freed
[ 0.045032] ACPI: Core revision 20090903
[ 0.046907] Setting APIC routing to flat
[ 0.049168] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.049171] CPU0: AMD QEMU Virtual CPU version 0.12.5 stepping 03
[ 0.052001] Brought up 1 CPUs
[ 0.052001] Total of 1 processors activated (4588.37 BogoMIPS).
[ 0.052001] CPU0 attaching NULL sched-domain.
[ 0.052001] devtmpfs: initialized
[ 0.052001] regulator: core version 0.5
[ 0.052001] NET: Registered protocol family 16
[ 0.052001] ACPI: bus type pci registered
[ 0.052001] PCI: Using configuration type 1 for base access
[ 0.052193] bio: create slab <bio-0> at 0
[ 0.052606] ACPI: EC: Look up EC in DSDT
[ 0.054608] ACPI: Interpreter enabled
[ 0.054611] ACPI: (supports S0 S3 S4 S5)
[ 0.054626] ACPI: Using IOAPIC for interrupt routing
[ 0.056685] ACPI: No dock devices found.
[ 0.056700] ACPI: PCI Root Bridge [PCI0] (0000:00)
[ 0.057331] pci 0000:00:01.1: reg 20 io port: [0xc000-0xc00f]
[ 0.057654] pci 0000:00:01.2: reg 20 io port: [0xc020-0xc03f]
[ 0.057976] pci 0000:00:01.3: quirk: region b000-b03f claimed by PIIX4 ACPI
[ 0.057984] pci 0000:00:01.3: quirk: region b100-b10f claimed by PIIX4 SMB
[ 0.060643] pci 0000:00:02.0: reg 10 32bit mmio pref: [0xf0000000-0xf1ffffff]
[ 0.061093] pci 0000:00:02.0: reg 14 32bit mmio: [0xf2000000-0xf2000fff]
[ 0.063078] pci 0000:00:02.0: reg 30 32bit mmio pref: [0xf2010000-0xf201ffff]
[ 0.063388] pci 0000:00:03.0: reg 10 io port: [0xc040-0xc05f]
[ 0.063423] pci 0000:00:03.0: reg 14 32bit mmio: [0xf2020000-0xf2020fff]
[ 0.063532] pci 0000:00:03.0: reg 30 32bit mmio pref: [0xf2028000-0xf202ffff]
[ 0.063789] pci 0000:00:04.0: reg 10 io port: [0xc080-0xc0bf]
[ 0.063821] pci 0000:00:04.0: reg 14 32bit mmio: [0xf2030000-0xf2030fff]
[ 0.064327] pci 0000:00:05.0: reg 10 io port: [0xc0c0-0xc0df]
[ 0.064723] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[ 0.067621] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[ 0.067733] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[ 0.067818] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[ 0.068012] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[ 0.068272] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks= none
[ 0.068276] vgaarb: loaded
[ 0.068458] PCI: Using ACPI for IRQ routing
[ 0.068820] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[ 0.068826] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[ 0.076050] Switching to clocksource kvm-clock
[ 0.077809] pnp: PnP ACPI init
[ 0.077823] ACPI: bus type pnp registered
[ 0.078465] pnp: PnP ACPI: found 6 devices
[ 0.078467] ACPI: ACPI bus type pnp unregistered
[ 0.083139] pci_bus 0000:00: resource 0 io: [0x00-0xffff]
[ 0.083142] pci_bus 0000:00: resource 1 mem: [0x000000-0xffffffffffffffff]
[ 0.083185] NET: Registered protocol family 2
[ 0.083308] IP route cache hash table entries: 32768 (order: 6, 262144 bytes)
[ 0.083925] TCP established hash table entries: 131072 (order: 9, 2097152 bytes)
[ 0.085931] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[ 0.087104] TCP: Hash tables configured (established 131072 bind 65536)
[ 0.087107] TCP reno registered
[ 0.087195] NET: Registered protocol family 1
[ 0.087215] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 0.087231] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[ 0.087247] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[ 0.087297] pci 0000:00:02.0: Boot video device
[ 0.087361] Unpacking initramfs...
[ 0.318392] Freeing initrd memory: 9804k freed
[ 0.321909] audit: initializing netlink socket (disabled)
[ 0.321929] type=2000 audit(1289486306.320:1): initialized
[ 0.325750] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[ 0.326994] VFS: Disk quotas dquot_6.5.2
[ 0.327054] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 0.327135] msgmni has been set to 2005
[ 0.327389] alg: No test for stdrng (krng)
[ 0.327430] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[ 0.327435] io scheduler noop registered
[ 0.327437] io scheduler anticipatory registered
[ 0.327438] io scheduler deadline registered
[ 0.327467] io scheduler cfq registered (default)
[ 0.328861] Linux agpgart interface v0.103
[ 0.328898] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 0.329153] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[ 0.329445] input: Macintosh mouse button emulation as /devices/virtual/input/input0
[ 0.329496] PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[ 0.330083] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 0.330089] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 0.330206] mice: PS/2 mouse device common for all mice
[ 0.330497] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[ 0.330750] rtc_cmos 00:01: rtc core: registered rtc_cmos as rtc0
[ 0.330853] rtc0: alarms up to one day, 114 bytes nvram, hpet irqs
[ 0.330866] cpuidle: using governor ladder
[ 0.330868] cpuidle: using governor menu
[ 0.330874] No iBFT detected.
[ 0.331242] TCP cubic registered
[ 0.331350] NET: Registered protocol family 10
[ 0.331889] lo: Disabled Privacy Extensions
[ 0.332209] Mobile IPv6
[ 0.332214] NET: Registered protocol family 17
[ 0.332323] PM: Resume from disk failed.
[ 0.332330] registered taskstats version 1
[ 0.332617] rtc_cmos 00:01: setting system clock to 2010-11-11 14:38:25 UTC (1289486305)
[ 0.332640] Initalizing network drop monitor service
[ 0.332678] Freeing unused kernel memory: 584k freed
[ 0.333011] Write protecting the kernel read-only data: 4232k
[ 0.352199] udev[46]: starting version 163
[ 0.484699] SCSI subsystem initialized
[ 0.502908] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[ 0.502931] virtio-pci 0000:00:03.0: PCI INT A -> Link[LNKC] -> GSI 11 (level, high) -> IRQ 11
[ 0.503361] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10
[ 0.503385] virtio-pci 0000:00:04.0: PCI INT A -> Link[LNKD] -> GSI 10 (level, high) -> IRQ 10
[ 0.503789] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
[ 0.503798] virtio-pci 0000:00:05.0: PCI INT A -> Link[LNKA] -> GSI 10 (level, high) -> IRQ 10
[ 0.506124] usbcore: registered new interface driver usbfs
[ 0.506225] usbcore: registered new interface driver hub
[ 0.507056] usbcore: registered new device driver usb
[ 0.509654] alloc irq_desc for 24 on node -1
[ 0.509656] alloc kstat_irqs on node -1
[ 0.509682] virtio-pci 0000:00:04.0: irq 24 for MSI/MSI-X
[ 0.509684] alloc irq_desc for 25 on node -1
[ 0.509686] alloc kstat_irqs on node -1
[ 0.509702] virtio-pci 0000:00:04.0: irq 25 for MSI/MSI-X
[ 0.510188] vda:
[ 0.513774] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 0.515092] uhci_hcd: USB Universal Host Controller Interface driver
[ 0.515135] uhci_hcd 0000:00:01.2: PCI INT D -> Link[LNKD] -> GSI 10 (level, high) -> IRQ 10
[ 0.515157] uhci_hcd 0000:00:01.2: setting latency timer to 64
[ 0.515165] uhci_hcd 0000:00:01.2: UHCI Host Controller
[ 0.515191] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
[ 0.515279] uhci_hcd 0000:00:01.2: irq 10, io base 0x0000c020
[ 0.515336] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001
[ 0.515338] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.515340] usb usb1: Product: UHCI Host Controller
[ 0.515341] usb usb1: Manufacturer: Linux 2.6.32-5-amd64 uhci_hcd
[ 0.515343] usb usb1: SerialNumber: 0000:00:01.2
[ 0.515401] usb usb1: configuration #1 chosen from 1 choice
[ 0.515425] hub 1-0:1.0: USB hub found
[ 0.515431] hub 1-0:1.0: 2 ports detected
[ 0.516371] FDC 0 is a S82078B
[ 0.517346] vda1 vda2 < vda5 vda6 vda7 >
[ 0.518929] alloc irq_desc for 26 on node -1
[ 0.518932] alloc kstat_irqs on node -1
[ 0.518956] virtio-pci 0000:00:03.0: irq 26 for MSI/MSI-X
[ 0.518958] alloc irq_desc for 27 on node -1
[ 0.518960] alloc kstat_irqs on node -1
[ 0.518976] virtio-pci 0000:00:03.0: irq 27 for MSI/MSI-X
[ 0.518978] alloc irq_desc for 28 on node -1
[ 0.518979] alloc kstat_irqs on node -1
[ 0.518995] virtio-pci 0000:00:03.0: irq 28 for MSI/MSI-X
[ 0.523552] libata version 3.00 loaded.
[ 0.525770] ata_piix 0000:00:01.1: version 2.13
[ 0.525837] ata_piix 0000:00:01.1: setting latency timer to 64
[ 0.525971] scsi0 : ata_piix
[ 0.526090] scsi1 : ata_piix
[ 0.526116] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc000 irq 14
[ 0.526118] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc008 irq 15
[ 0.681203] ata2.01: NODEV after polling detection
[ 0.681696] ata2.00: ATAPI: QEMU DVD-ROM, 0.12.5, max UDMA/100
[ 0.682639] ata2.00: configured for MWDMA2
[ 0.683636] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 0.12 PQ: 0 ANSI: 5
[ 0.704905] sr0: scsi3-mmc drive: 4x/4x xa/form2 tray
[ 0.704913] Uniform CD-ROM driver Revision: 3.20
[ 0.705660] sr 1:0:0:0: Attached scsi CD-ROM sr0
[ 0.728384] sr 1:0:0:0: Attached scsi generic sg0 type 5
[ 1.009511] device-mapper: uevent: version 1.0.3
[ 1.010490] device-mapper: ioctl: 4.15.0-ioctl (2009-04-01) initialised: dm-devel@redhat.com
[ 1.325731] PM: Starting manual resume from disk
[ 1.325735] PM: Resume from partition 253:1
[ 1.325736] PM: Checking hibernation image.
[ 1.325896] PM: Error -22 checking image file
[ 1.325897] PM: Resume from disk failed.
[ 1.349195] EXT4-fs (dm-0): mounted filesystem with ordered data mode
[ 2.378193] udev[280]: starting version 163
[ 3.103941] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
[ 3.104050] ACPI: Power Button [PWRF]
[ 3.117332] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0
[ 3.125068] processor LNXCPU:00: registered as cooling_device0
[ 3.274572] input: PC Speaker as /devices/platform/pcspkr/input/input3
[ 3.283026] Error: Driver 'pcspkr' is already registered, aborting...
[ 3.390764] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input4
[ 3.918183] loop: module loaded
[ 4.551033] Adding 1949688k swap on /dev/mapper/linux-swap. Priority:-1 extents:1 across:1949688k
[ 5.745818] kjournald starting. Commit interval 5 seconds
[ 5.746173] EXT3 FS on vda1, internal journal
[ 5.746182] EXT3-fs: mounted filesystem with ordered data mode.
[ 5.773950] EXT4-fs (dm-3): mounted filesystem with ordered data mode
[ 5.776104] EXT4-fs (dm-2): mounted filesystem with ordered data mode
[ 5.793957] EXT4-fs (dm-4): mounted filesystem with ordered data mode
[ 6.020171] Clocksource tsc unstable (delta = -96831035 ns)
[ 9.338099] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 10.143131] Netfilter messages via NETLINK v0.30.
[ 10.180372] nf_conntrack version 0.5.0 (8025 buckets, 32100 max)
[ 10.181748] CONFIG_NF_CT_ACCT is deprecated and will be removed soon. Please use
[ 10.181754] nf_conntrack.acct=1 kernel parameter, acct=1 nf_conntrack module option or
[ 10.181760] sysctl net.netfilter.nf_conntrack_acct=1 to enable it.
[ 10.525678] NF_TPROXY: Transparent proxy support initialized, version 4.1.0
[ 10.525685] NF_TPROXY: Copyright (c) 2006-2007 BalaBit IT Ltd.
[ 10.579481] ctnetlink v0.93: registering with nfnetlink.
[ 10.750559] ClusterIP Version 0.8 loaded successfully
[ 12.982746] xt_time: kernel timezone is -0000
[ 13.744315] u32 classifier
[ 13.744321] Performance counters on
[ 13.744325] input device check on
[ 13.744329] Actions configured
[ 14.366740] RPC: Registered udp transport module.
[ 14.366743] RPC: Registered tcp transport module.
[ 14.366745] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 14.453906] Slow work thread pool: Starting up
[ 14.453994] Slow work thread pool: Ready
[ 14.454134] FS-Cache: Loaded
[ 14.593936] FS-Cache: Netfs 'nfs' registered for caching
[ 14.659679] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[ 17.037526] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory
[ 17.037756] NFSD: starting 90-second grace period
[ 17.952167] eth0: no IPv6 routers present
[471149.337888] swapper: page allocation failure. order:0, mode:0x20
[471149.337901] Pid: 0, comm: swapper Not tainted 2.6.32-5-amd64 #1
[471149.337907] Call Trace:
[471149.337912] <IRQ> [<ffffffff810ba26e>] ? __alloc_pages_nodemask+0x592/0x5f4
[471149.338021] [<ffffffff81248bfc>] ? __netdev_alloc_skb+0x29/0x45
[471149.338031] [<ffffffff81247c29>] ? __alloc_skb+0x69/0x15a
[471149.338111] [<ffffffffa00dee4b>] ? try_fill_recv+0x8b/0x18b [virtio_net]
[471149.338129] [<ffffffffa00df8bf>] ? virtnet_poll+0x543/0x5c8 [virtio_net]
[471149.338147] [<ffffffff8124ed7b>] ? net_rx_action+0xae/0x1c9
[471149.338181] [<ffffffff81053acf>] ? __do_softirq+0xdd/0x1a2
[471149.338196] [<ffffffffa00de153>] ? skb_recv_done+0x28/0x34 [virtio_net]
[471149.338223] [<ffffffff81011cac>] ? call_softirq+0x1c/0x30
[471149.338231] [<ffffffff8101322b>] ? do_softirq+0x3f/0x7c
[471149.338240] [<ffffffff8105393e>] ? irq_exit+0x36/0x76
[471149.338247] [<ffffffff81012922>] ? do_IRQ+0xa0/0xb6
[471149.338254] [<ffffffff810114d3>] ? ret_from_intr+0x0/0x11
[471149.338259] <EOI> [<ffffffff8102c580>] ? native_safe_halt+0x2/0x3
[471149.338281] [<ffffffff81017205>] ? default_idle+0x34/0x51
[471149.338290] [<ffffffff8100feb1>] ? cpu_idle+0xa2/0xda
[471149.338323] [<ffffffff814f3140>] ? early_idt_handler+0x0/0x71
[471149.338342] [<ffffffff814f3cd1>] ? start_kernel+0x3dc/0x3e8
[471149.338351] [<ffffffff814f33b7>] ? x86_64_start_kernel+0xf9/0x106
[471149.338357] Mem-Info:
[471149.338362] Node 0 DMA per-cpu:
[471149.338376] CPU 0: hi: 0, btch: 1 usd: 0
[471149.338380] Node 0 DMA32 per-cpu:
[471149.338386] CPU 0: hi: 186, btch: 31 usd: 30
[471149.338398] active_anon:97258 inactive_anon:97314 isolated_anon:0
[471149.338401] active_file:18114 inactive_file:17158 isolated_file:0
[471149.338404] unevictable:8 dirty:14 writeback:0 unstable:0
[471149.338407] free:1361 slab_reclaimable:17576 slab_unreclaimable:2440
[471149.338442] mapped:1439 shmem:0 pagetables:1072 bounce:0
[471149.338451] Node 0 DMA free:3996kB min:60kB low:72kB high:88kB active_anon:496kB inactive_anon:692kB active_file:6648kB inactive_file:2704kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15368kB mlocked:0kB dirty:0kB writeback:0kB mapped:92kB shmem:0kB slab_reclaimable:1316kB slab_unreclaimable:44kB kernel_stack:0kB pagetables:4kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471149.338478] lowmem_reserve[]: 0 994 994 994
[471149.338487] Node 0 DMA32 free:1448kB min:4000kB low:5000kB high:6000kB active_anon:388536kB inactive_anon:388564kB active_file:65808kB inactive_file:65928kB unevictable:32kB isolated(anon):0kB isolated(file):0kB present:1018060kB mlocked:32kB dirty:56kB writeback:0kB mapped:5664kB shmem:0kB slab_reclaimable:68988kB slab_unreclaimable:9716kB kernel_stack:1008kB pagetables:4284kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471149.338513] lowmem_reserve[]: 0 0 0 0
[471149.338520] Node 0 DMA: 497*4kB 1*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 3996kB
[471149.338546] Node 0 DMA32: 0*4kB 1*8kB 0*16kB 1*32kB 0*64kB 1*128kB 1*256kB 0*512kB 1*1024kB 0*2048kB 0*4096kB = 1448kB
[471149.338564] 37315 total pagecache pages
[471149.338569] 2037 pages in swap cache
[471149.338574] Swap cache stats: add 437982, delete 435945, find 2087574/2128702
[471149.338578] Free swap = 1928368kB
[471149.338582] Total swap = 1949688kB
[471149.355624] 262139 pages RAM
[471149.355630] 5312 pages reserved
[471149.355634] 36706 pages shared
[471149.355637] 220442 pages non-shared
[471150.109576] swapper: page allocation failure. order:0, mode:0x20
[471150.109588] Pid: 0, comm: swapper Not tainted 2.6.32-5-amd64 #1
[471150.109591] Call Trace:
[471150.109593] <IRQ> [<ffffffff810ba26e>] ? __alloc_pages_nodemask+0x592/0x5f4
[471150.109612] [<ffffffff81248bfc>] ? __netdev_alloc_skb+0x29/0x45
[471150.109615] [<ffffffff81247c29>] ? __alloc_skb+0x69/0x15a
[471150.109663] [<ffffffffa00dee4b>] ? try_fill_recv+0x8b/0x18b [virtio_net]
[471150.109678] [<ffffffffa00df8bf>] ? virtnet_poll+0x543/0x5c8 [virtio_net]
[471150.109691] [<ffffffff8124ed7b>] ? net_rx_action+0xae/0x1c9
[471150.109696] [<ffffffff81053acf>] ? __do_softirq+0xdd/0x1a2
[471150.109717] [<ffffffffa00de153>] ? skb_recv_done+0x28/0x34 [virtio_net]
[471150.109729] [<ffffffff81011cac>] ? call_softirq+0x1c/0x30
[471150.109733] [<ffffffff8101322b>] ? do_softirq+0x3f/0x7c
[471150.109736] [<ffffffff8105393e>] ? irq_exit+0x36/0x76
[471150.109738] [<ffffffff81012922>] ? do_IRQ+0xa0/0xb6
[471150.109741] [<ffffffff810114d3>] ? ret_from_intr+0x0/0x11
[471150.109743] <EOI> [<ffffffff8102c580>] ? native_safe_halt+0x2/0x3
[471150.109749] [<ffffffff81017205>] ? default_idle+0x34/0x51
[471150.109752] [<ffffffff8100feb1>] ? cpu_idle+0xa2/0xda
[471150.109757] [<ffffffff814f3140>] ? early_idt_handler+0x0/0x71
[471150.109760] [<ffffffff814f3cd1>] ? start_kernel+0x3dc/0x3e8
[471150.109763] [<ffffffff814f33b7>] ? x86_64_start_kernel+0xf9/0x106
[471150.109766] Mem-Info:
[471150.109767] Node 0 DMA per-cpu:
[471150.109770] CPU 0: hi: 0, btch: 1 usd: 0
[471150.109772] Node 0 DMA32 per-cpu:
[471150.109774] CPU 0: hi: 186, btch: 31 usd: 30
[471150.109778] active_anon:98876 inactive_anon:98975 isolated_anon:0
[471150.109779] active_file:16841 inactive_file:15990 isolated_file:0
[471150.109780] unevictable:8 dirty:20 writeback:0 unstable:0
[471150.109781] free:1345 slab_reclaimable:17002 slab_unreclaimable:2381
[471150.109782] mapped:1413 shmem:0 pagetables:1078 bounce:0
[471150.109784] Node 0 DMA free:3996kB min:60kB low:72kB high:88kB active_anon:504kB inactive_anon:692kB active_file:6396kB inactive_file:2956kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15368kB mlocked:0kB dirty:0kB writeback:0kB mapped:92kB shmem:0kB slab_reclaimable:1300kB slab_unreclaimable:56kB kernel_stack:0kB pagetables:4kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.109811] lowmem_reserve[]: 0 994 994 994
[471150.109814] Node 0 DMA32 free:1384kB min:4000kB low:5000kB high:6000kB active_anon:395000kB inactive_anon:395208kB active_file:60968kB inactive_file:61004kB unevictable:32kB isolated(anon):0kB isolated(file):0kB present:1018060kB mlocked:32kB dirty:80kB writeback:0kB mapped:5560kB shmem:0kB slab_reclaimable:66708kB slab_unreclaimable:9468kB kernel_stack:1008kB pagetables:4308kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.109823] lowmem_reserve[]: 0 0 0 0
[471150.109826] Node 0 DMA: 497*4kB 1*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 3996kB
[471150.109833] Node 0 DMA32: 0*4kB 1*8kB 0*16kB 1*32kB 1*64kB 0*128kB 1*256kB 0*512kB 1*1024kB 0*2048kB 0*4096kB = 1384kB
[471150.109839] 34875 total pagecache pages
[471150.109841] 2040 pages in swap cache
[471150.109842] Swap cache stats: add 437991, delete 435951, find 2087581/2128710
[471150.109844] Free swap = 1928372kB
[471150.109845] Total swap = 1949688kB
[471150.113361] 262139 pages RAM
[471150.113361] 5312 pages reserved
[471150.113361] 34428 pages shared
[471150.113361] 222733 pages non-shared
[471150.113361] swapper: page allocation failure. order:0, mode:0x20
[471150.113361] Pid: 0, comm: swapper Not tainted 2.6.32-5-amd64 #1
[471150.113361] Call Trace:
[471150.113361] <IRQ> [<ffffffff810ba26e>] ? __alloc_pages_nodemask+0x592/0x5f4
[471150.113361] [<ffffffff81248bfc>] ? __netdev_alloc_skb+0x29/0x45
[471150.113361] [<ffffffff81247c29>] ? __alloc_skb+0x69/0x15a
[471150.113361] [<ffffffffa00dee4b>] ? try_fill_recv+0x8b/0x18b [virtio_net]
[471150.113361] [<ffffffff8127211b>] ? ip_rcv_finish+0x373/0x38d
[471150.113361] [<ffffffffa00df8bf>] ? virtnet_poll+0x543/0x5c8 [virtio_net]
[471150.113361] [<ffffffff8124ed7b>] ? net_rx_action+0xae/0x1c9
[471150.113361] [<ffffffff81053acf>] ? __do_softirq+0xdd/0x1a2
[471150.113361] [<ffffffffa00de153>] ? skb_recv_done+0x28/0x34 [virtio_net]
[471150.113361] [<ffffffff81011cac>] ? call_softirq+0x1c/0x30
[471150.113361] [<ffffffff8101322b>] ? do_softirq+0x3f/0x7c
[471150.113361] [<ffffffff8105393e>] ? irq_exit+0x36/0x76
[471150.113361] [<ffffffff81012922>] ? do_IRQ+0xa0/0xb6
[471150.113361] [<ffffffff810114d3>] ? ret_from_intr+0x0/0x11
[471150.113361] <EOI> [<ffffffff8102c580>] ? native_safe_halt+0x2/0x3
[471150.113361] [<ffffffff81017205>] ? default_idle+0x34/0x51
[471150.113361] [<ffffffff8100feb1>] ? cpu_idle+0xa2/0xda
[471150.113361] [<ffffffff814f3140>] ? early_idt_handler+0x0/0x71
[471150.113361] [<ffffffff814f3cd1>] ? start_kernel+0x3dc/0x3e8
[471150.113361] [<ffffffff814f33b7>] ? x86_64_start_kernel+0xf9/0x106
[471150.113361] Mem-Info:
[471150.113361] Node 0 DMA per-cpu:
[471150.113361] CPU 0: hi: 0, btch: 1 usd: 0
[471150.113361] Node 0 DMA32 per-cpu:
[471150.113361] CPU 0: hi: 186, btch: 31 usd: 30
[471150.113361] active_anon:98876 inactive_anon:98975 isolated_anon:0
[471150.113361] active_file:16841 inactive_file:15990 isolated_file:0
[471150.113361] unevictable:8 dirty:20 writeback:0 unstable:0
[471150.113361] free:1345 slab_reclaimable:17002 slab_unreclaimable:2381
[471150.113361] mapped:1413 shmem:0 pagetables:1078 bounce:0
[471150.113361] Node 0 DMA free:3996kB min:60kB low:72kB high:88kB active_anon:504kB inactive_anon:692kB active_file:6396kB inactive_file:2956kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15368kB mlocked:0kB dirty:0kB writeback:0kB mapped:92kB shmem:0kB slab_reclaimable:1300kB slab_unreclaimable:56kB kernel_stack:0kB pagetables:4kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.113361] lowmem_reserve[]: 0 994 994 994
[471150.113361] Node 0 DMA32 free:1384kB min:4000kB low:5000kB high:6000kB active_anon:395000kB inactive_anon:395208kB active_file:60968kB inactive_file:61004kB unevictable:32kB isolated(anon):0kB isolated(file):0kB present:1018060kB mlocked:32kB dirty:80kB writeback:0kB mapped:5560kB shmem:0kB slab_reclaimable:66708kB slab_unreclaimable:9468kB kernel_stack:1008kB pagetables:4308kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.113361] lowmem_reserve[]: 0 0 0 0
[471150.113361] Node 0 DMA: 497*4kB 1*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 3996kB
[471150.113361] Node 0 DMA32: 0*4kB 1*8kB 0*16kB 1*32kB 1*64kB 0*128kB 1*256kB 0*512kB 1*1024kB 0*2048kB 0*4096kB = 1384kB
[471150.113361] 34875 total pagecache pages
[471150.113361] 2040 pages in swap cache
[471150.113361] Swap cache stats: add 437991, delete 435951, find 2087581/2128710
[471150.113361] Free swap = 1928372kB
[471150.113361] Total swap = 1949688kB
[471150.120038] 262139 pages RAM
[471150.120039] 5312 pages reserved
[471150.120041] 34428 pages shared
[471150.120042] 222733 pages non-shared
[471150.120157] swapper: page allocation failure. order:0, mode:0x20
[471150.120160] Pid: 0, comm: swapper Not tainted 2.6.32-5-amd64 #1
[471150.120162] Call Trace:
[471150.120164] <IRQ> [<ffffffff810ba26e>] ? __alloc_pages_nodemask+0x592/0x5f4
[471150.120177] [<ffffffff81248bfc>] ? __netdev_alloc_skb+0x29/0x45
[471150.120188] [<ffffffff81247c29>] ? __alloc_skb+0x69/0x15a
[471150.120219] [<ffffffffa00dee4b>] ? try_fill_recv+0x8b/0x18b [virtio_net]
[471150.120223] [<ffffffff8127211b>] ? ip_rcv_finish+0x373/0x38d
[471150.120229] [<ffffffffa00df8bf>] ? virtnet_poll+0x543/0x5c8 [virtio_net]
[471150.120233] [<ffffffff8124ed7b>] ? net_rx_action+0xae/0x1c9
[471150.120237] [<ffffffff81053acf>] ? __do_softirq+0xdd/0x1a2
[471150.120242] [<ffffffffa00de153>] ? skb_recv_done+0x28/0x34 [virtio_net]
[471150.120246] [<ffffffff81011cac>] ? call_softirq+0x1c/0x30
[471150.120249] [<ffffffff8101322b>] ? do_softirq+0x3f/0x7c
[471150.120252] [<ffffffff8105393e>] ? irq_exit+0x36/0x76
[471150.120255] [<ffffffff81012922>] ? do_IRQ+0xa0/0xb6
[471150.120257] [<ffffffff810114d3>] ? ret_from_intr+0x0/0x11
[471150.120259] <EOI> [<ffffffff8102c580>] ? native_safe_halt+0x2/0x3
[471150.120265] [<ffffffff81017205>] ? default_idle+0x34/0x51
[471150.120269] [<ffffffff8100feb1>] ? cpu_idle+0xa2/0xda
[471150.120273] [<ffffffff814f3140>] ? early_idt_handler+0x0/0x71
[471150.120276] [<ffffffff814f3cd1>] ? start_kernel+0x3dc/0x3e8
[471150.120280] [<ffffffff814f33b7>] ? x86_64_start_kernel+0xf9/0x106
[471150.120282] Mem-Info:
[471150.120283] Node 0 DMA per-cpu:
[471150.120285] CPU 0: hi: 0, btch: 1 usd: 0
[471150.120287] Node 0 DMA32 per-cpu:
[471150.120289] CPU 0: hi: 186, btch: 31 usd: 30
[471150.120293] active_anon:98876 inactive_anon:98975 isolated_anon:0
[471150.120294] active_file:16841 inactive_file:15990 isolated_file:0
[471150.120295] unevictable:8 dirty:20 writeback:0 unstable:0
[471150.120296] free:1345 slab_reclaimable:17002 slab_unreclaimable:2381
[471150.120297] mapped:1413 shmem:0 pagetables:1078 bounce:0
[471150.120299] Node 0 DMA free:3996kB min:60kB low:72kB high:88kB active_anon:504kB inactive_anon:692kB active_file:6396kB inactive_file:2956kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15368kB mlocked:0kB dirty:0kB writeback:0kB mapped:92kB shmem:0kB slab_reclaimable:1300kB slab_unreclaimable:56kB kernel_stack:0kB pagetables:4kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.120308] lowmem_reserve[]: 0 994 994 994
[471150.120311] Node 0 DMA32 free:1384kB min:4000kB low:5000kB high:6000kB active_anon:395000kB inactive_anon:395208kB active_file:60968kB inactive_file:61004kB unevictable:32kB isolated(anon):0kB isolated(file):0kB present:1018060kB mlocked:32kB dirty:80kB writeback:0kB mapped:5560kB shmem:0kB slab_reclaimable:66708kB slab_unreclaimable:9468kB kernel_stack:1008kB pagetables:4308kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.120341] lowmem_reserve[]: 0 0 0 0
[471150.120344] Node 0 DMA: 497*4kB 1*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 3996kB
[471150.120351] Node 0 DMA32: 0*4kB 1*8kB 0*16kB 1*32kB 1*64kB 0*128kB 1*256kB 0*512kB 1*1024kB 0*2048kB 0*4096kB = 1384kB
[471150.120358] 34875 total pagecache pages
[471150.120360] 2040 pages in swap cache
[471150.120361] Swap cache stats: add 437991, delete 435951, find 2087581/2128710
[471150.120363] Free swap = 1928372kB
[471150.120364] Total swap = 1949688kB
[471150.125038] 262139 pages RAM
[471150.125040] 5312 pages reserved
[471150.125042] 34428 pages shared
[471150.125043] 222733 pages non-shared
[471150.125094] swapper: page allocation failure. order:0, mode:0x20
[471150.125098] Pid: 0, comm: swapper Not tainted 2.6.32-5-amd64 #1
[471150.125100] Call Trace:
[471150.125101] <IRQ> [<ffffffff810ba26e>] ? __alloc_pages_nodemask+0x592/0x5f4
[471150.125114] [<ffffffff81248bfc>] ? __netdev_alloc_skb+0x29/0x45
[471150.125117] [<ffffffff81247c29>] ? __alloc_skb+0x69/0x15a
[471150.125149] [<ffffffffa00dee4b>] ? try_fill_recv+0x8b/0x18b [virtio_net]
[471150.125154] [<ffffffff8127211b>] ? ip_rcv_finish+0x373/0x38d
[471150.125159] [<ffffffffa00df8bf>] ? virtnet_poll+0x543/0x5c8 [virtio_net]
[471150.125163] [<ffffffff8124ed7b>] ? net_rx_action+0xae/0x1c9
[471150.125168] [<ffffffff81053acf>] ? __do_softirq+0xdd/0x1a2
[471150.125172] [<ffffffffa00de153>] ? skb_recv_done+0x28/0x34 [virtio_net]
[471150.125177] [<ffffffff81011cac>] ? call_softirq+0x1c/0x30
[471150.125179] [<ffffffff8101322b>] ? do_softirq+0x3f/0x7c
[471150.125182] [<ffffffff8105393e>] ? irq_exit+0x36/0x76
[471150.125185] [<ffffffff81012922>] ? do_IRQ+0xa0/0xb6
[471150.125188] [<ffffffff810114d3>] ? ret_from_intr+0x0/0x11
[471150.125189] <EOI> [<ffffffff8102c580>] ? native_safe_halt+0x2/0x3
[471150.125196] [<ffffffff81017205>] ? default_idle+0x34/0x51
[471150.125199] [<ffffffff8100feb1>] ? cpu_idle+0xa2/0xda
[471150.125203] [<ffffffff814f3140>] ? early_idt_handler+0x0/0x71
[471150.125206] [<ffffffff814f3cd1>] ? start_kernel+0x3dc/0x3e8
[471150.125209] [<ffffffff814f33b7>] ? x86_64_start_kernel+0xf9/0x106
[471150.125211] Mem-Info:
[471150.125213] Node 0 DMA per-cpu:
[471150.125215] CPU 0: hi: 0, btch: 1 usd: 0
[471150.125216] Node 0 DMA32 per-cpu:
[471150.125218] CPU 0: hi: 186, btch: 31 usd: 30
[471150.125222] active_anon:98876 inactive_anon:98975 isolated_anon:0
[471150.125223] active_file:16841 inactive_file:15990 isolated_file:0
[471150.125224] unevictable:8 dirty:20 writeback:0 unstable:0
[471150.125225] free:1345 slab_reclaimable:17002 slab_unreclaimable:2381
[471150.125226] mapped:1413 shmem:0 pagetables:1078 bounce:0
[471150.125228] Node 0 DMA free:3996kB min:60kB low:72kB high:88kB active_anon:504kB inactive_anon:692kB active_file:6396kB inactive_file:2956kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15368kB mlocked:0kB dirty:0kB writeback:0kB mapped:92kB shmem:0kB slab_reclaimable:1300kB slab_unreclaimable:56kB kernel_stack:0kB pagetables:4kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.125237] lowmem_reserve[]: 0 994 994 994
[471150.125240] Node 0 DMA32 free:1384kB min:4000kB low:5000kB high:6000kB active_anon:395000kB inactive_anon:395208kB active_file:60968kB inactive_file:61004kB unevictable:32kB isolated(anon):0kB isolated(file):0kB present:1018060kB mlocked:32kB dirty:80kB writeback:0kB mapped:5560kB shmem:0kB slab_reclaimable:66708kB slab_unreclaimable:9468kB kernel_stack:1008kB pagetables:4308kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.125249] lowmem_reserve[]: 0 0 0 0
[471150.125252] Node 0 DMA: 497*4kB 1*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 3996kB
[471150.125276] Node 0 DMA32: 0*4kB 1*8kB 0*16kB 1*32kB 1*64kB 0*128kB 1*256kB 0*512kB 1*1024kB 0*2048kB 0*4096kB = 1384kB
[471150.125283] 34875 total pagecache pages
[471150.125285] 2040 pages in swap cache
[471150.125286] Swap cache stats: add 437991, delete 435951, find 2087581/2128710
[471150.125288] Free swap = 1928372kB
[471150.125289] Total swap = 1949688kB
[471150.129981] 262139 pages RAM
[471150.129983] 5312 pages reserved
[471150.129984] 34428 pages shared
[471150.129985] 222733 pages non-shared
[471150.130067] swapper: page allocation failure. order:0, mode:0x20
[471150.130070] Pid: 0, comm: swapper Not tainted 2.6.32-5-amd64 #1
[471150.130072] Call Trace:
[471150.130074] <IRQ> [<ffffffff810ba26e>] ? __alloc_pages_nodemask+0x592/0x5f4
[471150.130084] [<ffffffff81248bfc>] ? __netdev_alloc_skb+0x29/0x45
[471150.130087] [<ffffffff81247c29>] ? __alloc_skb+0x69/0x15a
[471150.130117] [<ffffffffa00dee4b>] ? try_fill_recv+0x8b/0x18b [virtio_net]
[471150.130122] [<ffffffff8127211b>] ? ip_rcv_finish+0x373/0x38d
[471150.130127] [<ffffffffa00df8bf>] ? virtnet_poll+0x543/0x5c8 [virtio_net]
[471150.130131] [<ffffffff8124ed7b>] ? net_rx_action+0xae/0x1c9
[471150.130136] [<ffffffff81053acf>] ? __do_softirq+0xdd/0x1a2
[471150.130141] [<ffffffffa00de153>] ? skb_recv_done+0x28/0x34 [virtio_net]
[471150.130145] [<ffffffff81011cac>] ? call_softirq+0x1c/0x30
[471150.130148] [<ffffffff8101322b>] ? do_softirq+0x3f/0x7c
[471150.130151] [<ffffffff8105393e>] ? irq_exit+0x36/0x76
[471150.130154] [<ffffffff81012922>] ? do_IRQ+0xa0/0xb6
[471150.130156] [<ffffffff810114d3>] ? ret_from_intr+0x0/0x11
[471150.130158] <EOI> [<ffffffff8102c580>] ? native_safe_halt+0x2/0x3
[471150.130164] [<ffffffff81017205>] ? default_idle+0x34/0x51
[471150.130168] [<ffffffff8100feb1>] ? cpu_idle+0xa2/0xda
[471150.130172] [<ffffffff814f3140>] ? early_idt_handler+0x0/0x71
[471150.130175] [<ffffffff814f3cd1>] ? start_kernel+0x3dc/0x3e8
[471150.130178] [<ffffffff814f33b7>] ? x86_64_start_kernel+0xf9/0x106
[471150.130180] Mem-Info:
[471150.130182] Node 0 DMA per-cpu:
[471150.130184] CPU 0: hi: 0, btch: 1 usd: 0
[471150.130185] Node 0 DMA32 per-cpu:
[471150.130187] CPU 0: hi: 186, btch: 31 usd: 30
[471150.130192] active_anon:98876 inactive_anon:98975 isolated_anon:0
[471150.130193] active_file:16841 inactive_file:15990 isolated_file:0
[471150.130194] unevictable:8 dirty:20 writeback:0 unstable:0
[471150.130195] free:1345 slab_reclaimable:17002 slab_unreclaimable:2381
[471150.130196] mapped:1413 shmem:0 pagetables:1078 bounce:0
[471150.130198] Node 0 DMA free:3996kB min:60kB low:72kB high:88kB active_anon:504kB inactive_anon:692kB active_file:6396kB inactive_file:2956kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15368kB mlocked:0kB dirty:0kB writeback:0kB mapped:92kB shmem:0kB slab_reclaimable:1300kB slab_unreclaimable:56kB kernel_stack:0kB pagetables:4kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.130207] lowmem_reserve[]: 0 994 994 994
[471150.130210] Node 0 DMA32 free:1384kB min:4000kB low:5000kB high:6000kB active_anon:395000kB inactive_anon:395208kB active_file:60968kB inactive_file:61004kB unevictable:32kB isolated(anon):0kB isolated(file):0kB present:1018060kB mlocked:32kB dirty:80kB writeback:0kB mapped:5560kB shmem:0kB slab_reclaimable:66708kB slab_unreclaimable:9468kB kernel_stack:1008kB pagetables:4308kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.130219] lowmem_reserve[]: 0 0 0 0
[471150.130221] Node 0 DMA: 497*4kB 1*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 3996kB
[471150.130228] Node 0 DMA32: 0*4kB 1*8kB 0*16kB 1*32kB 1*64kB 0*128kB 1*256kB 0*512kB 1*1024kB 0*2048kB 0*4096kB = 1384kB
[471150.130235] 34875 total pagecache pages
[471150.130236] 2040 pages in swap cache
[471150.130258] Swap cache stats: add 437991, delete 435951, find 2087581/2128710
[471150.130259] Free swap = 1928372kB
[471150.130261] Total swap = 1949688kB
[471150.134920] 262139 pages RAM
[471150.134922] 5312 pages reserved
[471150.134923] 34428 pages shared
[471150.134924] 222733 pages non-shared
[471150.135068] swapper: page allocation failure. order:0, mode:0x20
[471150.135071] Pid: 0, comm: swapper Not tainted 2.6.32-5-amd64 #1
[471150.135073] Call Trace:
[471150.135074] <IRQ> [<ffffffff810ba26e>] ? __alloc_pages_nodemask+0x592/0x5f4
[471150.135085] [<ffffffff81248bfc>] ? __netdev_alloc_skb+0x29/0x45
[471150.135088] [<ffffffff81247c29>] ? __alloc_skb+0x69/0x15a
[471150.135117] [<ffffffffa00dee4b>] ? try_fill_recv+0x8b/0x18b [virtio_net]
[471150.135121] [<ffffffff8127211b>] ? ip_rcv_finish+0x373/0x38d
[471150.135126] [<ffffffffa00df8bf>] ? virtnet_poll+0x543/0x5c8 [virtio_net]
[471150.135130] [<ffffffff8124ed7b>] ? net_rx_action+0xae/0x1c9
[471150.135135] [<ffffffff81053acf>] ? __do_softirq+0xdd/0x1a2
[471150.135139] [<ffffffffa00de153>] ? skb_recv_done+0x28/0x34 [virtio_net]
[471150.135143] [<ffffffff81011cac>] ? call_softirq+0x1c/0x30
[471150.135146] [<ffffffff8101322b>] ? do_softirq+0x3f/0x7c
[471150.135149] [<ffffffff8105393e>] ? irq_exit+0x36/0x76
[471150.135152] [<ffffffff81012922>] ? do_IRQ+0xa0/0xb6
[471150.135154] [<ffffffff810114d3>] ? ret_from_intr+0x0/0x11
[471150.135156] <EOI> [<ffffffff8102c580>] ? native_safe_halt+0x2/0x3
[471150.135162] [<ffffffff81017205>] ? default_idle+0x34/0x51
[471150.135166] [<ffffffff8100feb1>] ? cpu_idle+0xa2/0xda
[471150.135170] [<ffffffff814f3140>] ? early_idt_handler+0x0/0x71
[471150.135173] [<ffffffff814f3cd1>] ? start_kernel+0x3dc/0x3e8
[471150.135176] [<ffffffff814f33b7>] ? x86_64_start_kernel+0xf9/0x106
[471150.135178] Mem-Info:
[471150.135180] Node 0 DMA per-cpu:
[471150.135182] CPU 0: hi: 0, btch: 1 usd: 0
[471150.135183] Node 0 DMA32 per-cpu:
[471150.135185] CPU 0: hi: 186, btch: 31 usd: 30
[471150.135189] active_anon:98876 inactive_anon:98975 isolated_anon:0
[471150.135190] active_file:16841 inactive_file:15990 isolated_file:0
[471150.135191] unevictable:8 dirty:20 writeback:0 unstable:0
[471150.135192] free:1345 slab_reclaimable:17002 slab_unreclaimable:2381
[471150.135193] mapped:1413 shmem:0 pagetables:1078 bounce:0
[471150.135196] Node 0 DMA free:3996kB min:60kB low:72kB high:88kB active_anon:504kB inactive_anon:692kB active_file:6396kB inactive_file:2956kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15368kB mlocked:0kB dirty:0kB writeback:0kB mapped:92kB shmem:0kB slab_reclaimable:1300kB slab_unreclaimable:56kB kernel_stack:0kB pagetables:4kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.135204] lowmem_reserve[]: 0 994 994 994
[471150.135207] Node 0 DMA32 free:1384kB min:4000kB low:5000kB high:6000kB active_anon:395000kB inactive_anon:395208kB active_file:60968kB inactive_file:61004kB unevictable:32kB isolated(anon):0kB isolated(file):0kB present:1018060kB mlocked:32kB dirty:80kB writeback:0kB mapped:5560kB shmem:0kB slab_reclaimable:66708kB slab_unreclaimable:9468kB kernel_stack:1008kB pagetables:4308kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.135217] lowmem_reserve[]: 0 0 0 0
[471150.135219] Node 0 DMA: 497*4kB 1*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 3996kB
[471150.135226] Node 0 DMA32: 0*4kB 1*8kB 0*16kB 1*32kB 1*64kB 0*128kB 1*256kB 0*512kB 1*1024kB 0*2048kB 0*4096kB = 1384kB
[471150.135232] 34875 total pagecache pages
[471150.135234] 2040 pages in swap cache
[471150.135236] Swap cache stats: add 437991, delete 435951, find 2087581/2128710
[471150.135237] Free swap = 1928372kB
[471150.135238] Total swap = 1949688kB
[471150.139959] 262139 pages RAM
[471150.139961] 5312 pages reserved
[471150.139962] 34428 pages shared
[471150.139985] 222733 pages non-shared
[471150.140261] swapper: page allocation failure. order:0, mode:0x20
[471150.140264] Pid: 0, comm: swapper Not tainted 2.6.32-5-amd64 #1
[471150.140266] Call Trace:
[471150.140267] <IRQ> [<ffffffff810ba26e>] ? __alloc_pages_nodemask+0x592/0x5f4
[471150.140278] [<ffffffff81248bfc>] ? __netdev_alloc_skb+0x29/0x45
[471150.140281] [<ffffffff81247c29>] ? __alloc_skb+0x69/0x15a
[471150.140310] [<ffffffffa00dee4b>] ? try_fill_recv+0x8b/0x18b [virtio_net]
[471150.140315] [<ffffffff8127211b>] ? ip_rcv_finish+0x373/0x38d
[471150.140320] [<ffffffffa00df8bf>] ? virtnet_poll+0x543/0x5c8 [virtio_net]
[471150.140337] [<ffffffff8105a3b0>] ? run_timer_softirq+0x222/0x268
[471150.140341] [<ffffffff8124ed7b>] ? net_rx_action+0xae/0x1c9
[471150.140344] [<ffffffff81053acf>] ? __do_softirq+0xdd/0x1a2
[471150.140350] [<ffffffffa00de153>] ? skb_recv_done+0x28/0x34 [virtio_net]
[471150.140353] [<ffffffff81011cac>] ? call_softirq+0x1c/0x30
[471150.140356] [<ffffffff8101322b>] ? do_softirq+0x3f/0x7c
[471150.140359] [<ffffffff8105393e>] ? irq_exit+0x36/0x76
[471150.140362] [<ffffffff81012922>] ? do_IRQ+0xa0/0xb6
[471150.140364] [<ffffffff810114d3>] ? ret_from_intr+0x0/0x11
[471150.140366] <EOI> [<ffffffff8102c580>] ? native_safe_halt+0x2/0x3
[471150.140372] [<ffffffff81017205>] ? default_idle+0x34/0x51
[471150.140376] [<ffffffff8100feb1>] ? cpu_idle+0xa2/0xda
[471150.140380] [<ffffffff814f3140>] ? early_idt_handler+0x0/0x71
[471150.140383] [<ffffffff814f3cd1>] ? start_kernel+0x3dc/0x3e8
[471150.140386] [<ffffffff814f33b7>] ? x86_64_start_kernel+0xf9/0x106
[471150.140388] Mem-Info:
[471150.140390] Node 0 DMA per-cpu:
[471150.140392] CPU 0: hi: 0, btch: 1 usd: 0
[471150.140393] Node 0 DMA32 per-cpu:
[471150.140396] CPU 0: hi: 186, btch: 31 usd: 30
[471150.140400] active_anon:98876 inactive_anon:98975 isolated_anon:0
[471150.140401] active_file:16841 inactive_file:15990 isolated_file:0
[471150.140402] unevictable:8 dirty:20 writeback:0 unstable:0
[471150.140403] free:1345 slab_reclaimable:17002 slab_unreclaimable:2381
[471150.140404] mapped:1413 shmem:0 pagetables:1078 bounce:0
[471150.140406] Node 0 DMA free:3996kB min:60kB low:72kB high:88kB active_anon:504kB inactive_anon:692kB active_file:6396kB inactive_file:2956kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15368kB mlocked:0kB dirty:0kB writeback:0kB mapped:92kB shmem:0kB slab_reclaimable:1300kB slab_unreclaimable:56kB kernel_stack:0kB pagetables:4kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.140415] lowmem_reserve[]: 0 994 994 994
[471150.140417] Node 0 DMA32 free:1384kB min:4000kB low:5000kB high:6000kB active_anon:395000kB inactive_anon:395208kB active_file:60968kB inactive_file:61004kB unevictable:32kB isolated(anon):0kB isolated(file):0kB present:1018060kB mlocked:32kB dirty:80kB writeback:0kB mapped:5560kB shmem:0kB slab_reclaimable:66708kB slab_unreclaimable:9468kB kernel_stack:1008kB pagetables:4308kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.140427] lowmem_reserve[]: 0 0 0 0
[471150.140429] Node 0 DMA: 497*4kB 1*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 3996kB
[471150.140436] Node 0 DMA32: 0*4kB 1*8kB 0*16kB 1*32kB 1*64kB 0*128kB 1*256kB 0*512kB 1*1024kB 0*2048kB 0*4096kB = 1384kB
[471150.140443] 34875 total pagecache pages
[471150.140444] 2040 pages in swap cache
[471150.140446] Swap cache stats: add 437991, delete 435951, find 2087581/2128710
[471150.140448] Free swap = 1928372kB
[471150.140449] Total swap = 1949688kB
[471150.145127] 262139 pages RAM
[471150.145129] 5312 pages reserved
[471150.145130] 34428 pages shared
[471150.145131] 222733 pages non-shared
[471150.145196] swapper: page allocation failure. order:0, mode:0x20
[471150.145198] Pid: 0, comm: swapper Not tainted 2.6.32-5-amd64 #1
[471150.145221] Call Trace:
[471150.145224] <IRQ> [<ffffffff810ba26e>] ? __alloc_pages_nodemask+0x592/0x5f4
[471150.145237] [<ffffffff81248bfc>] ? __netdev_alloc_skb+0x29/0x45
[471150.145240] [<ffffffff81247c29>] ? __alloc_skb+0x69/0x15a
[471150.145273] [<ffffffffa00dee4b>] ? try_fill_recv+0x8b/0x18b [virtio_net]
[471150.145277] [<ffffffff8127211b>] ? ip_rcv_finish+0x373/0x38d
[471150.145282] [<ffffffffa00df8bf>] ? virtnet_poll+0x543/0x5c8 [virtio_net]
[471150.145287] [<ffffffff8105a3b0>] ? run_timer_softirq+0x222/0x268
[471150.145291] [<ffffffff8124ed7b>] ? net_rx_action+0xae/0x1c9
[471150.145295] [<ffffffff81053acf>] ? __do_softirq+0xdd/0x1a2
[471150.145299] [<ffffffffa00de153>] ? skb_recv_done+0x28/0x34 [virtio_net]
[471150.145303] [<ffffffff81011cac>] ? call_softirq+0x1c/0x30
[471150.145306] [<ffffffff8101322b>] ? do_softirq+0x3f/0x7c
[471150.145309] [<ffffffff8105393e>] ? irq_exit+0x36/0x76
[471150.145311] [<ffffffff81012922>] ? do_IRQ+0xa0/0xb6
[471150.145314] [<ffffffff810114d3>] ? ret_from_intr+0x0/0x11
[471150.145315] <EOI> [<ffffffff8102c580>] ? native_safe_halt+0x2/0x3
[471150.145322] [<ffffffff81017205>] ? default_idle+0x34/0x51
[471150.145325] [<ffffffff8100feb1>] ? cpu_idle+0xa2/0xda
[471150.145330] [<ffffffff814f3140>] ? early_idt_handler+0x0/0x71
[471150.145333] [<ffffffff814f3cd1>] ? start_kernel+0x3dc/0x3e8
[471150.145336] [<ffffffff814f33b7>] ? x86_64_start_kernel+0xf9/0x106
[471150.145338] Mem-Info:
[471150.145340] Node 0 DMA per-cpu:
[471150.145342] CPU 0: hi: 0, btch: 1 usd: 0
[471150.145344] Node 0 DMA32 per-cpu:
[471150.145346] CPU 0: hi: 186, btch: 31 usd: 30
[471150.145350] active_anon:98876 inactive_anon:98975 isolated_anon:0
[471150.145351] active_file:16841 inactive_file:15990 isolated_file:0
[471150.145352] unevictable:8 dirty:20 writeback:0 unstable:0
[471150.145353] free:1345 slab_reclaimable:17002 slab_unreclaimable:2381
[471150.145354] mapped:1413 shmem:0 pagetables:1078 bounce:0
[471150.145356] Node 0 DMA free:3996kB min:60kB low:72kB high:88kB active_anon:504kB inactive_anon:692kB active_file:6396kB inactive_file:2956kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15368kB mlocked:0kB dirty:0kB writeback:0kB mapped:92kB shmem:0kB slab_reclaimable:1300kB slab_unreclaimable:56kB kernel_stack:0kB pagetables:4kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.145365] lowmem_reserve[]: 0 994 994 994
[471150.145368] Node 0 DMA32 free:1384kB min:4000kB low:5000kB high:6000kB active_anon:395000kB inactive_anon:395208kB active_file:60968kB inactive_file:61004kB unevictable:32kB isolated(anon):0kB isolated(file):0kB present:1018060kB mlocked:32kB dirty:80kB writeback:0kB mapped:5560kB shmem:0kB slab_reclaimable:66708kB slab_unreclaimable:9468kB kernel_stack:1008kB pagetables:4308kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.145377] lowmem_reserve[]: 0 0 0 0
[471150.145379] Node 0 DMA: 497*4kB 1*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 3996kB
[471150.145390] Node 0 DMA32: 0*4kB 1*8kB 0*16kB 1*32kB 1*64kB 0*128kB 1*256kB 0*512kB 1*1024kB 0*2048kB 0*4096kB = 1384kB
[471150.145397] 34875 total pagecache pages
[471150.145398] 2040 pages in swap cache
[471150.145400] Swap cache stats: add 437991, delete 435951, find 2087581/2128710
[471150.145402] Free swap = 1928372kB
[471150.145403] Total swap = 1949688kB
[471150.150067] 262139 pages RAM
[471150.150069] 5312 pages reserved
[471150.150070] 34428 pages shared
[471150.150071] 222733 pages non-shared
[471150.150150] swapper: page allocation failure. order:0, mode:0x20
[471150.150153] Pid: 0, comm: swapper Not tainted 2.6.32-5-amd64 #1
[471150.150155] Call Trace:
[471150.150157] <IRQ> [<ffffffff810ba26e>] ? __alloc_pages_nodemask+0x592/0x5f4
[471150.150166] [<ffffffff81248bfc>] ? __netdev_alloc_skb+0x29/0x45
[471150.150169] [<ffffffff81247c29>] ? __alloc_skb+0x69/0x15a
[471150.150217] [<ffffffffa00dee4b>] ? try_fill_recv+0x8b/0x18b [virtio_net]
[471150.150222] [<ffffffff8127211b>] ? ip_rcv_finish+0x373/0x38d
[471150.150227] [<ffffffffa00df8bf>] ? virtnet_poll+0x543/0x5c8 [virtio_net]
[471150.150231] [<ffffffff8105a3b0>] ? run_timer_softirq+0x222/0x268
[471150.150236] [<ffffffff8124ed7b>] ? net_rx_action+0xae/0x1c9
[471150.150240] [<ffffffff81053acf>] ? __do_softirq+0xdd/0x1a2
[471150.150244] [<ffffffffa00de153>] ? skb_recv_done+0x28/0x34 [virtio_net]
[471150.150248] [<ffffffff81011cac>] ? call_softirq+0x1c/0x30
[471150.150251] [<ffffffff8101322b>] ? do_softirq+0x3f/0x7c
[471150.150254] [<ffffffff8105393e>] ? irq_exit+0x36/0x76
[471150.150256] [<ffffffff81012922>] ? do_IRQ+0xa0/0xb6
[471150.150259] [<ffffffff810114d3>] ? ret_from_intr+0x0/0x11
[471150.150261] <EOI> [<ffffffff8102c580>] ? native_safe_halt+0x2/0x3
[471150.150267] [<ffffffff81017205>] ? default_idle+0x34/0x51
[471150.150270] [<ffffffff8100feb1>] ? cpu_idle+0xa2/0xda
[471150.150274] [<ffffffff814f3140>] ? early_idt_handler+0x0/0x71
[471150.150277] [<ffffffff814f3cd1>] ? start_kernel+0x3dc/0x3e8
[471150.150281] [<ffffffff814f33b7>] ? x86_64_start_kernel+0xf9/0x106
[471150.150283] Mem-Info:
[471150.150285] Node 0 DMA per-cpu:
[471150.150287] CPU 0: hi: 0, btch: 1 usd: 0
[471150.150288] Node 0 DMA32 per-cpu:
[471150.150291] CPU 0: hi: 186, btch: 31 usd: 30
[471150.150295] active_anon:98876 inactive_anon:98975 isolated_anon:0
[471150.150296] active_file:16841 inactive_file:15990 isolated_file:0
[471150.150297] unevictable:8 dirty:20 writeback:0 unstable:0
[471150.150298] free:1345 slab_reclaimable:17002 slab_unreclaimable:2381
[471150.150299] mapped:1413 shmem:0 pagetables:1078 bounce:0
[471150.150301] Node 0 DMA free:3996kB min:60kB low:72kB high:88kB active_anon:504kB inactive_anon:692kB active_file:6396kB inactive_file:2956kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15368kB mlocked:0kB dirty:0kB writeback:0kB mapped:92kB shmem:0kB slab_reclaimable:1300kB slab_unreclaimable:56kB kernel_stack:0kB pagetables:4kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.150310] lowmem_reserve[]: 0 994 994 994
[471150.150313] Node 0 DMA32 free:1384kB min:4000kB low:5000kB high:6000kB active_anon:395000kB inactive_anon:395208kB active_file:60968kB inactive_file:61004kB unevictable:32kB isolated(anon):0kB isolated(file):0kB present:1018060kB mlocked:32kB dirty:80kB writeback:0kB mapped:5560kB shmem:0kB slab_reclaimable:66708kB slab_unreclaimable:9468kB kernel_stack:1008kB pagetables:4308kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
[471150.150322] lowmem_reserve[]: 0 0 0 0
[471150.150324] Node 0 DMA: 497*4kB 1*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 3996kB
[471150.150331] Node 0 DMA32: 0*4kB 1*8kB 0*16kB 1*32kB 1*64kB 0*128kB 1*256kB 0*512kB 1*1024kB 0*2048kB 0*4096kB = 1384kB
[471150.150338] 34875 total pagecache pages
[471150.150339] 2040 pages in swap cache
[471150.150341] Swap cache stats: add 437991, delete 435951, find 2087581/2128710
[471150.150343] Free swap = 1928372kB
[471150.150344] Total swap = 1949688kB
[471150.154993] 262139 pages RAM
[471150.154995] 5312 pages reserved
[471150.154996] 34428 pages shared
[471150.154997] 222733 pages non-shared


-- Package-specific info:
** Version:
Linux version 2.6.32-5-amd64 (Debian 2.6.32-27) (maks@debian.org) (gcc version 4.3.5 (Debian 4.3.5-4) ) #1 SMP Sat Oct 30 14:18:21 UTC 2010

** Command line:
BOOT_IMAGE=/vmlinuz-2.6.32-5-amd64 root=/dev/mapper/linux-root ro quiet

** Not tainted

** Model information
sys_vendor: Bochs
product_name: Bochs
product_version:
chassis_vendor: Bochs
chassis_version:
bios_vendor: Bochs
bios_version: Bochs

** Loaded modules:
Module Size Used by
nfsd 254182 13
exportfs 3170 1 nfsd
nfs 241066 0
lockd 57619 2 nfsd,nfs
fscache 29834 1 nfs
nfs_acl 2031 2 nfsd,nfs
auth_rpcgss 33476 2 nfsd,nfs
sunrpc 161237 14 nfsd,nfs,lockd,nfs_acl,auth_rpcgss
act_police 3636 0
cls_flow 5964 0
cls_fw 3513 0
cls_u32 5466 0
sch_htb 11942 0
sch_hfsc 12119 0
sch_ingress 1624 0
sch_sfq 4686 0
xt_time 1723 0
xt_connlimit 2863 0
xt_realm 919 0
iptable_raw 1867 0
xt_comment 907 47
xt_recent 5977 0
xt_policy 2170 0
ipt_ULOG 7129 0
ipt_REJECT 1953 4
ipt_REDIRECT 1111 0
ipt_NETMAP 1137 0
ipt_MASQUERADE 1554 0
ipt_ECN 1672 0
ipt_ecn 1272 0
ipt_CLUSTERIP 4910 0
ipt_ah 1061 0
ipt_addrtype 1769 3
nf_nat_tftp 966 0
nf_nat_snmp_basic 7796 0
nf_nat_sip 4934 0
nf_nat_pptp 2034 0
nf_nat_proto_gre 1245 1 nf_nat_pptp
nf_nat_irc 1366 0
nf_nat_h323 5095 0
nf_nat_ftp 2047 0
nf_nat_amanda 1144 0
ts_kmp 1623 5
nf_conntrack_amanda 2197 1 nf_nat_amanda
nf_conntrack_sane 3620 0
nf_conntrack_tftp 3321 1 nf_nat_tftp
nf_conntrack_sip 13546 1 nf_nat_sip
nf_conntrack_proto_sctp 6238 0
nf_conntrack_pptp 3801 1 nf_nat_pptp
nf_conntrack_proto_gre 3579 1 nf_conntrack_pptp
nf_conntrack_netlink 13128 0
nf_conntrack_netbios_ns 1282 0
nf_conntrack_irc 3347 1 nf_nat_irc
nf_conntrack_h323 37008 1 nf_nat_h323
nf_conntrack_ftp 5537 1 nf_nat_ftp
xt_TPROXY 1329 0
nf_tproxy_core 1549 1 xt_TPROXY,[permanent]
xt_tcpmss 1401 0
xt_pkttype 1003 0
xt_physdev 1508 0
xt_owner 1063 0
xt_NFQUEUE 1989 0
xt_NFLOG 1038 0
nfnetlink_log 7000 1 xt_NFLOG
xt_multiport 2267 10
xt_MARK 917 1
xt_mark 917 0
xt_mac 979 0
xt_limit 1782 0
xt_length 1164 0
xt_iprange 1433 0
xt_helper 1227 0
xt_hashlimit 7707 0
xt_DSCP 1995 0
xt_dscp 1611 0
xt_dccp 1915 0
xt_conntrack 2407 10
xt_CONNMARK 1267 0
xt_connmark 1123 0
xt_CLASSIFY 925 0
ipt_LOG 4518 6
xt_tcpudp 2319 42
xt_state 1303 0
iptable_nat 4299 0
nf_nat 13388 12 ipt_REDIRECT,ipt_NETMAP,ipt_MASQUERADE,nf_nat_tftp ,nf_nat_sip,nf_nat_pptp,nf_nat_proto_gre,nf_nat_ir c,nf_nat_h323,nf_nat_ftp,nf_nat_amanda,iptable_nat
nf_conntrack_ipv4 9833 13 iptable_nat,nf_nat
nf_defrag_ipv4 1139 2 xt_TPROXY,nf_conntrack_ipv4
nf_conntrack 46535 31 xt_connlimit,ipt_MASQUERADE,ipt_CLUSTERIP,nf_nat_t ftp,nf_nat_snmp_basic,nf_nat_sip,nf_nat_pptp,nf_na t_irc,nf_nat_h323,nf_nat_ftp,nf_nat_amanda,nf_conn track_amanda,nf_conntrack_sane,nf_conntrack_tftp,n f_conntrack_sip,nf_conntrack_proto_sctp,nf_conntra ck_pptp,nf_conntrack_proto_gre,nf_conntrack_netlin k,nf_conntrack_netbios_ns,nf_conntrack_irc,nf_conn track_h323,nf_conntrack_ftp,xt_helper,xt_conntrack ,xt_CONNMARK,xt_connmark,xt_state,iptable_nat,nf_n at,nf_conntrack_ipv4
iptable_mangle 2817 1
nfnetlink 2398 2 nf_conntrack_netlink,nfnetlink_log
iptable_filter 2258 1
ip_tables 13899 4 iptable_raw,iptable_nat,iptable_mangle,iptable_fil ter
x_tables 12845 44 xt_time,xt_connlimit,xt_realm,xt_comment,xt_recent ,xt_policy,ipt_ULOG,ipt_REJECT,ipt_REDIRECT,ipt_NE TMAP,ipt_MASQUERADE,ipt_ECN,ipt_ecn,ipt_CLUSTERIP, ipt_ah,ipt_addrtype,xt_TPROXY,xt_tcpmss,xt_pkttype ,xt_physdev,xt_owner,xt_NFQUEUE,xt_NFLOG,xt_multip ort,xt_MARK,xt_mark,xt_mac,xt_limit,xt_length,xt_i prange,xt_helper,xt_hashlimit
 
Old 11-17-2010, 10:57 PM
Ben Hutchings
 
Default Bug#603835: linux-image-2.6.32-5-amd64: page allocation failures while transferring data with virtio

On Wed, 2010-11-17 at 19:44 +0100, Frederik Himpe wrote:
> Package: linux-2.6
> Version: 2.6.32-27
> Severity: important
>
> I've got a KVM virtual machine which is using bacula-fd to back-up its
> files to another virtual machine.
>
> Regularly, while back-ing up, backtraces start appearing in the kernel
> logs and bacula-fd starts eating up a huge amount of memory.
[...]

And why is it the kernel's fault if bacula eats all the memory?

Ben.

--
Ben Hutchings
Once a job is fouled up, anything done to improve it makes it worse.
 
Old 11-18-2010, 06:57 AM
Frederik Himpe
 
Default Bug#603835: linux-image-2.6.32-5-amd64: page allocation failures while transferring data with virtio

On Wed, 2010-11-17 at 23:57 +0000, Ben Hutchings wrote:
> On Wed, 2010-11-17 at 19:44 +0100, Frederik Himpe wrote:
> > Package: linux-2.6
> > Version: 2.6.32-27
> > Severity: important
> >
> > I've got a KVM virtual machine which is using bacula-fd to back-up its
> > files to another virtual machine.
> >
> > Regularly, while back-ing up, backtraces start appearing in the kernel
> > logs and bacula-fd starts eating up a huge amount of memory.
> [...]
>
> And why is it the kernel's fault if bacula eats all the memory?

If the only problem is that bacula is eating all memory, than the kernel
OOM kernel should kill it instead of letting virtio networking die.

However, seeing how much similar problem reports with page allocation
failures in virtio there are on the web, not involving bacula, I would
rather suspect the problem is in virtio itself.
http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=576838#69 also
indicates that this bug was actually not fixed in reality, and that
using another network driver works around the problem.

--
Frederik Himpe <fhimpe@vub.ac.be>
Vrije Universiteit Brussel




--
To UNSUBSCRIBE, email to debian-kernel-REQUEST@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmaster@lists.debian.org
Archive: 1290067067.29412.4.camel@defected.localdomain">htt p://lists.debian.org/1290067067.29412.4.camel@defected.localdomain
 

Thread Tools




All times are GMT. The time now is 07:08 PM.

VBulletin, Copyright ©2000 - 2014, Jelsoft Enterprises Ltd.
Content Relevant URLs by vBSEO ©2007, Crawlability, Inc.
Copyright 2007 - 2008, www.linux-archive.org