[ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Linux version 3.6.0-rc1-00102-ga184d4e (kbuild@kbuild) (gcc version 4.7.1 (Debian 4.7.1-6) ) #7 SMP Wed Aug 29 06:31:21 CST 2012 [ 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-0x000000000fffdfff] usable [ 0.000000] BIOS-e820: [mem 0x000000000fffe000-0x000000000fffffff] 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] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel! [ 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 = 0xfffe max_arch_pfn = 0x100000 [ 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 [400fdac0] [ 0.000000] mpc: fdad0-fdbec [ 0.000000] initial memory mapped: [mem 0x00000000-0x033fffff] [ 0.000000] Base memory trampoline at [4008f000] 8f000 size 16384 [ 0.000000] init_memory_mapping: [mem 0x00000000-0x0fffdfff] [ 0.000000] [mem 0x00000000-0x003fffff] page 4k [ 0.000000] [mem 0x00400000-0x0fbfffff] page 2M [ 0.000000] [mem 0x0fc00000-0x0fffdfff] page 4k [ 0.000000] kernel direct mapping tables up to 0xfffdfff @ [mem 0x033fa000-0x033fffff] [ 0.000000] cma: CMA: reserved 16 MiB at 0d000000 [ 0.000000] log_buf_len: 8388608 [ 0.000000] early log buf free: 129060(98%) [ 0.000000] RAMDISK: [mem 0x0e73f000-0x0ffeffff] [ 0.000000] ACPI: RSDP 000fd930 00014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0fffe550 00038 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0fffff80 00074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0fffe590 01121 (v01 BXPC BXDSDT 00000001 INTL 20100528) [ 0.000000] ACPI: FACS 0fffff40 00040 [ 0.000000] ACPI: SSDT 0ffffe40 000FF (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: APIC 0ffffd50 00080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0ffffd10 00038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] ACPI: SSDT 0ffff6c0 00644 (v01 BXPC BXSSDTPC 00000001 INTL 20100528) [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffb000 ( fee00000) [ 0.000000] 0MB HIGHMEM available. [ 0.000000] 255MB LOWMEM available. [ 0.000000] mapped low ram: 0 - 0fffe000 [ 0.000000] low ram: 0 - 0fffe000 [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:2340d01, boot clock [ 0.000000] Zone ranges: [ 0.000000] Normal [mem 0x00010000-0x0fffdfff] [ 0.000000] HighMem 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-0x0fffdfff] [ 0.000000] On node 0 totalpages: 65409 [ 0.000000] free_area_init_node: node 0, pgdat 4210a0e0, node_mem_map 4e53f200 [ 0.000000] Normal zone: 512 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 64897 pages, LIFO batch:15 [ 0.000000] Using APIC driver default [ 0.000000] ACPI: PM-Timer IO Port: 0xb008 [ 0.000000] ACPI: Local APIC address 0xfee00000 [ 0.000000] mapped APIC to ffffb000 ( 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 ffffa000 (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 0x10000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] setup_percpu: NR_CPUS:32 nr_cpumask_bits:32 nr_cpu_ids:2 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 331 pages/cpu @4e2a9000 s1340316 r0 d15460 u1355776 [ 0.000000] pcpu-alloc: s1340316 r0 d15460 u1355776 alloc=331*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] kvm-clock: cpu 0, msr 0:e3efd01, primary cpu clock [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr e2ab780 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 64897 [ 0.000000] Kernel command line: trinity=2m hung_task_panic=1 branch=sound-unstable/master log_buf_len=8M ignore_loglevel debug sched_debug apic=debug dynamic_printk sysrq_always_enabled panic=10 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal root=/dev/ram0 rw link=vmlinuz-2012-08-29-06-31-54-sound-unstable:master:b9a0dcb-a184d4e-i386-randconfig-k848-1-stoakley BOOT_IMAGE=kernel-tests/kernels/i386-randconfig-k848/a184d4e/vmlinuz-3.6.0-rc1-00102-ga184d4e [ 0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes) [ 0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes) [ 0.000000] __ex_table already sorted, skipping sort [ 0.000000] Initializing CPU#0 [ 0.000000] Initializing HighMem for node 0 (00000000:00000000) [ 0.000000] Memory: 177420k/262136k available (10715k kernel code, 84216k reserved, 7089k data, 1908k init, 0k highmem) [ 0.000000] virtual kernel memory layout: [ 0.000000] fixmap : 0xffd36000 - 0xfffff000 (2852 kB) [ 0.000000] pkmap : 0xff800000 - 0xffc00000 (4096 kB) [ 0.000000] vmalloc : 0x507fe000 - 0xff7fe000 (2800 MB) [ 0.000000] lowmem : 0x40000000 - 0x4fffe000 ( 255 MB) [ 0.000000] .init : 0x42165000 - 0x42342000 (1908 kB) [ 0.000000] .data : 0x41a76ca5 - 0x421631c0 (7089 kB) [ 0.000000] .text : 0x41000000 - 0x41a76ca5 (10715 kB) [ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok. [ 0.000000] Hierarchical RCU implementation. [ 0.000000] [ 0.000000] [ 0.000000] [ 0.000000] [ 0.000000] [ 0.000000] NR_IRQS:2304 nr_irqs:512 16 [ 0.000000] CPU 0 irqstacks, hard=4fff0000 soft=4fff2000 [ 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: 3823 kB [ 0.000000] per task-struct memory footprint: 1920 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: 6 of 6 active objects replaced [ 0.000000] hpet clockevent registered [ 0.000000] tsc: Detected 2833.416 MHz processor [ 0.001999] Calibrating delay loop (skipped) preset value.. 5666.83 BogoMIPS (lpj=2833416) [ 0.002428] pid_max: default: 32768 minimum: 301 [ 0.003305] Mount-cache hash table entries: 512 [ 0.005675] Initializing cgroup subsys cpuacct [ 0.006039] Initializing cgroup subsys net_cls [ 0.007034] Initializing cgroup subsys blkio [ 0.007666] Initializing cgroup subsys perf_event [ 0.008039] Initializing cgroup subsys net_prio [ 0.009298] mce: CPU supports 10 MCE banks [ 0.010122] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.010122] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.010122] tlb_flushall_shift is 0x6 [ 0.014774] ACPI: Core revision 20120711 [ 0.032880] Getting VERSION: 50014 [ 0.033022] Getting VERSION: 50014 [ 0.034019] Getting ID: 0 [ 0.034424] Getting ID: f000000 [ 0.035019] Getting LVT0: 8700 [ 0.035478] Getting LVT1: 8400 [ 0.035926] Enabling APIC mode: Flat. Using 1 I/O APICs [ 0.036093] enabled ExtINT on CPU#0 [ 0.038814] ENABLING IO-APIC IRQs [ 0.039016] init IO_APIC IRQs [ 0.039460] apic 2 pin 0 not connected [ 0.040045] IOAPIC[0]: Set routing entry (2-1 -> 0x41 -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.041053] IOAPIC[0]: Set routing entry (2-2 -> 0x51 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.043058] IOAPIC[0]: Set routing entry (2-3 -> 0x61 -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.044088] IOAPIC[0]: Set routing entry (2-4 -> 0x71 -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.045043] IOAPIC[0]: Set routing entry (2-5 -> 0x81 -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.046042] IOAPIC[0]: Set routing entry (2-6 -> 0x91 -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.047044] IOAPIC[0]: Set routing entry (2-7 -> 0xa1 -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.048042] IOAPIC[0]: Set routing entry (2-8 -> 0xb1 -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.049042] IOAPIC[0]: Set routing entry (2-9 -> 0xc1 -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.050043] IOAPIC[0]: Set routing entry (2-10 -> 0xd1 -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.051077] IOAPIC[0]: Set routing entry (2-11 -> 0xe1 -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.052042] IOAPIC[0]: Set routing entry (2-12 -> 0x22 -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.053041] IOAPIC[0]: Set routing entry (2-13 -> 0x42 -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.054042] IOAPIC[0]: Set routing entry (2-14 -> 0x52 -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.055047] IOAPIC[0]: Set routing entry (2-15 -> 0x62 -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.056051] apic 2 pin 16 not connected [ 0.057004] apic 2 pin 17 not connected [ 0.057573] apic 2 pin 18 not connected [ 0.058003] apic 2 pin 19 not connected [ 0.058561] apic 2 pin 20 not connected [ 0.059003] apic 2 pin 21 not connected [ 0.060003] apic 2 pin 22 not connected [ 0.060571] apic 2 pin 23 not connected [ 0.061163] ..TIMER: vector=0x51 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.062003] smpboot: CPU0: Intel Common KVM processor stepping 01 [ 0.063724] Using local APIC timer interrupts. [ 0.063724] calibrating APIC timer ... [ 0.064990] ... lapic delta = 6562182 [ 0.064990] ... PM-Timer delta = 375835 [ 0.064990] APIC calibration not consistent with PM-Timer: 104ms instead of 100ms [ 0.064990] APIC delta adjusted to PM-Timer: 6249974 (6562182) [ 0.064990] TSC delta adjusted to PM-Timer: 283332516 (297485924) [ 0.064990] ..... delta 6249974 [ 0.064990] ..... mult: 268475147 [ 0.064990] ..... calibration result: 999995 [ 0.064990] ..... CPU clock speed is 2833.0325 MHz. [ 0.064990] ..... host bus clock speed is 999.0995 MHz. [ 0.065110] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only. [ 0.068533] ftrace: Allocated trace_printk buffers [ 0.069278] Testing tracer nop: PASSED [ 0.074460] NMI watchdog: disabled (cpu0): hardware events not enabled [ 0.077168] SMP alternatives: lockdep: fixing up alternatives [ 0.078039] CPU 1 irqstacks, hard=4c45e000 soft=4c460000 [ 0.078999] smpboot: Booting Node 0, Processors #1 OK [ 0.001999] Initializing CPU#1 [ 0.001999] kvm-clock: cpu 1, msr 0:e53ad01, secondary cpu clock [ 0.001999] masked ExtINT on CPU#1 [ 0.096659] KVM setup async PF for cpu 1 [ 0.097019] Brought up 2 CPUs [ 0.097024] smpboot: Total of 2 processors activated (11333.66 BogoMIPS) [ 0.098089] kvm-stealtime: cpu 1, msr e3f6780 [ 0.098886] devtmpfs: initialized [ 0.103917] dummy: [ 0.105232] NET: Registered protocol family 16 [ 0.107382] EISA bus registered [ 0.108673] ACPI: bus type pci registered [ 0.109336] PCI: PCI BIOS revision 2.10 entry at 0xfc78c, last bus=0 [ 0.110004] PCI: Using configuration type 1 for base access [ 0.116168] bio: create slab at 0 [ 0.117937] ACPI: Added _OSI(Module Device) [ 0.118001] ACPI: Added _OSI(Processor Device) [ 0.119000] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.119688] ACPI: Added _OSI(Processor Aggregator Device) [ 0.128237] ACPI: EC: Look up EC in DSDT [ 0.163751] ACPI: Interpreter enabled [ 0.163985] ACPI: (supports S0 S4 S5) [ 0.165168] ACPI: Using IOAPIC for interrupt routing [ 0.199298] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.200083] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.202073] pci_root PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.204055] PCI host bridge to bus 0000:00 [ 0.204684] pci_bus 0000:00: busn_res: [bus 00-ff] is inserted under domain [bus 00-ff] [ 0.204981] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.205984] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7] [ 0.206983] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff] [ 0.207982] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff] [ 0.208982] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xfebfffff] [ 0.210105] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.212381] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.214146] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.217019] pci 0000:00:01.1: reg 20: [io 0xc1c0-0xc1cf] [ 0.218998] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.220641] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI [ 0.220995] pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB [ 0.223075] pci 0000:00:02.0: [1013:00b8] type 00 class 0x030000 [ 0.225188] pci 0000:00:02.0: reg 10: [mem 0xfc000000-0xfdffffff pref] [ 0.227105] pci 0000:00:02.0: reg 14: [mem 0xfebf0000-0xfebf0fff] [ 0.236119] pci 0000:00:02.0: reg 30: [mem 0xfebe0000-0xfebeffff pref] [ 0.238360] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 0.239390] pci 0000:00:03.0: reg 10: [mem 0xfeba0000-0xfebbffff] [ 0.241033] pci 0000:00:03.0: reg 14: [io 0xc000-0xc03f] [ 0.245347] pci 0000:00:03.0: reg 30: [mem 0xfebc0000-0xfebdffff pref] [ 0.247009] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 [ 0.248573] pci 0000:00:04.0: reg 10: [io 0xc040-0xc07f] [ 0.250032] pci 0000:00:04.0: reg 14: [mem 0xfebf1000-0xfebf1fff] [ 0.255320] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.256459] pci 0000:00:05.0: reg 10: [io 0xc080-0xc0bf] [ 0.258028] pci 0000:00:05.0: reg 14: [mem 0xfebf2000-0xfebf2fff] [ 0.263486] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.264437] pci 0000:00:06.0: reg 10: [io 0xc0c0-0xc0ff] [ 0.266383] pci 0000:00:06.0: reg 14: [mem 0xfebf3000-0xfebf3fff] [ 0.271779] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 [ 0.273024] pci 0000:00:07.0: reg 10: [io 0xc100-0xc13f] [ 0.274691] pci 0000:00:07.0: reg 14: [mem 0xfebf4000-0xfebf4fff] [ 0.280025] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 [ 0.281500] pci 0000:00:08.0: reg 10: [io 0xc140-0xc17f] [ 0.283030] pci 0000:00:08.0: reg 14: [mem 0xfebf5000-0xfebf5fff] [ 0.288273] pci 0000:00:09.0: [1af4:1001] type 00 class 0x010000 [ 0.289445] pci 0000:00:09.0: reg 10: [io 0xc180-0xc1bf] [ 0.291021] pci 0000:00:09.0: reg 14: [mem 0xfebf6000-0xfebf6fff] [ 0.296313] pci 0000:00:0a.0: [8086:25ab] type 00 class 0x088000 [ 0.297270] pci 0000:00:0a.0: reg 10: [mem 0xfebf7000-0xfebf700f] [ 0.301568] pci_bus 0000:00: on NUMA node 0 [ 0.302139] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT] [ 0.316764] pci0000:00: Unable to request _OSC control (_OSC support mask: 0x08) [ 0.435128] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.436959] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.438664] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.440547] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.442123] ACPI: PCI Interrupt Link [LNKS] (IRQs 9) *0 [ 0.445101] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none [ 0.446197] vgaarb: loaded [ 0.446948] vgaarb: bridge control possible 0000:00:02.0 [ 0.450054] SCSI subsystem initialized [ 0.450695] ACPI: bus type scsi registered [ 0.452675] libata version 3.00 loaded. [ 0.455137] PCI: Using ACPI for IRQ routing [ 0.455768] PCI: pci_cache_line_size set to 64 bytes [ 0.457437] e820: reserve RAM buffer [mem 0x00093c00-0x0009ffff] [ 0.457978] e820: reserve RAM buffer [mem 0x0fffe000-0x0fffffff] [ 0.463221] Sangoma WANPIPE Router v1.1 (c) 1995-2000 Sangoma Technologies Inc. [ 0.464214] NET: Registered protocol family 23 [ 0.465124] Bluetooth: Core ver 2.16 [ 0.466047] NET: Registered protocol family 31 [ 0.466722] Bluetooth: HCI device and connection manager initialized [ 0.466984] Bluetooth: HCI socket layer initialized [ 0.467960] Bluetooth: L2CAP socket layer initialized [ 0.469037] Bluetooth: SCO socket layer initialized [ 0.471271] Switching to clocksource kvm-clock [ 0.474891] Warning: could not register annotated branches stats [ 0.673622] pnp: PnP ACPI init [ 0.674261] ACPI: bus type pnp registered [ 0.675242] pnp 00:00: [bus 00-ff] [ 0.675794] pnp 00:00: [io 0x0cf8-0x0cff] [ 0.676413] pnp 00:00: [io 0x0000-0x0cf7 window] [ 0.677156] pnp 00:00: [io 0x0d00-0xffff window] [ 0.677850] pnp 00:00: [mem 0x000a0000-0x000bffff window] [ 0.678670] pnp 00:00: [mem 0xe0000000-0xfebfffff window] [ 0.679814] pnp 00:00: Plug and Play ACPI device, IDs PNP0a03 (active) [ 0.680916] pnp 00:01: [io 0x0070-0x0071] [ 0.681561] pnp 00:01: [irq 8] [ 0.682134] pnp 00:01: [io 0x0072-0x0077] [ 0.682854] pnp 00:01: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.684255] pnp 00:02: [io 0x0060] [ 0.684836] pnp 00:02: [io 0x0064] [ 0.685362] pnp 00:02: [irq 1] [ 0.685936] pnp 00:02: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.687366] pnp 00:03: [irq 12] [ 0.688008] pnp 00:03: Plug and Play ACPI device, IDs PNP0f13 (active) [ 0.689586] pnp 00:04: [io 0x03f2-0x03f5] [ 0.690470] pnp 00:04: [io 0x03f7] [ 0.691014] pnp 00:04: [irq 6] [ 0.691481] pnp 00:04: [dma 2] [ 0.692217] pnp 00:04: Plug and Play ACPI device, IDs PNP0700 (active) [ 0.693984] pnp 00:05: [io 0x0378-0x037f] [ 0.694607] pnp 00:05: [irq 7] [ 0.695260] pnp 00:05: Plug and Play ACPI device, IDs PNP0400 (active) [ 0.697000] pnp 00:06: [io 0x03f8-0x03ff] [ 0.697684] pnp 00:06: [irq 4] [ 0.698500] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.702728] pnp 00:07: [mem 0xfed00000-0xfed003ff] [ 0.703956] pnp 00:07: Plug and Play ACPI device, IDs PNP0103 (active) [ 0.706458] pnp: PnP ACPI: found 8 devices [ 0.707113] ACPI: ACPI bus type pnp unregistered [ 0.745267] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7] [ 0.746143] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff] [ 0.746973] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff] [ 0.747930] pci_bus 0000:00: resource 7 [mem 0xe0000000-0xfebfffff] [ 0.748955] NET: Registered protocol family 1 [ 0.749667] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.750570] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.751521] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.752547] pci 0000:00:02.0: Boot video device [ 0.753496] PCI: CLS 0 bytes, default 64 [ 0.754800] Unpacking initramfs... [ 3.141515] Freeing initrd memory: 25284k freed [ 3.165382] The force parameter has not been set to 1 so the Iris poweroff handler will not be installed. [ 3.169290] Initializing RT-Tester: OK [ 3.170390] rcu-torture:--- Start of test: nreaders=4 nfakewriters=4 stat_interval=0 verbose=0 test_no_idle_hz=0 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 [ 3.267585] Testing tracer function: [ 4.282931] tsc: Refined TSC clocksource calibration: 2833.332 MHz PASSED [ 13.392541] Testing tracer irqsoff: PASSED [ 13.428537] Testing tracer branch: [ 20.093074] ------------[ cut here ]------------ [ 20.093861] WARNING: at /c/kernel-tests/src/stable/kernel/lockdep.c:3493 check_flags+0x166/0x386() [ 20.093861] Pid: 0, comm: swapper/0 Not tainted 3.6.0-rc1-00102-ga184d4e #7 [ 20.093861] Call Trace: [ 20.093861] [<41064af3>] warn_slowpath_common+0x8e/0xcd [ 20.093861] [<410f1372>] ? check_flags+0x166/0x386 [ 20.093861] [<41064c6b>] warn_slowpath_null+0x30/0x45 [ 20.093861] [<410f1372>] check_flags+0x166/0x386 [ 20.093861] [<410f7cc5>] lock_acquire+0x5b/0x24a [ 20.093861] [<4107f7d8>] run_timer_softirq+0x2a6/0x77d [ 20.093861] [<4107f72f>] ? run_timer_softirq+0x1fd/0x77d [ 20.093861] [<4107f449>] ? spin_unlock_irqrestore+0x26/0x26 [ 20.093861] [<41075293>] __do_softirq+0x234/0x5d6 [ 20.093861] [<4107505f>] ? local_bh_enable_ip+0x2b/0x2b [ 20.093861] [<41075adf>] ? irq_exit+0x9d/0x14b [ 20.093861] [<4103b1b7>] ? smp_apic_timer_interrupt+0xe1/0xfd [ 20.093861] [<41a75bc7>] ? apic_timer_interrupt+0x2f/0x34 [ 20.093861] [<41151975>] ? ftrace_likely_update+0x3bb/0x446 [ 20.093861] [<41102e17>] ? arch_local_irq_enable+0x6b/0x80 [ 20.093861] [<41a74287>] ? _raw_spin_unlock_irq+0x80/0xc5 [ 20.093861] [<410babf0>] ? finish_task_switch+0x109/0x1df [ 20.093861] [<410bab8a>] ? finish_task_switch+0xa3/0x1df [ 20.093861] [<41a7222b>] ? __schedule+0xb89/0xd51 [ 20.093861] [<4112efbe>] ? rcu_idle_exit+0x1d0/0x26c [ 20.093861] [<4112efd3>] ? rcu_idle_exit+0x1e5/0x26c [ 20.093861] [<41a7252f>] ? schedule+0x13c/0x14c [ 20.093861] [<41014615>] ? cpu_idle+0x1d5/0x1fd [ 20.093861] [<41a01c3d>] ? rest_init+0x319/0x32c [ 20.093861] [<4216637a>] ? start_kernel+0x84c/0x85f [ 20.093861] [<42165322>] ? i386_start_kernel+0xf8/0x10b [ 20.093861] ---[ end trace 39036f94f86e3090 ]--- [ 20.093861] possible reason: unannotated irqs-on. [ 20.093861] irq event stamp: 36791 [ 20.093861] hardirqs last enabled at (36790): [<41a74274>] _raw_spin_unlock_irq+0x6d/0xc5 [ 20.093861] hardirqs last disabled at (36791): [<41151700>] ftrace_likely_update+0x146/0x446 [ 20.093861] softirqs last enabled at (34274): [<41074fe8>] _local_bh_enable+0x20/0x30 [ 20.093861] softirqs last disabled at (34275): [<41007553>] call_on_stack+0x20/0x34