[ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Linux version 3.7.0-rc3-00017-g1a303f5 (kbuild@bee) (gcc version 4.7.2 (Debian 4.7.2-4) ) #2502 SMP Mon Nov 5 00:09:25 CST 2012 [ 0.000000] Command line: hung_task_panic=1 rcutree.rcu_cpu_stall_timeout=100 branch=konrad-mm/devel/zcache.v3 log_buf_len=8M ignore_loglevel debug sched_debug apic=debug dynamic_printk sysrq_always_enabled panic=10 load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal ip=::::kvm::dhcp nfsroot=10.239.97.14:/nfsroot/wfg,tcp,v3,nocto,actimeo=600,nolock,rsize=524288,wsize=524288 rw link=vmlinuz-2012-11-05-00-09-46-konrad-mm:devel:zcache.v3:1a303f5-1a303f5-x86_64-nfsroot-9-bens BOOT_IMAGE=kernel-tests/kernels/x86_64-nfsroot/1a303f5/vmlinuz-3.7.0-rc3-00017-g1a303f5 [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] Centaur CentaurHauls [ 0.000000] e820: BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x0000000000093bff] usable [ 0.000000] BIOS-e820: [mem 0x0000000000093c00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001fffdfff] usable [ 0.000000] BIOS-e820: [mem 0x000000001fffe000-0x000000001fffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] debug: ignoring loglevel setting. [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] DMI 2.4 present. [ 0.000000] DMI: Bochs Bochs, BIOS Bochs 01/01/2007 [ 0.000000] Hypervisor detected: KVM [ 0.000000] e820: update [mem 0x00000000-0x0000ffff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] e820: last_pfn = 0x1fffe 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 0x70406, new 0x7010600070106 [ 0.000000] Scan for SMP in [mem 0x00000000-0x000003ff] [ 0.000000] Scan for SMP in [mem 0x0009fc00-0x0009ffff] [ 0.000000] Scan for SMP in [mem 0x000f0000-0x000fffff] [ 0.000000] found SMP MP-table at [mem 0x000fdac0-0x000fdacf] mapped at [ffff8800000fdac0] [ 0.000000] mpc: fdad0-fdbec [ 0.000000] initial memory mapped: [mem 0x00000000-0x1fffffff] [ 0.000000] Base memory trampoline at [ffff88000008d000] 8d000 size 24576 [ 0.000000] init_memory_mapping: [mem 0x00000000-0x1fffdfff] [ 0.000000] [mem 0x00000000-0x1fffdfff] page 4k [ 0.000000] kernel direct mapping tables up to 0x1fffdfff @ [mem 0x1fefc000-0x1fffdfff] [ 0.000000] log_buf_len: 8388608 [ 0.000000] early log buf free: 258864(98%) [ 0.000000] ACPI: RSDP 00000000000fd930 00014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 000000001fffe550 00038 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 000000001fffff80 00074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 000000001fffe590 01121 (v01 BXPC BXDSDT 00000001 INTL 20100528) [ 0.000000] ACPI: FACS 000000001fffff40 00040 [ 0.000000] ACPI: SSDT 000000001ffffe40 000FF (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: APIC 000000001ffffd50 00080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 000000001ffffd10 00038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: SSDT 000000001ffff6c0 00644 (v01 BXPC BXSSDTPC 00000001 INTL 20100528) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fb000 ( fee00000) [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000001fffdfff] [ 0.000000] Initmem setup node 0 [mem 0x00000000-0x1fffdfff] [ 0.000000] NODE_DATA [mem 0x1f6f7000-0x1f6fbfff] [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:2283a81, boot clock [ 0.000000] [ffffea0000000000-ffffea00007fffff] PMD -> [ffff88001e600000-ffff88001edfffff] on node 0 [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x00010000-0x00ffffff] [ 0.000000] DMA32 [mem 0x01000000-0xffffffff] [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x00010000-0x00092fff] [ 0.000000] node 0: [mem 0x00100000-0x1fffdfff] [ 0.000000] On node 0 totalpages: 130945 [ 0.000000] DMA zone: 64 pages used for memmap [ 0.000000] DMA zone: 6 pages reserved [ 0.000000] DMA zone: 3901 pages, LIFO batch:0 [ 0.000000] DMA32 zone: 1984 pages used for memmap [ 0.000000] DMA32 zone: 124990 pages, LIFO batch:31 [ 0.000000] ACPI: PM-Timer IO Port: 0xb008 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffffffff5fb000 ( fee00000) [ 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_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] ACPI: IOAPIC (id[0x02] address[0xfec00000] gsi_base[0]) [ 0.000000] IOAPIC[0]: apic_id 2, 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] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 2, APIC INT 02 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 2, APIC INT 05 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 2, APIC INT 09 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 2, APIC INT 0a [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 2, APIC INT 0b [ 0.000000] ACPI: IRQ0 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 2, APIC INT 01 [ 0.000000] ACPI: IRQ2 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 2, APIC INT 03 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 2, APIC INT 04 [ 0.000000] ACPI: IRQ5 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 2, APIC INT 06 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 2, APIC INT 07 [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 2, APIC INT 08 [ 0.000000] ACPI: IRQ9 used by override. [ 0.000000] ACPI: IRQ10 used by override. [ 0.000000] ACPI: IRQ11 used by override. [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 2, APIC INT 0c [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 2, APIC INT 0d [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 2, APIC INT 0e [ 0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 2, APIC INT 0f [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs [ 0.000000] mapped IOAPIC to ffffffffff5fa000 (fec00000) [ 0.000000] nr_irqs_gsi: 40 [ 0.000000] PM: Registered nosave memory: 0000000000093000 - 0000000000094000 [ 0.000000] PM: Registered nosave memory: 0000000000094000 - 00000000000a0000 [ 0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000f0000 [ 0.000000] PM: Registered nosave memory: 00000000000f0000 - 0000000000100000 [ 0.000000] e820: [mem 0x20000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] setup_percpu: NR_CPUS:64 nr_cpumask_bits:64 nr_cpu_ids:2 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 476 pages/cpu @ffff88001f200000 s1919168 r8192 d22336 u2097152 [ 0.000000] pcpu-alloc: s1919168 r8192 d22336 u2097152 alloc=1*2097152 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] kvm-clock: cpu 0, msr 0:1f3d3a81, primary cpu clock [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 1f20d2c0 [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 128891 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: hung_task_panic=1 rcutree.rcu_cpu_stall_timeout=100 branch=konrad-mm/devel/zcache.v3 log_buf_len=8M ignore_loglevel debug sched_debug apic=debug dynamic_printk sysrq_always_enabled panic=10 load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal ip=::::kvm::dhcp nfsroot=10.239.97.14:/nfsroot/wfg,tcp,v3,nocto,actimeo=600,nolock,rsize=524288,wsize=524288 rw link=vmlinuz-2012-11-05-00-09-46-konrad-mm:devel:zcache.v3:1a303f5-1a303f5-x86_64-nfsroot-9-bens BOOT_IMAGE=kernel-tests/kernels/x86_64-nfsroot/1a303f5/vmlinuz-3.7.0-rc3-00017-g1a303f5 [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes) [ 0.000000] __ex_table already sorted, skipping sort [ 0.000000] Memory: 470352k/524280k available (10675k kernel code, 500k absent, 53428k reserved, 6405k data, 2880k init) [ 0.000000] SLUB: Genslabs=15, HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] [ 0.000000] [ 0.000000] [ 0.000000] NR_IRQS:4352 nr_irqs:512 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, Inc., 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] ------------------------ [ 0.000000] | Locking API testsuite: [ 0.000000] ---------------------------------------------------------------------------- [ 0.000000] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] A-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | [ 0.000000] double unlock: ok | ok | ok | ok | ok | ok | [ 0.000000] initialize held: ok | ok | ok | ok | ok | ok | [ 0.000000] bad unlock order: ok | ok | ok | ok | ok | ok | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] recursive read-lock: | ok | | ok | [ 0.000000] recursive read-lock #2: | ok | | ok | [ 0.000000] mixed read-write-lock: | ok | | ok | [ 0.000000] mixed write-read-lock: | ok | | ok | [ 0.000000] -------------------------------------------------------------------------- [ 0.000000] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.000000] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.000000] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.000000] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.000000] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.000000] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.000000] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.000000] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.000000] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.000000] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.000000] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.000000] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/123: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/123: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/132: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/132: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/213: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/213: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/231: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/231: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/312: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/312: ok | ok | ok | [ 0.000000] hard-irq lock-inversion/321: ok | ok | ok | [ 0.000000] soft-irq lock-inversion/321: ok | ok | ok | [ 0.000000] hard-irq read-recursion/123: ok | [ 0.000000] soft-irq read-recursion/123: ok | [ 0.000000] hard-irq read-recursion/132: ok | [ 0.000000] soft-irq read-recursion/132: ok | [ 0.000000] hard-irq read-recursion/213: ok | [ 0.000000] soft-irq read-recursion/213: ok | [ 0.000000] hard-irq read-recursion/231: ok | [ 0.000000] soft-irq read-recursion/231: ok | [ 0.000000] hard-irq read-recursion/312: ok | [ 0.000000] soft-irq read-recursion/312: ok | [ 0.000000] hard-irq read-recursion/321: ok | [ 0.000000] soft-irq read-recursion/321: ok | [ 0.000000] ------------------------------------------------------- [ 0.000000] Good, all 218 testcases passed! | [ 0.000000] --------------------------------- [ 0.000000] ODEBUG: 0 of 0 active objects replaced [ 0.000000] hpet clockevent registered [ 0.000000] tsc: Detected 2666.448 MHz processor [ 0.003000] Calibrating delay loop (skipped) preset value.. 5332.89 BogoMIPS (lpj=2666448) [ 0.004013] pid_max: default: 32768 minimum: 301 [ 0.016120] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.021068] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes) [ 0.022492] Mount-cache hash table entries: 256 [ 0.029153] Initializing cgroup subsys debug [ 0.030019] Initializing cgroup subsys devices [ 0.031030] Initializing cgroup subsys freezer [ 0.032031] Initializing cgroup subsys blkio [ 0.034169] mce: CPU supports 10 MCE banks [ 0.035087] numa_add_cpu cpu 0 node 0: mask now 0 [ 0.036022] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036022] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036022] tlb_flushall_shift: 6 [ 0.037092] debug: unmapping init [mem 0xffffffff82380000-0xffffffff82389fff] [ 0.046803] ACPI: Core revision 20120913 [ 0.103049] ftrace: allocating 40983 entries in 161 pages [ 0.120552] Getting VERSION: 50014 [ 0.121018] Getting VERSION: 50014 [ 0.122016] Getting ID: 0 [ 0.123016] Getting ID: ff000000 [ 0.124017] Getting LVT0: 8700 [ 0.125017] Getting LVT1: 8400 [ 0.126192] enabled ExtINT on CPU#0 [ 0.129440] ENABLING IO-APIC IRQs [ 0.131012] init IO_APIC IRQs [ 0.132012] apic 2 pin 0 not connected [ 0.133056] IOAPIC[0]: Set routing entry (2-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.134050] IOAPIC[0]: Set routing entry (2-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.135049] IOAPIC[0]: Set routing entry (2-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.136057] IOAPIC[0]: Set routing entry (2-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.137117] IOAPIC[0]: Set routing entry (2-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.138047] IOAPIC[0]: Set routing entry (2-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.139038] IOAPIC[0]: Set routing entry (2-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.140044] IOAPIC[0]: Set routing entry (2-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.141041] IOAPIC[0]: Set routing entry (2-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.142041] IOAPIC[0]: Set routing entry (2-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.143037] IOAPIC[0]: Set routing entry (2-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.144041] IOAPIC[0]: Set routing entry (2-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.145037] IOAPIC[0]: Set routing entry (2-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.146145] IOAPIC[0]: Set routing entry (2-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.147039] IOAPIC[0]: Set routing entry (2-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.148056] apic 2 pin 16 not connected [ 0.149006] apic 2 pin 17 not connected [ 0.150006] apic 2 pin 18 not connected [ 0.151012] apic 2 pin 19 not connected [ 0.152005] apic 2 pin 20 not connected [ 0.153006] apic 2 pin 21 not connected [ 0.154005] apic 2 pin 22 not connected [ 0.155010] apic 2 pin 23 not connected [ 0.156176] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.157005] smpboot: CPU0: Intel Common KVM processor (fam: 0f, model: 06, stepping: 01) [ 0.161006] Using local APIC timer interrupts. [ 0.161006] calibrating APIC timer ... [ 0.163000] ... lapic delta = 9599809 [ 0.163000] ... PM-Timer delta = 549798 [ 0.163000] APIC calibration not consistent with PM-Timer: 153ms instead of 100ms [ 0.163000] APIC delta adjusted to PM-Timer: 6250095 (9599809) [ 0.163000] TSC delta adjusted to PM-Timer: 266666027 (409584608) [ 0.163000] ..... delta 6250095 [ 0.163000] ..... mult: 268439536 [ 0.163000] ..... calibration result: 1000015 [ 0.163000] ..... CPU clock speed is 2666.0660 MHz. [ 0.163000] ..... host bus clock speed is 1000.0015 MHz. [ 0.163096] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only. [ 0.173575] SMP alternatives: lockdep: fixing up alternatives [ 0.175053] smpboot: Booting Node 0, Processors #1 OK [ 0.003000] kvm-clock: cpu 1, msr 0:1f5d3a81, secondary cpu clock [ 0.003000] masked ExtINT on CPU#1 [ 0.003000] numa_add_cpu cpu 1 node 0: mask now 0-1 [ 0.202185] Brought up 2 CPUs [ 0.203013] ---------------- [ 0.204004] | NMI testsuite: [ 0.202070] KVM setup async PF for cpu 1 [ 0.202070] kvm-stealtime: cpu 1, msr 1f40d2c0 [ 0.205004] -------------------- [ 0.206005] remote IPI: ok | [ 0.216005] local IPI: ok | [ 0.227014] -------------------- [ 0.228005] Good, all 2 testcases passed! | [ 0.229004] --------------------------------- [ 0.230006] smpboot: Total of 2 processors activated (10665.79 BogoMIPS) [ 0.236143] CPU0 attaching sched-domain: [ 0.237020] domain 0: span 0-1 level CPU [ 0.239006] groups: 0 1 [ 0.241933] CPU1 attaching sched-domain: [ 0.243008] domain 0: span 0-1 level CPU [ 0.244006] groups: 1 0 [ 0.253529] devtmpfs: initialized [ 0.268530] xor: automatically using best checksumming function: [ 0.279008] generic_sse: 7760.000 MB/sec [ 0.280043] atomic64 test passed for x86-64 platform with CX8 and with SSE [ 0.284455] kworker/u:0 (19) used greatest stack depth: 5360 bytes left [ 0.285300] RTC time: 16:14:12, date: 11/04/12 [ 0.288324] NET: Registered protocol family 16 [ 0.295325] kworker/u:0 (24) used greatest stack depth: 4960 bytes left [ 0.310196] ACPI: bus type pci registered [ 0.317323] dca service started, version 1.12.1 [ 0.320150] PCI: Using configuration type 1 for base access [ 0.720731] bio: create slab at 0 [ 0.748024] raid6: sse2x1 2269 MB/s [ 0.766012] raid6: sse2x2 5460 MB/s [ 0.784011] raid6: sse2x4 6179 MB/s [ 0.785004] raid6: using algorithm sse2x4 (6179 MB/s) [ 0.786004] raid6: using intx1 recovery algorithm [ 0.791447] ACPI: Added _OSI(Module Device) [ 0.792020] ACPI: Added _OSI(Processor Device) [ 0.794101] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.795017] ACPI: Added _OSI(Processor Aggregator Device) [ 0.802166] ACPI: EC: Look up EC in DSDT [ 0.893555] ACPI: Interpreter enabled [ 0.894007] ACPI: (supports S0 S3 S4 S5) [ 0.897749] ACPI: Using IOAPIC for interrupt routing [ 1.082351] ACPI: No dock devices found. [ 1.083015] PCI: Ignoring host bridge windows from ACPI; if necessary, use "pci=use_crs" and report a bug [ 1.085250] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 1.088141] pci_root PNP0A03:00: host bridge window [io 0x0000-0x0cf7] (ignored) [ 1.089013] pci_root PNP0A03:00: host bridge window [io 0x0d00-0xffff] (ignored) [ 1.090008] pci_root PNP0A03:00: host bridge window [mem 0x000a0000-0x000bffff] (ignored) [ 1.091014] pci_root PNP0A03:00: host bridge window [mem 0xe0000000-0xfebfffff] (ignored) [ 1.093024] PCI: root bus 00: using default resources [ 1.094069] pci_root PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 1.101126] PCI host bridge to bus 0000:00 [ 1.102031] pci_bus 0000:00: root bus resource [bus 00-ff] [ 1.103014] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 1.104013] pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffffff] [ 1.105193] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 1.108182] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 1.109910] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 1.112585] pci 0000:00:01.1: reg 20: [io 0xc1c0-0xc1cf] [ 1.115563] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 1.118512] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI [ 1.119024] pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB [ 1.121124] pci 0000:00:02.0: [1013:00b8] type 00 class 0x030000 [ 1.125612] pci 0000:00:02.0: reg 10: [mem 0xfc000000-0xfdffffff pref] [ 1.128532] pci 0000:00:02.0: reg 14: [mem 0xfebf0000-0xfebf0fff] [ 1.137483] pci 0000:00:02.0: reg 30: [mem 0xfebe0000-0xfebeffff pref] [ 1.140300] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 1.141807] pci 0000:00:03.0: reg 10: [mem 0xfeba0000-0xfebbffff] [ 1.144011] pci 0000:00:03.0: reg 14: [io 0xc000-0xc03f] [ 1.146000] pci 0000:00:03.0: reg 30: [mem 0xfebc0000-0xfebdffff pref] [ 1.146283] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 [ 1.147862] pci 0000:00:04.0: reg 10: [io 0xc040-0xc07f] [ 1.149000] pci 0000:00:04.0: reg 14: [mem 0xfebf1000-0xfebf1fff] [ 1.151298] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 1.154007] pci 0000:00:05.0: reg 10: [io 0xc080-0xc0bf] [ 1.155771] pci 0000:00:05.0: reg 14: [mem 0xfebf2000-0xfebf2fff] [ 1.157000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 1.157820] pci 0000:00:06.0: reg 10: [io 0xc0c0-0xc0ff] [ 1.158801] pci 0000:00:06.0: reg 14: [mem 0xfebf3000-0xfebf3fff] [ 1.160000] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 [ 1.160828] pci 0000:00:07.0: reg 10: [io 0xc100-0xc13f] [ 1.161787] pci 0000:00:07.0: reg 14: [mem 0xfebf4000-0xfebf4fff] [ 1.167269] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 [ 1.168882] pci 0000:00:08.0: reg 10: [io 0xc140-0xc17f] [ 1.169732] pci 0000:00:08.0: reg 14: [mem 0xfebf5000-0xfebf5fff] [ 1.175386] pci 0000:00:09.0: [1af4:1001] type 00 class 0x010000 [ 1.176838] pci 0000:00:09.0: reg 10: [io 0xc180-0xc1bf] [ 1.177735] pci 0000:00:09.0: reg 14: [mem 0xfebf6000-0xfebf6fff] [ 1.180057] pci 0000:00:0a.0: [8086:25ab] type 00 class 0x088000 [ 1.181507] pci 0000:00:0a.0: reg 10: [mem 0xfebf7000-0xfebf700f] [ 1.183153] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT] [ 1.221391] pci0000:00: ACPI _OSC support notification failed, disabling PCIe ASPM [ 1.222013] pci0000:00: Unable to request _OSC control (_OSC support mask: 0x08) [ 1.620126] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 1.628171] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 1.634942] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 1.639517] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 1.644842] ACPI: PCI Interrupt Link [LNKS] (IRQs 9) *0 [ 1.652375] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none [ 1.654022] vgaarb: loaded [ 1.655009] vgaarb: bridge control possible 0000:00:02.0 [ 1.663366] SCSI subsystem initialized [ 1.665113] ACPI: bus type scsi registered [ 1.671263] libata version 3.00 loaded. [ 1.676134] ACPI: bus type usb registered [ 1.682085] usbcore: registered new interface driver usbfs [ 1.687083] usbcore: registered new interface driver hub [ 1.692106] usbcore: registered new device driver usb [ 1.703327] pps_core: LinuxPPS API ver. 1 registered [ 1.704007] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 1.707421] PTP clock support registered [ 1.734184] wmi: Mapper loaded [ 1.738314] Advanced Linux Sound Architecture Driver Initialized. [ 1.740004] PCI: Using ACPI for IRQ routing [ 1.741021] PCI: pci_cache_line_size set to 64 bytes [ 1.744232] e820: reserve RAM buffer [mem 0x00093c00-0x0009ffff] [ 1.746035] e820: reserve RAM buffer [mem 0x1fffe000-0x1fffffff] [ 1.755827] HPET: 3 timers in total, 0 timers will be used for per-cpu timer [ 1.757035] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 1.760005] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 1.765029] Switching to clocksource kvm-clock [ 2.385882] pnp: PnP ACPI init [ 2.387578] ACPI: bus type pnp registered [ 2.389383] pnp 00:00: [bus 00-ff] [ 2.397969] pnp 00:00: [io 0x0cf8-0x0cff] [ 2.399687] pnp 00:00: [io 0x0000-0x0cf7 window] [ 2.401438] pnp 00:00: [io 0x0d00-0xffff window] [ 2.403202] pnp 00:00: [mem 0x000a0000-0x000bffff window] [ 2.405054] pnp 00:00: [mem 0xe0000000-0xfebfffff window] [ 2.429544] pnp 00:00: Plug and Play ACPI device, IDs PNP0a03 (active) [ 2.431943] pnp 00:01: [io 0x0070-0x0071] [ 2.433685] IOAPIC[0]: Set routing entry (2-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:3) [ 2.436791] pnp 00:01: [irq 8] [ 2.438216] pnp 00:01: [io 0x0072-0x0077] [ 2.461469] pnp 00:01: Plug and Play ACPI device, IDs PNP0b00 (active) [ 2.464398] pnp 00:02: [io 0x0060] [ 2.466116] pnp 00:02: [io 0x0064] [ 2.467609] IOAPIC[0]: Set routing entry (2-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:3) [ 2.473605] pnp 00:02: [irq 1] [ 2.493604] pnp 00:02: Plug and Play ACPI device, IDs PNP0303 (active) [ 2.496808] IOAPIC[0]: Set routing entry (2-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:3) [ 2.517155] pnp 00:03: [irq 12] [ 2.540413] pnp 00:03: Plug and Play ACPI device, IDs PNP0f13 (active) [ 2.543373] pnp 00:04: [io 0x03f2-0x03f5] [ 2.544922] pnp 00:04: [io 0x03f7] [ 2.552564] IOAPIC[0]: Set routing entry (2-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:3) [ 2.555639] pnp 00:04: [irq 6] [ 2.557039] pnp 00:04: [dma 2] [ 2.578357] pnp 00:04: Plug and Play ACPI device, IDs PNP0700 (active) [ 2.581876] pnp 00:05: [io 0x0378-0x037f] [ 2.588196] IOAPIC[0]: Set routing entry (2-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:3) [ 2.591411] pnp 00:05: [irq 7] [ 2.610414] pnp 00:05: Plug and Play ACPI device, IDs PNP0400 (active) [ 2.619405] pnp 00:06: [io 0x03f8-0x03ff] [ 2.621042] IOAPIC[0]: Set routing entry (2-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:3) [ 2.631307] pnp 00:06: [irq 4] [ 2.654079] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 2.669554] pnp 00:07: [mem 0xfed00000-0xfed003ff] [ 2.689221] pnp 00:07: Plug and Play ACPI device, IDs PNP0103 (active) [ 2.701092] pnp: PnP ACPI: found 8 devices [ 2.707813] ACPI: ACPI bus type pnp unregistered [ 2.970671] pci_bus 0000:00: resource 4 [io 0x0000-0xffff] [ 2.972577] pci_bus 0000:00: resource 5 [mem 0x00000000-0xffffffffff] [ 2.975075] NET: Registered protocol family 2 [ 2.981846] TCP established hash table entries: 16384 (order: 6, 262144 bytes) [ 2.998631] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 3.003930] TCP: Hash tables configured (established 16384 bind 16384) [ 3.006473] TCP: reno registered [ 3.008183] UDP hash table entries: 256 (order: 3, 49152 bytes) [ 3.010472] UDP-Lite hash table entries: 256 (order: 3, 49152 bytes) [ 3.020762] NET: Registered protocol family 1 [ 3.055416] RPC: Registered named UNIX socket transport module. [ 3.057421] RPC: Registered udp transport module. [ 3.063439] RPC: Registered tcp transport module. [ 3.069247] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 3.071374] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 3.073479] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 3.075535] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 3.077672] pci 0000:00:02.0: Boot video device [ 3.079580] PCI: CLS 0 bytes, default 64 [ 3.612979] DMA-API: preallocated 32768 debug entries [ 3.614928] DMA-API: debugging enabled by kernel config [ 3.636131] kvm: no hardware support [ 3.658773] Machine check injector initialized [ 3.723937] microcode: CPU0 sig=0xf61, pf=0x1, revision=0x1 [ 3.731090] microcode: CPU1 sig=0xf61, pf=0x1, revision=0x1 [ 3.734402] microcode: Microcode Update Driver: v2.00 , Peter Oruba [ 3.804563] Initializing RT-Tester: OK [ 3.806323] audit: initializing netlink socket (disabled) [ 3.808281] type=2000 audit(1352045654.804:1): initialized [ 3.871845] Kprobe smoke test started [ 4.107244] Kprobe smoke test passed successfully [ 4.109420] rcu-torture:--- Start of test: nreaders=4 nfakewriters=4 stat_interval=60 verbose=0 test_no_idle_hz=1 shuffle_interval=3 stutter=5 irqreader=1 fqs_duration=0 fqs_holdoff=0 fqs_stutter=3 test_boost=1/0 test_boost_interval=7 test_boost_duration=4 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0 [ 4.188848] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 4.649138] tsc: Refined TSC clocksource calibration: 2666.662 MHz [ 4.846309] NFS: Registering the id_resolver key type [ 4.849428] Key type id_resolver registered [ 4.851014] Key type id_legacy registered [ 4.854075] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 4.931728] fuse init (API version 7.20) [ 5.004955] JFS: nTxBlock = 3674, nTxLock = 29397 [ 5.073440] SGI XFS with ACLs, security attributes, large block/inode numbers, no debug enabled [ 5.186620] NILFS version 2 loaded [ 5.223961] Btrfs loaded [ 5.225344] msgmni has been set to 918 [ 5.291815] async_tx: api initialized (async) [ 5.299956] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) [ 5.322384] io scheduler noop registered [ 5.323957] io scheduler deadline registered [ 5.343585] io scheduler cfq registered (default) [ 5.347262] list_sort_test: start testing list_sort() [ 5.394177] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 5.407907] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 5.416110] ACPI: Power Button [PWRF] [ 5.719121] ioatdma: Intel(R) QuickData Technology Driver 4.00 [ 5.744721] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11 [ 5.761130] IOAPIC[0]: Set routing entry (2-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:3) [ 5.764422] virtio-pci 0000:00:04.0: setting latency timer to 64 [ 5.795117] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10 [ 5.802378] IOAPIC[0]: Set routing entry (2-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:3) [ 5.805544] virtio-pci 0000:00:05.0: setting latency timer to 64 [ 5.842979] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 10 [ 5.845387] virtio-pci 0000:00:06.0: setting latency timer to 64 [ 5.876983] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11 [ 5.884312] virtio-pci 0000:00:07.0: setting latency timer to 64 [ 5.887755] virtio-pci 0000:00:08.0: setting latency timer to 64 [ 5.924123] virtio-pci 0000:00:09.0: setting latency timer to 64 [ 7.245231] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 7.298453] 00:06: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 7.329688] Initializing Nozomi driver 2.1d [ 7.354090] Non-volatile memory driver v1.3 [ 7.363057] Linux agpgart interface v0.103 [ 7.370038] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds). [ 7.373149] Hangcheck: Using getrawmonotonic(). [ 7.395307] [drm] Initialized drm 1.1.0 20060810 [ 7.414931] Floppy drive(s): fd0 is 1.44M [ 7.427944] FDC 0 is a S82078B [ 7.544445] brd: module loaded [ 7.590698] loop: module loaded [ 7.600550] virtio-pci 0000:00:04.0: irq 40 for MSI/MSI-X [ 7.602456] virtio-pci 0000:00:04.0: irq 41 for MSI/MSI-X [ 7.618858] vda: unknown partition table [ 7.622830] virtio-pci 0000:00:05.0: irq 42 for MSI/MSI-X [ 7.624722] virtio-pci 0000:00:05.0: irq 43 for MSI/MSI-X [ 7.641112] vdb: unknown partition table [ 7.648366] virtio-pci 0000:00:06.0: irq 44 for MSI/MSI-X [ 7.650269] virtio-pci 0000:00:06.0: irq 45 for MSI/MSI-X [ 7.665971] vdc: unknown partition table [ 7.669699] virtio-pci 0000:00:07.0: irq 46 for MSI/MSI-X [ 7.671679] virtio-pci 0000:00:07.0: irq 47 for MSI/MSI-X [ 7.692221] vdd: unknown partition table [ 7.695970] virtio-pci 0000:00:08.0: irq 48 for MSI/MSI-X [ 7.697871] virtio-pci 0000:00:08.0: irq 49 for MSI/MSI-X [ 7.714461] vde: unknown partition table [ 7.720205] virtio-pci 0000:00:09.0: irq 50 for MSI/MSI-X [ 7.722195] virtio-pci 0000:00:09.0: irq 51 for MSI/MSI-X [ 7.737486] vdf: unknown partition table [ 7.747101] lkdtm: No crash points registered, enable through debugfs [ 7.754602] Uniform Multi-Platform E-IDE driver [ 7.757518] piix 0000:00:01.1: IDE controller (0x8086:0x7010 rev 0x00) [ 7.759878] piix 0000:00:01.1: not 100% native mode: will probe irqs later [ 7.762096] pci 0000:00:01.1: setting latency timer to 64 [ 7.763956] ide0: BM-DMA at 0xc1c0-0xc1c7 [ 7.765703] ide1: BM-DMA at 0xc1c8-0xc1cf [ 7.776257] Probing IDE interface ide0... [ 8.306431] Probing IDE interface ide1... [ 8.980325] hdc: QEMU DVD-ROM, ATAPI CD/DVD-ROM drive [ 9.595587] hdc: host max PIO4 wanted PIO255(auto-tune) selected PIO0 [ 9.597844] hdc: MWDMA2 mode selected [ 9.599734] ide0 at 0x1f0-0x1f7,0x3f6 on irq 14 [ 9.612292] ide1 at 0x170-0x177,0x376 on irq 15 [ 9.632089] ide_generic: please use "probe_mask=0x3f" module parameter for probing all legacy ISA IDE ports [ 9.635392] ide-gd driver 1.18 [ 9.651569] Loading iSCSI transport class v2.0-870. [ 9.673611] Loading Adaptec I2O RAID: Version 2.4 Build 5go [ 9.675540] Detecting Adaptec I2O RAID controllers... [ 9.686640] Adaptec aacraid driver 1.2-0[29800]-ms [ 9.691059] aic94xx: Adaptec aic94xx SAS/SATA driver version 1.0.3 loaded [ 9.703472] isci: Intel(R) C600 SAS Controller Driver - version 1.1.0 [ 9.712810] qla2xxx [0000:00:00.0]-0005: : QLogic Fibre Channel HBA Driver: 8.04.00.07-k. [ 9.723719] iscsi: registered transport (qla4xxx) [ 9.726480] QLogic iSCSI HBA Driver [ 9.762561] megaraid cmm: 2.20.2.7 (Release Date: Sun Jul 16 00:01:03 EST 2006) [ 9.766651] megaraid: 2.20.5.1 (Release Date: Thu Nov 16 15:32:35 EST 2006) [ 9.810641] megasas: 06.504.01.00-rc1 Mon. Oct. 1 17:00:00 PDT 2012 [ 9.813671] mpt2sas version 14.100.00.00 loaded [ 9.845156] GDT-HA: Storage RAID Controller Driver. Version: 3.05 [ 9.942063] tun: Universal TUN/TAP device driver, 1.6 [ 9.943935] tun: (C) 1999-2004 Max Krasnyansky [ 9.952197] Atheros(R) L2 Ethernet Driver - version 2.2.3 [ 9.954081] Copyright (c) 2007 Atheros Corporation. [ 9.980907] cnic: Broadcom NetXtreme II CNIC Driver cnic v2.5.14 (Sep 30, 2012) [ 9.998285] e100: Intel(R) PRO/100 Network Driver, 3.5.24-k2-NAPI [ 10.000320] e100: Copyright(c) 1999-2006 Intel Corporation [ 10.011092] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI [ 10.013322] e1000: Copyright (c) 1999-2006 Intel Corporation. [ 10.032589] e1000 0000:00:03.0: setting latency timer to 64 [ 10.859857] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56 [ 10.862149] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection [ 10.890404] e1000e: Intel(R) PRO/1000 Network Driver - 2.1.4-k [ 10.892389] e1000e: Copyright(c) 1999 - 2012 Intel Corporation. [ 10.898118] igb: Intel(R) Gigabit Ethernet Network Driver - version 4.0.1-k [ 10.918704] igb: Copyright (c) 2007-2012 Intel Corporation. [ 10.946457] igbvf: Intel(R) Gigabit Virtual Function Network Driver - version 2.0.1-k [ 10.949399] igbvf: Copyright (c) 2009 - 2012 Intel Corporation. [ 10.970343] ixgbe: Intel(R) 10 Gigabit PCI Express Network Driver - version 3.9.15-k [ 10.973254] ixgbe: Copyright (c) 1999-2012 Intel Corporation. [ 10.998559] ixgb: Intel(R) PRO/10GbE Network Driver - version 1.0.135-k2-NAPI [ 11.000794] ixgb: Copyright (c) 1999-2008 Intel Corporation. [ 11.009532] jme: JMicron JMC2XX ethernet driver version 1.0.8 [ 11.012886] sky2: driver version 1.30 [ 11.022766] pch_gbe: EG20T PCH Gigabit Ethernet Driver - version 1.01 [ 11.051049] usbcore: registered new interface driver catc [ 11.058877] usbcore: registered new interface driver kaweth [ 11.060823] pegasus: v0.6.14 (2006/09/27), Pegasus/Pegasus II USB Ethernet driver [ 11.099868] usbcore: registered new interface driver pegasus [ 11.102438] usbcore: registered new interface driver rtl8150 [ 11.134458] usbcore: registered new interface driver asix [ 11.136837] usbcore: registered new interface driver cdc_ether [ 11.149510] usbcore: registered new interface driver cdc_eem [ 11.154372] usbcore: registered new interface driver dm9601 [ 11.162204] usbcore: registered new interface driver smsc75xx [ 11.170146] usbcore: registered new interface driver smsc95xx [ 11.172716] usbcore: registered new interface driver gl620a [ 11.183181] usbcore: registered new interface driver net1080 [ 11.185729] usbcore: registered new interface driver plusb [ 11.188395] usbcore: registered new interface driver rndis_host [ 11.190879] usbcore: registered new interface driver cdc_subset [ 11.233560] usbcore: registered new interface driver zaurus [ 11.235936] usbcore: registered new interface driver MOSCHIP usb-ethernet driver [ 11.253833] usbcore: registered new interface driver int51x1 [ 11.280422] usbcore: registered new interface driver cdc_ncm [ 11.282463] Fusion MPT base driver 3.04.20 [ 11.288248] Copyright (c) 1999-2008 LSI Corporation [ 11.290130] Fusion MPT SPI Host driver 3.04.20 [ 11.323371] Fusion MPT FC Host driver 3.04.20 [ 11.326066] Fusion MPT SAS Host driver 3.04.20 [ 11.328294] Fusion MPT misc device (ioctl) driver 3.04.20 [ 11.363076] mptctl: Registered with Fusion MPT base driver [ 11.364961] mptctl: /dev/mptctl @ (major,minor=10,220) [ 11.375411] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 11.377504] ehci_hcd: block sizes: qh 104 qtd 96 itd 192 sitd 96 [ 11.398213] uhci_hcd: USB Universal Host Controller Interface driver [ 11.410073] Initializing USB Mass Storage driver... [ 11.415075] usbcore: registered new interface driver usb-storage [ 11.417087] USB Mass Storage support registered. [ 11.426077] usbcore: registered new interface driver ums-alauda [ 11.431058] usbcore: registered new interface driver ums-cypress [ 11.438066] usbcore: registered new interface driver ums-datafab [ 11.443040] usbcore: registered new interface driver ums-freecom [ 11.450043] usbcore: registered new interface driver ums-isd200 [ 11.455058] usbcore: registered new interface driver ums-jumpshot [ 11.462047] usbcore: registered new interface driver ums-karma [ 11.467061] usbcore: registered new interface driver ums-onetouch [ 11.475050] usbcore: registered new interface driver ums-sddr09 [ 11.480046] usbcore: registered new interface driver ums-sddr55 [ 11.482393] usbcore: registered new interface driver ums-usbat [ 11.491055] usbcore: registered new interface driver usbserial [ 11.493734] usbcore: registered new interface driver usbserial_generic [ 11.500056] usbserial: USB Serial support registered for generic [ 11.507003] usbcore: registered new interface driver belkin_sa [ 11.512042] usbserial: USB Serial support registered for Belkin / Peracom / GoHubs USB Serial Adapter [ 11.525047] usbcore: registered new interface driver mct_u232 [ 11.532036] usbserial: USB Serial support registered for MCT U232 [ 11.538057] usbcore: registered new interface driver usbtest [ 11.550036] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 11.556499] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 11.558640] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 11.601365] mousedev: PS/2 mouse device common for all mice [ 11.653876] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 11.698987] rtc_cmos 00:01: RTC can wake from S4 [ 11.717548] rtc_cmos 00:01: rtc core: registered rtc_cmos as rtc0 [ 11.719942] rtc0: alarms up to one day, 114 bytes nvram, hpet irqs [ 11.738937] rtc-test rtc-test.0: rtc core: registered test as rtc1 [ 11.743749] rtc-test rtc-test.1: rtc core: registered test as rtc2 [ 11.771744] i6300esb: Intel 6300ESB WatchDog Timer Driver v0.05 [ 11.775667] i6300esb: initialized (0xffffc90000596000). heartbeat=30 sec (nowayout=0) [ 11.834715] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.10 [ 11.838202] iTCO_vendor_support: vendor-support=0 [ 11.840146] watchdog: Software Watchdog: cannot register miscdev on minor=130 (err=-16). [ 11.845990] watchdog: Software Watchdog: a legacy watchdog module is probably present. [ 11.859504] softdog: Software Watchdog Timer: 0.08 initialized. soft_noboot=0 soft_margin=60 sec soft_panic=0 (nowayout=0) [ 11.875670] md: linear personality registered for level -1 [ 11.881027] md: raid0 personality registered for level 0 [ 11.886730] md: raid1 personality registered for level 1 [ 11.891815] md: raid10 personality registered for level 10 [ 11.896880] md: raid6 personality registered for level 6 [ 11.904813] md: raid5 personality registered for level 5 [ 11.906676] md: raid4 personality registered for level 4 [ 11.910978] md: multipath personality registered for level -4 [ 11.912868] md: faulty personality registered for level -5 [ 11.938957] device-mapper: uevent: version 1.0.3 [ 11.947380] device-mapper: ioctl: 4.23.0-ioctl (2012-07-25) initialised: dm-devel@redhat.com [ 11.992321] device-mapper: multipath: version 1.5.0 loaded [ 11.994282] device-mapper: multipath round-robin: version 1.0.0 loaded [ 12.007598] device-mapper: multipath queue-length: version 0.1.0 loaded [ 12.009677] device-mapper: multipath service-time: version 0.2.0 loaded [ 12.038202] device-mapper: dm-log-userspace: version 1.1.0 loaded [ 12.040252] cpuidle: using governor ladder [ 12.041811] cpuidle: using governor menu [ 12.057342] usbcore: registered new interface driver usbhid [ 12.059223] usbhid: USB HID core driver [ 12.071503] dell_wmi: No known WMI GUID found [ 12.073155] acer_wmi: Acer Laptop ACPI-WMI Extras [ 12.074891] acer_wmi: No or unsupported WMI interface, unable to load [ 12.097505] oprofile: using NMI interrupt. [ 12.100468] netem: version 1.3 [ 12.101840] Netfilter messages via NETLINK v0.30. [ 12.103698] nf_conntrack version 0.5.0 (3674 buckets, 14696 max) [ 12.121452] ctnetlink v0.93: registering with nfnetlink. [ 12.123637] NF_TPROXY: Transparent proxy support initialized, version 4.1.0 [ 12.126494] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 [ 12.153987] NF_TPROXY: Copyright (c) 2006-2007 BalaBit IT Ltd. [ 12.173773] xt_time: kernel timezone is -0000 [ 12.175892] ip_tables: (C) 2000-2006 Netfilter Core Team [ 12.184552] ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully [ 12.186867] arp_tables: (C) 2002 David S. Miller [ 12.192821] TCP: bic registered [ 12.194259] TCP: cubic registered [ 12.199668] TCP: westwood registered [ 12.201197] TCP: highspeed registered [ 12.204499] TCP: hybla registered [ 12.205928] TCP: htcp registered [ 12.211512] TCP: vegas registered [ 12.212991] TCP: veno registered [ 12.218275] TCP: scalable registered [ 12.219746] TCP: lp registered [ 12.221168] TCP: yeah registered [ 12.222834] TCP: illinois registered [ 12.224364] Initializing XFRM netlink socket [ 12.283588] NET: Registered protocol family 10 [ 12.307438] sit: IPv6 over IPv4 tunneling driver [ 12.312415] NET: Registered protocol family 17 [ 12.314186] NET: Registered protocol family 15 [ 12.344966] Bridge firewalling registered [ 12.346588] Ebtables v2.0 registered [ 12.360493] Key type dns_resolver registered [ 12.384806] [ 12.384806] printing PIC contents [ 12.387123] ... PIC IMR: ffff [ 12.388100] ... PIC IRR: 1153 [ 12.400976] ... PIC ISR: 0000 [ 12.402402] ... PIC ELCR: 0c00 [ 12.403803] printing local APIC contents on CPU#0/0: [ 12.404795] ... APIC ID: 00000000 (0) [ 12.404795] ... APIC VERSION: 00050014 [ 12.404795] ... APIC TASKPRI: 00000000 (00) [ 12.404795] ... APIC PROCPRI: 00000000 [ 12.404795] ... APIC LDR: 01000000 [ 12.404795] ... APIC DFR: ffffffff [ 12.404795] ... APIC SPIV: 000001ff [ 12.404795] ... APIC ISR field: [ 12.404795] 0000000000000000000000000000000000000000000000000000000000000000 [ 12.404795] ... APIC TMR field: [ 12.404795] 0000000000000000000000000000000000000000000000000000000000000000 [ 12.404795] ... APIC IRR field: [ 12.404795] 0000000000000000000000000000000000000000000000000000000000008000 [ 12.404795] ... APIC ESR: 00000000 [ 12.404795] ... APIC ICR: 000008fd [ 12.404795] ... APIC ICR2: 02000000 [ 12.404795] ... APIC LVTT: 000000ef [ 12.404795] ... APIC LVTPC: 00010000 [ 12.404795] ... APIC LVT0: 00010700 [ 12.404795] ... APIC LVT1: 00000400 [ 12.404795] ... APIC LVTERR: 000000fe [ 12.404795] ... APIC TMICT: 0002ccf0 [ 12.404795] ... APIC TMCCT: 00000000 [ 12.404795] ... APIC TDCR: 00000003 [ 12.404795] [ 12.616021] number of MP IRQ sources: 15. [ 12.617559] number of IO-APIC #2 registers: 24. [ 12.619209] testing the IO APIC....................... [ 12.620997] IO APIC #2...... [ 12.636424] .... register #00: 00000000 [ 12.637935] ....... : physical APIC id: 00 [ 12.639549] ....... : Delivery Type: 0 [ 12.655135] ....... : LTS : 0 [ 12.656671] .... register #01: 00170011 [ 12.658172] ....... : max redirection entries: 17 [ 12.659906] ....... : PRQ implemented: 0 [ 12.661492] ....... : IO APIC version: 11 [ 12.676161] .... register #02: 00000000 [ 12.677663] ....... : arbitration: 00 [ 12.679226] .... IRQ redirection table: [ 12.680722] NR Dst Mask Trig IRR Pol Stat Dmod Deli Vect: [ 12.682588] 00 00 1 0 0 0 0 0 0 00 [ 12.696581] 01 03 0 0 0 0 0 1 1 31 [ 12.698539] 02 03 0 0 0 0 0 1 1 30 [ 12.700509] 03 03 0 0 0 0 0 1 1 33 [ 12.713539] 04 03 1 0 0 0 0 1 1 34 [ 12.715523] 05 03 1 1 0 0 0 1 1 35 [ 12.717675] 06 03 0 0 0 0 0 1 1 36 [ 12.733142] 07 03 0 0 0 0 0 1 1 37 [ 12.735165] 08 03 0 0 0 0 0 1 1 38 [ 12.737210] 09 03 0 1 0 0 0 1 1 39 [ 12.751660] 0a 03 1 1 0 0 0 1 1 3A [ 12.753764] 0b 03 1 1 0 0 0 1 1 3B [ 12.770362] 0c 03 0 0 0 0 0 1 1 3C [ 12.772366] 0d 03 0 0 0 0 0 1 1 3D [ 12.779144] 0e 03 0 0 0 0 0 1 1 3E [ 12.788143] 0f 03 0 0 0 0 0 1 1 3F [ 12.790141] 10 00 1 0 0 0 0 0 0 00 [ 12.792263] 11 00 1 0 0 0 0 0 0 00 [ 12.806376] 12 00 1 0 0 0 0 0 0 00 [ 12.808390] 13 00 1 0 0 0 0 0 0 00 [ 12.810462] 14 00 1 0 0 0 0 0 0 00 [ 12.812581] 15 00 1 0 0 0 0 0 0 00 [ 12.827782] 16 00 1 0 0 0 0 0 0 00 [ 12.829880] 17 00 1 0 0 0 0 0 0 00 [ 12.831894] IRQ to pin mappings: [ 12.838105] IRQ0 -> 0:2 [ 12.850333] IRQ1 -> 0:1 [ 12.851908] IRQ3 -> 0:3 [ 12.853561] IRQ4 -> 0:4 [ 12.855185] IRQ5 -> 0:5 [ 12.861112] IRQ6 -> 0:6 [ 12.862667] IRQ7 -> 0:7 [ 12.877380] IRQ8 -> 0:8 [ 12.878931] IRQ9 -> 0:9 [ 12.880592] IRQ10 -> 0:10 [ 12.882212] IRQ11 -> 0:11 [ 12.883876] IRQ12 -> 0:12 [ 12.885500] IRQ13 -> 0:13 [ 12.902349] IRQ14 -> 0:14 [ 12.903938] IRQ15 -> 0:15 [ 12.905638] .................................... done. [ 12.949001] PM: Hibernation image not present or could not be loaded. [ 12.951335] registered taskstats version 1 [ 12.986537] Magic number: 4:196:236 [ 12.988292] tty ttyv6: hash matches [ 12.990770] console [netcon0] enabled [ 12.992268] netconsole: network logging started [ 12.998200] rtc_cmos 00:01: setting system clock to 2012-11-04 16:14:27 UTC (1352045667) [ 13.041173] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready [ 15.024634] e1000: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX [ 15.028325] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 15.038141] Sending DHCP requests ., OK [ 15.058061] IP-Config: Got DHCP answer from 10.0.2.2, my address is 10.0.2.15 [ 15.061974] IP-Config: Complete: [ 15.063389] device=eth0, addr=10.0.2.15, mask=255.255.255.0, gw=10.0.2.2 [ 15.073236] host=kvm, domain=, nis-domain=(none) [ 15.075129] bootserver=10.0.2.2, rootserver=10.239.97.14, rootpath= [ 15.076628] nameserver0=10.0.2.3[ 15.113191] ALSA device list: [ 15.114586] No soundcards found. [ 15.116958] md: Waiting for all devices to be available before autodetect [ 15.129328] md: If you don't use raid, use raid=noautodetect [ 15.159105] md: Autodetecting RAID arrays. [ 15.160711] md: Scanned 0 and added 0 devices. [ 15.183848] md: autorun ... [ 15.185193] md: ... autorun DONE. [ 18.388505] VFS: Mounted root (nfs filesystem) on device 0:14. [ 18.392295] devtmpfs: mounted [ 18.396402] debug: unmapping init [mem 0xffffffff820b0000-0xffffffff8237ffff] [ 19.164786] modprobe (1938) used greatest stack depth: 3440 bytes left [ 26.118021] S02mountkernfs. (1959) used greatest stack depth: 3024 bytes left [ 27.793245] udevd[2022]: starting version 175 [ 63.870637] S20pidentd (2355) used greatest stack depth: 2896 bytes left [ 64.168173] rcu-torture: rtc: ffffffff82cc1c20 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 4 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 [ 64.168173] rcu-torture: Reader Pipe: 8 0 0 0 0 0 0 0 0 0 0 [ 64.168173] rcu-torture: Reader Batch: 8 0 0 0 0 0 0 0 0 0 0 [ 64.168173] rcu-torture: Free-Block Circulation: 0 0 0 0 0 0 0 0 0 0 0 [ 64.740035] S20qemu-kvm (2359) used greatest stack depth: 2752 bytes left [ 109.382311] NFSD: Using /var/lib/nfs/v4recovery as the NFSv4 state recovery directory [ 109.417112] NFSD: starting 90-second grace period Kernel tests: Boot OK! [ 119.897159] Adding 307196k swap on /dev/vda. Priority:-1 extents:1 across:307196k [ 120.598628] Unregister pv shared memory for cpu 1 [ 120.601320] CPU0 attaching NULL sched-domain. [ 120.607032] CPU1 attaching NULL sched-domain. [ 120.610304] CPU0 attaching NULL sched-domain. [ 120.661775] numa_remove_cpu cpu 1 node 0: mask now 0 [ 120.756857] smpboot: CPU 1 is now offline [ 121.120577] SMP alternatives: lockdep: fixing up alternatives [ 121.141795] smpboot: Booting Node 0 Processor 1 APIC 0x1 [ 120.662418] kvm-clock: cpu 1, msr 0:1f5d3a81, secondary cpu clock [ 120.662418] masked ExtINT on CPU#1 [ 120.662418] numa_add_cpu cpu 1 node 0: mask now 0-1 [ 121.502677] CPU0 attaching NULL sched-domain. [ 121.504571] CPU0 attaching sched-domain: [ 121.506478] domain 0: span 0-1 level CPU [ 121.508233] groups: 0 (cpu_power = 1019) 1 [ 121.514949] CPU1 attaching sched-domain: [ 121.516542] domain 0: span 0-1 level CPU [ 121.522798] groups: 1 0 (cpu_power = 1019) [ 121.549926] KVM setup async PF for cpu 1 [ 121.549926] kvm-stealtime: cpu 1, msr 1f40d2c0 [ 122.706291] Unregister pv shared memory for cpu 1 [ 122.708767] CPU0 attaching NULL sched-domain. [ 122.711959] CPU1 attaching NULL sched-domain. [ 122.713826] CPU0 attaching NULL sched-domain. [ 122.760159] numa_remove_cpu cpu 1 node 0: mask now 0 [ 122.784101] smpboot: CPU 1 is now offline [ 124.207026] rcu-torture: rtc: ffffffff82cc1c20 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 4 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 [ 124.207026] rcu-torture: Reader Pipe: 8 0 0 0 0 0 0 0 0 0 0 [ 124.207026] rcu-torture: Reader Batch: 8 0 0 0 0 0 0 0 0 0 0 [ 124.207026] rcu-torture: Free-Block Circulation: 0 0 0 0 0 0 0 0 0 0 0 [ 125.684978] kjournald starting. Commit interval 5 seconds [ 125.701858] EXT3-fs (loop0): using internal journal [ 125.703644] EXT3-fs (loop0): mounted filesystem with writeback data mode case-anon-cow-rand [ 132.972735] SMP alternatives: lockdep: fixing up alternatives [ 133.007340] smpboot: Booting Node 0 Processor 1 APIC 0x1 [ 122.779989] kvm-clock: cpu 1, msr 0:1f5d3a81, secondary cpu clock [ 122.779989] masked ExtINT on CPU#1 [ 122.779989] numa_add_cpu cpu 1 node 0: mask now 0-1 [ 133.296360] CPU0 attaching NULL sched-domain. [ 133.301402] CPU0 attaching sched-domain: [ 133.302987] domain 0: span 0-1 level CPU [ 133.304721] groups: 0 (cpu_power = 1022) 1 [ 133.317079] CPU1 attaching sched-domain: [ 133.318695] domain 0: span 0-1 level CPU [ 133.320420] groups: 1 0 (cpu_power = 1022) [ 133.386327] KVM setup async PF for cpu 1 [ 133.387068] kvm-stealtime: cpu 1, msr 1f40d2c0 case-anon-cow-rand-mt case-anon-cow-seq [ 163.259017] [sched_delayed] sched: RT throttling activated case-anon-cow-seq-mt case-anon-r-rand [ 184.217070] rcu-torture: rtc: ffffffff82cc1c20 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 4 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 [ 184.217070] rcu-torture: Reader Pipe: 8 0 0 0 0 0 0 0 0 0 0 [ 184.217070] rcu-torture: Reader Batch: 8 0 0 0 0 0 0 0 0 0 0 [ 184.217070] rcu-torture: Free-Block Circulation: 0 0 0 0 0 0 0 0 0 0 0 case-anon-r-rand-mt [ 233.810908] Unregister pv shared memory for cpu 1 [ 233.813432] CPU0 attaching NULL sched-domain. [ 233.815066] CPU1 attaching NULL sched-domain. [ 233.819424] CPU0 attaching NULL sched-domain. [ 233.858437] numa_remove_cpu cpu 1 node 0: mask now 0 [ 233.875066] smpboot: CPU 1 is now offline [ 234.457729] SMP alternatives: lockdep: fixing up alternatives [ 234.469280] smpboot: Booting Node 0 Processor 1 APIC 0x1 [ 233.859023] kvm-clock: cpu 1, msr 0:1f5d3a81, secondary cpu clock [ 233.859023] masked ExtINT on CPU#1 [ 233.859023] numa_add_cpu cpu 1 node 0: mask now 0-1 [ 235.057144] CPU0 attaching NULL sched-domain. [ 235.068367] CPU0 attaching sched-domain: [ 235.074142] domain 0: span 0-1 level CPU [ 235.075849] groups: 0 (cpu_power = 1001) 1 [ 235.082194] CPU1 attaching sched-domain: [ 235.083760] domain 0: span 0-1 level CPU [ 235.089764] groups: 1 0 (cpu_power = 1001) [ 235.116490] KVM setup async PF for cpu 1 [ 235.117405] kvm-stealtime: cpu 1, msr 1f40d2c0 [ 235.381115] Unregister pv shared memory for cpu 1 [ 235.383592] CPU0 attaching NULL sched-domain. [ 235.385254] CPU1 attaching NULL sched-domain. [ 235.394236] CPU0 attaching NULL sched-domain. [ 235.489272] numa_remove_cpu cpu 1 node 0: mask now 0 [ 235.512066] smpboot: CPU 1 is now offline [ 236.851848] SMP alternatives: lockdep: fixing up alternatives [ 236.851855] smpboot: Booting Node 0 Processor 1 APIC 0x1 [ 235.500096] kvm-clock: cpu 1, msr 0:1f5d3a81, secondary cpu clock [ 235.500096] masked ExtINT on CPU#1 [ 235.500096] numa_add_cpu cpu 1 node 0: mask now 0-1 [ 237.018977] CPU0 attaching NULL sched-domain. [ 237.068594] CPU0 attaching sched-domain: [ 237.070272] domain 0: span 0-1 level CPU [ 237.071970] groups: 0 (cpu_power = 985) 1 [ 237.074200] CPU1 attaching sched-domain: [ 237.075726] domain 0: span 0-1 level CPU [ 237.087572] groups: 1 0 (cpu_power = 985) [ 237.101226] KVM setup async PF for cpu 1 [ 237.101226] kvm-stealtime: cpu 1, msr 1f40d2c0 case-anon-r-seq case-anon-r-seq-mt [ 244.267146] rcu-torture: rtc: ffffffff82cc1c20 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 4 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 [ 244.267146] rcu-torture: Reader Pipe: 8 0 0 0 0 0 0 0 0 0 0 [ 244.267146] rcu-torture: Reader Batch: 8 0 0 0 0 0 0 0 0 0 0 [ 244.267146] rcu-torture: Free-Block Circulation: 0 0 0 0 0 0 0 0 0 0 0 case-anon-rx-rand-mt [ 247.353157] Unregister pv shared memory for cpu 1 [ 247.391167] CPU0 attaching NULL sched-domain. [ 247.392837] CPU1 attaching NULL sched-domain. [ 247.437901] CPU0 attaching NULL sched-domain. [ 247.469736] numa_remove_cpu cpu 1 node 0: mask now 0 [ 247.494141] smpboot: CPU 1 is now offline case-anon-rx-seq-mt case-anon-w-rand [ 305.172404] rcu-torture: rtc: ffffffff82cc1c20 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 4 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 [ 305.172404] rcu-torture: Reader Pipe: 8 0 0 0 0 0 0 0 0 0 0 [ 305.172404] rcu-torture: Reader Batch: 8 0 0 0 0 0 0 0 0 0 0 [ 305.172404] rcu-torture: Free-Block Circulation: 0 0 0 0 0 0 0 0 0 0 0 case-anon-w-rand-mt case-anon-w-seq case-anon-w-seq-mt [ 347.843240] SMP alternatives: lockdep: fixing up alternatives [ 347.863156] smpboot: Booting Node 0 Processor 1 APIC 0x1 [ 247.481034] kvm-clock: cpu 1, msr 0:1f5d3a81, secondary cpu clock [ 247.481034] masked ExtINT on CPU#1 [ 247.481034] numa_add_cpu cpu 1 node 0: mask now 0-1 [ 348.203183] CPU0 attaching NULL sched-domain. [ 348.215488] CPU0 attaching sched-domain: [ 348.217559] domain 0: span 0-1 level CPU [ 348.219563] groups: 0 (cpu_power = 1021) 1 [ 348.222656] CPU1 attaching sched-domain: [ 348.233206] domain 0: span 0-1 level CPU [ 348.233206] groups: 1 0 (cpu_power = 1021) [ 348.281016] KVM setup async PF for cpu 1 [ 348.281016] kvm-stealtime: cpu 1, msr 1f40d2c0 [ 348.730015] Unregister pv shared memory for cpu 1 [ 348.776291] CPU0 attaching NULL sched-domain. [ 348.782402] CPU1 attaching NULL sched-domain. [ 348.789308] CPU0 attaching NULL sched-domain. [ 348.805016] numa_remove_cpu cpu 1 node 0: mask now 0 [ 348.821263] smpboot: CPU 1 is now offline [ 349.149796] SMP alternatives: lockdep: fixing up alternatives [ 349.156126] smpboot: Booting Node 0 Processor 1 APIC 0x1 [ 348.805034] kvm-clock: cpu 1, msr 0:1f5d3a81, secondary cpu clock [ 348.805034] masked ExtINT on CPU#1 [ 348.805034] numa_add_cpu cpu 1 node 0: mask now 0-1 [ 349.443353] CPU0 attaching NULL sched-domain. [ 349.448401] CPU0 attaching sched-domain: [ 349.450257] domain 0: span 0-1 level CPU [ 349.451961] groups: 0 (cpu_power = 1023) 1 [ 349.454498] CPU1 attaching sched-domain: [ 349.456104] domain 0: span 0-1 level CPU [ 349.457838] groups: 1 0 (cpu_power = 1023) [ 349.493062] KVM setup async PF for cpu 1 [ 349.493062] kvm-stealtime: cpu 1, msr 1f40d2c0 [ 350.834406] Unregister pv shared memory for cpu 1 [ 350.869348] CPU0 attaching NULL sched-domain. [ 350.871078] CPU1 attaching NULL sched-domain. [ 350.893263] CPU0 attaching NULL sched-domain. [ 350.914209] numa_remove_cpu cpu 1 node 0: mask now 0 [ 350.925072] smpboot: CPU 1 is now offline case-anon-wx-rand-mt [ 361.277774] SMP alternatives: lockdep: fixing up alternatives [ 361.310184] smpboot: Booting Node 0 Processor 1 APIC 0x1 [ 350.915073] kvm-clock: cpu 1, msr 0:1f5d3a81, secondary cpu clock [ 350.915073] masked ExtINT on CPU#1 [ 350.915073] numa_add_cpu cpu 1 node 0: mask now 0-1 [ 361.467024] CPU0 attaching NULL sched-domain. [ 361.468294] CPU0 attaching sched-domain: [ 361.469823] domain 0: span 0-1 level CPU [ 361.471555] groups: 0 (cpu_power = 935) 1 [ 361.473777] CPU1 attaching sched-domain: [ 361.486317] domain 0: span 0-1 level CPU [ 361.487194] groups: 1 0 (cpu_power = 935) [ 361.505485] KVM setup async PF for cpu 1 [ 361.506381] kvm-stealtime: cpu 1, msr 1f40d2c0 [ 365.195112] rcu-torture: rtc: ffffffff82cc1c20 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 4 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 [ 365.195112] rcu-torture: Reader Pipe: 8 0 0 0 0 0 0 0 0 0 0 [ 365.195112] rcu-torture: Reader Batch: 8 0 0 0 0 0 0 0 0 0 0 [ 365.195112] rcu-torture: Free-Block Circulation: 0 0 0 0 0 0 0 0 0 0 0 case-anon-wx-seq-mt case-fork case-fork-sleep case-hugetlb case-lru-file-mmap-read [ 425.240169] rcu-torture: rtc: ffffffff82cc1c20 ver: 1 tfle: 0 rta: 1 rtaf: 0 rtf: 0 rtmbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 4 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 [ 425.240169] rcu-torture: Reader Pipe: 8 0 0 0 0 0 0 0 0 0 0 [ 425.240169] rcu-torture: Reader Batch: 8 0 0 0 0 0 0 0 0 0 0 [ 425.240169] rcu-torture: Free-Block Circulation: 0 0 0 0 0 0 0 0 0 0 0 [ 432.205341] usemem (4011) used greatest stack depth: 2608 bytes left case-lru-file-mmap-read-rand [ 463.758546] Unregister pv shared memory for cpu 1 [ 463.852974] CPU0 attaching NULL sched-domain. [ 463.854698] CPU1 attaching NULL sched-domain. [ 463.952313] CPU0 attaching NULL sched-domain. [ 463.974741] numa_remove_cpu cpu 1 node 0: mask now 0 [ 464.012276] smpboot: CPU 1 is now offline [ 464.741124] SMP alternatives: lockdep: fixing up alternatives [ 464.748376] smpboot: Booting Node 0 Processor 1 APIC 0x1 [ 463.975027] kvm-clock: cpu 1, msr 0:1f5d3a81, secondary cpu clock [ 463.975027] masked ExtINT on CPU#1 [ 463.975027] numa_add_cpu cpu 1 node 0: mask now 0-1 [ 464.992259] CPU0 attaching NULL sched-domain. [ 464.997384] CPU0 attaching sched-domain: [ 464.998948] domain 0: span 0-1 level CPU [ 465.005054] groups: 0 (cpu_power = 989) 1 [ 465.007255] CPU1 attaching sched-domain: [ 465.012930] domain 0: span 0-1 level CPU [ 465.014665] groups: 1 0 (cpu_power = 989) [ 465.093519] KVM setup async PF for cpu 1 [ 465.094418] kvm-stealtime: cpu 1, msr 1f40d2c0 [ 465.830736] Unregister pv shared memory for cpu 1 [ 465.904348] CPU0 attaching NULL sched-domain. [ 465.906082] CPU1 attaching NULL sched-domain. [ 465.924430] CPU0 attaching NULL sched-domain. [ 465.964035] numa_remove_cpu cpu 1 node 0: mask now 0 [ 465.993326] smpboot: CPU 1 is now offline [ 467.472961] SMP alternatives: lockdep: fixing up alternatives [ 467.478118] smpboot: Booting Node 0 Processor 1 APIC 0x1 [ 465.987287] kvm-clock: cpu 1, msr 0:1f5d3a81, secondary cpu clock [ 465.987287] masked ExtINT on CPU#1 [ 465.987287] numa_add_cpu cpu 1 node 0: mask now 0-1 [ 468.121603] CPU0 attaching NULL sched-domain. [ 468.143384] CPU0 attaching sched-domain: [ 468.145303] domain 0: span 0-1 level CPU [ 468.146994] groups: 0 (cpu_power = 1022) 1 [ 468.149603] CPU1 attaching sched-domain: [ 468.151380] domain 0: span 0-1 level CPU [ 468.153376] groups: 1 0 (cpu_power = 1022) [ 469.443083] KVM setup async PF for cpu 1 [ 469.443083] kvm-stealtime: cpu 1, msr 1f40d2c0 [ 480.324905] Unregister pv shared memory for cpu 1 [ 480.327742] CPU0 attaching NULL sched-domain. [ 480.329408] ------------[ cut here ]------------ [ 480.330355] kernel BUG at /c/kernel-tests/src/stable/kernel/sched/rt.c:687! [ 480.330355] invalid opcode: 0000 [#1] SMP DEBUG_PAGEALLOC [ 480.330355] Modules linked in: [ 480.330355] CPU 1 [ 480.330355] Pid: 3007, comm: 01-cpu-hotplug Not tainted 3.7.0-rc3-00017-g1a303f5 #2502 Bochs Bochs [ 480.330355] RIP: 0010:[] [] __disable_runtime+0x188/0x1c2 [ 480.330355] RSP: 0018:ffff8800199cbad8 EFLAGS: 00010082 [ 480.330355] RAX: ffffffff810a6796 RBX: ffff88001f3d3b40 RCX: 0000000000000005 [ 480.330355] RDX: ffffffff81a5ff61 RSI: 0000000000000092 RDI: 0000000000000046 [ 480.330355] RBP: ffff8800199cbb38 R08: 00000000000006fc R09: 000000000000000c [ 480.330355] R10: 000000000000000c R11: ffff8800168cac88 R12: 0000000000000040 [ 480.330355] R13: ffff88001f3d4358 R14: 00000000001d3b40 R15: fffffffffd050f80 [ 480.330355] FS: 00007f2f660d9700(0000) GS:ffff88001f400000(0000) knlGS:0000000000000000 [ 480.330355] CS: 0010 DS: 0000 ES: 0000 CR0: 000000008005003b [ 480.330355] CR2: 00007f2f65aab830 CR3: 00000000184a1000 CR4: 00000000000006e0 [ 480.330355] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 [ 480.330355] DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000400 [ 480.330355] Process 01-cpu-hotplug (pid: 3007, threadinfo ffff8800199ca000, task ffff8800168ca3e0) [ 480.330355] Stack: [ 480.330355] ffff8800199cbb58 ffff8800168cac18 ffff88001f3d3b58 ffff880018544a58 [ 480.330355] ffff88001f3d3cb8 0000000000000040 ffff8800199cbb58 ffff88001f3d3b40 [ 480.330355] ffffffff81a931d0 ffffffff824086b0 ffff880018544a60 00000000ffffffff [ 480.330355] Call Trace: [ 480.330355] [] rq_offline_rt+0x74/0x96 [ 480.330355] [] set_rq_offline.part.37+0x2a/0x81 [ 480.330355] [] rq_attach_root+0x85/0x193 [ 480.330355] [] cpu_attach_domain+0x180/0x228 [ 480.330355] [] partition_sched_domains+0x157/0x350 [ 480.330355] [] ? partition_sched_domains+0x109/0x350 [ 480.330355] [] ? trace_hardirqs_on_caller+0x125/0x181 [ 480.330355] [] cpuset_update_active_cpus+0x10c/0x117 [ 480.330355] [] ? keep_working+0x2d/0x2d [ 480.330355] [] cpuset_cpu_inactive+0x20/0x3f [ 480.330355] [] notifier_call_chain+0x65/0x95 [ 480.330355] [] __raw_notifier_call_chain+0xe/0x10 [ 480.330355] [] __cpu_notify+0x20/0x37 [ 480.330355] [] _cpu_down+0xa4/0x26d [ 480.330355] [] cpu_down+0x28/0x3c [ 480.330355] [] store_online+0x3e/0x8f [ 480.330355] [] dev_attr_store+0x18/0x24 [ 480.330355] [] sysfs_write_file+0xee/0x126 [ 480.330355] [] vfs_write+0xa6/0x152 [ 480.330355] [] sys_write+0x4c/0x80 [ 480.330355] [] tracesys+0xdd/0xe2 [ 480.330355] Code: 00 00 00 49 63 d4 e8 e1 b8 39 00 3b 05 73 97 ff 00 48 89 45 c8 41 89 c4 0f 8c 2c ff ff ff 4c 89 ef e8 72 8d 9b 00 4d 85 ff 74 02 <0f> 0b 48 c7 83 10 08 00 00 ff ff ff ff 4c 89 ef c7 83 00 08 00 [ 480.330355] RIP [] __disable_runtime+0x188/0x1c2 [ 480.330355] RSP [ 480.330355] ---[ end trace 038e349e505803f2 ]--- [ 480.330355] BUG: sleeping function called from invalid context at /c/kernel-tests/src/stable/kernel/rwsem.c:20 [ 480.330355] in_atomic(): 1, irqs_disabled(): 1, pid: 3007, name: 01-cpu-hotplug [ 480.330355] INFO: lockdep is turned off. [ 480.330355] irq event stamp: 114442 [ 480.330355] hardirqs last enabled at (114441): [] restore_args+0x0/0x30 [ 480.330355] hardirqs last disabled at (114442): [] _raw_spin_lock_irqsave+0x2b/0x98 [ 480.330355] softirqs last enabled at (114440): [] __do_softirq+0x1ca/0x231 [ 480.330355] softirqs last disabled at (114425): [] call_softirq+0x1c/0x30 [ 480.330355] Pid: 3007, comm: 01-cpu-hotplug Tainted: G D 3.7.0-rc3-00017-g1a303f5 #2502 [ 480.330355] Call Trace: [ 480.330355] [] ? print_irqtrace_events+0xce/0xd2 [ 480.330355] [] __might_sleep+0x108/0x10d [ 480.330355] [] down_read+0x2a/0x96 [ 480.330355] [] exit_signals+0x26/0x129 [ 480.330355] [] do_exit+0x119/0x861 [ 480.330355] [] ? kmsg_dump+0xef/0x107 [ 480.330355] [] ? kmsg_dump+0x25/0x107 [ 480.330355] [] oops_end+0xaf/0xb8 [ 480.330355] [] die+0x5a/0x63 [ 480.330355] [] do_trap+0x6e/0x13a [ 480.330355] [] do_invalid_op+0xa1/0xaa [ 480.330355] [] ? __disable_runtime+0x188/0x1c2 [ 480.330355] [] ? trace_hardirqs_off_thunk+0x3a/0x3c [ 480.330355] [] ? restore_args+0x30/0x30 [ 480.330355] [] ? _raw_spin_unlock+0x29/0x3d [ 480.330355] [] invalid_op+0x1e/0x30 [ 480.330355] [] ? __disable_runtime+0x183/0x1c2 [ 480.330355] [] ? _raw_spin_unlock+0x29/0x3d [ 480.330355] [] ? __disable_runtime+0x188/0x1c2 [ 480.330355] [] ? __disable_runtime+0x183/0x1c2 [ 480.330355] [] rq_offline_rt+0x74/0x96 [ 480.330355] [] set_rq_offline.part.37+0x2a/0x81 [ 480.330355] [] rq_attach_root+0x85/0x193 [ 480.330355] [] cpu_attach_domain+0x180/0x228 [ 480.330355] [] partition_sched_domains+0x157/0x350 [ 480.330355] [] ? partition_sched_domains+0x109/0x350 [ 480.330355] [] ? trace_hardirqs_on_caller+0x125/0x181 [ 480.330355] [] cpuset_update_active_cpus+0x10c/0x117 [ 480.330355] [] ? keep_working+0x2d/0x2d [ 480.330355] [] cpuset_cpu_inactive+0x20/0x3f [ 480.330355] [] notifier_call_chain+0x65/0x95 [ 480.330355] [] __raw_notifier_call_chain+0xe/0x10 [ 480.330355] [] __cpu_notify+0x20/0x37 [ 480.330355] [] _cpu_down+0xa4/0x26d [ 480.330355] [] cpu_down+0x28/0x3c [ 480.330355] [] store_online+0x3e/0x8f [ 480.330355] [] dev_attr_store+0x18/0x24 [ 480.330355] [] sysfs_write_file+0xee/0x126 [ 480.330355] [] vfs_write+0xa6/0x152 [ 480.330355] [] sys_write+0x4c/0x80 [ 480.330355] [] tracesys+0xdd/0xe2 [ 480.330355] note: 01-cpu-hotplug[3007] exited with preempt_count 4 [ 481.110096] BUG: scheduling while atomic: 01-cpu-hotplug/3007/0x10000005 [ 481.125222] INFO: lockdep is turned off. [ 481.126735] Modules linked in: [ 481.128225] Pid: 3007, comm: 01-cpu-hotplug Tainted: G D 3.7.0-rc3-00017-g1a303f5 #2502 [ 481.144396] Call Trace: [ 481.145629] [] __schedule_bug+0x67/0x75 [ 481.147466] [] __schedule+0xb0/0x8b0 [ 481.149249] [] ? trace_buffer_lock_reserve+0x1f/0x55 [ 481.164411] [] ? trace_buffer_lock_reserve+0x1f/0x55 [ 481.166500] [] ? trace_current_buffer_lock_reserve+0x16/0x18 [ 481.169253] [] __cond_resched+0x28/0x34 [ 481.184159] [] _cond_resched+0x2e/0x35 [ 481.185990] [] down_read+0x2f/0x96 [ 481.187734] [] exit_mm+0x3f/0x115 [ 481.189455] [] ? _raw_spin_unlock_irq+0x2e/0x48 [ 481.204533] [] do_exit+0x22c/0x861 [ 481.206302] [] ? kmsg_dump+0xef/0x107 [ 481.208089] [] ? kmsg_dump+0x25/0x107 [ 481.209864] [] oops_end+0xaf/0xb8 [ 481.224669] [] die+0x5a/0x63 [ 481.226307] [] do_trap+0x6e/0x13a [ 481.228031] [] do_invalid_op+0xa1/0xaa [ 481.229826] [] ? __disable_runtime+0x188/0x1c2 [ 481.249877] [] ? trace_hardirqs_off_thunk+0x3a/0x3c [ 481.251920] [] ? restore_args+0x30/0x30 [ 481.253750] [] ? _raw_spin_unlock+0x29/0x3d [ 481.268723] [] invalid_op+0x1e/0x30 [ 481.270503] [] ? __disable_runtime+0x183/0x1c2 [ 481.272451] [] ? _raw_spin_unlock+0x29/0x3d [ 481.274349] [] ? __disable_runtime+0x188/0x1c2 [ 481.289400] [] ? __disable_runtime+0x183/0x1c2 [ 481.291351] [] rq_offline_rt+0x74/0x96 [ 481.293152] [] set_rq_offline.part.37+0x2a/0x81 [ 481.308234] [] rq_attach_root+0x85/0x193 [ 481.310090] [] cpu_attach_domain+0x180/0x228 [ 481.311998] [] partition_sched_domains+0x157/0x350 [ 481.314013] [] ? partition_sched_domains+0x109/0x350 [ 481.335164] [] ? trace_hardirqs_on_caller+0x125/0x181 [ 481.337258] [] cpuset_update_active_cpus+0x10c/0x117 [ 481.339323] [] ? keep_working+0x2d/0x2d [ 481.354238] [] cpuset_cpu_inactive+0x20/0x3f [ 481.356160] [] notifier_call_chain+0x65/0x95 [ 481.358077] [] __raw_notifier_call_chain+0xe/0x10 [ 481.360062] [] __cpu_notify+0x20/0x37 [ 481.374909] [] _cpu_down+0xa4/0x26d [ 481.376680] [] cpu_down+0x28/0x3c [ 481.378399] [] store_online+0x3e/0x8f [ 481.380185] [] dev_attr_store+0x18/0x24 [ 481.395101] [] sysfs_write_file+0xee/0x126 [ 481.404954] [] vfs_write+0xa6/0x152 [ 481.406730] [] sys_write+0x4c/0x80 [ 481.408463] [] tracesys+0xdd/0xe2