From mboxrd@z Thu Jan 1 00:00:00 1970 From: Amit Shah Subject: Re: x86: kvmclock: abstract save/restore sched_clock_state Date: Fri, 10 Feb 2012 18:48:28 +0530 Message-ID: <20120210131828.GA31999@amit.redhat.com> References: <20120207210542.GC20618@amt.cnet> <20120209122742.GA25505@amit.redhat.com> <4F33E299.3090500@redhat.com> <20120210100211.GA2971@amit.redhat.com> <20120210123216.GA11137@amt.cnet> Mime-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: QUOTED-PRINTABLE Cc: Igor Mammedov , kvm@vger.kernel.org, tglx@linutronix.de, mingo@redhat.com, hpa@zytor.com, x86@kernel.org, johnstul@us.ibm.com, riel@redhat.com, avi@redhat.com To: Marcelo Tosatti Return-path: Received: from mx1.redhat.com ([209.132.183.28]:14204 "EHLO mx1.redhat.com" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1754427Ab2BJNTC (ORCPT ); Fri, 10 Feb 2012 08:19:02 -0500 Content-Disposition: inline In-Reply-To: <20120210123216.GA11137@amt.cnet> Sender: kvm-owner@vger.kernel.org List-ID: On (Fri) 10 Feb 2012 [10:32:16], Marcelo Tosatti wrote: > On Fri, Feb 10, 2012 at 03:32:11PM +0530, Amit Shah wrote: > > On (Thu) 09 Feb 2012 [16:13:29], Igor Mammedov wrote: > >=20 > > > Stalls are probably caused by uninitialized percpu hv_clock, with > > > following patch I don't see stalls. Although I might be just luck= y. > > > http://git.kernel.org/?p=3Dvirt/kvm/kvm.git;a=3Dcommit;h=3De2971a= c7e1d186af059e088d305496c5cb47d487 > >=20 > > Your commit does make things better, I don't see any stalls on the > > first resume. > >=20 > > However, a subsequent s4 causes the stall to re-appear on resume, a= nd > > this time there are no stall messages; the kernel just sits there > > spinning on something. I've not found the solution to this one yet= (I > > had a commit similar to Marcelo's in the works, which got me to the > > previous works-but-stalls behaviour). >=20 > I cannot reproduce it here. Suspend/resume are operating normally aft= er > several iterations. Igor do you see anything similar? >=20 > Amit, can you please enable CONFIG_PRINTK_TIME=3Dy and post a full dm= esg=20 > (both during suspend and also the new kernel during resume). In my case, I run a ping to the host (10.0.2.2) while the s4 suspend/resume operations are performed. Complete dmesg, for all 3 invocations of the guest. First one boots, starts ping, starts s4. Second one starts s4 after confirming ping is working fine. Third one just stays there, spinning. $ ./x86_64-softmmu/qemu-system-x86_64 -kernel ~/src/linux/arch/x86/boot= /bzImage -append 'root=3D/dev/vda1 console=3Dtty0 console=3DttyS0 no_c= onsole_suspend' -drive file=3D/guests/f14-suspend.qcow2,if=3Dnone,id=3D= dr0 -device virtio-blk-pci,drive=3Ddr0 -net nic,model=3Dvirtio -net use= r -serial stdio -enable-kvm -m 512 -cpu host -smp 4 [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Linux version 3.3.0-rc2+ (amit@amit.redhat.com) (gcc ver= sion 4.6.2 20111027 (Red Hat 4.6.2-1) (GCC) ) #295 SMP PREEMPT Fri Feb = 10 18:39:48 IST 2012 [ 0.000000] Command line: root=3D/dev/vda1 console=3Dtty0 console=3D= ttyS0 no_console_suspend [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: 0000000000000000 - 000000000009dc00 (usable) [ 0.000000] BIOS-e820: 000000000009dc00 - 00000000000a0000 (reserve= d) [ 0.000000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserve= d) [ 0.000000] BIOS-e820: 0000000000100000 - 000000001fffd000 (usable) [ 0.000000] BIOS-e820: 000000001fffd000 - 0000000020000000 (reserve= d) [ 0.000000] BIOS-e820: 00000000feffc000 - 00000000ff000000 (reserve= d) [ 0.000000] BIOS-e820: 00000000fffc0000 - 0000000100000000 (reserve= d) [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] DMI 2.4 present. [ 0.000000] No AGP bridge found [ 0.000000] last_pfn =3D 0x1fffd max_arch_pfn =3D 0x400000000 [ 0.000000] x86 PAT enabled: cpu 0, old 0x70406, new 0x7010600070106 [ 0.000000] init_memory_mapping: 0000000000000000-000000001fffd000 [ 0.000000] RAMDISK: 1fa2e000 - 1fff0000 [ 0.000000] ACPI: RSDP 00000000000fd3f0 00014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 000000001fffd660 00034 (v01 BOCHS BXPCRSDT 0= 0000001 BXPC 00000001) [ 0.000000] ACPI: FACP 000000001fffff80 00074 (v01 BOCHS BXPCFACP 0= 0000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 000000001fffd9b0 02589 (v01 BXPC BXDSDT 0= 0000001 INTL 20100528) [ 0.000000] ACPI: FACS 000000001fffff40 00040 [ 0.000000] ACPI: SSDT 000000001fffd7e0 001C1 (v01 BOCHS BXPCSSDT 0= 0000001 BXPC 00000001) [ 0.000000] ACPI: APIC 000000001fffd6e0 0008A (v01 BOCHS BXPCAPIC 0= 0000001 BXPC 00000001) [ 0.000000] ACPI: HPET 000000001fffd6a0 00038 (v01 BOCHS BXPCHPET 0= 0000001 BXPC 00000001) [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:18509c1, boot clock [ 0.000000] Zone PFN ranges: [ 0.000000] DMA 0x00000010 -> 0x00001000 [ 0.000000] DMA32 0x00001000 -> 0x00100000 [ 0.000000] Normal empty [ 0.000000] Movable zone start PFN for each node [ 0.000000] Early memory PFN ranges [ 0.000000] 0: 0x00000010 -> 0x0000009d [ 0.000000] 0: 0x00000100 -> 0x0001fffd [ 0.000000] ACPI: PM-Timer IO Port: 0xb008 [ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x03] enabled) [ 0.000000] ACPI: IOAPIC (id[0x04] address[0xfec00000] gsi_base[0]) [ 0.000000] IOAPIC[0]: apic_id 4, version 17, address 0xfec00000, GS= I 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 lev= el) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high lev= el) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high l= evel) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high l= evel) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] SMP: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] PM: Registered nosave memory: 000000000009d000 - 0000000= 00009e000 [ 0.000000] PM: Registered nosave memory: 000000000009e000 - 0000000= 0000a0000 [ 0.000000] PM: Registered nosave memory: 00000000000a0000 - 0000000= 0000f0000 [ 0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000= 000100000 [ 0.000000] Allocating PCI resources starting at 20000000 (gap: 2000= 0000:deffc000) [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] setup_percpu: NR_CPUS:4 nr_cpumask_bits:4 nr_cpu_ids:4 n= r_node_ids:1 [ 0.000000] PERCPU: Embedded 474 pages/cpu @ffff88001f000000 s191078= 4 r8192 d22528 u2097152 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. = Total pages: 128901 [ 0.000000] Kernel command line: root=3D/dev/vda1 console=3Dtty0 con= sole=3DttyS0 no_console_suspend [ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes) [ 0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288= bytes) [ 0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 = bytes) [ 0.000000] xsave/xrstor: enabled xstate_bv 0x7, cntxt size 0x340 [ 0.000000] Checking aperture... [ 0.000000] No AGP bridge found [ 0.000000] Memory: 481644k/524276k available (3162k kernel code, 46= 0k absent, 42172k reserved, 3483k data, 2420k init) [ 0.000000] SLUB: Genslabs=3D15, HWalign=3D64, Order=3D0-3, MinObjec= ts=3D0, CPUs=3D4, Nodes=3D1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] NR_IRQS:4352 nr_irqs:712 16 [ 0.000000] Console: colour VGA+ 80x25 [ 0.000000] console [tty0] enabled [ 0.000000] console [ttyS0] enabled [ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, I= nc., Ingo Molnar [ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.000000] ... MAX_LOCK_DEPTH: 48 [ 0.000000] ... MAX_LOCKDEP_KEYS: 8191 [ 0.000000] ... CLASSHASH_SIZE: 4096 [ 0.000000] ... MAX_LOCKDEP_ENTRIES: 16384 [ 0.000000] ... MAX_LOCKDEP_CHAINS: 32768 [ 0.000000] ... CHAINHASH_SIZE: 16384 [ 0.000000] memory used by lock dependency info: 6367 kB [ 0.000000] per task-struct memory footprint: 2688 bytes [ 0.000000] Detected 2691.258 MHz processor. [ 0.008000] Calibrating delay loop (skipped) preset value.. 5382.51 = BogoMIPS (lpj=3D10765032) [ 0.008000] pid_max: default: 32768 minimum: 301 [ 0.010143] Mount-cache hash table entries: 256 [ 0.020001] ACPI: Core revision 20120111 [ 0.141619] ftrace: allocating 14018 entries in 55 pages [ 0.151144] ..TIMER: vector=3D0x30 apic1=3D0 pin1=3D2 apic2=3D-1 pin= 2=3D-1 [ 0.196275] CPU0: Intel(R) Core(TM) i7-2620M CPU @ 2.70GHz stepping = 07 [ 0.200011] APIC calibration not consistent with PM-Timer: 91ms inst= ead of 100ms [ 0.200011] APIC delta adjusted to PM-Timer: 6250032 (5749686) [ 0.200011] Performance Events: unsupported p6 CPU model 42 no PMU d= river, software events only. [ 0.220527] lockdep: fixing up alternatives. [ 0.232533] Booting Node 0, Processors #1 [ 0.008000] kvm-clock: cpu 1, msr 0:1f3d19c1, secondary cpu clock [ 0.256113] lockdep: fixing up alternatives. [ 0.257316] #2 [ 0.008000] kvm-clock: cpu 2, msr 0:1f5d19c1, secondary cpu clock [ 0.280143] lockdep: fixing up alternatives. [ 0.281629] #3 Ok. [ 0.008000] kvm-clock: cpu 3, msr 0:1f7d19c1, secondary cpu clock [ 0.296077] Brought up 4 CPUs [ 0.296992] Total of 4 processors activated (21530.06 BogoMIPS). [ 0.316988] NET: Registered protocol family 16 [ 0.321184] ACPI: bus type pci registered [ 0.322202] PCI: Using configuration type 1 for base access [ 0.348634] bio: create slab at 0 [ 0.350786] ACPI: Added _OSI(Module Device) [ 0.353256] ACPI: Added _OSI(Processor Device) [ 0.354509] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.356065] ACPI: Added _OSI(Processor Aggregator Device) [ 0.559295] ACPI: Interpreter enabled [ 0.560039] ACPI: (supports S0 S3 S4 S5) [ 0.562446] ACPI: Using IOAPIC for interrupt routing [ 0.857221] ACPI: No dock devices found. [ 0.858208] PCI: Ignoring host bridge windows from ACPI; if necessar= y, use "pci=3Duse_crs" and report a bug [ 0.861656] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.864746] PCI host bridge to bus 0000:00 [ 0.865675] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 0.866989] pci_bus 0000:00: root bus resource [mem 0x00000000-0xfff= fffffff] [ 0.871061] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by = PIIX4 ACPI [ 0.872068] pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by = PIIX4 SMB [ 0.949295] pci0000:00: Unable to request _OSC control (_OSC suppor= t mask: 0x18) [ 1.558297] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 1.563546] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 1.570707] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 1.576655] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 1.582843] ACPI: PCI Interrupt Link [LNKS] (IRQs 9) *0 [ 1.586374] vgaarb: device added: PCI:0000:00:02.0,decodes=3Dio+mem,= owns=3Dio+mem,locks=3Dnone [ 1.588884] vgaarb: loaded [ 1.589714] vgaarb: bridge control possible 0000:00:02.0 [ 1.597144] SCSI subsystem initialized [ 1.600542] PCI: Using ACPI for IRQ routing [ 1.606292] HPET: 3 timers in total, 0 timers will be used for per-c= pu timer [ 1.626553] Switching to clocksource kvm-clock [ 1.895262] pnp: PnP ACPI init [ 1.896376] ACPI: bus type pnp registered [ 1.926511] pnp: PnP ACPI: found 8 devices [ 1.927524] ACPI: ACPI bus type pnp unregistered [ 1.971474] NET: Registered protocol family 2 [ 1.973160] IP route cache hash table entries: 4096 (order: 3, 32768= bytes) [ 1.975901] TCP established hash table entries: 16384 (order: 6, 262= 144 bytes) [ 1.978510] TCP bind hash table entries: 16384 (order: 8, 1310720 by= tes) [ 1.981014] TCP: Hash tables configured (established 16384 bind 1638= 4) [ 1.982280] TCP reno registered [ 1.983765] UDP hash table entries: 256 (order: 3, 49152 bytes) [ 1.985783] UDP-Lite hash table entries: 256 (order: 3, 49152 bytes) [ 1.989160] NET: Registered protocol family 1 [ 1.990739] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.992981] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.994737] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.997711] Trying to unpack rootfs image as initramfs... [ 2.233326] debug: unmapping init memory ffff88001fa2e000..ffff88001= fff0000 [ 2.245530] audit: initializing netlink socket (disabled) [ 2.247191] type=3D2000 audit(1328879438.240:1): initialized [ 2.254089] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 2.354852] msgmni has been set to 940 [ 2.358392] Block layer SCSI generic (bsg) driver version 0.4 loaded= (major 254) [ 2.361069] io scheduler noop registered (default) [ 2.362417] start plist test [ 2.364468] end plist test [ 2.366458] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 2.367958] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.= 5 [ 2.371062] acpiphp: Slot [1] registered [ 2.373414] acpiphp: Slot [2] registered [ 2.375738] acpiphp: Slot [3] registered [ 2.378232] acpiphp: Slot [4] registered [ 2.380430] acpiphp: Slot [5] registered [ 2.382982] acpiphp: Slot [6] registered [ 2.385529] acpiphp: Slot [7] registered [ 2.394686] acpiphp: Slot [8] registered [ 2.396926] acpiphp: Slot [9] registered [ 2.399437] acpiphp: Slot [10] registered [ 2.402496] acpiphp: Slot [11] registered [ 2.405816] acpiphp: Slot [12] registered [ 2.408017] acpiphp: Slot [13] registered [ 2.410715] acpiphp: Slot [14] registered [ 2.412753] acpiphp: Slot [15] registered [ 2.414808] acpiphp: Slot [16] registered [ 2.416849] acpiphp: Slot [17] registered [ 2.418799] acpiphp: Slot [18] registered [ 2.420801] acpiphp: Slot [19] registered [ 2.422858] acpiphp: Slot [20] registered [ 2.425851] acpiphp: Slot [21] registered [ 2.427757] acpiphp: Slot [22] registered [ 2.429776] acpiphp: Slot [23] registered [ 2.431693] acpiphp: Slot [24] registered [ 2.434041] acpiphp: Slot [25] registered [ 2.436163] acpiphp: Slot [26] registered [ 2.438146] acpiphp: Slot [27] registered [ 2.441263] acpiphp: Slot [28] registered [ 2.443180] acpiphp: Slot [29] registered [ 2.445183] acpiphp: Slot [30] registered [ 2.447125] acpiphp: Slot [31] registered [ 2.638121] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11 [ 2.645567] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10 [ 2.651847] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled =EF=BF=BD[ 2.932550] serial8250: ttyS0 at I/O 0x3f8 (irq =3D 4) is a= 16550A [ 2.960030] 00:06: ttyS0 at I/O 0x3f8 (irq =3D 4) is a 16550A [ 2.964517] Non-volatile memory driver v1.3 [ 2.965508] Linux agpgart interface v0.103 [ 3.002429] brd: module loaded [ 3.023044] loop: module loaded [ 3.030536] vda: vda1 vda2 [ 3.046770] scsi0 : ata_piix [ 3.052222] scsi1 : ata_piix [ 3.054620] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc060 i= rq 14 [ 3.055938] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc068 i= rq 15 [ 3.065731] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21= -k8-NAPI [ 3.067108] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 3.069335] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at= 0x60,0x64 irq 1,12 [ 3.072770] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.074612] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.077138] mousedev: PS/2 mouse device common for all mice [ 3.080696] input: AT Translated Set 2 keyboard as /devices/platform= /i8042/serio0/input/input0 [ 3.086992] device-mapper: ioctl: 4.22.0-ioctl (2011-10-19) initiali= sed: dm-devel@redhat.com [ 3.089016] cpuidle: using governor ladder [ 3.089979] cpuidle: using governor menu [ 3.091007] TCP cubic registered [ 3.091920] NET: Registered protocol family 17 [ 3.095075] kmemleak: Kernel memory leak detector initialized [ 3.095122] kmemleak: Automatic memory scanning thread started [ 3.213173] ata2.00: ATAPI: QEMU DVD-ROM, 1.0.50, max UDMA/100 [ 3.215130] ata2.00: configured for MWDMA2 [ 3.218559] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM = 1.0. PQ: 0 ANSI: 5 [ 3.223417] sr0: scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray [ 3.224743] cdrom: Uniform CD-ROM driver Revision: 3.20 [ 3.232171] Refined TSC clocksource calibration: 2691.272 MHz. [ 3.233667] debug: unmapping init memory ffffffff8167f000..ffffffff8= 18dc000 [ 3.235507] Write protecting the kernel read-only data: 6144k [ 3.237158] debug: unmapping init memory ffff880001318000..ffff88000= 1400000 [ 3.238618] debug: unmapping init memory ffff8800015a0000..ffff88000= 1600000 modprobe: FATAL: Could not load /lib/modules/3.3.0-rc2+/modules.dep: No= such file or directory [ 3.372305] dracut: dracut-004-256.el6 [ 3.463437] udev: starting version 147 [ 3.465571] udevd (74): /proc/74/oom_adj is deprecated, please use /= proc/74/oom_score_adj instead. [ 3.867773] dracut: Starting plymouth daemon [ 4.433636] EXT4-fs (vda1): INFO: recovery required on readonly file= system [ 4.435071] EXT4-fs (vda1): write access will be enabled during reco= very [ 4.514528] EXT4-fs (vda1): recovery complete [ 4.517143] EXT4-fs (vda1): mounted filesystem with ordered data mod= e. Opts: (null) [ 4.539644] dracut: Mounted root filesystem /dev/vda1 modprobe: FATAL: Could not load /lib/modules/3.3.0-rc2+/modules.dep: No= such file or directory [ 4.706036] dracut: /sbin/load_policy: Can't load policy: No such de= vice [ 4.902961] dracut: Switching root Welcome to Fedora=20 Starting udev: [ 5.302643] udev[224]: starting version 161 [ OK ] Setting hostname localhost.localdomain: [ OK ] Checking filesystems Checking all file systems. [/sbin/fsck.ext4 (1) -- /] fsck.ext4 -a /dev/vda1=20 /dev/vda1: clean, 31463/589824 files, 650384/2359040 blocks [ OK ] Remounting root filesystem in read-write mode: [ 6.881187] EXT4-fs = (vda1): re-mounted. Opts: (null) [ OK ] Mounting local filesystems: [ OK ] Enabling local filesystem quotas: quotaon: Warning: No quota format de= tected in the kernel. [ OK ] Enabling /etc/fstab swaps: [ 7.498226] Adding 1048572k swap on /dev= /vda2. Priority:-1 extents:1 across:1048572k=20 [ OK ] Entering non-interactive startup Starting auditd: [ OK ] Starting system logger: [ OK ] Starting irqbalance: [ OK ] Starting system message bus: [ OK ] Starting acpi daemon: [ OK ] Retrigger failed udev events[ OK ] Starting crond: [ OK ] [ OK ] atd: [ OK ] =46edora release 14 (Laughlin) Kernel 3.3.0-rc2+ on an x86_64 (/dev/ttyS0) localhost.localdomain login: [ 19.927622] PM: Syncing filesystems ...= done. [ 19.949462] Freezing user space processes ... (elapsed 0.02 seconds)= done. [ 19.973325] PM: Preallocating image memory... done (allocated 36953 = pages) [ 20.027658] PM: Allocated 147812 kbytes in 0.05 seconds (2956.24 MB/= s) [ 20.028688] Freezing remaining freezable tasks ... (elapsed 0.01 sec= onds) done. [ 20.051109] PM: freeze of devices complete after 5.348 msecs [ 20.054308] PM: late freeze of devices complete after 1.492 msecs [ 20.056204] ACPI: Preparing to enter system sleep state S4 [ 20.058297] PM: Saving platform NVS memory [ 20.059239] Disabling non-boot CPUs ... [ 20.064208] CPU 1 is now offline [ 20.070176] CPU 2 is now offline [ 20.075103] CPU 3 is now offline [ 20.075929] lockdep: fixing up alternatives. [ 20.078259] PM: Creating hibernation image: [ 20.080938] PM: Need to copy 36297 pages [ 20.080938] PM: Hibernation image created (36297 pages copied) [ 20.080938] kvm-clock: cpu 0, msr 0:1f1d19c1, primary cpu clock, res= ume [ 20.086520] Enabling non-boot CPUs ... [ 20.098149] lockdep: fixing up alternatives. [ 20.098998] Booting Node 0 Processor 1 APIC 0x1 [ 20.066584] kvm-clock: cpu 1, msr 0:1f3d19c1, secondary cpu clock [ 20.066584] Calibrating delay loop (skipped) already calibrated this= CPU [ 20.140629] CPU1 is up [ 20.142184] lockdep: fixing up alternatives. [ 20.143416] Booting Node 0 Processor 2 APIC 0x2 [ 20.072385] kvm-clock: cpu 2, msr 0:1f5d19c1, secondary cpu clock [ 20.072385] Calibrating delay loop (skipped) already calibrated this= CPU [ 20.180614] CPU2 is up [ 20.182809] lockdep: fixing up alternatives. [ 20.183847] Booting Node 0 Processor 3 APIC 0x3 [ 20.077703] kvm-clock: cpu 3, msr 0:1f7d19c1, secondary cpu clock [ 20.077703] Calibrating delay loop (skipped) already calibrated this= CPU [ 20.224597] CPU3 is up [ 20.230960] ACPI: Waking up from system sleep state S4 [ 20.233680] PM: early thaw of devices complete after 0.304 msecs [ 20.401644] ata2.00: configured for MWDMA2 [ 20.403151] PM: thaw of devices complete after 168.149 msecs [ 20.408851] PM: Using 3 thread(s) for compression. [ 20.408853] PM: Compressing and saving image data (36368 pages) ... = done [ 22.576196] PM: Wrote 145472 kbytes in 2.16 seconds (67.34 MB/s) [ 22.579450] PM: S| [ 22.636548] PM: hibernate of devices complete after 2.872 msecs [ 22.638290] PM: late hibernate of devices complete after 0.331 msecs [ 22.639740] ACPI: Preparing to enter system sleep state S4 [ 22.641962] PM: Saving platform NVS memory [ 22.642935] Disabling non-boot CPUs ... [ 22.648517] CPU 1 is now offline [ 22.756125] CPU 2 is now offline [ 22.761957] CPU 3 is now offline [ 22.762818] lockdep: fixing up alternatives. =2E/x86_64-softmmu/qemu-system-x86_64 -kernel ~/src/linux/arch/x86/boot= /bzImage -append 'root=3D/dev/vda1 console=3Dtty0 console=3DttyS0 no_c= onsole_suspend' -initrd ~/tmp/initramfs-2.6.32-217.el6.x86_64.img -driv= e file=3D/guests/f14-suspend.qcow2,if=3Dnone,id=3Ddr0 -device virtio-bl= k-pci,drive=3Ddr0 -net nic,model=3Dvirtio -net user -serial stdio -en= able-kvm -m 512 -cpu host -smp 4 [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Linux version 3.3.0-rc2+ (amit@amit.redhat.com) (gcc ver= sion 4.6.2 20111027 (Red Hat 4.6.2-1) (GCC) ) #295 SMP PREEMPT Fri Feb = 10 18:39:48 IST 2012 [ 0.000000] Command line: root=3D/dev/vda1 console=3Dtty0 console=3D= ttyS0 no_console_suspend [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: 0000000000000000 - 000000000009dc00 (usable) [ 0.000000] BIOS-e820: 000000000009dc00 - 00000000000a0000 (reserve= d) [ 0.000000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserve= d) [ 0.000000] BIOS-e820: 0000000000100000 - 000000001fffd000 (usable) [ 0.000000] BIOS-e820: 000000001fffd000 - 0000000020000000 (reserve= d) [ 0.000000] BIOS-e820: 00000000feffc000 - 00000000ff000000 (reserve= d) [ 0.000000] BIOS-e820: 00000000fffc0000 - 0000000100000000 (reserve= d) [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] DMI 2.4 present. [ 0.000000] No AGP bridge found [ 0.000000] last_pfn =3D 0x1fffd max_arch_pfn =3D 0x400000000 [ 0.000000] x86 PAT enabled: cpu 0, old 0x70406, new 0x7010600070106 [ 0.000000] init_memory_mapping: 0000000000000000-000000001fffd000 [ 0.000000] RAMDISK: 1fa2e000 - 1fff0000 [ 0.000000] ACPI: RSDP 00000000000fd3f0 00014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 000000001fffd660 00034 (v01 BOCHS BXPCRSDT 0= 0000001 BXPC 00000001) [ 0.000000] ACPI: FACP 000000001fffff80 00074 (v01 BOCHS BXPCFACP 0= 0000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 000000001fffd9b0 02589 (v01 BXPC BXDSDT 0= 0000001 INTL 20100528) [ 0.000000] ACPI: FACS 000000001fffff40 00040 [ 0.000000] ACPI: SSDT 000000001fffd7e0 001C1 (v01 BOCHS BXPCSSDT 0= 0000001 BXPC 00000001) [ 0.000000] ACPI: APIC 000000001fffd6e0 0008A (v01 BOCHS BXPCAPIC 0= 0000001 BXPC 00000001) [ 0.000000] ACPI: HPET 000000001fffd6a0 00038 (v01 BOCHS BXPCHPET 0= 0000001 BXPC 00000001) [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:18509c1, boot clock [ 0.000000] Zone PFN ranges: [ 0.000000] DMA 0x00000010 -> 0x00001000 [ 0.000000] DMA32 0x00001000 -> 0x00100000 [ 0.000000] Normal empty [ 0.000000] Movable zone start PFN for each node [ 0.000000] Early memory PFN ranges [ 0.000000] 0: 0x00000010 -> 0x0000009d [ 0.000000] 0: 0x00000100 -> 0x0001fffd [ 0.000000] ACPI: PM-Timer IO Port: 0xb008 [ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x03] enabled) [ 0.000000] ACPI: IOAPIC (id[0x04] address[0xfec00000] gsi_base[0]) [ 0.000000] IOAPIC[0]: apic_id 4, version 17, address 0xfec00000, GS= I 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 lev= el) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high lev= el) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high l= evel) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high l= evel) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] SMP: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] PM: Registered nosave memory: 000000000009d000 - 0000000= 00009e000 [ 0.000000] PM: Registered nosave memory: 000000000009e000 - 0000000= 0000a0000 [ 0.000000] PM: Registered nosave memory: 00000000000a0000 - 0000000= 0000f0000 [ 0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000= 000100000 [ 0.000000] Allocating PCI resources starting at 20000000 (gap: 2000= 0000:deffc000) [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] setup_percpu: NR_CPUS:4 nr_cpumask_bits:4 nr_cpu_ids:4 n= r_node_ids:1 [ 0.000000] PERCPU: Embedded 474 pages/cpu @ffff88001f000000 s191078= 4 r8192 d22528 u2097152 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. = Total pages: 128901 [ 0.000000] Kernel command line: root=3D/dev/vda1 console=3Dtty0 con= sole=3DttyS0 no_console_suspend [ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes) [ 0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288= bytes) [ 0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 = bytes) [ 0.000000] xsave/xrstor: enabled xstate_bv 0x7, cntxt size 0x340 [ 0.000000] Checking aperture... [ 0.000000] No AGP bridge found [ 0.000000] Memory: 481644k/524276k available (3162k kernel code, 46= 0k absent, 42172k reserved, 3483k data, 2420k init) [ 0.000000] SLUB: Genslabs=3D15, HWalign=3D64, Order=3D0-3, MinObjec= ts=3D0, CPUs=3D4, Nodes=3D1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] NR_IRQS:4352 nr_irqs:712 16 [ 0.000000] Console: colour VGA+ 80x25 [ 0.000000] console [tty0] enabled [ 0.000000] console [ttyS0] enabled [ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, I= nc., Ingo Molnar [ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.000000] ... MAX_LOCK_DEPTH: 48 [ 0.000000] ... MAX_LOCKDEP_KEYS: 8191 [ 0.000000] ... CLASSHASH_SIZE: 4096 [ 0.000000] ... MAX_LOCKDEP_ENTRIES: 16384 [ 0.000000] ... MAX_LOCKDEP_CHAINS: 32768 [ 0.000000] ... CHAINHASH_SIZE: 16384 [ 0.000000] memory used by lock dependency info: 6367 kB [ 0.000000] per task-struct memory footprint: 2688 bytes [ 0.000000] Detected 2691.258 MHz processor. [ 0.008000] Calibrating delay loop (skipped) preset value.. 5382.51 = BogoMIPS (lpj=3D10765032) [ 0.008000] pid_max: default: 32768 minimum: 301 [ 0.012212] Mount-cache hash table entries: 256 [ 0.018408] ACPI: Core revision 20120111 [ 0.141561] ftrace: allocating 14018 entries in 55 pages [ 0.150614] ..TIMER: vector=3D0x30 apic1=3D0 pin1=3D2 apic2=3D-1 pin= 2=3D-1 [ 0.195946] CPU0: Intel(R) Core(TM) i7-2620M CPU @ 2.70GHz stepping = 07 [ 0.200011] APIC calibration not consistent with PM-Timer: 91ms inst= ead of 100ms [ 0.200011] APIC delta adjusted to PM-Timer: 6249972 (5729866) [ 0.200011] Performance Events: unsupported p6 CPU model 42 no PMU d= river, software events only. [ 0.220474] lockdep: fixing up alternatives. [ 0.232381] Booting Node 0, Processors #1 [ 0.008000] kvm-clock: cpu 1, msr 0:1f3d19c1, secondary cpu clock [ 0.260131] lockdep: fixing up alternatives. [ 0.261537] #2 [ 0.008000] kvm-clock: cpu 2, msr 0:1f5d19c1, secondary cpu clock [ 0.284158] lockdep: fixing up alternatives. [ 0.285621] #3 Ok. [ 0.008000] kvm-clock: cpu 3, msr 0:1f7d19c1, secondary cpu clock [ 0.300098] Brought up 4 CPUs [ 0.300948] Total of 4 processors activated (21530.06 BogoMIPS). [ 0.314752] NET: Registered protocol family 16 [ 0.318516] ACPI: bus type pci registered [ 0.320059] PCI: Using configuration type 1 for base access [ 0.348760] bio: create slab at 0 [ 0.351121] ACPI: Added _OSI(Module Device) [ 0.353139] ACPI: Added _OSI(Processor Device) [ 0.354521] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.356109] ACPI: Added _OSI(Processor Aggregator Device) [ 0.561419] ACPI: Interpreter enabled [ 0.562374] ACPI: (supports S0 S3 S4 S5) [ 0.564868] ACPI: Using IOAPIC for interrupt routing [ 0.859396] ACPI: No dock devices found. [ 0.860059] PCI: Ignoring host bridge windows from ACPI; if necessar= y, use "pci=3Duse_crs" and report a bug [ 0.862495] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.866923] PCI host bridge to bus 0000:00 [ 0.867948] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 0.868060] pci_bus 0000:00: root bus resource [mem 0x00000000-0xfff= fffffff] [ 0.873543] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by = PIIX4 ACPI [ 0.875301] pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by = PIIX4 SMB [ 0.950413] pci0000:00: Unable to request _OSC control (_OSC suppor= t mask: 0x18) [ 1.562832] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 1.567293] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 1.573939] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 1.579086] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 1.584993] ACPI: PCI Interrupt Link [LNKS] (IRQs 9) *0 [ 1.591202] vgaarb: device added: PCI:0000:00:02.0,decodes=3Dio+mem,= owns=3Dio+mem,locks=3Dnone [ 1.592213] vgaarb: loaded [ 1.593197] vgaarb: bridge control possible 0000:00:02.0 [ 1.600683] SCSI subsystem initialized [ 1.602115] PCI: Using ACPI for IRQ routing [ 1.608286] HPET: 3 timers in total, 0 timers will be used for per-c= pu timer [ 1.622720] Switching to clocksource kvm-clock [ 1.890639] pnp: PnP ACPI init [ 1.891666] ACPI: bus type pnp registered [ 1.922936] pnp: PnP ACPI: found 8 devices [ 1.923944] ACPI: ACPI bus type pnp unregistered [ 1.968395] NET: Registered protocol family 2 [ 1.969976] IP route cache hash table entries: 4096 (order: 3, 32768= bytes) [ 1.973286] TCP established hash table entries: 16384 (order: 6, 262= 144 bytes) [ 1.976340] TCP bind hash table entries: 16384 (order: 8, 1310720 by= tes) [ 1.980266] TCP: Hash tables configured (established 16384 bind 1638= 4) [ 1.982352] TCP reno registered [ 1.983533] UDP hash table entries: 256 (order: 3, 49152 bytes) [ 1.985221] UDP-Lite hash table entries: 256 (order: 3, 49152 bytes) [ 1.987980] NET: Registered protocol family 1 [ 1.989836] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.991366] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.993029] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.996522] Trying to unpack rootfs image as initramfs... [ 2.232249] debug: unmapping init memory ffff88001fa2e000..ffff88001= fff0000 [ 2.245977] audit: initializing netlink socket (disabled) [ 2.247348] type=3D2000 audit(1328879465.244:1): initialized [ 2.251524] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 2.351738] msgmni has been set to 940 [ 2.355237] Block layer SCSI generic (bsg) driver version 0.4 loaded= (major 254) [ 2.357442] io scheduler noop registered (default) [ 2.358481] start plist test [ 2.359779] end plist test [ 2.361469] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 2.362634] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.= 5 [ 2.366210] acpiphp: Slot [1] registered [ 2.368727] acpiphp: Slot [2] registered [ 2.370920] acpiphp: Slot [3] registered [ 2.373578] acpiphp: Slot [4] registered [ 2.375487] acpiphp: Slot [5] registered [ 2.377552] acpiphp: Slot [6] registered [ 2.379646] acpiphp: Slot [7] registered [ 2.387397] acpiphp: Slot [8] registered [ 2.390006] acpiphp: Slot [9] registered [ 2.391886] acpiphp: Slot [10] registered [ 2.394514] acpiphp: Slot [11] registered [ 2.396815] acpiphp: Slot [12] registered [ 2.398788] acpiphp: Slot [13] registered [ 2.400736] acpiphp: Slot [14] registered [ 2.402639] acpiphp: Slot [15] registered [ 2.405391] acpiphp: Slot [16] registered [ 2.407292] acpiphp: Slot [17] registered [ 2.409260] acpiphp: Slot [18] registered [ 2.411407] acpiphp: Slot [19] registered [ 2.413523] acpiphp: Slot [20] registered [ 2.415518] acpiphp: Slot [21] registered [ 2.417621] acpiphp: Slot [22] registered [ 2.419568] acpiphp: Slot [23] registered [ 2.422532] acpiphp: Slot [24] registered [ 2.424886] acpiphp: Slot [25] registered [ 2.426863] acpiphp: Slot [26] registered [ 2.428817] acpiphp: Slot [27] registered [ 2.430857] acpiphp: Slot [28] registered [ 2.432838] acpiphp: Slot [29] registered [ 2.434813] acpiphp: Slot [30] registered [ 2.437884] acpiphp: Slot [31] registered [ 2.625069] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11 [ 2.635091] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10 [ 2.638752] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled =EF=BF=BD[ 2.920580] serial8250: ttyS0 at I/O 0x3f8 (irq =3D 4) is a= 16550A [ 2.947902] 00:06: ttyS0 at I/O 0x3f8 (irq =3D 4) is a 16550A [ 2.952259] Non-volatile memory driver v1.3 [ 2.953273] Linux agpgart interface v0.103 [ 2.990450] brd: module loaded [ 3.010653] loop: module loaded [ 3.018104] vda: vda1 vda2 [ 3.035013] scsi0 : ata_piix [ 3.040861] scsi1 : ata_piix [ 3.043167] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc060 i= rq 14 [ 3.044520] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc068 i= rq 15 [ 3.053183] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21= -k8-NAPI [ 3.054888] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 3.058466] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at= 0x60,0x64 irq 1,12 [ 3.063337] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.065592] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.068414] mousedev: PS/2 mouse device common for all mice [ 3.073460] input: AT Translated Set 2 keyboard as /devices/platform= /i8042/serio0/input/input0 [ 3.077951] device-mapper: ioctl: 4.22.0-ioctl (2011-10-19) initiali= sed: dm-devel@redhat.com [ 3.080248] cpuidle: using governor ladder [ 3.081293] cpuidle: using governor menu [ 3.082362] TCP cubic registered [ 3.083268] NET: Registered protocol family 17 [ 3.087082] kmemleak: Kernel memory leak detector initialized [ 3.087108] kmemleak: Automatic memory scanning thread started [ 3.201317] ata2.00: ATAPI: QEMU DVD-ROM, 1.0.50, max UDMA/100 [ 3.204954] ata2.00: configured for MWDMA2 [ 3.208856] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM = 1.0. PQ: 0 ANSI: 5 [ 3.213979] sr0: scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray [ 3.215259] cdrom: Uniform CD-ROM driver Revision: 3.20 [ 3.224212] debug: unmapping init memory ffffffff8167f000..ffffffff8= 18dc000 [ 3.226117] Write protecting the kernel read-only data: 6144k [ 3.227745] debug: unmapping init memory ffff880001318000..ffff88000= 1400000 [ 3.229802] debug: unmapping init memory ffff8800015a0000..ffff88000= 1600000 [ 3.234772] Refined TSC clocksource calibration: 2691.274 MHz. modprobe: FATAL: Could not load /lib/modules/3.3.0-rc2+/modules.dep: No= such file or directory [ 3.376631] dracut: dracut-004-256.el6 [ 3.465878] udev: starting version 147 [ 3.468440] udevd (74): /proc/74/oom_adj is deprecated, please use /= proc/74/oom_score_adj instead. [ 3.787549] dracut: Starting plymouth daemon [ 4.232996] PM: Starting manual resume from disk [ 4.276874] Freezing user space processes ... (elapsed 0.01 seconds)= done. [ 4.304223] PM: Using 3 thread(s) for decompression. [ 4.304224] PM: Loading and decompressing image data (36368 pages) .= =2E. done [ 6.263529] PM: Read 145472 kbytes in 1.95 seconds (74.60 MB/s) [ 6.277105] PM: quiesce of devices complete after 5.331 msecs [ 6.279903] PM: late quiesce of devices complete after 1.229 msecs [ 6.281790] Disabling non-boot CPUs ... [ 6.286658] CPU 1 is now offline [ 6.392122] CPU 2 is now offline [ 6.397382] CPU 3 is now offline [ 6.397988] lockdep: fixing up alternatives. [ 20.080938] kvm-clock: cpu 0, msr 0:1f1d19c1, primary cpu clock, res= ume [ 20.080938] PM: Restoring platform NVS memory [ 20.086361] Enabling non-boot CPUs ... [ 20.097892] lockdep: fixing up alternatives. [ 20.099072] Booting Node 0 Processor 1 APIC 0x1 [ 20.066584] kvm-clock: cpu 1, msr 0:1f3d19c1, secondary cpu clock [ 20.066584] Calibrating delay loop (skipped) already calibrated this= CPU [ 20.140678] CPU1 is up [ 20.142345] lockdep: fixing up alternatives. [ 20.143531] Booting Node 0 Processor 2 APIC 0x2 [ 20.072385] kvm-clock: cpu 2, msr 0:1f5d19c1, secondary cpu clock [ 20.072385] Calibrating delay loop (skipped) already calibrated this= CPU [ 20.188725] CPU2 is up [ 20.191285] lockdep: fixing up alternatives. [ 20.192714] Booting Node 0 Processor 3 APIC 0x3 [ 20.077703] kvm-clock: cpu 3, msr 0:1f7d19c1, secondary cpu clock [ 20.077703] Calibrating delay loop (skipped) already calibrated this= CPU [ 20.232565] CPU3 is up [ 20.234208] ACPI: Waking up from system sleep state S4 [ 20.236994] PM: early restore of devices complete after 1.341 msecs [ 20.449753] ata2.00: configured for MWDMA2 [ 20.451505] PM: restore of devices complete after 164.925 msecs [ 20.454164] Restarting tasks ... done. [ 26.954508] PM: Syncing filesystems ... done. [ 26.962114] Freezing user space processes ... (elapsed 0.01 seconds)= done. [ 26.981693] PM: Preallocating image memory... done (allocated 36779 = pages) [ 27.031627] PM: Allocated 147116 kbytes in 0.04 seconds (3677.90 MB/= s) [ 27.033069] Freezing remaining freezable tasks ... (elapsed 0.01 sec= onds) done. [ 27.053831] PM: freeze of devices complete after 3.511 msecs [ 27.057025] PM: late freeze of devices complete after 1.345 msecs [ 27.058970] ACPI: Preparing to enter system sleep state S4 [ 27.061183] PM: Saving platform NVS memory [ 27.062449] Disabling non-boot CPUs ... [ 27.071546] Broke affinity for irq 1 [ 27.172141] CPU 1 is now offline [ 27.280127] CPU 2 is now offline [ 27.285542] CPU 3 is now offline [ 27.286668] lockdep: fixing up alternatives. [ 27.289201] PM: Creating hibernation image: [ 27.292051] PM: Need to copy 36433 pages [ 27.292051] PM: Hibernation image created (36433 pages copied) [ 27.292051] kvm-clock: cpu 0, msr 0:1f1d19c1, primary cpu clock, res= ume [ 27.297473] Enabling non-boot CPUs ... [ 27.307663] lockdep: fixing up alternatives. [ 27.309283] Booting Node 0 Processor 1 APIC 0x1 [ 27.074626] kvm-clock: cpu 1, msr 0:1f3d19c1, secondary cpu clock [ 27.074626] Calibrating delay loop (skipped) already calibrated this= CPU [ 27.356732] CPU1 is up [ 27.359102] lockdep: fixing up alternatives. [ 27.360289] Booting Node 0 Processor 2 APIC 0x2 [ 27.181025] kvm-clock: cpu 2, msr 0:1f5d19c1, secondary cpu clock [ 27.181025] Calibrating delay loop (skipped) already calibrated this= CPU [ 27.404533] CPU2 is up [ 27.406678] lockdep: fixing up alternatives. [ 27.407643] Booting Node 0 Processor 3 APIC 0x3 [ 27.288009] kvm-clock: cpu 3, msr 0:1f7d19c1, secondary cpu clock [ 27.288009] Calibrating delay loop (skipped) already calibrated this= CPU [ 27.448610] CPU3 is up [ 27.453028] ACPI: Waking up from system sleep state S4 [ 27.455146] PM: early thaw of devices complete after 0.398 msecs [ 27.621265] ata2.00: configured for MWDMA2 [ 27.623344] PM: thaw of devices complete after 166.397 msecs [ 27.628990] PM: Using 3 thread(s) for compression. [ 27.628991] PM: Compressing and saving image data (36505 pages) ... = done [ 29.748964] PM: Wrote 146020 kbytes in 2.11 seconds (69.20 MB/s) [ 29.752724] PM: S| [ 29.809222] PM: hibernate of devices complete after 2.545 msecs [ 29.810821] PM: late hibernate of devices complete after 0.281 msecs [ 29.812158] ACPI: Preparing to enter system sleep state S4 [ 29.813841] PM: Saving platform NVS memory [ 29.814814] Disabling non-boot CPUs ... [ 29.920146] CPU 1 is now offline [ 30.028156] CPU 2 is now offline [ 30.033019] Broke affinity for irq 15 [ 30.034989] CPU 3 is now offline [ 30.036411] lockdep: fixing up alternatives. =2E/x86_64-softmmu/qemu-system-x86_64 -kernel ~/src/linux/arch/x86/boot= /bzImage -append 'root=3D/dev/vda1 console=3Dtty0 console=3DttyS0 no_c= onsole_suspend' -initrd ~/tmp/initramfs-2.6.32-217.el6.x86_64.img -driv= e file=3D/guests/f14-suspend.qcow2,if=3Dnone,id=3Ddr0 -device virtio-bl= k-pci,drive=3Ddr0 -net nic,model=3Dvirtio -net user -serial stdio -en= able-kvm -m 512 -cpu host -smp 4 [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Linux version 3.3.0-rc2+ (amit@amit.redhat.com) (gcc ver= sion 4.6.2 20111027 (Red Hat 4.6.2-1) (GCC) ) #295 SMP PREEMPT Fri Feb = 10 18:39:48 IST 2012 [ 0.000000] Command line: root=3D/dev/vda1 console=3Dtty0 console=3D= ttyS0 no_console_suspend [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: 0000000000000000 - 000000000009dc00 (usable) [ 0.000000] BIOS-e820: 000000000009dc00 - 00000000000a0000 (reserve= d) [ 0.000000] BIOS-e820: 00000000000f0000 - 0000000000100000 (reserve= d) [ 0.000000] BIOS-e820: 0000000000100000 - 000000001fffd000 (usable) [ 0.000000] BIOS-e820: 000000001fffd000 - 0000000020000000 (reserve= d) [ 0.000000] BIOS-e820: 00000000feffc000 - 00000000ff000000 (reserve= d) [ 0.000000] BIOS-e820: 00000000fffc0000 - 0000000100000000 (reserve= d) [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] DMI 2.4 present. [ 0.000000] No AGP bridge found [ 0.000000] last_pfn =3D 0x1fffd max_arch_pfn =3D 0x400000000 [ 0.000000] x86 PAT enabled: cpu 0, old 0x70406, new 0x7010600070106 [ 0.000000] init_memory_mapping: 0000000000000000-000000001fffd000 [ 0.000000] RAMDISK: 1fa2e000 - 1fff0000 [ 0.000000] ACPI: RSDP 00000000000fd3f0 00014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 000000001fffd660 00034 (v01 BOCHS BXPCRSDT 0= 0000001 BXPC 00000001) [ 0.000000] ACPI: FACP 000000001fffff80 00074 (v01 BOCHS BXPCFACP 0= 0000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 000000001fffd9b0 02589 (v01 BXPC BXDSDT 0= 0000001 INTL 20100528) [ 0.000000] ACPI: FACS 000000001fffff40 00040 [ 0.000000] ACPI: SSDT 000000001fffd7e0 001C1 (v01 BOCHS BXPCSSDT 0= 0000001 BXPC 00000001) [ 0.000000] ACPI: APIC 000000001fffd6e0 0008A (v01 BOCHS BXPCAPIC 0= 0000001 BXPC 00000001) [ 0.000000] ACPI: HPET 000000001fffd6a0 00038 (v01 BOCHS BXPCHPET 0= 0000001 BXPC 00000001) [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:18509c1, boot clock [ 0.000000] Zone PFN ranges: [ 0.000000] DMA 0x00000010 -> 0x00001000 [ 0.000000] DMA32 0x00001000 -> 0x00100000 [ 0.000000] Normal empty [ 0.000000] Movable zone start PFN for each node [ 0.000000] Early memory PFN ranges [ 0.000000] 0: 0x00000010 -> 0x0000009d [ 0.000000] 0: 0x00000100 -> 0x0001fffd [ 0.000000] ACPI: PM-Timer IO Port: 0xb008 [ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x03] enabled) [ 0.000000] ACPI: IOAPIC (id[0x04] address[0xfec00000] gsi_base[0]) [ 0.000000] IOAPIC[0]: apic_id 4, version 17, address 0xfec00000, GS= I 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 lev= el) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high lev= el) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high l= evel) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high l= evel) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] SMP: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] PM: Registered nosave memory: 000000000009d000 - 0000000= 00009e000 [ 0.000000] PM: Registered nosave memory: 000000000009e000 - 0000000= 0000a0000 [ 0.000000] PM: Registered nosave memory: 00000000000a0000 - 0000000= 0000f0000 [ 0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000= 000100000 [ 0.000000] Allocating PCI resources starting at 20000000 (gap: 2000= 0000:deffc000) [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] setup_percpu: NR_CPUS:4 nr_cpumask_bits:4 nr_cpu_ids:4 n= r_node_ids:1 [ 0.000000] PERCPU: Embedded 474 pages/cpu @ffff88001f000000 s191078= 4 r8192 d22528 u2097152 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. = Total pages: 128901 [ 0.000000] Kernel command line: root=3D/dev/vda1 console=3Dtty0 con= sole=3DttyS0 no_console_suspend [ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes) [ 0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288= bytes) [ 0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 = bytes) [ 0.000000] xsave/xrstor: enabled xstate_bv 0x7, cntxt size 0x340 [ 0.000000] Checking aperture... [ 0.000000] No AGP bridge found [ 0.000000] Memory: 481644k/524276k available (3162k kernel code, 46= 0k absent, 42172k reserved, 3483k data, 2420k init) [ 0.000000] SLUB: Genslabs=3D15, HWalign=3D64, Order=3D0-3, MinObjec= ts=3D0, CPUs=3D4, Nodes=3D1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] NR_IRQS:4352 nr_irqs:712 16 [ 0.000000] Console: colour VGA+ 80x25 [ 0.000000] console [tty0] enabled [ 0.000000] console [ttyS0] enabled [ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, I= nc., Ingo Molnar [ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.000000] ... MAX_LOCK_DEPTH: 48 [ 0.000000] ... MAX_LOCKDEP_KEYS: 8191 [ 0.000000] ... CLASSHASH_SIZE: 4096 [ 0.000000] ... MAX_LOCKDEP_ENTRIES: 16384 [ 0.000000] ... MAX_LOCKDEP_CHAINS: 32768 [ 0.000000] ... CHAINHASH_SIZE: 16384 [ 0.000000] memory used by lock dependency info: 6367 kB [ 0.000000] per task-struct memory footprint: 2688 bytes [ 0.000000] Detected 2691.258 MHz processor. [ 0.012000] Calibrating delay loop (skipped) preset value.. 5382.51 = BogoMIPS (lpj=3D10765032) [ 0.012000] pid_max: default: 32768 minimum: 301 [ 0.012000] Mount-cache hash table entries: 256 [ 0.019120] ACPI: Core revision 20120111 [ 0.144778] ftrace: allocating 14018 entries in 55 pages [ 0.153954] ..TIMER: vector=3D0x30 apic1=3D0 pin1=3D2 apic2=3D-1 pin= 2=3D-1 [ 0.198922] CPU0: Intel(R) Core(TM) i7-2620M CPU @ 2.70GHz stepping = 07 [ 0.204012] APIC calibration not consistent with PM-Timer: 92ms inst= ead of 100ms [ 0.204012] APIC delta adjusted to PM-Timer: 6249983 (5750078) [ 0.204012] Performance Events: unsupported p6 CPU model 42 no PMU d= river, software events only. [ 0.224671] lockdep: fixing up alternatives. [ 0.236366] Booting Node 0, Processors #1 [ 0.012000] kvm-clock: cpu 1, msr 0:1f3d19c1, secondary cpu clock [ 0.264156] lockdep: fixing up alternatives. [ 0.265510] #2 [ 0.012000] kvm-clock: cpu 2, msr 0:1f5d19c1, secondary cpu clock [ 0.288199] lockdep: fixing up alternatives. [ 0.289706] #3 Ok. [ 0.012000] kvm-clock: cpu 3, msr 0:1f7d19c1, secondary cpu clock [ 0.304090] Brought up 4 CPUs [ 0.305140] Total of 4 processors activated (21530.06 BogoMIPS). [ 0.320274] NET: Registered protocol family 16 [ 0.324019] ACPI: bus type pci registered [ 0.324594] PCI: Using configuration type 1 for base access [ 0.353632] bio: create slab at 0 [ 0.356647] ACPI: Added _OSI(Module Device) [ 0.358035] ACPI: Added _OSI(Processor Device) [ 0.359445] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.360842] ACPI: Added _OSI(Processor Aggregator Device) [ 0.565985] ACPI: Interpreter enabled [ 0.567277] ACPI: (supports S0 S3 S4 S5) [ 0.571106] ACPI: Using IOAPIC for interrupt routing [ 0.878052] ACPI: No dock devices found. [ 0.879152] PCI: Ignoring host bridge windows from ACPI; if necessar= y, use "pci=3Duse_crs" and report a bug [ 0.880511] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.885429] PCI host bridge to bus 0000:00 [ 0.886466] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 0.887737] pci_bus 0000:00: root bus resource [mem 0x00000000-0xfff= fffffff] [ 0.892837] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by = PIIX4 ACPI [ 0.894750] pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by = PIIX4 SMB [ 0.970335] pci0000:00: Unable to request _OSC control (_OSC suppor= t mask: 0x18) [ 1.583715] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 1.590390] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 1.596519] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 1.602490] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 1.609401] ACPI: PCI Interrupt Link [LNKS] (IRQs 9) *0 [ 1.614133] vgaarb: device added: PCI:0000:00:02.0,decodes=3Dio+mem,= owns=3Dio+mem,locks=3Dnone [ 1.616905] vgaarb: loaded [ 1.617710] vgaarb: bridge control possible 0000:00:02.0 [ 1.622992] SCSI subsystem initialized [ 1.625239] PCI: Using ACPI for IRQ routing [ 1.630700] HPET: 3 timers in total, 0 timers will be used for per-c= pu timer [ 1.654453] Switching to clocksource kvm-clock [ 1.922667] pnp: PnP ACPI init [ 1.923703] ACPI: bus type pnp registered [ 1.954649] pnp: PnP ACPI: found 8 devices [ 1.955665] ACPI: ACPI bus type pnp unregistered [ 1.999911] NET: Registered protocol family 2 [ 2.001578] IP route cache hash table entries: 4096 (order: 3, 32768= bytes) [ 2.004869] TCP established hash table entries: 16384 (order: 6, 262= 144 bytes) [ 2.006856] TCP bind hash table entries: 16384 (order: 8, 1310720 by= tes) [ 2.009280] TCP: Hash tables configured (established 16384 bind 1638= 4) [ 2.010583] TCP reno registered [ 2.011458] UDP hash table entries: 256 (order: 3, 49152 bytes) [ 2.013055] UDP-Lite hash table entries: 256 (order: 3, 49152 bytes) [ 2.015547] NET: Registered protocol family 1 [ 2.016802] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 2.018184] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 2.019566] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 2.022396] Trying to unpack rootfs image as initramfs... [ 2.258350] debug: unmapping init memory ffff88001fa2e000..ffff88001= fff0000 [ 2.271675] audit: initializing netlink socket (disabled) [ 2.273096] type=3D2000 audit(1328879486.272:1): initialized [ 2.279331] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 2.377735] msgmni has been set to 940 [ 2.381564] Block layer SCSI generic (bsg) driver version 0.4 loaded= (major 254) [ 2.383252] io scheduler noop registered (default) [ 2.384845] start plist test [ 2.386357] end plist test [ 2.387986] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 2.389326] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.= 5 [ 2.392128] acpiphp: Slot [1] registered [ 2.394065] acpiphp: Slot [2] registered [ 2.395957] acpiphp: Slot [3] registered [ 2.398352] acpiphp: Slot [4] registered [ 2.400313] acpiphp: Slot [5] registered [ 2.402295] acpiphp: Slot [6] registered [ 2.404291] acpiphp: Slot [7] registered [ 2.412976] acpiphp: Slot [8] registered [ 2.415126] acpiphp: Slot [9] registered [ 2.417209] acpiphp: Slot [10] registered [ 2.419484] acpiphp: Slot [11] registered [ 2.421641] acpiphp: Slot [12] registered [ 2.423644] acpiphp: Slot [13] registered [ 2.425771] acpiphp: Slot [14] registered [ 2.427717] acpiphp: Slot [15] registered [ 2.430512] acpiphp: Slot [16] registered [ 2.432502] acpiphp: Slot [17] registered [ 2.434420] acpiphp: Slot [18] registered [ 2.436616] acpiphp: Slot [19] registered [ 2.438625] acpiphp: Slot [20] registered [ 2.440626] acpiphp: Slot [21] registered [ 2.442586] acpiphp: Slot [22] registered [ 2.445915] acpiphp: Slot [23] registered [ 2.447828] acpiphp: Slot [24] registered [ 2.449849] acpiphp: Slot [25] registered [ 2.451754] acpiphp: Slot [26] registered [ 2.453822] acpiphp: Slot [27] registered [ 2.455815] acpiphp: Slot [28] registered [ 2.457862] acpiphp: Slot [29] registered [ 2.459817] acpiphp: Slot [30] registered [ 2.463363] acpiphp: Slot [31] registered [ 2.652004] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11 [ 2.662122] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10 [ 2.665594] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled =EF=BF=BD[ 2.944554] serial8250: ttyS0 at I/O 0x3f8 (irq =3D 4) is a= 16550A [ 2.972203] 00:06: ttyS0 at I/O 0x3f8 (irq =3D 4) is a 16550A [ 2.976490] Non-volatile memory driver v1.3 [ 2.977506] Linux agpgart interface v0.103 [ 3.014141] brd: module loaded [ 3.033693] loop: module loaded [ 3.041319] vda: vda1 vda2 [ 3.059399] scsi0 : ata_piix [ 3.065431] scsi1 : ata_piix [ 3.068000] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc060 i= rq 14 [ 3.070550] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc068 i= rq 15 [ 3.081620] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21= -k8-NAPI [ 3.083229] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 3.086006] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at= 0x60,0x64 irq 1,12 [ 3.089426] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.091309] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.093860] mousedev: PS/2 mouse device common for all mice [ 3.097682] input: AT Translated Set 2 keyboard as /devices/platform= /i8042/serio0/input/input0 [ 3.107859] device-mapper: ioctl: 4.22.0-ioctl (2011-10-19) initiali= sed: dm-devel@redhat.com [ 3.110152] cpuidle: using governor ladder [ 3.111213] cpuidle: using governor menu [ 3.112544] TCP cubic registered [ 3.113505] NET: Registered protocol family 17 [ 3.116525] kmemleak: Kernel memory leak detector initialized [ 3.116550] kmemleak: Automatic memory scanning thread started [ 3.229160] ata2.00: ATAPI: QEMU DVD-ROM, 1.0.50, max UDMA/100 [ 3.230765] ata2.00: configured for MWDMA2 [ 3.234229] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM = 1.0. PQ: 0 ANSI: 5 [ 3.240337] sr0: scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray [ 3.241736] cdrom: Uniform CD-ROM driver Revision: 3.20 [ 3.250342] debug: unmapping init memory ffffffff8167f000..ffffffff8= 18dc000 [ 3.252205] Write protecting the kernel read-only data: 6144k [ 3.254771] debug: unmapping init memory ffff880001318000..ffff88000= 1400000 [ 3.257081] debug: unmapping init memory ffff8800015a0000..ffff88000= 1600000 [ 3.260140] Refined TSC clocksource calibration: 2691.271 MHz. modprobe: FATAL: Could not load /lib/modules/3.3.0-rc2+/modules.dep: No= such file or directory [ 3.398866] dracut: dracut-004-256.el6 [ 3.490652] udev: starting version 147 [ 3.492541] udevd (74): /proc/74/oom_adj is deprecated, please use /= proc/74/oom_score_adj instead. [ 3.810111] dracut: Starting plymouth daemon [ 4.288856] PM: Starting manual resume from disk [ 4.326388] Freezing user space processes ... (elapsed 0.02 seconds)= done. [ 4.363688] PM: Using 3 thread(s) for decompression. [ 4.363689] PM: Loading and decompressing image data (36505 pages) .= =2E. done [ 6.317330] PM: Read 146020 kbytes in 1.94 seconds (75.26 MB/s) [ 6.331360] PM: quiesce of devices complete after 5.255 msecs [ 6.333865] PM: late quiesce of devices complete after 1.067 msecs [ 6.335089] Disabling non-boot CPUs ... [ 6.340486] CPU 1 is now offline [ 6.448137] CPU 2 is now offline [ 6.556109] CPU 3 is now offline [ 6.557140] lockdep: fixing up alternatives. [ 27.292051] kvm-clock: cpu 0, msr 0:1f1d19c1, primary cpu clock, res= ume [ 27.292051] PM: Restoring platform NVS memory [ 27.292051] Enabling non-boot CPUs ... qemu: terminating on signal 2 Amit