[ 0.000000] Linux version 3.6.0-rc3-00013-g01f8a27 (kbuild@kbuild) (gcc version 4.7.1 (Debian 4.7.1-6) ) #158 SMP PREEMPT Wed Sep 5 03:53:06 CST 2012 [ 0.000000] KERNEL supported cpus: [ 0.000000] Intel GenuineIntel [ 0.000000] UMC UMC UMC UMC [ 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 [c00fdac0] [ 0.000000] mpc: fdad0-fdbec [ 0.000000] initial memory mapped: [mem 0x00000000-0x01bfffff] [ 0.000000] Base memory trampoline at [c008f000] 8f000 size 16384 [ 0.000000] init_memory_mapping: [mem 0x00000000-0x0fffdfff] [ 0.000000] [mem 0x00000000-0x0fffdfff] page 4k [ 0.000000] kernel direct mapping tables up to 0xfffdfff @ [mem 0x01bbd000-0x01bfffff] [ 0.000000] log_buf_len: 8388608 [ 0.000000] early log buf free: 129148(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] Zone ranges: [ 0.000000] DMA [mem 0x00010000-0x00ffffff] [ 0.000000] Normal [mem 0x01000000-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 c13a8100, node_mem_map cdcbf280 [ 0.000000] DMA zone: 40 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 3931 pages, LIFO batch:0 [ 0.000000] Normal zone: 600 pages used for memmap [ 0.000000] Normal zone: 60838 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] e820: [mem 0x10000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 13 pages/cpu @cdca5000 s30464 r0 d22784 u53248 [ 0.000000] pcpu-alloc: s30464 r0 d22784 u53248 alloc=13*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr dca7540 [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 64769 [ 0.000000] Kernel command line: trinity=2m hung_task_panic=1 rcutree.rcu_cpu_stall_timeout=100 branch=staging/staging-linus 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-09-05-03-53-56-staging:staging-linus:01f8a27-01f8a27-i386-randconfig-k905-8-stoakley BOOT_IMAGE=kernel-tests/kernels/i386-randconfig-k905/01f8a27/vmlinuz-3.6.0-rc3-00013-g01f8a27 [ 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: 215520k/262136k available (2335k kernel code, 46116k reserved, 1432k data, 284k init, 0k highmem) [ 0.000000] virtual kernel memory layout: [ 0.000000] fixmap : 0xfff16000 - 0xfffff000 ( 932 kB) [ 0.000000] pkmap : 0xff800000 - 0xffc00000 (4096 kB) [ 0.000000] vmalloc : 0xd07fe000 - 0xff7fe000 ( 752 MB) [ 0.000000] lowmem : 0xc0000000 - 0xcfffe000 ( 255 MB) [ 0.000000] .init : 0xc13af000 - 0xc13f6000 ( 284 kB) [ 0.000000] .data : 0xc1247f52 - 0xc13ae0c0 (1432 kB) [ 0.000000] .text : 0xc1000000 - 0xc1247f52 (2335 kB) [ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok. [ 0.000000] SLUB: Genslabs=15, HWalign=128, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] Preemptible hierarchical RCU implementation. [ 0.000000] [ 0.000000] [ 0.000000] [ 0.000000] NR_IRQS:2304 nr_irqs:512 16 [ 0.000000] CPU 0 irqstacks, hard=cd80a000 soft=cd80c000 [ 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: 3567 kB [ 0.000000] per task-struct memory footprint: 1152 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] hpet clockevent registered [ 0.000000] tsc: Fast TSC calibration using PIT [ 0.000000] tsc: Detected 2833.300 MHz processor [ 0.010004] Calibrating delay loop (skipped), value calculated using timer frequency.. 5666.60 BogoMIPS (lpj=28333000) [ 0.020008] pid_max: default: 32768 minimum: 301 [ 0.020877] Security Framework initialized [ 0.021509] AppArmor: AppArmor disabled by boot time parameter [ 0.022416] Mount-cache hash table entries: 512 [ 0.024121] Initializing cgroup subsys cpuacct [ 0.024798] Initializing cgroup subsys perf_event [ 0.025677] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.025677] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.025677] tlb_flushall_shift is 0x6 [ 0.031637] ACPI: Core revision 20120711 [ 0.034508] Getting VERSION: 50014 [ 0.035028] Getting VERSION: 50014 [ 0.035555] Getting ID: 0 [ 0.035960] Getting ID: f000000 [ 0.036442] Getting LVT0: 8700 [ 0.036902] Getting LVT1: 8400 [ 0.037371] Enabling APIC mode: Flat. Using 1 I/O APICs [ 0.038250] enabled ExtINT on CPU#0 [ 0.039858] ENABLING IO-APIC IRQs [ 0.040012] init IO_APIC IRQs [ 0.040460] apic 2 pin 0 not connected [ 0.041063] IOAPIC[0]: Set routing entry (2-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1) [ 0.042248] IOAPIC[0]: Set routing entry (2-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1) [ 0.043428] IOAPIC[0]: Set routing entry (2-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1) [ 0.044612] IOAPIC[0]: Set routing entry (2-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1) [ 0.045815] IOAPIC[0]: Set routing entry (2-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1) [ 0.046993] IOAPIC[0]: Set routing entry (2-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1) [ 0.048171] IOAPIC[0]: Set routing entry (2-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1) [ 0.049348] IOAPIC[0]: Set routing entry (2-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1) [ 0.050025] IOAPIC[0]: Set routing entry (2-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1) [ 0.051220] IOAPIC[0]: Set routing entry (2-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1) [ 0.052417] IOAPIC[0]: Set routing entry (2-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1) [ 0.053613] IOAPIC[0]: Set routing entry (2-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1) [ 0.054817] IOAPIC[0]: Set routing entry (2-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1) [ 0.056013] IOAPIC[0]: Set routing entry (2-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1) [ 0.057219] IOAPIC[0]: Set routing entry (2-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1) [ 0.058423] apic 2 pin 16 not connected [ 0.058970] apic 2 pin 17 not connected [ 0.060004] apic 2 pin 18 not connected [ 0.060591] apic 2 pin 19 not connected [ 0.061184] apic 2 pin 20 not connected [ 0.061773] apic 2 pin 21 not connected [ 0.062354] apic 2 pin 22 not connected [ 0.062941] apic 2 pin 23 not connected [ 0.063675] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.064992] smpboot: CPU0: Intel Common KVM processor stepping 01 [ 0.066476] Using local APIC timer interrupts. [ 0.066476] calibrating APIC timer ... [ 0.070000] ... lapic delta = 6221530 [ 0.070000] ... PM-Timer delta = 356322 [ 0.070000] ... PM-Timer result ok [ 0.070000] ..... delta 6221530 [ 0.070000] ..... mult: 267212678 [ 0.070000] ..... calibration result: 9954448 [ 0.070000] ..... CPU clock speed is 2820.4102 MHz. [ 0.070000] ..... host bus clock speed is 995.4448 MHz. [ 0.070000] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only. [ 0.110455] Testing tracer nop: PASSED [ 0.111308] NMI watchdog: disabled (cpu0): hardware events not enabled [ 0.130259] SMP alternatives: lockdep: fixing up alternatives [ 0.131704] CPU 1 irqstacks, hard=cd896000 soft=cd898000 [ 0.132937] smpboot: Booting Node 0, Processors #1 OK [ 0.020000] Initializing CPU#1 [ 0.020000] masked ExtINT on CPU#1 [ 0.020000] calibrate_delay_direct() failed to get a good estimate for loops_per_jiffy. [ 0.020000] Probably due to long platform interrupts. Consider using "lpj=" boot option. [ 0.450290] Brought up 2 CPUs [ 0.450766] smpboot: Total of 2 processors activated (11302.69 BogoMIPS) [ 0.450283] KVM setup async PF for cpu 1 [ 0.450617] kvm-stealtime: cpu 1, msr dcb4540 [ 0.453627] atomic64 test passed for i586+ platform with CX8 and with SSE [ 0.453888] NET: Registered protocol family 16 [ 0.455708] ACPI: bus type pci registered [ 0.456696] PCI: PCI BIOS revision 2.10 entry at 0xfc78c, last bus=0 [ 0.457627] PCI: Using configuration type 1 for base access [ 0.460953] bio: create slab at 0 [ 0.460953] ACPI: Added _OSI(Module Device) [ 0.460953] ACPI: Added _OSI(Processor Device) [ 0.461350] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.462026] ACPI: Added _OSI(Processor Aggregator Device) [ 0.464578] ACPI: EC: Look up EC in DSDT [ 0.471434] ACPI: Interpreter enabled [ 0.471978] ACPI: (supports S0 S3 S5) [ 0.472624] ACPI: Using IOAPIC for interrupt routing [ 0.479400] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.480067] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.481066] pci_root PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.482946] PCI host bridge to bus 0000:00 [ 0.483554] pci_bus 0000:00: busn_res: [bus 00-ff] is inserted under domain [bus 00-ff] [ 0.484707] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.485473] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7] [ 0.486319] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff] [ 0.487203] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff] [ 0.488194] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xfebfffff] [ 0.490183] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.491484] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.492927] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.495559] pci 0000:00:01.1: reg 20: [io 0xc1c0-0xc1cf] [ 0.497325] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.498706] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI [ 0.499759] pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB [ 0.500194] pci 0000:00:02.0: [1013:00b8] type 00 class 0x030000 [ 0.502953] pci 0000:00:02.0: reg 10: [mem 0xfc000000-0xfdffffff pref] [ 0.504916] pci 0000:00:02.0: reg 14: [mem 0xfebf0000-0xfebf0fff] [ 0.511016] pci 0000:00:02.0: reg 30: [mem 0xfebe0000-0xfebeffff pref] [ 0.512481] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 0.513736] pci 0000:00:03.0: reg 10: [mem 0xfeba0000-0xfebbffff] [ 0.515270] pci 0000:00:03.0: reg 14: [io 0xc000-0xc03f] [ 0.519224] pci 0000:00:03.0: reg 30: [mem 0xfebc0000-0xfebdffff pref] [ 0.520456] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 [ 0.521737] pci 0000:00:04.0: reg 10: [io 0xc040-0xc07f] [ 0.523177] pci 0000:00:04.0: reg 14: [mem 0xfebf1000-0xfebf1fff] [ 0.527846] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.529125] pci 0000:00:05.0: reg 10: [io 0xc080-0xc0bf] [ 0.530656] pci 0000:00:05.0: reg 14: [mem 0xfebf2000-0xfebf2fff] [ 0.535332] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.536585] pci 0000:00:06.0: reg 10: [io 0xc0c0-0xc0ff] [ 0.538023] pci 0000:00:06.0: reg 14: [mem 0xfebf3000-0xfebf3fff] [ 0.543232] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 [ 0.544533] pci 0000:00:07.0: reg 10: [io 0xc100-0xc13f] [ 0.545969] pci 0000:00:07.0: reg 14: [mem 0xfebf4000-0xfebf4fff] [ 0.550622] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 [ 0.551910] pci 0000:00:08.0: reg 10: [io 0xc140-0xc17f] [ 0.553346] pci 0000:00:08.0: reg 14: [mem 0xfebf5000-0xfebf5fff] [ 0.558010] pci 0000:00:09.0: [1af4:1001] type 00 class 0x010000 [ 0.559301] pci 0000:00:09.0: reg 10: [io 0xc180-0xc1bf] [ 0.560666] pci 0000:00:09.0: reg 14: [mem 0xfebf6000-0xfebf6fff] [ 0.565348] pci 0000:00:0a.0: [8086:25ab] type 00 class 0x088000 [ 0.566401] pci 0000:00:0a.0: reg 10: [mem 0xfebf7000-0xfebf700f] [ 0.570314] pci_bus 0000:00: on NUMA node 0 [ 0.570959] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT] [ 0.572801] pci0000:00: Unable to request _OSC control (_OSC support mask: 0x08) [ 0.584788] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.585887] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.586911] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.587949] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.588987] ACPI: PCI Interrupt Link [LNKS] (IRQs 9) *0 [ 0.590227] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none [ 0.591398] vgaarb: loaded [ 0.591806] vgaarb: bridge control possible 0000:00:02.0 [ 0.592642] PCI: Using ACPI for IRQ routing [ 0.593259] PCI: pci_cache_line_size set to 64 bytes [ 0.594336] e820: reserve RAM buffer [mem 0x00093c00-0x0009ffff] [ 0.595212] e820: reserve RAM buffer [mem 0x0fffe000-0x0fffffff] [ 0.596664] NetLabel: Initializing [ 0.596664] NetLabel: domain hash size = 128 [ 0.596664] NetLabel: protocols = UNLABELED CIPSOv4 [ 0.596664] NetLabel: unlabeled traffic allowed by default [ 0.596664] Switching to clocksource hpet [ 0.643928] pnp: PnP ACPI init [ 0.644432] ACPI: bus type pnp registered [ 0.645057] pnp 00:00: [bus 00-ff] [ 0.645566] pnp 00:00: [io 0x0cf8-0x0cff] [ 0.646137] pnp 00:00: [io 0x0000-0x0cf7 window] [ 0.646827] pnp 00:00: [io 0x0d00-0xffff window] [ 0.647519] pnp 00:00: [mem 0x000a0000-0x000bffff window] [ 0.648309] pnp 00:00: [mem 0xe0000000-0xfebfffff window] [ 0.649207] pnp 00:00: Plug and Play ACPI device, IDs PNP0a03 (active) [ 0.650251] pnp 00:01: [io 0x0070-0x0071] [ 0.650866] IOAPIC[0]: Set routing entry (2-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:3) [ 0.652032] pnp 00:01: [irq 8] [ 0.652491] pnp 00:01: [io 0x0072-0x0077] [ 0.653171] pnp 00:01: Plug and Play ACPI device, IDs PNP0b00 (active) [ 0.654169] pnp 00:02: [io 0x0060] [ 0.654686] pnp 00:02: [io 0x0064] [ 0.655202] IOAPIC[0]: Set routing entry (2-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:3) [ 0.656331] pnp 00:02: [irq 1] [ 0.656816] pnp 00:02: Plug and Play ACPI device, IDs PNP0303 (active) [ 0.657803] IOAPIC[0]: Set routing entry (2-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:3) [ 0.658986] pnp 00:03: [irq 12] [ 0.659493] pnp 00:03: Plug and Play ACPI device, IDs PNP0f13 (active) [ 0.660540] pnp 00:04: [io 0x03f2-0x03f5] [ 0.661141] pnp 00:04: [io 0x03f7] [ 0.661661] IOAPIC[0]: Set routing entry (2-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:3) [ 0.662819] pnp 00:04: [irq 6] [ 0.663280] pnp 00:04: [dma 2] [ 0.663817] pnp 00:04: Plug and Play ACPI device, IDs PNP0700 (active) [ 0.664833] pnp 00:05: [io 0x0378-0x037f] [ 0.665443] IOAPIC[0]: Set routing entry (2-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:3) [ 0.666554] pnp 00:05: [irq 7] [ 0.667038] pnp 00:05: Plug and Play ACPI device, IDs PNP0400 (active) [ 0.668046] pnp 00:06: [io 0x03f8-0x03ff] [ 0.668651] IOAPIC[0]: Set routing entry (2-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:3) [ 0.669806] pnp 00:06: [irq 4] [ 0.670368] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 0.671582] pnp 00:07: [mem 0xfed00000-0xfed003ff] [ 0.672352] pnp 00:07: Plug and Play ACPI device, IDs PNP0103 (active) [ 0.673506] pnp: PnP ACPI: found 8 devices [ 0.674104] ACPI: ACPI bus type pnp unregistered [ 0.709939] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7] [ 0.710819] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff] [ 0.711632] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff] [ 0.712540] pci_bus 0000:00: resource 7 [mem 0xe0000000-0xfebfffff] [ 0.713508] NET: Registered protocol family 2 [ 0.714497] TCP established hash table entries: 8192 (order: 4, 65536 bytes) [ 0.717581] TCP bind hash table entries: 8192 (order: 6, 294912 bytes) [ 0.719433] TCP: Hash tables configured (established 8192 bind 8192) [ 0.720477] TCP: reno registered [ 0.721102] UDP hash table entries: 256 (order: 2, 20480 bytes) [ 0.722160] UDP-Lite hash table entries: 256 (order: 2, 20480 bytes) [ 0.723240] NET: Registered protocol family 1 [ 0.723845] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.724702] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.725578] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.726516] pci 0000:00:02.0: Boot video device [ 0.727287] PCI: CLS 0 bytes, default 64 [ 0.728279] Unpacking initramfs... [ 3.250725] debug: unmapping init [mem 0xce73f000-0xcffeffff] [ 3.309275] DMA-API: preallocated 32768 debug entries [ 3.310104] DMA-API: debugging enabled by kernel config [ 3.322350] Initializing RT-Tester: OK [ 3.322908] audit: initializing netlink socket (disabled) [ 3.324123] type=2000 audit(1346788517.320:1): initialized [ 3.330798] Testing tracer function: PASSED [ 3.600059] Testing tracer preemptoff: [ 3.760076] ------------[ cut here ]------------ [ 3.760866] WARNING: at /c/kernel-tests/src/stable/kernel/lockdep.c:3506 check_flags+0x125/0x154() [ 3.762160] Modules linked in: [ 3.762643] Pid: 1, comm: swapper/0 Not tainted 3.6.0-rc3-00013-g01f8a27 #158 [ 3.763679] Call Trace: [ 3.764053] [] warn_slowpath_common+0x4d/0x62 [ 3.764838] [] ? check_flags+0x125/0x154 [ 3.765567] [] warn_slowpath_null+0x14/0x18 [ 3.766317] [] check_flags+0x125/0x154 [ 3.766998] [] lock_acquire+0x3b/0xef [ 3.767689] [] _raw_spin_lock_irqsave+0x3e/0x4e [ 3.768494] [] ? check_critical_timing+0x112/0x160 [ 3.769340] [] check_critical_timing+0x112/0x160 [ 3.770047] [] ? __do_softirq+0x19b/0x1f9 [ 3.770047] [] ? __do_softirq+0x19b/0x1f9 [ 3.770047] [] trace_preempt_on+0x8d/0xc9 [ 3.770047] [] ? __local_bh_enable+0x87/0x8a [ 3.770047] [] sub_preempt_count+0x7f/0x9c [ 3.770047] [] __local_bh_enable+0x87/0x8a [ 3.770047] [] __do_softirq+0x19b/0x1f9 [ 3.770047] [] ? ftrace_define_fields_irq_handler_entry+0x45/0x45 [ 3.770047] [] ? irq_exit+0x4f/0xa9 [ 3.770047] [] ? smp_apic_timer_interrupt+0x70/0x7e [ 3.770047] [] ? apic_timer_interrupt+0x2f/0x34 [ 3.770047] [] ? ring_buffer_discard_commit+0x24c/0x24c [ 3.770047] [] ? __const_udelay+0x1/0x1c [ 3.770047] [] ? trace_selftest_startup_preemptoff+0x74/0xed [ 3.770047] [] ? register_tracer+0x11c/0x1f7 [ 3.770047] [] ? init_function_trace+0xf/0xf [ 3.770047] [] ? init_irqsoff_tracer+0xd/0x11 [ 3.770047] [] ? do_one_initcall+0x70/0x118 [ 3.770047] [] ? kernel_init+0xec/0x169 [ 3.770047] [] ? start_kernel+0x2f2/0x2f2 [ 3.770047] [] ? kernel_thread_helper+0x6/0x10 [ 3.770047] ---[ end trace 4ce13c368c5b8019 ]--- [ 3.770047] irq event stamp: 599230 [ 3.770047] hardirqs last enabled at (599228): [] _raw_spin_unlock_irq+0x27/0x4d [ 3.770047] hardirqs last disabled at (599229): [] __do_softirq+0x165/0x1f9 [ 3.770047] softirqs last enabled at (599230): [] __do_softirq+0x19b/0x1f9 [ 3.770047] softirqs last disabled at (599225): [] do_softirq+0x63/0xba [ 3.800171] PASSED [ 3.806867] msgmni has been set to 420 [ 3.807488] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) [ 3.808577] io scheduler noop registered (default) [ 3.820756] crc32: CRC_LE_BITS = 1, CRC_BE BITS = 1 [ 3.821533] crc32: self tests passed, processed 225944 bytes in 3255030 nsec [ 3.826805] crc32c: CRC_LE_BITS = 1 [ 3.827326] crc32c: self tests passed, processed 225944 bytes in 2012510 nsec [ 3.828603] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.829719] ACPI: Power Button [PWRF] [ 3.864129] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 3.896087] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 3.923786] 00:06: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 3.924910] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.927008] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.927842] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.928987] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.930510] cpuidle: using governor ladder [ 3.935955] cpuidle: using governor menu [ 3.936665] TCP: cubic registered [ 3.937245] [ 3.937245] printing PIC contents [ 3.937960] ... PIC IMR: ffff [ 3.938421] ... PIC IRR: 1013 [ 3.939048] ... PIC ISR: 0000 [ 3.939512] ... PIC ELCR: 0c00 [ 3.940139] printing local APIC contents on CPU#0/0: [ 3.940869] ... APIC ID: 00000000 (0) [ 3.941472] ... APIC VERSION: 00050014 [ 3.942017] ... APIC TASKPRI: 00000000 (00) [ 3.942628] ... APIC PROCPRI: 00000000 [ 3.943176] ... APIC LDR: 01000000 [ 3.943679] ... APIC DFR: ffffffff [ 3.944176] ... APIC SPIV: 000001ff [ 3.944686] ... APIC ISR field: [ 3.945147] 0000000000000000000000000000000000000000000000000000000000000000 [ 3.946355] ... APIC TMR field: [ 3.946817] 0000000000000000000000000000000000000000000000000000000000000000 [ 3.948016] ... APIC IRR field: [ 3.948485] 0000000000000000000000000000000000000000000000000000000000000000 [ 3.949701] ... APIC ESR: 00000000 [ 3.950130] ... APIC ICR: 000008fd [ 3.950130] ... APIC ICR2: 02000000 [ 3.950130] ... APIC LVTT: 000000ef [ 3.950130] ... APIC LVTPC: 00010000 [ 3.950130] ... APIC LVT0: 00010700 [ 3.950130] ... APIC LVT1: 00000400 [ 3.950130] ... APIC LVTERR: 000000fe [ 3.950130] ... APIC TMICT: 0100ebb9 [ 3.950130] ... APIC TMCCT: 009132f7 [ 3.950130] ... APIC TDCR: 00000003 [ 3.950130] [ 3.964607] number of MP IRQ sources: 15. [ 3.965224] number of IO-APIC #2 registers: 24. [ 3.965885] testing the IO APIC....................... [ 3.966668] IO APIC #2...... [ 3.967099] .... register #00: 00000000 [ 3.967690] ....... : physical APIC id: 00 [ 3.968329] ....... : Delivery Type: 0 [ 3.968925] ....... : LTS : 0 [ 3.969513] .... register #01: 00170011 [ 3.970144] ....... : max redirection entries: 17 [ 3.970899] ....... : PRQ implemented: 0 [ 3.971523] ....... : IO APIC version: 11 [ 3.972169] .... register #02: 00000000 [ 3.972752] ....... : arbitration: 00 [ 3.973343] .... IRQ redirection table: [ 3.973922] NR Dst Mask Trig IRR Pol Stat Dmod Deli Vect: [ 3.974762] 00 00 1 0 0 0 0 0 0 00 [ 3.975556] 01 03 0 0 0 0 0 1 1 31 [ 3.976333] 02 03 0 0 0 0 0 1 1 30 [ 3.977124] 03 03 0 0 0 0 0 1 1 33 [ 3.977931] 04 03 1 0 0 0 0 1 1 34 [ 3.978747] 05 03 1 1 0 0 0 1 1 35 [ 3.979544] 06 03 0 0 0 0 0 1 1 36 [ 3.980423] 07 03 0 0 0 0 0 1 1 37 [ 3.981238] 08 03 0 0 0 0 0 1 1 38 [ 3.982043] 09 03 0 1 0 0 0 1 1 39 [ 3.982857] 0a 03 1 1 0 0 0 1 1 3A [ 3.983671] 0b 03 1 1 0 0 0 1 1 3B [ 3.984462] 0c 03 0 0 0 0 0 1 1 3C [ 3.985277] 0d 03 0 0 0 0 0 1 1 3D [ 3.986055] 0e 03 0 0 0 0 0 1 1 3E [ 3.986857] 0f 03 0 0 0 0 0 1 1 3F [ 3.987639] 10 00 1 0 0 0 0 0 0 00 [ 3.988434] 11 00 1 0 0 0 0 0 0 00 [ 3.994715] 12 00 1 0 0 0 0 0 0 00 [ 3.995509] 13 00 1 0 0 0 0 0 0 00 [ 3.996313] 14 00 1 0 0 0 0 0 0 00 [ 3.997082] 15 00 1 0 0 0 0 0 0 00 [ 3.997847] 16 00 1 0 0 0 0 0 0 00 [ 3.998594] 17 00 1 0 0 0 0 0 0 00 [ 3.999349] IRQ to pin mappings: [ 3.999814] IRQ0 -> 0:2 [ 4.000258] IRQ1 -> 0:1 [ 4.000659] IRQ3 -> 0:3 [ 4.001039] IRQ4 -> 0:4 [ 4.001425] IRQ5 -> 0:5 [ 4.001823] IRQ6 -> 0:6 [ 4.002207] IRQ7 -> 0:7 [ 4.002611] IRQ8 -> 0:8 [ 4.003013] IRQ9 -> 0:9 [ 4.003403] IRQ10 -> 0:10 [ 4.003826] IRQ11 -> 0:11 [ 4.004238] IRQ12 -> 0:12 [ 4.004663] IRQ13 -> 0:13 [ 4.005075] IRQ14 -> 0:14 [ 4.005502] IRQ15 -> 0:15 [ 4.005935] .................................... done. [ 4.006656] Using IPI No-Shortcut mode [ 4.007262] Running tests on trace events: [ 4.007845] Testing event kfree_skb: OK [ 4.040331] Testing event consume_skb: [ 4.040934] test-events (36) used greatest stack depth: 7220 bytes left OK [ 4.080459] Testing event skb_copy_datagram_iovec: OK [ 4.120428] Testing event net_dev_xmit: OK [ 4.160430] Testing event net_dev_queue: OK [ 4.200327] Testing event netif_receive_skb: OK [ 4.240333] Testing event netif_rx: OK [ 4.280347] Testing event napi_poll: [ 4.310075] tsc: Refined TSC clocksource calibration: 2833.331 MHz [ 4.310977] Switching to clocksource tsc OK [ 4.320224] Testing event sock_rcvqueue_full: OK [ 4.360265] Testing event sock_exceed_buf_limit: OK [ 4.400226] Testing event udp_fail_queue_rcv_skb: OK [ 4.440266] Testing event regmap_reg_write: OK [ 4.480265] Testing event regmap_reg_read: OK [ 4.520280] Testing event regmap_reg_read_cache: OK [ 4.560268] Testing event regmap_hw_read_start: OK [ 4.600213] Testing event regmap_hw_read_done: OK [ 4.640342] Testing event regmap_hw_write_start: OK [ 4.680216] Testing event regmap_hw_write_done: OK [ 4.720214] Testing event regcache_sync: OK [ 4.760213] Testing event regmap_cache_only: OK [ 4.802489] Testing event regmap_cache_bypass: OK [ 4.840221] Testing event mix_pool_bytes: OK [ 4.880211] Testing event mix_pool_bytes_nolock: OK [ 4.920209] Testing event credit_entropy_bits: OK [ 4.961442] Testing event get_random_bytes: OK [ 5.000220] Testing event extract_entropy: OK [ 5.040240] Testing event extract_entropy_user: OK [ 5.080222] Testing event gpio_direction: OK [ 5.120219] Testing event gpio_value: OK [ 5.160380] Testing event block_rq_abort: OK [ 5.200218] Testing event block_rq_requeue: OK [ 5.241582] Testing event block_rq_complete: OK [ 5.280281] Testing event block_rq_insert: OK [ 5.320276] Testing event block_rq_issue: OK [ 5.360220] Testing event block_bio_bounce: OK [ 5.400271] Testing event block_bio_complete: OK [ 5.440276] Testing event block_bio_backmerge: OK [ 5.480231] Testing event block_bio_frontmerge: OK [ 5.520263] Testing event block_bio_queue: OK [ 5.562513] Testing event block_getrq: OK [ 5.600264] Testing event block_sleeprq: OK [ 5.640272] Testing event block_plug: OK [ 5.680295] Testing event block_unplug: OK [ 5.720278] Testing event block_split: OK [ 5.760873] Testing event block_bio_remap: OK [ 5.800353] Testing event block_rq_remap: OK [ 5.840279] Testing event writeback_nothread: OK [ 5.881482] Testing event writeback_queue: OK [ 5.920268] Testing event writeback_exec: OK [ 5.960253] Testing event writeback_start: OK [ 6.000243] Testing event writeback_written: OK [ 6.040258] Testing event writeback_wait: OK [ 6.080381] Testing event writeback_pages_written: OK [ 6.121766] Testing event writeback_nowork: OK [ 6.160290] Testing event writeback_wake_background: OK [ 6.200213] Testing event writeback_wake_thread: OK [ 6.240247] Testing event writeback_wake_forker_thread: OK [ 6.280351] Testing event writeback_bdi_register: OK [ 6.320196] Testing event writeback_bdi_unregister: OK [ 6.360215] Testing event writeback_thread_start: OK [ 6.400170] Testing event writeback_thread_stop: OK [ 6.440197] Testing event wbc_writepage: OK [ 6.480219] Testing event writeback_queue_io: OK [ 6.520170] Testing event global_dirty_state: OK [ 6.560217] Testing event bdi_dirty_ratelimit: OK [ 6.600210] Testing event balance_dirty_pages: OK [ 6.641488] Testing event writeback_sb_inodes_requeue: OK [ 6.680264] Testing event writeback_congestion_wait: OK [ 6.720213] Testing event writeback_wait_iff_congested: OK [ 6.760250] Testing event writeback_single_inode: OK [ 6.800219] Testing event mm_compaction_isolate_migratepages: OK [ 6.840248] Testing event mm_compaction_isolate_freepages: OK [ 6.880207] Testing event mm_compaction_migratepages: OK [ 6.920210] Testing event kmalloc: OK [ 6.960247] Testing event kmem_cache_alloc: OK [ 7.000208] Testing event kmalloc_node: OK [ 7.040219] Testing event kmem_cache_alloc_node: OK [ 7.080246] Testing event kfree: OK [ 7.120209] Testing event kmem_cache_free: OK [ 7.160211] Testing event mm_page_free: OK [ 7.200245] Testing event mm_page_free_batched: OK [ 7.240215] Testing event mm_page_alloc: OK [ 7.280256] Testing event mm_page_alloc_zone_locked: OK [ 7.320206] Testing event mm_page_pcpu_drain: OK [ 7.360215] Testing event mm_page_alloc_extfrag: OK [ 7.400248] Testing event mm_vmscan_kswapd_sleep: OK [ 7.440211] Testing event mm_vmscan_kswapd_wake: OK [ 7.480215] Testing event mm_vmscan_wakeup_kswapd: OK [ 7.520250] Testing event mm_vmscan_direct_reclaim_begin: OK [ 7.560210] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 7.600212] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 7.640215] Testing event mm_vmscan_direct_reclaim_end: OK [ 7.680214] Testing event mm_vmscan_memcg_reclaim_end: OK [ 7.720249] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 7.760211] Testing event mm_shrink_slab_start: OK [ 7.800243] Testing event mm_shrink_slab_end: OK [ 7.840221] Testing event mm_vmscan_lru_isolate: OK [ 7.880275] Testing event mm_vmscan_memcg_isolate: [ 7.881050] test-events (133) used greatest stack depth: 7124 bytes left OK [ 7.920289] Testing event mm_vmscan_writepage: OK [ 7.960273] Testing event mm_vmscan_lru_shrink_inactive: OK [ 8.000270] Testing event oom_score_adj_update: OK [ 8.040360] Testing event cpu_idle: OK [ 8.080344] Testing event cpu_frequency: OK [ 8.120331] Testing event machine_suspend: OK [ 8.160329] Testing event wakeup_source_activate: OK [ 8.200762] Testing event wakeup_source_deactivate: OK [ 8.240388] Testing event clock_enable: OK [ 8.300390] Testing event clock_disable: OK [ 8.340387] Testing event clock_set_rate: OK [ 8.380381] Testing event power_domain_target: OK [ 8.420343] Testing event ftrace_test_filter: OK [ 8.460293] Testing event module_load: OK [ 8.500305] Testing event module_free: OK [ 8.540391] Testing event module_request: OK [ 8.580322] Testing event lock_acquire: OK [ 8.620357] Testing event lock_release: OK [ 8.660358] Testing event sched_kthread_stop: OK [ 8.700335] Testing event sched_kthread_stop_ret: OK [ 8.740297] Testing event sched_wakeup: OK [ 8.780268] Testing event sched_wakeup_new: OK [ 8.820352] Testing event sched_switch: OK [ 8.860322] Testing event sched_migrate_task: OK [ 8.900312] Testing event sched_process_free: OK [ 8.940322] Testing event sched_process_exit: OK [ 8.980286] Testing event sched_wait_task: OK [ 9.020277] Testing event sched_process_wait: OK [ 9.060230] Testing event sched_process_fork: OK [ 9.100218] Testing event sched_process_exec: OK [ 9.140256] Testing event sched_stat_wait: OK [ 9.180275] Testing event sched_stat_sleep: OK [ 9.220282] Testing event sched_stat_iowait: OK [ 9.260228] Testing event sched_stat_blocked: OK [ 9.300226] Testing event sched_stat_runtime: OK [ 9.340244] Testing event sched_pi_setprio: